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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:49:10 INFO - 1500227350381 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}]
10:49:10 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500227350385 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:49:10 INFO - 1500227350386 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:49:10 INFO - 1500227350388 Marionette TRACE 352 <- [1,6,null,{}]
10:49:10 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 175ms
10:49:10 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:49:10 INFO - 1500227350391 Marionette DEBUG Closed connection 352
10:49:10 INFO - 1500227350391 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50034
10:49:10 INFO - 1500227350392 Marionette DEBUG Closed connection 353
10:49:10 INFO - 1500227350393 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50035
10:49:10 INFO - 1500227350394 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:10 INFO - 1500227350395 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:10 INFO - 1500227350398 Marionette DEBUG Register listener.js for window 15
10:49:10 INFO - 1500227350402 Marionette TRACE 354 <- [1,1,null,{"sessionId":"96bd1948-2294-4d59-a9cb-b8972f140cd5","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:10 INFO - 1500227350404 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:49:10 INFO - 1500227350405 Marionette TRACE 354 <- [1,2,null,{}]
10:49:10 INFO - 1500227350406 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:10 INFO - 1500227350406 Marionette TRACE 354 <- [1,3,null,{}]
10:49:10 INFO - 1500227350407 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:10 INFO - 1500227350408 Marionette TRACE 354 <- [1,4,null,{}]
10:49:10 INFO - 1500227350410 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}]
10:49:10 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500227350413 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:49:10 INFO - 1500227350414 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:49:10 INFO - 1500227350416 Marionette TRACE 354 <- [1,6,null,{}]
10:49:10 INFO - 1500227350418 Marionette DEBUG Closed connection 354
10:49:10 INFO - 1500227350419 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50036
10:49:10 INFO - 1500227350420 Marionette DEBUG Closed connection 355
10:49:10 INFO - 1500227350421 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50037
10:49:10 INFO - 1500227350422 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:10 INFO - 1500227350422 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:10 INFO - 1500227350425 Marionette DEBUG Register listener.js for window 15
10:49:10 INFO - 1500227350429 Marionette TRACE 356 <- [1,1,null,{"sessionId":"8c512620-2360-4c7a-bd8d-70dd5a91510b","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:10 INFO - 1500227350431 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:49:10 INFO - 1500227350433 Marionette TRACE 356 <- [1,2,null,{}]
10:49:10 INFO - 1500227350435 Marionette DEBUG Closed connection 356
10:49:10 INFO - 1500227350436 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50038
10:49:10 INFO - 1500227350437 Marionette DEBUG Closed connection 357
10:49:10 INFO - 1500227350438 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50039
10:49:10 INFO - 1500227350439 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:10 INFO - 1500227350440 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:10 INFO - 1500227350443 Marionette DEBUG Register listener.js for window 15
10:49:10 INFO - 1500227350446 Marionette TRACE 358 <- [1,1,null,{"sessionId":"30522a0e-8415-48c2-a366-663898eccc17","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:10 INFO - 1500227350449 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49594/clicks.html"}]
10:49:10 INFO - 1500227350450 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49594/hidden.html"
10:49:10 INFO - 1500227350457 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49594/hidden.html"
10:49:10 INFO - 1500227350457 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49594/hidden.html"
10:49:10 INFO - 1500227350478 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49594/clicks.html"
10:49:10 INFO - 1500227350487 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49594/clicks.html"
10:49:10 INFO - 1500227350492 Marionette TRACE 358 <- [1,2,null,{}]
10:49:10 INFO - 1500227350493 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:49:10 INFO - 1500227350504 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4bdfb3df-afe3-46da-8830-0c33155c4981","ELEMENT":"4bdfb3df-afe3-46da-8830-0c33155c4981"}}]
10:49:10 INFO - 1500227350506 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"4bdfb3df-afe3-46da-8830-0c33155c4981"}]
10:49:10 INFO - 1500227350554 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49594/clicks.html"
10:49:10 INFO - 1500227350563 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49594/clicks.html"
10:49:10 INFO - 1500227350563 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49594/clicks.html"
10:49:10 INFO - 1500227350597 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49594/test.html"
10:49:10 INFO - 1500227350606 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49594/test.html"
10:49:10 INFO - 1500227350611 Marionette TRACE 358 <- [1,4,null,{}]
10:49:10 INFO - 1500227350620 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:49:10 INFO - 1500227350623 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"36b0faeb-728b-4ca7-8f1d-72c63743fb0c","ELEMENT":"36b0faeb-728b-4ca7-8f1d-72c63743fb0c"}}]
10:49:10 INFO - 1500227350624 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:49:10 INFO - 1500227350625 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:49:10 INFO - 1500227350627 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}]
10:49:10 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500227350631 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:49:10 INFO - 1500227350633 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:49:10 INFO - 1500227350635 Marionette TRACE 358 <- [1,8,null,{}]
10:49:10 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 247ms
10:49:10 INFO - TEST-START | test_click.py TestClick.test_container_element
10:49:10 INFO - 1500227350638 Marionette DEBUG Closed connection 358
10:49:10 INFO - 1500227350638 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50042
10:49:10 INFO - 1500227350639 Marionette DEBUG Closed connection 359
10:49:10 INFO - 1500227350640 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50043
10:49:10 INFO - 1500227350641 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:10 INFO - 1500227350642 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:10 INFO - 1500227350645 Marionette DEBUG Register listener.js for window 15
10:49:10 INFO - 1500227350653 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b1526864-6644-498f-b981-9cabd0943636","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:10 INFO - 1500227350655 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:49:10 INFO - 1500227350656 Marionette TRACE 360 <- [1,2,null,{}]
10:49:10 INFO - 1500227350657 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:10 INFO - 1500227350657 Marionette TRACE 360 <- [1,3,null,{}]
10:49:10 INFO - 1500227350658 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:10 INFO - 1500227350659 Marionette TRACE 360 <- [1,4,null,{}]
10:49:10 INFO - 1500227350661 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}]
10:49:10 INFO - TEST-START: test_click.py TestClick.test_container_element1500227350666 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:49:10 INFO - 1500227350667 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:49:10 INFO - 1500227350668 Marionette TRACE 360 <- [1,6,null,{}]
10:49:10 INFO - 1500227350671 Marionette DEBUG Closed connection 360
10:49:10 INFO - 1500227350672 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50044
10:49:10 INFO - 1500227350673 Marionette DEBUG Closed connection 361
10:49:10 INFO - 1500227350674 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50045
10:49:10 INFO - 1500227350675 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:10 INFO - 1500227350675 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:10 INFO - 1500227350678 Marionette DEBUG Register listener.js for window 15
10:49:10 INFO - 1500227350683 Marionette TRACE 362 <- [1,1,null,{"sessionId":"db3ba8b3-3a08-42ed-89e1-6d3a13a0577f","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:10 INFO - 1500227350686 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:49:10 INFO - 1500227350687 Marionette TRACE 362 <- [1,2,null,{}]
10:49:10 INFO - 1500227350690 Marionette DEBUG Closed connection 362
10:49:10 INFO - 1500227350690 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50046
10:49:10 INFO - 1500227350691 Marionette DEBUG Closed connection 363
10:49:10 INFO - 1500227350692 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50047
10:49:10 INFO - 1500227350693 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:10 INFO - 1500227350694 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:10 INFO - 1500227350697 Marionette DEBUG Register listener.js for window 15
10:49:10 INFO - 1500227350703 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0149e697-14c2-4cb8-97ca-fd4ef42eb507","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:10 INFO - 1500227350705 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"}]
10:49:10 INFO - 1500227350707 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49594/test.html"
10:49:10 INFO - 1500227350710 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49594/test.html"
10:49:10 INFO - 1500227350710 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49594/test.html"
10:49:10 INFO - 1500227350737 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"
10:49:10 INFO - 1500227350742 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"
10:49:10 INFO - 1500227350747 Marionette TRACE 364 <- [1,2,null,{}]
10:49:10 INFO - 1500227350756 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:49:10 INFO - 1500227350759 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e428877e-ba26-4a05-a3e6-45e10730f070","ELEMENT":"e428877e-ba26-4a05-a3e6-45e10730f070"}}]
10:49:10 INFO - 1500227350760 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e428877e-ba26-4a05-a3e6-45e10730f070"}]
10:49:11 INFO - 1500227351007 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:11 INFO - 1500227351008 Marionette TRACE 364 <- [1,4,null,{}]
10:49:11 INFO - 1500227351010 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e428877e-ba26-4a05-a3e6-45e10730f070","name":"selected"}]
10:49:11 INFO - 1500227351015 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:49:11 INFO - 1500227351017 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}]
10:49:11 INFO - TEST-END: test_click.py TestClick.test_container_element1500227351020 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:49:11 INFO - 1500227351021 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:49:11 INFO - 1500227351022 Marionette TRACE 364 <- [1,7,null,{}]
10:49:11 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 394ms
10:49:11 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:49:11 INFO - 1500227351033 Marionette DEBUG Closed connection 364
10:49:11 INFO - 1500227351033 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50048
10:49:11 INFO - 1500227351034 Marionette DEBUG Closed connection 365
10:49:11 INFO - 1500227351035 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50049
10:49:11 INFO - 1500227351036 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:11 INFO - 1500227351045 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351048 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351052 Marionette TRACE 366 <- [1,1,null,{"sessionId":"2200a0ba-43cc-45d2-8a7d-72a6f0349106","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351055 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:49:11 INFO - 1500227351055 Marionette TRACE 366 <- [1,2,null,{}]
10:49:11 INFO - 1500227351056 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:11 INFO - 1500227351057 Marionette TRACE 366 <- [1,3,null,{}]
10:49:11 INFO - 1500227351058 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:11 INFO - 1500227351058 Marionette TRACE 366 <- [1,4,null,{}]
10:49:11 INFO - 1500227351060 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}]
10:49:11 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500227351064 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:49:11 INFO - 1500227351065 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:49:11 INFO - 1500227351066 Marionette TRACE 366 <- [1,6,null,{}]
10:49:11 INFO - 1500227351069 Marionette DEBUG Closed connection 366
10:49:11 INFO - 1500227351070 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50050
10:49:11 INFO - 1500227351070 Marionette DEBUG Closed connection 367
10:49:11 INFO - 1500227351071 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50051
10:49:11 INFO - 1500227351072 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:11 INFO - 1500227351073 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351076 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351080 Marionette TRACE 368 <- [1,1,null,{"sessionId":"109ac7a1-598a-4d9a-a7e9-41d830a53dbc","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351082 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:49:11 INFO - 1500227351084 Marionette TRACE 368 <- [1,2,null,{}]
10:49:11 INFO - 1500227351086 Marionette DEBUG Closed connection 368
10:49:11 INFO - 1500227351087 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50052
10:49:11 INFO - 1500227351088 Marionette DEBUG Closed connection 369
10:49:11 INFO - 1500227351088 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50053
10:49:11 INFO - 1500227351090 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:11 INFO - 1500227351090 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351093 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351098 Marionette TRACE 370 <- [1,1,null,{"sessionId":"aac50c59-ff9d-424d-b666-63faf27bbdea","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351101 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"}]
10:49:11 INFO - 1500227351103 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"
10:49:11 INFO - 1500227351107 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"
10:49:11 INFO - 1500227351107 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"
10:49:11 INFO - 1500227351128 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"
10:49:11 INFO - 1500227351135 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"
10:49:11 INFO - 1500227351140 Marionette TRACE 370 <- [1,2,null,{}]
10:49:11 INFO - 1500227351141 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:49:11 INFO - 1500227351144 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"101ecfe8-8a34-4d50-bab5-65d1836855ca","ELEMENT":"101ecfe8-8a34-4d50-bab5-65d1836855ca"}}]
10:49:11 INFO - 1500227351152 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"101ecfe8-8a34-4d50-bab5-65d1836855ca"}]
10:49:11 INFO - 1500227351399 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:11 INFO - 1500227351401 Marionette TRACE 370 <- [1,4,null,{}]
10:49:11 INFO - 1500227351402 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"101ecfe8-8a34-4d50-bab5-65d1836855ca","name":"selected"}]
10:49:11 INFO - 1500227351407 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:49:11 INFO - 1500227351409 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}]
10:49:11 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500227351412 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:49:11 INFO - 1500227351413 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:49:11 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 385ms
10:49:11 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:49:11 INFO - 1500227351414 Marionette TRACE 370 <- [1,7,null,{}]
10:49:11 INFO - 1500227351417 Marionette DEBUG Closed connection 370
10:49:11 INFO - 1500227351417 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50054
10:49:11 INFO - 1500227351418 Marionette DEBUG Closed connection 371
10:49:11 INFO - 1500227351419 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50055
10:49:11 INFO - 1500227351420 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:11 INFO - 1500227351421 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351424 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351427 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8f5f28f9-b924-490c-9812-8d15df3d57bc","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351430 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:49:11 INFO - 1500227351430 Marionette TRACE 372 <- [1,2,null,{}]
10:49:11 INFO - 1500227351431 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:11 INFO - 1500227351432 Marionette TRACE 372 <- [1,3,null,{}]
10:49:11 INFO - 1500227351433 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:11 INFO - 1500227351433 Marionette TRACE 372 <- [1,4,null,{}]
10:49:11 INFO - 1500227351435 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}]
10:49:11 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500227351438 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:49:11 INFO - 1500227351439 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:49:11 INFO - 1500227351441 Marionette TRACE 372 <- [1,6,null,{}]
10:49:11 INFO - 1500227351443 Marionette DEBUG Closed connection 372
10:49:11 INFO - 1500227351444 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50056
10:49:11 INFO - 1500227351445 Marionette DEBUG Closed connection 373
10:49:11 INFO - 1500227351446 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50057
10:49:11 INFO - 1500227351447 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:11 INFO - 1500227351447 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351450 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351454 Marionette TRACE 374 <- [1,1,null,{"sessionId":"b3674e6c-4a45-4d6c-97df-13c70a795784","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351456 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:49:11 INFO - 1500227351458 Marionette TRACE 374 <- [1,2,null,{}]
10:49:11 INFO - 1500227351460 Marionette DEBUG Closed connection 374
10:49:11 INFO - 1500227351461 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50058
10:49:11 INFO - 1500227351462 Marionette DEBUG Closed connection 375
10:49:11 INFO - 1500227351463 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50059
10:49:11 INFO - 1500227351464 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:11 INFO - 1500227351464 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351467 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351471 Marionette TRACE 376 <- [1,1,null,{"sessionId":"65465768-6421-40c1-a563-3a942f7f447d","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351473 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"}]
10:49:11 INFO - 1500227351475 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"
10:49:11 INFO - 1500227351478 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"
10:49:11 INFO - 1500227351478 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"
10:49:11 INFO - 1500227351501 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"
10:49:11 INFO - 1500227351504 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"
10:49:11 INFO - 1500227351509 Marionette TRACE 376 <- [1,2,null,{}]
10:49:11 INFO - 1500227351510 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:49:11 INFO - 1500227351521 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79b231d3-5a44-4296-8927-db8c54ab0e7e","ELEMENT":"79b231d3-5a44-4296-8927-db8c54ab0e7e"}}]
10:49:11 INFO - 1500227351525 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"79b231d3-5a44-4296-8927-db8c54ab0e7e"}]
10:49:11 INFO - 1500227351768 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:11 INFO - 1500227351770 Marionette TRACE 376 <- [1,4,null,{}]
10:49:11 INFO - 1500227351772 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}]
10:49:11 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500227351775 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:49:11 INFO - 1500227351776 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:49:11 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 363ms
10:49:11 INFO - 1500227351778 Marionette TRACE 376 <- [1,6,null,{}]
10:49:11 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:49:11 INFO - 1500227351780 Marionette DEBUG Closed connection 376
10:49:11 INFO - 1500227351781 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50060
10:49:11 INFO - 1500227351782 Marionette DEBUG Closed connection 377
10:49:11 INFO - 1500227351783 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50061
10:49:11 INFO - 1500227351784 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:11 INFO - 1500227351785 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351788 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351799 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c02b174f-6b92-46b8-a8c7-0466eb29d2ba","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351802 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:49:11 INFO - 1500227351803 Marionette TRACE 378 <- [1,2,null,{}]
10:49:11 INFO - 1500227351804 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:11 INFO - 1500227351804 Marionette TRACE 378 <- [1,3,null,{}]
10:49:11 INFO - 1500227351805 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:11 INFO - 1500227351806 Marionette TRACE 378 <- [1,4,null,{}]
10:49:11 INFO - 1500227351808 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}]
10:49:11 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500227351811 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:49:11 INFO - 1500227351812 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:49:11 INFO - 1500227351814 Marionette TRACE 378 <- [1,6,null,{}]
10:49:11 INFO - 1500227351816 Marionette DEBUG Closed connection 378
10:49:11 INFO - 1500227351817 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50062
10:49:11 INFO - 1500227351818 Marionette DEBUG Closed connection 379
10:49:11 INFO - 1500227351818 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50063
10:49:11 INFO - 1500227351819 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:11 INFO - 1500227351820 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351823 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351826 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ddf69d88-3e3b-47b7-b53a-b7738ce80431","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351829 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:49:11 INFO - 1500227351830 Marionette TRACE 380 <- [1,2,null,{}]
10:49:11 INFO - 1500227351833 Marionette DEBUG Closed connection 380
10:49:11 INFO - 1500227351833 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50064
10:49:11 INFO - 1500227351834 Marionette DEBUG Closed connection 381
10:49:11 INFO - 1500227351835 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50065
10:49:11 INFO - 1500227351836 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:11 INFO - 1500227351837 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:11 INFO - 1500227351840 Marionette DEBUG Register listener.js for window 15
10:49:11 INFO - 1500227351843 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ea3b019f-50df-4280-9d0d-b5c88eca14be","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:11 INFO - 1500227351846 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"}]
10:49:11 INFO - 1500227351847 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"
10:49:11 INFO - 1500227351850 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"
10:49:11 INFO - 1500227351850 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"
10:49:11 INFO - 1500227351873 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"
10:49:11 INFO - 1500227351877 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"
10:49:11 INFO - 1500227351881 Marionette TRACE 382 <- [1,2,null,{}]
10:49:11 INFO - 1500227351889 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:49:11 INFO - 1500227351892 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8bd9c479-d2e5-4d98-851a-0c2fc9dd9a81","ELEMENT":"8bd9c479-d2e5-4d98-851a-0c2fc9dd9a81"}}]
10:49:11 INFO - 1500227351893 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"8bd9c479-d2e5-4d98-851a-0c2fc9dd9a81"}]
10:49:12 INFO - 1500227352141 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:12 INFO - 1500227352142 Marionette TRACE 382 <- [1,4,null,{}]
10:49:12 INFO - 1500227352144 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}]
10:49:12 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500227352147 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:49:12 INFO - 1500227352148 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:49:12 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 371ms
10:49:12 INFO - TEST-START | test_click.py TestClick.test_input_file
10:49:12 INFO - 1500227352149 Marionette TRACE 382 <- [1,6,null,{}]
10:49:12 INFO - 1500227352152 Marionette DEBUG Closed connection 382
10:49:12 INFO - 1500227352153 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50066
10:49:12 INFO - 1500227352153 Marionette DEBUG Closed connection 383
10:49:12 INFO - 1500227352154 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50067
10:49:12 INFO - 1500227352155 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:12 INFO - 1500227352156 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352159 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352162 Marionette TRACE 384 <- [1,1,null,{"sessionId":"aa21e762-6954-4220-898c-7d1cd7a389db","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352164 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:49:12 INFO - 1500227352165 Marionette TRACE 384 <- [1,2,null,{}]
10:49:12 INFO - 1500227352166 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:12 INFO - 1500227352166 Marionette TRACE 384 <- [1,3,null,{}]
10:49:12 INFO - 1500227352167 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:12 INFO - 1500227352168 Marionette TRACE 384 <- [1,4,null,{}]
10:49:12 INFO - 1500227352170 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}]
10:49:12 INFO - TEST-START: test_click.py TestClick.test_input_file1500227352173 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:49:12 INFO - 1500227352174 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:49:12 INFO - 1500227352175 Marionette TRACE 384 <- [1,6,null,{}]
10:49:12 INFO - 1500227352178 Marionette DEBUG Closed connection 384
10:49:12 INFO - 1500227352179 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50068
10:49:12 INFO - 1500227352179 Marionette DEBUG Closed connection 385
10:49:12 INFO - 1500227352180 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50069
10:49:12 INFO - 1500227352181 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:12 INFO - 1500227352182 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352185 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352188 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b52968e2-ddbd-4d9b-974f-57006877bce0","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352191 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:49:12 INFO - 1500227352192 Marionette TRACE 386 <- [1,2,null,{}]
10:49:12 INFO - 1500227352195 Marionette DEBUG Closed connection 386
10:49:12 INFO - 1500227352195 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50070
10:49:12 INFO - 1500227352196 Marionette DEBUG Closed connection 387
10:49:12 INFO - 1500227352197 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50071
10:49:12 INFO - 1500227352198 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:12 INFO - 1500227352199 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352202 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352205 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9ad149ec-dabf-4334-8a68-20ed33fb6325","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352207 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:49:12 INFO - 1500227352209 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"
10:49:12 INFO - 1500227352212 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"
10:49:12 INFO - 1500227352212 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"
10:49:12 INFO - 1500227352235 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:12 INFO - 1500227352239 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:12 INFO - 1500227352243 Marionette TRACE 388 <- [1,2,null,{}]
10:49:12 INFO - 1500227352244 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:49:12 INFO - 1500227352254 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf2eb481-00b3-42ad-adc2-112620a85ecf","ELEMENT":"cf2eb481-00b3-42ad-adc2-112620a85ecf"}}]
10:49:12 INFO - 1500227352257 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"cf2eb481-00b3-42ad-adc2-112620a85ecf"}]
10:49:12 INFO - 1500227352287 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:49:12 INFO - 1500227352289 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}]
10:49:12 INFO - TEST-END: test_click.py TestClick.test_input_file1500227352292 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:49:12 INFO - 1500227352293 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:49:12 INFO - 1500227352295 Marionette TRACE 388 <- [1,6,null,{}]
10:49:12 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 146ms
10:49:12 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:49:12 INFO - 1500227352299 Marionette DEBUG Closed connection 388
10:49:12 INFO - 1500227352300 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50072
10:49:12 INFO - 1500227352301 Marionette DEBUG Closed connection 389
10:49:12 INFO - 1500227352302 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50073
10:49:12 INFO - 1500227352308 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:12 INFO - 1500227352309 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352312 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352316 Marionette TRACE 390 <- [1,1,null,{"sessionId":"05acd9ca-ea24-4520-a099-5853590d9a9b","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352318 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:49:12 INFO - 1500227352319 Marionette TRACE 390 <- [1,2,null,{}]
10:49:12 INFO - 1500227352320 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:12 INFO - 1500227352320 Marionette TRACE 390 <- [1,3,null,{}]
10:49:12 INFO - 1500227352321 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:12 INFO - 1500227352322 Marionette TRACE 390 <- [1,4,null,{}]
10:49:12 INFO - 1500227352324 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}]
10:49:12 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500227352327 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:49:12 INFO - 1500227352328 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:49:12 INFO - 1500227352330 Marionette TRACE 390 <- [1,6,null,{}]
10:49:12 INFO - 1500227352333 Marionette DEBUG Closed connection 390
10:49:12 INFO - 1500227352333 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50074
10:49:12 INFO - 1500227352334 Marionette DEBUG Closed connection 391
10:49:12 INFO - 1500227352335 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50075
10:49:12 INFO - 1500227352336 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:12 INFO - 1500227352337 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352347 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352351 Marionette TRACE 392 <- [1,1,null,{"sessionId":"2b8ec971-7672-490b-aeb3-a5ea80ab15c9","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352353 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:49:12 INFO - 1500227352355 Marionette TRACE 392 <- [1,2,null,{}]
10:49:12 INFO - 1500227352407 Marionette DEBUG Closed connection 392
10:49:12 INFO - 1500227352407 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50076
10:49:12 INFO - 1500227352458 Marionette DEBUG Closed connection 393
10:49:12 INFO - 1500227352459 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50077
10:49:12 INFO - 1500227352473 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:12 INFO - 1500227352474 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352477 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352481 Marionette TRACE 394 <- [1,1,null,{"sessionId":"7dc554b7-b4b9-4f8a-8df2-338d202b5621","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352483 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"}]
10:49:12 INFO - 1500227352485 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:12 INFO - 1500227352487 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:12 INFO - 1500227352488 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:12 INFO - 1500227352511 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"
10:49:12 INFO - 1500227352514 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"
10:49:12 INFO - 1500227352525 Marionette TRACE 394 <- [1,2,null,{}]
10:49:12 INFO - 1500227352527 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:49:12 INFO - 1500227352530 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa2e6185-e55e-46d9-8eef-ccad2b65e755","ELEMENT":"fa2e6185-e55e-46d9-8eef-ccad2b65e755"}}]
10:49:12 INFO - 1500227352533 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:49:12 INFO - 1500227352537 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9d6eab5f-94d0-47cb-8abf-ed6dcc8ea7ea","ELEMENT":"9d6eab5f-94d0-47cb-8abf-ed6dcc8ea7ea"}}]
10:49:12 INFO - 1500227352539 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"fa2e6185-e55e-46d9-8eef-ccad2b65e755"}]
10:49:12 INFO - 1500227352780 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:12 INFO - 1500227352782 Marionette TRACE 394 <- [1,5,null,{}]
10:49:12 INFO - 1500227352783 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9d6eab5f-94d0-47cb-8abf-ed6dcc8ea7ea"}]
10:49:12 INFO - 1500227352801 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js: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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:49:12 INFO - 1500227352803 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:49:12 INFO - 1500227352806 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:49:12 INFO - 1500227352808 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}]
10:49:12 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500227352811 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:49:12 INFO - 1500227352812 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:49:12 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 517ms
10:49:12 INFO - 1500227352814 Marionette TRACE 394 <- [1,9,null,{}]
10:49:12 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:49:12 INFO - 1500227352816 Marionette DEBUG Closed connection 394
10:49:12 INFO - 1500227352817 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50078
10:49:12 INFO - 1500227352818 Marionette DEBUG Closed connection 395
10:49:12 INFO - 1500227352819 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50079
10:49:12 INFO - 1500227352820 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:12 INFO - 1500227352821 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352824 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352828 Marionette TRACE 396 <- [1,1,null,{"sessionId":"190eb62c-f067-4671-bce7-d590bf014a79","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352830 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:49:12 INFO - 1500227352831 Marionette TRACE 396 <- [1,2,null,{}]
10:49:12 INFO - 1500227352832 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:12 INFO - 1500227352832 Marionette TRACE 396 <- [1,3,null,{}]
10:49:12 INFO - 1500227352833 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:12 INFO - 1500227352834 Marionette TRACE 396 <- [1,4,null,{}]
10:49:12 INFO - 1500227352836 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}]
10:49:12 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500227352839 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:49:12 INFO - 1500227352840 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:49:12 INFO - 1500227352841 Marionette TRACE 396 <- [1,6,null,{}]
10:49:12 INFO - 1500227352844 Marionette DEBUG Closed connection 396
10:49:12 INFO - 1500227352845 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50080
10:49:12 INFO - 1500227352845 Marionette DEBUG Closed connection 397
10:49:12 INFO - 1500227352846 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50081
10:49:12 INFO - 1500227352847 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:12 INFO - 1500227352848 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352851 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352855 Marionette TRACE 398 <- [1,1,null,{"sessionId":"9d175c71-45d2-4877-96dd-6a5cf483c010","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352858 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:49:12 INFO - 1500227352859 Marionette TRACE 398 <- [1,2,null,{}]
10:49:12 INFO - 1500227352861 Marionette DEBUG Closed connection 398
10:49:12 INFO - 1500227352862 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50082
10:49:12 INFO - 1500227352863 Marionette DEBUG Closed connection 399
10:49:12 INFO - 1500227352864 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50083
10:49:12 INFO - 1500227352865 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:12 INFO - 1500227352866 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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}
10:49:12 INFO - 1500227352869 Marionette DEBUG Register listener.js for window 15
10:49:12 INFO - 1500227352873 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d36dac9a-34ac-484f-bc68-ab3b315195c5","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":924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgffnjg.mozrunner","moz:accessibilityChecks":false}}]
10:49:12 INFO - 1500227352875 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"}]
10:49:12 INFO - 1500227352877 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"
10:49:12 INFO - 1500227352880 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"
10:49:12 INFO - 1500227352880 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"
10:49:12 INFO - 1500227352895 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"
10:49:12 INFO - 1500227352900 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"
10:49:12 INFO - 1500227352912 Marionette TRACE 400 <- [1,2,null,{}]
10:49:12 INFO - 1500227352914 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:49:12 INFO - 1500227352917 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bd4a81b8-e51d-4ad9-ad8c-370ca57a239b","ELEMENT":"bd4a81b8-e51d-4ad9-ad8c-370ca57a239b"}}]
10:49:12 INFO - 1500227352919 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"bd4a81b8-e51d-4ad9-ad8c-370ca57a239b"}]
10:49:12 INFO - 1500227352943 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:49:12 INFO - 1500227352944 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"bd4a81b8-e51d-4ad9-ad8c-370ca57a239b"}]
10:49:12 INFO - 1500227352967 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element