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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
00:09:45 INFO - 1499411385765 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}]
00:09:45 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499411385768 Marionette TRACE 352 <- [1,5,null,{"value":null}]
00:09:45 INFO - 1499411385769 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
00:09:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 143ms
00:09:45 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
00:09:45 INFO - 1499411385771 Marionette TRACE 352 <- [1,6,null,{}]
00:09:45 INFO - 1499411385772 Marionette DEBUG Closed connection 352
00:09:45 INFO - 1499411385773 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49793
00:09:45 INFO - 1499411385774 Marionette DEBUG Closed connection 353
00:09:45 INFO - 1499411385775 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49794
00:09:45 INFO - 1499411385776 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:45 INFO - 1499411385777 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:45 INFO - 1499411385779 Marionette DEBUG Register listener.js for window 4294967297
00:09:45 INFO - 1499411385783 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b69b511c-fb91-4a2a-9f91-dac247a32c41","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:45 INFO - 1499411385784 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
00:09:45 INFO - 1499411385784 Marionette TRACE 354 <- [1,2,null,{}]
00:09:45 INFO - 1499411385785 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:09:45 INFO - 1499411385786 Marionette TRACE 354 <- [1,3,null,{}]
00:09:45 INFO - 1499411385787 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
00:09:45 INFO - 1499411385788 Marionette TRACE 354 <- [1,4,null,{}]
00:09:45 INFO - 1499411385789 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}]
00:09:45 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499411385792 Marionette TRACE 354 <- [1,5,null,{"value":null}]
00:09:45 INFO - 1499411385793 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
00:09:45 INFO - 1499411385795 Marionette TRACE 354 <- [1,6,null,{}]
00:09:45 INFO - 1499411385796 Marionette DEBUG Closed connection 354
00:09:45 INFO - 1499411385797 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49795
00:09:45 INFO - 1499411385798 Marionette DEBUG Closed connection 355
00:09:45 INFO - 1499411385799 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49796
00:09:45 INFO - 1499411385800 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:45 INFO - 1499411385801 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:45 INFO - 1499411385803 Marionette DEBUG Register listener.js for window 4294967297
00:09:45 INFO - 1499411385807 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d332ebb6-3fd9-4a78-a83a-fb2bd0b2c31e","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:45 INFO - 1499411385808 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
00:09:45 INFO - 1499411385809 Marionette TRACE 356 <- [1,2,null,{}]
00:09:45 INFO - 1499411385811 Marionette DEBUG Closed connection 356
00:09:45 INFO - 1499411385812 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49797
00:09:45 INFO - 1499411385813 Marionette DEBUG Closed connection 357
00:09:45 INFO - 1499411385813 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49798
00:09:45 INFO - 1499411385814 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:09:45 INFO - 1499411385815 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:45 INFO - 1499411385818 Marionette DEBUG Register listener.js for window 4294967297
00:09:45 INFO - 1499411385821 Marionette TRACE 358 <- [1,1,null,{"sessionId":"5b0fe1c2-0274-46d6-b376-53a55ffda2d9","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:45 INFO - 1499411385822 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49350/clicks.html"}]
00:09:45 INFO - 1499411385824 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49350/hidden.html"
00:09:45 INFO - 1499411385836 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49350/hidden.html"
00:09:45 INFO - 1499411385837 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49350/hidden.html"
00:09:45 INFO - 1499411385844 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49350/clicks.html"
00:09:45 INFO - 1499411385850 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49350/clicks.html"
00:09:45 INFO - 1499411385860 Marionette TRACE 358 <- [1,2,null,{}]
00:09:45 INFO - 1499411385872 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
00:09:45 INFO - 1499411385875 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cfa4c425-301c-408e-a35e-ee779617aef9","ELEMENT":"cfa4c425-301c-408e-a35e-ee779617aef9"}}]
00:09:45 INFO - 1499411385876 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"cfa4c425-301c-408e-a35e-ee779617aef9"}]
00:09:45 INFO - 1499411385921 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49350/clicks.html"
00:09:45 INFO - 1499411385930 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49350/clicks.html"
00:09:45 INFO - 1499411385930 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49350/clicks.html"
00:09:45 INFO - 1499411385940 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49350/test.html"
00:09:45 INFO - 1499411385946 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49350/test.html"
00:09:45 INFO - 1499411385952 Marionette TRACE 358 <- [1,4,null,{}]
00:09:45 INFO - 1499411385957 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
00:09:45 INFO - 1499411385960 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43f1b89f-0141-47a5-b5bf-aa356ddaf1c9","ELEMENT":"43f1b89f-0141-47a5-b5bf-aa356ddaf1c9"}}]
00:09:45 INFO - 1499411385961 Marionette TRACE 358 -> [0,6,"getTitle",{}]
00:09:45 INFO - 1499411385962 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
00:09:45 INFO - 1499411385968 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}]
00:09:45 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499411385971 Marionette TRACE 358 <- [1,7,null,{"value":null}]
00:09:45 INFO - 1499411385972 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
00:09:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 202ms
00:09:45 INFO - TEST-START | test_click.py TestClick.test_container_element
00:09:45 INFO - 1499411385974 Marionette TRACE 358 <- [1,8,null,{}]
00:09:45 INFO - 1499411385975 Marionette DEBUG Closed connection 358
00:09:45 INFO - 1499411385976 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49801
00:09:45 INFO - 1499411385977 Marionette DEBUG Closed connection 359
00:09:45 INFO - 1499411385978 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49802
00:09:45 INFO - 1499411385979 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:45 INFO - 1499411385980 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:45 INFO - 1499411385983 Marionette DEBUG Register listener.js for window 4294967297
00:09:45 INFO - 1499411385986 Marionette TRACE 360 <- [1,1,null,{"sessionId":"82d742be-913e-4a55-9225-0f3c0354d03b","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:45 INFO - 1499411385987 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
00:09:45 INFO - 1499411385988 Marionette TRACE 360 <- [1,2,null,{}]
00:09:45 INFO - 1499411385989 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:09:45 INFO - 1499411385989 Marionette TRACE 360 <- [1,3,null,{}]
00:09:45 INFO - 1499411385990 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
00:09:45 INFO - 1499411385991 Marionette TRACE 360 <- [1,4,null,{}]
00:09:45 INFO - 1499411385993 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}]
00:09:45 INFO - TEST-START: test_click.py TestClick.test_container_element1499411385996 Marionette TRACE 360 <- [1,5,null,{"value":null}]
00:09:45 INFO - 1499411385997 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
00:09:45 INFO - 1499411385998 Marionette TRACE 360 <- [1,6,null,{}]
00:09:46 INFO - 1499411386000 Marionette DEBUG Closed connection 360
00:09:46 INFO - 1499411386001 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49803
00:09:46 INFO - 1499411386002 Marionette DEBUG Closed connection 361
00:09:46 INFO - 1499411386002 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49804
00:09:46 INFO - 1499411386003 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:46 INFO - 1499411386004 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:46 INFO - 1499411386007 Marionette DEBUG Register listener.js for window 4294967297
00:09:46 INFO - 1499411386010 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3a577134-8484-4e6d-bd42-92f2ad50bf2b","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:46 INFO - 1499411386011 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
00:09:46 INFO - 1499411386013 Marionette TRACE 362 <- [1,2,null,{}]
00:09:46 INFO - 1499411386015 Marionette DEBUG Closed connection 362
00:09:46 INFO - 1499411386016 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49805
00:09:46 INFO - 1499411386016 Marionette DEBUG Closed connection 363
00:09:46 INFO - 1499411386017 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49806
00:09:46 INFO - 1499411386018 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:09:46 INFO - 1499411386019 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:46 INFO - 1499411386022 Marionette DEBUG Register listener.js for window 4294967297
00:09:46 INFO - 1499411386025 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1dda5a3f-18ee-432a-9682-0b8012074964","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:46 INFO - 1499411386026 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"}]
00:09:46 INFO - 1499411386028 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49350/test.html"
00:09:46 INFO - 1499411386031 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49350/test.html"
00:09:46 INFO - 1499411386031 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49350/test.html"
00:09:46 INFO - 1499411386038 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"
00:09:46 INFO - 1499411386042 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"
00:09:46 INFO - 1499411386055 Marionette TRACE 364 <- [1,2,null,{}]
00:09:46 INFO - 1499411386060 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
00:09:46 INFO - 1499411386067 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0bbefe68-7fc3-437c-9a8e-c8e00ccd568c","ELEMENT":"0bbefe68-7fc3-437c-9a8e-c8e00ccd568c"}}]
00:09:46 INFO - 1499411386068 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0bbefe68-7fc3-437c-9a8e-c8e00ccd568c"}]
00:09:46 INFO - 1499411386312 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:09:46 INFO - 1499411386313 Marionette TRACE 364 <- [1,4,null,{}]
00:09:46 INFO - 1499411386314 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0bbefe68-7fc3-437c-9a8e-c8e00ccd568c","name":"selected"}]
00:09:46 INFO - 1499411386319 Marionette TRACE 364 <- [1,5,null,{"value":true}]
00:09:46 INFO - 1499411386321 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}]
00:09:46 INFO - TEST-END: test_click.py TestClick.test_container_element1499411386324 Marionette TRACE 364 <- [1,6,null,{"value":null}]
00:09:46 INFO - 1499411386325 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
00:09:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 352ms
00:09:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
00:09:46 INFO - 1499411386326 Marionette TRACE 364 <- [1,7,null,{}]
00:09:46 INFO - 1499411386328 Marionette DEBUG Closed connection 364
00:09:46 INFO - 1499411386329 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49807
00:09:46 INFO - 1499411386332 Marionette DEBUG Closed connection 365
00:09:46 INFO - 1499411386332 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49808
00:09:46 INFO - 1499411386333 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:46 INFO - 1499411386334 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:46 INFO - 1499411386337 Marionette DEBUG Register listener.js for window 4294967297
00:09:46 INFO - 1499411386341 Marionette TRACE 366 <- [1,1,null,{"sessionId":"390c65f7-0538-494e-904a-72ba1627caed","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:46 INFO - 1499411386342 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
00:09:46 INFO - 1499411386342 Marionette TRACE 366 <- [1,2,null,{}]
00:09:46 INFO - 1499411386343 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:09:46 INFO - 1499411386344 Marionette TRACE 366 <- [1,3,null,{}]
00:09:46 INFO - 1499411386345 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
00:09:46 INFO - 1499411386345 Marionette TRACE 366 <- [1,4,null,{}]
00:09:46 INFO - 1499411386347 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}]
00:09:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499411386350 Marionette TRACE 366 <- [1,5,null,{"value":null}]
00:09:46 INFO - 1499411386351 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
00:09:46 INFO - 1499411386352 Marionette TRACE 366 <- [1,6,null,{}]
00:09:46 INFO - 1499411386354 Marionette DEBUG Closed connection 366
00:09:46 INFO - 1499411386355 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49809
00:09:46 INFO - 1499411386356 Marionette DEBUG Closed connection 367
00:09:46 INFO - 1499411386356 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49810
00:09:46 INFO - 1499411386357 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:46 INFO - 1499411386358 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:46 INFO - 1499411386361 Marionette DEBUG Register listener.js for window 4294967297
00:09:46 INFO - 1499411386364 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c88cd06b-394b-4a10-ac9b-299e66069ee2","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:46 INFO - 1499411386365 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
00:09:46 INFO - 1499411386367 Marionette TRACE 368 <- [1,2,null,{}]
00:09:46 INFO - 1499411386368 Marionette DEBUG Closed connection 368
00:09:46 INFO - 1499411386369 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49811
00:09:46 INFO - 1499411386370 Marionette DEBUG Closed connection 369
00:09:46 INFO - 1499411386371 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49812
00:09:46 INFO - 1499411386372 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:09:46 INFO - 1499411386373 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:46 INFO - 1499411386375 Marionette DEBUG Register listener.js for window 4294967297
00:09:46 INFO - 1499411386378 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d4ff0661-51a6-4dd1-9728-03e764fae89e","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:46 INFO - 1499411386380 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"}]
00:09:46 INFO - 1499411386382 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"
00:09:46 INFO - 1499411386384 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"
00:09:46 INFO - 1499411386384 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"
00:09:46 INFO - 1499411386391 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"
00:09:46 INFO - 1499411386395 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"
00:09:46 INFO - 1499411386404 Marionette TRACE 370 <- [1,2,null,{}]
00:09:46 INFO - 1499411386408 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
00:09:46 INFO - 1499411386411 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ecd53dfc-75b0-4df5-a67f-06b54320f983","ELEMENT":"ecd53dfc-75b0-4df5-a67f-06b54320f983"}}]
00:09:46 INFO - 1499411386417 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ecd53dfc-75b0-4df5-a67f-06b54320f983"}]
00:09:46 INFO - 1499411386668 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:09:46 INFO - 1499411386669 Marionette TRACE 370 <- [1,4,null,{}]
00:09:46 INFO - 1499411386670 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ecd53dfc-75b0-4df5-a67f-06b54320f983","name":"selected"}]
00:09:46 INFO - 1499411386675 Marionette TRACE 370 <- [1,5,null,{"value":true}]
00:09:46 INFO - 1499411386677 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}]
00:09:46 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499411386680 Marionette TRACE 370 <- [1,6,null,{"value":null}]
00:09:46 INFO - 1499411386681 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
00:09:46 INFO - 1499411386682 Marionette TRACE 370 <- [1,7,null,{}]
00:09:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 355ms
00:09:46 INFO - TEST-START | test_click.py TestClick.test_css_transforms
00:09:46 INFO - 1499411386684 Marionette DEBUG Closed connection 370
00:09:46 INFO - 1499411386685 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49813
00:09:46 INFO - 1499411386686 Marionette DEBUG Closed connection 371
00:09:46 INFO - 1499411386686 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49814
00:09:46 INFO - 1499411386687 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:46 INFO - 1499411386688 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:46 INFO - 1499411386691 Marionette DEBUG Register listener.js for window 4294967297
00:09:46 INFO - 1499411386694 Marionette TRACE 372 <- [1,1,null,{"sessionId":"474e71fc-ab90-4f12-a954-3aaddca21f43","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:46 INFO - 1499411386695 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
00:09:46 INFO - 1499411386696 Marionette TRACE 372 <- [1,2,null,{}]
00:09:46 INFO - 1499411386697 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:09:46 INFO - 1499411386697 Marionette TRACE 372 <- [1,3,null,{}]
00:09:46 INFO - 1499411386698 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
00:09:46 INFO - 1499411386699 Marionette TRACE 372 <- [1,4,null,{}]
00:09:46 INFO - 1499411386701 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}]
00:09:46 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499411386703 Marionette TRACE 372 <- [1,5,null,{"value":null}]
00:09:46 INFO - 1499411386704 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
00:09:46 INFO - 1499411386706 Marionette TRACE 372 <- [1,6,null,{}]
00:09:46 INFO - 1499411386708 Marionette DEBUG Closed connection 372
00:09:46 INFO - 1499411386708 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49815
00:09:46 INFO - 1499411386709 Marionette DEBUG Closed connection 373
00:09:46 INFO - 1499411386710 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49816
00:09:46 INFO - 1499411386711 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:46 INFO - 1499411386712 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:46 INFO - 1499411386715 Marionette DEBUG Register listener.js for window 4294967297
00:09:46 INFO - 1499411386718 Marionette TRACE 374 <- [1,1,null,{"sessionId":"9a895bc9-c944-4476-a70d-7e7db92c624b","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:46 INFO - 1499411386719 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
00:09:46 INFO - 1499411386720 Marionette TRACE 374 <- [1,2,null,{}]
00:09:46 INFO - 1499411386722 Marionette DEBUG Closed connection 374
00:09:46 INFO - 1499411386723 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49817
00:09:46 INFO - 1499411386724 Marionette DEBUG Closed connection 375
00:09:46 INFO - 1499411386725 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49818
00:09:46 INFO - 1499411386726 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:09:46 INFO - 1499411386727 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:46 INFO - 1499411386730 Marionette DEBUG Register listener.js for window 4294967297
00:09:46 INFO - 1499411386734 Marionette TRACE 376 <- [1,1,null,{"sessionId":"ce79ecd5-a7c8-4aaf-9278-c9980ad1afb7","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:46 INFO - 1499411386735 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"}]
00:09:46 INFO - 1499411386737 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"
00:09:46 INFO - 1499411386739 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"
00:09:46 INFO - 1499411386739 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"
00:09:46 INFO - 1499411386748 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"
00:09:46 INFO - 1499411386750 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"
00:09:46 INFO - 1499411386758 Marionette TRACE 376 <- [1,2,null,{}]
00:09:46 INFO - 1499411386762 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
00:09:46 INFO - 1499411386769 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8a757f9-8896-4e37-a189-f6db1938e506","ELEMENT":"e8a757f9-8896-4e37-a189-f6db1938e506"}}]
00:09:46 INFO - 1499411386770 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e8a757f9-8896-4e37-a189-f6db1938e506"}]
00:09:47 INFO - 1499411387011 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:09:47 INFO - 1499411387012 Marionette TRACE 376 <- [1,4,null,{}]
00:09:47 INFO - 1499411387014 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}]
00:09:47 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499411387017 Marionette TRACE 376 <- [1,5,null,{"value":null}]
00:09:47 INFO - 1499411387018 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
00:09:47 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 337ms
00:09:47 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
00:09:47 INFO - 1499411387020 Marionette TRACE 376 <- [1,6,null,{}]
00:09:47 INFO - 1499411387022 Marionette DEBUG Closed connection 376
00:09:47 INFO - 1499411387022 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49821
00:09:47 INFO - 1499411387023 Marionette DEBUG Closed connection 377
00:09:47 INFO - 1499411387024 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49822
00:09:47 INFO - 1499411387025 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:47 INFO - 1499411387026 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387029 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387032 Marionette TRACE 378 <- [1,1,null,{"sessionId":"cad36a4e-e448-4d63-b94e-d343947e0a18","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387033 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
00:09:47 INFO - 1499411387034 Marionette TRACE 378 <- [1,2,null,{}]
00:09:47 INFO - 1499411387034 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:09:47 INFO - 1499411387035 Marionette TRACE 378 <- [1,3,null,{}]
00:09:47 INFO - 1499411387036 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
00:09:47 INFO - 1499411387037 Marionette TRACE 378 <- [1,4,null,{}]
00:09:47 INFO - 1499411387038 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}]
00:09:47 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499411387041 Marionette TRACE 378 <- [1,5,null,{"value":null}]
00:09:47 INFO - 1499411387042 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
00:09:47 INFO - 1499411387044 Marionette TRACE 378 <- [1,6,null,{}]
00:09:47 INFO - 1499411387046 Marionette DEBUG Closed connection 378
00:09:47 INFO - 1499411387046 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49823
00:09:47 INFO - 1499411387047 Marionette DEBUG Closed connection 379
00:09:47 INFO - 1499411387048 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49824
00:09:47 INFO - 1499411387049 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:47 INFO - 1499411387050 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387053 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387056 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9c52a2c3-4503-463e-bfc3-14dd0820a364","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387057 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
00:09:47 INFO - 1499411387058 Marionette TRACE 380 <- [1,2,null,{}]
00:09:47 INFO - 1499411387060 Marionette DEBUG Closed connection 380
00:09:47 INFO - 1499411387061 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49825
00:09:47 INFO - 1499411387062 Marionette DEBUG Closed connection 381
00:09:47 INFO - 1499411387062 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49826
00:09:47 INFO - 1499411387063 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:09:47 INFO - 1499411387064 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387067 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387070 Marionette TRACE 382 <- [1,1,null,{"sessionId":"691f3feb-646a-4f65-af1a-63ffbacc9d20","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387071 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"}]
00:09:47 INFO - 1499411387073 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"
00:09:47 INFO - 1499411387075 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"
00:09:47 INFO - 1499411387076 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"
00:09:47 INFO - 1499411387086 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"
00:09:47 INFO - 1499411387087 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"
00:09:47 INFO - 1499411387093 Marionette TRACE 382 <- [1,2,null,{}]
00:09:47 INFO - 1499411387103 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
00:09:47 INFO - 1499411387106 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"efc889f9-c5d6-433f-ab4c-4a74c140c35b","ELEMENT":"efc889f9-c5d6-433f-ab4c-4a74c140c35b"}}]
00:09:47 INFO - 1499411387107 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"efc889f9-c5d6-433f-ab4c-4a74c140c35b"}]
00:09:47 INFO - 1499411387351 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:09:47 INFO - 1499411387352 Marionette TRACE 382 <- [1,4,null,{}]
00:09:47 INFO - 1499411387354 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}]
00:09:47 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499411387357 Marionette TRACE 382 <- [1,5,null,{"value":null}]
00:09:47 INFO - 1499411387358 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
00:09:47 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 339ms
00:09:47 INFO - TEST-START | test_click.py TestClick.test_input_file
00:09:47 INFO - 1499411387360 Marionette TRACE 382 <- [1,6,null,{}]
00:09:47 INFO - 1499411387361 Marionette DEBUG Closed connection 382
00:09:47 INFO - 1499411387362 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49827
00:09:47 INFO - 1499411387363 Marionette DEBUG Closed connection 383
00:09:47 INFO - 1499411387364 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49828
00:09:47 INFO - 1499411387365 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:47 INFO - 1499411387366 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387369 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387381 Marionette TRACE 384 <- [1,1,null,{"sessionId":"16b78f24-4e19-40e9-99f9-d58d97c75764","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387382 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
00:09:47 INFO - 1499411387382 Marionette TRACE 384 <- [1,2,null,{}]
00:09:47 INFO - 1499411387383 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:09:47 INFO - 1499411387384 Marionette TRACE 384 <- [1,3,null,{}]
00:09:47 INFO - 1499411387385 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
00:09:47 INFO - 1499411387385 Marionette TRACE 384 <- [1,4,null,{}]
00:09:47 INFO - 1499411387387 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}]
00:09:47 INFO - TEST-START: test_click.py TestClick.test_input_file1499411387390 Marionette TRACE 384 <- [1,5,null,{"value":null}]
00:09:47 INFO - 1499411387391 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
00:09:47 INFO - 1499411387393 Marionette TRACE 384 <- [1,6,null,{}]
00:09:47 INFO - 1499411387394 Marionette DEBUG Closed connection 384
00:09:47 INFO - 1499411387395 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49829
00:09:47 INFO - 1499411387396 Marionette DEBUG Closed connection 385
00:09:47 INFO - 1499411387397 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49830
00:09:47 INFO - 1499411387398 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:47 INFO - 1499411387399 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387402 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387405 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f85ee2c5-4d6b-494f-8aaa-4f39b1fa1091","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387406 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
00:09:47 INFO - 1499411387408 Marionette TRACE 386 <- [1,2,null,{}]
00:09:47 INFO - 1499411387409 Marionette DEBUG Closed connection 386
00:09:47 INFO - 1499411387410 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49831
00:09:47 INFO - 1499411387411 Marionette DEBUG Closed connection 387
00:09:47 INFO - 1499411387411 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49832
00:09:47 INFO - 1499411387413 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:09:47 INFO - 1499411387413 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387416 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387419 Marionette TRACE 388 <- [1,1,null,{"sessionId":"5bd8ac47-5c62-4bff-869e-52867511165c","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387420 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
00:09:47 INFO - 1499411387422 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"
00:09:47 INFO - 1499411387426 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"
00:09:47 INFO - 1499411387426 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"
00:09:47 INFO - 1499411387433 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:09:47 INFO - 1499411387438 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:09:47 INFO - 1499411387443 Marionette TRACE 388 <- [1,2,null,{}]
00:09:47 INFO - 1499411387446 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
00:09:47 INFO - 1499411387458 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe741923-e9da-43b5-bf79-871d92edfbc0","ELEMENT":"fe741923-e9da-43b5-bf79-871d92edfbc0"}}]
00:09:47 INFO - 1499411387459 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"fe741923-e9da-43b5-bf79-871d92edfbc0"}]
00:09:47 INFO - 1499411387492 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
00:09:47 INFO - 1499411387494 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}]
00:09:47 INFO - TEST-END: test_click.py TestClick.test_input_file1499411387497 Marionette TRACE 388 <- [1,5,null,{"value":null}]
00:09:47 INFO - 1499411387498 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
00:09:47 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 139ms
00:09:47 INFO - TEST-START | test_click.py TestClick.test_obscured_element
00:09:47 INFO - 1499411387500 Marionette TRACE 388 <- [1,6,null,{}]
00:09:47 INFO - 1499411387501 Marionette DEBUG Closed connection 388
00:09:47 INFO - 1499411387502 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49833
00:09:47 INFO - 1499411387503 Marionette DEBUG Closed connection 389
00:09:47 INFO - 1499411387504 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49834
00:09:47 INFO - 1499411387505 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:47 INFO - 1499411387506 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387509 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387512 Marionette TRACE 390 <- [1,1,null,{"sessionId":"6ba4b977-a2f1-4bbb-bc5f-e57c37dc9802","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387513 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
00:09:47 INFO - 1499411387514 Marionette TRACE 390 <- [1,2,null,{}]
00:09:47 INFO - 1499411387514 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:09:47 INFO - 1499411387515 Marionette TRACE 390 <- [1,3,null,{}]
00:09:47 INFO - 1499411387516 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
00:09:47 INFO - 1499411387517 Marionette TRACE 390 <- [1,4,null,{}]
00:09:47 INFO - 1499411387518 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}]
00:09:47 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499411387521 Marionette TRACE 390 <- [1,5,null,{"value":null}]
00:09:47 INFO - 1499411387522 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
00:09:47 INFO - 1499411387524 Marionette TRACE 390 <- [1,6,null,{}]
00:09:47 INFO - 1499411387525 Marionette DEBUG Closed connection 390
00:09:47 INFO - 1499411387526 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49835
00:09:47 INFO - 1499411387527 Marionette DEBUG Closed connection 391
00:09:47 INFO - 1499411387529 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49836
00:09:47 INFO - 1499411387531 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:47 INFO - 1499411387532 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387535 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387538 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fde11b8c-12ea-4516-9ea7-3eb640a3e7dd","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387539 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
00:09:47 INFO - 1499411387540 Marionette TRACE 392 <- [1,2,null,{}]
00:09:47 INFO - 1499411387542 Marionette DEBUG Closed connection 392
00:09:47 INFO - 1499411387543 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49837
00:09:47 INFO - 1499411387544 Marionette DEBUG Closed connection 393
00:09:47 INFO - 1499411387545 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49838
00:09:47 INFO - 1499411387546 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:09:47 INFO - 1499411387546 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387549 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387552 Marionette TRACE 394 <- [1,1,null,{"sessionId":"7a4c9b9b-34f6-40a3-bc40-6ee874d1116f","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387553 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"}]
00:09:47 INFO - 1499411387555 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:09:47 INFO - 1499411387558 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:09:47 INFO - 1499411387558 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:09:47 INFO - 1499411387568 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"
00:09:47 INFO - 1499411387569 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"
00:09:47 INFO - 1499411387576 Marionette TRACE 394 <- [1,2,null,{}]
00:09:47 INFO - 1499411387585 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
00:09:47 INFO - 1499411387590 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cb9e4d6d-72ed-46ac-8df6-f60df7a791d5","ELEMENT":"cb9e4d6d-72ed-46ac-8df6-f60df7a791d5"}}]
00:09:47 INFO - 1499411387591 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
00:09:47 INFO - 1499411387594 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bd6d7853-aadc-40ef-8a45-d3f8fb24a484","ELEMENT":"bd6d7853-aadc-40ef-8a45-d3f8fb24a484"}}]
00:09:47 INFO - 1499411387595 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"cb9e4d6d-72ed-46ac-8df6-f60df7a791d5"}]
00:09:47 INFO - 1499411387835 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:09:47 INFO - 1499411387836 Marionette TRACE 394 <- [1,5,null,{}]
00:09:47 INFO - 1499411387837 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"bd6d7853-aadc-40ef-8a45-d3f8fb24a484"}]
00:09:47 INFO - 1499411387856 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
00:09:47 INFO - 1499411387857 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
00:09:47 INFO - 1499411387861 Marionette TRACE 394 <- [1,7,null,{"value":false}]
00:09:47 INFO - 1499411387862 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}]
00:09:47 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499411387865 Marionette TRACE 394 <- [1,8,null,{"value":null}]
00:09:47 INFO - 1499411387866 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
00:09:47 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 368ms
00:09:47 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
00:09:47 INFO - 1499411387868 Marionette TRACE 394 <- [1,9,null,{}]
00:09:47 INFO - 1499411387870 Marionette DEBUG Closed connection 394
00:09:47 INFO - 1499411387871 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49839
00:09:47 INFO - 1499411387871 Marionette DEBUG Closed connection 395
00:09:47 INFO - 1499411387872 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49840
00:09:47 INFO - 1499411387873 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:47 INFO - 1499411387874 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387877 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387880 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8f701236-01ff-4f91-a47f-787f56725891","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387882 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
00:09:47 INFO - 1499411387882 Marionette TRACE 396 <- [1,2,null,{}]
00:09:47 INFO - 1499411387883 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:09:47 INFO - 1499411387884 Marionette TRACE 396 <- [1,3,null,{}]
00:09:47 INFO - 1499411387885 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
00:09:47 INFO - 1499411387885 Marionette TRACE 396 <- [1,4,null,{}]
00:09:47 INFO - 1499411387887 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}]
00:09:47 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499411387890 Marionette TRACE 396 <- [1,5,null,{"value":null}]
00:09:47 INFO - 1499411387891 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
00:09:47 INFO - 1499411387892 Marionette TRACE 396 <- [1,6,null,{}]
00:09:47 INFO - 1499411387894 Marionette DEBUG Closed connection 396
00:09:47 INFO - 1499411387895 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49841
00:09:47 INFO - 1499411387896 Marionette DEBUG Closed connection 397
00:09:47 INFO - 1499411387896 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49842
00:09:47 INFO - 1499411387898 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:09:47 INFO - 1499411387898 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387901 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387904 Marionette TRACE 398 <- [1,1,null,{"sessionId":"6b3f3516-b926-4907-ada6-f63364842d23","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387905 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
00:09:47 INFO - 1499411387907 Marionette TRACE 398 <- [1,2,null,{}]
00:09:47 INFO - 1499411387909 Marionette DEBUG Closed connection 398
00:09:47 INFO - 1499411387909 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49843
00:09:47 INFO - 1499411387910 Marionette DEBUG Closed connection 399
00:09:47 INFO - 1499411387911 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49844
00:09:47 INFO - 1499411387912 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:09:47 INFO - 1499411387913 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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}
00:09:47 INFO - 1499411387916 Marionette DEBUG Register listener.js for window 4294967297
00:09:47 INFO - 1499411387919 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ce796e1f-20b0-4a41-b315-5d2c7b06254f","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":2008,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmqcduu.mozrunner","moz:accessibilityChecks":false}}]
00:09:47 INFO - 1499411387920 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"}]
00:09:47 INFO - 1499411387922 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"
00:09:47 INFO - 1499411387924 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"
00:09:47 INFO - 1499411387925 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"
00:09:47 INFO - 1499411387934 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"
00:09:47 INFO - 1499411387936 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"
00:09:47 INFO - 1499411387944 Marionette TRACE 400 <- [1,2,null,{}]
00:09:47 INFO - 1499411387953 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
00:09:47 INFO - 1499411387958 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bac9993c-6d35-451c-bff3-32eaf7afb9df","ELEMENT":"bac9993c-6d35-451c-bff3-32eaf7afb9df"}}]
00:09:47 INFO - 1499411387959 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"bac9993c-6d35-451c-bff3-32eaf7afb9df"}]
00:09:47 INFO - 1499411387982 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
00:09:47 INFO - 1499411387983 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"bac9993c-6d35-451c-bff3-32eaf7afb9df"}]
00:09:48 INFO - 1499411388004 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element