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]
06:19:27 INFO - 1499174367753 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}]
06:19:27 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499174367756 Marionette TRACE 353 <- [1,5,null,{"value":null}]
06:19:27 INFO - 1499174367757 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
06:19:27 INFO - 1499174367759 Marionette TRACE 353 <- [1,6,null,{}]
06:19:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 116ms
06:19:27 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:19:27 INFO - 1499174367761 Marionette DEBUG Closed connection 353
06:19:27 INFO - 1499174367761 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50011
06:19:27 INFO - 1499174367763 Marionette DEBUG Closed connection 354
06:19:27 INFO - 1499174367763 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50012
06:19:27 INFO - 1499174367764 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:27 INFO - 1499174367765 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:27 INFO - 1499174367767 Marionette DEBUG Register listener.js for window 4294967297
06:19:27 INFO - 1499174367769 Marionette TRACE 355 <- [1,1,null,{"sessionId":"40914be4-8ae5-45f1-a7ec-7fb1679d3f79","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:27 INFO - 1499174367770 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
06:19:27 INFO - 1499174367771 Marionette TRACE 355 <- [1,2,null,{}]
06:19:27 INFO - 1499174367772 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:19:27 INFO - 1499174367772 Marionette TRACE 355 <- [1,3,null,{}]
06:19:27 INFO - 1499174367773 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
06:19:27 INFO - 1499174367773 Marionette TRACE 355 <- [1,4,null,{}]
06:19:27 INFO - 1499174367775 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}]
06:19:27 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499174367778 Marionette TRACE 355 <- [1,5,null,{"value":null}]
06:19:27 INFO - 1499174367779 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
06:19:27 INFO - 1499174367780 Marionette TRACE 355 <- [1,6,null,{}]
06:19:27 INFO - 1499174367782 Marionette DEBUG Closed connection 355
06:19:27 INFO - 1499174367782 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50013
06:19:27 INFO - 1499174367783 Marionette DEBUG Closed connection 356
06:19:27 INFO - 1499174367784 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50014
06:19:27 INFO - 1499174367785 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:27 INFO - 1499174367785 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:27 INFO - 1499174367787 Marionette DEBUG Register listener.js for window 4294967297
06:19:27 INFO - 1499174367790 Marionette TRACE 357 <- [1,1,null,{"sessionId":"885be39c-1dbb-4e9c-90a3-a1885ee60756","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:27 INFO - 1499174367791 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
06:19:27 INFO - 1499174367792 Marionette TRACE 357 <- [1,2,null,{}]
06:19:27 INFO - 1499174367793 Marionette DEBUG Closed connection 357
06:19:27 INFO - 1499174367794 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50015
06:19:27 INFO - 1499174367795 Marionette DEBUG Closed connection 358
06:19:27 INFO - 1499174367795 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50016
06:19:27 INFO - 1499174367796 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:19:27 INFO - 1499174367797 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:27 INFO - 1499174367799 Marionette DEBUG Register listener.js for window 4294967297
06:19:27 INFO - 1499174367801 Marionette TRACE 359 <- [1,1,null,{"sessionId":"f509b532-0978-4ae1-8ccb-20210da17b13","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:27 INFO - 1499174367802 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49572/clicks.html"}]
06:19:27 INFO - 1499174367804 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49572/hidden.html"
06:19:27 INFO - 1499174367811 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49572/hidden.html"
06:19:27 INFO - 1499174367812 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49572/hidden.html"
06:19:27 INFO - 1499174367818 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49572/clicks.html"
06:19:27 INFO - 1499174367823 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49572/clicks.html"
06:19:27 INFO - 1499174367824 Marionette TRACE 359 <- [1,2,null,{}]
06:19:27 INFO - 1499174367827 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:19:27 INFO - 1499174367836 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3427d968-565d-477f-a156-001171285c67","ELEMENT":"3427d968-565d-477f-a156-001171285c67"}}]
06:19:27 INFO - 1499174367837 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"3427d968-565d-477f-a156-001171285c67"}]
06:19:27 INFO - 1499174367874 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49572/clicks.html"
06:19:27 INFO - 1499174367883 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49572/clicks.html"
06:19:27 INFO - 1499174367884 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49572/clicks.html"
06:19:27 INFO - 1499174367890 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49572/test.html"
06:19:27 INFO - 1499174367893 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49572/test.html"
06:19:27 INFO - 1499174367903 Marionette TRACE 359 <- [1,4,null,{}]
06:19:27 INFO - 1499174367910 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:19:27 INFO - 1499174367915 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"54c5bf39-0e68-4626-b2f7-d2116d1f4b3b","ELEMENT":"54c5bf39-0e68-4626-b2f7-d2116d1f4b3b"}}]
06:19:27 INFO - 1499174367917 Marionette TRACE 359 -> [0,6,"getTitle",{}]
06:19:27 INFO - 1499174367917 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
06:19:27 INFO - 1499174367919 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}]
06:19:27 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499174367922 Marionette TRACE 359 <- [1,7,null,{"value":null}]
06:19:27 INFO - 1499174367923 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
06:19:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 165ms
06:19:27 INFO - 1499174367924 Marionette TRACE 359 <- [1,8,null,{}]
06:19:27 INFO - TEST-START | test_click.py TestClick.test_container_element
06:19:27 INFO - 1499174367925 Marionette DEBUG Closed connection 359
06:19:27 INFO - 1499174367927 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50019
06:19:27 INFO - 1499174367927 Marionette DEBUG Closed connection 360
06:19:27 INFO - 1499174367928 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50020
06:19:27 INFO - 1499174367929 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:27 INFO - 1499174367930 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:27 INFO - 1499174367932 Marionette DEBUG Register listener.js for window 4294967297
06:19:27 INFO - 1499174367935 Marionette TRACE 361 <- [1,1,null,{"sessionId":"db248a6c-4560-4a2c-bc77-e8fb7f5fd649","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:27 INFO - 1499174367936 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
06:19:27 INFO - 1499174367936 Marionette TRACE 361 <- [1,2,null,{}]
06:19:27 INFO - 1499174367937 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:19:27 INFO - 1499174367938 Marionette TRACE 361 <- [1,3,null,{}]
06:19:27 INFO - 1499174367939 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
06:19:27 INFO - 1499174367939 Marionette TRACE 361 <- [1,4,null,{}]
06:19:27 INFO - 1499174367941 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}]
06:19:27 INFO - TEST-START: test_click.py TestClick.test_container_element1499174367943 Marionette TRACE 361 <- [1,5,null,{"value":null}]
06:19:27 INFO - 1499174367944 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
06:19:27 INFO - 1499174367945 Marionette TRACE 361 <- [1,6,null,{}]
06:19:27 INFO - 1499174367946 Marionette DEBUG Closed connection 361
06:19:27 INFO - 1499174367947 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50021
06:19:27 INFO - 1499174367948 Marionette DEBUG Closed connection 362
06:19:27 INFO - 1499174367948 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50022
06:19:27 INFO - 1499174367949 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:27 INFO - 1499174367950 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:27 INFO - 1499174367952 Marionette DEBUG Register listener.js for window 4294967297
06:19:27 INFO - 1499174367954 Marionette TRACE 363 <- [1,1,null,{"sessionId":"d09da0bb-71cb-4c9f-be18-40aae918a5ce","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:27 INFO - 1499174367955 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
06:19:27 INFO - 1499174367957 Marionette TRACE 363 <- [1,2,null,{}]
06:19:27 INFO - 1499174367958 Marionette DEBUG Closed connection 363
06:19:27 INFO - 1499174367958 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50023
06:19:27 INFO - 1499174367959 Marionette DEBUG Closed connection 364
06:19:27 INFO - 1499174367960 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50024
06:19:27 INFO - 1499174367961 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:19:27 INFO - 1499174367961 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:27 INFO - 1499174367963 Marionette DEBUG Register listener.js for window 4294967297
06:19:27 INFO - 1499174367966 Marionette TRACE 365 <- [1,1,null,{"sessionId":"0851fa8c-92b8-4d16-85de-0cc0d340e2b8","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:27 INFO - 1499174367967 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"}]
06:19:27 INFO - 1499174367969 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49572/test.html"
06:19:27 INFO - 1499174367971 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49572/test.html"
06:19:27 INFO - 1499174367971 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49572/test.html"
06:19:27 INFO - 1499174367976 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:27 INFO - 1499174367980 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174367989 Marionette TRACE 365 <- [1,2,null,{}]
06:19:28 INFO - 1499174367993 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:19:28 INFO - 1499174367999 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88e9a110-1dae-4a00-a481-747c47f5a89f","ELEMENT":"88e9a110-1dae-4a00-a481-747c47f5a89f"}}]
06:19:28 INFO - 1499174368001 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"88e9a110-1dae-4a00-a481-747c47f5a89f"}]
06:19:28 INFO - 1499174368239 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:19:28 INFO - 1499174368240 Marionette TRACE 365 <- [1,4,null,{}]
06:19:28 INFO - 1499174368241 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"88e9a110-1dae-4a00-a481-747c47f5a89f","name":"selected"}]
06:19:28 INFO - 1499174368245 Marionette TRACE 365 <- [1,5,null,{"value":true}]
06:19:28 INFO - 1499174368247 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}]
06:19:28 INFO - TEST-END: test_click.py TestClick.test_container_element1499174368249 Marionette TRACE 365 <- [1,6,null,{"value":null}]
06:19:28 INFO - 1499174368250 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
06:19:28 INFO - 1499174368251 Marionette TRACE 365 <- [1,7,null,{}]
06:19:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 327ms
06:19:28 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:19:28 INFO - 1499174368253 Marionette DEBUG Closed connection 365
06:19:28 INFO - 1499174368254 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50025
06:19:28 INFO - 1499174368255 Marionette DEBUG Closed connection 366
06:19:28 INFO - 1499174368255 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50026
06:19:28 INFO - 1499174368257 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:28 INFO - 1499174368257 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368259 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368262 Marionette TRACE 367 <- [1,1,null,{"sessionId":"60f6f1ea-9c7e-4df3-a2b4-bc77adf5fc7e","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368264 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
06:19:28 INFO - 1499174368265 Marionette TRACE 367 <- [1,2,null,{}]
06:19:28 INFO - 1499174368265 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:19:28 INFO - 1499174368266 Marionette TRACE 367 <- [1,3,null,{}]
06:19:28 INFO - 1499174368267 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
06:19:28 INFO - 1499174368267 Marionette TRACE 367 <- [1,4,null,{}]
06:19:28 INFO - 1499174368269 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}]
06:19:28 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499174368271 Marionette TRACE 367 <- [1,5,null,{"value":null}]
06:19:28 INFO - 1499174368272 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
06:19:28 INFO - 1499174368273 Marionette TRACE 367 <- [1,6,null,{}]
06:19:28 INFO - 1499174368275 Marionette DEBUG Closed connection 367
06:19:28 INFO - 1499174368275 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50027
06:19:28 INFO - 1499174368276 Marionette DEBUG Closed connection 368
06:19:28 INFO - 1499174368277 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50028
06:19:28 INFO - 1499174368278 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:28 INFO - 1499174368278 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368280 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368283 Marionette TRACE 369 <- [1,1,null,{"sessionId":"6da2c1de-20e5-432c-9960-1d9c3153e467","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368284 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
06:19:28 INFO - 1499174368285 Marionette TRACE 369 <- [1,2,null,{}]
06:19:28 INFO - 1499174368286 Marionette DEBUG Closed connection 369
06:19:28 INFO - 1499174368287 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50029
06:19:28 INFO - 1499174368288 Marionette DEBUG Closed connection 370
06:19:28 INFO - 1499174368288 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50030
06:19:28 INFO - 1499174368289 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:19:28 INFO - 1499174368290 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368297 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368300 Marionette TRACE 371 <- [1,1,null,{"sessionId":"bf47b088-6bb9-4c3f-a4c4-09e1767f1017","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368301 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"}]
06:19:28 INFO - 1499174368303 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368305 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368305 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368311 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368314 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368320 Marionette TRACE 371 <- [1,2,null,{}]
06:19:28 INFO - 1499174368323 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:19:28 INFO - 1499174368327 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6488ccbc-02c7-499d-8cc2-3728070adfb9","ELEMENT":"6488ccbc-02c7-499d-8cc2-3728070adfb9"}}]
06:19:28 INFO - 1499174368328 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"6488ccbc-02c7-499d-8cc2-3728070adfb9"}]
06:19:28 INFO - 1499174368568 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:19:28 INFO - 1499174368569 Marionette TRACE 371 <- [1,4,null,{}]
06:19:28 INFO - 1499174368570 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"6488ccbc-02c7-499d-8cc2-3728070adfb9","name":"selected"}]
06:19:28 INFO - 1499174368574 Marionette TRACE 371 <- [1,5,null,{"value":true}]
06:19:28 INFO - 1499174368575 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}]
06:19:28 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499174368578 Marionette TRACE 371 <- [1,6,null,{"value":null}]
06:19:28 INFO - 1499174368579 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
06:19:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 329ms
06:19:28 INFO - 1499174368580 Marionette TRACE 371 <- [1,7,null,{}]
06:19:28 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:19:28 INFO - 1499174368581 Marionette DEBUG Closed connection 371
06:19:28 INFO - 1499174368583 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50031
06:19:28 INFO - 1499174368584 Marionette DEBUG Closed connection 372
06:19:28 INFO - 1499174368585 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50032
06:19:28 INFO - 1499174368586 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:28 INFO - 1499174368586 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368588 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368591 Marionette TRACE 373 <- [1,1,null,{"sessionId":"744898d2-8b6b-49d3-af83-1668db93d6d8","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368592 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
06:19:28 INFO - 1499174368593 Marionette TRACE 373 <- [1,2,null,{}]
06:19:28 INFO - 1499174368593 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:19:28 INFO - 1499174368594 Marionette TRACE 373 <- [1,3,null,{}]
06:19:28 INFO - 1499174368595 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
06:19:28 INFO - 1499174368595 Marionette TRACE 373 <- [1,4,null,{}]
06:19:28 INFO - 1499174368597 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}]
06:19:28 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499174368599 Marionette TRACE 373 <- [1,5,null,{"value":null}]
06:19:28 INFO - 1499174368600 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
06:19:28 INFO - 1499174368601 Marionette TRACE 373 <- [1,6,null,{}]
06:19:28 INFO - 1499174368602 Marionette DEBUG Closed connection 373
06:19:28 INFO - 1499174368603 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50033
06:19:28 INFO - 1499174368604 Marionette DEBUG Closed connection 374
06:19:28 INFO - 1499174368605 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50034
06:19:28 INFO - 1499174368606 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:28 INFO - 1499174368606 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368608 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368610 Marionette TRACE 375 <- [1,1,null,{"sessionId":"d0b4fd54-29d3-4f7b-87ed-5a8f04062a23","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368611 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
06:19:28 INFO - 1499174368613 Marionette TRACE 375 <- [1,2,null,{}]
06:19:28 INFO - 1499174368614 Marionette DEBUG Closed connection 375
06:19:28 INFO - 1499174368615 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50035
06:19:28 INFO - 1499174368615 Marionette DEBUG Closed connection 376
06:19:28 INFO - 1499174368616 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50036
06:19:28 INFO - 1499174368617 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:19:28 INFO - 1499174368618 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368620 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368622 Marionette TRACE 377 <- [1,1,null,{"sessionId":"d35c8605-0e21-4ca7-93f3-8227b53d2e07","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368623 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"}]
06:19:28 INFO - 1499174368625 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368627 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368627 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368633 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:19:28 INFO - 1499174368635 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:19:28 INFO - 1499174368639 Marionette TRACE 377 <- [1,2,null,{}]
06:19:28 INFO - 1499174368642 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:19:28 INFO - 1499174368650 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0f6a495e-642c-4710-b97e-64c3a1ea3488","ELEMENT":"0f6a495e-642c-4710-b97e-64c3a1ea3488"}}]
06:19:28 INFO - 1499174368652 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"0f6a495e-642c-4710-b97e-64c3a1ea3488"}]
06:19:28 INFO - 1499174368891 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:19:28 INFO - 1499174368892 Marionette TRACE 377 <- [1,4,null,{}]
06:19:28 INFO - 1499174368894 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}]
06:19:28 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499174368896 Marionette TRACE 377 <- [1,5,null,{"value":null}]
06:19:28 INFO - 1499174368897 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
06:19:28 INFO - 1499174368898 Marionette TRACE 377 <- [1,6,null,{}]
06:19:28 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 318ms
06:19:28 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:19:28 INFO - 1499174368900 Marionette DEBUG Closed connection 377
06:19:28 INFO - 1499174368901 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50037
06:19:28 INFO - 1499174368902 Marionette DEBUG Closed connection 378
06:19:28 INFO - 1499174368902 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50038
06:19:28 INFO - 1499174368903 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:28 INFO - 1499174368904 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368906 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368909 Marionette TRACE 379 <- [1,1,null,{"sessionId":"808e3513-c099-4497-ab4b-647c401e702d","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368910 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
06:19:28 INFO - 1499174368910 Marionette TRACE 379 <- [1,2,null,{}]
06:19:28 INFO - 1499174368911 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:19:28 INFO - 1499174368911 Marionette TRACE 379 <- [1,3,null,{}]
06:19:28 INFO - 1499174368912 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
06:19:28 INFO - 1499174368913 Marionette TRACE 379 <- [1,4,null,{}]
06:19:28 INFO - 1499174368914 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}]
06:19:28 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499174368917 Marionette TRACE 379 <- [1,5,null,{"value":null}]
06:19:28 INFO - 1499174368918 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
06:19:28 INFO - 1499174368919 Marionette TRACE 379 <- [1,6,null,{}]
06:19:28 INFO - 1499174368920 Marionette DEBUG Closed connection 379
06:19:28 INFO - 1499174368921 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50039
06:19:28 INFO - 1499174368921 Marionette DEBUG Closed connection 380
06:19:28 INFO - 1499174368922 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50040
06:19:28 INFO - 1499174368923 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:28 INFO - 1499174368923 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368925 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368928 Marionette TRACE 381 <- [1,1,null,{"sessionId":"2937bfc2-03b9-4b97-8b6f-ee4201eecfe7","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368929 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
06:19:28 INFO - 1499174368930 Marionette TRACE 381 <- [1,2,null,{}]
06:19:28 INFO - 1499174368931 Marionette DEBUG Closed connection 381
06:19:28 INFO - 1499174368932 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50041
06:19:28 INFO - 1499174368933 Marionette DEBUG Closed connection 382
06:19:28 INFO - 1499174368933 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50042
06:19:28 INFO - 1499174368934 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:19:28 INFO - 1499174368935 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:28 INFO - 1499174368937 Marionette DEBUG Register listener.js for window 4294967297
06:19:28 INFO - 1499174368940 Marionette TRACE 383 <- [1,1,null,{"sessionId":"7dac7ffe-8238-4571-ad9e-c48c8b8d0699","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:28 INFO - 1499174368941 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"}]
06:19:28 INFO - 1499174368942 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:19:28 INFO - 1499174368945 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:19:28 INFO - 1499174368945 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:19:28 INFO - 1499174368953 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368958 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:28 INFO - 1499174368959 Marionette TRACE 383 <- [1,2,null,{}]
06:19:28 INFO - 1499174368961 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:19:28 INFO - 1499174368968 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cb54b561-d0e4-446b-8f80-e44f3a06588f","ELEMENT":"cb54b561-d0e4-446b-8f80-e44f3a06588f"}}]
06:19:28 INFO - 1499174368969 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"cb54b561-d0e4-446b-8f80-e44f3a06588f"}]
06:19:29 INFO - 1499174369208 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:19:29 INFO - 1499174369209 Marionette TRACE 383 <- [1,4,null,{}]
06:19:29 INFO - 1499174369211 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}]
06:19:29 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499174369213 Marionette TRACE 383 <- [1,5,null,{"value":null}]
06:19:29 INFO - 1499174369215 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
06:19:29 INFO - 1499174369217 Marionette TRACE 383 <- [1,6,null,{}]
06:19:29 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 318ms
06:19:29 INFO - TEST-START | test_click.py TestClick.test_input_file
06:19:29 INFO - 1499174369218 Marionette DEBUG Closed connection 383
06:19:29 INFO - 1499174369219 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50043
06:19:29 INFO - 1499174369220 Marionette DEBUG Closed connection 384
06:19:29 INFO - 1499174369220 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50044
06:19:29 INFO - 1499174369221 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:29 INFO - 1499174369222 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369224 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369227 Marionette TRACE 385 <- [1,1,null,{"sessionId":"42161561-ac88-4931-995f-bdcb4174e239","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369228 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
06:19:29 INFO - 1499174369228 Marionette TRACE 385 <- [1,2,null,{}]
06:19:29 INFO - 1499174369229 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:19:29 INFO - 1499174369230 Marionette TRACE 385 <- [1,3,null,{}]
06:19:29 INFO - 1499174369231 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
06:19:29 INFO - 1499174369231 Marionette TRACE 385 <- [1,4,null,{}]
06:19:29 INFO - 1499174369233 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}]
06:19:29 INFO - TEST-START: test_click.py TestClick.test_input_file1499174369235 Marionette TRACE 385 <- [1,5,null,{"value":null}]
06:19:29 INFO - 1499174369236 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
06:19:29 INFO - 1499174369237 Marionette TRACE 385 <- [1,6,null,{}]
06:19:29 INFO - 1499174369238 Marionette DEBUG Closed connection 385
06:19:29 INFO - 1499174369239 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50045
06:19:29 INFO - 1499174369240 Marionette DEBUG Closed connection 386
06:19:29 INFO - 1499174369240 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50046
06:19:29 INFO - 1499174369242 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:29 INFO - 1499174369242 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369244 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369247 Marionette TRACE 387 <- [1,1,null,{"sessionId":"5d8f1e30-be9e-45ee-82dd-654f2a3a8ade","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369248 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
06:19:29 INFO - 1499174369249 Marionette TRACE 387 <- [1,2,null,{}]
06:19:29 INFO - 1499174369250 Marionette DEBUG Closed connection 387
06:19:29 INFO - 1499174369251 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50047
06:19:29 INFO - 1499174369251 Marionette DEBUG Closed connection 388
06:19:29 INFO - 1499174369252 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50048
06:19:29 INFO - 1499174369253 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:19:29 INFO - 1499174369254 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369256 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369266 Marionette TRACE 389 <- [1,1,null,{"sessionId":"5ac518fa-8f1f-451e-9c81-a6bfde8f13f9","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369267 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:19:29 INFO - 1499174369269 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:29 INFO - 1499174369270 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:29 INFO - 1499174369271 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:19:29 INFO - 1499174369277 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:19:29 INFO - 1499174369281 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:19:29 INFO - 1499174369290 Marionette TRACE 389 <- [1,2,null,{}]
06:19:29 INFO - 1499174369293 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:19:29 INFO - 1499174369298 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4c10a213-4e57-45f9-bff5-61827783b54c","ELEMENT":"4c10a213-4e57-45f9-bff5-61827783b54c"}}]
06:19:29 INFO - 1499174369300 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"4c10a213-4e57-45f9-bff5-61827783b54c"}]
06:19:29 INFO - 1499174369331 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
06:19:29 INFO - 1499174369333 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}]
06:19:29 INFO - TEST-END: test_click.py TestClick.test_input_file1499174369335 Marionette TRACE 389 <- [1,5,null,{"value":null}]
06:19:29 INFO - 1499174369336 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
06:19:29 INFO - 1499174369337 Marionette TRACE 389 <- [1,6,null,{}]
06:19:29 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 121ms
06:19:29 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:19:29 INFO - 1499174369339 Marionette DEBUG Closed connection 389
06:19:29 INFO - 1499174369340 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50049
06:19:29 INFO - 1499174369341 Marionette DEBUG Closed connection 390
06:19:29 INFO - 1499174369342 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50050
06:19:29 INFO - 1499174369343 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:29 INFO - 1499174369343 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369346 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369348 Marionette TRACE 391 <- [1,1,null,{"sessionId":"1ac92709-2414-4b4e-8479-90093b4f19e2","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369349 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
06:19:29 INFO - 1499174369350 Marionette TRACE 391 <- [1,2,null,{}]
06:19:29 INFO - 1499174369351 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:19:29 INFO - 1499174369351 Marionette TRACE 391 <- [1,3,null,{}]
06:19:29 INFO - 1499174369352 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
06:19:29 INFO - 1499174369352 Marionette TRACE 391 <- [1,4,null,{}]
06:19:29 INFO - 1499174369354 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}]
06:19:29 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499174369356 Marionette TRACE 391 <- [1,5,null,{"value":null}]
06:19:29 INFO - 1499174369358 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
06:19:29 INFO - 1499174369359 Marionette TRACE 391 <- [1,6,null,{}]
06:19:29 INFO - 1499174369360 Marionette DEBUG Closed connection 391
06:19:29 INFO - 1499174369361 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50051
06:19:29 INFO - 1499174369361 Marionette DEBUG Closed connection 392
06:19:29 INFO - 1499174369362 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50052
06:19:29 INFO - 1499174369363 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:29 INFO - 1499174369364 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369366 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369368 Marionette TRACE 393 <- [1,1,null,{"sessionId":"f997a464-9039-4728-a402-e3053433f50f","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369369 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
06:19:29 INFO - 1499174369370 Marionette TRACE 393 <- [1,2,null,{}]
06:19:29 INFO - 1499174369372 Marionette DEBUG Closed connection 393
06:19:29 INFO - 1499174369373 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50053
06:19:29 INFO - 1499174369373 Marionette DEBUG Closed connection 394
06:19:29 INFO - 1499174369374 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50054
06:19:29 INFO - 1499174369375 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:19:29 INFO - 1499174369375 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369377 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369380 Marionette TRACE 395 <- [1,1,null,{"sessionId":"49acd049-79c5-45fe-95fa-15113d376d85","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369381 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"}]
06:19:29 INFO - 1499174369382 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:19:29 INFO - 1499174369384 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:19:29 INFO - 1499174369385 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:19:29 INFO - 1499174369391 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:19:29 INFO - 1499174369393 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:19:29 INFO - 1499174369394 Marionette TRACE 395 <- [1,2,null,{}]
06:19:29 INFO - 1499174369402 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:19:29 INFO - 1499174369405 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"453cf098-e185-44fa-9687-9383871613f4","ELEMENT":"453cf098-e185-44fa-9687-9383871613f4"}}]
06:19:29 INFO - 1499174369406 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:19:29 INFO - 1499174369408 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a0ae98dd-f641-409d-b846-094b968a517c","ELEMENT":"a0ae98dd-f641-409d-b846-094b968a517c"}}]
06:19:29 INFO - 1499174369409 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"453cf098-e185-44fa-9687-9383871613f4"}]
06:19:29 INFO - 1499174369643 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:19:29 INFO - 1499174369644 Marionette TRACE 395 <- [1,5,null,{}]
06:19:29 INFO - 1499174369645 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"a0ae98dd-f641-409d-b846-094b968a517c"}]
06:19:29 INFO - 1499174369658 Marionette TRACE 395 <- [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]
06:19:29 INFO - 1499174369660 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:19:29 INFO - 1499174369663 Marionette TRACE 395 <- [1,7,null,{"value":false}]
06:19:29 INFO - 1499174369664 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}]
06:19:29 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499174369667 Marionette TRACE 395 <- [1,8,null,{"value":null}]
06:19:29 INFO - 1499174369668 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
06:19:29 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 330ms
06:19:29 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:19:29 INFO - 1499174369669 Marionette TRACE 395 <- [1,9,null,{}]
06:19:29 INFO - 1499174369670 Marionette DEBUG Closed connection 395
06:19:29 INFO - 1499174369671 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50055
06:19:29 INFO - 1499174369672 Marionette DEBUG Closed connection 396
06:19:29 INFO - 1499174369673 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50056
06:19:29 INFO - 1499174369674 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:29 INFO - 1499174369674 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369676 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369679 Marionette TRACE 397 <- [1,1,null,{"sessionId":"28cc2e4e-2270-4cba-8f27-af7bfe5dfed4","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369680 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
06:19:29 INFO - 1499174369681 Marionette TRACE 397 <- [1,2,null,{}]
06:19:29 INFO - 1499174369681 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:19:29 INFO - 1499174369682 Marionette TRACE 397 <- [1,3,null,{}]
06:19:29 INFO - 1499174369683 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
06:19:29 INFO - 1499174369683 Marionette TRACE 397 <- [1,4,null,{}]
06:19:29 INFO - 1499174369685 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}]
06:19:29 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499174369687 Marionette TRACE 397 <- [1,5,null,{"value":null}]
06:19:29 INFO - 1499174369688 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
06:19:29 INFO - 1499174369689 Marionette TRACE 397 <- [1,6,null,{}]
06:19:29 INFO - 1499174369690 Marionette DEBUG Closed connection 397
06:19:29 INFO - 1499174369691 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50057
06:19:29 INFO - 1499174369691 Marionette DEBUG Closed connection 398
06:19:29 INFO - 1499174369692 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50058
06:19:29 INFO - 1499174369693 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:19:29 INFO - 1499174369694 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369696 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369699 Marionette TRACE 399 <- [1,1,null,{"sessionId":"917f7398-782f-40c1-b899-d71fde51afd0","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369700 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
06:19:29 INFO - 1499174369701 Marionette TRACE 399 <- [1,2,null,{}]
06:19:29 INFO - 1499174369702 Marionette DEBUG Closed connection 399
06:19:29 INFO - 1499174369703 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50059
06:19:29 INFO - 1499174369703 Marionette DEBUG Closed connection 400
06:19:29 INFO - 1499174369704 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50060
06:19:29 INFO - 1499174369705 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:19:29 INFO - 1499174369706 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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}
06:19:29 INFO - 1499174369708 Marionette DEBUG Register listener.js for window 4294967297
06:19:29 INFO - 1499174369710 Marionette TRACE 401 <- [1,1,null,{"sessionId":"d20b924c-9576-4e21-ae6f-2c7a676e8f29","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":5504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_16hmy.mozrunner","moz:accessibilityChecks":false}}]
06:19:29 INFO - 1499174369711 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"}]
06:19:29 INFO - 1499174369713 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:19:29 INFO - 1499174369715 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:19:29 INFO - 1499174369715 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:19:29 INFO - 1499174369721 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
06:19:29 INFO - 1499174369723 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
06:19:29 INFO - 1499174369726 Marionette TRACE 401 <- [1,2,null,{}]
06:19:29 INFO - 1499174369735 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:19:29 INFO - 1499174369739 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"104e72d4-9ac7-432b-8e44-789fdc264da0","ELEMENT":"104e72d4-9ac7-432b-8e44-789fdc264da0"}}]
06:19:29 INFO - 1499174369739 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"104e72d4-9ac7-432b-8e44-789fdc264da0"}]
06:19:29 INFO - 1499174369760 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
06:19:29 INFO - 1499174369761 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"104e72d4-9ac7-432b-8e44-789fdc264da0"}]
06:19:29 INFO - 1499174369775 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element