could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementNotInteractableError@chrome://marionette/content/error.js:311:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:176:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
05:31:08 INFO - 1498825868128 Marionette TRACE 358 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:31:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 113ms
05:31:08 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498825868131 Marionette TRACE 358 <- [1,5,null,{"value":null}]
05:31:08 INFO - 1498825868132 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
05:31:08 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:31:08 INFO - 1498825868133 Marionette TRACE 358 <- [1,6,null,{}]
05:31:08 INFO - 1498825868134 Marionette DEBUG Closed connection 358
05:31:08 INFO - 1498825868136 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49729
05:31:08 INFO - 1498825868136 Marionette DEBUG Closed connection 359
05:31:08 INFO - 1498825868137 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49730
05:31:08 INFO - 1498825868137 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:08 INFO - 1498825868138 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868140 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868143 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4f8ec76e-e163-46fb-b451-dd43ab909c20","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868144 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:31:08 INFO - 1498825868144 Marionette TRACE 360 <- [1,2,null,{}]
05:31:08 INFO - 1498825868145 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:08 INFO - 1498825868145 Marionette TRACE 360 <- [1,3,null,{}]
05:31:08 INFO - 1498825868146 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:08 INFO - 1498825868146 Marionette TRACE 360 <- [1,4,null,{}]
05:31:08 INFO - 1498825868147 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:31:08 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498825868150 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:31:08 INFO - 1498825868150 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:31:08 INFO - 1498825868151 Marionette TRACE 360 <- [1,6,null,{}]
05:31:08 INFO - 1498825868153 Marionette DEBUG Closed connection 360
05:31:08 INFO - 1498825868153 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49731
05:31:08 INFO - 1498825868154 Marionette DEBUG Closed connection 361
05:31:08 INFO - 1498825868154 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49732
05:31:08 INFO - 1498825868155 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:08 INFO - 1498825868156 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868158 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868161 Marionette TRACE 362 <- [1,1,null,{"sessionId":"0cc3e68c-df20-4fc0-ad37-112d32d1bbc1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868162 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:31:08 INFO - 1498825868163 Marionette TRACE 362 <- [1,2,null,{}]
05:31:08 INFO - 1498825868164 Marionette DEBUG Closed connection 362
05:31:08 INFO - 1498825868165 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49733
05:31:08 INFO - 1498825868165 Marionette DEBUG Closed connection 363
05:31:08 INFO - 1498825868166 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49734
05:31:08 INFO - 1498825868167 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:08 INFO - 1498825868167 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868169 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868172 Marionette TRACE 364 <- [1,1,null,{"sessionId":"866fc345-d7fe-440e-bc57-a9510fa052f7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868173 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49335/clicks.html"}]
05:31:08 INFO - 1498825868175 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/hidden.html"
05:31:08 INFO - 1498825868181 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/hidden.html"
05:31:08 INFO - 1498825868181 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/hidden.html"
05:31:08 INFO - 1498825868187 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/clicks.html"
05:31:08 INFO - 1498825868191 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/clicks.html"
05:31:08 INFO - 1498825868192 Marionette TRACE 364 <- [1,2,null,{}]
05:31:08 INFO - 1498825868197 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:31:08 INFO - 1498825868200 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c6735d63-3dfe-4969-aa79-1aeb5cd57117","ELEMENT":"c6735d63-3dfe-4969-aa79-1aeb5cd57117"}}]
05:31:08 INFO - 1498825868201 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c6735d63-3dfe-4969-aa79-1aeb5cd57117"}]
05:31:08 INFO - 1498825868238 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/clicks.html"
05:31:08 INFO - 1498825868259 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/clicks.html"
05:31:08 INFO - 1498825868259 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/clicks.html"
05:31:08 INFO - 1498825868268 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/test.html"
05:31:08 INFO - 1498825868273 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/test.html"
05:31:08 INFO - 1498825868274 Marionette TRACE 364 <- [1,4,null,{}]
05:31:08 INFO - 1498825868276 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:31:08 INFO - 1498825868281 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"497fb15b-7fd9-492d-9f41-6df085a5e1db","ELEMENT":"497fb15b-7fd9-492d-9f41-6df085a5e1db"}}]
05:31:08 INFO - 1498825868282 Marionette TRACE 364 -> [0,6,"getTitle",{}]
05:31:08 INFO - 1498825868293 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
05:31:08 INFO - 1498825868295 Marionette TRACE 364 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:31:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 179ms
05:31:08 INFO - TEST-START | test_click.py TestClick.test_container_element
05:31:08 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498825868310 Marionette TRACE 364 <- [1,7,null,{"value":null}]
05:31:08 INFO - 1498825868311 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
05:31:08 INFO - 1498825868312 Marionette TRACE 364 <- [1,8,null,{}]
05:31:08 INFO - 1498825868314 Marionette DEBUG Closed connection 364
05:31:08 INFO - 1498825868315 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49737
05:31:08 INFO - 1498825868315 Marionette DEBUG Closed connection 365
05:31:08 INFO - 1498825868316 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49738
05:31:08 INFO - 1498825868317 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:08 INFO - 1498825868317 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868319 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868322 Marionette TRACE 366 <- [1,1,null,{"sessionId":"6ad757d5-eb19-494f-b620-84d6e919ba37","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868331 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:31:08 INFO - 1498825868332 Marionette TRACE 366 <- [1,2,null,{}]
05:31:08 INFO - 1498825868333 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:08 INFO - 1498825868333 Marionette TRACE 366 <- [1,3,null,{}]
05:31:08 INFO - 1498825868334 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:08 INFO - 1498825868334 Marionette TRACE 366 <- [1,4,null,{}]
05:31:08 INFO - 1498825868336 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:31:08 INFO - TEST-START: test_click.py TestClick.test_container_element1498825868338 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:31:08 INFO - 1498825868339 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:31:08 INFO - 1498825868340 Marionette TRACE 366 <- [1,6,null,{}]
05:31:08 INFO - 1498825868342 Marionette DEBUG Closed connection 366
05:31:08 INFO - 1498825868342 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49739
05:31:08 INFO - 1498825868343 Marionette DEBUG Closed connection 367
05:31:08 INFO - 1498825868343 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49740
05:31:08 INFO - 1498825868344 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:08 INFO - 1498825868345 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868347 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868349 Marionette TRACE 368 <- [1,1,null,{"sessionId":"228e9e80-82d2-47f8-8cd9-a14dff5e9fd9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868350 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:31:08 INFO - 1498825868351 Marionette TRACE 368 <- [1,2,null,{}]
05:31:08 INFO - 1498825868353 Marionette DEBUG Closed connection 368
05:31:08 INFO - 1498825868353 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49741
05:31:08 INFO - 1498825868354 Marionette DEBUG Closed connection 369
05:31:08 INFO - 1498825868355 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49742
05:31:08 INFO - 1498825868355 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:08 INFO - 1498825868356 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868364 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868367 Marionette TRACE 370 <- [1,1,null,{"sessionId":"1ba80d67-4439-4d12-9e8f-27aa8918187a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868368 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:31:08 INFO - 1498825868374 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/test.html"
05:31:08 INFO - 1498825868375 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/test.html"
05:31:08 INFO - 1498825868376 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/test.html"
05:31:08 INFO - 1498825868382 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:08 INFO - 1498825868391 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:08 INFO - 1498825868392 Marionette TRACE 370 <- [1,2,null,{}]
05:31:08 INFO - 1498825868399 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:31:08 INFO - 1498825868402 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9ddfea89-c48a-495e-8a9f-d2eac8c395b1","ELEMENT":"9ddfea89-c48a-495e-8a9f-d2eac8c395b1"}}]
05:31:08 INFO - 1498825868403 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9ddfea89-c48a-495e-8a9f-d2eac8c395b1"}]
05:31:08 INFO - 1498825868639 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:08 INFO - 1498825868640 Marionette TRACE 370 <- [1,4,null,{}]
05:31:08 INFO - 1498825868641 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9ddfea89-c48a-495e-8a9f-d2eac8c395b1","name":"selected"}]
05:31:08 INFO - 1498825868645 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:31:08 INFO - 1498825868647 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:31:08 INFO - TEST-END: test_click.py TestClick.test_container_element1498825868649 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:31:08 INFO - 1498825868650 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:31:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 338ms
05:31:08 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:31:08 INFO - 1498825868651 Marionette TRACE 370 <- [1,7,null,{}]
05:31:08 INFO - 1498825868653 Marionette DEBUG Closed connection 370
05:31:08 INFO - 1498825868654 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49743
05:31:08 INFO - 1498825868654 Marionette DEBUG Closed connection 371
05:31:08 INFO - 1498825868655 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49744
05:31:08 INFO - 1498825868656 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:08 INFO - 1498825868657 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868659 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868661 Marionette TRACE 372 <- [1,1,null,{"sessionId":"6aea6a43-bf98-4e74-bb6a-88d0c25d7d1a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868662 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:31:08 INFO - 1498825868663 Marionette TRACE 372 <- [1,2,null,{}]
05:31:08 INFO - 1498825868664 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:08 INFO - 1498825868664 Marionette TRACE 372 <- [1,3,null,{}]
05:31:08 INFO - 1498825868665 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:08 INFO - 1498825868665 Marionette TRACE 372 <- [1,4,null,{}]
05:31:08 INFO - 1498825868667 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:31:08 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498825868669 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:31:08 INFO - 1498825868670 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:31:08 INFO - 1498825868671 Marionette TRACE 372 <- [1,6,null,{}]
05:31:08 INFO - 1498825868672 Marionette DEBUG Closed connection 372
05:31:08 INFO - 1498825868673 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49745
05:31:08 INFO - 1498825868673 Marionette DEBUG Closed connection 373
05:31:08 INFO - 1498825868674 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49746
05:31:08 INFO - 1498825868675 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:08 INFO - 1498825868675 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868677 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868680 Marionette TRACE 374 <- [1,1,null,{"sessionId":"9ed56655-27a1-4a54-be25-ca6e95c249b4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868681 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:31:08 INFO - 1498825868682 Marionette TRACE 374 <- [1,2,null,{}]
05:31:08 INFO - 1498825868683 Marionette DEBUG Closed connection 374
05:31:08 INFO - 1498825868684 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49747
05:31:08 INFO - 1498825868685 Marionette DEBUG Closed connection 375
05:31:08 INFO - 1498825868685 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49748
05:31:08 INFO - 1498825868686 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:08 INFO - 1498825868687 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868689 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868691 Marionette TRACE 376 <- [1,1,null,{"sessionId":"68407750-321f-45e7-a5e7-acc62822fd41","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868692 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:31:08 INFO - 1498825868694 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:08 INFO - 1498825868696 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:08 INFO - 1498825868696 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:08 INFO - 1498825868701 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:08 INFO - 1498825868704 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:08 INFO - 1498825868705 Marionette TRACE 376 <- [1,2,null,{}]
05:31:08 INFO - 1498825868708 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:31:08 INFO - 1498825868710 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2cca7e91-622a-48d0-b030-4a0856d724c8","ELEMENT":"2cca7e91-622a-48d0-b030-4a0856d724c8"}}]
05:31:08 INFO - 1498825868715 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"2cca7e91-622a-48d0-b030-4a0856d724c8"}]
05:31:08 INFO - 1498825868952 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:08 INFO - 1498825868954 Marionette TRACE 376 <- [1,4,null,{}]
05:31:08 INFO - 1498825868955 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"2cca7e91-622a-48d0-b030-4a0856d724c8","name":"selected"}]
05:31:08 INFO - 1498825868958 Marionette TRACE 376 <- [1,5,null,{"value":true}]
05:31:08 INFO - 1498825868960 Marionette TRACE 376 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:31:08 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498825868963 Marionette TRACE 376 <- [1,6,null,{"value":null}]
05:31:08 INFO - 1498825868963 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
05:31:08 INFO - 1498825868965 Marionette TRACE 376 <- [1,7,null,{}]
05:31:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 312ms
05:31:08 INFO - 1498825868966 Marionette DEBUG Closed connection 376
05:31:08 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:31:08 INFO - 1498825868968 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49749
05:31:08 INFO - 1498825868969 Marionette DEBUG Closed connection 377
05:31:08 INFO - 1498825868969 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49750
05:31:08 INFO - 1498825868970 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:08 INFO - 1498825868970 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868972 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868975 Marionette TRACE 378 <- [1,1,null,{"sessionId":"b2928ca8-5029-4de3-8f6c-e6f3ba1b401b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868976 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:31:08 INFO - 1498825868976 Marionette TRACE 378 <- [1,2,null,{}]
05:31:08 INFO - 1498825868977 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:08 INFO - 1498825868978 Marionette TRACE 378 <- [1,3,null,{}]
05:31:08 INFO - 1498825868978 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:08 INFO - 1498825868979 Marionette TRACE 378 <- [1,4,null,{}]
05:31:08 INFO - 1498825868981 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:31:08 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498825868983 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:31:08 INFO - 1498825868984 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:31:08 INFO - 1498825868985 Marionette TRACE 378 <- [1,6,null,{}]
05:31:08 INFO - 1498825868986 Marionette DEBUG Closed connection 378
05:31:08 INFO - 1498825868987 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49751
05:31:08 INFO - 1498825868988 Marionette DEBUG Closed connection 379
05:31:08 INFO - 1498825868988 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49752
05:31:08 INFO - 1498825868989 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:08 INFO - 1498825868990 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:08 INFO - 1498825868994 Marionette DEBUG Register listener.js for window 2147483649
05:31:08 INFO - 1498825868996 Marionette TRACE 380 <- [1,1,null,{"sessionId":"39ce572d-a46b-4399-955e-edfc6d7a598e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:08 INFO - 1498825868997 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:31:09 INFO - 1498825868998 Marionette TRACE 380 <- [1,2,null,{}]
05:31:09 INFO - 1498825869000 Marionette DEBUG Closed connection 380
05:31:09 INFO - 1498825869000 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49753
05:31:09 INFO - 1498825869001 Marionette DEBUG Closed connection 381
05:31:09 INFO - 1498825869001 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49754
05:31:09 INFO - 1498825869002 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:09 INFO - 1498825869003 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869005 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869007 Marionette TRACE 382 <- [1,1,null,{"sessionId":"8481f94d-5286-443c-a537-d6e4ea31782b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869008 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:31:09 INFO - 1498825869010 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:09 INFO - 1498825869011 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:09 INFO - 1498825869012 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:09 INFO - 1498825869017 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:09 INFO - 1498825869019 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:09 INFO - 1498825869022 Marionette TRACE 382 <- [1,2,null,{}]
05:31:09 INFO - 1498825869024 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:31:09 INFO - 1498825869026 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d75d0712-5496-4b00-9054-598f46144411","ELEMENT":"d75d0712-5496-4b00-9054-598f46144411"}}]
05:31:09 INFO - 1498825869032 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d75d0712-5496-4b00-9054-598f46144411"}]
05:31:09 INFO - 1498825869271 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:09 INFO - 1498825869272 Marionette TRACE 382 <- [1,4,null,{}]
05:31:09 INFO - 1498825869274 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:31:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498825869276 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:31:09 INFO - 1498825869277 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:31:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 312ms
05:31:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:31:09 INFO - 1498825869278 Marionette TRACE 382 <- [1,6,null,{}]
05:31:09 INFO - 1498825869280 Marionette DEBUG Closed connection 382
05:31:09 INFO - 1498825869280 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49755
05:31:09 INFO - 1498825869281 Marionette DEBUG Closed connection 383
05:31:09 INFO - 1498825869282 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49756
05:31:09 INFO - 1498825869283 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:09 INFO - 1498825869283 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869285 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869288 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b8c1c0cb-9e0a-413d-90d0-7bbd0d9cd143","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869289 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:31:09 INFO - 1498825869289 Marionette TRACE 384 <- [1,2,null,{}]
05:31:09 INFO - 1498825869290 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:09 INFO - 1498825869291 Marionette TRACE 384 <- [1,3,null,{}]
05:31:09 INFO - 1498825869291 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:09 INFO - 1498825869292 Marionette TRACE 384 <- [1,4,null,{}]
05:31:09 INFO - 1498825869294 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:31:09 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498825869296 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:31:09 INFO - 1498825869297 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:31:09 INFO - 1498825869298 Marionette TRACE 384 <- [1,6,null,{}]
05:31:09 INFO - 1498825869299 Marionette DEBUG Closed connection 384
05:31:09 INFO - 1498825869300 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49757
05:31:09 INFO - 1498825869300 Marionette DEBUG Closed connection 385
05:31:09 INFO - 1498825869301 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49758
05:31:09 INFO - 1498825869302 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:09 INFO - 1498825869303 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869305 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869307 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c5da0782-8636-42e2-9d73-4f92504fba30","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869308 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:31:09 INFO - 1498825869309 Marionette TRACE 386 <- [1,2,null,{}]
05:31:09 INFO - 1498825869310 Marionette DEBUG Closed connection 386
05:31:09 INFO - 1498825869311 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49759
05:31:09 INFO - 1498825869311 Marionette DEBUG Closed connection 387
05:31:09 INFO - 1498825869312 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49760
05:31:09 INFO - 1498825869313 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:09 INFO - 1498825869313 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869315 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869318 Marionette TRACE 388 <- [1,1,null,{"sessionId":"aef85750-ddf4-4b28-8c6c-299dd63091e1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869319 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:31:09 INFO - 1498825869320 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:09 INFO - 1498825869322 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:09 INFO - 1498825869322 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:09 INFO - 1498825869329 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:09 INFO - 1498825869330 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:09 INFO - 1498825869335 Marionette TRACE 388 <- [1,2,null,{}]
05:31:09 INFO - 1498825869337 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:31:09 INFO - 1498825869339 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c000f8e7-0774-4449-8a1d-d8817711d5b7","ELEMENT":"c000f8e7-0774-4449-8a1d-d8817711d5b7"}}]
05:31:09 INFO - 1498825869340 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c000f8e7-0774-4449-8a1d-d8817711d5b7"}]
05:31:09 INFO - 1498825869581 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:09 INFO - 1498825869605 Marionette TRACE 388 <- [1,4,null,{}]
05:31:09 INFO - 1498825869610 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:31:09 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498825869615 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:31:09 INFO - 1498825869617 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:31:09 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 341ms
05:31:09 INFO - TEST-START | test_click.py TestClick.test_input_file
05:31:09 INFO - 1498825869619 Marionette TRACE 388 <- [1,6,null,{}]
05:31:09 INFO - 1498825869622 Marionette DEBUG Closed connection 388
05:31:09 INFO - 1498825869623 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49761
05:31:09 INFO - 1498825869628 Marionette DEBUG Closed connection 389
05:31:09 INFO - 1498825869629 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49762
05:31:09 INFO - 1498825869632 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:09 INFO - 1498825869634 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869637 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869642 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d81b2224-fb57-49bf-b14e-74acefec5d00","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869644 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:31:09 INFO - 1498825869644 Marionette TRACE 390 <- [1,2,null,{}]
05:31:09 INFO - 1498825869654 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:09 INFO - 1498825869655 Marionette TRACE 390 <- [1,3,null,{}]
05:31:09 INFO - 1498825869659 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:09 INFO - 1498825869660 Marionette TRACE 390 <- [1,4,null,{}]
05:31:09 INFO - 1498825869662 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:31:09 INFO - TEST-START: test_click.py TestClick.test_input_file1498825869665 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:31:09 INFO - 1498825869666 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:31:09 INFO - 1498825869668 Marionette TRACE 390 <- [1,6,null,{}]
05:31:09 INFO - 1498825869669 Marionette DEBUG Closed connection 390
05:31:09 INFO - 1498825869670 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49763
05:31:09 INFO - 1498825869671 Marionette DEBUG Closed connection 391
05:31:09 INFO - 1498825869672 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49764
05:31:09 INFO - 1498825869673 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:09 INFO - 1498825869674 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869677 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869681 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a50dc49f-ae01-4332-a14f-4825a9245d09","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869682 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:31:09 INFO - 1498825869684 Marionette TRACE 392 <- [1,2,null,{}]
05:31:09 INFO - 1498825869686 Marionette DEBUG Closed connection 392
05:31:09 INFO - 1498825869686 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49765
05:31:09 INFO - 1498825869687 Marionette DEBUG Closed connection 393
05:31:09 INFO - 1498825869687 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49766
05:31:09 INFO - 1498825869690 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:09 INFO - 1498825869692 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869694 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869697 Marionette TRACE 394 <- [1,1,null,{"sessionId":"fbba4e71-6531-4918-9546-ed732ad39d52","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869698 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:31:09 INFO - 1498825869700 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:09 INFO - 1498825869702 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:09 INFO - 1498825869702 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:09 INFO - 1498825869707 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:09 INFO - 1498825869711 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:09 INFO - 1498825869720 Marionette TRACE 394 <- [1,2,null,{}]
05:31:09 INFO - 1498825869723 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:31:09 INFO - 1498825869726 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c5b392c9-a782-4fc0-b563-22540c23398c","ELEMENT":"c5b392c9-a782-4fc0-b563-22540c23398c"}}]
05:31:09 INFO - 1498825869727 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"c5b392c9-a782-4fc0-b563-22540c23398c"}]
05:31:09 INFO - 1498825869758 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nInvalidArgumentError@chrome://marionette/content/error.js:325:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:158:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
05:31:09 INFO - 1498825869759 Marionette TRACE 394 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:31:09 INFO - TEST-END: test_click.py TestClick.test_input_file1498825869762 Marionette TRACE 394 <- [1,5,null,{"value":null}]
05:31:09 INFO - 1498825869763 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
05:31:09 INFO - 1498825869764 Marionette TRACE 394 <- [1,6,null,{}]
05:31:09 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 145ms
05:31:09 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:31:09 INFO - 1498825869766 Marionette DEBUG Closed connection 394
05:31:09 INFO - 1498825869768 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49767
05:31:09 INFO - 1498825869769 Marionette DEBUG Closed connection 395
05:31:09 INFO - 1498825869770 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49768
05:31:09 INFO - 1498825869771 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:09 INFO - 1498825869771 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869774 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869777 Marionette TRACE 396 <- [1,1,null,{"sessionId":"46400029-5560-41f1-b1ff-3308c5ea8e75","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869778 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:31:09 INFO - 1498825869779 Marionette TRACE 396 <- [1,2,null,{}]
05:31:09 INFO - 1498825869780 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:09 INFO - 1498825869780 Marionette TRACE 396 <- [1,3,null,{}]
05:31:09 INFO - 1498825869781 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:09 INFO - 1498825869781 Marionette TRACE 396 <- [1,4,null,{}]
05:31:09 INFO - 1498825869783 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:31:09 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498825869786 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:31:09 INFO - 1498825869786 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:31:09 INFO - 1498825869788 Marionette TRACE 396 <- [1,6,null,{}]
05:31:09 INFO - 1498825869789 Marionette DEBUG Closed connection 396
05:31:09 INFO - 1498825869790 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49769
05:31:09 INFO - 1498825869790 Marionette DEBUG Closed connection 397
05:31:09 INFO - 1498825869791 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49770
05:31:09 INFO - 1498825869792 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:09 INFO - 1498825869793 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869795 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869798 Marionette TRACE 398 <- [1,1,null,{"sessionId":"c7b955b8-83a2-46b9-8f7d-dcc164cbff5e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869800 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:31:09 INFO - 1498825869802 Marionette TRACE 398 <- [1,2,null,{}]
05:31:09 INFO - 1498825869804 Marionette DEBUG Closed connection 398
05:31:09 INFO - 1498825869806 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49771
05:31:09 INFO - 1498825869806 Marionette DEBUG Closed connection 399
05:31:09 INFO - 1498825869807 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49772
05:31:09 INFO - 1498825869808 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:09 INFO - 1498825869809 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:09 INFO - 1498825869812 Marionette DEBUG Register listener.js for window 2147483649
05:31:09 INFO - 1498825869815 Marionette TRACE 400 <- [1,1,null,{"sessionId":"20303dbc-8e78-403f-8428-d47a080cd1e6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:09 INFO - 1498825869816 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:31:09 INFO - 1498825869818 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:09 INFO - 1498825869820 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:09 INFO - 1498825869820 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:09 INFO - 1498825869831 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:09 INFO - 1498825869832 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:09 INFO - 1498825869839 Marionette TRACE 400 <- [1,2,null,{}]
05:31:09 INFO - 1498825869841 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:31:09 INFO - 1498825869847 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c728ca8a-e7f5-42f7-9a5b-101f079abf2f","ELEMENT":"c728ca8a-e7f5-42f7-9a5b-101f079abf2f"}}]
05:31:09 INFO - 1498825869848 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:31:09 INFO - 1498825869850 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"26d6cb3f-f1ae-4055-b1b6-434ba6e9d1db","ELEMENT":"26d6cb3f-f1ae-4055-b1b6-434ba6e9d1db"}}]
05:31:09 INFO - 1498825869851 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c728ca8a-e7f5-42f7-9a5b-101f079abf2f"}]
05:31:10 INFO - 1498825870084 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:10 INFO - 1498825870085 Marionette TRACE 400 <- [1,5,null,{}]
05:31:10 INFO - 1498825870086 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"26d6cb3f-f1ae-4055-b1b6-434ba6e9d1db"}]
05:31:10 INFO - 1498825870100 Marionette TRACE 400 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementClickInterceptedError@chrome://marionette/content/error.js:304:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:185:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
05:31:10 INFO - 1498825870102 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:31:10 INFO - 1498825870104 Marionette TRACE 400 <- [1,7,null,{"value":false}]
05:31:10 INFO - 1498825870105 Marionette TRACE 400 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:31:10 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498825870107 Marionette TRACE 400 <- [1,8,null,{"value":null}]
05:31:10 INFO - 1498825870108 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
05:31:10 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 343ms
05:31:10 INFO - 1498825870109 Marionette TRACE 400 <- [1,9,null,{}]
05:31:10 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:31:10 INFO - 1498825870111 Marionette DEBUG Closed connection 400
05:31:10 INFO - 1498825870112 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49773
05:31:10 INFO - 1498825870113 Marionette DEBUG Closed connection 401
05:31:10 INFO - 1498825870113 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49774
05:31:10 INFO - 1498825870114 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:10 INFO - 1498825870114 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:10 INFO - 1498825870117 Marionette DEBUG Register listener.js for window 2147483649
05:31:10 INFO - 1498825870119 Marionette TRACE 402 <- [1,1,null,{"sessionId":"8cbd3e7f-8634-464d-9624-7c6fb3dcd841","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:10 INFO - 1498825870120 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
05:31:10 INFO - 1498825870121 Marionette TRACE 402 <- [1,2,null,{}]
05:31:10 INFO - 1498825870122 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:10 INFO - 1498825870122 Marionette TRACE 402 <- [1,3,null,{}]
05:31:10 INFO - 1498825870123 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:10 INFO - 1498825870124 Marionette TRACE 402 <- [1,4,null,{}]
05:31:10 INFO - 1498825870125 Marionette TRACE 402 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:31:10 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498825870128 Marionette TRACE 402 <- [1,5,null,{"value":null}]
05:31:10 INFO - 1498825870129 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
05:31:10 INFO - 1498825870130 Marionette TRACE 402 <- [1,6,null,{}]
05:31:10 INFO - 1498825870131 Marionette DEBUG Closed connection 402
05:31:10 INFO - 1498825870131 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49775
05:31:10 INFO - 1498825870132 Marionette DEBUG Closed connection 403
05:31:10 INFO - 1498825870133 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49776
05:31:10 INFO - 1498825870134 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:10 INFO - 1498825870134 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:10 INFO - 1498825870136 Marionette DEBUG Register listener.js for window 2147483649
05:31:10 INFO - 1498825870139 Marionette TRACE 404 <- [1,1,null,{"sessionId":"22578fef-0bef-4170-b8db-7378e60dbee2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:10 INFO - 1498825870140 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
05:31:10 INFO - 1498825870141 Marionette TRACE 404 <- [1,2,null,{}]
05:31:10 INFO - 1498825870142 Marionette DEBUG Closed connection 404
05:31:10 INFO - 1498825870143 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49777
05:31:10 INFO - 1498825870143 Marionette DEBUG Closed connection 405
05:31:10 INFO - 1498825870144 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49778
05:31:10 INFO - 1498825870145 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:10 INFO - 1498825870146 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}
05:31:10 INFO - 1498825870148 Marionette DEBUG Register listener.js for window 2147483649
05:31:10 INFO - 1498825870150 Marionette TRACE 406 <- [1,1,null,{"sessionId":"96314074-a471-49f4-9117-90d7564ef1a9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2288,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmporf62h.mozrunner","moz:accessibilityChecks":false}}]
05:31:10 INFO - 1498825870151 Marionette TRACE 406 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:31:10 INFO - 1498825870153 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:10 INFO - 1498825870154 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:10 INFO - 1498825870155 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:10 INFO - 1498825870163 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:31:10 INFO - 1498825870164 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:31:10 INFO - 1498825870170 Marionette TRACE 406 <- [1,2,null,{}]
05:31:10 INFO - 1498825870173 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:31:10 INFO - 1498825870175 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab497d41-af7a-41c0-a7d0-687072087d27","ELEMENT":"ab497d41-af7a-41c0-a7d0-687072087d27"}}]
05:31:10 INFO - 1498825870176 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"ab497d41-af7a-41c0-a7d0-687072087d27"}]
05:31:10 INFO - 1498825870199 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
05:31:10 INFO - 1498825870201 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"ab497d41-af7a-41c0-a7d0-687072087d27"}]
05:31:10 INFO - 1498825870215 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element