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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:54:39 INFO - 1500342879812 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
18:54:39 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500342879815 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:54:39 INFO - 1500342879816 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:54:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 157ms
18:54:39 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:54:39 INFO - 1500342879818 Marionette TRACE 352 <- [1,6,null,{}]
18:54:39 INFO - 1500342879822 Marionette DEBUG Closed connection 352
18:54:39 INFO - 1500342879823 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49917
18:54:39 INFO - 1500342879824 Marionette DEBUG Closed connection 353
18:54:39 INFO - 1500342879825 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49918
18:54:39 INFO - 1500342879826 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:39 INFO - 1500342879827 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:39 INFO - 1500342879830 Marionette DEBUG Register listener.js for window 15
18:54:39 INFO - 1500342879833 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ad394deb-5249-4fea-9f1e-4b1f4aa8428b","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:39 INFO - 1500342879835 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:54:39 INFO - 1500342879836 Marionette TRACE 354 <- [1,2,null,{}]
18:54:39 INFO - 1500342879837 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:54:39 INFO - 1500342879837 Marionette TRACE 354 <- [1,3,null,{}]
18:54:39 INFO - 1500342879838 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:54:39 INFO - 1500342879839 Marionette TRACE 354 <- [1,4,null,{}]
18:54:39 INFO - 1500342879841 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
18:54:39 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500342879844 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:54:39 INFO - 1500342879845 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:54:39 INFO - 1500342879846 Marionette TRACE 354 <- [1,6,null,{}]
18:54:39 INFO - 1500342879849 Marionette DEBUG Closed connection 354
18:54:39 INFO - 1500342879849 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49919
18:54:39 INFO - 1500342879850 Marionette DEBUG Closed connection 355
18:54:39 INFO - 1500342879851 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49920
18:54:39 INFO - 1500342879852 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:39 INFO - 1500342879853 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:39 INFO - 1500342879855 Marionette DEBUG Register listener.js for window 15
18:54:39 INFO - 1500342879866 Marionette TRACE 356 <- [1,1,null,{"sessionId":"a8c253d4-921c-4ef2-953c-fa9cc5ea89bc","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:39 INFO - 1500342879870 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:54:39 INFO - 1500342879871 Marionette TRACE 356 <- [1,2,null,{}]
18:54:39 INFO - 1500342879874 Marionette DEBUG Closed connection 356
18:54:39 INFO - 1500342879874 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49921
18:54:39 INFO - 1500342879876 Marionette DEBUG Closed connection 357
18:54:39 INFO - 1500342879877 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49922
18:54:39 INFO - 1500342879878 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:54:39 INFO - 1500342879879 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:39 INFO - 1500342879881 Marionette DEBUG Register listener.js for window 15
18:54:39 INFO - 1500342879885 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b69676e9-977a-4b13-9397-1df10c1cadf0","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:39 INFO - 1500342879888 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49475/clicks.html"}]
18:54:39 INFO - 1500342879889 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49475/hidden.html"
18:54:39 INFO - 1500342879896 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49475/hidden.html"
18:54:39 INFO - 1500342879896 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49475/hidden.html"
18:54:39 INFO - 1500342879924 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49475/clicks.html"
18:54:39 INFO - 1500342879926 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49475/clicks.html"
18:54:39 INFO - 1500342879930 Marionette TRACE 358 <- [1,2,null,{}]
18:54:39 INFO - 1500342879939 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:54:39 INFO - 1500342879941 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d941333a-62ba-4752-a875-7674dd771298","ELEMENT":"d941333a-62ba-4752-a875-7674dd771298"}}]
18:54:39 INFO - 1500342879942 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"d941333a-62ba-4752-a875-7674dd771298"}]
18:54:39 INFO - 1500342879989 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49475/clicks.html"
18:54:40 INFO - 1500342879997 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49475/clicks.html"
18:54:40 INFO - 1500342879998 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49475/clicks.html"
18:54:40 INFO - 1500342880029 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49475/test.html"
18:54:40 INFO - 1500342880039 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49475/test.html"
18:54:40 INFO - 1500342880043 Marionette TRACE 358 <- [1,4,null,{}]
18:54:40 INFO - 1500342880045 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:54:40 INFO - 1500342880055 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f49a3141-0c1c-4442-9923-d04cb4db3d10","ELEMENT":"f49a3141-0c1c-4442-9923-d04cb4db3d10"}}]
18:54:40 INFO - 1500342880058 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:54:40 INFO - 1500342880059 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:54:40 INFO - 1500342880060 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
18:54:40 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500342880064 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:54:40 INFO - 1500342880065 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:54:40 INFO - 1500342880067 Marionette TRACE 358 <- [1,8,null,{}]
18:54:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 250ms
18:54:40 INFO - TEST-START | test_click.py TestClick.test_container_element
18:54:40 INFO - 1500342880070 Marionette DEBUG Closed connection 358
18:54:40 INFO - 1500342880071 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49925
18:54:40 INFO - 1500342880073 Marionette DEBUG Closed connection 359
18:54:40 INFO - 1500342880073 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49926
18:54:40 INFO - 1500342880075 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:40 INFO - 1500342880076 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880079 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880086 Marionette TRACE 360 <- [1,1,null,{"sessionId":"dc7d2024-609a-4fa4-96bf-9784cd135506","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880088 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:54:40 INFO - 1500342880088 Marionette TRACE 360 <- [1,2,null,{}]
18:54:40 INFO - 1500342880090 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:54:40 INFO - 1500342880091 Marionette TRACE 360 <- [1,3,null,{}]
18:54:40 INFO - 1500342880092 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:54:40 INFO - 1500342880093 Marionette TRACE 360 <- [1,4,null,{}]
18:54:40 INFO - 1500342880094 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
18:54:40 INFO - TEST-START: test_click.py TestClick.test_container_element1500342880099 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:54:40 INFO - 1500342880100 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:54:40 INFO - 1500342880101 Marionette TRACE 360 <- [1,6,null,{}]
18:54:40 INFO - 1500342880104 Marionette DEBUG Closed connection 360
18:54:40 INFO - 1500342880104 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49927
18:54:40 INFO - 1500342880105 Marionette DEBUG Closed connection 361
18:54:40 INFO - 1500342880106 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49928
18:54:40 INFO - 1500342880107 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:40 INFO - 1500342880108 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880110 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880115 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f4f7a55a-abb4-4ab0-9d06-9ceedb572ea1","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880118 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:54:40 INFO - 1500342880120 Marionette TRACE 362 <- [1,2,null,{}]
18:54:40 INFO - 1500342880122 Marionette DEBUG Closed connection 362
18:54:40 INFO - 1500342880123 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49929
18:54:40 INFO - 1500342880125 Marionette DEBUG Closed connection 363
18:54:40 INFO - 1500342880125 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49930
18:54:40 INFO - 1500342880127 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:54:40 INFO - 1500342880127 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880130 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880134 Marionette TRACE 364 <- [1,1,null,{"sessionId":"928fe123-7d6f-4e79-859a-0c2e1287dc0d","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880142 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:54:40 INFO - 1500342880143 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49475/test.html"
18:54:40 INFO - 1500342880146 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49475/test.html"
18:54:40 INFO - 1500342880146 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49475/test.html"
18:54:40 INFO - 1500342880172 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"
18:54:40 INFO - 1500342880177 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"
18:54:40 INFO - 1500342880181 Marionette TRACE 364 <- [1,2,null,{}]
18:54:40 INFO - 1500342880194 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:54:40 INFO - 1500342880198 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a0977863-a62e-4676-bde8-136fcaddb772","ELEMENT":"a0977863-a62e-4676-bde8-136fcaddb772"}}]
18:54:40 INFO - 1500342880199 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"a0977863-a62e-4676-bde8-136fcaddb772"}]
18:54:40 INFO - 1500342880444 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:54:40 INFO - 1500342880445 Marionette TRACE 364 <- [1,4,null,{}]
18:54:40 INFO - 1500342880446 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"a0977863-a62e-4676-bde8-136fcaddb772","name":"selected"}]
18:54:40 INFO - 1500342880451 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:54:40 INFO - 1500342880453 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
18:54:40 INFO - TEST-END: test_click.py TestClick.test_container_element1500342880456 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:54:40 INFO - 1500342880457 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:54:40 INFO - 1500342880459 Marionette TRACE 364 <- [1,7,null,{}]
18:54:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 391ms
18:54:40 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:54:40 INFO - 1500342880474 Marionette DEBUG Closed connection 364
18:54:40 INFO - 1500342880475 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49931
18:54:40 INFO - 1500342880490 Marionette DEBUG Closed connection 365
18:54:40 INFO - 1500342880491 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49932
18:54:40 INFO - 1500342880499 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:40 INFO - 1500342880500 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880503 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880506 Marionette TRACE 366 <- [1,1,null,{"sessionId":"79367fd4-b709-400f-8e54-e9ceda5139fc","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880530 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:54:40 INFO - 1500342880531 Marionette TRACE 366 <- [1,2,null,{}]
18:54:40 INFO - 1500342880543 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:54:40 INFO - 1500342880544 Marionette TRACE 366 <- [1,3,null,{}]
18:54:40 INFO - 1500342880545 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:54:40 INFO - 1500342880546 Marionette TRACE 366 <- [1,4,null,{}]
18:54:40 INFO - 1500342880548 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
18:54:40 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500342880551 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:54:40 INFO - 1500342880552 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:54:40 INFO - 1500342880554 Marionette TRACE 366 <- [1,6,null,{}]
18:54:40 INFO - 1500342880556 Marionette DEBUG Closed connection 366
18:54:40 INFO - 1500342880557 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49933
18:54:40 INFO - 1500342880558 Marionette DEBUG Closed connection 367
18:54:40 INFO - 1500342880559 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49934
18:54:40 INFO - 1500342880560 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:40 INFO - 1500342880560 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880563 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880574 Marionette TRACE 368 <- [1,1,null,{"sessionId":"59cf1406-e1b2-48cd-90c4-aa1aebb9a56f","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880576 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:54:40 INFO - 1500342880578 Marionette TRACE 368 <- [1,2,null,{}]
18:54:40 INFO - 1500342880580 Marionette DEBUG Closed connection 368
18:54:40 INFO - 1500342880581 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49935
18:54:40 INFO - 1500342880582 Marionette DEBUG Closed connection 369
18:54:40 INFO - 1500342880583 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49936
18:54:40 INFO - 1500342880584 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:54:40 INFO - 1500342880585 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880588 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880591 Marionette TRACE 370 <- [1,1,null,{"sessionId":"36ce7c96-eef3-452c-a03d-0f4a25f59700","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880593 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:54:40 INFO - 1500342880595 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"
18:54:40 INFO - 1500342880599 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"
18:54:40 INFO - 1500342880599 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"
18:54:40 INFO - 1500342880613 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"
18:54:40 INFO - 1500342880623 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"
18:54:40 INFO - 1500342880627 Marionette TRACE 370 <- [1,2,null,{}]
18:54:40 INFO - 1500342880629 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:54:40 INFO - 1500342880638 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e00a0e3-0c00-4116-8b6c-5587b38db7d7","ELEMENT":"9e00a0e3-0c00-4116-8b6c-5587b38db7d7"}}]
18:54:40 INFO - 1500342880639 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9e00a0e3-0c00-4116-8b6c-5587b38db7d7"}]
18:54:40 INFO - 1500342880886 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:54:40 INFO - 1500342880887 Marionette TRACE 370 <- [1,4,null,{}]
18:54:40 INFO - 1500342880888 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9e00a0e3-0c00-4116-8b6c-5587b38db7d7","name":"selected"}]
18:54:40 INFO - 1500342880893 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:54:40 INFO - 1500342880895 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
18:54:40 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500342880898 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:54:40 INFO - 1500342880899 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:54:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 442ms
18:54:40 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:54:40 INFO - 1500342880901 Marionette TRACE 370 <- [1,7,null,{}]
18:54:40 INFO - 1500342880903 Marionette DEBUG Closed connection 370
18:54:40 INFO - 1500342880904 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49937
18:54:40 INFO - 1500342880905 Marionette DEBUG Closed connection 371
18:54:40 INFO - 1500342880906 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49938
18:54:40 INFO - 1500342880907 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:40 INFO - 1500342880907 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880910 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880914 Marionette TRACE 372 <- [1,1,null,{"sessionId":"978250f4-e327-4ea1-9cec-bf16a163c996","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880916 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:54:40 INFO - 1500342880916 Marionette TRACE 372 <- [1,2,null,{}]
18:54:40 INFO - 1500342880917 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:54:40 INFO - 1500342880918 Marionette TRACE 372 <- [1,3,null,{}]
18:54:40 INFO - 1500342880919 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:54:40 INFO - 1500342880919 Marionette TRACE 372 <- [1,4,null,{}]
18:54:40 INFO - 1500342880921 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
18:54:40 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500342880924 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:54:40 INFO - 1500342880925 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:54:40 INFO - 1500342880927 Marionette TRACE 372 <- [1,6,null,{}]
18:54:40 INFO - 1500342880929 Marionette DEBUG Closed connection 372
18:54:40 INFO - 1500342880930 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49939
18:54:40 INFO - 1500342880931 Marionette DEBUG Closed connection 373
18:54:40 INFO - 1500342880931 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49940
18:54:40 INFO - 1500342880933 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:40 INFO - 1500342880933 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880936 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880939 Marionette TRACE 374 <- [1,1,null,{"sessionId":"99a837a6-a3bb-4010-b2fc-0f49056b91e9","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880941 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:54:40 INFO - 1500342880943 Marionette TRACE 374 <- [1,2,null,{}]
18:54:40 INFO - 1500342880945 Marionette DEBUG Closed connection 374
18:54:40 INFO - 1500342880946 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49941
18:54:40 INFO - 1500342880947 Marionette DEBUG Closed connection 375
18:54:40 INFO - 1500342880947 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49942
18:54:40 INFO - 1500342880948 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:54:40 INFO - 1500342880949 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:40 INFO - 1500342880952 Marionette DEBUG Register listener.js for window 15
18:54:40 INFO - 1500342880955 Marionette TRACE 376 <- [1,1,null,{"sessionId":"32ddce36-6b58-46ac-8402-e65f4ff2b0f7","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:40 INFO - 1500342880957 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
18:54:40 INFO - 1500342880959 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"
18:54:40 INFO - 1500342880962 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"
18:54:40 INFO - 1500342880962 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"
18:54:40 INFO - 1500342880985 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"
18:54:40 INFO - 1500342880988 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"
18:54:40 INFO - 1500342880991 Marionette TRACE 376 <- [1,2,null,{}]
18:54:40 INFO - 1500342880993 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:54:40 INFO - 1500342880995 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8aeed42b-d619-406e-b4c8-5c6fc2b067f2","ELEMENT":"8aeed42b-d619-406e-b4c8-5c6fc2b067f2"}}]
18:54:41 INFO - 1500342881004 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8aeed42b-d619-406e-b4c8-5c6fc2b067f2"}]
18:54:41 INFO - 1500342881247 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:54:41 INFO - 1500342881248 Marionette TRACE 376 <- [1,4,null,{}]
18:54:41 INFO - 1500342881250 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
18:54:41 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500342881253 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:54:41 INFO - 1500342881254 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:54:41 INFO - 1500342881256 Marionette TRACE 376 <- [1,6,null,{}]
18:54:41 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 355ms
18:54:41 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:54:41 INFO - 1500342881258 Marionette DEBUG Closed connection 376
18:54:41 INFO - 1500342881259 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49943
18:54:41 INFO - 1500342881260 Marionette DEBUG Closed connection 377
18:54:41 INFO - 1500342881261 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49944
18:54:41 INFO - 1500342881262 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:41 INFO - 1500342881262 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881265 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881269 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c40b8456-5920-4b3f-abcf-97f38e31c6a2","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881271 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:54:41 INFO - 1500342881272 Marionette TRACE 378 <- [1,2,null,{}]
18:54:41 INFO - 1500342881273 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:54:41 INFO - 1500342881273 Marionette TRACE 378 <- [1,3,null,{}]
18:54:41 INFO - 1500342881274 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:54:41 INFO - 1500342881275 Marionette TRACE 378 <- [1,4,null,{}]
18:54:41 INFO - 1500342881276 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
18:54:41 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500342881279 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:54:41 INFO - 1500342881280 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:54:41 INFO - 1500342881282 Marionette TRACE 378 <- [1,6,null,{}]
18:54:41 INFO - 1500342881284 Marionette DEBUG Closed connection 378
18:54:41 INFO - 1500342881285 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49945
18:54:41 INFO - 1500342881286 Marionette DEBUG Closed connection 379
18:54:41 INFO - 1500342881287 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49946
18:54:41 INFO - 1500342881288 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:41 INFO - 1500342881288 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881291 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881294 Marionette TRACE 380 <- [1,1,null,{"sessionId":"11dabfde-604a-49a5-9298-ea35936cefe6","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881297 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:54:41 INFO - 1500342881298 Marionette TRACE 380 <- [1,2,null,{}]
18:54:41 INFO - 1500342881300 Marionette DEBUG Closed connection 380
18:54:41 INFO - 1500342881301 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49947
18:54:41 INFO - 1500342881302 Marionette DEBUG Closed connection 381
18:54:41 INFO - 1500342881303 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49948
18:54:41 INFO - 1500342881304 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:54:41 INFO - 1500342881304 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881314 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881319 Marionette TRACE 382 <- [1,1,null,{"sessionId":"f2234560-41fe-4b2b-adc2-741125e122c6","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881321 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:54:41 INFO - 1500342881323 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"
18:54:41 INFO - 1500342881326 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"
18:54:41 INFO - 1500342881326 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"
18:54:41 INFO - 1500342881346 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"
18:54:41 INFO - 1500342881352 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"
18:54:41 INFO - 1500342881356 Marionette TRACE 382 <- [1,2,null,{}]
18:54:41 INFO - 1500342881357 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:54:41 INFO - 1500342881360 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ca7c29a5-b50b-4e33-8e02-cc7c7418b9e9","ELEMENT":"ca7c29a5-b50b-4e33-8e02-cc7c7418b9e9"}}]
18:54:41 INFO - 1500342881361 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ca7c29a5-b50b-4e33-8e02-cc7c7418b9e9"}]
18:54:41 INFO - 1500342881613 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:54:41 INFO - 1500342881614 Marionette TRACE 382 <- [1,4,null,{}]
18:54:41 INFO - 1500342881615 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
18:54:41 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500342881619 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:54:41 INFO - 1500342881620 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:54:41 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 365ms
18:54:41 INFO - 1500342881621 Marionette TRACE 382 <- [1,6,null,{}]
18:54:41 INFO - TEST-START | test_click.py TestClick.test_input_file
18:54:41 INFO - 1500342881623 Marionette DEBUG Closed connection 382
18:54:41 INFO - 1500342881625 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49949
18:54:41 INFO - 1500342881625 Marionette DEBUG Closed connection 383
18:54:41 INFO - 1500342881626 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49950
18:54:41 INFO - 1500342881627 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:41 INFO - 1500342881628 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881631 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881634 Marionette TRACE 384 <- [1,1,null,{"sessionId":"79700b84-4cfd-4058-b8a9-2d5b8bfc40f5","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881636 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:54:41 INFO - 1500342881637 Marionette TRACE 384 <- [1,2,null,{}]
18:54:41 INFO - 1500342881638 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:54:41 INFO - 1500342881638 Marionette TRACE 384 <- [1,3,null,{}]
18:54:41 INFO - 1500342881639 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:54:41 INFO - 1500342881640 Marionette TRACE 384 <- [1,4,null,{}]
18:54:41 INFO - 1500342881642 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
18:54:41 INFO - TEST-START: test_click.py TestClick.test_input_file1500342881645 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:54:41 INFO - 1500342881646 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:54:41 INFO - 1500342881647 Marionette TRACE 384 <- [1,6,null,{}]
18:54:41 INFO - 1500342881649 Marionette DEBUG Closed connection 384
18:54:41 INFO - 1500342881650 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49951
18:54:41 INFO - 1500342881651 Marionette DEBUG Closed connection 385
18:54:41 INFO - 1500342881652 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49952
18:54:41 INFO - 1500342881653 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:41 INFO - 1500342881653 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881656 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881659 Marionette TRACE 386 <- [1,1,null,{"sessionId":"13b84cd3-3e34-40e0-af15-efe814460011","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881661 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:54:41 INFO - 1500342881663 Marionette TRACE 386 <- [1,2,null,{}]
18:54:41 INFO - 1500342881665 Marionette DEBUG Closed connection 386
18:54:41 INFO - 1500342881666 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49953
18:54:41 INFO - 1500342881667 Marionette DEBUG Closed connection 387
18:54:41 INFO - 1500342881667 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49954
18:54:41 INFO - 1500342881668 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:54:41 INFO - 1500342881669 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881672 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881675 Marionette TRACE 388 <- [1,1,null,{"sessionId":"d0bc368a-92cd-4c8f-b0a3-8f4f1f0d4594","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881677 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:54:41 INFO - 1500342881679 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"
18:54:41 INFO - 1500342881681 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"
18:54:41 INFO - 1500342881682 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"
18:54:41 INFO - 1500342881695 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:54:41 INFO - 1500342881709 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:54:41 INFO - 1500342881713 Marionette TRACE 388 <- [1,2,null,{}]
18:54:41 INFO - 1500342881722 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:54:41 INFO - 1500342881725 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93bd9c49-4e95-4755-9202-d7c9b77d6227","ELEMENT":"93bd9c49-4e95-4755-9202-d7c9b77d6227"}}]
18:54:41 INFO - 1500342881726 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"93bd9c49-4e95-4755-9202-d7c9b77d6227"}]
18:54:41 INFO - 1500342881755 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:54:41 INFO - 1500342881757 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
18:54:41 INFO - TEST-END: test_click.py TestClick.test_input_file1500342881760 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:54:41 INFO - 1500342881761 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:54:41 INFO - 1500342881763 Marionette TRACE 388 <- [1,6,null,{}]
18:54:41 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 141ms
18:54:41 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:54:41 INFO - 1500342881765 Marionette DEBUG Closed connection 388
18:54:41 INFO - 1500342881766 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49955
18:54:41 INFO - 1500342881767 Marionette DEBUG Closed connection 389
18:54:41 INFO - 1500342881767 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49956
18:54:41 INFO - 1500342881769 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:41 INFO - 1500342881769 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881772 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881776 Marionette TRACE 390 <- [1,1,null,{"sessionId":"cbc1493d-5144-4324-b4ed-2dad7d24d25c","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881778 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:54:41 INFO - 1500342881778 Marionette TRACE 390 <- [1,2,null,{}]
18:54:41 INFO - 1500342881779 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:54:41 INFO - 1500342881780 Marionette TRACE 390 <- [1,3,null,{}]
18:54:41 INFO - 1500342881781 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:54:41 INFO - 1500342881781 Marionette TRACE 390 <- [1,4,null,{}]
18:54:41 INFO - 1500342881783 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
18:54:41 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500342881786 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:54:41 INFO - 1500342881787 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:54:41 INFO - 1500342881789 Marionette TRACE 390 <- [1,6,null,{}]
18:54:41 INFO - 1500342881791 Marionette DEBUG Closed connection 390
18:54:41 INFO - 1500342881792 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49957
18:54:41 INFO - 1500342881793 Marionette DEBUG Closed connection 391
18:54:41 INFO - 1500342881793 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49958
18:54:41 INFO - 1500342881795 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:41 INFO - 1500342881795 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881798 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881801 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ba79ac9d-b90b-418f-a307-cbad6af956dc","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881804 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:54:41 INFO - 1500342881805 Marionette TRACE 392 <- [1,2,null,{}]
18:54:41 INFO - 1500342881808 Marionette DEBUG Closed connection 392
18:54:41 INFO - 1500342881809 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49959
18:54:41 INFO - 1500342881809 Marionette DEBUG Closed connection 393
18:54:41 INFO - 1500342881810 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49960
18:54:41 INFO - 1500342881811 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:54:41 INFO - 1500342881812 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:41 INFO - 1500342881814 Marionette DEBUG Register listener.js for window 15
18:54:41 INFO - 1500342881824 Marionette TRACE 394 <- [1,1,null,{"sessionId":"0dcd35d3-bc09-44f0-b9eb-c10b3a2eadf2","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:41 INFO - 1500342881827 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
18:54:41 INFO - 1500342881829 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:54:41 INFO - 1500342881831 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:54:41 INFO - 1500342881832 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:54:41 INFO - 1500342881845 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"
18:54:41 INFO - 1500342881850 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"
18:54:41 INFO - 1500342881862 Marionette TRACE 394 <- [1,2,null,{}]
18:54:41 INFO - 1500342881866 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:54:41 INFO - 1500342881869 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"91aae415-9d1c-4205-91d3-2687f7291e74","ELEMENT":"91aae415-9d1c-4205-91d3-2687f7291e74"}}]
18:54:41 INFO - 1500342881871 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:54:41 INFO - 1500342881873 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6fb4e4f5-3ba0-4169-a512-9bcff547e74f","ELEMENT":"6fb4e4f5-3ba0-4169-a512-9bcff547e74f"}}]
18:54:41 INFO - 1500342881874 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"91aae415-9d1c-4205-91d3-2687f7291e74"}]
18:54:42 INFO - 1500342882114 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:54:42 INFO - 1500342882115 Marionette TRACE 394 <- [1,5,null,{}]
18:54:42 INFO - 1500342882116 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"6fb4e4f5-3ba0-4169-a512-9bcff547e74f"}]
18:54:42 INFO - 1500342882134 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:54:42 INFO - 1500342882136 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:54:42 INFO - 1500342882139 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:54:42 INFO - 1500342882140 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
18:54:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500342882143 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:54:42 INFO - 1500342882145 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:54:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 383ms
18:54:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:54:42 INFO - 1500342882146 Marionette TRACE 394 <- [1,9,null,{}]
18:54:42 INFO - 1500342882149 Marionette DEBUG Closed connection 394
18:54:42 INFO - 1500342882149 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49961
18:54:42 INFO - 1500342882150 Marionette DEBUG Closed connection 395
18:54:42 INFO - 1500342882151 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49962
18:54:42 INFO - 1500342882152 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:42 INFO - 1500342882153 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:42 INFO - 1500342882156 Marionette DEBUG Register listener.js for window 15
18:54:42 INFO - 1500342882159 Marionette TRACE 396 <- [1,1,null,{"sessionId":"3550a2d8-3186-49e8-9710-2614183ba32c","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:42 INFO - 1500342882162 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:54:42 INFO - 1500342882162 Marionette TRACE 396 <- [1,2,null,{}]
18:54:42 INFO - 1500342882163 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:54:42 INFO - 1500342882164 Marionette TRACE 396 <- [1,3,null,{}]
18:54:42 INFO - 1500342882165 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:54:42 INFO - 1500342882165 Marionette TRACE 396 <- [1,4,null,{}]
18:54:42 INFO - 1500342882167 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
18:54:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500342882170 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:54:42 INFO - 1500342882171 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:54:42 INFO - 1500342882172 Marionette TRACE 396 <- [1,6,null,{}]
18:54:42 INFO - 1500342882174 Marionette DEBUG Closed connection 396
18:54:42 INFO - 1500342882175 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49963
18:54:42 INFO - 1500342882176 Marionette DEBUG Closed connection 397
18:54:42 INFO - 1500342882177 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49964
18:54:42 INFO - 1500342882178 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:54:42 INFO - 1500342882178 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:42 INFO - 1500342882181 Marionette DEBUG Register listener.js for window 15
18:54:42 INFO - 1500342882185 Marionette TRACE 398 <- [1,1,null,{"sessionId":"6229f6ef-677f-452a-9602-e30b19ca8d53","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:42 INFO - 1500342882188 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:54:42 INFO - 1500342882189 Marionette TRACE 398 <- [1,2,null,{}]
18:54:42 INFO - 1500342882191 Marionette DEBUG Closed connection 398
18:54:42 INFO - 1500342882192 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49965
18:54:42 INFO - 1500342882193 Marionette DEBUG Closed connection 399
18:54:42 INFO - 1500342882194 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49966
18:54:42 INFO - 1500342882195 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:54:42 INFO - 1500342882196 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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}
18:54:42 INFO - 1500342882198 Marionette DEBUG Register listener.js for window 15
18:54:42 INFO - 1500342882202 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8c8454e1-38c8-4dba-8e8d-d6813b9888c5","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":3720,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4j0gqz.mozrunner","moz:accessibilityChecks":false}}]
18:54:42 INFO - 1500342882204 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
18:54:42 INFO - 1500342882206 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"
18:54:42 INFO - 1500342882209 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"
18:54:42 INFO - 1500342882209 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"
18:54:42 INFO - 1500342882230 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"
18:54:42 INFO - 1500342882234 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"
18:54:42 INFO - 1500342882244 Marionette TRACE 400 <- [1,2,null,{}]
18:54:42 INFO - 1500342882246 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:54:42 INFO - 1500342882249 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0bbdee88-40f9-47cc-9f66-d9a49d6d2203","ELEMENT":"0bbdee88-40f9-47cc-9f66-d9a49d6d2203"}}]
18:54:42 INFO - 1500342882251 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"0bbdee88-40f9-47cc-9f66-d9a49d6d2203"}]
18:54:42 INFO - 1500342882275 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:54:42 INFO - 1500342882276 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"0bbdee88-40f9-47cc-9f66-d9a49d6d2203"}]
18:54:42 INFO - 1500342882294 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element