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]
01:15:43 INFO - 1500970543681 Marionette TRACE 353 -> [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}]
01:15:43 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500970543684 Marionette TRACE 353 <- [1,5,null,{"value":null}]
01:15:43 INFO - 1500970543685 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
01:15:43 INFO - 1500970543686 Marionette TRACE 353 <- [1,6,null,{}]
01:15:43 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 148ms
01:15:43 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
01:15:43 INFO - 1500970543688 Marionette DEBUG Closed connection 353
01:15:43 INFO - 1500970543689 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49760
01:15:43 INFO - 1500970543690 Marionette DEBUG Closed connection 354
01:15:43 INFO - 1500970543690 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49761
01:15:43 INFO - 1500970543692 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:43 INFO - 1500970543692 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:43 INFO - 1500970543695 Marionette DEBUG Register listener.js for window 12
01:15:43 INFO - 1500970543698 Marionette TRACE 355 <- [1,1,null,{"sessionId":"a26a4225-2779-4e31-bef9-154a12f34b10","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:43 INFO - 1500970543700 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
01:15:43 INFO - 1500970543701 Marionette TRACE 355 <- [1,2,null,{}]
01:15:43 INFO - 1500970543702 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:15:43 INFO - 1500970543703 Marionette TRACE 355 <- [1,3,null,{}]
01:15:43 INFO - 1500970543704 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
01:15:43 INFO - 1500970543704 Marionette TRACE 355 <- [1,4,null,{}]
01:15:43 INFO - 1500970543706 Marionette TRACE 355 -> [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}]
01:15:43 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500970543708 Marionette TRACE 355 <- [1,5,null,{"value":null}]
01:15:43 INFO - 1500970543709 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
01:15:43 INFO - 1500970543711 Marionette TRACE 355 <- [1,6,null,{}]
01:15:43 INFO - 1500970543713 Marionette DEBUG Closed connection 355
01:15:43 INFO - 1500970543714 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49762
01:15:43 INFO - 1500970543714 Marionette DEBUG Closed connection 356
01:15:43 INFO - 1500970543715 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49763
01:15:43 INFO - 1500970543716 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:43 INFO - 1500970543716 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:43 INFO - 1500970543719 Marionette DEBUG Register listener.js for window 12
01:15:43 INFO - 1500970543722 Marionette TRACE 357 <- [1,1,null,{"sessionId":"359b9c48-5ba5-4d57-9163-9a09829e3a1c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:43 INFO - 1500970543724 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
01:15:43 INFO - 1500970543726 Marionette TRACE 357 <- [1,2,null,{}]
01:15:43 INFO - 1500970543728 Marionette DEBUG Closed connection 357
01:15:43 INFO - 1500970543729 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49764
01:15:43 INFO - 1500970543730 Marionette DEBUG Closed connection 358
01:15:43 INFO - 1500970543731 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49765
01:15:43 INFO - 1500970543732 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:15:43 INFO - 1500970543733 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:43 INFO - 1500970543735 Marionette DEBUG Register listener.js for window 12
01:15:43 INFO - 1500970543739 Marionette TRACE 359 <- [1,1,null,{"sessionId":"9097f49c-6673-4a72-b7aa-2e235ccd4ee2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:43 INFO - 1500970543741 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
01:15:43 INFO - 1500970543742 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
01:15:43 INFO - 1500970543748 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
01:15:43 INFO - 1500970543748 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
01:15:43 INFO - 1500970543772 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
01:15:43 INFO - 1500970543774 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
01:15:43 INFO - 1500970543778 Marionette TRACE 359 <- [1,2,null,{}]
01:15:43 INFO - 1500970543779 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
01:15:43 INFO - 1500970543787 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c2c44309-8767-4684-bd3d-7c7e3bf57804","ELEMENT":"c2c44309-8767-4684-bd3d-7c7e3bf57804"}}]
01:15:43 INFO - 1500970543789 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"c2c44309-8767-4684-bd3d-7c7e3bf57804"}]
01:15:43 INFO - 1500970543843 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
01:15:43 INFO - 1500970543847 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
01:15:43 INFO - 1500970543848 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
01:15:43 INFO - 1500970543872 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
01:15:43 INFO - 1500970543874 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
01:15:43 INFO - 1500970543877 Marionette TRACE 359 <- [1,4,null,{}]
01:15:43 INFO - 1500970543878 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
01:15:43 INFO - 1500970543880 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40cb71a6-768f-4937-9504-9027a0499db3","ELEMENT":"40cb71a6-768f-4937-9504-9027a0499db3"}}]
01:15:43 INFO - 1500970543888 Marionette TRACE 359 -> [0,6,"getTitle",{}]
01:15:43 INFO - 1500970543888 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
01:15:43 INFO - 1500970543890 Marionette TRACE 359 -> [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}]
01:15:43 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500970543893 Marionette TRACE 359 <- [1,7,null,{"value":null}]
01:15:43 INFO - 1500970543893 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
01:15:43 INFO - 1500970543895 Marionette TRACE 359 <- [1,8,null,{}]
01:15:43 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 209ms
01:15:43 INFO - TEST-START | test_click.py TestClick.test_container_element
01:15:43 INFO - 1500970543897 Marionette DEBUG Closed connection 359
01:15:43 INFO - 1500970543898 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49768
01:15:43 INFO - 1500970543899 Marionette DEBUG Closed connection 360
01:15:43 INFO - 1500970543900 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49769
01:15:43 INFO - 1500970543901 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:43 INFO - 1500970543902 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:43 INFO - 1500970543905 Marionette DEBUG Register listener.js for window 12
01:15:43 INFO - 1500970543908 Marionette TRACE 361 <- [1,1,null,{"sessionId":"12ad6021-3ee3-45e8-9434-909589057d08","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:43 INFO - 1500970543910 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
01:15:43 INFO - 1500970543911 Marionette TRACE 361 <- [1,2,null,{}]
01:15:43 INFO - 1500970543911 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:15:43 INFO - 1500970543912 Marionette TRACE 361 <- [1,3,null,{}]
01:15:43 INFO - 1500970543913 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
01:15:43 INFO - 1500970543913 Marionette TRACE 361 <- [1,4,null,{}]
01:15:43 INFO - 1500970543916 Marionette TRACE 361 -> [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}]
01:15:43 INFO - TEST-START: test_click.py TestClick.test_container_element1500970543918 Marionette TRACE 361 <- [1,5,null,{"value":null}]
01:15:43 INFO - 1500970543920 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
01:15:43 INFO - 1500970543921 Marionette TRACE 361 <- [1,6,null,{}]
01:15:43 INFO - 1500970543923 Marionette DEBUG Closed connection 361
01:15:43 INFO - 1500970543924 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49770
01:15:43 INFO - 1500970543925 Marionette DEBUG Closed connection 362
01:15:43 INFO - 1500970543926 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49771
01:15:43 INFO - 1500970543927 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:43 INFO - 1500970543927 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:43 INFO - 1500970543930 Marionette DEBUG Register listener.js for window 12
01:15:43 INFO - 1500970543935 Marionette TRACE 363 <- [1,1,null,{"sessionId":"9525ac2c-ff7e-445d-85ea-08be4bd5ac35","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:43 INFO - 1500970543937 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
01:15:43 INFO - 1500970543939 Marionette TRACE 363 <- [1,2,null,{}]
01:15:43 INFO - 1500970543941 Marionette DEBUG Closed connection 363
01:15:43 INFO - 1500970543942 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49772
01:15:43 INFO - 1500970543943 Marionette DEBUG Closed connection 364
01:15:43 INFO - 1500970543943 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49773
01:15:43 INFO - 1500970543944 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:15:43 INFO - 1500970543945 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:43 INFO - 1500970543948 Marionette DEBUG Register listener.js for window 12
01:15:43 INFO - 1500970543952 Marionette TRACE 365 <- [1,1,null,{"sessionId":"bff3b8cb-7233-44ff-b4aa-3cb009440b70","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:43 INFO - 1500970543955 Marionette TRACE 365 -> [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"}]
01:15:43 INFO - 1500970543956 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
01:15:43 INFO - 1500970543958 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
01:15:43 INFO - 1500970543958 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
01:15:43 INFO - 1500970543987 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"
01:15:43 INFO - 1500970543988 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"
01:15:43 INFO - 1500970543991 Marionette TRACE 365 <- [1,2,null,{}]
01:15:43 INFO - 1500970543993 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:15:43 INFO - 1500970543995 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"02174b1b-9e17-4116-ab33-6638eb4c1560","ELEMENT":"02174b1b-9e17-4116-ab33-6638eb4c1560"}}]
01:15:43 INFO - 1500970543996 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"02174b1b-9e17-4116-ab33-6638eb4c1560"}]
01:15:44 INFO - 1500970544248 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:15:44 INFO - 1500970544249 Marionette TRACE 365 <- [1,4,null,{}]
01:15:44 INFO - 1500970544251 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"02174b1b-9e17-4116-ab33-6638eb4c1560","name":"selected"}]
01:15:44 INFO - 1500970544256 Marionette TRACE 365 <- [1,5,null,{"value":true}]
01:15:44 INFO - 1500970544257 Marionette TRACE 365 -> [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}]
01:15:44 INFO - TEST-END: test_click.py TestClick.test_container_element1500970544261 Marionette TRACE 365 <- [1,6,null,{"value":null}]
01:15:44 INFO - 1500970544262 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
01:15:44 INFO - 1500970544264 Marionette TRACE 365 <- [1,7,null,{}]
01:15:44 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
01:15:44 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
01:15:44 INFO - 1500970544266 Marionette DEBUG Closed connection 365
01:15:44 INFO - 1500970544267 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49774
01:15:44 INFO - 1500970544267 Marionette DEBUG Closed connection 366
01:15:44 INFO - 1500970544268 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49775
01:15:44 INFO - 1500970544269 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:44 INFO - 1500970544270 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:44 INFO - 1500970544272 Marionette DEBUG Register listener.js for window 12
01:15:44 INFO - 1500970544281 Marionette TRACE 367 <- [1,1,null,{"sessionId":"51e6a3e0-c5f8-4902-a39b-39e4fbbab4d4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:44 INFO - 1500970544284 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
01:15:44 INFO - 1500970544284 Marionette TRACE 367 <- [1,2,null,{}]
01:15:44 INFO - 1500970544286 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:15:44 INFO - 1500970544286 Marionette TRACE 367 <- [1,3,null,{}]
01:15:44 INFO - 1500970544287 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
01:15:44 INFO - 1500970544289 Marionette TRACE 367 <- [1,4,null,{}]
01:15:44 INFO - 1500970544290 Marionette TRACE 367 -> [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}]
01:15:44 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500970544294 Marionette TRACE 367 <- [1,5,null,{"value":null}]
01:15:44 INFO - 1500970544295 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
01:15:44 INFO - 1500970544296 Marionette TRACE 367 <- [1,6,null,{}]
01:15:44 INFO - 1500970544299 Marionette DEBUG Closed connection 367
01:15:44 INFO - 1500970544299 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49776
01:15:44 INFO - 1500970544300 Marionette DEBUG Closed connection 368
01:15:44 INFO - 1500970544300 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49777
01:15:44 INFO - 1500970544302 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:44 INFO - 1500970544302 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:44 INFO - 1500970544305 Marionette DEBUG Register listener.js for window 12
01:15:44 INFO - 1500970544309 Marionette TRACE 369 <- [1,1,null,{"sessionId":"d85cb6ef-b95d-4d94-a172-faa5668d5689","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:44 INFO - 1500970544311 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
01:15:44 INFO - 1500970544312 Marionette TRACE 369 <- [1,2,null,{}]
01:15:44 INFO - 1500970544314 Marionette DEBUG Closed connection 369
01:15:44 INFO - 1500970544315 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49778
01:15:44 INFO - 1500970544315 Marionette DEBUG Closed connection 370
01:15:44 INFO - 1500970544316 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49779
01:15:44 INFO - 1500970544317 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:15:44 INFO - 1500970544318 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:44 INFO - 1500970544320 Marionette DEBUG Register listener.js for window 12
01:15:44 INFO - 1500970544323 Marionette TRACE 371 <- [1,1,null,{"sessionId":"543150ad-3e14-4586-b2e2-9dd6b5b3308d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:44 INFO - 1500970544325 Marionette TRACE 371 -> [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"}]
01:15:44 INFO - 1500970544327 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"
01:15:44 INFO - 1500970544329 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"
01:15:44 INFO - 1500970544329 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"
01:15:44 INFO - 1500970544351 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"
01:15:44 INFO - 1500970544353 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"
01:15:44 INFO - 1500970544356 Marionette TRACE 371 <- [1,2,null,{}]
01:15:44 INFO - 1500970544357 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:15:44 INFO - 1500970544360 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4364f146-3f8d-4b30-aacf-71f027c08ee5","ELEMENT":"4364f146-3f8d-4b30-aacf-71f027c08ee5"}}]
01:15:44 INFO - 1500970544361 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"4364f146-3f8d-4b30-aacf-71f027c08ee5"}]
01:15:44 INFO - 1500970544620 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:15:44 INFO - 1500970544621 Marionette TRACE 371 <- [1,4,null,{}]
01:15:44 INFO - 1500970544622 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"4364f146-3f8d-4b30-aacf-71f027c08ee5","name":"selected"}]
01:15:44 INFO - 1500970544627 Marionette TRACE 371 <- [1,5,null,{"value":true}]
01:15:44 INFO - 1500970544628 Marionette TRACE 371 -> [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}]
01:15:44 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500970544631 Marionette TRACE 371 <- [1,6,null,{"value":null}]
01:15:44 INFO - 1500970544632 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
01:15:44 INFO - 1500970544633 Marionette TRACE 371 <- [1,7,null,{}]
01:15:44 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 369ms
01:15:44 INFO - TEST-START | test_click.py TestClick.test_css_transforms
01:15:44 INFO - 1500970544636 Marionette DEBUG Closed connection 371
01:15:44 INFO - 1500970544636 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49780
01:15:44 INFO - 1500970544637 Marionette DEBUG Closed connection 372
01:15:44 INFO - 1500970544637 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49781
01:15:44 INFO - 1500970544638 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:44 INFO - 1500970544639 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:44 INFO - 1500970544642 Marionette DEBUG Register listener.js for window 12
01:15:44 INFO - 1500970544645 Marionette TRACE 373 <- [1,1,null,{"sessionId":"16b5e2ad-431e-4226-bd56-edc6aecb9c2a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:44 INFO - 1500970544647 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
01:15:44 INFO - 1500970544648 Marionette TRACE 373 <- [1,2,null,{}]
01:15:44 INFO - 1500970544649 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:15:44 INFO - 1500970544649 Marionette TRACE 373 <- [1,3,null,{}]
01:15:44 INFO - 1500970544650 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
01:15:44 INFO - 1500970544650 Marionette TRACE 373 <- [1,4,null,{}]
01:15:44 INFO - 1500970544652 Marionette TRACE 373 -> [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}]
01:15:44 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500970544655 Marionette TRACE 373 <- [1,5,null,{"value":null}]
01:15:44 INFO - 1500970544656 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
01:15:44 INFO - 1500970544658 Marionette TRACE 373 <- [1,6,null,{}]
01:15:44 INFO - 1500970544660 Marionette DEBUG Closed connection 373
01:15:44 INFO - 1500970544660 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49782
01:15:44 INFO - 1500970544661 Marionette DEBUG Closed connection 374
01:15:44 INFO - 1500970544662 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49783
01:15:44 INFO - 1500970544663 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:44 INFO - 1500970544664 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:44 INFO - 1500970544666 Marionette DEBUG Register listener.js for window 12
01:15:44 INFO - 1500970544669 Marionette TRACE 375 <- [1,1,null,{"sessionId":"b9e0c783-a5bb-481b-8120-d65370257440","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:44 INFO - 1500970544671 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
01:15:44 INFO - 1500970544673 Marionette TRACE 375 <- [1,2,null,{}]
01:15:44 INFO - 1500970544675 Marionette DEBUG Closed connection 375
01:15:44 INFO - 1500970544675 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49784
01:15:44 INFO - 1500970544676 Marionette DEBUG Closed connection 376
01:15:44 INFO - 1500970544677 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49785
01:15:44 INFO - 1500970544678 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:15:44 INFO - 1500970544678 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:44 INFO - 1500970544681 Marionette DEBUG Register listener.js for window 12
01:15:44 INFO - 1500970544684 Marionette TRACE 377 <- [1,1,null,{"sessionId":"81dfe18e-0f17-4e20-89fb-cbd8cf11da93","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:44 INFO - 1500970544686 Marionette TRACE 377 -> [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"}]
01:15:44 INFO - 1500970544687 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"
01:15:44 INFO - 1500970544689 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"
01:15:44 INFO - 1500970544690 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"
01:15:44 INFO - 1500970544710 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"
01:15:44 INFO - 1500970544713 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"
01:15:44 INFO - 1500970544716 Marionette TRACE 377 <- [1,2,null,{}]
01:15:44 INFO - 1500970544723 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
01:15:44 INFO - 1500970544726 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bc5849e0-cd4f-4333-a1a3-09bf033c121b","ELEMENT":"bc5849e0-cd4f-4333-a1a3-09bf033c121b"}}]
01:15:44 INFO - 1500970544726 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"bc5849e0-cd4f-4333-a1a3-09bf033c121b"}]
01:15:44 INFO - 1500970544975 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:15:44 INFO - 1500970544976 Marionette TRACE 377 <- [1,4,null,{}]
01:15:44 INFO - 1500970544978 Marionette TRACE 377 -> [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}]
01:15:44 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500970544981 Marionette TRACE 377 <- [1,5,null,{"value":null}]
01:15:44 INFO - 1500970544982 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
01:15:44 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 350ms
01:15:44 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
01:15:44 INFO - 1500970544983 Marionette TRACE 377 <- [1,6,null,{}]
01:15:45 INFO - 1500970545000 Marionette DEBUG Closed connection 377
01:15:45 INFO - 1500970545000 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49786
01:15:45 INFO - 1500970545001 Marionette DEBUG Closed connection 378
01:15:45 INFO - 1500970545002 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49787
01:15:45 INFO - 1500970545003 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:45 INFO - 1500970545004 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545006 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545009 Marionette TRACE 379 <- [1,1,null,{"sessionId":"c13d0702-ce2e-4a5e-a287-80fd37592bfa","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545012 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
01:15:45 INFO - 1500970545012 Marionette TRACE 379 <- [1,2,null,{}]
01:15:45 INFO - 1500970545013 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:15:45 INFO - 1500970545014 Marionette TRACE 379 <- [1,3,null,{}]
01:15:45 INFO - 1500970545015 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
01:15:45 INFO - 1500970545015 Marionette TRACE 379 <- [1,4,null,{}]
01:15:45 INFO - 1500970545017 Marionette TRACE 379 -> [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}]
01:15:45 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500970545019 Marionette TRACE 379 <- [1,5,null,{"value":null}]
01:15:45 INFO - 1500970545020 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
01:15:45 INFO - 1500970545021 Marionette TRACE 379 <- [1,6,null,{}]
01:15:45 INFO - 1500970545024 Marionette DEBUG Closed connection 379
01:15:45 INFO - 1500970545024 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49788
01:15:45 INFO - 1500970545025 Marionette DEBUG Closed connection 380
01:15:45 INFO - 1500970545026 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49789
01:15:45 INFO - 1500970545027 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:45 INFO - 1500970545027 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545030 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545033 Marionette TRACE 381 <- [1,1,null,{"sessionId":"aa417bbe-8a48-4a3a-9417-20b6839e718b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545035 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
01:15:45 INFO - 1500970545037 Marionette TRACE 381 <- [1,2,null,{}]
01:15:45 INFO - 1500970545039 Marionette DEBUG Closed connection 381
01:15:45 INFO - 1500970545039 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49790
01:15:45 INFO - 1500970545040 Marionette DEBUG Closed connection 382
01:15:45 INFO - 1500970545041 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49791
01:15:45 INFO - 1500970545042 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:15:45 INFO - 1500970545042 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545045 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545048 Marionette TRACE 383 <- [1,1,null,{"sessionId":"c7e01448-724b-4c5e-9112-6674a82a3cc9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545050 Marionette TRACE 383 -> [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"}]
01:15:45 INFO - 1500970545051 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"
01:15:45 INFO - 1500970545053 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"
01:15:45 INFO - 1500970545054 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"
01:15:45 INFO - 1500970545073 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"
01:15:45 INFO - 1500970545075 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"
01:15:45 INFO - 1500970545079 Marionette TRACE 383 <- [1,2,null,{}]
01:15:45 INFO - 1500970545080 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
01:15:45 INFO - 1500970545088 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"52d861c7-d66d-4ce8-9691-cae2f3e00d6f","ELEMENT":"52d861c7-d66d-4ce8-9691-cae2f3e00d6f"}}]
01:15:45 INFO - 1500970545089 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"52d861c7-d66d-4ce8-9691-cae2f3e00d6f"}]
01:15:45 INFO - 1500970545334 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:15:45 INFO - 1500970545335 Marionette TRACE 383 <- [1,4,null,{}]
01:15:45 INFO - 1500970545337 Marionette TRACE 383 -> [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}]
01:15:45 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500970545340 Marionette TRACE 383 <- [1,5,null,{"value":null}]
01:15:45 INFO - 1500970545341 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
01:15:45 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 359ms
01:15:45 INFO - TEST-START | test_click.py TestClick.test_input_file
01:15:45 INFO - 1500970545342 Marionette TRACE 383 <- [1,6,null,{}]
01:15:45 INFO - 1500970545345 Marionette DEBUG Closed connection 383
01:15:45 INFO - 1500970545345 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49792
01:15:45 INFO - 1500970545346 Marionette DEBUG Closed connection 384
01:15:45 INFO - 1500970545347 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49793
01:15:45 INFO - 1500970545348 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:45 INFO - 1500970545348 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545351 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545354 Marionette TRACE 385 <- [1,1,null,{"sessionId":"47878334-7cbf-428a-81cb-343a5bb10599","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545356 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
01:15:45 INFO - 1500970545357 Marionette TRACE 385 <- [1,2,null,{}]
01:15:45 INFO - 1500970545358 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:15:45 INFO - 1500970545358 Marionette TRACE 385 <- [1,3,null,{}]
01:15:45 INFO - 1500970545359 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
01:15:45 INFO - 1500970545360 Marionette TRACE 385 <- [1,4,null,{}]
01:15:45 INFO - 1500970545361 Marionette TRACE 385 -> [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}]
01:15:45 INFO - TEST-START: test_click.py TestClick.test_input_file1500970545364 Marionette TRACE 385 <- [1,5,null,{"value":null}]
01:15:45 INFO - 1500970545365 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
01:15:45 INFO - 1500970545366 Marionette TRACE 385 <- [1,6,null,{}]
01:15:45 INFO - 1500970545368 Marionette DEBUG Closed connection 385
01:15:45 INFO - 1500970545369 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49794
01:15:45 INFO - 1500970545370 Marionette DEBUG Closed connection 386
01:15:45 INFO - 1500970545370 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49795
01:15:45 INFO - 1500970545371 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:45 INFO - 1500970545372 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545375 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545384 Marionette TRACE 387 <- [1,1,null,{"sessionId":"406e727c-da46-48ab-bbd5-9053f2360bbe","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545387 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
01:15:45 INFO - 1500970545388 Marionette TRACE 387 <- [1,2,null,{}]
01:15:45 INFO - 1500970545390 Marionette DEBUG Closed connection 387
01:15:45 INFO - 1500970545391 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49796
01:15:45 INFO - 1500970545392 Marionette DEBUG Closed connection 388
01:15:45 INFO - 1500970545392 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49797
01:15:45 INFO - 1500970545393 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:15:45 INFO - 1500970545394 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545397 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545401 Marionette TRACE 389 <- [1,1,null,{"sessionId":"2f53751a-df8d-4f39-80da-ee021fae3873","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545403 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
01:15:45 INFO - 1500970545405 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"
01:15:45 INFO - 1500970545407 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"
01:15:45 INFO - 1500970545407 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"
01:15:45 INFO - 1500970545427 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:15:45 INFO - 1500970545430 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:15:45 INFO - 1500970545433 Marionette TRACE 389 <- [1,2,null,{}]
01:15:45 INFO - 1500970545451 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
01:15:45 INFO - 1500970545454 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43b9fe4c-299c-44cc-9dc0-d3ae54e21d1f","ELEMENT":"43b9fe4c-299c-44cc-9dc0-d3ae54e21d1f"}}]
01:15:45 INFO - 1500970545456 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"43b9fe4c-299c-44cc-9dc0-d3ae54e21d1f"}]
01:15:45 INFO - 1500970545493 Marionette TRACE 389 <- [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]
01:15:45 INFO - 1500970545495 Marionette TRACE 389 -> [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}]
01:15:45 INFO - TEST-END: test_click.py TestClick.test_input_file1500970545500 Marionette TRACE 389 <- [1,5,null,{"value":null}]
01:15:45 INFO - 1500970545501 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
01:15:45 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 161ms
01:15:45 INFO - TEST-START | test_click.py TestClick.test_obscured_element
01:15:45 INFO - 1500970545504 Marionette TRACE 389 <- [1,6,null,{}]
01:15:45 INFO - 1500970545507 Marionette DEBUG Closed connection 389
01:15:45 INFO - 1500970545507 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49798
01:15:45 INFO - 1500970545509 Marionette DEBUG Closed connection 390
01:15:45 INFO - 1500970545510 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49799
01:15:45 INFO - 1500970545512 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:45 INFO - 1500970545514 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545517 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545523 Marionette TRACE 391 <- [1,1,null,{"sessionId":"8c489975-dc25-48cb-b964-dcfd1a8a3cf5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545527 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
01:15:45 INFO - 1500970545528 Marionette TRACE 391 <- [1,2,null,{}]
01:15:45 INFO - 1500970545529 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:15:45 INFO - 1500970545530 Marionette TRACE 391 <- [1,3,null,{}]
01:15:45 INFO - 1500970545532 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
01:15:45 INFO - 1500970545532 Marionette TRACE 391 <- [1,4,null,{}]
01:15:45 INFO - 1500970545534 Marionette TRACE 391 -> [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}]
01:15:45 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500970545539 Marionette TRACE 391 <- [1,5,null,{"value":null}]
01:15:45 INFO - 1500970545540 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
01:15:45 INFO - 1500970545542 Marionette TRACE 391 <- [1,6,null,{}]
01:15:45 INFO - 1500970545545 Marionette DEBUG Closed connection 391
01:15:45 INFO - 1500970545546 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49800
01:15:45 INFO - 1500970545547 Marionette DEBUG Closed connection 392
01:15:45 INFO - 1500970545548 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49801
01:15:45 INFO - 1500970545551 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:45 INFO - 1500970545552 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545555 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545571 Marionette TRACE 393 <- [1,1,null,{"sessionId":"6f0ef37f-1023-4c9a-99ea-fda1b16fd947","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545574 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
01:15:45 INFO - 1500970545577 Marionette TRACE 393 <- [1,2,null,{}]
01:15:45 INFO - 1500970545579 Marionette DEBUG Closed connection 393
01:15:45 INFO - 1500970545580 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49802
01:15:45 INFO - 1500970545581 Marionette DEBUG Closed connection 394
01:15:45 INFO - 1500970545582 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49803
01:15:45 INFO - 1500970545583 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:15:45 INFO - 1500970545584 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:45 INFO - 1500970545587 Marionette DEBUG Register listener.js for window 12
01:15:45 INFO - 1500970545591 Marionette TRACE 395 <- [1,1,null,{"sessionId":"f059dc5b-1d1d-4b21-9c63-0e2242d427eb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:45 INFO - 1500970545639 Marionette TRACE 395 -> [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"}]
01:15:45 INFO - 1500970545642 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:15:45 INFO - 1500970545645 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:15:45 INFO - 1500970545645 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:15:45 INFO - 1500970545671 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"
01:15:45 INFO - 1500970545674 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"
01:15:45 INFO - 1500970545679 Marionette TRACE 395 <- [1,2,null,{}]
01:15:45 INFO - 1500970545680 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
01:15:45 INFO - 1500970545689 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"080c2783-c378-4b8d-b20e-eea216050e62","ELEMENT":"080c2783-c378-4b8d-b20e-eea216050e62"}}]
01:15:45 INFO - 1500970545697 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
01:15:45 INFO - 1500970545701 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"488d8df5-0791-4ff9-995b-82601aa72129","ELEMENT":"488d8df5-0791-4ff9-995b-82601aa72129"}}]
01:15:45 INFO - 1500970545702 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"080c2783-c378-4b8d-b20e-eea216050e62"}]
01:15:45 INFO - 1500970545950 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:15:45 INFO - 1500970545951 Marionette TRACE 395 <- [1,5,null,{}]
01:15:45 INFO - 1500970545960 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"488d8df5-0791-4ff9-995b-82601aa72129"}]
01:15:45 INFO - 1500970545982 Marionette TRACE 395 <- [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]
01:15:45 INFO - 1500970545984 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
01:15:45 INFO - 1500970545988 Marionette TRACE 395 <- [1,7,null,{"value":false}]
01:15:45 INFO - 1500970545990 Marionette TRACE 395 -> [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}]
01:15:45 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500970545994 Marionette TRACE 395 <- [1,8,null,{"value":null}]
01:15:45 INFO - 1500970545995 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
01:15:46 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 493ms
01:15:46 INFO - 1500970545998 Marionette TRACE 395 <- [1,9,null,{}]
01:15:46 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
01:15:46 INFO - 1500970546002 Marionette DEBUG Closed connection 395
01:15:46 INFO - 1500970546003 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49804
01:15:46 INFO - 1500970546005 Marionette DEBUG Closed connection 396
01:15:46 INFO - 1500970546006 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49805
01:15:46 INFO - 1500970546008 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:46 INFO - 1500970546010 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:46 INFO - 1500970546014 Marionette DEBUG Register listener.js for window 12
01:15:46 INFO - 1500970546020 Marionette TRACE 397 <- [1,1,null,{"sessionId":"a58527b8-8544-4b8c-81a8-8cadff068290","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:46 INFO - 1500970546024 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
01:15:46 INFO - 1500970546025 Marionette TRACE 397 <- [1,2,null,{}]
01:15:46 INFO - 1500970546025 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:15:46 INFO - 1500970546026 Marionette TRACE 397 <- [1,3,null,{}]
01:15:46 INFO - 1500970546027 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
01:15:46 INFO - 1500970546028 Marionette TRACE 397 <- [1,4,null,{}]
01:15:46 INFO - 1500970546030 Marionette TRACE 397 -> [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}]
01:15:46 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500970546034 Marionette TRACE 397 <- [1,5,null,{"value":null}]
01:15:46 INFO - 1500970546035 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
01:15:46 INFO - 1500970546037 Marionette TRACE 397 <- [1,6,null,{}]
01:15:46 INFO - 1500970546041 Marionette DEBUG Closed connection 397
01:15:46 INFO - 1500970546042 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49806
01:15:46 INFO - 1500970546043 Marionette DEBUG Closed connection 398
01:15:46 INFO - 1500970546044 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49807
01:15:46 INFO - 1500970546046 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:15:46 INFO - 1500970546048 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:46 INFO - 1500970546051 Marionette DEBUG Register listener.js for window 12
01:15:46 INFO - 1500970546056 Marionette TRACE 399 <- [1,1,null,{"sessionId":"d12dac41-199e-4292-ac0e-ed8031dc17cb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:46 INFO - 1500970546059 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
01:15:46 INFO - 1500970546061 Marionette TRACE 399 <- [1,2,null,{}]
01:15:46 INFO - 1500970546063 Marionette DEBUG Closed connection 399
01:15:46 INFO - 1500970546064 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49808
01:15:46 INFO - 1500970546066 Marionette DEBUG Closed connection 400
01:15:46 INFO - 1500970546067 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49809
01:15:46 INFO - 1500970546068 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:15:46 INFO - 1500970546069 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}
01:15:46 INFO - 1500970546072 Marionette DEBUG Register listener.js for window 12
01:15:46 INFO - 1500970546076 Marionette TRACE 401 <- [1,1,null,{"sessionId":"cd1d581f-823e-4c33-81f5-5cedd3913791","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3116,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-240.001\\AppData\\Local\\Temp\\tmpqqwlut.mozrunner","moz:accessibilityChecks":false}}]
01:15:46 INFO - 1500970546079 Marionette TRACE 401 -> [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"}]
01:15:46 INFO - 1500970546082 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"
01:15:46 INFO - 1500970546085 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"
01:15:46 INFO - 1500970546086 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"
01:15:46 INFO - 1500970546110 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"
01:15:46 INFO - 1500970546112 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"
01:15:46 INFO - 1500970546116 Marionette TRACE 401 <- [1,2,null,{}]
01:15:46 INFO - 1500970546122 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
01:15:46 INFO - 1500970546125 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b81badd8-e44f-4020-b83b-55f518b02a6a","ELEMENT":"b81badd8-e44f-4020-b83b-55f518b02a6a"}}]
01:15:46 INFO - 1500970546126 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b81badd8-e44f-4020-b83b-55f518b02a6a"}]
01:15:46 INFO - 1500970546153 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
01:15:46 INFO - 1500970546154 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"b81badd8-e44f-4020-b83b-55f518b02a6a"}]
01:15:46 INFO - 1500970546176 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element