could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
05:00:26 INFO - 1498910426109 Marionette TRACE 359 -> [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}]
05:00:26 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498910426112 Marionette TRACE 359 <- [1,5,null,{"value":null}]
05:00:26 INFO - 1498910426113 Marionette TRACE 359 -> [0,6,"deleteSession",{}]
05:00:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 131ms
05:00:26 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:00:26 INFO - 1498910426115 Marionette TRACE 359 <- [1,6,null,{}]
05:00:26 INFO - 1498910426116 Marionette DEBUG Closed connection 359
05:00:26 INFO - 1498910426117 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50444
05:00:26 INFO - 1498910426118 Marionette DEBUG Closed connection 360
05:00:26 INFO - 1498910426119 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50445
05:00:26 INFO - 1498910426119 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:26 INFO - 1498910426120 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426122 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426125 Marionette TRACE 361 <- [1,1,null,{"sessionId":"d4fc732a-1c6b-43ee-81da-661f0ddfe2dc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426126 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
05:00:26 INFO - 1498910426127 Marionette TRACE 361 <- [1,2,null,{}]
05:00:26 INFO - 1498910426128 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:00:26 INFO - 1498910426128 Marionette TRACE 361 <- [1,3,null,{}]
05:00:26 INFO - 1498910426129 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
05:00:26 INFO - 1498910426129 Marionette TRACE 361 <- [1,4,null,{}]
05:00:26 INFO - 1498910426131 Marionette TRACE 361 -> [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}]
05:00:26 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498910426134 Marionette TRACE 361 <- [1,5,null,{"value":null}]
05:00:26 INFO - 1498910426136 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
05:00:26 INFO - 1498910426137 Marionette TRACE 361 <- [1,6,null,{}]
05:00:26 INFO - 1498910426139 Marionette DEBUG Closed connection 361
05:00:26 INFO - 1498910426140 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50446
05:00:26 INFO - 1498910426140 Marionette DEBUG Closed connection 362
05:00:26 INFO - 1498910426141 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50447
05:00:26 INFO - 1498910426142 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:26 INFO - 1498910426142 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426145 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426148 Marionette TRACE 363 <- [1,1,null,{"sessionId":"8a6ecfe9-c036-4c7c-ad07-309ae97b6f38","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426149 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
05:00:26 INFO - 1498910426150 Marionette TRACE 363 <- [1,2,null,{}]
05:00:26 INFO - 1498910426151 Marionette DEBUG Closed connection 363
05:00:26 INFO - 1498910426152 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50448
05:00:26 INFO - 1498910426152 Marionette DEBUG Closed connection 364
05:00:26 INFO - 1498910426153 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50449
05:00:26 INFO - 1498910426154 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:00:26 INFO - 1498910426155 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426157 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426160 Marionette TRACE 365 <- [1,1,null,{"sessionId":"c195f7db-ab8c-459f-a6ab-d2fdc1f10455","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426161 Marionette TRACE 365 -> [0,2,"get",{"url":"http://127.0.0.1:50045/clicks.html"}]
05:00:26 INFO - 1498910426163 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50045/hidden.html"
05:00:26 INFO - 1498910426170 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50045/hidden.html"
05:00:26 INFO - 1498910426171 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50045/hidden.html"
05:00:26 INFO - 1498910426178 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50045/clicks.html"
05:00:26 INFO - 1498910426183 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50045/clicks.html"
05:00:26 INFO - 1498910426185 Marionette TRACE 365 <- [1,2,null,{}]
05:00:26 INFO - 1498910426188 Marionette TRACE 365 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:00:26 INFO - 1498910426197 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3249e56f-ef3c-4836-8c2f-a381c664126e","ELEMENT":"3249e56f-ef3c-4836-8c2f-a381c664126e"}}]
05:00:26 INFO - 1498910426199 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"3249e56f-ef3c-4836-8c2f-a381c664126e"}]
05:00:26 INFO - 1498910426237 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50045/clicks.html"
05:00:26 INFO - 1498910426244 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50045/clicks.html"
05:00:26 INFO - 1498910426244 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50045/clicks.html"
05:00:26 INFO - 1498910426251 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50045/test.html"
05:00:26 INFO - 1498910426254 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50045/test.html"
05:00:26 INFO - 1498910426260 Marionette TRACE 365 <- [1,4,null,{}]
05:00:26 INFO - 1498910426265 Marionette TRACE 365 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:00:26 INFO - 1498910426267 Marionette TRACE 365 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7ee3dc0c-08ee-4e47-8a5a-82ea28dec121","ELEMENT":"7ee3dc0c-08ee-4e47-8a5a-82ea28dec121"}}]
05:00:26 INFO - 1498910426268 Marionette TRACE 365 -> [0,6,"getTitle",{}]
05:00:26 INFO - 1498910426271 Marionette TRACE 365 <- [1,6,null,{"value":"Marionette Test"}]
05:00:26 INFO - 1498910426278 Marionette TRACE 365 -> [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}]
05:00:26 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498910426281 Marionette TRACE 365 <- [1,7,null,{"value":null}]
05:00:26 INFO - 1498910426282 Marionette TRACE 365 -> [0,8,"deleteSession",{}]
05:00:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 169ms
05:00:26 INFO - 1498910426283 Marionette TRACE 365 <- [1,8,null,{}]
05:00:26 INFO - TEST-START | test_click.py TestClick.test_container_element
05:00:26 INFO - 1498910426284 Marionette DEBUG Closed connection 365
05:00:26 INFO - 1498910426286 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50452
05:00:26 INFO - 1498910426287 Marionette DEBUG Closed connection 366
05:00:26 INFO - 1498910426288 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50453
05:00:26 INFO - 1498910426289 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:26 INFO - 1498910426289 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426292 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426295 Marionette TRACE 367 <- [1,1,null,{"sessionId":"6ff52856-20b6-4a68-a390-a12a4a289f9e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426296 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
05:00:26 INFO - 1498910426296 Marionette TRACE 367 <- [1,2,null,{}]
05:00:26 INFO - 1498910426297 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:00:26 INFO - 1498910426298 Marionette TRACE 367 <- [1,3,null,{}]
05:00:26 INFO - 1498910426299 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
05:00:26 INFO - 1498910426299 Marionette TRACE 367 <- [1,4,null,{}]
05:00:26 INFO - 1498910426301 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:00:26 INFO - TEST-START: test_click.py TestClick.test_container_element1498910426303 Marionette TRACE 367 <- [1,5,null,{"value":null}]
05:00:26 INFO - 1498910426304 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
05:00:26 INFO - 1498910426305 Marionette TRACE 367 <- [1,6,null,{}]
05:00:26 INFO - 1498910426307 Marionette DEBUG Closed connection 367
05:00:26 INFO - 1498910426308 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50454
05:00:26 INFO - 1498910426308 Marionette DEBUG Closed connection 368
05:00:26 INFO - 1498910426309 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50455
05:00:26 INFO - 1498910426310 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:26 INFO - 1498910426311 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426313 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426316 Marionette TRACE 369 <- [1,1,null,{"sessionId":"b22399e6-fc03-4e1a-a32a-db555ed2dd76","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426317 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
05:00:26 INFO - 1498910426318 Marionette TRACE 369 <- [1,2,null,{}]
05:00:26 INFO - 1498910426320 Marionette DEBUG Closed connection 369
05:00:26 INFO - 1498910426320 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50456
05:00:26 INFO - 1498910426321 Marionette DEBUG Closed connection 370
05:00:26 INFO - 1498910426322 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50457
05:00:26 INFO - 1498910426323 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:00:26 INFO - 1498910426324 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426326 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426329 Marionette TRACE 371 <- [1,1,null,{"sessionId":"d6d8f31f-583a-4f0a-980d-859a858732f9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426330 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%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"}]
05:00:26 INFO - 1498910426332 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50045/test.html"
05:00:26 INFO - 1498910426333 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50045/test.html"
05:00:26 INFO - 1498910426334 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50045/test.html"
05:00:26 INFO - 1498910426344 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"
05:00:26 INFO - 1498910426345 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"
05:00:26 INFO - 1498910426353 Marionette TRACE 371 <- [1,2,null,{}]
05:00:26 INFO - 1498910426362 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:00:26 INFO - 1498910426367 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"269453db-7a71-4168-aa01-b2d95a1e3c78","ELEMENT":"269453db-7a71-4168-aa01-b2d95a1e3c78"}}]
05:00:26 INFO - 1498910426368 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"269453db-7a71-4168-aa01-b2d95a1e3c78"}]
05:00:26 INFO - 1498910426614 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:00:26 INFO - 1498910426615 Marionette TRACE 371 <- [1,4,null,{}]
05:00:26 INFO - 1498910426616 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"269453db-7a71-4168-aa01-b2d95a1e3c78","name":"selected"}]
05:00:26 INFO - 1498910426620 Marionette TRACE 371 <- [1,5,null,{"value":true}]
05:00:26 INFO - 1498910426622 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:00:26 INFO - TEST-END: test_click.py TestClick.test_container_element1498910426625 Marionette TRACE 371 <- [1,6,null,{"value":null}]
05:00:26 INFO - 1498910426626 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
05:00:26 INFO - 1498910426627 Marionette TRACE 371 <- [1,7,null,{}]
05:00:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 344ms
05:00:26 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:00:26 INFO - 1498910426629 Marionette DEBUG Closed connection 371
05:00:26 INFO - 1498910426630 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50458
05:00:26 INFO - 1498910426631 Marionette DEBUG Closed connection 372
05:00:26 INFO - 1498910426631 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50459
05:00:26 INFO - 1498910426632 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:26 INFO - 1498910426633 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426635 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426638 Marionette TRACE 373 <- [1,1,null,{"sessionId":"b25487e1-d252-4a26-a268-e2799291e2f4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426639 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
05:00:26 INFO - 1498910426640 Marionette TRACE 373 <- [1,2,null,{}]
05:00:26 INFO - 1498910426641 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:00:26 INFO - 1498910426641 Marionette TRACE 373 <- [1,3,null,{}]
05:00:26 INFO - 1498910426642 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
05:00:26 INFO - 1498910426642 Marionette TRACE 373 <- [1,4,null,{}]
05:00:26 INFO - 1498910426644 Marionette TRACE 373 -> [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}]
05:00:26 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498910426647 Marionette TRACE 373 <- [1,5,null,{"value":null}]
05:00:26 INFO - 1498910426647 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
05:00:26 INFO - 1498910426649 Marionette TRACE 373 <- [1,6,null,{}]
05:00:26 INFO - 1498910426650 Marionette DEBUG Closed connection 373
05:00:26 INFO - 1498910426651 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50460
05:00:26 INFO - 1498910426652 Marionette DEBUG Closed connection 374
05:00:26 INFO - 1498910426652 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50461
05:00:26 INFO - 1498910426653 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:26 INFO - 1498910426654 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426656 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426659 Marionette TRACE 375 <- [1,1,null,{"sessionId":"ee9f4caf-65c5-4241-aca4-d221434d8f7b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426660 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
05:00:26 INFO - 1498910426661 Marionette TRACE 375 <- [1,2,null,{}]
05:00:26 INFO - 1498910426663 Marionette DEBUG Closed connection 375
05:00:26 INFO - 1498910426663 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50462
05:00:26 INFO - 1498910426664 Marionette DEBUG Closed connection 376
05:00:26 INFO - 1498910426665 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50463
05:00:26 INFO - 1498910426666 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:00:26 INFO - 1498910426666 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426668 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426671 Marionette TRACE 377 <- [1,1,null,{"sessionId":"cb69485b-f7c6-4419-a815-9c95955f44fb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426672 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%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"}]
05:00:26 INFO - 1498910426674 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"
05:00:26 INFO - 1498910426676 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"
05:00:26 INFO - 1498910426676 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"
05:00:26 INFO - 1498910426682 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"
05:00:26 INFO - 1498910426685 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"
05:00:26 INFO - 1498910426687 Marionette TRACE 377 <- [1,2,null,{}]
05:00:26 INFO - 1498910426696 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:00:26 INFO - 1498910426699 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a3ca22d-aba3-4871-b495-a640dca08c75","ELEMENT":"9a3ca22d-aba3-4871-b495-a640dca08c75"}}]
05:00:26 INFO - 1498910426700 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"9a3ca22d-aba3-4871-b495-a640dca08c75"}]
05:00:26 INFO - 1498910426940 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:00:26 INFO - 1498910426940 Marionette TRACE 377 <- [1,4,null,{}]
05:00:26 INFO - 1498910426942 Marionette TRACE 377 -> [0,5,"getElementProperty",{"id":"9a3ca22d-aba3-4871-b495-a640dca08c75","name":"selected"}]
05:00:26 INFO - 1498910426946 Marionette TRACE 377 <- [1,5,null,{"value":true}]
05:00:26 INFO - 1498910426948 Marionette TRACE 377 -> [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}]
05:00:26 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498910426950 Marionette TRACE 377 <- [1,6,null,{"value":null}]
05:00:26 INFO - 1498910426951 Marionette TRACE 377 -> [0,7,"deleteSession",{}]
05:00:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 325ms
05:00:26 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:00:26 INFO - 1498910426953 Marionette TRACE 377 <- [1,7,null,{}]
05:00:26 INFO - 1498910426954 Marionette DEBUG Closed connection 377
05:00:26 INFO - 1498910426955 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50464
05:00:26 INFO - 1498910426956 Marionette DEBUG Closed connection 378
05:00:26 INFO - 1498910426957 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50465
05:00:26 INFO - 1498910426958 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:26 INFO - 1498910426958 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:26 INFO - 1498910426960 Marionette DEBUG Register listener.js for window 2147483649
05:00:26 INFO - 1498910426963 Marionette TRACE 379 <- [1,1,null,{"sessionId":"8ad35d14-1ea6-4432-a658-b932f8a29912","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:26 INFO - 1498910426964 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
05:00:26 INFO - 1498910426965 Marionette TRACE 379 <- [1,2,null,{}]
05:00:26 INFO - 1498910426966 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:00:26 INFO - 1498910426966 Marionette TRACE 379 <- [1,3,null,{}]
05:00:26 INFO - 1498910426967 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
05:00:26 INFO - 1498910426967 Marionette TRACE 379 <- [1,4,null,{}]
05:00:26 INFO - 1498910426969 Marionette TRACE 379 -> [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}]
05:00:26 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498910426971 Marionette TRACE 379 <- [1,5,null,{"value":null}]
05:00:26 INFO - 1498910426972 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
05:00:26 INFO - 1498910426974 Marionette TRACE 379 <- [1,6,null,{}]
05:00:26 INFO - 1498910426975 Marionette DEBUG Closed connection 379
05:00:26 INFO - 1498910426976 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50466
05:00:26 INFO - 1498910426976 Marionette DEBUG Closed connection 380
05:00:27 INFO - 1498910426977 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50467
05:00:27 INFO - 1498910426978 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:27 INFO - 1498910426978 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910426981 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910426983 Marionette TRACE 381 <- [1,1,null,{"sessionId":"f94d9b93-b30e-4dd7-8f27-6c00f288718d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910426984 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
05:00:27 INFO - 1498910426986 Marionette TRACE 381 <- [1,2,null,{}]
05:00:27 INFO - 1498910426987 Marionette DEBUG Closed connection 381
05:00:27 INFO - 1498910426988 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50468
05:00:27 INFO - 1498910426988 Marionette DEBUG Closed connection 382
05:00:27 INFO - 1498910426989 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50469
05:00:27 INFO - 1498910426990 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:00:27 INFO - 1498910426991 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910426993 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910426996 Marionette TRACE 383 <- [1,1,null,{"sessionId":"0819d6ff-9f24-477a-84f5-47e6ee9f0d2d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910426997 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%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"}]
05:00:27 INFO - 1498910426999 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"
05:00:27 INFO - 1498910427001 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"
05:00:27 INFO - 1498910427001 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"
05:00:27 INFO - 1498910427009 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"
05:00:27 INFO - 1498910427010 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"
05:00:27 INFO - 1498910427018 Marionette TRACE 383 <- [1,2,null,{}]
05:00:27 INFO - 1498910427021 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:00:27 INFO - 1498910427027 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1e879dc5-b205-4bd8-ba0f-a312d8515d84","ELEMENT":"1e879dc5-b205-4bd8-ba0f-a312d8515d84"}}]
05:00:27 INFO - 1498910427028 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"1e879dc5-b205-4bd8-ba0f-a312d8515d84"}]
05:00:27 INFO - 1498910427288 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:00:27 INFO - 1498910427327 Marionette TRACE 383 <- [1,4,null,{}]
05:00:27 INFO - 1498910427336 Marionette TRACE 383 -> [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}]
05:00:27 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498910427354 Marionette TRACE 383 <- [1,5,null,{"value":null}]
05:00:27 INFO - 1498910427356 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
05:00:27 INFO - 1498910427359 Marionette TRACE 383 <- [1,6,null,{}]
05:00:27 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 406ms
05:00:27 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:00:27 INFO - 1498910427362 Marionette DEBUG Closed connection 383
05:00:27 INFO - 1498910427363 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50470
05:00:27 INFO - 1498910427367 Marionette DEBUG Closed connection 384
05:00:27 INFO - 1498910427368 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50471
05:00:27 INFO - 1498910427379 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:27 INFO - 1498910427382 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427385 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427392 Marionette TRACE 385 <- [1,1,null,{"sessionId":"195d72c7-a751-4ccd-a338-0bdfc9c55dd7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427394 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
05:00:27 INFO - 1498910427395 Marionette TRACE 385 <- [1,2,null,{}]
05:00:27 INFO - 1498910427396 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:00:27 INFO - 1498910427397 Marionette TRACE 385 <- [1,3,null,{}]
05:00:27 INFO - 1498910427398 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
05:00:27 INFO - 1498910427399 Marionette TRACE 385 <- [1,4,null,{}]
05:00:27 INFO - 1498910427401 Marionette TRACE 385 -> [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}]
05:00:27 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498910427405 Marionette TRACE 385 <- [1,5,null,{"value":null}]
05:00:27 INFO - 1498910427406 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
05:00:27 INFO - 1498910427409 Marionette TRACE 385 <- [1,6,null,{}]
05:00:27 INFO - 1498910427410 Marionette DEBUG Closed connection 385
05:00:27 INFO - 1498910427411 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50472
05:00:27 INFO - 1498910427412 Marionette DEBUG Closed connection 386
05:00:27 INFO - 1498910427414 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50473
05:00:27 INFO - 1498910427415 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:27 INFO - 1498910427416 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427419 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427424 Marionette TRACE 387 <- [1,1,null,{"sessionId":"b89cc453-4a9c-4b6e-a035-e4b6907df663","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427425 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
05:00:27 INFO - 1498910427427 Marionette TRACE 387 <- [1,2,null,{}]
05:00:27 INFO - 1498910427429 Marionette DEBUG Closed connection 387
05:00:27 INFO - 1498910427430 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50474
05:00:27 INFO - 1498910427431 Marionette DEBUG Closed connection 388
05:00:27 INFO - 1498910427432 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50475
05:00:27 INFO - 1498910427435 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:00:27 INFO - 1498910427436 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427439 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427443 Marionette TRACE 389 <- [1,1,null,{"sessionId":"35ac52b2-04b7-49ed-9c1b-b8790092a2f6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427444 Marionette TRACE 389 -> [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"}]
05:00:27 INFO - 1498910427447 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"
05:00:27 INFO - 1498910427450 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"
05:00:27 INFO - 1498910427450 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"
05:00:27 INFO - 1498910427463 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"
05:00:27 INFO - 1498910427465 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"
05:00:27 INFO - 1498910427474 Marionette TRACE 389 <- [1,2,null,{}]
05:00:27 INFO - 1498910427479 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:00:27 INFO - 1498910427484 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da051afd-14af-4466-b839-7416af0bf2e2","ELEMENT":"da051afd-14af-4466-b839-7416af0bf2e2"}}]
05:00:27 INFO - 1498910427485 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"da051afd-14af-4466-b839-7416af0bf2e2"}]
05:00:27 INFO - 1498910427731 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:00:27 INFO - 1498910427732 Marionette TRACE 389 <- [1,4,null,{}]
05:00:27 INFO - 1498910427734 Marionette TRACE 389 -> [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}]
05:00:27 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 380ms
05:00:27 INFO - TEST-START | test_click.py TestClick.test_input_file
05:00:27 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498910427738 Marionette TRACE 389 <- [1,5,null,{"value":null}]
05:00:27 INFO - 1498910427739 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
05:00:27 INFO - 1498910427740 Marionette TRACE 389 <- [1,6,null,{}]
05:00:27 INFO - 1498910427742 Marionette DEBUG Closed connection 389
05:00:27 INFO - 1498910427742 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50476
05:00:27 INFO - 1498910427743 Marionette DEBUG Closed connection 390
05:00:27 INFO - 1498910427744 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50477
05:00:27 INFO - 1498910427745 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:27 INFO - 1498910427746 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427749 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427753 Marionette TRACE 391 <- [1,1,null,{"sessionId":"4fca1017-83be-414e-8e83-88eac66e36c8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427754 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
05:00:27 INFO - 1498910427755 Marionette TRACE 391 <- [1,2,null,{}]
05:00:27 INFO - 1498910427756 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:00:27 INFO - 1498910427756 Marionette TRACE 391 <- [1,3,null,{}]
05:00:27 INFO - 1498910427757 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
05:00:27 INFO - 1498910427758 Marionette TRACE 391 <- [1,4,null,{}]
05:00:27 INFO - 1498910427760 Marionette TRACE 391 -> [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}]
05:00:27 INFO - TEST-START: test_click.py TestClick.test_input_file1498910427763 Marionette TRACE 391 <- [1,5,null,{"value":null}]
05:00:27 INFO - 1498910427764 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
05:00:27 INFO - 1498910427765 Marionette TRACE 391 <- [1,6,null,{}]
05:00:27 INFO - 1498910427767 Marionette DEBUG Closed connection 391
05:00:27 INFO - 1498910427768 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50478
05:00:27 INFO - 1498910427769 Marionette DEBUG Closed connection 392
05:00:27 INFO - 1498910427769 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50479
05:00:27 INFO - 1498910427770 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:27 INFO - 1498910427771 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427774 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427777 Marionette TRACE 393 <- [1,1,null,{"sessionId":"d9010902-aa6e-4562-bb90-4968cd8482d6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427779 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
05:00:27 INFO - 1498910427781 Marionette TRACE 393 <- [1,2,null,{}]
05:00:27 INFO - 1498910427784 Marionette DEBUG Closed connection 393
05:00:27 INFO - 1498910427785 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50480
05:00:27 INFO - 1498910427787 Marionette DEBUG Closed connection 394
05:00:27 INFO - 1498910427787 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50481
05:00:27 INFO - 1498910427788 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:00:27 INFO - 1498910427790 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427793 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427797 Marionette TRACE 395 <- [1,1,null,{"sessionId":"79eddd6e-1a34-494d-9f5c-3bd195992df6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427798 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:00:27 INFO - 1498910427800 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"
05:00:27 INFO - 1498910427802 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"
05:00:27 INFO - 1498910427802 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"
05:00:27 INFO - 1498910427809 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:00:27 INFO - 1498910427814 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:00:27 INFO - 1498910427821 Marionette TRACE 395 <- [1,2,null,{}]
05:00:27 INFO - 1498910427835 Marionette TRACE 395 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:00:27 INFO - 1498910427838 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"195d6e26-28bf-4033-b454-7fe4bbbf9bcf","ELEMENT":"195d6e26-28bf-4033-b454-7fe4bbbf9bcf"}}]
05:00:27 INFO - 1498910427844 Marionette TRACE 395 -> [0,4,"clickElement",{"id":"195d6e26-28bf-4033-b454-7fe4bbbf9bcf"}]
05:00:27 INFO - 1498910427871 Marionette TRACE 395 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
05:00:27 INFO - 1498910427873 Marionette TRACE 395 -> [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}]
05:00:27 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 139ms
05:00:27 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:00:27 INFO - TEST-END: test_click.py TestClick.test_input_file1498910427877 Marionette TRACE 395 <- [1,5,null,{"value":null}]
05:00:27 INFO - 1498910427878 Marionette TRACE 395 -> [0,6,"deleteSession",{}]
05:00:27 INFO - 1498910427879 Marionette TRACE 395 <- [1,6,null,{}]
05:00:27 INFO - 1498910427880 Marionette DEBUG Closed connection 395
05:00:27 INFO - 1498910427882 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50482
05:00:27 INFO - 1498910427882 Marionette DEBUG Closed connection 396
05:00:27 INFO - 1498910427883 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50483
05:00:27 INFO - 1498910427884 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:27 INFO - 1498910427885 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427887 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427890 Marionette TRACE 397 <- [1,1,null,{"sessionId":"c1ea9574-b64a-4ae1-b0b9-4c96cbbfa01c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427892 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
05:00:27 INFO - 1498910427892 Marionette TRACE 397 <- [1,2,null,{}]
05:00:27 INFO - 1498910427893 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:00:27 INFO - 1498910427894 Marionette TRACE 397 <- [1,3,null,{}]
05:00:27 INFO - 1498910427895 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
05:00:27 INFO - 1498910427895 Marionette TRACE 397 <- [1,4,null,{}]
05:00:27 INFO - 1498910427897 Marionette TRACE 397 -> [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}]
05:00:27 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498910427900 Marionette TRACE 397 <- [1,5,null,{"value":null}]
05:00:27 INFO - 1498910427902 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
05:00:27 INFO - 1498910427903 Marionette TRACE 397 <- [1,6,null,{}]
05:00:27 INFO - 1498910427904 Marionette DEBUG Closed connection 397
05:00:27 INFO - 1498910427905 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50484
05:00:27 INFO - 1498910427906 Marionette DEBUG Closed connection 398
05:00:27 INFO - 1498910427907 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50485
05:00:27 INFO - 1498910427908 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:27 INFO - 1498910427908 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427911 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427914 Marionette TRACE 399 <- [1,1,null,{"sessionId":"db865029-1a8d-4351-8056-a91a7b5b9f09","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427915 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
05:00:27 INFO - 1498910427917 Marionette TRACE 399 <- [1,2,null,{}]
05:00:27 INFO - 1498910427918 Marionette DEBUG Closed connection 399
05:00:27 INFO - 1498910427919 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50486
05:00:27 INFO - 1498910427919 Marionette DEBUG Closed connection 400
05:00:27 INFO - 1498910427920 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50487
05:00:27 INFO - 1498910427921 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:00:27 INFO - 1498910427922 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:27 INFO - 1498910427925 Marionette DEBUG Register listener.js for window 2147483649
05:00:27 INFO - 1498910427928 Marionette TRACE 401 <- [1,1,null,{"sessionId":"d25c53e5-3d78-4775-9e41-2ee3fd830cf8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:27 INFO - 1498910427929 Marionette TRACE 401 -> [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"}]
05:00:27 INFO - 1498910427931 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:00:27 INFO - 1498910427933 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:00:27 INFO - 1498910427933 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:00:27 INFO - 1498910427944 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"
05:00:27 INFO - 1498910427946 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"
05:00:27 INFO - 1498910427952 Marionette TRACE 401 <- [1,2,null,{}]
05:00:27 INFO - 1498910427961 Marionette TRACE 401 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:00:27 INFO - 1498910427966 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b8d1a42-7ad8-4d6f-a796-7a2d95485082","ELEMENT":"0b8d1a42-7ad8-4d6f-a796-7a2d95485082"}}]
05:00:27 INFO - 1498910427967 Marionette TRACE 401 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:00:27 INFO - 1498910427970 Marionette TRACE 401 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5ea128ae-4ce0-4c7e-8e62-64eebaf9e383","ELEMENT":"5ea128ae-4ce0-4c7e-8e62-64eebaf9e383"}}]
05:00:27 INFO - 1498910427971 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"0b8d1a42-7ad8-4d6f-a796-7a2d95485082"}]
05:00:28 INFO - 1498910428216 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:00:28 INFO - 1498910428217 Marionette TRACE 401 <- [1,5,null,{}]
05:00:28 INFO - 1498910428218 Marionette TRACE 401 -> [0,6,"clickElement",{"id":"5ea128ae-4ce0-4c7e-8e62-64eebaf9e383"}]
05:00:28 INFO - 1498910428234 Marionette TRACE 401 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
05:00:28 INFO - 1498910428236 Marionette TRACE 401 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:00:28 INFO - 1498910428239 Marionette TRACE 401 <- [1,7,null,{"value":false}]
05:00:28 INFO - 1498910428241 Marionette TRACE 401 -> [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}]
05:00:28 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498910428244 Marionette TRACE 401 <- [1,8,null,{"value":null}]
05:00:28 INFO - 1498910428245 Marionette TRACE 401 -> [0,9,"deleteSession",{}]
05:00:28 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 366ms
05:00:28 INFO - 1498910428246 Marionette TRACE 401 <- [1,9,null,{}]
05:00:28 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:00:28 INFO - 1498910428247 Marionette DEBUG Closed connection 401
05:00:28 INFO - 1498910428249 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50488
05:00:28 INFO - 1498910428249 Marionette DEBUG Closed connection 402
05:00:28 INFO - 1498910428250 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50489
05:00:28 INFO - 1498910428251 Marionette TRACE 403 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:28 INFO - 1498910428252 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:28 INFO - 1498910428254 Marionette DEBUG Register listener.js for window 2147483649
05:00:28 INFO - 1498910428258 Marionette TRACE 403 <- [1,1,null,{"sessionId":"82097729-c591-4b38-91b8-a02acc2a88b5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:28 INFO - 1498910428259 Marionette TRACE 403 -> [0,2,"setTimeouts",{"script":30000}]
05:00:28 INFO - 1498910428259 Marionette TRACE 403 <- [1,2,null,{}]
05:00:28 INFO - 1498910428260 Marionette TRACE 403 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:00:28 INFO - 1498910428261 Marionette TRACE 403 <- [1,3,null,{}]
05:00:28 INFO - 1498910428262 Marionette TRACE 403 -> [0,4,"setTimeouts",{"implicit":0}]
05:00:28 INFO - 1498910428262 Marionette TRACE 403 <- [1,4,null,{}]
05:00:28 INFO - 1498910428264 Marionette TRACE 403 -> [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}]
05:00:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498910428267 Marionette TRACE 403 <- [1,5,null,{"value":null}]
05:00:28 INFO - 1498910428268 Marionette TRACE 403 -> [0,6,"deleteSession",{}]
05:00:28 INFO - 1498910428269 Marionette TRACE 403 <- [1,6,null,{}]
05:00:28 INFO - 1498910428271 Marionette DEBUG Closed connection 403
05:00:28 INFO - 1498910428272 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50490
05:00:28 INFO - 1498910428273 Marionette DEBUG Closed connection 404
05:00:28 INFO - 1498910428274 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50491
05:00:28 INFO - 1498910428275 Marionette TRACE 405 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:00:28 INFO - 1498910428276 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:28 INFO - 1498910428285 Marionette DEBUG Register listener.js for window 2147483649
05:00:28 INFO - 1498910428290 Marionette TRACE 405 <- [1,1,null,{"sessionId":"f5b773fb-fb28-45aa-beca-e2cdc98bb7d4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:28 INFO - 1498910428291 Marionette TRACE 405 -> [0,2,"deleteSession",{}]
05:00:28 INFO - 1498910428293 Marionette TRACE 405 <- [1,2,null,{}]
05:00:28 INFO - 1498910428294 Marionette DEBUG Closed connection 405
05:00:28 INFO - 1498910428295 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50492
05:00:28 INFO - 1498910428296 Marionette DEBUG Closed connection 406
05:00:28 INFO - 1498910428297 Marionette DEBUG Accepted connection 407 from 127.0.0.1:50493
05:00:28 INFO - 1498910428298 Marionette TRACE 407 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:00:28 INFO - 1498910428299 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}
05:00:28 INFO - 1498910428301 Marionette DEBUG Register listener.js for window 2147483649
05:00:28 INFO - 1498910428304 Marionette TRACE 407 <- [1,1,null,{"sessionId":"907e502f-0c68-477d-bb88-a8c0c3155b4c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeshbie.mozrunner","moz:accessibilityChecks":false}}]
05:00:28 INFO - 1498910428305 Marionette TRACE 407 -> [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"}]
05:00:28 INFO - 1498910428307 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"
05:00:28 INFO - 1498910428310 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"
05:00:28 INFO - 1498910428310 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"
05:00:28 INFO - 1498910428316 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"
05:00:28 INFO - 1498910428319 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"
05:00:28 INFO - 1498910428328 Marionette TRACE 407 <- [1,2,null,{}]
05:00:28 INFO - 1498910428332 Marionette TRACE 407 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:00:28 INFO - 1498910428335 Marionette TRACE 407 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6144cb98-d9bc-48e6-aa3c-3958d34428da","ELEMENT":"6144cb98-d9bc-48e6-aa3c-3958d34428da"}}]
05:00:28 INFO - 1498910428336 Marionette TRACE 407 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6144cb98-d9bc-48e6-aa3c-3958d34428da"}]
05:00:28 INFO - 1498910428358 Marionette TRACE 407 <- [1,4,null,{"value":"none"}]
05:00:28 INFO - 1498910428359 Marionette TRACE 407 -> [0,5,"clickElement",{"id":"6144cb98-d9bc-48e6-aa3c-3958d34428da"}]
05:00:28 INFO - 1498910428374 Marionette TRACE 407 <- [1,5,{"error":"element click intercepted","message":"Element