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]
12:21:50 INFO - 1499800910941 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}]
12:21:50 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 117ms
12:21:50 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:21:50 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499800910945 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:21:50 INFO - 1499800910946 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:21:50 INFO - 1499800910947 Marionette TRACE 352 <- [1,6,null,{}]
12:21:50 INFO - 1499800910949 Marionette DEBUG Closed connection 352
12:21:50 INFO - 1499800910950 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49856
12:21:50 INFO - 1499800910950 Marionette DEBUG Closed connection 353
12:21:50 INFO - 1499800910951 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49857
12:21:50 INFO - 1499800910952 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:50 INFO - 1499800910953 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:50 INFO - 1499800910955 Marionette DEBUG Register listener.js for window 4294967297
12:21:50 INFO - 1499800910958 Marionette TRACE 354 <- [1,1,null,{"sessionId":"8bc2b9d4-55cb-410c-b689-0c65ad161f38","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:50 INFO - 1499800910959 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:21:50 INFO - 1499800910959 Marionette TRACE 354 <- [1,2,null,{}]
12:21:50 INFO - 1499800910960 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:21:50 INFO - 1499800910961 Marionette TRACE 354 <- [1,3,null,{}]
12:21:50 INFO - 1499800910961 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:21:50 INFO - 1499800910962 Marionette TRACE 354 <- [1,4,null,{}]
12:21:50 INFO - 1499800910963 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}]
12:21:50 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499800910966 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:21:50 INFO - 1499800910967 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:21:50 INFO - 1499800910968 Marionette TRACE 354 <- [1,6,null,{}]
12:21:50 INFO - 1499800910969 Marionette DEBUG Closed connection 354
12:21:50 INFO - 1499800910970 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49858
12:21:50 INFO - 1499800910971 Marionette DEBUG Closed connection 355
12:21:50 INFO - 1499800910973 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49859
12:21:50 INFO - 1499800910975 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:50 INFO - 1499800910975 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:50 INFO - 1499800910977 Marionette DEBUG Register listener.js for window 4294967297
12:21:50 INFO - 1499800910980 Marionette TRACE 356 <- [1,1,null,{"sessionId":"eff02c2b-52d8-4901-bbc6-5126153832ca","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:50 INFO - 1499800910981 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:21:50 INFO - 1499800910982 Marionette TRACE 356 <- [1,2,null,{}]
12:21:50 INFO - 1499800910984 Marionette DEBUG Closed connection 356
12:21:50 INFO - 1499800910984 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49860
12:21:50 INFO - 1499800910985 Marionette DEBUG Closed connection 357
12:21:50 INFO - 1499800910986 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49861
12:21:50 INFO - 1499800910987 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:21:50 INFO - 1499800910987 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:50 INFO - 1499800910989 Marionette DEBUG Register listener.js for window 4294967297
12:21:50 INFO - 1499800910992 Marionette TRACE 358 <- [1,1,null,{"sessionId":"9d7b61ee-f82d-4f39-800d-eaae504e53b3","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:50 INFO - 1499800910993 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49415/clicks.html"}]
12:21:50 INFO - 1499800910995 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49415/hidden.html"
12:21:51 INFO - 1499800911002 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49415/hidden.html"
12:21:51 INFO - 1499800911002 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49415/hidden.html"
12:21:51 INFO - 1499800911011 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49415/clicks.html"
12:21:51 INFO - 1499800911012 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49415/clicks.html"
12:21:51 INFO - 1499800911016 Marionette TRACE 358 <- [1,2,null,{}]
12:21:51 INFO - 1499800911021 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:21:51 INFO - 1499800911024 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ffb4ab56-8a15-45bf-a44f-aeffe9ec884e","ELEMENT":"ffb4ab56-8a15-45bf-a44f-aeffe9ec884e"}}]
12:21:51 INFO - 1499800911029 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"ffb4ab56-8a15-45bf-a44f-aeffe9ec884e"}]
12:21:51 INFO - 1499800911066 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49415/clicks.html"
12:21:51 INFO - 1499800911074 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49415/clicks.html"
12:21:51 INFO - 1499800911074 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49415/clicks.html"
12:21:51 INFO - 1499800911081 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49415/test.html"
12:21:51 INFO - 1499800911083 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49415/test.html"
12:21:51 INFO - 1499800911097 Marionette TRACE 358 <- [1,4,null,{}]
12:21:51 INFO - 1499800911104 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:21:51 INFO - 1499800911111 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"45c8083b-9b3a-4560-8aee-c86b0e88c148","ELEMENT":"45c8083b-9b3a-4560-8aee-c86b0e88c148"}}]
12:21:51 INFO - 1499800911112 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:21:51 INFO - 1499800911112 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:21:51 INFO - 1499800911114 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}]
12:21:51 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499800911116 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:21:51 INFO - 1499800911117 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:21:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 171ms
12:21:51 INFO - TEST-START | test_click.py TestClick.test_container_element
12:21:51 INFO - 1499800911119 Marionette TRACE 358 <- [1,8,null,{}]
12:21:51 INFO - 1499800911120 Marionette DEBUG Closed connection 358
12:21:51 INFO - 1499800911121 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49864
12:21:51 INFO - 1499800911121 Marionette DEBUG Closed connection 359
12:21:51 INFO - 1499800911122 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49865
12:21:51 INFO - 1499800911123 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:51 INFO - 1499800911124 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911126 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911129 Marionette TRACE 360 <- [1,1,null,{"sessionId":"0fa6b4be-0ade-4604-bbf6-61f577d2a9bb","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911130 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:21:51 INFO - 1499800911131 Marionette TRACE 360 <- [1,2,null,{}]
12:21:51 INFO - 1499800911132 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:21:51 INFO - 1499800911132 Marionette TRACE 360 <- [1,3,null,{}]
12:21:51 INFO - 1499800911133 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:21:51 INFO - 1499800911134 Marionette TRACE 360 <- [1,4,null,{}]
12:21:51 INFO - 1499800911135 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}]
12:21:51 INFO - TEST-START: test_click.py TestClick.test_container_element1499800911137 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:21:51 INFO - 1499800911138 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:21:51 INFO - 1499800911139 Marionette TRACE 360 <- [1,6,null,{}]
12:21:51 INFO - 1499800911141 Marionette DEBUG Closed connection 360
12:21:51 INFO - 1499800911141 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49866
12:21:51 INFO - 1499800911142 Marionette DEBUG Closed connection 361
12:21:51 INFO - 1499800911143 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49867
12:21:51 INFO - 1499800911144 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:51 INFO - 1499800911144 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911146 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911149 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f848d5cf-89fc-462d-a3b9-f2e9915a9366","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911150 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:21:51 INFO - 1499800911151 Marionette TRACE 362 <- [1,2,null,{}]
12:21:51 INFO - 1499800911152 Marionette DEBUG Closed connection 362
12:21:51 INFO - 1499800911153 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49868
12:21:51 INFO - 1499800911153 Marionette DEBUG Closed connection 363
12:21:51 INFO - 1499800911154 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49869
12:21:51 INFO - 1499800911155 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:21:51 INFO - 1499800911156 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911158 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911161 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f8235dab-38bb-47cc-b7d2-b25970365b1c","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911162 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"}]
12:21:51 INFO - 1499800911164 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49415/test.html"
12:21:51 INFO - 1499800911167 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49415/test.html"
12:21:51 INFO - 1499800911167 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49415/test.html"
12:21:51 INFO - 1499800911174 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"
12:21:51 INFO - 1499800911179 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"
12:21:51 INFO - 1499800911186 Marionette TRACE 364 <- [1,2,null,{}]
12:21:51 INFO - 1499800911189 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:21:51 INFO - 1499800911195 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8c28c050-b511-4e50-a16d-12859c1cedd0","ELEMENT":"8c28c050-b511-4e50-a16d-12859c1cedd0"}}]
12:21:51 INFO - 1499800911196 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8c28c050-b511-4e50-a16d-12859c1cedd0"}]
12:21:51 INFO - 1499800911434 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:21:51 INFO - 1499800911435 Marionette TRACE 364 <- [1,4,null,{}]
12:21:51 INFO - 1499800911436 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8c28c050-b511-4e50-a16d-12859c1cedd0","name":"selected"}]
12:21:51 INFO - 1499800911440 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:21:51 INFO - 1499800911442 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}]
12:21:51 INFO - TEST-END: test_click.py TestClick.test_container_element1499800911444 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:21:51 INFO - 1499800911445 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:21:51 INFO - 1499800911446 Marionette TRACE 364 <- [1,7,null,{}]
12:21:51 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 328ms
12:21:51 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:21:51 INFO - 1499800911448 Marionette DEBUG Closed connection 364
12:21:51 INFO - 1499800911449 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49870
12:21:51 INFO - 1499800911449 Marionette DEBUG Closed connection 365
12:21:51 INFO - 1499800911450 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49871
12:21:51 INFO - 1499800911451 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:51 INFO - 1499800911452 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911454 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911457 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ff3a89bb-c1bd-414c-99ae-b3b5f786681f","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911458 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:21:51 INFO - 1499800911458 Marionette TRACE 366 <- [1,2,null,{}]
12:21:51 INFO - 1499800911459 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:21:51 INFO - 1499800911460 Marionette TRACE 366 <- [1,3,null,{}]
12:21:51 INFO - 1499800911461 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:21:51 INFO - 1499800911461 Marionette TRACE 366 <- [1,4,null,{}]
12:21:51 INFO - 1499800911463 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}]
12:21:51 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499800911465 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:21:51 INFO - 1499800911466 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:21:51 INFO - 1499800911467 Marionette TRACE 366 <- [1,6,null,{}]
12:21:51 INFO - 1499800911469 Marionette DEBUG Closed connection 366
12:21:51 INFO - 1499800911469 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49872
12:21:51 INFO - 1499800911470 Marionette DEBUG Closed connection 367
12:21:51 INFO - 1499800911471 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49873
12:21:51 INFO - 1499800911472 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:51 INFO - 1499800911472 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911474 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911477 Marionette TRACE 368 <- [1,1,null,{"sessionId":"05d36fcd-6e6b-430c-a8fd-78b54ae9a824","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911478 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:21:51 INFO - 1499800911479 Marionette TRACE 368 <- [1,2,null,{}]
12:21:51 INFO - 1499800911480 Marionette DEBUG Closed connection 368
12:21:51 INFO - 1499800911481 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49874
12:21:51 INFO - 1499800911481 Marionette DEBUG Closed connection 369
12:21:51 INFO - 1499800911482 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49875
12:21:51 INFO - 1499800911483 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:21:51 INFO - 1499800911484 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911486 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911488 Marionette TRACE 370 <- [1,1,null,{"sessionId":"39eb3990-bff0-4208-bb7b-f17d8e69f3f9","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911490 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"}]
12:21:51 INFO - 1499800911492 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"
12:21:51 INFO - 1499800911494 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"
12:21:51 INFO - 1499800911494 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"
12:21:51 INFO - 1499800911499 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"
12:21:51 INFO - 1499800911502 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"
12:21:51 INFO - 1499800911504 Marionette TRACE 370 <- [1,2,null,{}]
12:21:51 INFO - 1499800911512 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:21:51 INFO - 1499800911517 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"81e99f21-11e8-4257-96b5-2aed42a05fdf","ELEMENT":"81e99f21-11e8-4257-96b5-2aed42a05fdf"}}]
12:21:51 INFO - 1499800911517 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"81e99f21-11e8-4257-96b5-2aed42a05fdf"}]
12:21:51 INFO - 1499800911761 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:21:51 INFO - 1499800911762 Marionette TRACE 370 <- [1,4,null,{}]
12:21:51 INFO - 1499800911763 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"81e99f21-11e8-4257-96b5-2aed42a05fdf","name":"selected"}]
12:21:51 INFO - 1499800911767 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:21:51 INFO - 1499800911768 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}]
12:21:51 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499800911771 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:21:51 INFO - 1499800911772 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:21:51 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 327ms
12:21:51 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:21:51 INFO - 1499800911773 Marionette TRACE 370 <- [1,7,null,{}]
12:21:51 INFO - 1499800911775 Marionette DEBUG Closed connection 370
12:21:51 INFO - 1499800911776 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49878
12:21:51 INFO - 1499800911776 Marionette DEBUG Closed connection 371
12:21:51 INFO - 1499800911777 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49879
12:21:51 INFO - 1499800911778 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:51 INFO - 1499800911779 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911781 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911784 Marionette TRACE 372 <- [1,1,null,{"sessionId":"db539ddc-0768-439b-ae18-0b6adf1f283c","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911785 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:21:51 INFO - 1499800911785 Marionette TRACE 372 <- [1,2,null,{}]
12:21:51 INFO - 1499800911786 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:21:51 INFO - 1499800911787 Marionette TRACE 372 <- [1,3,null,{}]
12:21:51 INFO - 1499800911787 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:21:51 INFO - 1499800911788 Marionette TRACE 372 <- [1,4,null,{}]
12:21:51 INFO - 1499800911790 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}]
12:21:51 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499800911792 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:21:51 INFO - 1499800911793 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:21:51 INFO - 1499800911794 Marionette TRACE 372 <- [1,6,null,{}]
12:21:51 INFO - 1499800911795 Marionette DEBUG Closed connection 372
12:21:51 INFO - 1499800911796 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49880
12:21:51 INFO - 1499800911797 Marionette DEBUG Closed connection 373
12:21:51 INFO - 1499800911797 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49881
12:21:51 INFO - 1499800911798 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:51 INFO - 1499800911799 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911801 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911804 Marionette TRACE 374 <- [1,1,null,{"sessionId":"28feb7bd-6c1c-4da3-bddf-476b18fface7","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911805 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:21:51 INFO - 1499800911806 Marionette TRACE 374 <- [1,2,null,{}]
12:21:51 INFO - 1499800911808 Marionette DEBUG Closed connection 374
12:21:51 INFO - 1499800911808 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49882
12:21:51 INFO - 1499800911809 Marionette DEBUG Closed connection 375
12:21:51 INFO - 1499800911810 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49883
12:21:51 INFO - 1499800911811 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:21:51 INFO - 1499800911811 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:51 INFO - 1499800911813 Marionette DEBUG Register listener.js for window 4294967297
12:21:51 INFO - 1499800911823 Marionette TRACE 376 <- [1,1,null,{"sessionId":"de67dfb0-d1c9-4023-a25d-e5d6bd935c37","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:51 INFO - 1499800911824 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"}]
12:21:51 INFO - 1499800911827 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"
12:21:51 INFO - 1499800911829 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"
12:21:51 INFO - 1499800911829 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"
12:21:51 INFO - 1499800911835 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"
12:21:51 INFO - 1499800911837 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"
12:21:51 INFO - 1499800911840 Marionette TRACE 376 <- [1,2,null,{}]
12:21:51 INFO - 1499800911850 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:21:51 INFO - 1499800911853 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c77afdd4-5e0c-4a77-b3b5-daa577edcd57","ELEMENT":"c77afdd4-5e0c-4a77-b3b5-daa577edcd57"}}]
12:21:51 INFO - 1499800911854 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"c77afdd4-5e0c-4a77-b3b5-daa577edcd57"}]
12:21:52 INFO - 1499800912088 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:21:52 INFO - 1499800912089 Marionette TRACE 376 <- [1,4,null,{}]
12:21:52 INFO - 1499800912091 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}]
12:21:52 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499800912093 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:21:52 INFO - 1499800912094 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:21:52 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
12:21:52 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:21:52 INFO - 1499800912095 Marionette TRACE 376 <- [1,6,null,{}]
12:21:52 INFO - 1499800912097 Marionette DEBUG Closed connection 376
12:21:52 INFO - 1499800912098 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49884
12:21:52 INFO - 1499800912098 Marionette DEBUG Closed connection 377
12:21:52 INFO - 1499800912099 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49885
12:21:52 INFO - 1499800912100 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:52 INFO - 1499800912101 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912103 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912106 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f575aed6-7935-41f7-ac8d-cd77260ad7ff","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912107 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:21:52 INFO - 1499800912107 Marionette TRACE 378 <- [1,2,null,{}]
12:21:52 INFO - 1499800912108 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:21:52 INFO - 1499800912109 Marionette TRACE 378 <- [1,3,null,{}]
12:21:52 INFO - 1499800912109 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:21:52 INFO - 1499800912110 Marionette TRACE 378 <- [1,4,null,{}]
12:21:52 INFO - 1499800912111 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}]
12:21:52 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499800912114 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:21:52 INFO - 1499800912115 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:21:52 INFO - 1499800912116 Marionette TRACE 378 <- [1,6,null,{}]
12:21:52 INFO - 1499800912117 Marionette DEBUG Closed connection 378
12:21:52 INFO - 1499800912118 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49886
12:21:52 INFO - 1499800912118 Marionette DEBUG Closed connection 379
12:21:52 INFO - 1499800912119 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49887
12:21:52 INFO - 1499800912120 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:52 INFO - 1499800912120 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912122 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912125 Marionette TRACE 380 <- [1,1,null,{"sessionId":"40288afd-85a3-453f-b491-4188650333fc","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912126 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:21:52 INFO - 1499800912127 Marionette TRACE 380 <- [1,2,null,{}]
12:21:52 INFO - 1499800912129 Marionette DEBUG Closed connection 380
12:21:52 INFO - 1499800912129 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49888
12:21:52 INFO - 1499800912130 Marionette DEBUG Closed connection 381
12:21:52 INFO - 1499800912130 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49889
12:21:52 INFO - 1499800912131 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:21:52 INFO - 1499800912132 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912134 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912137 Marionette TRACE 382 <- [1,1,null,{"sessionId":"dca3f0b2-7400-4543-a66d-2483a5c069c2","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912138 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"}]
12:21:52 INFO - 1499800912139 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"
12:21:52 INFO - 1499800912141 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"
12:21:52 INFO - 1499800912141 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"
12:21:52 INFO - 1499800912147 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"
12:21:52 INFO - 1499800912149 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"
12:21:52 INFO - 1499800912152 Marionette TRACE 382 <- [1,2,null,{}]
12:21:52 INFO - 1499800912155 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:21:52 INFO - 1499800912157 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a0f6f6d-52c5-4cb2-aaad-7dd1b4723554","ELEMENT":"7a0f6f6d-52c5-4cb2-aaad-7dd1b4723554"}}]
12:21:52 INFO - 1499800912162 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"7a0f6f6d-52c5-4cb2-aaad-7dd1b4723554"}]
12:21:52 INFO - 1499800912402 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:21:52 INFO - 1499800912403 Marionette TRACE 382 <- [1,4,null,{}]
12:21:52 INFO - 1499800912404 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}]
12:21:52 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499800912407 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:21:52 INFO - 1499800912408 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:21:52 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 314ms
12:21:52 INFO - TEST-START | test_click.py TestClick.test_input_file
12:21:52 INFO - 1499800912410 Marionette TRACE 382 <- [1,6,null,{}]
12:21:52 INFO - 1499800912411 Marionette DEBUG Closed connection 382
12:21:52 INFO - 1499800912412 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49890
12:21:52 INFO - 1499800912413 Marionette DEBUG Closed connection 383
12:21:52 INFO - 1499800912413 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49891
12:21:52 INFO - 1499800912414 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:52 INFO - 1499800912415 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912417 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912420 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a32ebc4b-5fe6-45cc-81d5-86a9f87500e2","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912421 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:21:52 INFO - 1499800912421 Marionette TRACE 384 <- [1,2,null,{}]
12:21:52 INFO - 1499800912422 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:21:52 INFO - 1499800912422 Marionette TRACE 384 <- [1,3,null,{}]
12:21:52 INFO - 1499800912423 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:21:52 INFO - 1499800912424 Marionette TRACE 384 <- [1,4,null,{}]
12:21:52 INFO - 1499800912425 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}]
12:21:52 INFO - TEST-START: test_click.py TestClick.test_input_file1499800912428 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:21:52 INFO - 1499800912428 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:21:52 INFO - 1499800912430 Marionette TRACE 384 <- [1,6,null,{}]
12:21:52 INFO - 1499800912431 Marionette DEBUG Closed connection 384
12:21:52 INFO - 1499800912432 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49892
12:21:52 INFO - 1499800912432 Marionette DEBUG Closed connection 385
12:21:52 INFO - 1499800912433 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49893
12:21:52 INFO - 1499800912434 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:52 INFO - 1499800912434 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912436 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912439 Marionette TRACE 386 <- [1,1,null,{"sessionId":"73b47258-f483-43a5-90d5-47e11f5c986f","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912440 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:21:52 INFO - 1499800912441 Marionette TRACE 386 <- [1,2,null,{}]
12:21:52 INFO - 1499800912443 Marionette DEBUG Closed connection 386
12:21:52 INFO - 1499800912443 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49894
12:21:52 INFO - 1499800912444 Marionette DEBUG Closed connection 387
12:21:52 INFO - 1499800912445 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49895
12:21:52 INFO - 1499800912446 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:21:52 INFO - 1499800912446 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912448 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912451 Marionette TRACE 388 <- [1,1,null,{"sessionId":"06f9656d-7373-4c16-9f1d-c8538807afb4","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912452 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:21:52 INFO - 1499800912454 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"
12:21:52 INFO - 1499800912455 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"
12:21:52 INFO - 1499800912456 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"
12:21:52 INFO - 1499800912462 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:21:52 INFO - 1499800912465 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:21:52 INFO - 1499800912468 Marionette TRACE 388 <- [1,2,null,{}]
12:21:52 INFO - 1499800912471 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:21:52 INFO - 1499800912473 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"feb1e605-97f4-4d6a-be99-62cae4954ab5","ELEMENT":"feb1e605-97f4-4d6a-be99-62cae4954ab5"}}]
12:21:52 INFO - 1499800912483 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"feb1e605-97f4-4d6a-be99-62cae4954ab5"}]
12:21:52 INFO - 1499800912514 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]
12:21:52 INFO - 1499800912516 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}]
12:21:52 INFO - TEST-END: test_click.py TestClick.test_input_file1499800912519 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:21:52 INFO - 1499800912520 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:21:52 INFO - 1499800912521 Marionette TRACE 388 <- [1,6,null,{}]
12:21:52 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 112ms
12:21:52 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:21:52 INFO - 1499800912523 Marionette DEBUG Closed connection 388
12:21:52 INFO - 1499800912523 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49896
12:21:52 INFO - 1499800912524 Marionette DEBUG Closed connection 389
12:21:52 INFO - 1499800912525 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49897
12:21:52 INFO - 1499800912526 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:52 INFO - 1499800912526 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912528 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912531 Marionette TRACE 390 <- [1,1,null,{"sessionId":"a9305545-85b0-4eb0-b8ad-cda7ab24f350","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912532 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:21:52 INFO - 1499800912533 Marionette TRACE 390 <- [1,2,null,{}]
12:21:52 INFO - 1499800912534 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:21:52 INFO - 1499800912534 Marionette TRACE 390 <- [1,3,null,{}]
12:21:52 INFO - 1499800912535 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:21:52 INFO - 1499800912535 Marionette TRACE 390 <- [1,4,null,{}]
12:21:52 INFO - 1499800912537 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}]
12:21:52 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499800912540 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:21:52 INFO - 1499800912540 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:21:52 INFO - 1499800912542 Marionette TRACE 390 <- [1,6,null,{}]
12:21:52 INFO - 1499800912543 Marionette DEBUG Closed connection 390
12:21:52 INFO - 1499800912543 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49898
12:21:52 INFO - 1499800912544 Marionette DEBUG Closed connection 391
12:21:52 INFO - 1499800912545 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49899
12:21:52 INFO - 1499800912546 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:52 INFO - 1499800912546 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912548 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912551 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fa091576-35da-405d-8b23-d5b3aa2cf304","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912552 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:21:52 INFO - 1499800912553 Marionette TRACE 392 <- [1,2,null,{}]
12:21:52 INFO - 1499800912554 Marionette DEBUG Closed connection 392
12:21:52 INFO - 1499800912555 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49900
12:21:52 INFO - 1499800912556 Marionette DEBUG Closed connection 393
12:21:52 INFO - 1499800912556 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49901
12:21:52 INFO - 1499800912557 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:21:52 INFO - 1499800912558 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912560 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912563 Marionette TRACE 394 <- [1,1,null,{"sessionId":"042cc63e-e7d1-49ab-948c-29151c37ef37","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912564 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"}]
12:21:52 INFO - 1499800912565 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:21:52 INFO - 1499800912567 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:21:52 INFO - 1499800912567 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:21:52 INFO - 1499800912573 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"
12:21:52 INFO - 1499800912575 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"
12:21:52 INFO - 1499800912582 Marionette TRACE 394 <- [1,2,null,{}]
12:21:52 INFO - 1499800912586 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:21:52 INFO - 1499800912588 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b7507d74-92d2-47c0-8e40-a3751c80e50f","ELEMENT":"b7507d74-92d2-47c0-8e40-a3751c80e50f"}}]
12:21:52 INFO - 1499800912589 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:21:52 INFO - 1499800912595 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3e392438-e567-4bf3-a8a1-84306da626e7","ELEMENT":"3e392438-e567-4bf3-a8a1-84306da626e7"}}]
12:21:52 INFO - 1499800912596 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b7507d74-92d2-47c0-8e40-a3751c80e50f"}]
12:21:52 INFO - 1499800912831 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:21:52 INFO - 1499800912832 Marionette TRACE 394 <- [1,5,null,{}]
12:21:52 INFO - 1499800912833 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"3e392438-e567-4bf3-a8a1-84306da626e7"}]
12:21:52 INFO - 1499800912847 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:21:52 INFO - 1499800912849 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:21:52 INFO - 1499800912852 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:21:52 INFO - 1499800912854 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}]
12:21:52 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499800912856 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:21:52 INFO - 1499800912857 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:21:52 INFO - 1499800912858 Marionette TRACE 394 <- [1,9,null,{}]
12:21:52 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
12:21:52 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:21:52 INFO - 1499800912860 Marionette DEBUG Closed connection 394
12:21:52 INFO - 1499800912861 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49902
12:21:52 INFO - 1499800912861 Marionette DEBUG Closed connection 395
12:21:52 INFO - 1499800912862 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49903
12:21:52 INFO - 1499800912863 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:52 INFO - 1499800912864 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912866 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912869 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1f7c9405-44d1-409f-8a0a-77445f179899","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912870 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:21:52 INFO - 1499800912871 Marionette TRACE 396 <- [1,2,null,{}]
12:21:52 INFO - 1499800912871 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:21:52 INFO - 1499800912872 Marionette TRACE 396 <- [1,3,null,{}]
12:21:52 INFO - 1499800912873 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:21:52 INFO - 1499800912873 Marionette TRACE 396 <- [1,4,null,{}]
12:21:52 INFO - 1499800912875 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}]
12:21:52 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499800912877 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:21:52 INFO - 1499800912878 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:21:52 INFO - 1499800912879 Marionette TRACE 396 <- [1,6,null,{}]
12:21:52 INFO - 1499800912880 Marionette DEBUG Closed connection 396
12:21:52 INFO - 1499800912881 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49904
12:21:52 INFO - 1499800912882 Marionette DEBUG Closed connection 397
12:21:52 INFO - 1499800912882 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49905
12:21:52 INFO - 1499800912883 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:21:52 INFO - 1499800912884 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912886 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912889 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a68d32fd-721f-424a-bd3a-df560680fa86","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912890 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:21:52 INFO - 1499800912891 Marionette TRACE 398 <- [1,2,null,{}]
12:21:52 INFO - 1499800912892 Marionette DEBUG Closed connection 398
12:21:52 INFO - 1499800912893 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49906
12:21:52 INFO - 1499800912893 Marionette DEBUG Closed connection 399
12:21:52 INFO - 1499800912894 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49907
12:21:52 INFO - 1499800912895 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:21:52 INFO - 1499800912896 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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}
12:21:52 INFO - 1499800912898 Marionette DEBUG Register listener.js for window 4294967297
12:21:52 INFO - 1499800912901 Marionette TRACE 400 <- [1,1,null,{"sessionId":"894c4923-19a0-4105-8f6e-94051024d813","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":5688,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmpst9p.mozrunner","moz:accessibilityChecks":false}}]
12:21:52 INFO - 1499800912902 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"}]
12:21:52 INFO - 1499800912904 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"
12:21:52 INFO - 1499800912905 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"
12:21:52 INFO - 1499800912906 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"
12:21:52 INFO - 1499800912912 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"
12:21:52 INFO - 1499800912914 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"
12:21:52 INFO - 1499800912918 Marionette TRACE 400 <- [1,2,null,{}]
12:21:52 INFO - 1499800912922 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:21:52 INFO - 1499800912929 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e9bc29d-8500-4353-b3f9-e48bfc72f90f","ELEMENT":"6e9bc29d-8500-4353-b3f9-e48bfc72f90f"}}]
12:21:52 INFO - 1499800912930 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6e9bc29d-8500-4353-b3f9-e48bfc72f90f"}]
12:21:52 INFO - 1499800912951 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:21:52 INFO - 1499800912952 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"6e9bc29d-8500-4353-b3f9-e48bfc72f90f"}]
12:21:52 INFO - 1499800912966 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element