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]
06:11:28 INFO - 1501074688635 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}]
06:11:28 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501074688637 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:11:28 INFO - 1501074688638 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:11:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 123ms
06:11:28 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:11:28 INFO - 1501074688639 Marionette TRACE 352 <- [1,6,null,{}]
06:11:28 INFO - 1501074688641 Marionette DEBUG Closed connection 352
06:11:28 INFO - 1501074688642 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49785
06:11:28 INFO - 1501074688642 Marionette DEBUG Closed connection 353
06:11:28 INFO - 1501074688643 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49786
06:11:28 INFO - 1501074688644 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:28 INFO - 1501074688645 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:28 INFO - 1501074688646 Marionette DEBUG Register listener.js for window 12
06:11:28 INFO - 1501074688649 Marionette TRACE 354 <- [1,1,null,{"sessionId":"4cb0dfd0-a103-4ac8-ad89-b6f6943a6125","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:28 INFO - 1501074688651 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:11:28 INFO - 1501074688651 Marionette TRACE 354 <- [1,2,null,{}]
06:11:28 INFO - 1501074688652 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:11:28 INFO - 1501074688652 Marionette TRACE 354 <- [1,3,null,{}]
06:11:28 INFO - 1501074688653 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:11:28 INFO - 1501074688654 Marionette TRACE 354 <- [1,4,null,{}]
06:11:28 INFO - 1501074688655 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}]
06:11:28 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501074688667 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:11:28 INFO - 1501074688668 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:11:28 INFO - 1501074688670 Marionette TRACE 354 <- [1,6,null,{}]
06:11:28 INFO - 1501074688672 Marionette DEBUG Closed connection 354
06:11:28 INFO - 1501074688672 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49787
06:11:28 INFO - 1501074688673 Marionette DEBUG Closed connection 355
06:11:28 INFO - 1501074688674 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49788
06:11:28 INFO - 1501074688675 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:28 INFO - 1501074688675 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:28 INFO - 1501074688677 Marionette DEBUG Register listener.js for window 12
06:11:28 INFO - 1501074688679 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4363f971-0310-4ea2-a977-1a4fb42de05f","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:28 INFO - 1501074688681 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:11:28 INFO - 1501074688683 Marionette TRACE 356 <- [1,2,null,{}]
06:11:28 INFO - 1501074688684 Marionette DEBUG Closed connection 356
06:11:28 INFO - 1501074688685 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49789
06:11:28 INFO - 1501074688686 Marionette DEBUG Closed connection 357
06:11:28 INFO - 1501074688686 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49790
06:11:28 INFO - 1501074688687 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:11:28 INFO - 1501074688688 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:28 INFO - 1501074688689 Marionette DEBUG Register listener.js for window 12
06:11:28 INFO - 1501074688692 Marionette TRACE 358 <- [1,1,null,{"sessionId":"27cd1fbf-c733-4dc3-aa25-75a4505a7508","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:28 INFO - 1501074688693 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49354/clicks.html"}]
06:11:28 INFO - 1501074688695 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49354/hidden.html"
06:11:28 INFO - 1501074688700 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49354/hidden.html"
06:11:28 INFO - 1501074688700 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49354/hidden.html"
06:11:28 INFO - 1501074688715 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49354/clicks.html"
06:11:28 INFO - 1501074688719 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49354/clicks.html"
06:11:28 INFO - 1501074688729 Marionette TRACE 358 <- [1,2,null,{}]
06:11:28 INFO - 1501074688730 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:11:28 INFO - 1501074688732 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"efef319e-79d7-4a1f-84b5-5a7f2c4252b4","ELEMENT":"efef319e-79d7-4a1f-84b5-5a7f2c4252b4"}}]
06:11:28 INFO - 1501074688733 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"efef319e-79d7-4a1f-84b5-5a7f2c4252b4"}]
06:11:28 INFO - 1501074688771 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49354/clicks.html"
06:11:28 INFO - 1501074688778 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49354/clicks.html"
06:11:28 INFO - 1501074688779 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49354/clicks.html"
06:11:28 INFO - 1501074688795 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49354/test.html"
06:11:28 INFO - 1501074688797 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49354/test.html"
06:11:28 INFO - 1501074688800 Marionette TRACE 358 <- [1,4,null,{}]
06:11:28 INFO - 1501074688808 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:11:28 INFO - 1501074688811 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b0922956-7eef-48d5-9c62-3acebce43aa3","ELEMENT":"b0922956-7eef-48d5-9c62-3acebce43aa3"}}]
06:11:28 INFO - 1501074688812 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:11:28 INFO - 1501074688813 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:11:28 INFO - 1501074688814 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}]
06:11:28 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501074688817 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:11:28 INFO - 1501074688818 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:11:28 INFO - 1501074688820 Marionette TRACE 358 <- [1,8,null,{}]
06:11:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 181ms
06:11:28 INFO - TEST-START | test_click.py TestClick.test_container_element
06:11:28 INFO - 1501074688822 Marionette DEBUG Closed connection 358
06:11:28 INFO - 1501074688823 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49793
06:11:28 INFO - 1501074688824 Marionette DEBUG Closed connection 359
06:11:28 INFO - 1501074688825 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49794
06:11:28 INFO - 1501074688826 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:28 INFO - 1501074688826 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:28 INFO - 1501074688828 Marionette DEBUG Register listener.js for window 12
06:11:28 INFO - 1501074688830 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3799a340-8943-4d6b-aee1-da21e4e39408","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:28 INFO - 1501074688832 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:11:28 INFO - 1501074688833 Marionette TRACE 360 <- [1,2,null,{}]
06:11:28 INFO - 1501074688833 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:11:28 INFO - 1501074688834 Marionette TRACE 360 <- [1,3,null,{}]
06:11:28 INFO - 1501074688835 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:11:28 INFO - 1501074688835 Marionette TRACE 360 <- [1,4,null,{}]
06:11:28 INFO - 1501074688837 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}]
06:11:28 INFO - TEST-START: test_click.py TestClick.test_container_element1501074688839 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:11:28 INFO - 1501074688840 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:11:28 INFO - 1501074688841 Marionette TRACE 360 <- [1,6,null,{}]
06:11:28 INFO - 1501074688842 Marionette DEBUG Closed connection 360
06:11:28 INFO - 1501074688843 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49795
06:11:28 INFO - 1501074688844 Marionette DEBUG Closed connection 361
06:11:28 INFO - 1501074688845 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49796
06:11:28 INFO - 1501074688845 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:28 INFO - 1501074688846 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:28 INFO - 1501074688847 Marionette DEBUG Register listener.js for window 12
06:11:28 INFO - 1501074688850 Marionette TRACE 362 <- [1,1,null,{"sessionId":"43aa51dc-e3b3-4e78-b9c7-c7688a0a5d03","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:28 INFO - 1501074688852 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:11:28 INFO - 1501074688853 Marionette TRACE 362 <- [1,2,null,{}]
06:11:28 INFO - 1501074688855 Marionette DEBUG Closed connection 362
06:11:28 INFO - 1501074688855 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49797
06:11:28 INFO - 1501074688856 Marionette DEBUG Closed connection 363
06:11:28 INFO - 1501074688857 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49798
06:11:28 INFO - 1501074688858 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:11:28 INFO - 1501074688858 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:28 INFO - 1501074688860 Marionette DEBUG Register listener.js for window 12
06:11:28 INFO - 1501074688862 Marionette TRACE 364 <- [1,1,null,{"sessionId":"262d742a-744a-4b91-8fb9-c61402834414","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:28 INFO - 1501074688864 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"}]
06:11:28 INFO - 1501074688865 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49354/test.html"
06:11:28 INFO - 1501074688868 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49354/test.html"
06:11:28 INFO - 1501074688869 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49354/test.html"
06:11:28 INFO - 1501074688897 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"
06:11:28 INFO - 1501074688902 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"
06:11:28 INFO - 1501074688912 Marionette TRACE 364 <- [1,2,null,{}]
06:11:28 INFO - 1501074688913 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:11:28 INFO - 1501074688916 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"82185e48-1eba-4e0b-bbe1-84506112be78","ELEMENT":"82185e48-1eba-4e0b-bbe1-84506112be78"}}]
06:11:28 INFO - 1501074688916 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"82185e48-1eba-4e0b-bbe1-84506112be78"}]
06:11:29 INFO - 1501074689152 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:11:29 INFO - 1501074689153 Marionette TRACE 364 <- [1,4,null,{}]
06:11:29 INFO - 1501074689155 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"82185e48-1eba-4e0b-bbe1-84506112be78","name":"selected"}]
06:11:29 INFO - 1501074689159 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:11:29 INFO - 1501074689160 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}]
06:11:29 INFO - TEST-END: test_click.py TestClick.test_container_element1501074689163 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:11:29 INFO - 1501074689164 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:11:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 344ms
06:11:29 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:11:29 INFO - 1501074689165 Marionette TRACE 364 <- [1,7,null,{}]
06:11:29 INFO - 1501074689167 Marionette DEBUG Closed connection 364
06:11:29 INFO - 1501074689168 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49799
06:11:29 INFO - 1501074689168 Marionette DEBUG Closed connection 365
06:11:29 INFO - 1501074689169 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49800
06:11:29 INFO - 1501074689170 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:29 INFO - 1501074689171 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689172 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689175 Marionette TRACE 366 <- [1,1,null,{"sessionId":"a8e15ae8-549d-4f17-a95b-1caa32de203e","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689177 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:11:29 INFO - 1501074689177 Marionette TRACE 366 <- [1,2,null,{}]
06:11:29 INFO - 1501074689178 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:11:29 INFO - 1501074689178 Marionette TRACE 366 <- [1,3,null,{}]
06:11:29 INFO - 1501074689179 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:11:29 INFO - 1501074689179 Marionette TRACE 366 <- [1,4,null,{}]
06:11:29 INFO - 1501074689181 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}]
06:11:29 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501074689183 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:11:29 INFO - 1501074689184 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:11:29 INFO - 1501074689185 Marionette TRACE 366 <- [1,6,null,{}]
06:11:29 INFO - 1501074689187 Marionette DEBUG Closed connection 366
06:11:29 INFO - 1501074689188 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49801
06:11:29 INFO - 1501074689188 Marionette DEBUG Closed connection 367
06:11:29 INFO - 1501074689189 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49802
06:11:29 INFO - 1501074689190 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:29 INFO - 1501074689190 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689199 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689202 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f26f4334-4cf5-4d62-864f-8349822e434d","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689204 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:11:29 INFO - 1501074689205 Marionette TRACE 368 <- [1,2,null,{}]
06:11:29 INFO - 1501074689207 Marionette DEBUG Closed connection 368
06:11:29 INFO - 1501074689207 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49803
06:11:29 INFO - 1501074689208 Marionette DEBUG Closed connection 369
06:11:29 INFO - 1501074689209 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49804
06:11:29 INFO - 1501074689210 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:11:29 INFO - 1501074689210 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689212 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689214 Marionette TRACE 370 <- [1,1,null,{"sessionId":"de627d7c-09e8-4900-b25f-32a120be3171","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689216 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"}]
06:11:29 INFO - 1501074689217 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"
06:11:29 INFO - 1501074689223 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"
06:11:29 INFO - 1501074689224 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"
06:11:29 INFO - 1501074689239 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"
06:11:29 INFO - 1501074689240 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"
06:11:29 INFO - 1501074689243 Marionette TRACE 370 <- [1,2,null,{}]
06:11:29 INFO - 1501074689247 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:11:29 INFO - 1501074689250 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0e601069-2c1c-4c4b-a494-f53142487545","ELEMENT":"0e601069-2c1c-4c4b-a494-f53142487545"}}]
06:11:29 INFO - 1501074689256 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"0e601069-2c1c-4c4b-a494-f53142487545"}]
06:11:29 INFO - 1501074689493 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:11:29 INFO - 1501074689494 Marionette TRACE 370 <- [1,4,null,{}]
06:11:29 INFO - 1501074689495 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"0e601069-2c1c-4c4b-a494-f53142487545","name":"selected"}]
06:11:29 INFO - 1501074689498 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:11:29 INFO - 1501074689500 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}]
06:11:29 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501074689502 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:11:29 INFO - 1501074689503 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:11:29 INFO - 1501074689505 Marionette TRACE 370 <- [1,7,null,{}]
06:11:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 339ms
06:11:29 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:11:29 INFO - 1501074689506 Marionette DEBUG Closed connection 370
06:11:29 INFO - 1501074689507 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49805
06:11:29 INFO - 1501074689508 Marionette DEBUG Closed connection 371
06:11:29 INFO - 1501074689509 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49806
06:11:29 INFO - 1501074689510 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:29 INFO - 1501074689510 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689512 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689514 Marionette TRACE 372 <- [1,1,null,{"sessionId":"958c26cb-78be-4eaf-b211-9c2d4fe3e2f8","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689516 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:11:29 INFO - 1501074689517 Marionette TRACE 372 <- [1,2,null,{}]
06:11:29 INFO - 1501074689518 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:11:29 INFO - 1501074689518 Marionette TRACE 372 <- [1,3,null,{}]
06:11:29 INFO - 1501074689519 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:11:29 INFO - 1501074689519 Marionette TRACE 372 <- [1,4,null,{}]
06:11:29 INFO - 1501074689521 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}]
06:11:29 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501074689523 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:11:29 INFO - 1501074689524 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:11:29 INFO - 1501074689525 Marionette TRACE 372 <- [1,6,null,{}]
06:11:29 INFO - 1501074689527 Marionette DEBUG Closed connection 372
06:11:29 INFO - 1501074689527 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49807
06:11:29 INFO - 1501074689528 Marionette DEBUG Closed connection 373
06:11:29 INFO - 1501074689529 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49808
06:11:29 INFO - 1501074689530 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:29 INFO - 1501074689530 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689532 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689534 Marionette TRACE 374 <- [1,1,null,{"sessionId":"0d678c89-6f83-4500-813c-ef84114861d8","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689536 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:11:29 INFO - 1501074689537 Marionette TRACE 374 <- [1,2,null,{}]
06:11:29 INFO - 1501074689539 Marionette DEBUG Closed connection 374
06:11:29 INFO - 1501074689539 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49809
06:11:29 INFO - 1501074689540 Marionette DEBUG Closed connection 375
06:11:29 INFO - 1501074689541 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49810
06:11:29 INFO - 1501074689542 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:11:29 INFO - 1501074689542 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689544 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689546 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e8748463-112d-4a74-9592-b2a0b1d7e61b","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689548 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"}]
06:11:29 INFO - 1501074689550 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"
06:11:29 INFO - 1501074689552 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"
06:11:29 INFO - 1501074689553 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"
06:11:29 INFO - 1501074689566 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"
06:11:29 INFO - 1501074689569 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"
06:11:29 INFO - 1501074689578 Marionette TRACE 376 <- [1,2,null,{}]
06:11:29 INFO - 1501074689580 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:11:29 INFO - 1501074689582 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"61cf6878-e196-433b-8cc4-041acbfbe988","ELEMENT":"61cf6878-e196-433b-8cc4-041acbfbe988"}}]
06:11:29 INFO - 1501074689583 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"61cf6878-e196-433b-8cc4-041acbfbe988"}]
06:11:29 INFO - 1501074689818 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:11:29 INFO - 1501074689819 Marionette TRACE 376 <- [1,4,null,{}]
06:11:29 INFO - 1501074689820 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}]
06:11:29 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501074689823 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:11:29 INFO - 1501074689824 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:11:29 INFO - 1501074689825 Marionette TRACE 376 <- [1,6,null,{}]
06:11:29 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 321ms
06:11:29 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:11:29 INFO - 1501074689827 Marionette DEBUG Closed connection 376
06:11:29 INFO - 1501074689828 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49811
06:11:29 INFO - 1501074689828 Marionette DEBUG Closed connection 377
06:11:29 INFO - 1501074689829 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49812
06:11:29 INFO - 1501074689830 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:29 INFO - 1501074689831 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689832 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689835 Marionette TRACE 378 <- [1,1,null,{"sessionId":"0ccc6a29-3724-4178-ab4f-eeb50b1e8864","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689837 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:11:29 INFO - 1501074689837 Marionette TRACE 378 <- [1,2,null,{}]
06:11:29 INFO - 1501074689838 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:11:29 INFO - 1501074689838 Marionette TRACE 378 <- [1,3,null,{}]
06:11:29 INFO - 1501074689839 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:11:29 INFO - 1501074689840 Marionette TRACE 378 <- [1,4,null,{}]
06:11:29 INFO - 1501074689841 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}]
06:11:29 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501074689844 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:11:29 INFO - 1501074689845 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:11:29 INFO - 1501074689846 Marionette TRACE 378 <- [1,6,null,{}]
06:11:29 INFO - 1501074689848 Marionette DEBUG Closed connection 378
06:11:29 INFO - 1501074689848 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49813
06:11:29 INFO - 1501074689849 Marionette DEBUG Closed connection 379
06:11:29 INFO - 1501074689850 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49814
06:11:29 INFO - 1501074689851 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:29 INFO - 1501074689851 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689853 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689862 Marionette TRACE 380 <- [1,1,null,{"sessionId":"699543b3-da9c-4f32-ac76-cfe615795109","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689864 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:11:29 INFO - 1501074689865 Marionette TRACE 380 <- [1,2,null,{}]
06:11:29 INFO - 1501074689867 Marionette DEBUG Closed connection 380
06:11:29 INFO - 1501074689868 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49815
06:11:29 INFO - 1501074689869 Marionette DEBUG Closed connection 381
06:11:29 INFO - 1501074689869 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49816
06:11:29 INFO - 1501074689870 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:11:29 INFO - 1501074689871 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:29 INFO - 1501074689873 Marionette DEBUG Register listener.js for window 12
06:11:29 INFO - 1501074689875 Marionette TRACE 382 <- [1,1,null,{"sessionId":"0ab90847-f6e0-4078-9073-f5c6eb56f5d0","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:29 INFO - 1501074689877 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"}]
06:11:29 INFO - 1501074689878 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"
06:11:29 INFO - 1501074689880 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"
06:11:29 INFO - 1501074689880 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"
06:11:29 INFO - 1501074689898 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"
06:11:29 INFO - 1501074689900 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"
06:11:29 INFO - 1501074689909 Marionette TRACE 382 <- [1,2,null,{}]
06:11:29 INFO - 1501074689916 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:11:29 INFO - 1501074689918 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"27a47157-780e-4794-9183-af2bb49297d0","ELEMENT":"27a47157-780e-4794-9183-af2bb49297d0"}}]
06:11:29 INFO - 1501074689920 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"27a47157-780e-4794-9183-af2bb49297d0"}]
06:11:30 INFO - 1501074690158 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:11:30 INFO - 1501074690159 Marionette TRACE 382 <- [1,4,null,{}]
06:11:30 INFO - 1501074690160 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}]
06:11:30 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501074690163 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:11:30 INFO - 1501074690164 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:11:30 INFO - 1501074690165 Marionette TRACE 382 <- [1,6,null,{}]
06:11:30 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 339ms
06:11:30 INFO - TEST-START | test_click.py TestClick.test_input_file
06:11:30 INFO - 1501074690166 Marionette DEBUG Closed connection 382
06:11:30 INFO - 1501074690168 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49817
06:11:30 INFO - 1501074690168 Marionette DEBUG Closed connection 383
06:11:30 INFO - 1501074690169 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49818
06:11:30 INFO - 1501074690170 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:30 INFO - 1501074690171 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690172 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690175 Marionette TRACE 384 <- [1,1,null,{"sessionId":"8a35038a-18d6-4115-84eb-93d82bca3217","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690177 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:11:30 INFO - 1501074690177 Marionette TRACE 384 <- [1,2,null,{}]
06:11:30 INFO - 1501074690178 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:11:30 INFO - 1501074690179 Marionette TRACE 384 <- [1,3,null,{}]
06:11:30 INFO - 1501074690179 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:11:30 INFO - 1501074690180 Marionette TRACE 384 <- [1,4,null,{}]
06:11:30 INFO - 1501074690181 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}]
06:11:30 INFO - TEST-START: test_click.py TestClick.test_input_file1501074690184 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:11:30 INFO - 1501074690184 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:11:30 INFO - 1501074690185 Marionette TRACE 384 <- [1,6,null,{}]
06:11:30 INFO - 1501074690187 Marionette DEBUG Closed connection 384
06:11:30 INFO - 1501074690188 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49819
06:11:30 INFO - 1501074690189 Marionette DEBUG Closed connection 385
06:11:30 INFO - 1501074690189 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49820
06:11:30 INFO - 1501074690190 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:30 INFO - 1501074690191 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690192 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690195 Marionette TRACE 386 <- [1,1,null,{"sessionId":"3427386b-a7f8-4e9e-b2fd-531f166b2dab","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690196 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:11:30 INFO - 1501074690197 Marionette TRACE 386 <- [1,2,null,{}]
06:11:30 INFO - 1501074690199 Marionette DEBUG Closed connection 386
06:11:30 INFO - 1501074690200 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49821
06:11:30 INFO - 1501074690200 Marionette DEBUG Closed connection 387
06:11:30 INFO - 1501074690201 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49822
06:11:30 INFO - 1501074690202 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:11:30 INFO - 1501074690203 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690204 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690206 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1c711dae-7643-4ed9-a048-97a61759a231","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690208 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:11:30 INFO - 1501074690209 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"
06:11:30 INFO - 1501074690211 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"
06:11:30 INFO - 1501074690212 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"
06:11:30 INFO - 1501074690227 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:11:30 INFO - 1501074690229 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:11:30 INFO - 1501074690232 Marionette TRACE 388 <- [1,2,null,{}]
06:11:30 INFO - 1501074690233 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:11:30 INFO - 1501074690240 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"35750950-ddda-4101-9040-2b2ebc37e36a","ELEMENT":"35750950-ddda-4101-9040-2b2ebc37e36a"}}]
06:11:30 INFO - 1501074690242 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"35750950-ddda-4101-9040-2b2ebc37e36a"}]
06:11:30 INFO - 1501074690268 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]
06:11:30 INFO - 1501074690270 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}]
06:11:30 INFO - TEST-END: test_click.py TestClick.test_input_file1501074690272 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:11:30 INFO - 1501074690273 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:11:30 INFO - 1501074690274 Marionette TRACE 388 <- [1,6,null,{}]
06:11:30 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 109ms
06:11:30 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:11:30 INFO - 1501074690276 Marionette DEBUG Closed connection 388
06:11:30 INFO - 1501074690277 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49823
06:11:30 INFO - 1501074690278 Marionette DEBUG Closed connection 389
06:11:30 INFO - 1501074690278 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49824
06:11:30 INFO - 1501074690279 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:30 INFO - 1501074690280 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690281 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690284 Marionette TRACE 390 <- [1,1,null,{"sessionId":"2419e5e9-2eb9-471a-a47e-144c28dfa881","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690286 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:11:30 INFO - 1501074690286 Marionette TRACE 390 <- [1,2,null,{}]
06:11:30 INFO - 1501074690287 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:11:30 INFO - 1501074690288 Marionette TRACE 390 <- [1,3,null,{}]
06:11:30 INFO - 1501074690289 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:11:30 INFO - 1501074690289 Marionette TRACE 390 <- [1,4,null,{}]
06:11:30 INFO - 1501074690291 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}]
06:11:30 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501074690293 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:11:30 INFO - 1501074690294 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:11:30 INFO - 1501074690295 Marionette TRACE 390 <- [1,6,null,{}]
06:11:30 INFO - 1501074690296 Marionette DEBUG Closed connection 390
06:11:30 INFO - 1501074690297 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49825
06:11:30 INFO - 1501074690297 Marionette DEBUG Closed connection 391
06:11:30 INFO - 1501074690298 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49826
06:11:30 INFO - 1501074690299 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:30 INFO - 1501074690300 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690301 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690303 Marionette TRACE 392 <- [1,1,null,{"sessionId":"eb423174-4ca9-4692-a42d-f8ec8c6c786e","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690306 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:11:30 INFO - 1501074690307 Marionette TRACE 392 <- [1,2,null,{}]
06:11:30 INFO - 1501074690308 Marionette DEBUG Closed connection 392
06:11:30 INFO - 1501074690309 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49827
06:11:30 INFO - 1501074690310 Marionette DEBUG Closed connection 393
06:11:30 INFO - 1501074690310 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49828
06:11:30 INFO - 1501074690311 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:11:30 INFO - 1501074690312 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690313 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690323 Marionette TRACE 394 <- [1,1,null,{"sessionId":"80005bd9-cd59-4ffc-a07c-312249e56ed9","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690325 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"}]
06:11:30 INFO - 1501074690326 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:11:30 INFO - 1501074690328 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:11:30 INFO - 1501074690328 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:11:30 INFO - 1501074690347 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"
06:11:30 INFO - 1501074690349 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"
06:11:30 INFO - 1501074690352 Marionette TRACE 394 <- [1,2,null,{}]
06:11:30 INFO - 1501074690359 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:11:30 INFO - 1501074690361 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e2090a10-2cac-4e4a-ad15-d92a0219294f","ELEMENT":"e2090a10-2cac-4e4a-ad15-d92a0219294f"}}]
06:11:30 INFO - 1501074690362 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:11:30 INFO - 1501074690364 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"41f1e070-e3df-45ff-9a89-b88f55df4dd7","ELEMENT":"41f1e070-e3df-45ff-9a89-b88f55df4dd7"}}]
06:11:30 INFO - 1501074690365 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e2090a10-2cac-4e4a-ad15-d92a0219294f"}]
06:11:30 INFO - 1501074690600 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:11:30 INFO - 1501074690601 Marionette TRACE 394 <- [1,5,null,{}]
06:11:30 INFO - 1501074690602 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"41f1e070-e3df-45ff-9a89-b88f55df4dd7"}]
06:11:30 INFO - 1501074690616 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]
06:11:30 INFO - 1501074690618 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:11:30 INFO - 1501074690621 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:11:30 INFO - 1501074690622 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}]
06:11:30 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501074690625 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:11:30 INFO - 1501074690625 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:11:30 INFO - 1501074690627 Marionette TRACE 394 <- [1,9,null,{}]
06:11:30 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 352ms
06:11:30 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:11:30 INFO - 1501074690629 Marionette DEBUG Closed connection 394
06:11:30 INFO - 1501074690629 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49829
06:11:30 INFO - 1501074690630 Marionette DEBUG Closed connection 395
06:11:30 INFO - 1501074690631 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49830
06:11:30 INFO - 1501074690632 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:30 INFO - 1501074690632 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690634 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690637 Marionette TRACE 396 <- [1,1,null,{"sessionId":"85fc1e30-1b58-4685-b3b0-ccd0471bbf9c","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690639 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:11:30 INFO - 1501074690639 Marionette TRACE 396 <- [1,2,null,{}]
06:11:30 INFO - 1501074690640 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:11:30 INFO - 1501074690640 Marionette TRACE 396 <- [1,3,null,{}]
06:11:30 INFO - 1501074690641 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:11:30 INFO - 1501074690642 Marionette TRACE 396 <- [1,4,null,{}]
06:11:30 INFO - 1501074690643 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}]
06:11:30 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501074690646 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:11:30 INFO - 1501074690647 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:11:30 INFO - 1501074690648 Marionette TRACE 396 <- [1,6,null,{}]
06:11:30 INFO - 1501074690649 Marionette DEBUG Closed connection 396
06:11:30 INFO - 1501074690650 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49831
06:11:30 INFO - 1501074690651 Marionette DEBUG Closed connection 397
06:11:30 INFO - 1501074690652 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49832
06:11:30 INFO - 1501074690653 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:11:30 INFO - 1501074690653 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690655 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690657 Marionette TRACE 398 <- [1,1,null,{"sessionId":"7d9547c5-0202-4087-8b0b-d54297dcd157","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690659 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:11:30 INFO - 1501074690660 Marionette TRACE 398 <- [1,2,null,{}]
06:11:30 INFO - 1501074690662 Marionette DEBUG Closed connection 398
06:11:30 INFO - 1501074690662 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49833
06:11:30 INFO - 1501074690663 Marionette DEBUG Closed connection 399
06:11:30 INFO - 1501074690664 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49834
06:11:30 INFO - 1501074690665 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:11:30 INFO - 1501074690666 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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}
06:11:30 INFO - 1501074690667 Marionette DEBUG Register listener.js for window 12
06:11:30 INFO - 1501074690670 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d671ead6-33fd-4bee-81b8-ff4ab13c2c9a","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":1760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpolq2pd.mozrunner","moz:accessibilityChecks":false}}]
06:11:30 INFO - 1501074690674 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"}]
06:11:30 INFO - 1501074690675 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"
06:11:30 INFO - 1501074690677 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"
06:11:30 INFO - 1501074690677 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"
06:11:30 INFO - 1501074690694 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"
06:11:30 INFO - 1501074690696 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"
06:11:30 INFO - 1501074690699 Marionette TRACE 400 <- [1,2,null,{}]
06:11:30 INFO - 1501074690706 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:11:30 INFO - 1501074690709 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"823f9fb9-a086-4b34-93b6-1c9d970ecb9a","ELEMENT":"823f9fb9-a086-4b34-93b6-1c9d970ecb9a"}}]
06:11:30 INFO - 1501074690710 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"823f9fb9-a086-4b34-93b6-1c9d970ecb9a"}]
06:11:30 INFO - 1501074690733 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:11:30 INFO - 1501074690734 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"823f9fb9-a086-4b34-93b6-1c9d970ecb9a"}]
06:11:30 INFO - 1501074690748 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element