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]
12:38:26 INFO - 1499110706325 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
12:38:26 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499110706327 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:38:26 INFO - 1499110706328 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:38:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 113ms
12:38:26 INFO - 1499110706330 Marionette TRACE 352 <- [1,6,null,{}]
12:38:26 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:38:26 INFO - 1499110706331 Marionette DEBUG Closed connection 352
12:38:26 INFO - 1499110706332 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49769
12:38:26 INFO - 1499110706333 Marionette DEBUG Closed connection 353
12:38:26 INFO - 1499110706333 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49770
12:38:26 INFO - 1499110706334 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:26 INFO - 1499110706335 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706337 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706339 Marionette TRACE 354 <- [1,1,null,{"sessionId":"cfd2da35-8a0f-4a4b-acb7-da3981098dbb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706340 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:38:26 INFO - 1499110706341 Marionette TRACE 354 <- [1,2,null,{}]
12:38:26 INFO - 1499110706341 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:26 INFO - 1499110706342 Marionette TRACE 354 <- [1,3,null,{}]
12:38:26 INFO - 1499110706343 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:26 INFO - 1499110706343 Marionette TRACE 354 <- [1,4,null,{}]
12:38:26 INFO - 1499110706344 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
12:38:26 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499110706347 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:38:26 INFO - 1499110706347 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:38:26 INFO - 1499110706348 Marionette TRACE 354 <- [1,6,null,{}]
12:38:26 INFO - 1499110706350 Marionette DEBUG Closed connection 354
12:38:26 INFO - 1499110706350 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49771
12:38:26 INFO - 1499110706351 Marionette DEBUG Closed connection 355
12:38:26 INFO - 1499110706351 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49772
12:38:26 INFO - 1499110706352 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:26 INFO - 1499110706353 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706355 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706358 Marionette TRACE 356 <- [1,1,null,{"sessionId":"7bfec40c-45cb-416a-979f-7d111da98c03","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706359 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:38:26 INFO - 1499110706360 Marionette TRACE 356 <- [1,2,null,{}]
12:38:26 INFO - 1499110706361 Marionette DEBUG Closed connection 356
12:38:26 INFO - 1499110706361 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49773
12:38:26 INFO - 1499110706362 Marionette DEBUG Closed connection 357
12:38:26 INFO - 1499110706363 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49774
12:38:26 INFO - 1499110706364 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:26 INFO - 1499110706364 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706366 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706377 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c75debc0-d196-4809-8970-54d3b313975f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706378 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49335/clicks.html"}]
12:38:26 INFO - 1499110706380 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/hidden.html"
12:38:26 INFO - 1499110706386 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/hidden.html"
12:38:26 INFO - 1499110706386 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/hidden.html"
12:38:26 INFO - 1499110706392 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/clicks.html"
12:38:26 INFO - 1499110706397 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/clicks.html"
12:38:26 INFO - 1499110706399 Marionette TRACE 358 <- [1,2,null,{}]
12:38:26 INFO - 1499110706402 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:38:26 INFO - 1499110706405 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8126753e-1278-465d-b5d8-10cded0a0770","ELEMENT":"8126753e-1278-465d-b5d8-10cded0a0770"}}]
12:38:26 INFO - 1499110706406 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"8126753e-1278-465d-b5d8-10cded0a0770"}]
12:38:26 INFO - 1499110706451 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/clicks.html"
12:38:26 INFO - 1499110706456 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/clicks.html"
12:38:26 INFO - 1499110706456 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/clicks.html"
12:38:26 INFO - 1499110706462 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/test.html"
12:38:26 INFO - 1499110706466 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/test.html"
12:38:26 INFO - 1499110706468 Marionette TRACE 358 <- [1,4,null,{}]
12:38:26 INFO - 1499110706471 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:38:26 INFO - 1499110706475 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f1133d61-18b3-4f47-a7a3-0616ceb9d2e6","ELEMENT":"f1133d61-18b3-4f47-a7a3-0616ceb9d2e6"}}]
12:38:26 INFO - 1499110706476 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:38:26 INFO - 1499110706477 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:38:26 INFO - 1499110706479 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
12:38:26 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499110706484 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:38:26 INFO - 1499110706485 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:38:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 157ms
12:38:26 INFO - TEST-START | test_click.py TestClick.test_container_element
12:38:26 INFO - 1499110706487 Marionette TRACE 358 <- [1,8,null,{}]
12:38:26 INFO - 1499110706488 Marionette DEBUG Closed connection 358
12:38:26 INFO - 1499110706489 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49777
12:38:26 INFO - 1499110706490 Marionette DEBUG Closed connection 359
12:38:26 INFO - 1499110706490 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49778
12:38:26 INFO - 1499110706492 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:26 INFO - 1499110706492 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706495 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706498 Marionette TRACE 360 <- [1,1,null,{"sessionId":"0ed6b71c-05cd-4dbd-9655-e01bf57c9ada","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706499 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:38:26 INFO - 1499110706500 Marionette TRACE 360 <- [1,2,null,{}]
12:38:26 INFO - 1499110706501 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:26 INFO - 1499110706501 Marionette TRACE 360 <- [1,3,null,{}]
12:38:26 INFO - 1499110706504 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:26 INFO - 1499110706505 Marionette TRACE 360 <- [1,4,null,{}]
12:38:26 INFO - 1499110706506 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
12:38:26 INFO - TEST-START: test_click.py TestClick.test_container_element1499110706508 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:38:26 INFO - 1499110706509 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:38:26 INFO - 1499110706511 Marionette TRACE 360 <- [1,6,null,{}]
12:38:26 INFO - 1499110706512 Marionette DEBUG Closed connection 360
12:38:26 INFO - 1499110706513 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49779
12:38:26 INFO - 1499110706513 Marionette DEBUG Closed connection 361
12:38:26 INFO - 1499110706514 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49780
12:38:26 INFO - 1499110706515 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:26 INFO - 1499110706515 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706517 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706520 Marionette TRACE 362 <- [1,1,null,{"sessionId":"697539ec-ec05-4c8c-8b39-9da93fa385dc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706521 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:38:26 INFO - 1499110706522 Marionette TRACE 362 <- [1,2,null,{}]
12:38:26 INFO - 1499110706523 Marionette DEBUG Closed connection 362
12:38:26 INFO - 1499110706524 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49781
12:38:26 INFO - 1499110706524 Marionette DEBUG Closed connection 363
12:38:26 INFO - 1499110706525 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49782
12:38:26 INFO - 1499110706526 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:26 INFO - 1499110706526 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706528 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706531 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5f97e7c6-1185-4d7e-be99-9235116987d6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706532 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:38:26 INFO - 1499110706533 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/test.html"
12:38:26 INFO - 1499110706536 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/test.html"
12:38:26 INFO - 1499110706536 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/test.html"
12:38:26 INFO - 1499110706543 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:26 INFO - 1499110706548 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:26 INFO - 1499110706554 Marionette TRACE 364 <- [1,2,null,{}]
12:38:26 INFO - 1499110706557 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:38:26 INFO - 1499110706559 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7902ef22-e668-4e8d-91c7-4d8e0d149f18","ELEMENT":"7902ef22-e668-4e8d-91c7-4d8e0d149f18"}}]
12:38:26 INFO - 1499110706560 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7902ef22-e668-4e8d-91c7-4d8e0d149f18"}]
12:38:26 INFO - 1499110706798 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:26 INFO - 1499110706799 Marionette TRACE 364 <- [1,4,null,{}]
12:38:26 INFO - 1499110706800 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7902ef22-e668-4e8d-91c7-4d8e0d149f18","name":"selected"}]
12:38:26 INFO - 1499110706804 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:38:26 INFO - 1499110706806 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
12:38:26 INFO - TEST-END: test_click.py TestClick.test_container_element1499110706809 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:38:26 INFO - 1499110706809 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:38:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 323ms
12:38:26 INFO - 1499110706811 Marionette TRACE 364 <- [1,7,null,{}]
12:38:26 INFO - 1499110706812 Marionette DEBUG Closed connection 364
12:38:26 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:38:26 INFO - 1499110706814 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49783
12:38:26 INFO - 1499110706814 Marionette DEBUG Closed connection 365
12:38:26 INFO - 1499110706815 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49784
12:38:26 INFO - 1499110706816 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:26 INFO - 1499110706816 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706818 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706821 Marionette TRACE 366 <- [1,1,null,{"sessionId":"82a526aa-6f6d-45f3-a7ff-e79120442732","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706822 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:38:26 INFO - 1499110706822 Marionette TRACE 366 <- [1,2,null,{}]
12:38:26 INFO - 1499110706823 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:26 INFO - 1499110706823 Marionette TRACE 366 <- [1,3,null,{}]
12:38:26 INFO - 1499110706824 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:26 INFO - 1499110706825 Marionette TRACE 366 <- [1,4,null,{}]
12:38:26 INFO - 1499110706826 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
12:38:26 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499110706829 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:38:26 INFO - 1499110706830 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:38:26 INFO - 1499110706831 Marionette TRACE 366 <- [1,6,null,{}]
12:38:26 INFO - 1499110706832 Marionette DEBUG Closed connection 366
12:38:26 INFO - 1499110706833 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49785
12:38:26 INFO - 1499110706833 Marionette DEBUG Closed connection 367
12:38:26 INFO - 1499110706834 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49786
12:38:26 INFO - 1499110706835 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:26 INFO - 1499110706835 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706837 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706840 Marionette TRACE 368 <- [1,1,null,{"sessionId":"65ae893e-34bd-43bb-8e5e-157167d340a6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706841 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:38:26 INFO - 1499110706842 Marionette TRACE 368 <- [1,2,null,{}]
12:38:26 INFO - 1499110706843 Marionette DEBUG Closed connection 368
12:38:26 INFO - 1499110706844 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49787
12:38:26 INFO - 1499110706845 Marionette DEBUG Closed connection 369
12:38:26 INFO - 1499110706845 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49788
12:38:26 INFO - 1499110706846 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:26 INFO - 1499110706847 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:26 INFO - 1499110706849 Marionette DEBUG Register listener.js for window 4294967297
12:38:26 INFO - 1499110706852 Marionette TRACE 370 <- [1,1,null,{"sessionId":"8e4daa17-15e9-4228-834c-db312b2402e0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:26 INFO - 1499110706853 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:38:26 INFO - 1499110706854 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:26 INFO - 1499110706856 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:26 INFO - 1499110706856 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:26 INFO - 1499110706862 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:26 INFO - 1499110706865 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:26 INFO - 1499110706869 Marionette TRACE 370 <- [1,2,null,{}]
12:38:26 INFO - 1499110706872 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:38:26 INFO - 1499110706874 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"542fc667-24b8-4e16-925d-86165d1b03d7","ELEMENT":"542fc667-24b8-4e16-925d-86165d1b03d7"}}]
12:38:26 INFO - 1499110706875 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"542fc667-24b8-4e16-925d-86165d1b03d7"}]
12:38:27 INFO - 1499110707113 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:27 INFO - 1499110707114 Marionette TRACE 370 <- [1,4,null,{}]
12:38:27 INFO - 1499110707115 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"542fc667-24b8-4e16-925d-86165d1b03d7","name":"selected"}]
12:38:27 INFO - 1499110707119 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:38:27 INFO - 1499110707120 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
12:38:27 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499110707122 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:38:27 INFO - 1499110707123 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:38:27 INFO - 1499110707124 Marionette TRACE 370 <- [1,7,null,{}]
12:38:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 313ms
12:38:27 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:38:27 INFO - 1499110707125 Marionette DEBUG Closed connection 370
12:38:27 INFO - 1499110707128 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49789
12:38:27 INFO - 1499110707128 Marionette DEBUG Closed connection 371
12:38:27 INFO - 1499110707129 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49790
12:38:27 INFO - 1499110707130 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:27 INFO - 1499110707130 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707132 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707135 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3d1f9df9-ce06-458a-8e3f-52ad3e4b6cc2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707136 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:38:27 INFO - 1499110707136 Marionette TRACE 372 <- [1,2,null,{}]
12:38:27 INFO - 1499110707137 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:27 INFO - 1499110707137 Marionette TRACE 372 <- [1,3,null,{}]
12:38:27 INFO - 1499110707139 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:27 INFO - 1499110707139 Marionette TRACE 372 <- [1,4,null,{}]
12:38:27 INFO - 1499110707140 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
12:38:27 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499110707143 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:38:27 INFO - 1499110707143 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:38:27 INFO - 1499110707144 Marionette TRACE 372 <- [1,6,null,{}]
12:38:27 INFO - 1499110707146 Marionette DEBUG Closed connection 372
12:38:27 INFO - 1499110707146 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49791
12:38:27 INFO - 1499110707147 Marionette DEBUG Closed connection 373
12:38:27 INFO - 1499110707148 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49792
12:38:27 INFO - 1499110707149 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:27 INFO - 1499110707149 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707151 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707154 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6247d4c9-3a32-4234-b0ef-6378c9fe2af7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707155 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:38:27 INFO - 1499110707156 Marionette TRACE 374 <- [1,2,null,{}]
12:38:27 INFO - 1499110707157 Marionette DEBUG Closed connection 374
12:38:27 INFO - 1499110707157 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49793
12:38:27 INFO - 1499110707158 Marionette DEBUG Closed connection 375
12:38:27 INFO - 1499110707159 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49794
12:38:27 INFO - 1499110707160 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:27 INFO - 1499110707160 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707162 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707165 Marionette TRACE 376 <- [1,1,null,{"sessionId":"abc488a3-a1fe-47e4-ad6e-51ce573fcfe8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707166 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
12:38:27 INFO - 1499110707168 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:27 INFO - 1499110707169 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:27 INFO - 1499110707170 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:27 INFO - 1499110707175 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:38:27 INFO - 1499110707178 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:38:27 INFO - 1499110707186 Marionette TRACE 376 <- [1,2,null,{}]
12:38:27 INFO - 1499110707189 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:38:27 INFO - 1499110707192 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8bd6bdc4-6506-462d-835b-1cf9857d7a57","ELEMENT":"8bd6bdc4-6506-462d-835b-1cf9857d7a57"}}]
12:38:27 INFO - 1499110707193 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8bd6bdc4-6506-462d-835b-1cf9857d7a57"}]
12:38:27 INFO - 1499110707431 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:27 INFO - 1499110707432 Marionette TRACE 376 <- [1,4,null,{}]
12:38:27 INFO - 1499110707434 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
12:38:27 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499110707436 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:38:27 INFO - 1499110707437 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:38:27 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 311ms
12:38:27 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:38:27 INFO - 1499110707438 Marionette TRACE 376 <- [1,6,null,{}]
12:38:27 INFO - 1499110707440 Marionette DEBUG Closed connection 376
12:38:27 INFO - 1499110707440 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49795
12:38:27 INFO - 1499110707441 Marionette DEBUG Closed connection 377
12:38:27 INFO - 1499110707441 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49796
12:38:27 INFO - 1499110707442 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:27 INFO - 1499110707443 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707445 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707448 Marionette TRACE 378 <- [1,1,null,{"sessionId":"35ab1bec-a99f-4013-8e04-bdc94e9ec2ba","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707450 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:38:27 INFO - 1499110707450 Marionette TRACE 378 <- [1,2,null,{}]
12:38:27 INFO - 1499110707451 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:27 INFO - 1499110707451 Marionette TRACE 378 <- [1,3,null,{}]
12:38:27 INFO - 1499110707452 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:27 INFO - 1499110707452 Marionette TRACE 378 <- [1,4,null,{}]
12:38:27 INFO - 1499110707454 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
12:38:27 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499110707456 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:38:27 INFO - 1499110707457 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:38:27 INFO - 1499110707458 Marionette TRACE 378 <- [1,6,null,{}]
12:38:27 INFO - 1499110707459 Marionette DEBUG Closed connection 378
12:38:27 INFO - 1499110707460 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49797
12:38:27 INFO - 1499110707461 Marionette DEBUG Closed connection 379
12:38:27 INFO - 1499110707461 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49798
12:38:27 INFO - 1499110707462 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:27 INFO - 1499110707463 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707465 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707467 Marionette TRACE 380 <- [1,1,null,{"sessionId":"078f86b2-2ed0-4e89-b4c4-69a95a274bfb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707468 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:38:27 INFO - 1499110707469 Marionette TRACE 380 <- [1,2,null,{}]
12:38:27 INFO - 1499110707470 Marionette DEBUG Closed connection 380
12:38:27 INFO - 1499110707471 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49799
12:38:27 INFO - 1499110707472 Marionette DEBUG Closed connection 381
12:38:27 INFO - 1499110707472 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49800
12:38:27 INFO - 1499110707473 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:27 INFO - 1499110707474 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707476 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707478 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2710493f-06c8-4513-a06d-1e533d645406","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707479 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:38:27 INFO - 1499110707481 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:38:27 INFO - 1499110707482 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:38:27 INFO - 1499110707482 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:38:27 INFO - 1499110707488 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:27 INFO - 1499110707490 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:27 INFO - 1499110707492 Marionette TRACE 382 <- [1,2,null,{}]
12:38:27 INFO - 1499110707494 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:38:27 INFO - 1499110707496 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"23213e7f-8442-4fe1-9664-fc1334fe20d9","ELEMENT":"23213e7f-8442-4fe1-9664-fc1334fe20d9"}}]
12:38:27 INFO - 1499110707498 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"23213e7f-8442-4fe1-9664-fc1334fe20d9"}]
12:38:27 INFO - 1499110707736 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:27 INFO - 1499110707737 Marionette TRACE 382 <- [1,4,null,{}]
12:38:27 INFO - 1499110707738 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
12:38:27 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499110707741 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:38:27 INFO - 1499110707741 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:38:27 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 305ms
12:38:27 INFO - TEST-START | test_click.py TestClick.test_input_file
12:38:27 INFO - 1499110707742 Marionette TRACE 382 <- [1,6,null,{}]
12:38:27 INFO - 1499110707744 Marionette DEBUG Closed connection 382
12:38:27 INFO - 1499110707744 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49801
12:38:27 INFO - 1499110707745 Marionette DEBUG Closed connection 383
12:38:27 INFO - 1499110707746 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49802
12:38:27 INFO - 1499110707747 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:27 INFO - 1499110707747 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707749 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707752 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f5d8b708-7509-4c7e-85ae-f0b99121c3d1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707754 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:38:27 INFO - 1499110707754 Marionette TRACE 384 <- [1,2,null,{}]
12:38:27 INFO - 1499110707755 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:27 INFO - 1499110707755 Marionette TRACE 384 <- [1,3,null,{}]
12:38:27 INFO - 1499110707756 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:27 INFO - 1499110707757 Marionette TRACE 384 <- [1,4,null,{}]
12:38:27 INFO - 1499110707758 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
12:38:27 INFO - TEST-START: test_click.py TestClick.test_input_file1499110707760 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:38:27 INFO - 1499110707761 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:38:27 INFO - 1499110707762 Marionette TRACE 384 <- [1,6,null,{}]
12:38:27 INFO - 1499110707763 Marionette DEBUG Closed connection 384
12:38:27 INFO - 1499110707764 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49803
12:38:27 INFO - 1499110707764 Marionette DEBUG Closed connection 385
12:38:27 INFO - 1499110707765 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49804
12:38:27 INFO - 1499110707766 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:27 INFO - 1499110707767 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707769 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707771 Marionette TRACE 386 <- [1,1,null,{"sessionId":"ff506b9f-5238-41fd-94e5-e9cc8d178e46","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707772 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:38:27 INFO - 1499110707773 Marionette TRACE 386 <- [1,2,null,{}]
12:38:27 INFO - 1499110707774 Marionette DEBUG Closed connection 386
12:38:27 INFO - 1499110707775 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49805
12:38:27 INFO - 1499110707776 Marionette DEBUG Closed connection 387
12:38:27 INFO - 1499110707776 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49806
12:38:27 INFO - 1499110707777 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:27 INFO - 1499110707778 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707780 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707783 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4705d43b-297d-4f72-88cb-4b041af6f5b3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707784 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:38:27 INFO - 1499110707785 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:27 INFO - 1499110707787 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:27 INFO - 1499110707787 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:38:27 INFO - 1499110707792 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:27 INFO - 1499110707795 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:27 INFO - 1499110707797 Marionette TRACE 388 <- [1,2,null,{}]
12:38:27 INFO - 1499110707802 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:38:27 INFO - 1499110707805 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5cf140aa-b5bb-4272-94a7-df682937eaad","ELEMENT":"5cf140aa-b5bb-4272-94a7-df682937eaad"}}]
12:38:27 INFO - 1499110707806 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"5cf140aa-b5bb-4272-94a7-df682937eaad"}]
12:38:27 INFO - 1499110707836 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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:38:27 INFO - 1499110707838 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
12:38:27 INFO - TEST-END: test_click.py TestClick.test_input_file1499110707840 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:38:27 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 99ms
12:38:27 INFO - 1499110707841 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:38:27 INFO - 1499110707842 Marionette TRACE 388 <- [1,6,null,{}]
12:38:27 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:38:27 INFO - 1499110707843 Marionette DEBUG Closed connection 388
12:38:27 INFO - 1499110707844 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49807
12:38:27 INFO - 1499110707845 Marionette DEBUG Closed connection 389
12:38:27 INFO - 1499110707846 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49808
12:38:27 INFO - 1499110707846 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:27 INFO - 1499110707847 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707849 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707851 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fe394dd9-eb24-49d2-ab54-18626d42f7bd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707861 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:38:27 INFO - 1499110707861 Marionette TRACE 390 <- [1,2,null,{}]
12:38:27 INFO - 1499110707862 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:27 INFO - 1499110707862 Marionette TRACE 390 <- [1,3,null,{}]
12:38:27 INFO - 1499110707863 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:27 INFO - 1499110707864 Marionette TRACE 390 <- [1,4,null,{}]
12:38:27 INFO - 1499110707865 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
12:38:27 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499110707867 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:38:27 INFO - 1499110707868 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:38:27 INFO - 1499110707869 Marionette TRACE 390 <- [1,6,null,{}]
12:38:27 INFO - 1499110707871 Marionette DEBUG Closed connection 390
12:38:27 INFO - 1499110707871 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49809
12:38:27 INFO - 1499110707872 Marionette DEBUG Closed connection 391
12:38:27 INFO - 1499110707872 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49810
12:38:27 INFO - 1499110707873 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:27 INFO - 1499110707874 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707876 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707878 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f0c28ae8-4201-4c93-ae33-39918ccefb6f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707879 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:38:27 INFO - 1499110707880 Marionette TRACE 392 <- [1,2,null,{}]
12:38:27 INFO - 1499110707882 Marionette DEBUG Closed connection 392
12:38:27 INFO - 1499110707882 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49811
12:38:27 INFO - 1499110707883 Marionette DEBUG Closed connection 393
12:38:27 INFO - 1499110707884 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49812
12:38:27 INFO - 1499110707884 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:27 INFO - 1499110707885 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:27 INFO - 1499110707887 Marionette DEBUG Register listener.js for window 4294967297
12:38:27 INFO - 1499110707889 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e952dac4-8111-4245-826c-93d88343ebd6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:27 INFO - 1499110707890 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
12:38:27 INFO - 1499110707892 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:27 INFO - 1499110707893 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:27 INFO - 1499110707894 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:27 INFO - 1499110707901 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:38:27 INFO - 1499110707902 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:38:27 INFO - 1499110707906 Marionette TRACE 394 <- [1,2,null,{}]
12:38:27 INFO - 1499110707909 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:38:27 INFO - 1499110707911 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ebf4ecf3-91cd-4808-8ec3-c2a101f400f0","ELEMENT":"ebf4ecf3-91cd-4808-8ec3-c2a101f400f0"}}]
12:38:27 INFO - 1499110707912 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:38:27 INFO - 1499110707913 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"44dc4c90-b9a3-4cbe-a2a5-754c96fdade1","ELEMENT":"44dc4c90-b9a3-4cbe-a2a5-754c96fdade1"}}]
12:38:27 INFO - 1499110707914 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"ebf4ecf3-91cd-4808-8ec3-c2a101f400f0"}]
12:38:28 INFO - 1499110708149 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:28 INFO - 1499110708150 Marionette TRACE 394 <- [1,5,null,{}]
12:38:28 INFO - 1499110708151 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"44dc4c90-b9a3-4cbe-a2a5-754c96fdade1"}]
12:38:28 INFO - 1499110708164 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:38:28 INFO - 1499110708166 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:38:28 INFO - 1499110708169 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:38:28 INFO - 1499110708170 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
12:38:28 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499110708172 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:38:28 INFO - 1499110708173 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:38:28 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 331ms
12:38:28 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:38:28 INFO - 1499110708174 Marionette TRACE 394 <- [1,9,null,{}]
12:38:28 INFO - 1499110708176 Marionette DEBUG Closed connection 394
12:38:28 INFO - 1499110708176 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49813
12:38:28 INFO - 1499110708177 Marionette DEBUG Closed connection 395
12:38:28 INFO - 1499110708178 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49814
12:38:28 INFO - 1499110708179 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:28 INFO - 1499110708179 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:28 INFO - 1499110708181 Marionette DEBUG Register listener.js for window 4294967297
12:38:28 INFO - 1499110708184 Marionette TRACE 396 <- [1,1,null,{"sessionId":"4bd792c6-3a39-403a-9e1a-fc19f5c1e7d5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:28 INFO - 1499110708185 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:38:28 INFO - 1499110708185 Marionette TRACE 396 <- [1,2,null,{}]
12:38:28 INFO - 1499110708186 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:28 INFO - 1499110708186 Marionette TRACE 396 <- [1,3,null,{}]
12:38:28 INFO - 1499110708187 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:28 INFO - 1499110708188 Marionette TRACE 396 <- [1,4,null,{}]
12:38:28 INFO - 1499110708190 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
12:38:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499110708192 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:38:28 INFO - 1499110708193 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:38:28 INFO - 1499110708194 Marionette TRACE 396 <- [1,6,null,{}]
12:38:28 INFO - 1499110708195 Marionette DEBUG Closed connection 396
12:38:28 INFO - 1499110708195 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49815
12:38:28 INFO - 1499110708196 Marionette DEBUG Closed connection 397
12:38:28 INFO - 1499110708197 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49816
12:38:28 INFO - 1499110708198 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:28 INFO - 1499110708198 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:28 INFO - 1499110708200 Marionette DEBUG Register listener.js for window 4294967297
12:38:28 INFO - 1499110708203 Marionette TRACE 398 <- [1,1,null,{"sessionId":"2128d6b7-cd92-4f4d-b756-f95f29bd2b53","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:28 INFO - 1499110708204 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:38:28 INFO - 1499110708205 Marionette TRACE 398 <- [1,2,null,{}]
12:38:28 INFO - 1499110708206 Marionette DEBUG Closed connection 398
12:38:28 INFO - 1499110708206 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49817
12:38:28 INFO - 1499110708207 Marionette DEBUG Closed connection 399
12:38:28 INFO - 1499110708208 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49818
12:38:28 INFO - 1499110708209 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:28 INFO - 1499110708209 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}
12:38:28 INFO - 1499110708211 Marionette DEBUG Register listener.js for window 4294967297
12:38:28 INFO - 1499110708213 Marionette TRACE 400 <- [1,1,null,{"sessionId":"100a13c9-83c9-41a8-b635-a6ec5cdbf12a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":748,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-186.001\\AppData\\Local\\Temp\\tmpxpyzxi.mozrunner","moz:accessibilityChecks":false}}]
12:38:28 INFO - 1499110708214 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
12:38:28 INFO - 1499110708216 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:38:28 INFO - 1499110708217 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:38:28 INFO - 1499110708218 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:38:28 INFO - 1499110708223 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
12:38:28 INFO - 1499110708226 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
12:38:28 INFO - 1499110708228 Marionette TRACE 400 <- [1,2,null,{}]
12:38:28 INFO - 1499110708231 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:38:28 INFO - 1499110708236 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"300c85b6-fdbd-4eff-a649-0039a6bc2c23","ELEMENT":"300c85b6-fdbd-4eff-a649-0039a6bc2c23"}}]
12:38:28 INFO - 1499110708237 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"300c85b6-fdbd-4eff-a649-0039a6bc2c23"}]
12:38:28 INFO - 1499110708263 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:38:28 INFO - 1499110708264 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"300c85b6-fdbd-4eff-a649-0039a6bc2c23"}]
12:38:28 INFO - 1499110708278 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element