could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183: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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:04:03 INFO - 1499339043231 Marionette TRACE 352 -> [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}]
04:04:03 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499339043235 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:04:03 INFO - 1499339043236 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:04:03 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 135ms
04:04:03 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:04:03 INFO - 1499339043237 Marionette TRACE 352 <- [1,6,null,{}]
04:04:03 INFO - 1499339043239 Marionette DEBUG Closed connection 352
04:04:03 INFO - 1499339043240 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49840
04:04:03 INFO - 1499339043241 Marionette DEBUG Closed connection 353
04:04:03 INFO - 1499339043242 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49841
04:04:03 INFO - 1499339043243 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:03 INFO - 1499339043243 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043246 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043250 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b0b609ad-10a8-4c81-b375-1f6966b8077a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043251 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:04:03 INFO - 1499339043252 Marionette TRACE 354 <- [1,2,null,{}]
04:04:03 INFO - 1499339043253 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:03 INFO - 1499339043253 Marionette TRACE 354 <- [1,3,null,{}]
04:04:03 INFO - 1499339043254 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:03 INFO - 1499339043255 Marionette TRACE 354 <- [1,4,null,{}]
04:04:03 INFO - 1499339043257 Marionette TRACE 354 -> [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}]
04:04:03 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499339043260 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:04:03 INFO - 1499339043262 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:04:03 INFO - 1499339043263 Marionette TRACE 354 <- [1,6,null,{}]
04:04:03 INFO - 1499339043265 Marionette DEBUG Closed connection 354
04:04:03 INFO - 1499339043266 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49842
04:04:03 INFO - 1499339043267 Marionette DEBUG Closed connection 355
04:04:03 INFO - 1499339043267 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49843
04:04:03 INFO - 1499339043268 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:03 INFO - 1499339043269 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043272 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043275 Marionette TRACE 356 <- [1,1,null,{"sessionId":"6fe2eb34-49de-47b6-a81d-ee71fbf472f7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043276 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:04:03 INFO - 1499339043278 Marionette TRACE 356 <- [1,2,null,{}]
04:04:03 INFO - 1499339043280 Marionette DEBUG Closed connection 356
04:04:03 INFO - 1499339043280 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49844
04:04:03 INFO - 1499339043281 Marionette DEBUG Closed connection 357
04:04:03 INFO - 1499339043282 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49845
04:04:03 INFO - 1499339043283 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:03 INFO - 1499339043284 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043287 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043290 Marionette TRACE 358 <- [1,1,null,{"sessionId":"2ada7b65-372b-46c3-a252-b3f999b7c3f6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043292 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49396/clicks.html"}]
04:04:03 INFO - 1499339043294 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49396/hidden.html"
04:04:03 INFO - 1499339043307 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49396/hidden.html"
04:04:03 INFO - 1499339043308 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49396/hidden.html"
04:04:03 INFO - 1499339043324 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49396/clicks.html"
04:04:03 INFO - 1499339043326 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49396/clicks.html"
04:04:03 INFO - 1499339043334 Marionette TRACE 358 <- [1,2,null,{}]
04:04:03 INFO - 1499339043341 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:04:03 INFO - 1499339043347 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c6111366-7f29-47ea-8cb9-c10a10e5439c","ELEMENT":"c6111366-7f29-47ea-8cb9-c10a10e5439c"}}]
04:04:03 INFO - 1499339043352 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"c6111366-7f29-47ea-8cb9-c10a10e5439c"}]
04:04:03 INFO - 1499339043391 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49396/clicks.html"
04:04:03 INFO - 1499339043403 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49396/clicks.html"
04:04:03 INFO - 1499339043404 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49396/clicks.html"
04:04:03 INFO - 1499339043413 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49396/test.html"
04:04:03 INFO - 1499339043421 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49396/test.html"
04:04:03 INFO - 1499339043422 Marionette TRACE 358 <- [1,4,null,{}]
04:04:03 INFO - 1499339043426 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:04:03 INFO - 1499339043429 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f3e5e93-4ae9-4d8c-9dd8-7f23ac06de77","ELEMENT":"3f3e5e93-4ae9-4d8c-9dd8-7f23ac06de77"}}]
04:04:03 INFO - 1499339043430 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:04:03 INFO - 1499339043430 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:04:03 INFO - 1499339043437 Marionette TRACE 358 -> [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}]
04:04:03 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499339043440 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:04:03 INFO - 1499339043441 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:04:03 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 205ms
04:04:03 INFO - TEST-START | test_click.py TestClick.test_container_element
04:04:03 INFO - 1499339043442 Marionette TRACE 358 <- [1,8,null,{}]
04:04:03 INFO - 1499339043444 Marionette DEBUG Closed connection 358
04:04:03 INFO - 1499339043445 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49848
04:04:03 INFO - 1499339043446 Marionette DEBUG Closed connection 359
04:04:03 INFO - 1499339043447 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49849
04:04:03 INFO - 1499339043448 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:03 INFO - 1499339043448 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043453 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043457 Marionette TRACE 360 <- [1,1,null,{"sessionId":"396db216-518d-453f-be7e-8e9d49f324d3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043458 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:04:03 INFO - 1499339043459 Marionette TRACE 360 <- [1,2,null,{}]
04:04:03 INFO - 1499339043460 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:03 INFO - 1499339043460 Marionette TRACE 360 <- [1,3,null,{}]
04:04:03 INFO - 1499339043461 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:03 INFO - 1499339043462 Marionette TRACE 360 <- [1,4,null,{}]
04:04:03 INFO - 1499339043464 Marionette TRACE 360 -> [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}]
04:04:03 INFO - TEST-START: test_click.py TestClick.test_container_element1499339043467 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:04:03 INFO - 1499339043468 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:04:03 INFO - 1499339043470 Marionette TRACE 360 <- [1,6,null,{}]
04:04:03 INFO - 1499339043471 Marionette DEBUG Closed connection 360
04:04:03 INFO - 1499339043472 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49850
04:04:03 INFO - 1499339043473 Marionette DEBUG Closed connection 361
04:04:03 INFO - 1499339043474 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49851
04:04:03 INFO - 1499339043475 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:03 INFO - 1499339043475 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043478 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043482 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d90dd4cf-d422-4f6d-a6f0-fd0a13a90788","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043483 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:04:03 INFO - 1499339043484 Marionette TRACE 362 <- [1,2,null,{}]
04:04:03 INFO - 1499339043486 Marionette DEBUG Closed connection 362
04:04:03 INFO - 1499339043487 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49852
04:04:03 INFO - 1499339043488 Marionette DEBUG Closed connection 363
04:04:03 INFO - 1499339043489 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49853
04:04:03 INFO - 1499339043490 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:03 INFO - 1499339043490 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043493 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043497 Marionette TRACE 364 <- [1,1,null,{"sessionId":"69417669-1771-4261-9b08-acde22d24144","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043498 Marionette TRACE 364 -> [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"}]
04:04:03 INFO - 1499339043500 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49396/test.html"
04:04:03 INFO - 1499339043503 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49396/test.html"
04:04:03 INFO - 1499339043503 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49396/test.html"
04:04:03 INFO - 1499339043510 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"
04:04:03 INFO - 1499339043515 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"
04:04:03 INFO - 1499339043526 Marionette TRACE 364 <- [1,2,null,{}]
04:04:03 INFO - 1499339043540 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:04:03 INFO - 1499339043544 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3dfb28fd-d5f3-46af-841a-37b6eac24244","ELEMENT":"3dfb28fd-d5f3-46af-841a-37b6eac24244"}}]
04:04:03 INFO - 1499339043545 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"3dfb28fd-d5f3-46af-841a-37b6eac24244"}]
04:04:03 INFO - 1499339043793 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:03 INFO - 1499339043795 Marionette TRACE 364 <- [1,4,null,{}]
04:04:03 INFO - 1499339043796 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"3dfb28fd-d5f3-46af-841a-37b6eac24244","name":"selected"}]
04:04:03 INFO - 1499339043801 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:04:03 INFO - 1499339043803 Marionette TRACE 364 -> [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}]
04:04:03 INFO - TEST-END: test_click.py TestClick.test_container_element1499339043806 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:04:03 INFO - 1499339043807 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:04:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 366ms
04:04:03 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:04:03 INFO - 1499339043809 Marionette TRACE 364 <- [1,7,null,{}]
04:04:03 INFO - 1499339043811 Marionette DEBUG Closed connection 364
04:04:03 INFO - 1499339043812 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49854
04:04:03 INFO - 1499339043813 Marionette DEBUG Closed connection 365
04:04:03 INFO - 1499339043813 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49855
04:04:03 INFO - 1499339043815 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:03 INFO - 1499339043815 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043819 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043822 Marionette TRACE 366 <- [1,1,null,{"sessionId":"286fa762-4ffc-49f8-a052-386b82edcc04","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043823 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:04:03 INFO - 1499339043824 Marionette TRACE 366 <- [1,2,null,{}]
04:04:03 INFO - 1499339043825 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:03 INFO - 1499339043825 Marionette TRACE 366 <- [1,3,null,{}]
04:04:03 INFO - 1499339043826 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:03 INFO - 1499339043827 Marionette TRACE 366 <- [1,4,null,{}]
04:04:03 INFO - 1499339043828 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_outside_view')","sandbox":"simpletest","line":316}]
04:04:03 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499339043831 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:04:03 INFO - 1499339043832 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:04:03 INFO - 1499339043834 Marionette TRACE 366 <- [1,6,null,{}]
04:04:03 INFO - 1499339043836 Marionette DEBUG Closed connection 366
04:04:03 INFO - 1499339043836 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49856
04:04:03 INFO - 1499339043837 Marionette DEBUG Closed connection 367
04:04:03 INFO - 1499339043838 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49857
04:04:03 INFO - 1499339043839 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:03 INFO - 1499339043840 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043843 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043846 Marionette TRACE 368 <- [1,1,null,{"sessionId":"91fb5bfb-13cb-4de5-82f6-90d23ecb42d8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043847 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:04:03 INFO - 1499339043849 Marionette TRACE 368 <- [1,2,null,{}]
04:04:03 INFO - 1499339043851 Marionette DEBUG Closed connection 368
04:04:03 INFO - 1499339043851 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49858
04:04:03 INFO - 1499339043852 Marionette DEBUG Closed connection 369
04:04:03 INFO - 1499339043853 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49859
04:04:03 INFO - 1499339043854 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:03 INFO - 1499339043855 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:03 INFO - 1499339043858 Marionette DEBUG Register listener.js for window 4294967297
04:04:03 INFO - 1499339043861 Marionette TRACE 370 <- [1,1,null,{"sessionId":"68fbcd96-1661-4ae2-92ea-c85da3370ddc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:03 INFO - 1499339043862 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%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"}]
04:04:03 INFO - 1499339043864 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"
04:04:03 INFO - 1499339043866 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"
04:04:03 INFO - 1499339043867 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"
04:04:03 INFO - 1499339043874 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"
04:04:03 INFO - 1499339043878 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"
04:04:03 INFO - 1499339043880 Marionette TRACE 370 <- [1,2,null,{}]
04:04:03 INFO - 1499339043890 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:04:03 INFO - 1499339043893 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c337d73b-b385-4257-89bf-aeae9a116c63","ELEMENT":"c337d73b-b385-4257-89bf-aeae9a116c63"}}]
04:04:03 INFO - 1499339043895 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c337d73b-b385-4257-89bf-aeae9a116c63"}]
04:04:04 INFO - 1499339044139 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:04 INFO - 1499339044140 Marionette TRACE 370 <- [1,4,null,{}]
04:04:04 INFO - 1499339044142 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c337d73b-b385-4257-89bf-aeae9a116c63","name":"selected"}]
04:04:04 INFO - 1499339044146 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:04:04 INFO - 1499339044148 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_outside_view')","sandbox":"simpletest","line":328}]
04:04:04 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499339044151 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:04:04 INFO - 1499339044152 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:04:04 INFO - 1499339044154 Marionette TRACE 370 <- [1,7,null,{}]
04:04:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 345ms
04:04:04 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:04:04 INFO - 1499339044156 Marionette DEBUG Closed connection 370
04:04:04 INFO - 1499339044157 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49860
04:04:04 INFO - 1499339044157 Marionette DEBUG Closed connection 371
04:04:04 INFO - 1499339044158 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49861
04:04:04 INFO - 1499339044159 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:04 INFO - 1499339044160 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044163 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044167 Marionette TRACE 372 <- [1,1,null,{"sessionId":"577ea3d8-c06e-490a-990f-82ca4fb3a9c3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044168 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:04:04 INFO - 1499339044169 Marionette TRACE 372 <- [1,2,null,{}]
04:04:04 INFO - 1499339044170 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:04 INFO - 1499339044170 Marionette TRACE 372 <- [1,3,null,{}]
04:04:04 INFO - 1499339044171 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:04 INFO - 1499339044172 Marionette TRACE 372 <- [1,4,null,{}]
04:04:04 INFO - 1499339044174 Marionette TRACE 372 -> [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}]
04:04:04 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499339044176 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:04:04 INFO - 1499339044177 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:04:04 INFO - 1499339044179 Marionette TRACE 372 <- [1,6,null,{}]
04:04:04 INFO - 1499339044181 Marionette DEBUG Closed connection 372
04:04:04 INFO - 1499339044182 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49862
04:04:04 INFO - 1499339044182 Marionette DEBUG Closed connection 373
04:04:04 INFO - 1499339044183 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49863
04:04:04 INFO - 1499339044184 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:04 INFO - 1499339044185 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044188 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044191 Marionette TRACE 374 <- [1,1,null,{"sessionId":"cb9efeec-fac2-4126-b8c1-6c21a6149f43","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044193 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:04:04 INFO - 1499339044194 Marionette TRACE 374 <- [1,2,null,{}]
04:04:04 INFO - 1499339044196 Marionette DEBUG Closed connection 374
04:04:04 INFO - 1499339044197 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49864
04:04:04 INFO - 1499339044197 Marionette DEBUG Closed connection 375
04:04:04 INFO - 1499339044198 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49865
04:04:04 INFO - 1499339044199 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:04 INFO - 1499339044200 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044203 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044206 Marionette TRACE 376 <- [1,1,null,{"sessionId":"4035e3db-f6ab-4f9a-b421-796667ea4f5b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044207 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%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"}]
04:04:04 INFO - 1499339044209 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"
04:04:04 INFO - 1499339044212 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"
04:04:04 INFO - 1499339044212 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"
04:04:04 INFO - 1499339044221 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"
04:04:04 INFO - 1499339044224 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"
04:04:04 INFO - 1499339044228 Marionette TRACE 376 <- [1,2,null,{}]
04:04:04 INFO - 1499339044240 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:04:04 INFO - 1499339044243 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3e7f9aa1-01df-4a6c-988c-c91ab7fdee63","ELEMENT":"3e7f9aa1-01df-4a6c-988c-c91ab7fdee63"}}]
04:04:04 INFO - 1499339044244 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"3e7f9aa1-01df-4a6c-988c-c91ab7fdee63"}]
04:04:04 INFO - 1499339044485 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:04 INFO - 1499339044486 Marionette TRACE 376 <- [1,4,null,{}]
04:04:04 INFO - 1499339044489 Marionette TRACE 376 -> [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}]
04:04:04 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499339044492 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:04:04 INFO - 1499339044493 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:04:04 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 340ms
04:04:04 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:04:04 INFO - 1499339044494 Marionette TRACE 376 <- [1,6,null,{}]
04:04:04 INFO - 1499339044496 Marionette DEBUG Closed connection 376
04:04:04 INFO - 1499339044497 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49866
04:04:04 INFO - 1499339044498 Marionette DEBUG Closed connection 377
04:04:04 INFO - 1499339044499 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49867
04:04:04 INFO - 1499339044500 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:04 INFO - 1499339044501 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044504 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044507 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9a3f2cb4-e0b4-462f-92cd-04d26b461d2c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044508 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:04:04 INFO - 1499339044509 Marionette TRACE 378 <- [1,2,null,{}]
04:04:04 INFO - 1499339044510 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:04 INFO - 1499339044510 Marionette TRACE 378 <- [1,3,null,{}]
04:04:04 INFO - 1499339044511 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:04 INFO - 1499339044512 Marionette TRACE 378 <- [1,4,null,{}]
04:04:04 INFO - 1499339044514 Marionette TRACE 378 -> [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}]
04:04:04 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499339044517 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:04:04 INFO - 1499339044518 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:04:04 INFO - 1499339044519 Marionette TRACE 378 <- [1,6,null,{}]
04:04:04 INFO - 1499339044521 Marionette DEBUG Closed connection 378
04:04:04 INFO - 1499339044522 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49868
04:04:04 INFO - 1499339044523 Marionette DEBUG Closed connection 379
04:04:04 INFO - 1499339044523 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49869
04:04:04 INFO - 1499339044524 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:04 INFO - 1499339044525 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044528 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044531 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ac380fe1-29d3-4e48-b91a-dca0f92b42be","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044532 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:04:04 INFO - 1499339044534 Marionette TRACE 380 <- [1,2,null,{}]
04:04:04 INFO - 1499339044535 Marionette DEBUG Closed connection 380
04:04:04 INFO - 1499339044536 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49870
04:04:04 INFO - 1499339044537 Marionette DEBUG Closed connection 381
04:04:04 INFO - 1499339044538 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49871
04:04:04 INFO - 1499339044539 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:04 INFO - 1499339044540 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044542 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044546 Marionette TRACE 382 <- [1,1,null,{"sessionId":"a2e33b90-fa55-4147-a14f-c0cae9d43758","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044547 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%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"}]
04:04:04 INFO - 1499339044549 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"
04:04:04 INFO - 1499339044551 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"
04:04:04 INFO - 1499339044551 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"
04:04:04 INFO - 1499339044558 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"
04:04:04 INFO - 1499339044562 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"
04:04:04 INFO - 1499339044570 Marionette TRACE 382 <- [1,2,null,{}]
04:04:04 INFO - 1499339044574 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:04:04 INFO - 1499339044577 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1cde07cb-3d57-4d56-9236-ddbde971fd30","ELEMENT":"1cde07cb-3d57-4d56-9236-ddbde971fd30"}}]
04:04:04 INFO - 1499339044578 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"1cde07cb-3d57-4d56-9236-ddbde971fd30"}]
04:04:04 INFO - 1499339044826 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:04 INFO - 1499339044828 Marionette TRACE 382 <- [1,4,null,{}]
04:04:04 INFO - 1499339044830 Marionette TRACE 382 -> [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}]
04:04:04 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499339044833 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:04:04 INFO - 1499339044834 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:04:04 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 340ms
04:04:04 INFO - TEST-START | test_click.py TestClick.test_input_file
04:04:04 INFO - 1499339044835 Marionette TRACE 382 <- [1,6,null,{}]
04:04:04 INFO - 1499339044837 Marionette DEBUG Closed connection 382
04:04:04 INFO - 1499339044838 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49872
04:04:04 INFO - 1499339044839 Marionette DEBUG Closed connection 383
04:04:04 INFO - 1499339044840 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49873
04:04:04 INFO - 1499339044841 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:04 INFO - 1499339044841 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044845 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044857 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a3f70abb-4faa-4aad-a33d-f51adc03d30e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044858 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:04:04 INFO - 1499339044859 Marionette TRACE 384 <- [1,2,null,{}]
04:04:04 INFO - 1499339044860 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:04 INFO - 1499339044860 Marionette TRACE 384 <- [1,3,null,{}]
04:04:04 INFO - 1499339044861 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:04 INFO - 1499339044862 Marionette TRACE 384 <- [1,4,null,{}]
04:04:04 INFO - 1499339044864 Marionette TRACE 384 -> [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}]
04:04:04 INFO - TEST-START: test_click.py TestClick.test_input_file1499339044867 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:04:04 INFO - 1499339044868 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:04:04 INFO - 1499339044870 Marionette TRACE 384 <- [1,6,null,{}]
04:04:04 INFO - 1499339044872 Marionette DEBUG Closed connection 384
04:04:04 INFO - 1499339044872 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49874
04:04:04 INFO - 1499339044873 Marionette DEBUG Closed connection 385
04:04:04 INFO - 1499339044874 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49875
04:04:04 INFO - 1499339044875 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:04 INFO - 1499339044876 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044879 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044882 Marionette TRACE 386 <- [1,1,null,{"sessionId":"df733162-53db-44fa-a962-37667c79d38d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044883 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:04:04 INFO - 1499339044885 Marionette TRACE 386 <- [1,2,null,{}]
04:04:04 INFO - 1499339044886 Marionette DEBUG Closed connection 386
04:04:04 INFO - 1499339044887 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49876
04:04:04 INFO - 1499339044889 Marionette DEBUG Closed connection 387
04:04:04 INFO - 1499339044891 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49877
04:04:04 INFO - 1499339044892 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:04 INFO - 1499339044893 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044896 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044899 Marionette TRACE 388 <- [1,1,null,{"sessionId":"5dc33978-4991-4d1a-b169-608e44af6375","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:04 INFO - 1499339044900 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:04:04 INFO - 1499339044904 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"
04:04:04 INFO - 1499339044906 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"
04:04:04 INFO - 1499339044907 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"
04:04:04 INFO - 1499339044914 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:04 INFO - 1499339044919 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:04 INFO - 1499339044929 Marionette TRACE 388 <- [1,2,null,{}]
04:04:04 INFO - 1499339044936 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:04:04 INFO - 1499339044942 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"856196c4-4acc-43bc-823e-7cd899671567","ELEMENT":"856196c4-4acc-43bc-823e-7cd899671567"}}]
04:04:04 INFO - 1499339044943 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"856196c4-4acc-43bc-823e-7cd899671567"}]
04:04:04 INFO - 1499339044972 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165: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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:04:04 INFO - 1499339044974 Marionette TRACE 388 -> [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}]
04:04:04 INFO - TEST-END: test_click.py TestClick.test_input_file1499339044982 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:04:04 INFO - 1499339044983 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:04:04 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 149ms
04:04:04 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:04:04 INFO - 1499339044985 Marionette TRACE 388 <- [1,6,null,{}]
04:04:04 INFO - 1499339044987 Marionette DEBUG Closed connection 388
04:04:04 INFO - 1499339044987 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49878
04:04:04 INFO - 1499339044988 Marionette DEBUG Closed connection 389
04:04:04 INFO - 1499339044989 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49879
04:04:04 INFO - 1499339044990 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:04 INFO - 1499339044991 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:04 INFO - 1499339044994 Marionette DEBUG Register listener.js for window 4294967297
04:04:04 INFO - 1499339044997 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c884fb46-0c96-4ae4-a959-a6c0e72c6c6e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:05 INFO - 1499339044998 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:04:05 INFO - 1499339044999 Marionette TRACE 390 <- [1,2,null,{}]
04:04:05 INFO - 1499339045000 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:05 INFO - 1499339045000 Marionette TRACE 390 <- [1,3,null,{}]
04:04:05 INFO - 1499339045001 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:05 INFO - 1499339045002 Marionette TRACE 390 <- [1,4,null,{}]
04:04:05 INFO - 1499339045004 Marionette TRACE 390 -> [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}]
04:04:05 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499339045007 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:04:05 INFO - 1499339045008 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:04:05 INFO - 1499339045009 Marionette TRACE 390 <- [1,6,null,{}]
04:04:05 INFO - 1499339045011 Marionette DEBUG Closed connection 390
04:04:05 INFO - 1499339045012 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49880
04:04:05 INFO - 1499339045012 Marionette DEBUG Closed connection 391
04:04:05 INFO - 1499339045013 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49881
04:04:05 INFO - 1499339045014 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:05 INFO - 1499339045015 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:05 INFO - 1499339045018 Marionette DEBUG Register listener.js for window 4294967297
04:04:05 INFO - 1499339045021 Marionette TRACE 392 <- [1,1,null,{"sessionId":"b7ccc205-a97e-47b5-87a1-fb01fc428ccf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:05 INFO - 1499339045022 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:04:05 INFO - 1499339045024 Marionette TRACE 392 <- [1,2,null,{}]
04:04:05 INFO - 1499339045025 Marionette DEBUG Closed connection 392
04:04:05 INFO - 1499339045026 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49882
04:04:05 INFO - 1499339045027 Marionette DEBUG Closed connection 393
04:04:05 INFO - 1499339045028 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49883
04:04:05 INFO - 1499339045029 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:05 INFO - 1499339045030 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:05 INFO - 1499339045032 Marionette DEBUG Register listener.js for window 4294967297
04:04:05 INFO - 1499339045036 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2cb66ceb-270f-4f10-ac63-5d62e7fcb68e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:05 INFO - 1499339045037 Marionette TRACE 394 -> [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"}]
04:04:05 INFO - 1499339045039 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:05 INFO - 1499339045041 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:05 INFO - 1499339045042 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:05 INFO - 1499339045051 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"
04:04:05 INFO - 1499339045053 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"
04:04:05 INFO - 1499339045060 Marionette TRACE 394 <- [1,2,null,{}]
04:04:05 INFO - 1499339045069 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:04:05 INFO - 1499339045074 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e9fd7353-ddea-4a9e-94ea-a5e235636537","ELEMENT":"e9fd7353-ddea-4a9e-94ea-a5e235636537"}}]
04:04:05 INFO - 1499339045075 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:04:05 INFO - 1499339045082 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4da84198-904b-4781-ba9c-fbefe1a22c0b","ELEMENT":"4da84198-904b-4781-ba9c-fbefe1a22c0b"}}]
04:04:05 INFO - 1499339045083 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e9fd7353-ddea-4a9e-94ea-a5e235636537"}]
04:04:05 INFO - 1499339045335 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:05 INFO - 1499339045336 Marionette TRACE 394 <- [1,5,null,{}]
04:04:05 INFO - 1499339045338 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4da84198-904b-4781-ba9c-fbefe1a22c0b"}]
04:04:05 INFO - 1499339045356 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192: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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:04:05 INFO - 1499339045358 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:04:05 INFO - 1499339045361 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:04:05 INFO - 1499339045363 Marionette TRACE 394 -> [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}]
04:04:05 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499339045366 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:04:05 INFO - 1499339045367 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:04:05 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 384ms
04:04:05 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:04:05 INFO - 1499339045369 Marionette TRACE 394 <- [1,9,null,{}]
04:04:05 INFO - 1499339045371 Marionette DEBUG Closed connection 394
04:04:05 INFO - 1499339045371 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49884
04:04:05 INFO - 1499339045372 Marionette DEBUG Closed connection 395
04:04:05 INFO - 1499339045373 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49885
04:04:05 INFO - 1499339045374 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:05 INFO - 1499339045375 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:05 INFO - 1499339045378 Marionette DEBUG Register listener.js for window 4294967297
04:04:05 INFO - 1499339045381 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1cd7049a-5354-46ad-a594-bb6e895a79ec","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:05 INFO - 1499339045382 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:04:05 INFO - 1499339045383 Marionette TRACE 396 <- [1,2,null,{}]
04:04:05 INFO - 1499339045384 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:05 INFO - 1499339045384 Marionette TRACE 396 <- [1,3,null,{}]
04:04:05 INFO - 1499339045385 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:05 INFO - 1499339045386 Marionette TRACE 396 <- [1,4,null,{}]
04:04:05 INFO - 1499339045387 Marionette TRACE 396 -> [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}]
04:04:05 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499339045390 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:04:05 INFO - 1499339045391 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:04:05 INFO - 1499339045393 Marionette TRACE 396 <- [1,6,null,{}]
04:04:05 INFO - 1499339045394 Marionette DEBUG Closed connection 396
04:04:05 INFO - 1499339045395 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49886
04:04:05 INFO - 1499339045396 Marionette DEBUG Closed connection 397
04:04:05 INFO - 1499339045397 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49887
04:04:05 INFO - 1499339045398 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:05 INFO - 1499339045399 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:05 INFO - 1499339045402 Marionette DEBUG Register listener.js for window 4294967297
04:04:05 INFO - 1499339045405 Marionette TRACE 398 <- [1,1,null,{"sessionId":"7c3d3cc3-973a-4c47-931f-c733b092935a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:05 INFO - 1499339045406 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:04:05 INFO - 1499339045407 Marionette TRACE 398 <- [1,2,null,{}]
04:04:05 INFO - 1499339045409 Marionette DEBUG Closed connection 398
04:04:05 INFO - 1499339045410 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49888
04:04:05 INFO - 1499339045411 Marionette DEBUG Closed connection 399
04:04:05 INFO - 1499339045411 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49889
04:04:05 INFO - 1499339045412 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:05 INFO - 1499339045413 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}
04:04:05 INFO - 1499339045416 Marionette DEBUG Register listener.js for window 4294967297
04:04:05 INFO - 1499339045419 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1af60c63-b14f-46e6-9c83-d9e2db8ac1f9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3032,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkqnepc.mozrunner","moz:accessibilityChecks":false}}]
04:04:05 INFO - 1499339045420 Marionette TRACE 400 -> [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"}]
04:04:05 INFO - 1499339045422 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"
04:04:05 INFO - 1499339045424 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"
04:04:05 INFO - 1499339045425 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"
04:04:05 INFO - 1499339045434 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"
04:04:05 INFO - 1499339045436 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"
04:04:05 INFO - 1499339045445 Marionette TRACE 400 <- [1,2,null,{}]
04:04:05 INFO - 1499339045454 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:04:05 INFO - 1499339045459 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2e0a5de7-fe35-457c-97f5-9d0955b81f2f","ELEMENT":"2e0a5de7-fe35-457c-97f5-9d0955b81f2f"}}]
04:04:05 INFO - 1499339045460 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2e0a5de7-fe35-457c-97f5-9d0955b81f2f"}]
04:04:05 INFO - 1499339045488 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:04:05 INFO - 1499339045489 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"2e0a5de7-fe35-457c-97f5-9d0955b81f2f"}]
04:04:05 INFO - 1499339045507 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element