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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
01:54:24 INFO - 1499676864255 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}]
01:54:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 162ms
01:54:24 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
01:54:24 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499676864265 Marionette TRACE 352 <- [1,5,null,{"value":null}]
01:54:24 INFO - 1499676864266 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
01:54:24 INFO - 1499676864268 Marionette TRACE 352 <- [1,6,null,{}]
01:54:24 INFO - 1499676864270 Marionette DEBUG Closed connection 352
01:54:24 INFO - 1499676864270 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49813
01:54:24 INFO - 1499676864271 Marionette DEBUG Closed connection 353
01:54:24 INFO - 1499676864272 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49814
01:54:24 INFO - 1499676864273 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:24 INFO - 1499676864274 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864277 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864281 Marionette TRACE 354 <- [1,1,null,{"sessionId":"af171636-948c-49b3-bea7-96371812087a","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864282 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
01:54:24 INFO - 1499676864283 Marionette TRACE 354 <- [1,2,null,{}]
01:54:24 INFO - 1499676864284 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:54:24 INFO - 1499676864284 Marionette TRACE 354 <- [1,3,null,{}]
01:54:24 INFO - 1499676864285 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
01:54:24 INFO - 1499676864286 Marionette TRACE 354 <- [1,4,null,{}]
01:54:24 INFO - 1499676864288 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}]
01:54:24 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499676864291 Marionette TRACE 354 <- [1,5,null,{"value":null}]
01:54:24 INFO - 1499676864292 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
01:54:24 INFO - 1499676864293 Marionette TRACE 354 <- [1,6,null,{}]
01:54:24 INFO - 1499676864295 Marionette DEBUG Closed connection 354
01:54:24 INFO - 1499676864296 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49815
01:54:24 INFO - 1499676864297 Marionette DEBUG Closed connection 355
01:54:24 INFO - 1499676864298 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49816
01:54:24 INFO - 1499676864299 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:24 INFO - 1499676864299 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864302 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864306 Marionette TRACE 356 <- [1,1,null,{"sessionId":"bf615059-9da6-459d-bb61-4eac973f8808","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864307 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
01:54:24 INFO - 1499676864309 Marionette TRACE 356 <- [1,2,null,{}]
01:54:24 INFO - 1499676864311 Marionette DEBUG Closed connection 356
01:54:24 INFO - 1499676864311 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49817
01:54:24 INFO - 1499676864312 Marionette DEBUG Closed connection 357
01:54:24 INFO - 1499676864313 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49818
01:54:24 INFO - 1499676864314 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:54:24 INFO - 1499676864315 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864318 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864321 Marionette TRACE 358 <- [1,1,null,{"sessionId":"48ae2e6e-a7db-49d4-8153-d183f9ad7a7c","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864322 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49367/clicks.html"}]
01:54:24 INFO - 1499676864325 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/hidden.html"
01:54:24 INFO - 1499676864334 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/hidden.html"
01:54:24 INFO - 1499676864335 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/hidden.html"
01:54:24 INFO - 1499676864349 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49367/clicks.html"
01:54:24 INFO - 1499676864350 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49367/clicks.html"
01:54:24 INFO - 1499676864354 Marionette TRACE 358 <- [1,2,null,{}]
01:54:24 INFO - 1499676864364 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
01:54:24 INFO - 1499676864370 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d2bcbeb6-f39d-47d1-bae5-aaf666026f2a","ELEMENT":"d2bcbeb6-f39d-47d1-bae5-aaf666026f2a"}}]
01:54:24 INFO - 1499676864372 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"d2bcbeb6-f39d-47d1-bae5-aaf666026f2a"}]
01:54:24 INFO - 1499676864429 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/clicks.html"
01:54:24 INFO - 1499676864445 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/clicks.html"
01:54:24 INFO - 1499676864446 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/clicks.html"
01:54:24 INFO - 1499676864456 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49367/test.html"
01:54:24 INFO - 1499676864463 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49367/test.html"
01:54:24 INFO - 1499676864464 Marionette TRACE 358 <- [1,4,null,{}]
01:54:24 INFO - 1499676864469 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
01:54:24 INFO - 1499676864472 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"11dfa5b7-012d-4013-a46e-828882f93227","ELEMENT":"11dfa5b7-012d-4013-a46e-828882f93227"}}]
01:54:24 INFO - 1499676864478 Marionette TRACE 358 -> [0,6,"getTitle",{}]
01:54:24 INFO - 1499676864478 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
01:54:24 INFO - 1499676864481 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}]
01:54:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499676864494 Marionette TRACE 358 <- [1,7,null,{"value":null}]
01:54:24 INFO - 1499676864496 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
01:54:24 INFO - 1499676864497 Marionette TRACE 358 <- [1,8,null,{}]
01:54:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 229ms
01:54:24 INFO - TEST-START | test_click.py TestClick.test_container_element
01:54:24 INFO - 1499676864499 Marionette DEBUG Closed connection 358
01:54:24 INFO - 1499676864500 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49821
01:54:24 INFO - 1499676864501 Marionette DEBUG Closed connection 359
01:54:24 INFO - 1499676864502 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49822
01:54:24 INFO - 1499676864503 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:24 INFO - 1499676864504 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864507 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864510 Marionette TRACE 360 <- [1,1,null,{"sessionId":"92e01f20-9295-4b88-a42c-4e6559efd21e","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864512 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
01:54:24 INFO - 1499676864512 Marionette TRACE 360 <- [1,2,null,{}]
01:54:24 INFO - 1499676864513 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:54:24 INFO - 1499676864514 Marionette TRACE 360 <- [1,3,null,{}]
01:54:24 INFO - 1499676864515 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
01:54:24 INFO - 1499676864515 Marionette TRACE 360 <- [1,4,null,{}]
01:54:24 INFO - 1499676864517 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}]
01:54:24 INFO - TEST-START: test_click.py TestClick.test_container_element1499676864520 Marionette TRACE 360 <- [1,5,null,{"value":null}]
01:54:24 INFO - 1499676864521 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
01:54:24 INFO - 1499676864523 Marionette TRACE 360 <- [1,6,null,{}]
01:54:24 INFO - 1499676864525 Marionette DEBUG Closed connection 360
01:54:24 INFO - 1499676864525 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49823
01:54:24 INFO - 1499676864526 Marionette DEBUG Closed connection 361
01:54:24 INFO - 1499676864527 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49824
01:54:24 INFO - 1499676864528 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:24 INFO - 1499676864529 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864532 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864535 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b48176fc-5518-41c3-9b4d-85b362691981","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864536 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
01:54:24 INFO - 1499676864538 Marionette TRACE 362 <- [1,2,null,{}]
01:54:24 INFO - 1499676864539 Marionette DEBUG Closed connection 362
01:54:24 INFO - 1499676864540 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49825
01:54:24 INFO - 1499676864541 Marionette DEBUG Closed connection 363
01:54:24 INFO - 1499676864542 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49826
01:54:24 INFO - 1499676864543 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:54:24 INFO - 1499676864544 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864547 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864550 Marionette TRACE 364 <- [1,1,null,{"sessionId":"832c8003-c511-4548-b3a7-76246a138a5f","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864551 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"}]
01:54:24 INFO - 1499676864553 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/test.html"
01:54:24 INFO - 1499676864556 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/test.html"
01:54:24 INFO - 1499676864556 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/test.html"
01:54:24 INFO - 1499676864580 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"
01:54:24 INFO - 1499676864585 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"
01:54:24 INFO - 1499676864586 Marionette TRACE 364 <- [1,2,null,{}]
01:54:24 INFO - 1499676864595 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:54:24 INFO - 1499676864606 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eb98597a-beaa-4773-a9cf-0636edbcbe84","ELEMENT":"eb98597a-beaa-4773-a9cf-0636edbcbe84"}}]
01:54:24 INFO - 1499676864608 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"eb98597a-beaa-4773-a9cf-0636edbcbe84"}]
01:54:24 INFO - 1499676864852 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:54:24 INFO - 1499676864853 Marionette TRACE 364 <- [1,4,null,{}]
01:54:24 INFO - 1499676864854 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"eb98597a-beaa-4773-a9cf-0636edbcbe84","name":"selected"}]
01:54:24 INFO - 1499676864859 Marionette TRACE 364 <- [1,5,null,{"value":true}]
01:54:24 INFO - 1499676864861 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}]
01:54:24 INFO - TEST-END: test_click.py TestClick.test_container_element1499676864864 Marionette TRACE 364 <- [1,6,null,{"value":null}]
01:54:24 INFO - 1499676864865 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
01:54:24 INFO - 1499676864866 Marionette TRACE 364 <- [1,7,null,{}]
01:54:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
01:54:24 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
01:54:24 INFO - 1499676864868 Marionette DEBUG Closed connection 364
01:54:24 INFO - 1499676864869 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49827
01:54:24 INFO - 1499676864870 Marionette DEBUG Closed connection 365
01:54:24 INFO - 1499676864871 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49828
01:54:24 INFO - 1499676864872 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:24 INFO - 1499676864872 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864875 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864879 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8f4124fd-669e-400f-bff0-ae040485695b","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864880 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
01:54:24 INFO - 1499676864880 Marionette TRACE 366 <- [1,2,null,{}]
01:54:24 INFO - 1499676864881 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:54:24 INFO - 1499676864882 Marionette TRACE 366 <- [1,3,null,{}]
01:54:24 INFO - 1499676864883 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
01:54:24 INFO - 1499676864883 Marionette TRACE 366 <- [1,4,null,{}]
01:54:24 INFO - 1499676864885 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}]
01:54:24 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499676864888 Marionette TRACE 366 <- [1,5,null,{"value":null}]
01:54:24 INFO - 1499676864889 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
01:54:24 INFO - 1499676864891 Marionette TRACE 366 <- [1,6,null,{}]
01:54:24 INFO - 1499676864892 Marionette DEBUG Closed connection 366
01:54:24 INFO - 1499676864893 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49829
01:54:24 INFO - 1499676864894 Marionette DEBUG Closed connection 367
01:54:24 INFO - 1499676864895 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49830
01:54:24 INFO - 1499676864896 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:24 INFO - 1499676864897 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864899 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864911 Marionette TRACE 368 <- [1,1,null,{"sessionId":"65608e45-6d04-4110-bfa5-80b9b7d90311","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864913 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
01:54:24 INFO - 1499676864914 Marionette TRACE 368 <- [1,2,null,{}]
01:54:24 INFO - 1499676864916 Marionette DEBUG Closed connection 368
01:54:24 INFO - 1499676864917 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49831
01:54:24 INFO - 1499676864918 Marionette DEBUG Closed connection 369
01:54:24 INFO - 1499676864919 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49832
01:54:24 INFO - 1499676864920 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:54:24 INFO - 1499676864921 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:24 INFO - 1499676864924 Marionette DEBUG Register listener.js for window 4294967297
01:54:24 INFO - 1499676864927 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7cfef94a-243d-48ac-9d58-d8ec60991865","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:24 INFO - 1499676864928 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"}]
01:54:24 INFO - 1499676864931 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"
01:54:24 INFO - 1499676864933 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"
01:54:24 INFO - 1499676864933 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"
01:54:24 INFO - 1499676864943 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"
01:54:24 INFO - 1499676864945 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"
01:54:24 INFO - 1499676864953 Marionette TRACE 370 <- [1,2,null,{}]
01:54:24 INFO - 1499676864962 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:54:24 INFO - 1499676864969 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"35bcbc24-74c5-4c59-8dcb-59f5251bf01d","ELEMENT":"35bcbc24-74c5-4c59-8dcb-59f5251bf01d"}}]
01:54:24 INFO - 1499676864970 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"35bcbc24-74c5-4c59-8dcb-59f5251bf01d"}]
01:54:25 INFO - 1499676865216 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:54:25 INFO - 1499676865217 Marionette TRACE 370 <- [1,4,null,{}]
01:54:25 INFO - 1499676865218 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"35bcbc24-74c5-4c59-8dcb-59f5251bf01d","name":"selected"}]
01:54:25 INFO - 1499676865223 Marionette TRACE 370 <- [1,5,null,{"value":true}]
01:54:25 INFO - 1499676865225 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}]
01:54:25 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499676865228 Marionette TRACE 370 <- [1,6,null,{"value":null}]
01:54:25 INFO - 1499676865229 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
01:54:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 364ms
01:54:25 INFO - TEST-START | test_click.py TestClick.test_css_transforms
01:54:25 INFO - 1499676865231 Marionette TRACE 370 <- [1,7,null,{}]
01:54:25 INFO - 1499676865233 Marionette DEBUG Closed connection 370
01:54:25 INFO - 1499676865233 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49833
01:54:25 INFO - 1499676865234 Marionette DEBUG Closed connection 371
01:54:25 INFO - 1499676865235 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49834
01:54:25 INFO - 1499676865236 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:25 INFO - 1499676865237 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865240 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865244 Marionette TRACE 372 <- [1,1,null,{"sessionId":"03d47af2-2079-4c5d-8948-40c83f26979c","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865245 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
01:54:25 INFO - 1499676865246 Marionette TRACE 372 <- [1,2,null,{}]
01:54:25 INFO - 1499676865247 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:54:25 INFO - 1499676865247 Marionette TRACE 372 <- [1,3,null,{}]
01:54:25 INFO - 1499676865248 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
01:54:25 INFO - 1499676865248 Marionette TRACE 372 <- [1,4,null,{}]
01:54:25 INFO - 1499676865250 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}]
01:54:25 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499676865253 Marionette TRACE 372 <- [1,5,null,{"value":null}]
01:54:25 INFO - 1499676865254 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
01:54:25 INFO - 1499676865256 Marionette TRACE 372 <- [1,6,null,{}]
01:54:25 INFO - 1499676865257 Marionette DEBUG Closed connection 372
01:54:25 INFO - 1499676865258 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49835
01:54:25 INFO - 1499676865259 Marionette DEBUG Closed connection 373
01:54:25 INFO - 1499676865260 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49836
01:54:25 INFO - 1499676865261 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:25 INFO - 1499676865261 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865264 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865268 Marionette TRACE 374 <- [1,1,null,{"sessionId":"8f487074-a98a-432e-9c28-211c1a56725a","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865269 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
01:54:25 INFO - 1499676865270 Marionette TRACE 374 <- [1,2,null,{}]
01:54:25 INFO - 1499676865272 Marionette DEBUG Closed connection 374
01:54:25 INFO - 1499676865273 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49837
01:54:25 INFO - 1499676865274 Marionette DEBUG Closed connection 375
01:54:25 INFO - 1499676865274 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49838
01:54:25 INFO - 1499676865276 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:54:25 INFO - 1499676865276 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865279 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865282 Marionette TRACE 376 <- [1,1,null,{"sessionId":"d619c909-a788-4a19-96c4-cbf9483d907a","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865284 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"}]
01:54:25 INFO - 1499676865285 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"
01:54:25 INFO - 1499676865288 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"
01:54:25 INFO - 1499676865288 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"
01:54:25 INFO - 1499676865295 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"
01:54:25 INFO - 1499676865298 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"
01:54:25 INFO - 1499676865304 Marionette TRACE 376 <- [1,2,null,{}]
01:54:25 INFO - 1499676865316 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
01:54:25 INFO - 1499676865319 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"684a43ee-64a0-42c7-ad7a-f3a16eeb6d41","ELEMENT":"684a43ee-64a0-42c7-ad7a-f3a16eeb6d41"}}]
01:54:25 INFO - 1499676865320 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"684a43ee-64a0-42c7-ad7a-f3a16eeb6d41"}]
01:54:25 INFO - 1499676865561 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:54:25 INFO - 1499676865562 Marionette TRACE 376 <- [1,4,null,{}]
01:54:25 INFO - 1499676865564 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}]
01:54:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499676865567 Marionette TRACE 376 <- [1,5,null,{"value":null}]
01:54:25 INFO - 1499676865568 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
01:54:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 338ms
01:54:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
01:54:25 INFO - 1499676865570 Marionette TRACE 376 <- [1,6,null,{}]
01:54:25 INFO - 1499676865571 Marionette DEBUG Closed connection 376
01:54:25 INFO - 1499676865572 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49839
01:54:25 INFO - 1499676865573 Marionette DEBUG Closed connection 377
01:54:25 INFO - 1499676865574 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49840
01:54:25 INFO - 1499676865575 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:25 INFO - 1499676865576 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865579 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865583 Marionette TRACE 378 <- [1,1,null,{"sessionId":"992a6e84-1af0-4f63-b862-c163ea6c2c0a","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865584 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
01:54:25 INFO - 1499676865585 Marionette TRACE 378 <- [1,2,null,{}]
01:54:25 INFO - 1499676865585 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:54:25 INFO - 1499676865586 Marionette TRACE 378 <- [1,3,null,{}]
01:54:25 INFO - 1499676865587 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
01:54:25 INFO - 1499676865587 Marionette TRACE 378 <- [1,4,null,{}]
01:54:25 INFO - 1499676865589 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}]
01:54:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499676865592 Marionette TRACE 378 <- [1,5,null,{"value":null}]
01:54:25 INFO - 1499676865593 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
01:54:25 INFO - 1499676865595 Marionette TRACE 378 <- [1,6,null,{}]
01:54:25 INFO - 1499676865597 Marionette DEBUG Closed connection 378
01:54:25 INFO - 1499676865597 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49841
01:54:25 INFO - 1499676865598 Marionette DEBUG Closed connection 379
01:54:25 INFO - 1499676865599 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49842
01:54:25 INFO - 1499676865600 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:25 INFO - 1499676865601 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865604 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865608 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9d1a633b-5543-4301-97ed-0a6938917f99","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865609 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
01:54:25 INFO - 1499676865611 Marionette TRACE 380 <- [1,2,null,{}]
01:54:25 INFO - 1499676865612 Marionette DEBUG Closed connection 380
01:54:25 INFO - 1499676865613 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49843
01:54:25 INFO - 1499676865614 Marionette DEBUG Closed connection 381
01:54:25 INFO - 1499676865615 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49844
01:54:25 INFO - 1499676865616 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:54:25 INFO - 1499676865617 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865620 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865624 Marionette TRACE 382 <- [1,1,null,{"sessionId":"06b69a5f-1037-4af0-ad9d-021b0dcc85e2","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865625 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"}]
01:54:25 INFO - 1499676865627 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"
01:54:25 INFO - 1499676865629 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"
01:54:25 INFO - 1499676865630 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"
01:54:25 INFO - 1499676865637 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"
01:54:25 INFO - 1499676865641 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"
01:54:25 INFO - 1499676865649 Marionette TRACE 382 <- [1,2,null,{}]
01:54:25 INFO - 1499676865653 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
01:54:25 INFO - 1499676865656 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"afa1b9fb-564e-4cd7-a862-1746dc7ef40b","ELEMENT":"afa1b9fb-564e-4cd7-a862-1746dc7ef40b"}}]
01:54:25 INFO - 1499676865663 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"afa1b9fb-564e-4cd7-a862-1746dc7ef40b"}]
01:54:25 INFO - 1499676865912 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:54:25 INFO - 1499676865913 Marionette TRACE 382 <- [1,4,null,{}]
01:54:25 INFO - 1499676865915 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}]
01:54:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499676865918 Marionette TRACE 382 <- [1,5,null,{"value":null}]
01:54:25 INFO - 1499676865919 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
01:54:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 350ms
01:54:25 INFO - TEST-START | test_click.py TestClick.test_input_file
01:54:25 INFO - 1499676865921 Marionette TRACE 382 <- [1,6,null,{}]
01:54:25 INFO - 1499676865922 Marionette DEBUG Closed connection 382
01:54:25 INFO - 1499676865923 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49845
01:54:25 INFO - 1499676865924 Marionette DEBUG Closed connection 383
01:54:25 INFO - 1499676865925 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49846
01:54:25 INFO - 1499676865926 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:25 INFO - 1499676865927 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865930 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865934 Marionette TRACE 384 <- [1,1,null,{"sessionId":"35291401-1280-449b-9b87-7e98269afcb0","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865935 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
01:54:25 INFO - 1499676865935 Marionette TRACE 384 <- [1,2,null,{}]
01:54:25 INFO - 1499676865936 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:54:25 INFO - 1499676865937 Marionette TRACE 384 <- [1,3,null,{}]
01:54:25 INFO - 1499676865938 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
01:54:25 INFO - 1499676865938 Marionette TRACE 384 <- [1,4,null,{}]
01:54:25 INFO - 1499676865940 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}]
01:54:25 INFO - TEST-START: test_click.py TestClick.test_input_file1499676865943 Marionette TRACE 384 <- [1,5,null,{"value":null}]
01:54:25 INFO - 1499676865944 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
01:54:25 INFO - 1499676865946 Marionette TRACE 384 <- [1,6,null,{}]
01:54:25 INFO - 1499676865947 Marionette DEBUG Closed connection 384
01:54:25 INFO - 1499676865948 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49847
01:54:25 INFO - 1499676865949 Marionette DEBUG Closed connection 385
01:54:25 INFO - 1499676865949 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49848
01:54:25 INFO - 1499676865951 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:25 INFO - 1499676865951 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865954 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865958 Marionette TRACE 386 <- [1,1,null,{"sessionId":"24ae5780-dc56-4633-8594-f46f12aa2ed7","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865959 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
01:54:25 INFO - 1499676865960 Marionette TRACE 386 <- [1,2,null,{}]
01:54:25 INFO - 1499676865962 Marionette DEBUG Closed connection 386
01:54:25 INFO - 1499676865963 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49849
01:54:25 INFO - 1499676865964 Marionette DEBUG Closed connection 387
01:54:25 INFO - 1499676865965 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49850
01:54:25 INFO - 1499676865966 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:54:25 INFO - 1499676865967 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:25 INFO - 1499676865969 Marionette DEBUG Register listener.js for window 4294967297
01:54:25 INFO - 1499676865973 Marionette TRACE 388 <- [1,1,null,{"sessionId":"8ce617f6-0362-4e0a-95de-74bbb0081e6f","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:25 INFO - 1499676865974 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
01:54:25 INFO - 1499676865976 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"
01:54:25 INFO - 1499676865979 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"
01:54:25 INFO - 1499676865979 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"
01:54:25 INFO - 1499676865986 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:54:25 INFO - 1499676865991 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:54:26 INFO - 1499676866004 Marionette TRACE 388 <- [1,2,null,{}]
01:54:26 INFO - 1499676866013 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
01:54:26 INFO - 1499676866019 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d799d0e7-2d78-4ee7-87b0-0fd6c2ccd607","ELEMENT":"d799d0e7-2d78-4ee7-87b0-0fd6c2ccd607"}}]
01:54:26 INFO - 1499676866020 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d799d0e7-2d78-4ee7-87b0-0fd6c2ccd607"}]
01:54:26 INFO - 1499676866048 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
01:54:26 INFO - 1499676866050 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}]
01:54:26 INFO - TEST-END: test_click.py TestClick.test_input_file1499676866053 Marionette TRACE 388 <- [1,5,null,{"value":null}]
01:54:26 INFO - 1499676866054 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
01:54:26 INFO - 1499676866056 Marionette TRACE 388 <- [1,6,null,{}]
01:54:26 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 134ms
01:54:26 INFO - TEST-START | test_click.py TestClick.test_obscured_element
01:54:26 INFO - 1499676866057 Marionette DEBUG Closed connection 388
01:54:26 INFO - 1499676866058 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49851
01:54:26 INFO - 1499676866059 Marionette DEBUG Closed connection 389
01:54:26 INFO - 1499676866060 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49852
01:54:26 INFO - 1499676866061 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:26 INFO - 1499676866062 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:26 INFO - 1499676866110 Marionette DEBUG Register listener.js for window 4294967297
01:54:26 INFO - 1499676866114 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fdd6c411-21d5-448b-9fd0-14fcd8d80809","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:26 INFO - 1499676866115 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
01:54:26 INFO - 1499676866116 Marionette TRACE 390 <- [1,2,null,{}]
01:54:26 INFO - 1499676866116 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:54:26 INFO - 1499676866117 Marionette TRACE 390 <- [1,3,null,{}]
01:54:26 INFO - 1499676866118 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
01:54:26 INFO - 1499676866118 Marionette TRACE 390 <- [1,4,null,{}]
01:54:26 INFO - 1499676866120 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}]
01:54:26 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499676866126 Marionette TRACE 390 <- [1,5,null,{"value":null}]
01:54:26 INFO - 1499676866127 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
01:54:26 INFO - 1499676866128 Marionette TRACE 390 <- [1,6,null,{}]
01:54:26 INFO - 1499676866130 Marionette DEBUG Closed connection 390
01:54:26 INFO - 1499676866131 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49853
01:54:26 INFO - 1499676866132 Marionette DEBUG Closed connection 391
01:54:26 INFO - 1499676866133 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49854
01:54:26 INFO - 1499676866134 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:26 INFO - 1499676866134 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:26 INFO - 1499676866137 Marionette DEBUG Register listener.js for window 4294967297
01:54:26 INFO - 1499676866140 Marionette TRACE 392 <- [1,1,null,{"sessionId":"74249fd2-cfbd-4d8a-8147-5e5a7a8ed3a3","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:26 INFO - 1499676866142 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
01:54:26 INFO - 1499676866143 Marionette TRACE 392 <- [1,2,null,{}]
01:54:26 INFO - 1499676866145 Marionette DEBUG Closed connection 392
01:54:26 INFO - 1499676866146 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49855
01:54:26 INFO - 1499676866146 Marionette DEBUG Closed connection 393
01:54:26 INFO - 1499676866147 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49856
01:54:26 INFO - 1499676866148 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:54:26 INFO - 1499676866149 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:26 INFO - 1499676866152 Marionette DEBUG Register listener.js for window 4294967297
01:54:26 INFO - 1499676866155 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f3a709d9-bb79-40a4-ac8b-12a95c6c9b8e","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:26 INFO - 1499676866156 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"}]
01:54:26 INFO - 1499676866158 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:54:26 INFO - 1499676866161 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:54:26 INFO - 1499676866161 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:54:26 INFO - 1499676866169 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"
01:54:26 INFO - 1499676866172 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"
01:54:26 INFO - 1499676866181 Marionette TRACE 394 <- [1,2,null,{}]
01:54:26 INFO - 1499676866185 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
01:54:26 INFO - 1499676866188 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6b40f785-eef6-4850-940a-c8b7e45928b8","ELEMENT":"6b40f785-eef6-4850-940a-c8b7e45928b8"}}]
01:54:26 INFO - 1499676866194 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
01:54:26 INFO - 1499676866197 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bb3f9ca3-e788-406c-9341-1ebcfc2f214b","ELEMENT":"bb3f9ca3-e788-406c-9341-1ebcfc2f214b"}}]
01:54:26 INFO - 1499676866198 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"6b40f785-eef6-4850-940a-c8b7e45928b8"}]
01:54:26 INFO - 1499676866443 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:54:26 INFO - 1499676866444 Marionette TRACE 394 <- [1,5,null,{}]
01:54:26 INFO - 1499676866445 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"bb3f9ca3-e788-406c-9341-1ebcfc2f214b"}]
01:54:26 INFO - 1499676866463 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
01:54:26 INFO - 1499676866465 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
01:54:26 INFO - 1499676866532 Marionette TRACE 394 <- [1,7,null,{"value":false}]
01:54:26 INFO - 1499676866536 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}]
01:54:26 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499676866541 Marionette TRACE 394 <- [1,8,null,{"value":null}]
01:54:26 INFO - 1499676866543 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
01:54:26 INFO - 1499676866546 Marionette TRACE 394 <- [1,9,null,{}]
01:54:26 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 490ms
01:54:26 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
01:54:26 INFO - 1499676866576 Marionette DEBUG Closed connection 394
01:54:26 INFO - 1499676866577 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49857
01:54:26 INFO - 1499676866579 Marionette DEBUG Closed connection 395
01:54:26 INFO - 1499676866580 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49858
01:54:26 INFO - 1499676866582 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:26 INFO - 1499676866585 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:26 INFO - 1499676866589 Marionette DEBUG Register listener.js for window 4294967297
01:54:26 INFO - 1499676866598 Marionette TRACE 396 <- [1,1,null,{"sessionId":"2f719591-6859-48d3-be45-27923b143774","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:26 INFO - 1499676866600 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
01:54:26 INFO - 1499676866602 Marionette TRACE 396 <- [1,2,null,{}]
01:54:26 INFO - 1499676866603 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:54:26 INFO - 1499676866604 Marionette TRACE 396 <- [1,3,null,{}]
01:54:26 INFO - 1499676866606 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
01:54:26 INFO - 1499676866607 Marionette TRACE 396 <- [1,4,null,{}]
01:54:26 INFO - 1499676866609 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}]
01:54:26 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499676866613 Marionette TRACE 396 <- [1,5,null,{"value":null}]
01:54:26 INFO - 1499676866615 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
01:54:26 INFO - 1499676866618 Marionette TRACE 396 <- [1,6,null,{}]
01:54:26 INFO - 1499676866620 Marionette DEBUG Closed connection 396
01:54:26 INFO - 1499676866622 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49859
01:54:26 INFO - 1499676866623 Marionette DEBUG Closed connection 397
01:54:26 INFO - 1499676866624 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49860
01:54:26 INFO - 1499676866626 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:54:26 INFO - 1499676866627 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:26 INFO - 1499676866630 Marionette DEBUG Register listener.js for window 4294967297
01:54:26 INFO - 1499676866635 Marionette TRACE 398 <- [1,1,null,{"sessionId":"195641ac-4250-40dd-8317-ee5661702a45","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:26 INFO - 1499676866636 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
01:54:26 INFO - 1499676866639 Marionette TRACE 398 <- [1,2,null,{}]
01:54:26 INFO - 1499676866641 Marionette DEBUG Closed connection 398
01:54:26 INFO - 1499676866642 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49861
01:54:26 INFO - 1499676866643 Marionette DEBUG Closed connection 399
01:54:26 INFO - 1499676866644 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49862
01:54:26 INFO - 1499676866648 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:54:26 INFO - 1499676866649 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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}
01:54:26 INFO - 1499676866652 Marionette DEBUG Register listener.js for window 4294967297
01:54:26 INFO - 1499676866656 Marionette TRACE 400 <- [1,1,null,{"sessionId":"13e4b22a-0783-40e7-941d-8a87ab508e46","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":3548,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeq7h4f.mozrunner","moz:accessibilityChecks":false}}]
01:54:26 INFO - 1499676866666 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"}]
01:54:26 INFO - 1499676866669 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"
01:54:26 INFO - 1499676866671 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"
01:54:26 INFO - 1499676866671 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"
01:54:26 INFO - 1499676866678 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"
01:54:26 INFO - 1499676866682 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"
01:54:26 INFO - 1499676866700 Marionette TRACE 400 <- [1,2,null,{}]
01:54:26 INFO - 1499676866720 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
01:54:26 INFO - 1499676866726 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"69cef886-5524-4289-8e55-805994b1725e","ELEMENT":"69cef886-5524-4289-8e55-805994b1725e"}}]
01:54:26 INFO - 1499676866733 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"69cef886-5524-4289-8e55-805994b1725e"}]
01:54:26 INFO - 1499676866762 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
01:54:26 INFO - 1499676866763 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"69cef886-5524-4289-8e55-805994b1725e"}]
01:54:26 INFO - 1499676866782 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element