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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:21:44 INFO - 1501064504559 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}]
03:21:44 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501064504562 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:21:44 INFO - 1501064504563 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:21:44 INFO - 1501064504564 Marionette TRACE 352 <- [1,6,null,{}]
03:21:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 140ms
03:21:44 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:21:44 INFO - 1501064504566 Marionette DEBUG Closed connection 352
03:21:44 INFO - 1501064504567 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49817
03:21:44 INFO - 1501064504568 Marionette DEBUG Closed connection 353
03:21:44 INFO - 1501064504568 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49818
03:21:44 INFO - 1501064504570 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:44 INFO - 1501064504570 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:44 INFO - 1501064504573 Marionette DEBUG Register listener.js for window 4294967297
03:21:44 INFO - 1501064504577 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1d793f6a-86f6-458a-86bd-08b4bf70e0e1","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:44 INFO - 1501064504578 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:21:44 INFO - 1501064504578 Marionette TRACE 354 <- [1,2,null,{}]
03:21:44 INFO - 1501064504579 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:21:44 INFO - 1501064504580 Marionette TRACE 354 <- [1,3,null,{}]
03:21:44 INFO - 1501064504581 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:21:44 INFO - 1501064504581 Marionette TRACE 354 <- [1,4,null,{}]
03:21:44 INFO - 1501064504583 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}]
03:21:44 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501064504586 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:21:44 INFO - 1501064504587 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:21:44 INFO - 1501064504589 Marionette TRACE 354 <- [1,6,null,{}]
03:21:44 INFO - 1501064504590 Marionette DEBUG Closed connection 354
03:21:44 INFO - 1501064504591 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49819
03:21:44 INFO - 1501064504592 Marionette DEBUG Closed connection 355
03:21:44 INFO - 1501064504593 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49820
03:21:44 INFO - 1501064504594 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:44 INFO - 1501064504595 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:44 INFO - 1501064504598 Marionette DEBUG Register listener.js for window 4294967297
03:21:44 INFO - 1501064504601 Marionette TRACE 356 <- [1,1,null,{"sessionId":"39fac52e-3869-4ac4-b255-93e673f0c294","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:44 INFO - 1501064504603 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:21:44 INFO - 1501064504605 Marionette TRACE 356 <- [1,2,null,{}]
03:21:44 INFO - 1501064504607 Marionette DEBUG Closed connection 356
03:21:44 INFO - 1501064504607 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49821
03:21:44 INFO - 1501064504608 Marionette DEBUG Closed connection 357
03:21:44 INFO - 1501064504609 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49822
03:21:44 INFO - 1501064504610 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:21:44 INFO - 1501064504611 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:44 INFO - 1501064504614 Marionette DEBUG Register listener.js for window 4294967297
03:21:44 INFO - 1501064504618 Marionette TRACE 358 <- [1,1,null,{"sessionId":"99f6dbf2-f231-454c-a0c8-71e324af7b86","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:44 INFO - 1501064504619 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49376/clicks.html"}]
03:21:44 INFO - 1501064504621 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49376/hidden.html"
03:21:44 INFO - 1501064504632 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49376/hidden.html"
03:21:44 INFO - 1501064504632 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49376/hidden.html"
03:21:44 INFO - 1501064504642 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49376/clicks.html"
03:21:44 INFO - 1501064504648 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49376/clicks.html"
03:21:44 INFO - 1501064504660 Marionette TRACE 358 <- [1,2,null,{}]
03:21:44 INFO - 1501064504675 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:21:44 INFO - 1501064504678 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4efbd450-4897-4191-9c14-740f2e570816","ELEMENT":"4efbd450-4897-4191-9c14-740f2e570816"}}]
03:21:44 INFO - 1501064504680 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"4efbd450-4897-4191-9c14-740f2e570816"}]
03:21:44 INFO - 1501064504723 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49376/clicks.html"
03:21:44 INFO - 1501064504737 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49376/clicks.html"
03:21:44 INFO - 1501064504738 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49376/clicks.html"
03:21:44 INFO - 1501064504747 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49376/test.html"
03:21:44 INFO - 1501064504754 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49376/test.html"
03:21:44 INFO - 1501064504759 Marionette TRACE 358 <- [1,4,null,{}]
03:21:44 INFO - 1501064504769 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:21:44 INFO - 1501064504773 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"59404cac-8185-4753-ac93-38b35b66980f","ELEMENT":"59404cac-8185-4753-ac93-38b35b66980f"}}]
03:21:44 INFO - 1501064504774 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:21:44 INFO - 1501064504774 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:21:44 INFO - 1501064504776 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}]
03:21:44 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501064504780 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:21:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 218ms
03:21:44 INFO - 1501064504781 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:21:44 INFO - 1501064504783 Marionette TRACE 358 <- [1,8,null,{}]
03:21:44 INFO - TEST-START | test_click.py TestClick.test_container_element
03:21:44 INFO - 1501064504785 Marionette DEBUG Closed connection 358
03:21:44 INFO - 1501064504786 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49825
03:21:44 INFO - 1501064504787 Marionette DEBUG Closed connection 359
03:21:44 INFO - 1501064504788 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49826
03:21:44 INFO - 1501064504789 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:44 INFO - 1501064504789 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:44 INFO - 1501064504792 Marionette DEBUG Register listener.js for window 4294967297
03:21:44 INFO - 1501064504796 Marionette TRACE 360 <- [1,1,null,{"sessionId":"9c75f03c-873b-4ffb-b630-9e30aa4b3b5d","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:44 INFO - 1501064504797 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:21:44 INFO - 1501064504798 Marionette TRACE 360 <- [1,2,null,{}]
03:21:44 INFO - 1501064504799 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:21:44 INFO - 1501064504799 Marionette TRACE 360 <- [1,3,null,{}]
03:21:44 INFO - 1501064504800 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:21:44 INFO - 1501064504801 Marionette TRACE 360 <- [1,4,null,{}]
03:21:44 INFO - 1501064504803 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}]
03:21:44 INFO - TEST-START: test_click.py TestClick.test_container_element1501064504806 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:21:44 INFO - 1501064504807 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:21:44 INFO - 1501064504809 Marionette TRACE 360 <- [1,6,null,{}]
03:21:44 INFO - 1501064504810 Marionette DEBUG Closed connection 360
03:21:44 INFO - 1501064504811 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49827
03:21:44 INFO - 1501064504812 Marionette DEBUG Closed connection 361
03:21:44 INFO - 1501064504813 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49828
03:21:44 INFO - 1501064504814 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:44 INFO - 1501064504814 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:44 INFO - 1501064504817 Marionette DEBUG Register listener.js for window 4294967297
03:21:44 INFO - 1501064504820 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f8245a6b-779f-45e0-9d5e-534355e85d71","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:44 INFO - 1501064504821 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:21:44 INFO - 1501064504823 Marionette TRACE 362 <- [1,2,null,{}]
03:21:44 INFO - 1501064504825 Marionette DEBUG Closed connection 362
03:21:44 INFO - 1501064504825 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49829
03:21:44 INFO - 1501064504826 Marionette DEBUG Closed connection 363
03:21:44 INFO - 1501064504827 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49830
03:21:44 INFO - 1501064504828 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:21:44 INFO - 1501064504829 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:44 INFO - 1501064504832 Marionette DEBUG Register listener.js for window 4294967297
03:21:44 INFO - 1501064504835 Marionette TRACE 364 <- [1,1,null,{"sessionId":"94978f15-11ec-472f-93fc-a9c940d03380","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:44 INFO - 1501064504837 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"}]
03:21:44 INFO - 1501064504839 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49376/test.html"
03:21:44 INFO - 1501064504842 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49376/test.html"
03:21:44 INFO - 1501064504842 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49376/test.html"
03:21:44 INFO - 1501064504850 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"
03:21:44 INFO - 1501064504854 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"
03:21:44 INFO - 1501064504870 Marionette TRACE 364 <- [1,2,null,{}]
03:21:44 INFO - 1501064504880 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:21:44 INFO - 1501064504884 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8f337d37-ddd4-40d0-a263-6c9fe5df2748","ELEMENT":"8f337d37-ddd4-40d0-a263-6c9fe5df2748"}}]
03:21:44 INFO - 1501064504886 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8f337d37-ddd4-40d0-a263-6c9fe5df2748"}]
03:21:45 INFO - 1501064505135 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:21:45 INFO - 1501064505136 Marionette TRACE 364 <- [1,4,null,{}]
03:21:45 INFO - 1501064505137 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8f337d37-ddd4-40d0-a263-6c9fe5df2748","name":"selected"}]
03:21:45 INFO - 1501064505142 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:21:45 INFO - 1501064505144 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}]
03:21:45 INFO - TEST-END: test_click.py TestClick.test_container_element1501064505147 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:21:45 INFO - 1501064505148 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:21:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 366ms
03:21:45 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:21:45 INFO - 1501064505150 Marionette TRACE 364 <- [1,7,null,{}]
03:21:45 INFO - 1501064505152 Marionette DEBUG Closed connection 364
03:21:45 INFO - 1501064505152 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49831
03:21:45 INFO - 1501064505153 Marionette DEBUG Closed connection 365
03:21:45 INFO - 1501064505154 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49832
03:21:45 INFO - 1501064505155 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:45 INFO - 1501064505156 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505158 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505162 Marionette TRACE 366 <- [1,1,null,{"sessionId":"976f4a3f-b319-4285-af53-35a93ec19c16","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505163 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:21:45 INFO - 1501064505163 Marionette TRACE 366 <- [1,2,null,{}]
03:21:45 INFO - 1501064505164 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:21:45 INFO - 1501064505165 Marionette TRACE 366 <- [1,3,null,{}]
03:21:45 INFO - 1501064505166 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:21:45 INFO - 1501064505166 Marionette TRACE 366 <- [1,4,null,{}]
03:21:45 INFO - 1501064505168 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}]
03:21:45 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501064505171 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:21:45 INFO - 1501064505172 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:21:45 INFO - 1501064505173 Marionette TRACE 366 <- [1,6,null,{}]
03:21:45 INFO - 1501064505175 Marionette DEBUG Closed connection 366
03:21:45 INFO - 1501064505176 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49833
03:21:45 INFO - 1501064505177 Marionette DEBUG Closed connection 367
03:21:45 INFO - 1501064505177 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49834
03:21:45 INFO - 1501064505179 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:45 INFO - 1501064505179 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505181 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505185 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ff5edb10-3a5b-4678-97ad-fd440a1c361d","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505186 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:21:45 INFO - 1501064505187 Marionette TRACE 368 <- [1,2,null,{}]
03:21:45 INFO - 1501064505189 Marionette DEBUG Closed connection 368
03:21:45 INFO - 1501064505190 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49835
03:21:45 INFO - 1501064505191 Marionette DEBUG Closed connection 369
03:21:45 INFO - 1501064505191 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49836
03:21:45 INFO - 1501064505192 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:21:45 INFO - 1501064505193 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505195 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505199 Marionette TRACE 370 <- [1,1,null,{"sessionId":"2fb32062-a62b-459c-ad7f-a6de5d252ac1","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505200 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"}]
03:21:45 INFO - 1501064505202 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"
03:21:45 INFO - 1501064505204 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"
03:21:45 INFO - 1501064505205 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"
03:21:45 INFO - 1501064505211 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"
03:21:45 INFO - 1501064505215 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"
03:21:45 INFO - 1501064505225 Marionette TRACE 370 <- [1,2,null,{}]
03:21:45 INFO - 1501064505229 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:21:45 INFO - 1501064505236 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f6deda0-3bcf-4351-8ac8-07aa37a8b2ac","ELEMENT":"7f6deda0-3bcf-4351-8ac8-07aa37a8b2ac"}}]
03:21:45 INFO - 1501064505238 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"7f6deda0-3bcf-4351-8ac8-07aa37a8b2ac"}]
03:21:45 INFO - 1501064505491 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:21:45 INFO - 1501064505492 Marionette TRACE 370 <- [1,4,null,{}]
03:21:45 INFO - 1501064505493 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"7f6deda0-3bcf-4351-8ac8-07aa37a8b2ac","name":"selected"}]
03:21:45 INFO - 1501064505498 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:21:45 INFO - 1501064505500 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}]
03:21:45 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501064505503 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:21:45 INFO - 1501064505504 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:21:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 355ms
03:21:45 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:21:45 INFO - 1501064505506 Marionette TRACE 370 <- [1,7,null,{}]
03:21:45 INFO - 1501064505508 Marionette DEBUG Closed connection 370
03:21:45 INFO - 1501064505508 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49837
03:21:45 INFO - 1501064505509 Marionette DEBUG Closed connection 371
03:21:45 INFO - 1501064505510 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49838
03:21:45 INFO - 1501064505511 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:45 INFO - 1501064505512 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505514 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505526 Marionette TRACE 372 <- [1,1,null,{"sessionId":"a2ef3c26-12b4-4fa6-9817-f2389058b9fe","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505527 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:21:45 INFO - 1501064505528 Marionette TRACE 372 <- [1,2,null,{}]
03:21:45 INFO - 1501064505529 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:21:45 INFO - 1501064505529 Marionette TRACE 372 <- [1,3,null,{}]
03:21:45 INFO - 1501064505530 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:21:45 INFO - 1501064505531 Marionette TRACE 372 <- [1,4,null,{}]
03:21:45 INFO - 1501064505533 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}]
03:21:45 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501064505536 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:21:45 INFO - 1501064505537 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:21:45 INFO - 1501064505539 Marionette TRACE 372 <- [1,6,null,{}]
03:21:45 INFO - 1501064505540 Marionette DEBUG Closed connection 372
03:21:45 INFO - 1501064505541 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49839
03:21:45 INFO - 1501064505542 Marionette DEBUG Closed connection 373
03:21:45 INFO - 1501064505543 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49840
03:21:45 INFO - 1501064505544 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:45 INFO - 1501064505545 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505547 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505550 Marionette TRACE 374 <- [1,1,null,{"sessionId":"46e74fad-15bb-43ab-8622-e42be02d5029","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505551 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:21:45 INFO - 1501064505553 Marionette TRACE 374 <- [1,2,null,{}]
03:21:45 INFO - 1501064505555 Marionette DEBUG Closed connection 374
03:21:45 INFO - 1501064505555 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49841
03:21:45 INFO - 1501064505556 Marionette DEBUG Closed connection 375
03:21:45 INFO - 1501064505557 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49842
03:21:45 INFO - 1501064505558 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:21:45 INFO - 1501064505559 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505561 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505564 Marionette TRACE 376 <- [1,1,null,{"sessionId":"3abe716b-c39d-4ed3-ac1b-8d9474c076b6","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505565 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"}]
03:21:45 INFO - 1501064505570 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"
03:21:45 INFO - 1501064505572 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"
03:21:45 INFO - 1501064505572 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"
03:21:45 INFO - 1501064505579 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"
03:21:45 INFO - 1501064505582 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"
03:21:45 INFO - 1501064505594 Marionette TRACE 376 <- [1,2,null,{}]
03:21:45 INFO - 1501064505604 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:21:45 INFO - 1501064505614 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f7f8de9-4a1a-4b79-ac87-efe3a4da0eef","ELEMENT":"6f7f8de9-4a1a-4b79-ac87-efe3a4da0eef"}}]
03:21:45 INFO - 1501064505615 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6f7f8de9-4a1a-4b79-ac87-efe3a4da0eef"}]
03:21:45 INFO - 1501064505856 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:21:45 INFO - 1501064505857 Marionette TRACE 376 <- [1,4,null,{}]
03:21:45 INFO - 1501064505859 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}]
03:21:45 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501064505862 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:21:45 INFO - 1501064505863 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:21:45 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 358ms
03:21:45 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:21:45 INFO - 1501064505865 Marionette TRACE 376 <- [1,6,null,{}]
03:21:45 INFO - 1501064505867 Marionette DEBUG Closed connection 376
03:21:45 INFO - 1501064505867 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49843
03:21:45 INFO - 1501064505868 Marionette DEBUG Closed connection 377
03:21:45 INFO - 1501064505869 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49844
03:21:45 INFO - 1501064505870 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:45 INFO - 1501064505871 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505873 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505877 Marionette TRACE 378 <- [1,1,null,{"sessionId":"00a28cd6-235e-415b-8af3-e0460fc033ee","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505878 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:21:45 INFO - 1501064505878 Marionette TRACE 378 <- [1,2,null,{}]
03:21:45 INFO - 1501064505879 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:21:45 INFO - 1501064505880 Marionette TRACE 378 <- [1,3,null,{}]
03:21:45 INFO - 1501064505881 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:21:45 INFO - 1501064505882 Marionette TRACE 378 <- [1,4,null,{}]
03:21:45 INFO - 1501064505883 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}]
03:21:45 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501064505886 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:21:45 INFO - 1501064505887 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:21:45 INFO - 1501064505889 Marionette TRACE 378 <- [1,6,null,{}]
03:21:45 INFO - 1501064505891 Marionette DEBUG Closed connection 378
03:21:45 INFO - 1501064505891 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49845
03:21:45 INFO - 1501064505892 Marionette DEBUG Closed connection 379
03:21:45 INFO - 1501064505893 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49846
03:21:45 INFO - 1501064505894 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:45 INFO - 1501064505895 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505897 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505900 Marionette TRACE 380 <- [1,1,null,{"sessionId":"f5b66162-08c6-4a4d-aaf6-3b75db91c4a8","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505901 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:21:45 INFO - 1501064505903 Marionette TRACE 380 <- [1,2,null,{}]
03:21:45 INFO - 1501064505905 Marionette DEBUG Closed connection 380
03:21:45 INFO - 1501064505905 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49847
03:21:45 INFO - 1501064505906 Marionette DEBUG Closed connection 381
03:21:45 INFO - 1501064505907 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49848
03:21:45 INFO - 1501064505908 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:21:45 INFO - 1501064505909 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:45 INFO - 1501064505911 Marionette DEBUG Register listener.js for window 4294967297
03:21:45 INFO - 1501064505914 Marionette TRACE 382 <- [1,1,null,{"sessionId":"14a23532-57ac-4490-b8c7-0635e82de5d2","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:45 INFO - 1501064505915 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"}]
03:21:45 INFO - 1501064505917 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"
03:21:45 INFO - 1501064505920 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"
03:21:45 INFO - 1501064505920 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"
03:21:45 INFO - 1501064505927 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"
03:21:45 INFO - 1501064505930 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"
03:21:45 INFO - 1501064505939 Marionette TRACE 382 <- [1,2,null,{}]
03:21:45 INFO - 1501064505944 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:21:45 INFO - 1501064505953 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cc620b45-3d40-474e-8204-16057dca36c1","ELEMENT":"cc620b45-3d40-474e-8204-16057dca36c1"}}]
03:21:45 INFO - 1501064505954 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"cc620b45-3d40-474e-8204-16057dca36c1"}]
03:21:46 INFO - 1501064506204 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:21:46 INFO - 1501064506205 Marionette TRACE 382 <- [1,4,null,{}]
03:21:46 INFO - 1501064506207 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}]
03:21:46 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501064506210 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:21:46 INFO - 1501064506211 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:21:46 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 348ms
03:21:46 INFO - TEST-START | test_click.py TestClick.test_input_file
03:21:46 INFO - 1501064506213 Marionette TRACE 382 <- [1,6,null,{}]
03:21:46 INFO - 1501064506215 Marionette DEBUG Closed connection 382
03:21:46 INFO - 1501064506216 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49849
03:21:46 INFO - 1501064506217 Marionette DEBUG Closed connection 383
03:21:46 INFO - 1501064506217 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49850
03:21:46 INFO - 1501064506218 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:46 INFO - 1501064506219 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506221 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506225 Marionette TRACE 384 <- [1,1,null,{"sessionId":"194e47ed-3e0f-40a9-97d1-379d607af936","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506226 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:21:46 INFO - 1501064506227 Marionette TRACE 384 <- [1,2,null,{}]
03:21:46 INFO - 1501064506228 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:21:46 INFO - 1501064506228 Marionette TRACE 384 <- [1,3,null,{}]
03:21:46 INFO - 1501064506229 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:21:46 INFO - 1501064506230 Marionette TRACE 384 <- [1,4,null,{}]
03:21:46 INFO - 1501064506232 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}]
03:21:46 INFO - TEST-START: test_click.py TestClick.test_input_file1501064506235 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:21:46 INFO - 1501064506236 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:21:46 INFO - 1501064506237 Marionette TRACE 384 <- [1,6,null,{}]
03:21:46 INFO - 1501064506239 Marionette DEBUG Closed connection 384
03:21:46 INFO - 1501064506240 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49851
03:21:46 INFO - 1501064506241 Marionette DEBUG Closed connection 385
03:21:46 INFO - 1501064506242 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49852
03:21:46 INFO - 1501064506243 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:46 INFO - 1501064506243 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506245 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506249 Marionette TRACE 386 <- [1,1,null,{"sessionId":"568b32cf-eefd-4864-8396-db28d3bdab47","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506250 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:21:46 INFO - 1501064506251 Marionette TRACE 386 <- [1,2,null,{}]
03:21:46 INFO - 1501064506253 Marionette DEBUG Closed connection 386
03:21:46 INFO - 1501064506254 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49853
03:21:46 INFO - 1501064506255 Marionette DEBUG Closed connection 387
03:21:46 INFO - 1501064506255 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49854
03:21:46 INFO - 1501064506256 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:21:46 INFO - 1501064506257 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506259 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506263 Marionette TRACE 388 <- [1,1,null,{"sessionId":"56699ef3-a1fe-4c83-8eb8-3ee375ab84ab","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506264 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:21:46 INFO - 1501064506266 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"
03:21:46 INFO - 1501064506268 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"
03:21:46 INFO - 1501064506269 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"
03:21:46 INFO - 1501064506276 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:21:46 INFO - 1501064506280 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:21:46 INFO - 1501064506288 Marionette TRACE 388 <- [1,2,null,{}]
03:21:46 INFO - 1501064506292 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:21:46 INFO - 1501064506297 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7feb8459-847a-4bb5-b2e5-3bb46f58efa3","ELEMENT":"7feb8459-847a-4bb5-b2e5-3bb46f58efa3"}}]
03:21:46 INFO - 1501064506303 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"7feb8459-847a-4bb5-b2e5-3bb46f58efa3"}]
03:21:46 INFO - 1501064506331 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:21:46 INFO - 1501064506333 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}]
03:21:46 INFO - TEST-END: test_click.py TestClick.test_input_file1501064506336 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:21:46 INFO - 1501064506337 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:21:46 INFO - 1501064506338 Marionette TRACE 388 <- [1,6,null,{}]
03:21:46 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 125ms
03:21:46 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:21:46 INFO - 1501064506340 Marionette DEBUG Closed connection 388
03:21:46 INFO - 1501064506341 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49855
03:21:46 INFO - 1501064506342 Marionette DEBUG Closed connection 389
03:21:46 INFO - 1501064506343 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49856
03:21:46 INFO - 1501064506344 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:46 INFO - 1501064506344 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506347 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506350 Marionette TRACE 390 <- [1,1,null,{"sessionId":"610e28e3-18a2-4ac3-9fe5-387e7e135f30","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506351 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:21:46 INFO - 1501064506352 Marionette TRACE 390 <- [1,2,null,{}]
03:21:46 INFO - 1501064506353 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:21:46 INFO - 1501064506353 Marionette TRACE 390 <- [1,3,null,{}]
03:21:46 INFO - 1501064506354 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:21:46 INFO - 1501064506355 Marionette TRACE 390 <- [1,4,null,{}]
03:21:46 INFO - 1501064506357 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}]
03:21:46 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501064506360 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:21:46 INFO - 1501064506361 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:21:46 INFO - 1501064506362 Marionette TRACE 390 <- [1,6,null,{}]
03:21:46 INFO - 1501064506364 Marionette DEBUG Closed connection 390
03:21:46 INFO - 1501064506365 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49857
03:21:46 INFO - 1501064506366 Marionette DEBUG Closed connection 391
03:21:46 INFO - 1501064506366 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49858
03:21:46 INFO - 1501064506367 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:46 INFO - 1501064506368 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506370 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506374 Marionette TRACE 392 <- [1,1,null,{"sessionId":"386d048d-8142-4619-985a-2514b5cc6b2e","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506375 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:21:46 INFO - 1501064506377 Marionette TRACE 392 <- [1,2,null,{}]
03:21:46 INFO - 1501064506379 Marionette DEBUG Closed connection 392
03:21:46 INFO - 1501064506379 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49859
03:21:46 INFO - 1501064506380 Marionette DEBUG Closed connection 393
03:21:46 INFO - 1501064506381 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49860
03:21:46 INFO - 1501064506382 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:21:46 INFO - 1501064506383 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506385 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506388 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3d31d498-f7ce-4f4b-b31c-3853fbb64e73","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506389 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"}]
03:21:46 INFO - 1501064506391 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:21:46 INFO - 1501064506394 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:21:46 INFO - 1501064506394 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:21:46 INFO - 1501064506401 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"
03:21:46 INFO - 1501064506404 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"
03:21:46 INFO - 1501064506411 Marionette TRACE 394 <- [1,2,null,{}]
03:21:46 INFO - 1501064506423 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:21:46 INFO - 1501064506426 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"99465555-85af-4430-b786-f4ed984779e8","ELEMENT":"99465555-85af-4430-b786-f4ed984779e8"}}]
03:21:46 INFO - 1501064506427 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:21:46 INFO - 1501064506431 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d955bd24-6d9c-4e1e-b1ac-c32beb87c8fb","ELEMENT":"d955bd24-6d9c-4e1e-b1ac-c32beb87c8fb"}}]
03:21:46 INFO - 1501064506433 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"99465555-85af-4430-b786-f4ed984779e8"}]
03:21:46 INFO - 1501064506673 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:21:46 INFO - 1501064506674 Marionette TRACE 394 <- [1,5,null,{}]
03:21:46 INFO - 1501064506675 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d955bd24-6d9c-4e1e-b1ac-c32beb87c8fb"}]
03:21:46 INFO - 1501064506693 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:21:46 INFO - 1501064506695 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:21:46 INFO - 1501064506698 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:21:46 INFO - 1501064506700 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}]
03:21:46 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501064506703 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:21:46 INFO - 1501064506704 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:21:46 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 367ms
03:21:46 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:21:46 INFO - 1501064506706 Marionette TRACE 394 <- [1,9,null,{}]
03:21:46 INFO - 1501064506707 Marionette DEBUG Closed connection 394
03:21:46 INFO - 1501064506708 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49861
03:21:46 INFO - 1501064506709 Marionette DEBUG Closed connection 395
03:21:46 INFO - 1501064506710 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49862
03:21:46 INFO - 1501064506711 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:46 INFO - 1501064506712 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506714 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506718 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1381d664-18f2-4548-af99-ee37d3a77d54","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506719 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:21:46 INFO - 1501064506719 Marionette TRACE 396 <- [1,2,null,{}]
03:21:46 INFO - 1501064506720 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:21:46 INFO - 1501064506721 Marionette TRACE 396 <- [1,3,null,{}]
03:21:46 INFO - 1501064506722 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:21:46 INFO - 1501064506722 Marionette TRACE 396 <- [1,4,null,{}]
03:21:46 INFO - 1501064506724 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}]
03:21:46 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501064506727 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:21:46 INFO - 1501064506728 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:21:46 INFO - 1501064506729 Marionette TRACE 396 <- [1,6,null,{}]
03:21:46 INFO - 1501064506731 Marionette DEBUG Closed connection 396
03:21:46 INFO - 1501064506732 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49863
03:21:46 INFO - 1501064506733 Marionette DEBUG Closed connection 397
03:21:46 INFO - 1501064506733 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49864
03:21:46 INFO - 1501064506734 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:21:46 INFO - 1501064506735 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506737 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506741 Marionette TRACE 398 <- [1,1,null,{"sessionId":"f35b2078-971b-491c-bd69-918b560d8837","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506742 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:21:46 INFO - 1501064506744 Marionette TRACE 398 <- [1,2,null,{}]
03:21:46 INFO - 1501064506745 Marionette DEBUG Closed connection 398
03:21:46 INFO - 1501064506746 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49865
03:21:46 INFO - 1501064506747 Marionette DEBUG Closed connection 399
03:21:46 INFO - 1501064506749 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49866
03:21:46 INFO - 1501064506750 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:21:46 INFO - 1501064506751 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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}
03:21:46 INFO - 1501064506753 Marionette DEBUG Register listener.js for window 4294967297
03:21:46 INFO - 1501064506756 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e861ab69-9ffc-4f1c-9c6c-a211fa4cb09d","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":5604,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptwhhe3.mozrunner","moz:accessibilityChecks":false}}]
03:21:46 INFO - 1501064506758 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"}]
03:21:46 INFO - 1501064506760 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"
03:21:46 INFO - 1501064506762 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"
03:21:46 INFO - 1501064506762 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"
03:21:46 INFO - 1501064506770 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"
03:21:46 INFO - 1501064506772 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"
03:21:46 INFO - 1501064506779 Marionette TRACE 400 <- [1,2,null,{}]
03:21:46 INFO - 1501064506791 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:21:46 INFO - 1501064506794 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f526c1f1-2096-40da-b935-10df45a41a04","ELEMENT":"f526c1f1-2096-40da-b935-10df45a41a04"}}]
03:21:46 INFO - 1501064506796 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f526c1f1-2096-40da-b935-10df45a41a04"}]
03:21:46 INFO - 1501064506824 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:21:46 INFO - 1501064506826 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f526c1f1-2096-40da-b935-10df45a41a04"}]
03:21:46 INFO - 1501064506846 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element