could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:09:32 INFO - 1499443772236 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
09:09:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 125ms
09:09:32 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499443772239 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:09:32 INFO - 1499443772239 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:09:32 INFO - 1499443772241 Marionette TRACE 352 <- [1,6,null,{}]
09:09:32 INFO - 1499443772242 Marionette DEBUG Closed connection 352
09:09:32 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:09:32 INFO - 1499443772244 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49794
09:09:32 INFO - 1499443772245 Marionette DEBUG Closed connection 353
09:09:32 INFO - 1499443772246 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49795
09:09:32 INFO - 1499443772247 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:32 INFO - 1499443772247 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772249 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772252 Marionette TRACE 354 <- [1,1,null,{"sessionId":"c9f63f3e-524f-43c4-8734-aeeb855aefc4","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772261 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:09:32 INFO - 1499443772261 Marionette TRACE 354 <- [1,2,null,{}]
09:09:32 INFO - 1499443772262 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:09:32 INFO - 1499443772263 Marionette TRACE 354 <- [1,3,null,{}]
09:09:32 INFO - 1499443772264 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:09:32 INFO - 1499443772264 Marionette TRACE 354 <- [1,4,null,{}]
09:09:32 INFO - 1499443772266 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
09:09:32 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499443772268 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:09:32 INFO - 1499443772269 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:09:32 INFO - 1499443772270 Marionette TRACE 354 <- [1,6,null,{}]
09:09:32 INFO - 1499443772272 Marionette DEBUG Closed connection 354
09:09:32 INFO - 1499443772273 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49796
09:09:32 INFO - 1499443772274 Marionette DEBUG Closed connection 355
09:09:32 INFO - 1499443772274 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49797
09:09:32 INFO - 1499443772275 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:32 INFO - 1499443772276 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772278 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772280 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9df01afa-34e9-4218-9e55-9d86d011cb3b","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772282 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:09:32 INFO - 1499443772283 Marionette TRACE 356 <- [1,2,null,{}]
09:09:32 INFO - 1499443772285 Marionette DEBUG Closed connection 356
09:09:32 INFO - 1499443772286 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49798
09:09:32 INFO - 1499443772286 Marionette DEBUG Closed connection 357
09:09:32 INFO - 1499443772287 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49799
09:09:32 INFO - 1499443772288 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:09:32 INFO - 1499443772289 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772290 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772293 Marionette TRACE 358 <- [1,1,null,{"sessionId":"675a85a3-2012-4ef6-aef8-aa248030f02d","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772295 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49366/clicks.html"}]
09:09:32 INFO - 1499443772296 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49366/hidden.html"
09:09:32 INFO - 1499443772301 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49366/hidden.html"
09:09:32 INFO - 1499443772301 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49366/hidden.html"
09:09:32 INFO - 1499443772317 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49366/clicks.html"
09:09:32 INFO - 1499443772322 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49366/clicks.html"
09:09:32 INFO - 1499443772331 Marionette TRACE 358 <- [1,2,null,{}]
09:09:32 INFO - 1499443772333 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:09:32 INFO - 1499443772335 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"137ebc14-4bba-4337-b7e2-252e34ed353c","ELEMENT":"137ebc14-4bba-4337-b7e2-252e34ed353c"}}]
09:09:32 INFO - 1499443772336 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"137ebc14-4bba-4337-b7e2-252e34ed353c"}]
09:09:32 INFO - 1499443772375 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49366/clicks.html"
09:09:32 INFO - 1499443772382 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49366/clicks.html"
09:09:32 INFO - 1499443772383 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49366/clicks.html"
09:09:32 INFO - 1499443772398 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49366/test.html"
09:09:32 INFO - 1499443772401 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49366/test.html"
09:09:32 INFO - 1499443772404 Marionette TRACE 358 <- [1,4,null,{}]
09:09:32 INFO - 1499443772405 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:09:32 INFO - 1499443772416 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"116ace66-1184-4891-a626-7179d14362ce","ELEMENT":"116ace66-1184-4891-a626-7179d14362ce"}}]
09:09:32 INFO - 1499443772417 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:09:32 INFO - 1499443772417 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:09:32 INFO - 1499443772419 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
09:09:32 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499443772421 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:09:32 INFO - 1499443772422 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:09:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 182ms
09:09:32 INFO - TEST-START | test_click.py TestClick.test_container_element
09:09:32 INFO - 1499443772423 Marionette TRACE 358 <- [1,8,null,{}]
09:09:32 INFO - 1499443772426 Marionette DEBUG Closed connection 358
09:09:32 INFO - 1499443772426 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49802
09:09:32 INFO - 1499443772428 Marionette DEBUG Closed connection 359
09:09:32 INFO - 1499443772428 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49803
09:09:32 INFO - 1499443772429 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:32 INFO - 1499443772430 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772432 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772434 Marionette TRACE 360 <- [1,1,null,{"sessionId":"c59a9958-92c6-42a9-bcf5-8d2f2e474164","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772436 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:09:32 INFO - 1499443772437 Marionette TRACE 360 <- [1,2,null,{}]
09:09:32 INFO - 1499443772438 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:09:32 INFO - 1499443772438 Marionette TRACE 360 <- [1,3,null,{}]
09:09:32 INFO - 1499443772439 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:09:32 INFO - 1499443772439 Marionette TRACE 360 <- [1,4,null,{}]
09:09:32 INFO - 1499443772441 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
09:09:32 INFO - TEST-START: test_click.py TestClick.test_container_element1499443772443 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:09:32 INFO - 1499443772444 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:09:32 INFO - 1499443772445 Marionette TRACE 360 <- [1,6,null,{}]
09:09:32 INFO - 1499443772447 Marionette DEBUG Closed connection 360
09:09:32 INFO - 1499443772448 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49804
09:09:32 INFO - 1499443772448 Marionette DEBUG Closed connection 361
09:09:32 INFO - 1499443772449 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49805
09:09:32 INFO - 1499443772450 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:32 INFO - 1499443772450 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772452 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772455 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a2daaf46-dcfe-4372-a4ca-8c40ea2047a9","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772457 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:09:32 INFO - 1499443772458 Marionette TRACE 362 <- [1,2,null,{}]
09:09:32 INFO - 1499443772460 Marionette DEBUG Closed connection 362
09:09:32 INFO - 1499443772460 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49806
09:09:32 INFO - 1499443772461 Marionette DEBUG Closed connection 363
09:09:32 INFO - 1499443772462 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49807
09:09:32 INFO - 1499443772462 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:09:32 INFO - 1499443772463 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772465 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772467 Marionette TRACE 364 <- [1,1,null,{"sessionId":"2784ec12-b094-4741-ae29-94f793735d2c","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772469 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:09:32 INFO - 1499443772470 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49366/test.html"
09:09:32 INFO - 1499443772474 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49366/test.html"
09:09:32 INFO - 1499443772474 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49366/test.html"
09:09:32 INFO - 1499443772502 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:32 INFO - 1499443772508 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:32 INFO - 1499443772518 Marionette TRACE 364 <- [1,2,null,{}]
09:09:32 INFO - 1499443772519 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:09:32 INFO - 1499443772522 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0ad13ff7-023b-45a5-a886-7a939b63999f","ELEMENT":"0ad13ff7-023b-45a5-a886-7a939b63999f"}}]
09:09:32 INFO - 1499443772523 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0ad13ff7-023b-45a5-a886-7a939b63999f"}]
09:09:32 INFO - 1499443772761 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:09:32 INFO - 1499443772762 Marionette TRACE 364 <- [1,4,null,{}]
09:09:32 INFO - 1499443772763 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0ad13ff7-023b-45a5-a886-7a939b63999f","name":"selected"}]
09:09:32 INFO - 1499443772768 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:09:32 INFO - 1499443772770 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
09:09:32 INFO - TEST-END: test_click.py TestClick.test_container_element1499443772773 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:09:32 INFO - 1499443772773 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:09:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 351ms
09:09:32 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:09:32 INFO - 1499443772775 Marionette TRACE 364 <- [1,7,null,{}]
09:09:32 INFO - 1499443772776 Marionette DEBUG Closed connection 364
09:09:32 INFO - 1499443772777 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49808
09:09:32 INFO - 1499443772778 Marionette DEBUG Closed connection 365
09:09:32 INFO - 1499443772779 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49809
09:09:32 INFO - 1499443772780 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:32 INFO - 1499443772780 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772782 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772785 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b8c404f7-2a16-4da1-be48-dcb8bff43449","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772787 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:09:32 INFO - 1499443772787 Marionette TRACE 366 <- [1,2,null,{}]
09:09:32 INFO - 1499443772788 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:09:32 INFO - 1499443772788 Marionette TRACE 366 <- [1,3,null,{}]
09:09:32 INFO - 1499443772789 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:09:32 INFO - 1499443772790 Marionette TRACE 366 <- [1,4,null,{}]
09:09:32 INFO - 1499443772792 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
09:09:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499443772794 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:09:32 INFO - 1499443772795 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:09:32 INFO - 1499443772796 Marionette TRACE 366 <- [1,6,null,{}]
09:09:32 INFO - 1499443772798 Marionette DEBUG Closed connection 366
09:09:32 INFO - 1499443772798 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49810
09:09:32 INFO - 1499443772799 Marionette DEBUG Closed connection 367
09:09:32 INFO - 1499443772800 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49811
09:09:32 INFO - 1499443772800 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:32 INFO - 1499443772801 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772810 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772813 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e145b522-96eb-40a4-91a2-b8224ee55cfe","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772815 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:09:32 INFO - 1499443772816 Marionette TRACE 368 <- [1,2,null,{}]
09:09:32 INFO - 1499443772818 Marionette DEBUG Closed connection 368
09:09:32 INFO - 1499443772818 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49812
09:09:32 INFO - 1499443772819 Marionette DEBUG Closed connection 369
09:09:32 INFO - 1499443772820 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49813
09:09:32 INFO - 1499443772821 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:09:32 INFO - 1499443772821 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:32 INFO - 1499443772824 Marionette DEBUG Register listener.js for window 12
09:09:32 INFO - 1499443772827 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ea7cfd99-9251-4865-b734-7a9aac414e49","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:32 INFO - 1499443772829 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:09:32 INFO - 1499443772831 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:32 INFO - 1499443772833 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:32 INFO - 1499443772833 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:32 INFO - 1499443772864 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:32 INFO - 1499443772868 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:32 INFO - 1499443772870 Marionette TRACE 370 <- [1,2,null,{}]
09:09:32 INFO - 1499443772871 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:09:32 INFO - 1499443772881 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f7507c9-9613-4748-afc1-78b9aeecc1aa","ELEMENT":"4f7507c9-9613-4748-afc1-78b9aeecc1aa"}}]
09:09:32 INFO - 1499443772885 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"4f7507c9-9613-4748-afc1-78b9aeecc1aa"}]
09:09:33 INFO - 1499443773124 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:09:33 INFO - 1499443773124 Marionette TRACE 370 <- [1,4,null,{}]
09:09:33 INFO - 1499443773125 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"4f7507c9-9613-4748-afc1-78b9aeecc1aa","name":"selected"}]
09:09:33 INFO - 1499443773129 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:09:33 INFO - 1499443773131 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
09:09:33 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499443773133 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:09:33 INFO - 1499443773134 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:09:33 INFO - 1499443773135 Marionette TRACE 370 <- [1,7,null,{}]
09:09:33 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 360ms
09:09:33 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:09:33 INFO - 1499443773137 Marionette DEBUG Closed connection 370
09:09:33 INFO - 1499443773138 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49814
09:09:33 INFO - 1499443773139 Marionette DEBUG Closed connection 371
09:09:33 INFO - 1499443773140 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49815
09:09:33 INFO - 1499443773141 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:33 INFO - 1499443773141 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773143 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773146 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3d63d187-e8e6-4000-a1d2-add3fb9ae869","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773148 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:09:33 INFO - 1499443773148 Marionette TRACE 372 <- [1,2,null,{}]
09:09:33 INFO - 1499443773149 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:09:33 INFO - 1499443773149 Marionette TRACE 372 <- [1,3,null,{}]
09:09:33 INFO - 1499443773150 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:09:33 INFO - 1499443773151 Marionette TRACE 372 <- [1,4,null,{}]
09:09:33 INFO - 1499443773152 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
09:09:33 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499443773155 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:09:33 INFO - 1499443773155 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:09:33 INFO - 1499443773156 Marionette TRACE 372 <- [1,6,null,{}]
09:09:33 INFO - 1499443773158 Marionette DEBUG Closed connection 372
09:09:33 INFO - 1499443773159 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49816
09:09:33 INFO - 1499443773160 Marionette DEBUG Closed connection 373
09:09:33 INFO - 1499443773160 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49817
09:09:33 INFO - 1499443773161 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:33 INFO - 1499443773162 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773164 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773166 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ed4f7e4c-0f58-40c4-8669-72b5fb1a0b02","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773168 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:09:33 INFO - 1499443773169 Marionette TRACE 374 <- [1,2,null,{}]
09:09:33 INFO - 1499443773171 Marionette DEBUG Closed connection 374
09:09:33 INFO - 1499443773171 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49818
09:09:33 INFO - 1499443773172 Marionette DEBUG Closed connection 375
09:09:33 INFO - 1499443773173 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49819
09:09:33 INFO - 1499443773174 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:09:33 INFO - 1499443773174 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773176 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773179 Marionette TRACE 376 <- [1,1,null,{"sessionId":"dc36b48e-2a69-45ec-b2ab-28144b129bdd","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773181 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
09:09:33 INFO - 1499443773182 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:33 INFO - 1499443773185 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:33 INFO - 1499443773185 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:33 INFO - 1499443773203 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:09:33 INFO - 1499443773206 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:09:33 INFO - 1499443773215 Marionette TRACE 376 <- [1,2,null,{}]
09:09:33 INFO - 1499443773216 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:09:33 INFO - 1499443773218 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3bda8fa3-d9f0-43d2-9bb7-63fdd053e2fb","ELEMENT":"3bda8fa3-d9f0-43d2-9bb7-63fdd053e2fb"}}]
09:09:33 INFO - 1499443773219 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"3bda8fa3-d9f0-43d2-9bb7-63fdd053e2fb"}]
09:09:33 INFO - 1499443773455 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:09:33 INFO - 1499443773455 Marionette TRACE 376 <- [1,4,null,{}]
09:09:33 INFO - 1499443773457 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
09:09:33 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499443773460 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:09:33 INFO - 1499443773461 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:09:33 INFO - 1499443773462 Marionette TRACE 376 <- [1,6,null,{}]
09:09:33 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 326ms
09:09:33 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:09:33 INFO - 1499443773464 Marionette DEBUG Closed connection 376
09:09:33 INFO - 1499443773465 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49820
09:09:33 INFO - 1499443773466 Marionette DEBUG Closed connection 377
09:09:33 INFO - 1499443773467 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49821
09:09:33 INFO - 1499443773468 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:33 INFO - 1499443773468 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773470 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773473 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9366597c-5c8d-4eb1-b0b8-ec4c2d49954d","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773475 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:09:33 INFO - 1499443773475 Marionette TRACE 378 <- [1,2,null,{}]
09:09:33 INFO - 1499443773476 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:09:33 INFO - 1499443773476 Marionette TRACE 378 <- [1,3,null,{}]
09:09:33 INFO - 1499443773477 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:09:33 INFO - 1499443773478 Marionette TRACE 378 <- [1,4,null,{}]
09:09:33 INFO - 1499443773479 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
09:09:33 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499443773482 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:09:33 INFO - 1499443773482 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:09:33 INFO - 1499443773483 Marionette TRACE 378 <- [1,6,null,{}]
09:09:33 INFO - 1499443773485 Marionette DEBUG Closed connection 378
09:09:33 INFO - 1499443773486 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49822
09:09:33 INFO - 1499443773486 Marionette DEBUG Closed connection 379
09:09:33 INFO - 1499443773487 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49823
09:09:33 INFO - 1499443773488 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:33 INFO - 1499443773489 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773491 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773500 Marionette TRACE 380 <- [1,1,null,{"sessionId":"dc0d73dc-5adc-4b76-a0ac-3b8b13914c22","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773502 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:09:33 INFO - 1499443773503 Marionette TRACE 380 <- [1,2,null,{}]
09:09:33 INFO - 1499443773505 Marionette DEBUG Closed connection 380
09:09:33 INFO - 1499443773505 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49824
09:09:33 INFO - 1499443773506 Marionette DEBUG Closed connection 381
09:09:33 INFO - 1499443773507 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49825
09:09:33 INFO - 1499443773508 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:09:33 INFO - 1499443773509 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773511 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773513 Marionette TRACE 382 <- [1,1,null,{"sessionId":"34c71ae1-7d4d-4afb-96b3-65e9ce0a73f8","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773515 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:09:33 INFO - 1499443773516 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:09:33 INFO - 1499443773518 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:09:33 INFO - 1499443773518 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:09:33 INFO - 1499443773534 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:33 INFO - 1499443773538 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:33 INFO - 1499443773540 Marionette TRACE 382 <- [1,2,null,{}]
09:09:33 INFO - 1499443773547 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:09:33 INFO - 1499443773549 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"24312915-584f-434f-8136-3a75b702c4ff","ELEMENT":"24312915-584f-434f-8136-3a75b702c4ff"}}]
09:09:33 INFO - 1499443773551 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"24312915-584f-434f-8136-3a75b702c4ff"}]
09:09:33 INFO - 1499443773790 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:09:33 INFO - 1499443773790 Marionette TRACE 382 <- [1,4,null,{}]
09:09:33 INFO - 1499443773792 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
09:09:33 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499443773795 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:09:33 INFO - 1499443773796 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:09:33 INFO - 1499443773797 Marionette TRACE 382 <- [1,6,null,{}]
09:09:33 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 334ms
09:09:33 INFO - TEST-START | test_click.py TestClick.test_input_file
09:09:33 INFO - 1499443773799 Marionette DEBUG Closed connection 382
09:09:33 INFO - 1499443773800 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49826
09:09:33 INFO - 1499443773800 Marionette DEBUG Closed connection 383
09:09:33 INFO - 1499443773801 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49827
09:09:33 INFO - 1499443773802 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:33 INFO - 1499443773803 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773805 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773807 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5a964a61-11a9-44c8-a387-c466fb19e658","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773809 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:09:33 INFO - 1499443773810 Marionette TRACE 384 <- [1,2,null,{}]
09:09:33 INFO - 1499443773810 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:09:33 INFO - 1499443773811 Marionette TRACE 384 <- [1,3,null,{}]
09:09:33 INFO - 1499443773812 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:09:33 INFO - 1499443773812 Marionette TRACE 384 <- [1,4,null,{}]
09:09:33 INFO - 1499443773814 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
09:09:33 INFO - TEST-START: test_click.py TestClick.test_input_file1499443773816 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:09:33 INFO - 1499443773817 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:09:33 INFO - 1499443773818 Marionette TRACE 384 <- [1,6,null,{}]
09:09:33 INFO - 1499443773819 Marionette DEBUG Closed connection 384
09:09:33 INFO - 1499443773820 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49828
09:09:33 INFO - 1499443773821 Marionette DEBUG Closed connection 385
09:09:33 INFO - 1499443773822 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49829
09:09:33 INFO - 1499443773823 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:33 INFO - 1499443773823 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773825 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773827 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d030ff0b-dfb8-4d02-bd25-8c28529c749d","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773829 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:09:33 INFO - 1499443773830 Marionette TRACE 386 <- [1,2,null,{}]
09:09:33 INFO - 1499443773832 Marionette DEBUG Closed connection 386
09:09:33 INFO - 1499443773832 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49830
09:09:33 INFO - 1499443773833 Marionette DEBUG Closed connection 387
09:09:33 INFO - 1499443773834 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49831
09:09:33 INFO - 1499443773835 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:09:33 INFO - 1499443773835 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773837 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773839 Marionette TRACE 388 <- [1,1,null,{"sessionId":"bb39b079-b882-4597-ae3d-9df675ca4940","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773841 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:09:33 INFO - 1499443773842 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:33 INFO - 1499443773844 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:33 INFO - 1499443773845 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:09:33 INFO - 1499443773854 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:09:33 INFO - 1499443773862 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:09:33 INFO - 1499443773867 Marionette TRACE 388 <- [1,2,null,{}]
09:09:33 INFO - 1499443773868 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:09:33 INFO - 1499443773870 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f6fa3aae-ac03-4b32-90b1-a05e7a00d56c","ELEMENT":"f6fa3aae-ac03-4b32-90b1-a05e7a00d56c"}}]
09:09:33 INFO - 1499443773871 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"f6fa3aae-ac03-4b32-90b1-a05e7a00d56c"}]
09:09:33 INFO - 1499443773901 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:09:33 INFO - 1499443773903 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
09:09:33 INFO - TEST-END: test_click.py TestClick.test_input_file1499443773906 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:09:33 INFO - 1499443773907 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:09:33 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 111ms
09:09:33 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:09:33 INFO - 1499443773908 Marionette TRACE 388 <- [1,6,null,{}]
09:09:33 INFO - 1499443773909 Marionette DEBUG Closed connection 388
09:09:33 INFO - 1499443773911 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49832
09:09:33 INFO - 1499443773911 Marionette DEBUG Closed connection 389
09:09:33 INFO - 1499443773912 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49833
09:09:33 INFO - 1499443773913 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:33 INFO - 1499443773913 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773915 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773918 Marionette TRACE 390 <- [1,1,null,{"sessionId":"693679e2-3eec-42bc-95f0-649fd523024a","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773920 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:09:33 INFO - 1499443773920 Marionette TRACE 390 <- [1,2,null,{}]
09:09:33 INFO - 1499443773921 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:09:33 INFO - 1499443773922 Marionette TRACE 390 <- [1,3,null,{}]
09:09:33 INFO - 1499443773923 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:09:33 INFO - 1499443773923 Marionette TRACE 390 <- [1,4,null,{}]
09:09:33 INFO - 1499443773925 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
09:09:33 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499443773927 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:09:33 INFO - 1499443773928 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:09:33 INFO - 1499443773929 Marionette TRACE 390 <- [1,6,null,{}]
09:09:33 INFO - 1499443773930 Marionette DEBUG Closed connection 390
09:09:33 INFO - 1499443773931 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49834
09:09:33 INFO - 1499443773932 Marionette DEBUG Closed connection 391
09:09:33 INFO - 1499443773933 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49835
09:09:33 INFO - 1499443773934 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:33 INFO - 1499443773934 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773936 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773939 Marionette TRACE 392 <- [1,1,null,{"sessionId":"33ff0681-49e5-44b4-87f7-184a15d56716","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773942 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:09:33 INFO - 1499443773943 Marionette TRACE 392 <- [1,2,null,{}]
09:09:33 INFO - 1499443773945 Marionette DEBUG Closed connection 392
09:09:33 INFO - 1499443773946 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49836
09:09:33 INFO - 1499443773946 Marionette DEBUG Closed connection 393
09:09:33 INFO - 1499443773947 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49837
09:09:33 INFO - 1499443773948 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:09:33 INFO - 1499443773949 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:33 INFO - 1499443773951 Marionette DEBUG Register listener.js for window 12
09:09:33 INFO - 1499443773960 Marionette TRACE 394 <- [1,1,null,{"sessionId":"1fab93e3-f486-40c9-9683-adf54fb6c5ca","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:33 INFO - 1499443773962 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
09:09:33 INFO - 1499443773964 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:09:33 INFO - 1499443773966 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:09:33 INFO - 1499443773966 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:09:33 INFO - 1499443773984 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:09:33 INFO - 1499443773986 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:09:33 INFO - 1499443773988 Marionette TRACE 394 <- [1,2,null,{}]
09:09:33 INFO - 1499443773989 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:09:34 INFO - 1499443773997 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"46e4a130-5909-4147-bd67-7df3aa45c413","ELEMENT":"46e4a130-5909-4147-bd67-7df3aa45c413"}}]
09:09:34 INFO - 1499443773998 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:09:34 INFO - 1499443773999 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"646a518c-b877-4d3c-8ddf-de5120a6aa93","ELEMENT":"646a518c-b877-4d3c-8ddf-de5120a6aa93"}}]
09:09:34 INFO - 1499443774000 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"46e4a130-5909-4147-bd67-7df3aa45c413"}]
09:09:34 INFO - 1499443774234 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:09:34 INFO - 1499443774234 Marionette TRACE 394 <- [1,5,null,{}]
09:09:34 INFO - 1499443774235 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"646a518c-b877-4d3c-8ddf-de5120a6aa93"}]
09:09:34 INFO - 1499443774249 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:09:34 INFO - 1499443774251 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:09:34 INFO - 1499443774253 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:09:34 INFO - 1499443774255 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
09:09:34 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499443774258 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:09:34 INFO - 1499443774258 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:09:34 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 352ms
09:09:34 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:09:34 INFO - 1499443774259 Marionette TRACE 394 <- [1,9,null,{}]
09:09:34 INFO - 1499443774261 Marionette DEBUG Closed connection 394
09:09:34 INFO - 1499443774262 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49838
09:09:34 INFO - 1499443774263 Marionette DEBUG Closed connection 395
09:09:34 INFO - 1499443774264 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49839
09:09:34 INFO - 1499443774265 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:34 INFO - 1499443774265 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:34 INFO - 1499443774267 Marionette DEBUG Register listener.js for window 12
09:09:34 INFO - 1499443774270 Marionette TRACE 396 <- [1,1,null,{"sessionId":"df637652-9299-4444-bb3c-b7abc3e18d66","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:34 INFO - 1499443774272 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:09:34 INFO - 1499443774272 Marionette TRACE 396 <- [1,2,null,{}]
09:09:34 INFO - 1499443774273 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:09:34 INFO - 1499443774273 Marionette TRACE 396 <- [1,3,null,{}]
09:09:34 INFO - 1499443774274 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:09:34 INFO - 1499443774275 Marionette TRACE 396 <- [1,4,null,{}]
09:09:34 INFO - 1499443774276 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
09:09:34 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499443774279 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:09:34 INFO - 1499443774279 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:09:34 INFO - 1499443774280 Marionette TRACE 396 <- [1,6,null,{}]
09:09:34 INFO - 1499443774282 Marionette DEBUG Closed connection 396
09:09:34 INFO - 1499443774283 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49840
09:09:34 INFO - 1499443774283 Marionette DEBUG Closed connection 397
09:09:34 INFO - 1499443774284 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49841
09:09:34 INFO - 1499443774285 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:09:34 INFO - 1499443774285 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:34 INFO - 1499443774287 Marionette DEBUG Register listener.js for window 12
09:09:34 INFO - 1499443774290 Marionette TRACE 398 <- [1,1,null,{"sessionId":"23b76331-e9ee-471a-b20d-785418be031c","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:34 INFO - 1499443774292 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:09:34 INFO - 1499443774293 Marionette TRACE 398 <- [1,2,null,{}]
09:09:34 INFO - 1499443774295 Marionette DEBUG Closed connection 398
09:09:34 INFO - 1499443774295 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49842
09:09:34 INFO - 1499443774296 Marionette DEBUG Closed connection 399
09:09:34 INFO - 1499443774297 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49843
09:09:34 INFO - 1499443774298 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:09:34 INFO - 1499443774298 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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}
09:09:34 INFO - 1499443774300 Marionette DEBUG Register listener.js for window 12
09:09:34 INFO - 1499443774303 Marionette TRACE 400 <- [1,1,null,{"sessionId":"28edd5c6-5d57-460f-9821-4dab91bdc6ba","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":1636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkx8zxq.mozrunner","moz:accessibilityChecks":false}}]
09:09:34 INFO - 1499443774304 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
09:09:34 INFO - 1499443774306 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:09:34 INFO - 1499443774312 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:09:34 INFO - 1499443774312 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:09:34 INFO - 1499443774326 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
09:09:34 INFO - 1499443774328 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
09:09:34 INFO - 1499443774330 Marionette TRACE 400 <- [1,2,null,{}]
09:09:34 INFO - 1499443774332 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:09:34 INFO - 1499443774334 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5d2d3fc5-3893-4a92-8fd9-f2cff2338f13","ELEMENT":"5d2d3fc5-3893-4a92-8fd9-f2cff2338f13"}}]
09:09:34 INFO - 1499443774335 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5d2d3fc5-3893-4a92-8fd9-f2cff2338f13"}]
09:09:34 INFO - 1499443774362 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:09:34 INFO - 1499443774363 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5d2d3fc5-3893-4a92-8fd9-f2cff2338f13"}]
09:09:34 INFO - 1499443774381 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element