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]
18:16:41 INFO - 1500427001454 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}]
18:16:41 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500427001457 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:16:41 INFO - 1500427001458 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:16:41 INFO - 1500427001460 Marionette TRACE 352 <- [1,6,null,{}]
18:16:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 189ms
18:16:41 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:16:41 INFO - 1500427001463 Marionette DEBUG Closed connection 352
18:16:41 INFO - 1500427001464 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49813
18:16:41 INFO - 1500427001465 Marionette DEBUG Closed connection 353
18:16:41 INFO - 1500427001465 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49814
18:16:41 INFO - 1500427001466 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:41 INFO - 1500427001474 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:41 INFO - 1500427001477 Marionette DEBUG Register listener.js for window 15
18:16:41 INFO - 1500427001481 Marionette TRACE 354 <- [1,1,null,{"sessionId":"683ab734-bdef-4ca1-86ef-d370102c52cc","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:41 INFO - 1500427001493 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:16:41 INFO - 1500427001494 Marionette TRACE 354 <- [1,2,null,{}]
18:16:41 INFO - 1500427001495 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:16:41 INFO - 1500427001495 Marionette TRACE 354 <- [1,3,null,{}]
18:16:41 INFO - 1500427001496 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:16:41 INFO - 1500427001497 Marionette TRACE 354 <- [1,4,null,{}]
18:16:41 INFO - 1500427001499 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}]
18:16:41 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500427001502 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:16:41 INFO - 1500427001503 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:16:41 INFO - 1500427001504 Marionette TRACE 354 <- [1,6,null,{}]
18:16:41 INFO - 1500427001507 Marionette DEBUG Closed connection 354
18:16:41 INFO - 1500427001507 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49815
18:16:41 INFO - 1500427001508 Marionette DEBUG Closed connection 355
18:16:41 INFO - 1500427001509 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49816
18:16:41 INFO - 1500427001510 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:41 INFO - 1500427001511 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:41 INFO - 1500427001514 Marionette DEBUG Register listener.js for window 15
18:16:41 INFO - 1500427001517 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d1222485-32aa-4d60-8a8a-b9249c8c0c34","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:41 INFO - 1500427001519 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:16:41 INFO - 1500427001521 Marionette TRACE 356 <- [1,2,null,{}]
18:16:41 INFO - 1500427001523 Marionette DEBUG Closed connection 356
18:16:41 INFO - 1500427001524 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49817
18:16:41 INFO - 1500427001525 Marionette DEBUG Closed connection 357
18:16:41 INFO - 1500427001525 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49818
18:16:41 INFO - 1500427001526 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:16:41 INFO - 1500427001527 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:41 INFO - 1500427001530 Marionette DEBUG Register listener.js for window 15
18:16:41 INFO - 1500427001533 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c19a5349-be64-4d5e-946a-b432f43c69ef","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:41 INFO - 1500427001535 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49378/clicks.html"}]
18:16:41 INFO - 1500427001537 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49378/hidden.html"
18:16:41 INFO - 1500427001543 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49378/hidden.html"
18:16:41 INFO - 1500427001544 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49378/hidden.html"
18:16:41 INFO - 1500427001590 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49378/clicks.html"
18:16:41 INFO - 1500427001592 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49378/clicks.html"
18:16:41 INFO - 1500427001602 Marionette TRACE 358 <- [1,2,null,{}]
18:16:41 INFO - 1500427001619 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:16:41 INFO - 1500427001622 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b15ee99f-5d66-4ae0-8066-1262cd09ae27","ELEMENT":"b15ee99f-5d66-4ae0-8066-1262cd09ae27"}}]
18:16:41 INFO - 1500427001631 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"b15ee99f-5d66-4ae0-8066-1262cd09ae27"}]
18:16:41 INFO - 1500427001672 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49378/clicks.html"
18:16:41 INFO - 1500427001680 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49378/clicks.html"
18:16:41 INFO - 1500427001681 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49378/clicks.html"
18:16:41 INFO - 1500427001717 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49378/test.html"
18:16:41 INFO - 1500427001722 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49378/test.html"
18:16:41 INFO - 1500427001735 Marionette TRACE 358 <- [1,4,null,{}]
18:16:41 INFO - 1500427001738 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:16:41 INFO - 1500427001740 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a26f15aa-e21e-4863-91b2-4b90555d073b","ELEMENT":"a26f15aa-e21e-4863-91b2-4b90555d073b"}}]
18:16:41 INFO - 1500427001742 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:16:41 INFO - 1500427001743 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:16:41 INFO - 1500427001745 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}]
18:16:41 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500427001748 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:16:41 INFO - 1500427001749 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:16:41 INFO - 1500427001750 Marionette TRACE 358 <- [1,8,null,{}]
18:16:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 290ms
18:16:41 INFO - TEST-START | test_click.py TestClick.test_container_element
18:16:41 INFO - 1500427001753 Marionette DEBUG Closed connection 358
18:16:41 INFO - 1500427001754 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49823
18:16:41 INFO - 1500427001754 Marionette DEBUG Closed connection 359
18:16:41 INFO - 1500427001755 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49824
18:16:41 INFO - 1500427001756 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:41 INFO - 1500427001757 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:41 INFO - 1500427001760 Marionette DEBUG Register listener.js for window 15
18:16:41 INFO - 1500427001764 Marionette TRACE 360 <- [1,1,null,{"sessionId":"60acb3b2-20e6-4ac6-bc75-1981d3246769","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:41 INFO - 1500427001766 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:16:41 INFO - 1500427001766 Marionette TRACE 360 <- [1,2,null,{}]
18:16:41 INFO - 1500427001767 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:16:41 INFO - 1500427001768 Marionette TRACE 360 <- [1,3,null,{}]
18:16:41 INFO - 1500427001769 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:16:41 INFO - 1500427001770 Marionette TRACE 360 <- [1,4,null,{}]
18:16:41 INFO - 1500427001772 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}]
18:16:41 INFO - TEST-START: test_click.py TestClick.test_container_element1500427001775 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:16:41 INFO - 1500427001776 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:16:41 INFO - 1500427001777 Marionette TRACE 360 <- [1,6,null,{}]
18:16:41 INFO - 1500427001780 Marionette DEBUG Closed connection 360
18:16:41 INFO - 1500427001780 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49825
18:16:41 INFO - 1500427001781 Marionette DEBUG Closed connection 361
18:16:41 INFO - 1500427001782 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49826
18:16:41 INFO - 1500427001783 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:41 INFO - 1500427001784 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:41 INFO - 1500427001786 Marionette DEBUG Register listener.js for window 15
18:16:41 INFO - 1500427001790 Marionette TRACE 362 <- [1,1,null,{"sessionId":"867b1059-037d-46cd-9f1a-227a2d4b2f48","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:41 INFO - 1500427001792 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:16:41 INFO - 1500427001793 Marionette TRACE 362 <- [1,2,null,{}]
18:16:41 INFO - 1500427001796 Marionette DEBUG Closed connection 362
18:16:41 INFO - 1500427001797 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49827
18:16:41 INFO - 1500427001797 Marionette DEBUG Closed connection 363
18:16:41 INFO - 1500427001798 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49828
18:16:41 INFO - 1500427001799 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:16:41 INFO - 1500427001800 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:41 INFO - 1500427001803 Marionette DEBUG Register listener.js for window 15
18:16:41 INFO - 1500427001806 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1acd83fc-1025-4738-86df-7c13cedec8fa","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:41 INFO - 1500427001808 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"}]
18:16:41 INFO - 1500427001810 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49378/test.html"
18:16:41 INFO - 1500427001812 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49378/test.html"
18:16:41 INFO - 1500427001812 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49378/test.html"
18:16:41 INFO - 1500427001839 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"
18:16:41 INFO - 1500427001844 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"
18:16:41 INFO - 1500427001848 Marionette TRACE 364 <- [1,2,null,{}]
18:16:41 INFO - 1500427001849 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:16:41 INFO - 1500427001859 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c65e3d0a-077b-48ae-a209-88e5a655e40e","ELEMENT":"c65e3d0a-077b-48ae-a209-88e5a655e40e"}}]
18:16:41 INFO - 1500427001861 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c65e3d0a-077b-48ae-a209-88e5a655e40e"}]
18:16:42 INFO - 1500427002108 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:16:42 INFO - 1500427002108 Marionette TRACE 364 <- [1,4,null,{}]
18:16:42 INFO - 1500427002110 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"c65e3d0a-077b-48ae-a209-88e5a655e40e","name":"selected"}]
18:16:42 INFO - 1500427002114 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:16:42 INFO - 1500427002116 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}]
18:16:42 INFO - TEST-END: test_click.py TestClick.test_container_element1500427002119 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:16:42 INFO - 1500427002120 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:16:42 INFO - 1500427002122 Marionette TRACE 364 <- [1,7,null,{}]
18:16:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 371ms
18:16:42 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:16:42 INFO - 1500427002124 Marionette DEBUG Closed connection 364
18:16:42 INFO - 1500427002125 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49829
18:16:42 INFO - 1500427002126 Marionette DEBUG Closed connection 365
18:16:42 INFO - 1500427002127 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49830
18:16:42 INFO - 1500427002128 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:42 INFO - 1500427002128 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:42 INFO - 1500427002131 Marionette DEBUG Register listener.js for window 15
18:16:42 INFO - 1500427002142 Marionette TRACE 366 <- [1,1,null,{"sessionId":"bbb2d63e-8574-4a19-8199-586f9bd60fb8","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:42 INFO - 1500427002144 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:16:42 INFO - 1500427002144 Marionette TRACE 366 <- [1,2,null,{}]
18:16:42 INFO - 1500427002145 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:16:42 INFO - 1500427002146 Marionette TRACE 366 <- [1,3,null,{}]
18:16:42 INFO - 1500427002147 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:16:42 INFO - 1500427002147 Marionette TRACE 366 <- [1,4,null,{}]
18:16:42 INFO - 1500427002149 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}]
18:16:42 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500427002152 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:16:42 INFO - 1500427002153 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:16:42 INFO - 1500427002155 Marionette TRACE 366 <- [1,6,null,{}]
18:16:42 INFO - 1500427002157 Marionette DEBUG Closed connection 366
18:16:42 INFO - 1500427002158 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49831
18:16:42 INFO - 1500427002159 Marionette DEBUG Closed connection 367
18:16:42 INFO - 1500427002159 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49832
18:16:42 INFO - 1500427002160 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:42 INFO - 1500427002161 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:42 INFO - 1500427002164 Marionette DEBUG Register listener.js for window 15
18:16:42 INFO - 1500427002167 Marionette TRACE 368 <- [1,1,null,{"sessionId":"1c23da36-f3b2-4378-802a-42dab8cd2b0c","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:42 INFO - 1500427002169 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:16:42 INFO - 1500427002171 Marionette TRACE 368 <- [1,2,null,{}]
18:16:42 INFO - 1500427002173 Marionette DEBUG Closed connection 368
18:16:42 INFO - 1500427002174 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49833
18:16:42 INFO - 1500427002174 Marionette DEBUG Closed connection 369
18:16:42 INFO - 1500427002175 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49834
18:16:42 INFO - 1500427002176 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:16:42 INFO - 1500427002177 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:42 INFO - 1500427002180 Marionette DEBUG Register listener.js for window 15
18:16:42 INFO - 1500427002183 Marionette TRACE 370 <- [1,1,null,{"sessionId":"498b767a-0268-413d-bcf0-4381f184a09f","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:42 INFO - 1500427002185 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"}]
18:16:42 INFO - 1500427002188 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"
18:16:42 INFO - 1500427002190 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"
18:16:42 INFO - 1500427002190 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"
18:16:42 INFO - 1500427002212 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"
18:16:42 INFO - 1500427002214 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"
18:16:42 INFO - 1500427002218 Marionette TRACE 370 <- [1,2,null,{}]
18:16:42 INFO - 1500427002225 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:16:42 INFO - 1500427002228 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c80726b2-8397-40c8-ba39-6464530cc56a","ELEMENT":"c80726b2-8397-40c8-ba39-6464530cc56a"}}]
18:16:42 INFO - 1500427002229 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c80726b2-8397-40c8-ba39-6464530cc56a"}]
18:16:42 INFO - 1500427002475 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:16:42 INFO - 1500427002476 Marionette TRACE 370 <- [1,4,null,{}]
18:16:42 INFO - 1500427002477 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c80726b2-8397-40c8-ba39-6464530cc56a","name":"selected"}]
18:16:42 INFO - 1500427002482 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:16:42 INFO - 1500427002484 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}]
18:16:42 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500427002487 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:16:42 INFO - 1500427002488 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:16:42 INFO - 1500427002489 Marionette TRACE 370 <- [1,7,null,{}]
18:16:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 367ms
18:16:42 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:16:42 INFO - 1500427002492 Marionette DEBUG Closed connection 370
18:16:42 INFO - 1500427002493 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49835
18:16:42 INFO - 1500427002493 Marionette DEBUG Closed connection 371
18:16:42 INFO - 1500427002494 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49836
18:16:42 INFO - 1500427002495 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:42 INFO - 1500427002496 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:42 INFO - 1500427002499 Marionette DEBUG Register listener.js for window 15
18:16:42 INFO - 1500427002503 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7553c262-fc2d-455a-9605-60f9c1f75407","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:42 INFO - 1500427002505 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:16:42 INFO - 1500427002505 Marionette TRACE 372 <- [1,2,null,{}]
18:16:42 INFO - 1500427002506 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:16:42 INFO - 1500427002507 Marionette TRACE 372 <- [1,3,null,{}]
18:16:42 INFO - 1500427002508 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:16:42 INFO - 1500427002508 Marionette TRACE 372 <- [1,4,null,{}]
18:16:42 INFO - 1500427002510 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}]
18:16:42 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500427002513 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:16:42 INFO - 1500427002514 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:16:42 INFO - 1500427002515 Marionette TRACE 372 <- [1,6,null,{}]
18:16:42 INFO - 1500427002518 Marionette DEBUG Closed connection 372
18:16:42 INFO - 1500427002519 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49837
18:16:42 INFO - 1500427002519 Marionette DEBUG Closed connection 373
18:16:42 INFO - 1500427002520 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49838
18:16:42 INFO - 1500427002521 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:42 INFO - 1500427002522 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:42 INFO - 1500427002525 Marionette DEBUG Register listener.js for window 15
18:16:42 INFO - 1500427002528 Marionette TRACE 374 <- [1,1,null,{"sessionId":"125b260f-bbbc-47d6-a04b-8f43ce791ef4","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:42 INFO - 1500427002530 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:16:42 INFO - 1500427002532 Marionette TRACE 374 <- [1,2,null,{}]
18:16:42 INFO - 1500427002534 Marionette DEBUG Closed connection 374
18:16:42 INFO - 1500427002535 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49839
18:16:42 INFO - 1500427002536 Marionette DEBUG Closed connection 375
18:16:42 INFO - 1500427002537 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49840
18:16:42 INFO - 1500427002538 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:16:42 INFO - 1500427002538 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:42 INFO - 1500427002541 Marionette DEBUG Register listener.js for window 15
18:16:42 INFO - 1500427002544 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a2076eaf-c79a-424f-9220-cb2515e1bd3c","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:42 INFO - 1500427002547 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"}]
18:16:42 INFO - 1500427002548 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"
18:16:42 INFO - 1500427002551 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"
18:16:42 INFO - 1500427002551 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"
18:16:42 INFO - 1500427002572 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"
18:16:42 INFO - 1500427002576 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"
18:16:42 INFO - 1500427002580 Marionette TRACE 376 <- [1,2,null,{}]
18:16:42 INFO - 1500427002581 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:16:42 INFO - 1500427002591 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9824851d-5bb5-49ee-94a5-f762aba05821","ELEMENT":"9824851d-5bb5-49ee-94a5-f762aba05821"}}]
18:16:42 INFO - 1500427002593 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"9824851d-5bb5-49ee-94a5-f762aba05821"}]
18:16:42 INFO - 1500427002837 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:16:42 INFO - 1500427002837 Marionette TRACE 376 <- [1,4,null,{}]
18:16:42 INFO - 1500427002839 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}]
18:16:42 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500427002843 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:16:42 INFO - 1500427002844 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:16:42 INFO - 1500427002845 Marionette TRACE 376 <- [1,6,null,{}]
18:16:42 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 356ms
18:16:42 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:16:42 INFO - 1500427002848 Marionette DEBUG Closed connection 376
18:16:42 INFO - 1500427002848 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49841
18:16:42 INFO - 1500427002849 Marionette DEBUG Closed connection 377
18:16:42 INFO - 1500427002850 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49842
18:16:42 INFO - 1500427002851 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:42 INFO - 1500427002852 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:42 INFO - 1500427002855 Marionette DEBUG Register listener.js for window 15
18:16:42 INFO - 1500427002865 Marionette TRACE 378 <- [1,1,null,{"sessionId":"853d7e8c-d4b8-42ec-9e14-0ed2a9300ae5","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:42 INFO - 1500427002867 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:16:42 INFO - 1500427002868 Marionette TRACE 378 <- [1,2,null,{}]
18:16:42 INFO - 1500427002869 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:16:42 INFO - 1500427002869 Marionette TRACE 378 <- [1,3,null,{}]
18:16:42 INFO - 1500427002870 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:16:42 INFO - 1500427002871 Marionette TRACE 378 <- [1,4,null,{}]
18:16:42 INFO - 1500427002873 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}]
18:16:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500427002876 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:16:42 INFO - 1500427002877 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:16:42 INFO - 1500427002878 Marionette TRACE 378 <- [1,6,null,{}]
18:16:42 INFO - 1500427002881 Marionette DEBUG Closed connection 378
18:16:42 INFO - 1500427002882 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49843
18:16:42 INFO - 1500427002882 Marionette DEBUG Closed connection 379
18:16:42 INFO - 1500427002883 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49844
18:16:42 INFO - 1500427002969 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:42 INFO - 1500427002974 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:42 INFO - 1500427002980 Marionette DEBUG Register listener.js for window 15
18:16:42 INFO - 1500427002988 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3e545e86-a3ac-4f24-9ffb-e487381053b7","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:42 INFO - 1500427002993 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:16:42 INFO - 1500427002995 Marionette TRACE 380 <- [1,2,null,{}]
18:16:43 INFO - 1500427003000 Marionette DEBUG Closed connection 380
18:16:43 INFO - 1500427003001 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49845
18:16:43 INFO - 1500427003016 Marionette DEBUG Closed connection 381
18:16:43 INFO - 1500427003017 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49846
18:16:43 INFO - 1500427003021 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:16:43 INFO - 1500427003023 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:43 INFO - 1500427003026 Marionette DEBUG Register listener.js for window 15
18:16:43 INFO - 1500427003032 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2b7c96d5-bcb2-4e28-a43a-45a0091df057","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:43 INFO - 1500427003036 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"}]
18:16:43 INFO - 1500427003039 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"
18:16:43 INFO - 1500427003043 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"
18:16:43 INFO - 1500427003043 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"
18:16:43 INFO - 1500427003073 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"
18:16:43 INFO - 1500427003076 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"
18:16:43 INFO - 1500427003082 Marionette TRACE 382 <- [1,2,null,{}]
18:16:43 INFO - 1500427003092 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:16:43 INFO - 1500427003098 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7803e773-76e3-42d2-838f-bad5f5bc4ba3","ELEMENT":"7803e773-76e3-42d2-838f-bad5f5bc4ba3"}}]
18:16:43 INFO - 1500427003100 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"7803e773-76e3-42d2-838f-bad5f5bc4ba3"}]
18:16:43 INFO - 1500427003352 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:16:43 INFO - 1500427003353 Marionette TRACE 382 <- [1,4,null,{}]
18:16:43 INFO - 1500427003355 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}]
18:16:43 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500427003360 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:16:43 INFO - 1500427003368 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:16:43 INFO - 1500427003371 Marionette TRACE 382 <- [1,6,null,{}]
18:16:43 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 526ms
18:16:43 INFO - TEST-START | test_click.py TestClick.test_input_file
18:16:43 INFO - 1500427003392 Marionette DEBUG Closed connection 382
18:16:43 INFO - 1500427003393 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49847
18:16:43 INFO - 1500427003395 Marionette DEBUG Closed connection 383
18:16:43 INFO - 1500427003396 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49848
18:16:43 INFO - 1500427003398 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:43 INFO - 1500427003399 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:43 INFO - 1500427003402 Marionette DEBUG Register listener.js for window 15
18:16:43 INFO - 1500427003407 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4bee7551-5fb3-4bc5-94c5-d85bbd9407ae","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:43 INFO - 1500427003412 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:16:43 INFO - 1500427003413 Marionette TRACE 384 <- [1,2,null,{}]
18:16:43 INFO - 1500427003416 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:16:43 INFO - 1500427003417 Marionette TRACE 384 <- [1,3,null,{}]
18:16:43 INFO - 1500427003450 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:16:43 INFO - 1500427003450 Marionette TRACE 384 <- [1,4,null,{}]
18:16:43 INFO - 1500427003452 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}]
18:16:43 INFO - TEST-START: test_click.py TestClick.test_input_file1500427003457 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:16:43 INFO - 1500427003458 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:16:43 INFO - 1500427003460 Marionette TRACE 384 <- [1,6,null,{}]
18:16:43 INFO - 1500427003467 Marionette DEBUG Closed connection 384
18:16:43 INFO - 1500427003468 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49849
18:16:43 INFO - 1500427003469 Marionette DEBUG Closed connection 385
18:16:43 INFO - 1500427003470 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49850
18:16:43 INFO - 1500427003471 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:43 INFO - 1500427003472 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:43 INFO - 1500427003475 Marionette DEBUG Register listener.js for window 15
18:16:43 INFO - 1500427003479 Marionette TRACE 386 <- [1,1,null,{"sessionId":"bd67b101-8a42-41c7-a29c-8ddb890065e2","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:43 INFO - 1500427003482 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:16:43 INFO - 1500427003484 Marionette TRACE 386 <- [1,2,null,{}]
18:16:43 INFO - 1500427003486 Marionette DEBUG Closed connection 386
18:16:43 INFO - 1500427003488 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49851
18:16:43 INFO - 1500427003490 Marionette DEBUG Closed connection 387
18:16:43 INFO - 1500427003490 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49852
18:16:43 INFO - 1500427003492 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:16:43 INFO - 1500427003493 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:43 INFO - 1500427003495 Marionette DEBUG Register listener.js for window 15
18:16:43 INFO - 1500427003503 Marionette TRACE 388 <- [1,1,null,{"sessionId":"23e0d417-43ac-4597-896b-114b2b4cfbd4","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:43 INFO - 1500427003516 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:16:43 INFO - 1500427003518 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"
18:16:43 INFO - 1500427003521 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"
18:16:43 INFO - 1500427003522 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"
18:16:43 INFO - 1500427003548 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:16:43 INFO - 1500427003552 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:16:43 INFO - 1500427003566 Marionette TRACE 388 <- [1,2,null,{}]
18:16:43 INFO - 1500427003568 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:16:43 INFO - 1500427003572 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ff0cb86f-68d4-4094-b920-1f791f386e42","ELEMENT":"ff0cb86f-68d4-4094-b920-1f791f386e42"}}]
18:16:43 INFO - 1500427003585 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"ff0cb86f-68d4-4094-b920-1f791f386e42"}]
18:16:43 INFO - 1500427003615 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]
18:16:43 INFO - 1500427003617 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}]
18:16:43 INFO - TEST-END: test_click.py TestClick.test_input_file1500427003621 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:16:43 INFO - 1500427003622 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:16:43 INFO - 1500427003624 Marionette TRACE 388 <- [1,6,null,{}]
18:16:43 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 252ms
18:16:43 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:16:43 INFO - 1500427003626 Marionette DEBUG Closed connection 388
18:16:43 INFO - 1500427003627 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49853
18:16:43 INFO - 1500427003628 Marionette DEBUG Closed connection 389
18:16:43 INFO - 1500427003629 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49854
18:16:43 INFO - 1500427003630 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:43 INFO - 1500427003630 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:43 INFO - 1500427003633 Marionette DEBUG Register listener.js for window 15
18:16:43 INFO - 1500427003638 Marionette TRACE 390 <- [1,1,null,{"sessionId":"b934a92f-3c24-42f0-bf8c-cbff355bff74","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:43 INFO - 1500427003640 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:16:43 INFO - 1500427003641 Marionette TRACE 390 <- [1,2,null,{}]
18:16:43 INFO - 1500427003642 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:16:43 INFO - 1500427003643 Marionette TRACE 390 <- [1,3,null,{}]
18:16:43 INFO - 1500427003644 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:16:43 INFO - 1500427003644 Marionette TRACE 390 <- [1,4,null,{}]
18:16:43 INFO - 1500427003646 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}]
18:16:43 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500427003657 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:16:43 INFO - 1500427003708 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:16:43 INFO - 1500427003710 Marionette TRACE 390 <- [1,6,null,{}]
18:16:43 INFO - 1500427003737 Marionette DEBUG Closed connection 390
18:16:43 INFO - 1500427003739 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49855
18:16:43 INFO - 1500427003752 Marionette DEBUG Closed connection 391
18:16:43 INFO - 1500427003753 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49856
18:16:43 INFO - 1500427003755 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:43 INFO - 1500427003756 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:43 INFO - 1500427003759 Marionette DEBUG Register listener.js for window 15
18:16:43 INFO - 1500427003763 Marionette TRACE 392 <- [1,1,null,{"sessionId":"e986d35f-c3bd-4cfd-a482-1e85e83df283","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:43 INFO - 1500427003765 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:16:43 INFO - 1500427003767 Marionette TRACE 392 <- [1,2,null,{}]
18:16:43 INFO - 1500427003769 Marionette DEBUG Closed connection 392
18:16:43 INFO - 1500427003770 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49857
18:16:43 INFO - 1500427003771 Marionette DEBUG Closed connection 393
18:16:43 INFO - 1500427003772 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49858
18:16:43 INFO - 1500427003774 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:16:43 INFO - 1500427003774 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:43 INFO - 1500427003777 Marionette DEBUG Register listener.js for window 15
18:16:43 INFO - 1500427003781 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f8c37d8b-7c49-43a8-8172-3fb68696f5ad","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:43 INFO - 1500427003783 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"}]
18:16:43 INFO - 1500427003786 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:16:43 INFO - 1500427003788 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:16:43 INFO - 1500427003789 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:16:43 INFO - 1500427003816 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"
18:16:43 INFO - 1500427003819 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"
18:16:43 INFO - 1500427003830 Marionette TRACE 394 <- [1,2,null,{}]
18:16:43 INFO - 1500427003832 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:16:43 INFO - 1500427003836 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e7604db8-6615-46bc-aca1-e8c68f9957f4","ELEMENT":"e7604db8-6615-46bc-aca1-e8c68f9957f4"}}]
18:16:43 INFO - 1500427003838 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:16:43 INFO - 1500427003841 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b4edb52c-087f-4846-8f17-a4a021ebda9f","ELEMENT":"b4edb52c-087f-4846-8f17-a4a021ebda9f"}}]
18:16:43 INFO - 1500427003843 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e7604db8-6615-46bc-aca1-e8c68f9957f4"}]
18:16:44 INFO - 1500427004093 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:16:44 INFO - 1500427004094 Marionette TRACE 394 <- [1,5,null,{}]
18:16:44 INFO - 1500427004095 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b4edb52c-087f-4846-8f17-a4a021ebda9f"}]
18:16:44 INFO - 1500427004115 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]
18:16:44 INFO - 1500427004117 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
18:16:44 INFO - 1500427004120 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:16:44 INFO - 1500427004122 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}]
18:16:44 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500427004126 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:16:44 INFO - 1500427004127 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:16:44 INFO - 1500427004129 Marionette TRACE 394 <- [1,9,null,{}]
18:16:44 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 505ms
18:16:44 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:16:44 INFO - 1500427004131 Marionette DEBUG Closed connection 394
18:16:44 INFO - 1500427004132 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49859
18:16:44 INFO - 1500427004133 Marionette DEBUG Closed connection 395
18:16:44 INFO - 1500427004134 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49860
18:16:44 INFO - 1500427004135 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:44 INFO - 1500427004136 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:44 INFO - 1500427004139 Marionette DEBUG Register listener.js for window 15
18:16:44 INFO - 1500427004143 Marionette TRACE 396 <- [1,1,null,{"sessionId":"21dc389c-fa93-446e-939e-2aadb977e9e8","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:44 INFO - 1500427004145 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:16:44 INFO - 1500427004145 Marionette TRACE 396 <- [1,2,null,{}]
18:16:44 INFO - 1500427004146 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:16:44 INFO - 1500427004147 Marionette TRACE 396 <- [1,3,null,{}]
18:16:44 INFO - 1500427004148 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:16:44 INFO - 1500427004149 Marionette TRACE 396 <- [1,4,null,{}]
18:16:44 INFO - 1500427004151 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}]
18:16:44 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500427004155 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:16:44 INFO - 1500427004156 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:16:44 INFO - 1500427004158 Marionette TRACE 396 <- [1,6,null,{}]
18:16:44 INFO - 1500427004160 Marionette DEBUG Closed connection 396
18:16:44 INFO - 1500427004161 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49861
18:16:44 INFO - 1500427004162 Marionette DEBUG Closed connection 397
18:16:44 INFO - 1500427004163 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49862
18:16:44 INFO - 1500427004164 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:16:44 INFO - 1500427004164 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:44 INFO - 1500427004167 Marionette DEBUG Register listener.js for window 15
18:16:44 INFO - 1500427004171 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1b198b6f-e7b2-42de-96f6-f59f2c53a90f","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:44 INFO - 1500427004173 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:16:44 INFO - 1500427004174 Marionette TRACE 398 <- [1,2,null,{}]
18:16:44 INFO - 1500427004177 Marionette DEBUG Closed connection 398
18:16:44 INFO - 1500427004178 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49863
18:16:44 INFO - 1500427004179 Marionette DEBUG Closed connection 399
18:16:44 INFO - 1500427004180 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49864
18:16:44 INFO - 1500427004181 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:16:44 INFO - 1500427004182 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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}
18:16:44 INFO - 1500427004186 Marionette DEBUG Register listener.js for window 15
18:16:44 INFO - 1500427004191 Marionette TRACE 400 <- [1,1,null,{"sessionId":"07497e08-4e52-4f44-9695-6e13be75a589","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":1064,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuok6bm.mozrunner","moz:accessibilityChecks":false}}]
18:16:44 INFO - 1500427004193 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"}]
18:16:44 INFO - 1500427004195 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"
18:16:44 INFO - 1500427004198 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"
18:16:44 INFO - 1500427004198 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"
18:16:44 INFO - 1500427004222 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"
18:16:44 INFO - 1500427004225 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"
18:16:44 INFO - 1500427004229 Marionette TRACE 400 <- [1,2,null,{}]
18:16:44 INFO - 1500427004230 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:16:44 INFO - 1500427004234 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"06a94356-2d05-49f5-a76d-93fd87321396","ELEMENT":"06a94356-2d05-49f5-a76d-93fd87321396"}}]
18:16:44 INFO - 1500427004243 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"06a94356-2d05-49f5-a76d-93fd87321396"}]
18:16:44 INFO - 1500427004267 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:16:44 INFO - 1500427004268 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"06a94356-2d05-49f5-a76d-93fd87321396"}]
18:16:44 INFO - 1500427004292 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element