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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:45:36 INFO - 1499705136109 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}]
09:45:36 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499705136111 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:45:36 INFO - 1499705136112 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:45:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 127ms
09:45:36 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:45:36 INFO - 1499705136113 Marionette TRACE 352 <- [1,6,null,{}]
09:45:36 INFO - 1499705136115 Marionette DEBUG Closed connection 352
09:45:36 INFO - 1499705136115 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49748
09:45:36 INFO - 1499705136116 Marionette DEBUG Closed connection 353
09:45:36 INFO - 1499705136117 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49749
09:45:36 INFO - 1499705136118 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:36 INFO - 1499705136118 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136121 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136123 Marionette TRACE 354 <- [1,1,null,{"sessionId":"46850543-8227-4a90-8235-130930197dfc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136125 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:45:36 INFO - 1499705136126 Marionette TRACE 354 <- [1,2,null,{}]
09:45:36 INFO - 1499705136127 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:36 INFO - 1499705136127 Marionette TRACE 354 <- [1,3,null,{}]
09:45:36 INFO - 1499705136128 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:36 INFO - 1499705136128 Marionette TRACE 354 <- [1,4,null,{}]
09:45:36 INFO - 1499705136130 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}]
09:45:36 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499705136132 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:45:36 INFO - 1499705136133 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:45:36 INFO - 1499705136134 Marionette TRACE 354 <- [1,6,null,{}]
09:45:36 INFO - 1499705136136 Marionette DEBUG Closed connection 354
09:45:36 INFO - 1499705136137 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49750
09:45:36 INFO - 1499705136137 Marionette DEBUG Closed connection 355
09:45:36 INFO - 1499705136138 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49751
09:45:36 INFO - 1499705136139 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:36 INFO - 1499705136139 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136142 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136144 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5b1eced6-800f-4be3-95c8-15c24966a98a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136146 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:45:36 INFO - 1499705136147 Marionette TRACE 356 <- [1,2,null,{}]
09:45:36 INFO - 1499705136149 Marionette DEBUG Closed connection 356
09:45:36 INFO - 1499705136150 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49752
09:45:36 INFO - 1499705136150 Marionette DEBUG Closed connection 357
09:45:36 INFO - 1499705136151 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49753
09:45:36 INFO - 1499705136152 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:36 INFO - 1499705136152 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136155 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136158 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0257d37b-0f6a-4d7f-9520-d55e0c9f3952","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136160 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
09:45:36 INFO - 1499705136162 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
09:45:36 INFO - 1499705136167 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
09:45:36 INFO - 1499705136168 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
09:45:36 INFO - 1499705136187 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
09:45:36 INFO - 1499705136189 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
09:45:36 INFO - 1499705136192 Marionette TRACE 358 <- [1,2,null,{}]
09:45:36 INFO - 1499705136199 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:45:36 INFO - 1499705136201 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cec01b09-5441-45f0-ae70-748a50f919ad","ELEMENT":"cec01b09-5441-45f0-ae70-748a50f919ad"}}]
09:45:36 INFO - 1499705136202 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"cec01b09-5441-45f0-ae70-748a50f919ad"}]
09:45:36 INFO - 1499705136238 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
09:45:36 INFO - 1499705136248 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
09:45:36 INFO - 1499705136248 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
09:45:36 INFO - 1499705136267 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
09:45:36 INFO - 1499705136268 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
09:45:36 INFO - 1499705136270 Marionette TRACE 358 <- [1,4,null,{}]
09:45:36 INFO - 1499705136271 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:45:36 INFO - 1499705136273 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"33c57c2c-d443-42b6-abb9-e771e0caf19b","ELEMENT":"33c57c2c-d443-42b6-abb9-e771e0caf19b"}}]
09:45:36 INFO - 1499705136274 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:45:36 INFO - 1499705136274 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:45:36 INFO - 1499705136281 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}]
09:45:36 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499705136284 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:45:36 INFO - 1499705136285 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:45:36 INFO - 1499705136286 Marionette TRACE 358 <- [1,8,null,{}]
09:45:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 173ms
09:45:36 INFO - TEST-START | test_click.py TestClick.test_container_element
09:45:36 INFO - 1499705136288 Marionette DEBUG Closed connection 358
09:45:36 INFO - 1499705136288 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49756
09:45:36 INFO - 1499705136289 Marionette DEBUG Closed connection 359
09:45:36 INFO - 1499705136290 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49757
09:45:36 INFO - 1499705136291 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:36 INFO - 1499705136291 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136294 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136297 Marionette TRACE 360 <- [1,1,null,{"sessionId":"8e8c9a19-8a8c-4c53-978b-adacb2dce0f9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136299 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:45:36 INFO - 1499705136299 Marionette TRACE 360 <- [1,2,null,{}]
09:45:36 INFO - 1499705136300 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:36 INFO - 1499705136301 Marionette TRACE 360 <- [1,3,null,{}]
09:45:36 INFO - 1499705136302 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:36 INFO - 1499705136303 Marionette TRACE 360 <- [1,4,null,{}]
09:45:36 INFO - 1499705136305 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}]
09:45:36 INFO - TEST-START: test_click.py TestClick.test_container_element1499705136307 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:45:36 INFO - 1499705136322 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:45:36 INFO - 1499705136323 Marionette TRACE 360 <- [1,6,null,{}]
09:45:36 INFO - 1499705136325 Marionette DEBUG Closed connection 360
09:45:36 INFO - 1499705136326 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49758
09:45:36 INFO - 1499705136327 Marionette DEBUG Closed connection 361
09:45:36 INFO - 1499705136327 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49759
09:45:36 INFO - 1499705136328 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:36 INFO - 1499705136335 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136337 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136339 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e5bb7276-d386-413c-b21d-37ef87774d51","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136342 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:45:36 INFO - 1499705136343 Marionette TRACE 362 <- [1,2,null,{}]
09:45:36 INFO - 1499705136344 Marionette DEBUG Closed connection 362
09:45:36 INFO - 1499705136345 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49760
09:45:36 INFO - 1499705136346 Marionette DEBUG Closed connection 363
09:45:36 INFO - 1499705136346 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49761
09:45:36 INFO - 1499705136347 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:36 INFO - 1499705136348 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136350 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136352 Marionette TRACE 364 <- [1,1,null,{"sessionId":"89b70f7c-0661-47f3-ba60-4fe4c9dab5a3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136354 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"}]
09:45:36 INFO - 1499705136355 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
09:45:36 INFO - 1499705136357 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
09:45:36 INFO - 1499705136357 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
09:45:36 INFO - 1499705136381 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"
09:45:36 INFO - 1499705136383 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"
09:45:36 INFO - 1499705136386 Marionette TRACE 364 <- [1,2,null,{}]
09:45:36 INFO - 1499705136387 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:45:36 INFO - 1499705136393 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"19c1eb62-6083-4b97-802c-7371259af225","ELEMENT":"19c1eb62-6083-4b97-802c-7371259af225"}}]
09:45:36 INFO - 1499705136405 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"19c1eb62-6083-4b97-802c-7371259af225"}]
09:45:36 INFO - 1499705136643 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:36 INFO - 1499705136643 Marionette TRACE 364 <- [1,4,null,{}]
09:45:36 INFO - 1499705136644 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"19c1eb62-6083-4b97-802c-7371259af225","name":"selected"}]
09:45:36 INFO - 1499705136648 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:45:36 INFO - 1499705136650 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}]
09:45:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 367ms
09:45:36 INFO - TEST-END: test_click.py TestClick.test_container_element1499705136652 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:45:36 INFO - 1499705136653 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:45:36 INFO - 1499705136654 Marionette TRACE 364 <- [1,7,null,{}]
09:45:36 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:45:36 INFO - 1499705136656 Marionette DEBUG Closed connection 364
09:45:36 INFO - 1499705136657 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49762
09:45:36 INFO - 1499705136658 Marionette DEBUG Closed connection 365
09:45:36 INFO - 1499705136659 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49763
09:45:36 INFO - 1499705136660 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:36 INFO - 1499705136660 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136662 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136664 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3ec1854c-4f0d-4c7f-86d7-96e29070e80c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136666 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:45:36 INFO - 1499705136667 Marionette TRACE 366 <- [1,2,null,{}]
09:45:36 INFO - 1499705136668 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:36 INFO - 1499705136668 Marionette TRACE 366 <- [1,3,null,{}]
09:45:36 INFO - 1499705136669 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:36 INFO - 1499705136669 Marionette TRACE 366 <- [1,4,null,{}]
09:45:36 INFO - 1499705136671 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}]
09:45:36 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499705136673 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:45:36 INFO - 1499705136674 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:45:36 INFO - 1499705136675 Marionette TRACE 366 <- [1,6,null,{}]
09:45:36 INFO - 1499705136677 Marionette DEBUG Closed connection 366
09:45:36 INFO - 1499705136677 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49764
09:45:36 INFO - 1499705136678 Marionette DEBUG Closed connection 367
09:45:36 INFO - 1499705136678 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49765
09:45:36 INFO - 1499705136679 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:36 INFO - 1499705136680 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136682 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136684 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e0700b16-d0ee-49f7-9a32-13ef10ff6011","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136686 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:45:36 INFO - 1499705136687 Marionette TRACE 368 <- [1,2,null,{}]
09:45:36 INFO - 1499705136689 Marionette DEBUG Closed connection 368
09:45:36 INFO - 1499705136689 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49766
09:45:36 INFO - 1499705136690 Marionette DEBUG Closed connection 369
09:45:36 INFO - 1499705136691 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49767
09:45:36 INFO - 1499705136692 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:36 INFO - 1499705136692 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136694 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136696 Marionette TRACE 370 <- [1,1,null,{"sessionId":"e4961547-4942-4d81-8d8f-88d244ba5abe","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:36 INFO - 1499705136698 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"}]
09:45:36 INFO - 1499705136699 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"
09:45:36 INFO - 1499705136701 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"
09:45:36 INFO - 1499705136701 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"
09:45:36 INFO - 1499705136716 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"
09:45:36 INFO - 1499705136719 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"
09:45:36 INFO - 1499705136722 Marionette TRACE 370 <- [1,2,null,{}]
09:45:36 INFO - 1499705136723 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:45:36 INFO - 1499705136732 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c41cc66-0f71-4651-8f0d-298678e4b74e","ELEMENT":"6c41cc66-0f71-4651-8f0d-298678e4b74e"}}]
09:45:36 INFO - 1499705136733 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"6c41cc66-0f71-4651-8f0d-298678e4b74e"}]
09:45:36 INFO - 1499705136976 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:36 INFO - 1499705136977 Marionette TRACE 370 <- [1,4,null,{}]
09:45:36 INFO - 1499705136978 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"6c41cc66-0f71-4651-8f0d-298678e4b74e","name":"selected"}]
09:45:36 INFO - 1499705136982 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:45:36 INFO - 1499705136983 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}]
09:45:36 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499705136985 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:45:36 INFO - 1499705136986 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:45:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 332ms
09:45:36 INFO - 1499705136987 Marionette TRACE 370 <- [1,7,null,{}]
09:45:36 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:45:36 INFO - 1499705136989 Marionette DEBUG Closed connection 370
09:45:36 INFO - 1499705136990 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49768
09:45:36 INFO - 1499705136991 Marionette DEBUG Closed connection 371
09:45:36 INFO - 1499705136992 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49769
09:45:36 INFO - 1499705136992 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:36 INFO - 1499705136993 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:36 INFO - 1499705136995 Marionette DEBUG Register listener.js for window 12
09:45:36 INFO - 1499705136997 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c31d7e75-3248-44e4-9726-4d1700c0233e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705136999 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:45:37 INFO - 1499705136999 Marionette TRACE 372 <- [1,2,null,{}]
09:45:37 INFO - 1499705137000 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:37 INFO - 1499705137001 Marionette TRACE 372 <- [1,3,null,{}]
09:45:37 INFO - 1499705137001 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:37 INFO - 1499705137002 Marionette TRACE 372 <- [1,4,null,{}]
09:45:37 INFO - 1499705137003 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}]
09:45:37 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499705137005 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:45:37 INFO - 1499705137006 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:45:37 INFO - 1499705137007 Marionette TRACE 372 <- [1,6,null,{}]
09:45:37 INFO - 1499705137009 Marionette DEBUG Closed connection 372
09:45:37 INFO - 1499705137009 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49770
09:45:37 INFO - 1499705137010 Marionette DEBUG Closed connection 373
09:45:37 INFO - 1499705137010 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49771
09:45:37 INFO - 1499705137011 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:37 INFO - 1499705137012 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137014 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137016 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4755d482-15a9-4857-9f21-52ab70612357","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137018 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:45:37 INFO - 1499705137019 Marionette TRACE 374 <- [1,2,null,{}]
09:45:37 INFO - 1499705137021 Marionette DEBUG Closed connection 374
09:45:37 INFO - 1499705137022 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49772
09:45:37 INFO - 1499705137022 Marionette DEBUG Closed connection 375
09:45:37 INFO - 1499705137023 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49773
09:45:37 INFO - 1499705137024 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:37 INFO - 1499705137024 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137026 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137028 Marionette TRACE 376 <- [1,1,null,{"sessionId":"819b9179-c210-43bf-bd4b-41f62cd54f0e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137030 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"}]
09:45:37 INFO - 1499705137031 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"
09:45:37 INFO - 1499705137033 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"
09:45:37 INFO - 1499705137033 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"
09:45:37 INFO - 1499705137050 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"
09:45:37 INFO - 1499705137052 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"
09:45:37 INFO - 1499705137055 Marionette TRACE 376 <- [1,2,null,{}]
09:45:37 INFO - 1499705137056 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:45:37 INFO - 1499705137063 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8e0163d3-05d8-4b23-be9f-24ea9c032a63","ELEMENT":"8e0163d3-05d8-4b23-be9f-24ea9c032a63"}}]
09:45:37 INFO - 1499705137064 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8e0163d3-05d8-4b23-be9f-24ea9c032a63"}]
09:45:37 INFO - 1499705137298 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:37 INFO - 1499705137299 Marionette TRACE 376 <- [1,4,null,{}]
09:45:37 INFO - 1499705137300 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}]
09:45:37 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 318ms
09:45:37 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499705137302 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:45:37 INFO - 1499705137303 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:45:37 INFO - 1499705137304 Marionette TRACE 376 <- [1,6,null,{}]
09:45:37 INFO - 1499705137307 Marionette DEBUG Closed connection 376
09:45:37 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:45:37 INFO - 1499705137309 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49774
09:45:37 INFO - 1499705137310 Marionette DEBUG Closed connection 377
09:45:37 INFO - 1499705137310 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49775
09:45:37 INFO - 1499705137311 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:37 INFO - 1499705137311 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137313 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137316 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5eed4097-4847-41bc-b451-166c1fbe99f9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137317 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:45:37 INFO - 1499705137318 Marionette TRACE 378 <- [1,2,null,{}]
09:45:37 INFO - 1499705137318 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:37 INFO - 1499705137319 Marionette TRACE 378 <- [1,3,null,{}]
09:45:37 INFO - 1499705137320 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:37 INFO - 1499705137321 Marionette TRACE 378 <- [1,4,null,{}]
09:45:37 INFO - 1499705137322 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}]
09:45:37 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499705137324 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:45:37 INFO - 1499705137325 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:45:37 INFO - 1499705137326 Marionette TRACE 378 <- [1,6,null,{}]
09:45:37 INFO - 1499705137328 Marionette DEBUG Closed connection 378
09:45:37 INFO - 1499705137328 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49776
09:45:37 INFO - 1499705137329 Marionette DEBUG Closed connection 379
09:45:37 INFO - 1499705137329 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49777
09:45:37 INFO - 1499705137330 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:37 INFO - 1499705137331 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137333 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137335 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0b1840ab-4e84-4ecb-9dc6-dcf920b8e734","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137337 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:45:37 INFO - 1499705137338 Marionette TRACE 380 <- [1,2,null,{}]
09:45:37 INFO - 1499705137340 Marionette DEBUG Closed connection 380
09:45:37 INFO - 1499705137340 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49778
09:45:37 INFO - 1499705137341 Marionette DEBUG Closed connection 381
09:45:37 INFO - 1499705137342 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49779
09:45:37 INFO - 1499705137343 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:37 INFO - 1499705137350 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137352 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137354 Marionette TRACE 382 <- [1,1,null,{"sessionId":"0eec3868-4237-4009-8d24-068d79e00f2e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137356 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"}]
09:45:37 INFO - 1499705137357 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"
09:45:37 INFO - 1499705137359 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"
09:45:37 INFO - 1499705137359 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"
09:45:37 INFO - 1499705137368 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"
09:45:37 INFO - 1499705137372 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"
09:45:37 INFO - 1499705137375 Marionette TRACE 382 <- [1,2,null,{}]
09:45:37 INFO - 1499705137384 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:45:37 INFO - 1499705137386 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c959987d-5d0c-4c86-a0c3-8907980e0d20","ELEMENT":"c959987d-5d0c-4c86-a0c3-8907980e0d20"}}]
09:45:37 INFO - 1499705137387 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c959987d-5d0c-4c86-a0c3-8907980e0d20"}]
09:45:37 INFO - 1499705137623 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:37 INFO - 1499705137624 Marionette TRACE 382 <- [1,4,null,{}]
09:45:37 INFO - 1499705137625 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}]
09:45:37 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499705137627 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:45:37 INFO - 1499705137628 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:45:37 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 323ms
09:45:37 INFO - 1499705137629 Marionette TRACE 382 <- [1,6,null,{}]
09:45:37 INFO - TEST-START | test_click.py TestClick.test_input_file
09:45:37 INFO - 1499705137631 Marionette DEBUG Closed connection 382
09:45:37 INFO - 1499705137632 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49780
09:45:37 INFO - 1499705137633 Marionette DEBUG Closed connection 383
09:45:37 INFO - 1499705137634 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49781
09:45:37 INFO - 1499705137635 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:37 INFO - 1499705137635 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137637 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137640 Marionette TRACE 384 <- [1,1,null,{"sessionId":"54b3b9ef-8fc1-4c5e-be6a-3b3ab531d0b4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137643 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:45:37 INFO - 1499705137643 Marionette TRACE 384 <- [1,2,null,{}]
09:45:37 INFO - 1499705137644 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:37 INFO - 1499705137645 Marionette TRACE 384 <- [1,3,null,{}]
09:45:37 INFO - 1499705137646 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:37 INFO - 1499705137646 Marionette TRACE 384 <- [1,4,null,{}]
09:45:37 INFO - 1499705137648 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}]
09:45:37 INFO - TEST-START: test_click.py TestClick.test_input_file1499705137650 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:45:37 INFO - 1499705137651 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:45:37 INFO - 1499705137652 Marionette TRACE 384 <- [1,6,null,{}]
09:45:37 INFO - 1499705137654 Marionette DEBUG Closed connection 384
09:45:37 INFO - 1499705137654 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49782
09:45:37 INFO - 1499705137655 Marionette DEBUG Closed connection 385
09:45:37 INFO - 1499705137655 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49783
09:45:37 INFO - 1499705137656 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:37 INFO - 1499705137657 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137659 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137662 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6263f2b6-bcf4-419a-8c19-c8a3bf33416d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137664 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:45:37 INFO - 1499705137665 Marionette TRACE 386 <- [1,2,null,{}]
09:45:37 INFO - 1499705137667 Marionette DEBUG Closed connection 386
09:45:37 INFO - 1499705137667 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49784
09:45:37 INFO - 1499705137668 Marionette DEBUG Closed connection 387
09:45:37 INFO - 1499705137668 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49785
09:45:37 INFO - 1499705137669 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:37 INFO - 1499705137670 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137672 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137674 Marionette TRACE 388 <- [1,1,null,{"sessionId":"25b846e9-62c0-442b-8ced-41849bb7c083","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137676 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:45:37 INFO - 1499705137677 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"
09:45:37 INFO - 1499705137679 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"
09:45:37 INFO - 1499705137679 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"
09:45:37 INFO - 1499705137688 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:37 INFO - 1499705137697 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:37 INFO - 1499705137701 Marionette TRACE 388 <- [1,2,null,{}]
09:45:37 INFO - 1499705137702 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:45:37 INFO - 1499705137704 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cda8af71-d9e1-4772-a2c4-0c1b2a556712","ELEMENT":"cda8af71-d9e1-4772-a2c4-0c1b2a556712"}}]
09:45:37 INFO - 1499705137705 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"cda8af71-d9e1-4772-a2c4-0c1b2a556712"}]
09:45:37 INFO - 1499705137739 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:45:37 INFO - 1499705137741 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}]
09:45:37 INFO - TEST-END: test_click.py TestClick.test_input_file1499705137743 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:45:37 INFO - 1499705137744 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:45:37 INFO - 1499705137745 Marionette TRACE 388 <- [1,6,null,{}]
09:45:37 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 115ms
09:45:37 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:45:37 INFO - 1499705137747 Marionette DEBUG Closed connection 388
09:45:37 INFO - 1499705137748 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49786
09:45:37 INFO - 1499705137748 Marionette DEBUG Closed connection 389
09:45:37 INFO - 1499705137749 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49787
09:45:37 INFO - 1499705137750 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:37 INFO - 1499705137750 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137752 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137755 Marionette TRACE 390 <- [1,1,null,{"sessionId":"adc9fdcd-cdc0-4dbe-923c-b3b8ec386556","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137757 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:45:37 INFO - 1499705137757 Marionette TRACE 390 <- [1,2,null,{}]
09:45:37 INFO - 1499705137758 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:37 INFO - 1499705137758 Marionette TRACE 390 <- [1,3,null,{}]
09:45:37 INFO - 1499705137759 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:37 INFO - 1499705137759 Marionette TRACE 390 <- [1,4,null,{}]
09:45:37 INFO - 1499705137761 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}]
09:45:37 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499705137763 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:45:37 INFO - 1499705137763 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:45:37 INFO - 1499705137765 Marionette TRACE 390 <- [1,6,null,{}]
09:45:37 INFO - 1499705137767 Marionette DEBUG Closed connection 390
09:45:37 INFO - 1499705137767 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49788
09:45:37 INFO - 1499705137768 Marionette DEBUG Closed connection 391
09:45:37 INFO - 1499705137769 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49789
09:45:37 INFO - 1499705137769 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:37 INFO - 1499705137770 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137772 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137775 Marionette TRACE 392 <- [1,1,null,{"sessionId":"17147895-6dae-46a9-84c3-baf17729bfa1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137776 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:45:37 INFO - 1499705137777 Marionette TRACE 392 <- [1,2,null,{}]
09:45:37 INFO - 1499705137779 Marionette DEBUG Closed connection 392
09:45:37 INFO - 1499705137779 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49790
09:45:37 INFO - 1499705137780 Marionette DEBUG Closed connection 393
09:45:37 INFO - 1499705137781 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49791
09:45:37 INFO - 1499705137782 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:37 INFO - 1499705137782 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:37 INFO - 1499705137784 Marionette DEBUG Register listener.js for window 12
09:45:37 INFO - 1499705137787 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2ba6a6b9-f114-4450-a568-5ba73608c9fc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:37 INFO - 1499705137789 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"}]
09:45:37 INFO - 1499705137790 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:37 INFO - 1499705137792 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:37 INFO - 1499705137792 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:37 INFO - 1499705137801 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"
09:45:37 INFO - 1499705137805 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"
09:45:37 INFO - 1499705137808 Marionette TRACE 394 <- [1,2,null,{}]
09:45:37 INFO - 1499705137814 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:45:37 INFO - 1499705137816 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88b9ca0b-ed54-4eef-a2f6-b3d59ff29e36","ELEMENT":"88b9ca0b-ed54-4eef-a2f6-b3d59ff29e36"}}]
09:45:37 INFO - 1499705137817 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:45:37 INFO - 1499705137819 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ac11ace9-e67f-47cb-adb9-06c681617f4b","ELEMENT":"ac11ace9-e67f-47cb-adb9-06c681617f4b"}}]
09:45:37 INFO - 1499705137820 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"88b9ca0b-ed54-4eef-a2f6-b3d59ff29e36"}]
09:45:38 INFO - 1499705138054 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:38 INFO - 1499705138055 Marionette TRACE 394 <- [1,5,null,{}]
09:45:38 INFO - 1499705138056 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"ac11ace9-e67f-47cb-adb9-06c681617f4b"}]
09:45:38 INFO - 1499705138069 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:45:38 INFO - 1499705138070 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:45:38 INFO - 1499705138072 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:45:38 INFO - 1499705138074 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}]
09:45:38 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499705138076 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:45:38 INFO - 1499705138077 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:45:38 INFO - 1499705138078 Marionette TRACE 394 <- [1,9,null,{}]
09:45:38 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 333ms
09:45:38 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:45:38 INFO - 1499705138080 Marionette DEBUG Closed connection 394
09:45:38 INFO - 1499705138081 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49792
09:45:38 INFO - 1499705138082 Marionette DEBUG Closed connection 395
09:45:38 INFO - 1499705138082 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49793
09:45:38 INFO - 1499705138083 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:38 INFO - 1499705138083 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:38 INFO - 1499705138085 Marionette DEBUG Register listener.js for window 12
09:45:38 INFO - 1499705138088 Marionette TRACE 396 <- [1,1,null,{"sessionId":"75f1e6a4-5ae0-4813-9e2e-3c5d974acf45","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:38 INFO - 1499705138089 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:45:38 INFO - 1499705138090 Marionette TRACE 396 <- [1,2,null,{}]
09:45:38 INFO - 1499705138090 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:38 INFO - 1499705138091 Marionette TRACE 396 <- [1,3,null,{}]
09:45:38 INFO - 1499705138091 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:38 INFO - 1499705138092 Marionette TRACE 396 <- [1,4,null,{}]
09:45:38 INFO - 1499705138093 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}]
09:45:38 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499705138095 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:45:38 INFO - 1499705138096 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:45:38 INFO - 1499705138097 Marionette TRACE 396 <- [1,6,null,{}]
09:45:38 INFO - 1499705138098 Marionette DEBUG Closed connection 396
09:45:38 INFO - 1499705138099 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49794
09:45:38 INFO - 1499705138100 Marionette DEBUG Closed connection 397
09:45:38 INFO - 1499705138100 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49795
09:45:38 INFO - 1499705138101 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:38 INFO - 1499705138102 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:38 INFO - 1499705138104 Marionette DEBUG Register listener.js for window 12
09:45:38 INFO - 1499705138106 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b7272a5d-9a33-4bf4-9b31-ae8e00a927a0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:38 INFO - 1499705138108 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:45:38 INFO - 1499705138109 Marionette TRACE 398 <- [1,2,null,{}]
09:45:38 INFO - 1499705138110 Marionette DEBUG Closed connection 398
09:45:38 INFO - 1499705138111 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49796
09:45:38 INFO - 1499705138112 Marionette DEBUG Closed connection 399
09:45:38 INFO - 1499705138112 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49797
09:45:38 INFO - 1499705138113 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:38 INFO - 1499705138114 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}
09:45:38 INFO - 1499705138116 Marionette DEBUG Register listener.js for window 12
09:45:38 INFO - 1499705138118 Marionette TRACE 400 <- [1,1,null,{"sessionId":"9ef00e18-fbac-45e1-b2e4-ab42e1ea0d5c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2512,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpcousi2.mozrunner","moz:accessibilityChecks":false}}]
09:45:38 INFO - 1499705138120 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"}]
09:45:38 INFO - 1499705138121 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"
09:45:38 INFO - 1499705138123 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"
09:45:38 INFO - 1499705138123 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"
09:45:38 INFO - 1499705138147 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"
09:45:38 INFO - 1499705138149 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"
09:45:38 INFO - 1499705138151 Marionette TRACE 400 <- [1,2,null,{}]
09:45:38 INFO - 1499705138152 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:45:38 INFO - 1499705138154 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2729861c-880b-49b3-80f2-ec9b0d1eaae6","ELEMENT":"2729861c-880b-49b3-80f2-ec9b0d1eaae6"}}]
09:45:38 INFO - 1499705138155 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2729861c-880b-49b3-80f2-ec9b0d1eaae6"}]
09:45:38 INFO - 1499705138180 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:45:38 INFO - 1499705138182 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"2729861c-880b-49b3-80f2-ec9b0d1eaae6"}]
09:45:38 INFO - 1499705138198 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element