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]
20:13:47 INFO - 1499656427060 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}]
20:13:47 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499656427063 Marionette TRACE 352 <- [1,5,null,{"value":null}]
20:13:47 INFO - 1499656427063 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
20:13:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 143ms
20:13:47 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
20:13:47 INFO - 1499656427065 Marionette TRACE 352 <- [1,6,null,{}]
20:13:47 INFO - 1499656427067 Marionette DEBUG Closed connection 352
20:13:47 INFO - 1499656427068 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49720
20:13:47 INFO - 1499656427068 Marionette DEBUG Closed connection 353
20:13:47 INFO - 1499656427069 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49721
20:13:47 INFO - 1499656427070 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:47 INFO - 1499656427071 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427074 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427077 Marionette TRACE 354 <- [1,1,null,{"sessionId":"e945c255-42c4-4544-9929-47cb43c8535f","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427079 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
20:13:47 INFO - 1499656427080 Marionette TRACE 354 <- [1,2,null,{}]
20:13:47 INFO - 1499656427081 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:13:47 INFO - 1499656427082 Marionette TRACE 354 <- [1,3,null,{}]
20:13:47 INFO - 1499656427083 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
20:13:47 INFO - 1499656427083 Marionette TRACE 354 <- [1,4,null,{}]
20:13:47 INFO - 1499656427085 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}]
20:13:47 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499656427088 Marionette TRACE 354 <- [1,5,null,{"value":null}]
20:13:47 INFO - 1499656427088 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
20:13:47 INFO - 1499656427090 Marionette TRACE 354 <- [1,6,null,{}]
20:13:47 INFO - 1499656427092 Marionette DEBUG Closed connection 354
20:13:47 INFO - 1499656427093 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49722
20:13:47 INFO - 1499656427093 Marionette DEBUG Closed connection 355
20:13:47 INFO - 1499656427094 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49723
20:13:47 INFO - 1499656427095 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:47 INFO - 1499656427096 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427098 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427102 Marionette TRACE 356 <- [1,1,null,{"sessionId":"0fe9589d-f229-4d23-af85-07488a2dd2c5","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427104 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
20:13:47 INFO - 1499656427105 Marionette TRACE 356 <- [1,2,null,{}]
20:13:47 INFO - 1499656427107 Marionette DEBUG Closed connection 356
20:13:47 INFO - 1499656427108 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49724
20:13:47 INFO - 1499656427108 Marionette DEBUG Closed connection 357
20:13:47 INFO - 1499656427109 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49725
20:13:47 INFO - 1499656427110 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:13:47 INFO - 1499656427111 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427113 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427116 Marionette TRACE 358 <- [1,1,null,{"sessionId":"ade2e206-1683-4918-bf5b-54d6d05d4f59","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427120 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49295/clicks.html"}]
20:13:47 INFO - 1499656427121 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49295/hidden.html"
20:13:47 INFO - 1499656427132 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49295/hidden.html"
20:13:47 INFO - 1499656427133 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49295/hidden.html"
20:13:47 INFO - 1499656427153 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49295/clicks.html"
20:13:47 INFO - 1499656427154 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49295/clicks.html"
20:13:47 INFO - 1499656427157 Marionette TRACE 358 <- [1,2,null,{}]
20:13:47 INFO - 1499656427164 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
20:13:47 INFO - 1499656427167 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f02c4e36-4c88-41fa-ac1f-adad2d2e2f37","ELEMENT":"f02c4e36-4c88-41fa-ac1f-adad2d2e2f37"}}]
20:13:47 INFO - 1499656427168 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f02c4e36-4c88-41fa-ac1f-adad2d2e2f37"}]
20:13:47 INFO - 1499656427218 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49295/clicks.html"
20:13:47 INFO - 1499656427223 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49295/clicks.html"
20:13:47 INFO - 1499656427224 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49295/clicks.html"
20:13:47 INFO - 1499656427242 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49295/test.html"
20:13:47 INFO - 1499656427249 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49295/test.html"
20:13:47 INFO - 1499656427255 Marionette TRACE 358 <- [1,4,null,{}]
20:13:47 INFO - 1499656427256 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
20:13:47 INFO - 1499656427258 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0d650dcf-9469-4440-be1f-4c3ea897f80a","ELEMENT":"0d650dcf-9469-4440-be1f-4c3ea897f80a"}}]
20:13:47 INFO - 1499656427265 Marionette TRACE 358 -> [0,6,"getTitle",{}]
20:13:47 INFO - 1499656427266 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
20:13:47 INFO - 1499656427267 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}]
20:13:47 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499656427270 Marionette TRACE 358 <- [1,7,null,{"value":null}]
20:13:47 INFO - 1499656427271 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
20:13:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 207ms
20:13:47 INFO - 1499656427272 Marionette TRACE 358 <- [1,8,null,{}]
20:13:47 INFO - TEST-START | test_click.py TestClick.test_container_element
20:13:47 INFO - 1499656427274 Marionette DEBUG Closed connection 358
20:13:47 INFO - 1499656427275 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49728
20:13:47 INFO - 1499656427276 Marionette DEBUG Closed connection 359
20:13:47 INFO - 1499656427277 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49729
20:13:47 INFO - 1499656427278 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:47 INFO - 1499656427279 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427282 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427285 Marionette TRACE 360 <- [1,1,null,{"sessionId":"fe99f076-eca5-4bcb-98d6-c37b456152c7","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427287 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
20:13:47 INFO - 1499656427288 Marionette TRACE 360 <- [1,2,null,{}]
20:13:47 INFO - 1499656427288 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:13:47 INFO - 1499656427289 Marionette TRACE 360 <- [1,3,null,{}]
20:13:47 INFO - 1499656427290 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
20:13:47 INFO - 1499656427290 Marionette TRACE 360 <- [1,4,null,{}]
20:13:47 INFO - 1499656427292 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}]
20:13:47 INFO - TEST-START: test_click.py TestClick.test_container_element1499656427296 Marionette TRACE 360 <- [1,5,null,{"value":null}]
20:13:47 INFO - 1499656427297 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
20:13:47 INFO - 1499656427298 Marionette TRACE 360 <- [1,6,null,{}]
20:13:47 INFO - 1499656427300 Marionette DEBUG Closed connection 360
20:13:47 INFO - 1499656427301 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49730
20:13:47 INFO - 1499656427302 Marionette DEBUG Closed connection 361
20:13:47 INFO - 1499656427302 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49731
20:13:47 INFO - 1499656427303 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:47 INFO - 1499656427304 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427306 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427310 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1df16cf3-7a21-4c3b-af7a-913758443b54","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427313 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
20:13:47 INFO - 1499656427314 Marionette TRACE 362 <- [1,2,null,{}]
20:13:47 INFO - 1499656427316 Marionette DEBUG Closed connection 362
20:13:47 INFO - 1499656427317 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49732
20:13:47 INFO - 1499656427318 Marionette DEBUG Closed connection 363
20:13:47 INFO - 1499656427318 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49733
20:13:47 INFO - 1499656427319 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:13:47 INFO - 1499656427320 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427323 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427326 Marionette TRACE 364 <- [1,1,null,{"sessionId":"13dd78d0-a52d-42a7-8c78-0c125555584d","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427330 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"}]
20:13:47 INFO - 1499656427332 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49295/test.html"
20:13:47 INFO - 1499656427334 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49295/test.html"
20:13:47 INFO - 1499656427334 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49295/test.html"
20:13:47 INFO - 1499656427357 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"
20:13:47 INFO - 1499656427366 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"
20:13:47 INFO - 1499656427369 Marionette TRACE 364 <- [1,2,null,{}]
20:13:47 INFO - 1499656427371 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:13:47 INFO - 1499656427373 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa6f46eb-35da-4727-8aef-9de5260cc326","ELEMENT":"fa6f46eb-35da-4727-8aef-9de5260cc326"}}]
20:13:47 INFO - 1499656427374 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"fa6f46eb-35da-4727-8aef-9de5260cc326"}]
20:13:47 INFO - 1499656427627 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:13:47 INFO - 1499656427628 Marionette TRACE 364 <- [1,4,null,{}]
20:13:47 INFO - 1499656427629 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"fa6f46eb-35da-4727-8aef-9de5260cc326","name":"selected"}]
20:13:47 INFO - 1499656427633 Marionette TRACE 364 <- [1,5,null,{"value":true}]
20:13:47 INFO - 1499656427635 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}]
20:13:47 INFO - TEST-END: test_click.py TestClick.test_container_element1499656427638 Marionette TRACE 364 <- [1,6,null,{"value":null}]
20:13:47 INFO - 1499656427639 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
20:13:47 INFO - 1499656427640 Marionette TRACE 364 <- [1,7,null,{}]
20:13:47 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 367ms
20:13:47 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
20:13:47 INFO - 1499656427642 Marionette DEBUG Closed connection 364
20:13:47 INFO - 1499656427643 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49734
20:13:47 INFO - 1499656427644 Marionette DEBUG Closed connection 365
20:13:47 INFO - 1499656427644 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49735
20:13:47 INFO - 1499656427645 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:47 INFO - 1499656427646 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427649 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427651 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4d3bd759-fe18-4117-bdb3-5e81ea88a499","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427653 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
20:13:47 INFO - 1499656427654 Marionette TRACE 366 <- [1,2,null,{}]
20:13:47 INFO - 1499656427655 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:13:47 INFO - 1499656427655 Marionette TRACE 366 <- [1,3,null,{}]
20:13:47 INFO - 1499656427657 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
20:13:47 INFO - 1499656427659 Marionette TRACE 366 <- [1,4,null,{}]
20:13:47 INFO - 1499656427660 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}]
20:13:47 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499656427662 Marionette TRACE 366 <- [1,5,null,{"value":null}]
20:13:47 INFO - 1499656427663 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
20:13:47 INFO - 1499656427665 Marionette TRACE 366 <- [1,6,null,{}]
20:13:47 INFO - 1499656427667 Marionette DEBUG Closed connection 366
20:13:47 INFO - 1499656427668 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49736
20:13:47 INFO - 1499656427668 Marionette DEBUG Closed connection 367
20:13:47 INFO - 1499656427669 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49737
20:13:47 INFO - 1499656427670 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:47 INFO - 1499656427671 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427680 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427684 Marionette TRACE 368 <- [1,1,null,{"sessionId":"6b7b391e-f107-4c62-a539-3bbfd45b803f","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427686 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
20:13:47 INFO - 1499656427687 Marionette TRACE 368 <- [1,2,null,{}]
20:13:47 INFO - 1499656427689 Marionette DEBUG Closed connection 368
20:13:47 INFO - 1499656427690 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49738
20:13:47 INFO - 1499656427691 Marionette DEBUG Closed connection 369
20:13:47 INFO - 1499656427692 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49739
20:13:47 INFO - 1499656427693 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:13:47 INFO - 1499656427693 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:47 INFO - 1499656427696 Marionette DEBUG Register listener.js for window 12
20:13:47 INFO - 1499656427699 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d3f38269-fa22-4d0e-874a-470e3a31a82a","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:47 INFO - 1499656427701 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"}]
20:13:47 INFO - 1499656427703 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"
20:13:47 INFO - 1499656427705 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"
20:13:47 INFO - 1499656427705 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"
20:13:47 INFO - 1499656427723 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"
20:13:47 INFO - 1499656427728 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"
20:13:47 INFO - 1499656427739 Marionette TRACE 370 <- [1,2,null,{}]
20:13:47 INFO - 1499656427740 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:13:47 INFO - 1499656427743 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"772d4403-ad38-4a0e-8bbe-24c03eb8b998","ELEMENT":"772d4403-ad38-4a0e-8bbe-24c03eb8b998"}}]
20:13:47 INFO - 1499656427744 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"772d4403-ad38-4a0e-8bbe-24c03eb8b998"}]
20:13:47 INFO - 1499656427988 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:13:47 INFO - 1499656427989 Marionette TRACE 370 <- [1,4,null,{}]
20:13:47 INFO - 1499656427990 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"772d4403-ad38-4a0e-8bbe-24c03eb8b998","name":"selected"}]
20:13:47 INFO - 1499656427994 Marionette TRACE 370 <- [1,5,null,{"value":true}]
20:13:47 INFO - 1499656427996 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}]
20:13:48 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499656427998 Marionette TRACE 370 <- [1,6,null,{"value":null}]
20:13:48 INFO - 1499656427999 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
20:13:48 INFO - 1499656428000 Marionette TRACE 370 <- [1,7,null,{}]
20:13:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 360ms
20:13:48 INFO - TEST-START | test_click.py TestClick.test_css_transforms
20:13:48 INFO - 1499656428002 Marionette DEBUG Closed connection 370
20:13:48 INFO - 1499656428003 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49740
20:13:48 INFO - 1499656428004 Marionette DEBUG Closed connection 371
20:13:48 INFO - 1499656428004 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49741
20:13:48 INFO - 1499656428005 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:48 INFO - 1499656428006 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428008 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428011 Marionette TRACE 372 <- [1,1,null,{"sessionId":"20bccff2-fe25-49f6-9f57-8f79e08637ae","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428013 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
20:13:48 INFO - 1499656428014 Marionette TRACE 372 <- [1,2,null,{}]
20:13:48 INFO - 1499656428015 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:13:48 INFO - 1499656428015 Marionette TRACE 372 <- [1,3,null,{}]
20:13:48 INFO - 1499656428016 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
20:13:48 INFO - 1499656428016 Marionette TRACE 372 <- [1,4,null,{}]
20:13:48 INFO - 1499656428018 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}]
20:13:48 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499656428020 Marionette TRACE 372 <- [1,5,null,{"value":null}]
20:13:48 INFO - 1499656428021 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
20:13:48 INFO - 1499656428022 Marionette TRACE 372 <- [1,6,null,{}]
20:13:48 INFO - 1499656428025 Marionette DEBUG Closed connection 372
20:13:48 INFO - 1499656428025 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49742
20:13:48 INFO - 1499656428026 Marionette DEBUG Closed connection 373
20:13:48 INFO - 1499656428027 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49743
20:13:48 INFO - 1499656428028 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:48 INFO - 1499656428028 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428031 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428034 Marionette TRACE 374 <- [1,1,null,{"sessionId":"761779b4-3659-446e-b0e5-f19cfc45ab03","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428036 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
20:13:48 INFO - 1499656428037 Marionette TRACE 374 <- [1,2,null,{}]
20:13:48 INFO - 1499656428039 Marionette DEBUG Closed connection 374
20:13:48 INFO - 1499656428040 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49744
20:13:48 INFO - 1499656428041 Marionette DEBUG Closed connection 375
20:13:48 INFO - 1499656428041 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49745
20:13:48 INFO - 1499656428043 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:13:48 INFO - 1499656428044 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428047 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428050 Marionette TRACE 376 <- [1,1,null,{"sessionId":"0fcaa7de-8e35-4eec-9a1a-1896f92285d6","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428052 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"}]
20:13:48 INFO - 1499656428053 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"
20:13:48 INFO - 1499656428055 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"
20:13:48 INFO - 1499656428056 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"
20:13:48 INFO - 1499656428075 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"
20:13:48 INFO - 1499656428079 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"
20:13:48 INFO - 1499656428087 Marionette TRACE 376 <- [1,2,null,{}]
20:13:48 INFO - 1499656428089 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
20:13:48 INFO - 1499656428091 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce4396fb-d5d0-494c-9257-d74d0f7cfafe","ELEMENT":"ce4396fb-d5d0-494c-9257-d74d0f7cfafe"}}]
20:13:48 INFO - 1499656428092 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"ce4396fb-d5d0-494c-9257-d74d0f7cfafe"}]
20:13:48 INFO - 1499656428340 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:13:48 INFO - 1499656428341 Marionette TRACE 376 <- [1,4,null,{}]
20:13:48 INFO - 1499656428343 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}]
20:13:48 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499656428346 Marionette TRACE 376 <- [1,5,null,{"value":null}]
20:13:48 INFO - 1499656428347 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
20:13:48 INFO - 1499656428348 Marionette TRACE 376 <- [1,6,null,{}]
20:13:48 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 348ms
20:13:48 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
20:13:48 INFO - 1499656428350 Marionette DEBUG Closed connection 376
20:13:48 INFO - 1499656428351 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49746
20:13:48 INFO - 1499656428352 Marionette DEBUG Closed connection 377
20:13:48 INFO - 1499656428352 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49747
20:13:48 INFO - 1499656428353 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:48 INFO - 1499656428354 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428356 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428359 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d7cfd0f9-2d26-429d-b44c-07b20e25d807","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428362 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
20:13:48 INFO - 1499656428362 Marionette TRACE 378 <- [1,2,null,{}]
20:13:48 INFO - 1499656428363 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:13:48 INFO - 1499656428364 Marionette TRACE 378 <- [1,3,null,{}]
20:13:48 INFO - 1499656428365 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
20:13:48 INFO - 1499656428365 Marionette TRACE 378 <- [1,4,null,{}]
20:13:48 INFO - 1499656428366 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}]
20:13:48 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499656428369 Marionette TRACE 378 <- [1,5,null,{"value":null}]
20:13:48 INFO - 1499656428370 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
20:13:48 INFO - 1499656428371 Marionette TRACE 378 <- [1,6,null,{}]
20:13:48 INFO - 1499656428373 Marionette DEBUG Closed connection 378
20:13:48 INFO - 1499656428374 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49748
20:13:48 INFO - 1499656428374 Marionette DEBUG Closed connection 379
20:13:48 INFO - 1499656428375 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49749
20:13:48 INFO - 1499656428376 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:48 INFO - 1499656428376 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428379 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428382 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ae58c6a4-0f75-46e7-b41d-0067fba14327","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428383 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
20:13:48 INFO - 1499656428385 Marionette TRACE 380 <- [1,2,null,{}]
20:13:48 INFO - 1499656428387 Marionette DEBUG Closed connection 380
20:13:48 INFO - 1499656428387 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49750
20:13:48 INFO - 1499656428388 Marionette DEBUG Closed connection 381
20:13:48 INFO - 1499656428388 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49751
20:13:48 INFO - 1499656428390 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:13:48 INFO - 1499656428390 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428393 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428395 Marionette TRACE 382 <- [1,1,null,{"sessionId":"aa03d5e2-0627-4496-ab8d-f34d4b427b93","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428398 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"}]
20:13:48 INFO - 1499656428399 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"
20:13:48 INFO - 1499656428401 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"
20:13:48 INFO - 1499656428401 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"
20:13:48 INFO - 1499656428419 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"
20:13:48 INFO - 1499656428423 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"
20:13:48 INFO - 1499656428427 Marionette TRACE 382 <- [1,2,null,{}]
20:13:48 INFO - 1499656428433 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
20:13:48 INFO - 1499656428435 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"17cb2625-9dff-46ed-a1b8-6351f96e235a","ELEMENT":"17cb2625-9dff-46ed-a1b8-6351f96e235a"}}]
20:13:48 INFO - 1499656428436 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"17cb2625-9dff-46ed-a1b8-6351f96e235a"}]
20:13:48 INFO - 1499656428680 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:13:48 INFO - 1499656428681 Marionette TRACE 382 <- [1,4,null,{}]
20:13:48 INFO - 1499656428683 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}]
20:13:48 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499656428685 Marionette TRACE 382 <- [1,5,null,{"value":null}]
20:13:48 INFO - 1499656428686 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
20:13:48 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 339ms
20:13:48 INFO - 1499656428688 Marionette TRACE 382 <- [1,6,null,{}]
20:13:48 INFO - TEST-START | test_click.py TestClick.test_input_file
20:13:48 INFO - 1499656428690 Marionette DEBUG Closed connection 382
20:13:48 INFO - 1499656428691 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49752
20:13:48 INFO - 1499656428691 Marionette DEBUG Closed connection 383
20:13:48 INFO - 1499656428692 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49753
20:13:48 INFO - 1499656428693 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:48 INFO - 1499656428693 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428696 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428699 Marionette TRACE 384 <- [1,1,null,{"sessionId":"010a2fd3-73cd-449c-98a5-64a338defbcb","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428701 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
20:13:48 INFO - 1499656428701 Marionette TRACE 384 <- [1,2,null,{}]
20:13:48 INFO - 1499656428702 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:13:48 INFO - 1499656428703 Marionette TRACE 384 <- [1,3,null,{}]
20:13:48 INFO - 1499656428704 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
20:13:48 INFO - 1499656428704 Marionette TRACE 384 <- [1,4,null,{}]
20:13:48 INFO - 1499656428705 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}]
20:13:48 INFO - TEST-START: test_click.py TestClick.test_input_file1499656428708 Marionette TRACE 384 <- [1,5,null,{"value":null}]
20:13:48 INFO - 1499656428709 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
20:13:48 INFO - 1499656428710 Marionette TRACE 384 <- [1,6,null,{}]
20:13:48 INFO - 1499656428712 Marionette DEBUG Closed connection 384
20:13:48 INFO - 1499656428713 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49754
20:13:48 INFO - 1499656428713 Marionette DEBUG Closed connection 385
20:13:48 INFO - 1499656428714 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49755
20:13:48 INFO - 1499656428715 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:48 INFO - 1499656428716 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428719 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428722 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c07e3ed8-0b78-458c-947b-3cb749207261","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428731 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
20:13:48 INFO - 1499656428732 Marionette TRACE 386 <- [1,2,null,{}]
20:13:48 INFO - 1499656428734 Marionette DEBUG Closed connection 386
20:13:48 INFO - 1499656428735 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49756
20:13:48 INFO - 1499656428735 Marionette DEBUG Closed connection 387
20:13:48 INFO - 1499656428736 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49757
20:13:48 INFO - 1499656428737 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:13:48 INFO - 1499656428738 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428740 Marionette DEBUG Register listener.js for window 12
20:13:48 INFO - 1499656428743 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f19e1291-1656-4446-ba2d-1396316e3de6","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:48 INFO - 1499656428865 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
20:13:48 INFO - 1499656428868 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"
20:13:48 INFO - 1499656428872 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"
20:13:48 INFO - 1499656428874 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"
20:13:48 INFO - 1499656428901 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:13:48 INFO - 1499656428906 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:13:48 INFO - 1499656428912 Marionette TRACE 388 <- [1,2,null,{}]
20:13:48 INFO - 1499656428919 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
20:13:48 INFO - 1499656428924 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1cdcd041-4de9-4d64-a7e9-4f2a5a8199be","ELEMENT":"1cdcd041-4de9-4d64-a7e9-4f2a5a8199be"}}]
20:13:48 INFO - 1499656428927 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"1cdcd041-4de9-4d64-a7e9-4f2a5a8199be"}]
20:13:48 INFO - 1499656428963 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]
20:13:48 INFO - 1499656428970 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}]
20:13:48 INFO - TEST-END: test_click.py TestClick.test_input_file1499656428974 Marionette TRACE 388 <- [1,5,null,{"value":null}]
20:13:48 INFO - 1499656428975 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
20:13:48 INFO - 1499656428978 Marionette TRACE 388 <- [1,6,null,{}]
20:13:48 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 290ms
20:13:48 INFO - TEST-START | test_click.py TestClick.test_obscured_element
20:13:48 INFO - 1499656428982 Marionette DEBUG Closed connection 388
20:13:48 INFO - 1499656428983 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49758
20:13:48 INFO - 1499656428984 Marionette DEBUG Closed connection 389
20:13:48 INFO - 1499656428985 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49759
20:13:48 INFO - 1499656428986 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:48 INFO - 1499656428989 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:48 INFO - 1499656428993 Marionette DEBUG Register listener.js for window 12
20:13:49 INFO - 1499656429009 Marionette TRACE 390 <- [1,1,null,{"sessionId":"33c60319-c4d4-4258-93a0-ad34312c94de","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:49 INFO - 1499656429016 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
20:13:49 INFO - 1499656429017 Marionette TRACE 390 <- [1,2,null,{}]
20:13:49 INFO - 1499656429018 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:13:49 INFO - 1499656429019 Marionette TRACE 390 <- [1,3,null,{}]
20:13:49 INFO - 1499656429020 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
20:13:49 INFO - 1499656429021 Marionette TRACE 390 <- [1,4,null,{}]
20:13:49 INFO - 1499656429023 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}]
20:13:49 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499656429028 Marionette TRACE 390 <- [1,5,null,{"value":null}]
20:13:49 INFO - 1499656429030 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
20:13:49 INFO - 1499656429033 Marionette TRACE 390 <- [1,6,null,{}]
20:13:49 INFO - 1499656429036 Marionette DEBUG Closed connection 390
20:13:49 INFO - 1499656429037 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49760
20:13:49 INFO - 1499656429038 Marionette DEBUG Closed connection 391
20:13:49 INFO - 1499656429039 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49761
20:13:49 INFO - 1499656429040 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:49 INFO - 1499656429041 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:49 INFO - 1499656429044 Marionette DEBUG Register listener.js for window 12
20:13:49 INFO - 1499656429048 Marionette TRACE 392 <- [1,1,null,{"sessionId":"df3d7fa3-1f7d-4090-97bb-d26c4917f937","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:49 INFO - 1499656429051 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
20:13:49 INFO - 1499656429053 Marionette TRACE 392 <- [1,2,null,{}]
20:13:49 INFO - 1499656429055 Marionette DEBUG Closed connection 392
20:13:49 INFO - 1499656429064 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49762
20:13:49 INFO - 1499656429066 Marionette DEBUG Closed connection 393
20:13:49 INFO - 1499656429067 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49763
20:13:49 INFO - 1499656429068 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:13:49 INFO - 1499656429070 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:49 INFO - 1499656429072 Marionette DEBUG Register listener.js for window 12
20:13:49 INFO - 1499656429077 Marionette TRACE 394 <- [1,1,null,{"sessionId":"4bd3e90c-6dff-42d8-9bfc-c7319ef64bc3","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:49 INFO - 1499656429080 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"}]
20:13:49 INFO - 1499656429082 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:13:49 INFO - 1499656429085 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:13:49 INFO - 1499656429086 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:13:49 INFO - 1499656429123 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"
20:13:49 INFO - 1499656429126 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"
20:13:49 INFO - 1499656429137 Marionette TRACE 394 <- [1,2,null,{}]
20:13:49 INFO - 1499656429138 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
20:13:49 INFO - 1499656429141 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"45329848-374f-47ae-a3f7-469d37db1482","ELEMENT":"45329848-374f-47ae-a3f7-469d37db1482"}}]
20:13:49 INFO - 1499656429148 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
20:13:49 INFO - 1499656429151 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"607eba2e-9fff-4672-92a3-a91ac1072c1a","ELEMENT":"607eba2e-9fff-4672-92a3-a91ac1072c1a"}}]
20:13:49 INFO - 1499656429157 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"45329848-374f-47ae-a3f7-469d37db1482"}]
20:13:49 INFO - 1499656429399 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:13:49 INFO - 1499656429400 Marionette TRACE 394 <- [1,5,null,{}]
20:13:49 INFO - 1499656429401 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"607eba2e-9fff-4672-92a3-a91ac1072c1a"}]
20:13:49 INFO - 1499656429420 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]
20:13:49 INFO - 1499656429422 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
20:13:49 INFO - 1499656429426 Marionette TRACE 394 <- [1,7,null,{"value":false}]
20:13:49 INFO - 1499656429428 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}]
20:13:49 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499656429430 Marionette TRACE 394 <- [1,8,null,{"value":null}]
20:13:49 INFO - 1499656429431 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
20:13:49 INFO - 1499656429433 Marionette TRACE 394 <- [1,9,null,{}]
20:13:49 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 454ms
20:13:49 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
20:13:49 INFO - 1499656429436 Marionette DEBUG Closed connection 394
20:13:49 INFO - 1499656429437 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49764
20:13:49 INFO - 1499656429438 Marionette DEBUG Closed connection 395
20:13:49 INFO - 1499656429438 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49765
20:13:49 INFO - 1499656429439 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:49 INFO - 1499656429440 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:49 INFO - 1499656429444 Marionette DEBUG Register listener.js for window 12
20:13:49 INFO - 1499656429448 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a27308d7-284b-4462-9cda-7c9555b2feb9","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:49 INFO - 1499656429450 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
20:13:49 INFO - 1499656429451 Marionette TRACE 396 <- [1,2,null,{}]
20:13:49 INFO - 1499656429452 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:13:49 INFO - 1499656429452 Marionette TRACE 396 <- [1,3,null,{}]
20:13:49 INFO - 1499656429453 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
20:13:49 INFO - 1499656429454 Marionette TRACE 396 <- [1,4,null,{}]
20:13:49 INFO - 1499656429455 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}]
20:13:49 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499656429458 Marionette TRACE 396 <- [1,5,null,{"value":null}]
20:13:49 INFO - 1499656429459 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
20:13:49 INFO - 1499656429460 Marionette TRACE 396 <- [1,6,null,{}]
20:13:49 INFO - 1499656429463 Marionette DEBUG Closed connection 396
20:13:49 INFO - 1499656429464 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49766
20:13:49 INFO - 1499656429464 Marionette DEBUG Closed connection 397
20:13:49 INFO - 1499656429465 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49767
20:13:49 INFO - 1499656429466 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:13:49 INFO - 1499656429467 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:49 INFO - 1499656429470 Marionette DEBUG Register listener.js for window 12
20:13:49 INFO - 1499656429476 Marionette TRACE 398 <- [1,1,null,{"sessionId":"878d27c5-9b46-4fd7-adc3-5cd82074f327","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:49 INFO - 1499656429479 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
20:13:49 INFO - 1499656429480 Marionette TRACE 398 <- [1,2,null,{}]
20:13:49 INFO - 1499656429483 Marionette DEBUG Closed connection 398
20:13:49 INFO - 1499656429485 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49768
20:13:49 INFO - 1499656429486 Marionette DEBUG Closed connection 399
20:13:49 INFO - 1499656429487 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49769
20:13:49 INFO - 1499656429488 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:13:49 INFO - 1499656429489 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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}
20:13:49 INFO - 1499656429492 Marionette DEBUG Register listener.js for window 12
20:13:49 INFO - 1499656429496 Marionette TRACE 400 <- [1,1,null,{"sessionId":"34d900bc-87c3-4fba-9535-4e81c893fdfa","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":3432,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-139\\AppData\\Local\\Temp\\tmpxwni8w.mozrunner","moz:accessibilityChecks":false}}]
20:13:49 INFO - 1499656429499 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"}]
20:13:49 INFO - 1499656429500 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"
20:13:49 INFO - 1499656429503 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"
20:13:49 INFO - 1499656429503 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"
20:13:49 INFO - 1499656429525 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"
20:13:49 INFO - 1499656429529 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"
20:13:49 INFO - 1499656429539 Marionette TRACE 400 <- [1,2,null,{}]
20:13:49 INFO - 1499656429540 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
20:13:49 INFO - 1499656429544 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa897b16-8dd9-45ee-b14c-808cf6bd3efa","ELEMENT":"aa897b16-8dd9-45ee-b14c-808cf6bd3efa"}}]
20:13:49 INFO - 1499656429545 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"aa897b16-8dd9-45ee-b14c-808cf6bd3efa"}]
20:13:49 INFO - 1499656429571 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
20:13:49 INFO - 1499656429572 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"aa897b16-8dd9-45ee-b14c-808cf6bd3efa"}]
20:13:49 INFO - 1499656429590 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element