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]
18:51:36 INFO - 1499133096264 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}]
18:51:36 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499133096266 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:51:36 INFO - 1499133096267 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:51:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 133ms
18:51:36 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:51:36 INFO - 1499133096269 Marionette TRACE 352 <- [1,6,null,{}]
18:51:36 INFO - 1499133096270 Marionette DEBUG Closed connection 352
18:51:36 INFO - 1499133096271 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49769
18:51:36 INFO - 1499133096272 Marionette DEBUG Closed connection 353
18:51:36 INFO - 1499133096273 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49770
18:51:36 INFO - 1499133096274 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:36 INFO - 1499133096274 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096277 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096280 Marionette TRACE 354 <- [1,1,null,{"sessionId":"6f417a81-a30c-4619-9725-d0d2c929de30","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096282 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:51:36 INFO - 1499133096282 Marionette TRACE 354 <- [1,2,null,{}]
18:51:36 INFO - 1499133096283 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:51:36 INFO - 1499133096283 Marionette TRACE 354 <- [1,3,null,{}]
18:51:36 INFO - 1499133096284 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:51:36 INFO - 1499133096285 Marionette TRACE 354 <- [1,4,null,{}]
18:51:36 INFO - 1499133096287 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}]
18:51:36 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499133096289 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:51:36 INFO - 1499133096290 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:51:36 INFO - 1499133096292 Marionette TRACE 354 <- [1,6,null,{}]
18:51:36 INFO - 1499133096294 Marionette DEBUG Closed connection 354
18:51:36 INFO - 1499133096294 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49771
18:51:36 INFO - 1499133096295 Marionette DEBUG Closed connection 355
18:51:36 INFO - 1499133096296 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49772
18:51:36 INFO - 1499133096297 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:36 INFO - 1499133096298 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096300 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096304 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d908dbbd-b6da-46d7-8bf9-0ad3b07719a3","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096305 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:51:36 INFO - 1499133096306 Marionette TRACE 356 <- [1,2,null,{}]
18:51:36 INFO - 1499133096308 Marionette DEBUG Closed connection 356
18:51:36 INFO - 1499133096309 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49773
18:51:36 INFO - 1499133096310 Marionette DEBUG Closed connection 357
18:51:36 INFO - 1499133096310 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49774
18:51:36 INFO - 1499133096311 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:51:36 INFO - 1499133096312 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096315 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096318 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b1022ffa-250a-427f-8781-a94a6ee49a6b","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096319 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
18:51:36 INFO - 1499133096321 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
18:51:36 INFO - 1499133096328 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
18:51:36 INFO - 1499133096328 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
18:51:36 INFO - 1499133096335 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
18:51:36 INFO - 1499133096344 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
18:51:36 INFO - 1499133096345 Marionette TRACE 358 <- [1,2,null,{}]
18:51:36 INFO - 1499133096350 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:51:36 INFO - 1499133096354 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"85237431-3968-4d5a-81b1-aebe5fecab4c","ELEMENT":"85237431-3968-4d5a-81b1-aebe5fecab4c"}}]
18:51:36 INFO - 1499133096356 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"85237431-3968-4d5a-81b1-aebe5fecab4c"}]
18:51:36 INFO - 1499133096400 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
18:51:36 INFO - 1499133096412 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
18:51:36 INFO - 1499133096413 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
18:51:36 INFO - 1499133096421 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
18:51:36 INFO - 1499133096425 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
18:51:36 INFO - 1499133096429 Marionette TRACE 358 <- [1,4,null,{}]
18:51:36 INFO - 1499133096433 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:51:36 INFO - 1499133096436 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"55e1dfd6-c255-4a9d-bd3a-a4fce6e80b57","ELEMENT":"55e1dfd6-c255-4a9d-bd3a-a4fce6e80b57"}}]
18:51:36 INFO - 1499133096437 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:51:36 INFO - 1499133096438 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:51:36 INFO - 1499133096446 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}]
18:51:36 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499133096449 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:51:36 INFO - 1499133096451 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:51:36 INFO - 1499133096452 Marionette TRACE 358 <- [1,8,null,{}]
18:51:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 183ms
18:51:36 INFO - TEST-START | test_click.py TestClick.test_container_element
18:51:36 INFO - 1499133096454 Marionette DEBUG Closed connection 358
18:51:36 INFO - 1499133096455 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49777
18:51:36 INFO - 1499133096456 Marionette DEBUG Closed connection 359
18:51:36 INFO - 1499133096457 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49778
18:51:36 INFO - 1499133096458 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:36 INFO - 1499133096458 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096461 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096465 Marionette TRACE 360 <- [1,1,null,{"sessionId":"e485e69e-55cc-41f1-8ba4-a6ccf7159b53","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096466 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:51:36 INFO - 1499133096467 Marionette TRACE 360 <- [1,2,null,{}]
18:51:36 INFO - 1499133096468 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:51:36 INFO - 1499133096468 Marionette TRACE 360 <- [1,3,null,{}]
18:51:36 INFO - 1499133096469 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:51:36 INFO - 1499133096470 Marionette TRACE 360 <- [1,4,null,{}]
18:51:36 INFO - 1499133096471 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}]
18:51:36 INFO - TEST-START: test_click.py TestClick.test_container_element1499133096474 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:51:36 INFO - 1499133096475 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:51:36 INFO - 1499133096477 Marionette TRACE 360 <- [1,6,null,{}]
18:51:36 INFO - 1499133096478 Marionette DEBUG Closed connection 360
18:51:36 INFO - 1499133096479 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49779
18:51:36 INFO - 1499133096480 Marionette DEBUG Closed connection 361
18:51:36 INFO - 1499133096480 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49780
18:51:36 INFO - 1499133096481 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:36 INFO - 1499133096482 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096485 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096488 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a58a1b09-9f54-4209-bdc6-986383047954","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096489 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:51:36 INFO - 1499133096491 Marionette TRACE 362 <- [1,2,null,{}]
18:51:36 INFO - 1499133096492 Marionette DEBUG Closed connection 362
18:51:36 INFO - 1499133096493 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49781
18:51:36 INFO - 1499133096494 Marionette DEBUG Closed connection 363
18:51:36 INFO - 1499133096494 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49782
18:51:36 INFO - 1499133096495 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:51:36 INFO - 1499133096496 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096499 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096502 Marionette TRACE 364 <- [1,1,null,{"sessionId":"09971231-6b95-4bbb-8ad6-7a8fc1be3111","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096503 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"}]
18:51:36 INFO - 1499133096505 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
18:51:36 INFO - 1499133096507 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
18:51:36 INFO - 1499133096508 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
18:51:36 INFO - 1499133096520 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"
18:51:36 INFO - 1499133096523 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"
18:51:36 INFO - 1499133096530 Marionette TRACE 364 <- [1,2,null,{}]
18:51:36 INFO - 1499133096533 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:51:36 INFO - 1499133096535 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6136d17f-467f-41b5-be9e-68bc9a85ad12","ELEMENT":"6136d17f-467f-41b5-be9e-68bc9a85ad12"}}]
18:51:36 INFO - 1499133096536 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6136d17f-467f-41b5-be9e-68bc9a85ad12"}]
18:51:36 INFO - 1499133096777 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:51:36 INFO - 1499133096800 Marionette TRACE 364 <- [1,4,null,{}]
18:51:36 INFO - 1499133096804 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6136d17f-467f-41b5-be9e-68bc9a85ad12","name":"selected"}]
18:51:36 INFO - 1499133096810 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:51:36 INFO - 1499133096814 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}]
18:51:36 INFO - TEST-END: test_click.py TestClick.test_container_element1499133096840 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:51:36 INFO - 1499133096842 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:51:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 394ms
18:51:36 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:51:36 INFO - 1499133096847 Marionette TRACE 364 <- [1,7,null,{}]
18:51:36 INFO - 1499133096850 Marionette DEBUG Closed connection 364
18:51:36 INFO - 1499133096851 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49783
18:51:36 INFO - 1499133096852 Marionette DEBUG Closed connection 365
18:51:36 INFO - 1499133096854 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49784
18:51:36 INFO - 1499133096856 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:36 INFO - 1499133096859 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096863 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096870 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8f42a495-643e-4b43-95ba-d98ea654b73b","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096873 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:51:36 INFO - 1499133096874 Marionette TRACE 366 <- [1,2,null,{}]
18:51:36 INFO - 1499133096879 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:51:36 INFO - 1499133096880 Marionette TRACE 366 <- [1,3,null,{}]
18:51:36 INFO - 1499133096882 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:51:36 INFO - 1499133096883 Marionette TRACE 366 <- [1,4,null,{}]
18:51:36 INFO - 1499133096885 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}]
18:51:36 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499133096889 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:51:36 INFO - 1499133096891 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:51:36 INFO - 1499133096893 Marionette TRACE 366 <- [1,6,null,{}]
18:51:36 INFO - 1499133096895 Marionette DEBUG Closed connection 366
18:51:36 INFO - 1499133096896 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49785
18:51:36 INFO - 1499133096897 Marionette DEBUG Closed connection 367
18:51:36 INFO - 1499133096898 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49786
18:51:36 INFO - 1499133096900 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:36 INFO - 1499133096901 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096904 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096909 Marionette TRACE 368 <- [1,1,null,{"sessionId":"bbf4c0f7-5392-44c9-bce9-ec1d601d58fc","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096912 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:51:36 INFO - 1499133096915 Marionette TRACE 368 <- [1,2,null,{}]
18:51:36 INFO - 1499133096917 Marionette DEBUG Closed connection 368
18:51:36 INFO - 1499133096918 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49787
18:51:36 INFO - 1499133096919 Marionette DEBUG Closed connection 369
18:51:36 INFO - 1499133096920 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49788
18:51:36 INFO - 1499133096921 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:51:36 INFO - 1499133096922 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:36 INFO - 1499133096925 Marionette DEBUG Register listener.js for window 4294967297
18:51:36 INFO - 1499133096929 Marionette TRACE 370 <- [1,1,null,{"sessionId":"bdf69612-e112-429c-bf52-6aa21ed9b31a","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:36 INFO - 1499133096930 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"}]
18:51:36 INFO - 1499133096937 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"
18:51:36 INFO - 1499133096939 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"
18:51:36 INFO - 1499133096939 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"
18:51:36 INFO - 1499133096946 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"
18:51:36 INFO - 1499133096949 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"
18:51:36 INFO - 1499133096962 Marionette TRACE 370 <- [1,2,null,{}]
18:51:36 INFO - 1499133096968 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:51:36 INFO - 1499133096971 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"49d63683-3598-47aa-b0d4-ba5659bdeb1f","ELEMENT":"49d63683-3598-47aa-b0d4-ba5659bdeb1f"}}]
18:51:36 INFO - 1499133096973 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"49d63683-3598-47aa-b0d4-ba5659bdeb1f"}]
18:51:37 INFO - 1499133097217 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:51:37 INFO - 1499133097218 Marionette TRACE 370 <- [1,4,null,{}]
18:51:37 INFO - 1499133097219 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"49d63683-3598-47aa-b0d4-ba5659bdeb1f","name":"selected"}]
18:51:37 INFO - 1499133097224 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:51:37 INFO - 1499133097226 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}]
18:51:37 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499133097229 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:51:37 INFO - 1499133097230 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:51:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 384ms
18:51:37 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:51:37 INFO - 1499133097232 Marionette TRACE 370 <- [1,7,null,{}]
18:51:37 INFO - 1499133097234 Marionette DEBUG Closed connection 370
18:51:37 INFO - 1499133097235 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49789
18:51:37 INFO - 1499133097236 Marionette DEBUG Closed connection 371
18:51:37 INFO - 1499133097236 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49790
18:51:37 INFO - 1499133097238 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:37 INFO - 1499133097238 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097242 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097246 Marionette TRACE 372 <- [1,1,null,{"sessionId":"842191fd-9921-438c-b8a5-5282278fbec5","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097247 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:51:37 INFO - 1499133097248 Marionette TRACE 372 <- [1,2,null,{}]
18:51:37 INFO - 1499133097249 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:51:37 INFO - 1499133097250 Marionette TRACE 372 <- [1,3,null,{}]
18:51:37 INFO - 1499133097251 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:51:37 INFO - 1499133097252 Marionette TRACE 372 <- [1,4,null,{}]
18:51:37 INFO - 1499133097254 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}]
18:51:37 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499133097257 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:51:37 INFO - 1499133097258 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:51:37 INFO - 1499133097260 Marionette TRACE 372 <- [1,6,null,{}]
18:51:37 INFO - 1499133097262 Marionette DEBUG Closed connection 372
18:51:37 INFO - 1499133097262 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49791
18:51:37 INFO - 1499133097263 Marionette DEBUG Closed connection 373
18:51:37 INFO - 1499133097264 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49792
18:51:37 INFO - 1499133097265 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:37 INFO - 1499133097266 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097269 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097273 Marionette TRACE 374 <- [1,1,null,{"sessionId":"eec60be3-9473-4f92-8295-bf568fcf9c0b","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097276 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:51:37 INFO - 1499133097278 Marionette TRACE 374 <- [1,2,null,{}]
18:51:37 INFO - 1499133097280 Marionette DEBUG Closed connection 374
18:51:37 INFO - 1499133097283 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49793
18:51:37 INFO - 1499133097284 Marionette DEBUG Closed connection 375
18:51:37 INFO - 1499133097284 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49794
18:51:37 INFO - 1499133097286 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:51:37 INFO - 1499133097288 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097291 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097295 Marionette TRACE 376 <- [1,1,null,{"sessionId":"13759e21-dd26-459d-9248-4d8b8e32c50f","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097296 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"}]
18:51:37 INFO - 1499133097298 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"
18:51:37 INFO - 1499133097300 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"
18:51:37 INFO - 1499133097300 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"
18:51:37 INFO - 1499133097312 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"
18:51:37 INFO - 1499133097313 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"
18:51:37 INFO - 1499133097322 Marionette TRACE 376 <- [1,2,null,{}]
18:51:37 INFO - 1499133097332 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:51:37 INFO - 1499133097335 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d981fc73-2a0e-4289-8c03-7d0dbd9fca36","ELEMENT":"d981fc73-2a0e-4289-8c03-7d0dbd9fca36"}}]
18:51:37 INFO - 1499133097336 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d981fc73-2a0e-4289-8c03-7d0dbd9fca36"}]
18:51:37 INFO - 1499133097582 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:51:37 INFO - 1499133097583 Marionette TRACE 376 <- [1,4,null,{}]
18:51:37 INFO - 1499133097585 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}]
18:51:37 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499133097588 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:51:37 INFO - 1499133097589 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:51:37 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 359ms
18:51:37 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:51:37 INFO - 1499133097591 Marionette TRACE 376 <- [1,6,null,{}]
18:51:37 INFO - 1499133097592 Marionette DEBUG Closed connection 376
18:51:37 INFO - 1499133097593 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49795
18:51:37 INFO - 1499133097594 Marionette DEBUG Closed connection 377
18:51:37 INFO - 1499133097595 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49796
18:51:37 INFO - 1499133097596 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:37 INFO - 1499133097596 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097599 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097603 Marionette TRACE 378 <- [1,1,null,{"sessionId":"fbeb32b7-8f8f-4333-8c25-a4c2fb0df158","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097604 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:51:37 INFO - 1499133097605 Marionette TRACE 378 <- [1,2,null,{}]
18:51:37 INFO - 1499133097606 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:51:37 INFO - 1499133097606 Marionette TRACE 378 <- [1,3,null,{}]
18:51:37 INFO - 1499133097607 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:51:37 INFO - 1499133097608 Marionette TRACE 378 <- [1,4,null,{}]
18:51:37 INFO - 1499133097610 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}]
18:51:37 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499133097612 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:51:37 INFO - 1499133097613 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:51:37 INFO - 1499133097615 Marionette TRACE 378 <- [1,6,null,{}]
18:51:37 INFO - 1499133097616 Marionette DEBUG Closed connection 378
18:51:37 INFO - 1499133097617 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49797
18:51:37 INFO - 1499133097618 Marionette DEBUG Closed connection 379
18:51:37 INFO - 1499133097619 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49798
18:51:37 INFO - 1499133097620 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:37 INFO - 1499133097620 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097623 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097626 Marionette TRACE 380 <- [1,1,null,{"sessionId":"72e7eda1-e497-4852-89a2-4ddacb6df049","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097628 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:51:37 INFO - 1499133097629 Marionette TRACE 380 <- [1,2,null,{}]
18:51:37 INFO - 1499133097631 Marionette DEBUG Closed connection 380
18:51:37 INFO - 1499133097631 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49799
18:51:37 INFO - 1499133097632 Marionette DEBUG Closed connection 381
18:51:37 INFO - 1499133097633 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49800
18:51:37 INFO - 1499133097634 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:51:37 INFO - 1499133097634 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097637 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097640 Marionette TRACE 382 <- [1,1,null,{"sessionId":"3f193cf3-572e-40e1-8cb5-81a32c4a31b4","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097642 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"}]
18:51:37 INFO - 1499133097643 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"
18:51:37 INFO - 1499133097645 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"
18:51:37 INFO - 1499133097645 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"
18:51:37 INFO - 1499133097651 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"
18:51:37 INFO - 1499133097655 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"
18:51:37 INFO - 1499133097664 Marionette TRACE 382 <- [1,2,null,{}]
18:51:37 INFO - 1499133097668 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:51:37 INFO - 1499133097671 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d8def436-99c4-49f1-b6c0-73116ba75bd7","ELEMENT":"d8def436-99c4-49f1-b6c0-73116ba75bd7"}}]
18:51:37 INFO - 1499133097672 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d8def436-99c4-49f1-b6c0-73116ba75bd7"}]
18:51:37 INFO - 1499133097915 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:51:37 INFO - 1499133097916 Marionette TRACE 382 <- [1,4,null,{}]
18:51:37 INFO - 1499133097918 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}]
18:51:37 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499133097921 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:51:37 INFO - 1499133097922 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:51:37 INFO - 1499133097923 Marionette TRACE 382 <- [1,6,null,{}]
18:51:37 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 332ms
18:51:37 INFO - TEST-START | test_click.py TestClick.test_input_file
18:51:37 INFO - 1499133097925 Marionette DEBUG Closed connection 382
18:51:37 INFO - 1499133097926 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49801
18:51:37 INFO - 1499133097927 Marionette DEBUG Closed connection 383
18:51:37 INFO - 1499133097928 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49802
18:51:37 INFO - 1499133097929 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:37 INFO - 1499133097929 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097932 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097935 Marionette TRACE 384 <- [1,1,null,{"sessionId":"7ad3699e-bd01-4ce4-a780-089796fa899c","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097937 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:51:37 INFO - 1499133097937 Marionette TRACE 384 <- [1,2,null,{}]
18:51:37 INFO - 1499133097938 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:51:37 INFO - 1499133097939 Marionette TRACE 384 <- [1,3,null,{}]
18:51:37 INFO - 1499133097940 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:51:37 INFO - 1499133097940 Marionette TRACE 384 <- [1,4,null,{}]
18:51:37 INFO - 1499133097942 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}]
18:51:37 INFO - TEST-START: test_click.py TestClick.test_input_file1499133097947 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:51:37 INFO - 1499133097948 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:51:37 INFO - 1499133097950 Marionette TRACE 384 <- [1,6,null,{}]
18:51:37 INFO - 1499133097952 Marionette DEBUG Closed connection 384
18:51:37 INFO - 1499133097953 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49803
18:51:37 INFO - 1499133097954 Marionette DEBUG Closed connection 385
18:51:37 INFO - 1499133097955 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49804
18:51:37 INFO - 1499133097956 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:37 INFO - 1499133097958 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097962 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097966 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cbf509ea-bbb5-4263-8c30-7e877562ae50","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097967 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:51:37 INFO - 1499133097969 Marionette TRACE 386 <- [1,2,null,{}]
18:51:37 INFO - 1499133097970 Marionette DEBUG Closed connection 386
18:51:37 INFO - 1499133097972 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49805
18:51:37 INFO - 1499133097972 Marionette DEBUG Closed connection 387
18:51:37 INFO - 1499133097973 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49806
18:51:37 INFO - 1499133097974 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:51:37 INFO - 1499133097975 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:37 INFO - 1499133097978 Marionette DEBUG Register listener.js for window 4294967297
18:51:37 INFO - 1499133097981 Marionette TRACE 388 <- [1,1,null,{"sessionId":"354486bc-1d71-4836-b1f8-24b903f4c743","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:37 INFO - 1499133097983 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:51:37 INFO - 1499133097985 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"
18:51:37 INFO - 1499133097986 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"
18:51:37 INFO - 1499133097987 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"
18:51:38 INFO - 1499133097994 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:51:38 INFO - 1499133097999 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:51:38 INFO - 1499133098005 Marionette TRACE 388 <- [1,2,null,{}]
18:51:38 INFO - 1499133098012 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:51:38 INFO - 1499133098015 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80e3568d-5836-4231-bca2-3aee8e416c12","ELEMENT":"80e3568d-5836-4231-bca2-3aee8e416c12"}}]
18:51:38 INFO - 1499133098016 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"80e3568d-5836-4231-bca2-3aee8e416c12"}]
18:51:38 INFO - 1499133098051 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]
18:51:38 INFO - 1499133098053 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}]
18:51:38 INFO - TEST-END: test_click.py TestClick.test_input_file1499133098056 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:51:38 INFO - 1499133098057 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:51:38 INFO - 1499133098059 Marionette TRACE 388 <- [1,6,null,{}]
18:51:38 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 134ms
18:51:38 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:51:38 INFO - 1499133098060 Marionette DEBUG Closed connection 388
18:51:38 INFO - 1499133098061 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49807
18:51:38 INFO - 1499133098062 Marionette DEBUG Closed connection 389
18:51:38 INFO - 1499133098062 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49808
18:51:38 INFO - 1499133098064 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:38 INFO - 1499133098064 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:38 INFO - 1499133098067 Marionette DEBUG Register listener.js for window 4294967297
18:51:38 INFO - 1499133098070 Marionette TRACE 390 <- [1,1,null,{"sessionId":"5a3436b2-95fb-4935-9846-1fdcbbb66e8e","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:38 INFO - 1499133098071 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:51:38 INFO - 1499133098072 Marionette TRACE 390 <- [1,2,null,{}]
18:51:38 INFO - 1499133098073 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:51:38 INFO - 1499133098074 Marionette TRACE 390 <- [1,3,null,{}]
18:51:38 INFO - 1499133098074 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:51:38 INFO - 1499133098075 Marionette TRACE 390 <- [1,4,null,{}]
18:51:38 INFO - 1499133098077 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}]
18:51:38 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499133098080 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:51:38 INFO - 1499133098080 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:51:38 INFO - 1499133098082 Marionette TRACE 390 <- [1,6,null,{}]
18:51:38 INFO - 1499133098083 Marionette DEBUG Closed connection 390
18:51:38 INFO - 1499133098084 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49809
18:51:38 INFO - 1499133098085 Marionette DEBUG Closed connection 391
18:51:38 INFO - 1499133098085 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49810
18:51:38 INFO - 1499133098086 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:38 INFO - 1499133098087 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:38 INFO - 1499133098090 Marionette DEBUG Register listener.js for window 4294967297
18:51:38 INFO - 1499133098093 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a24711f3-e32e-4ddf-ad07-88ac039dd0d9","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:38 INFO - 1499133098094 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:51:38 INFO - 1499133098096 Marionette TRACE 392 <- [1,2,null,{}]
18:51:38 INFO - 1499133098097 Marionette DEBUG Closed connection 392
18:51:38 INFO - 1499133098098 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49811
18:51:38 INFO - 1499133098099 Marionette DEBUG Closed connection 393
18:51:38 INFO - 1499133098099 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49812
18:51:38 INFO - 1499133098100 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:51:38 INFO - 1499133098101 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:38 INFO - 1499133098104 Marionette DEBUG Register listener.js for window 4294967297
18:51:38 INFO - 1499133098107 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f71c38eb-da5a-4770-9713-94cf158f71cf","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:38 INFO - 1499133098108 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"}]
18:51:38 INFO - 1499133098110 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:51:38 INFO - 1499133098112 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:51:38 INFO - 1499133098112 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:51:38 INFO - 1499133098119 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"
18:51:38 INFO - 1499133098122 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"
18:51:38 INFO - 1499133098131 Marionette TRACE 394 <- [1,2,null,{}]
18:51:38 INFO - 1499133098134 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:51:38 INFO - 1499133098137 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e260c65c-afd4-483e-bb85-cd72d701b6bd","ELEMENT":"e260c65c-afd4-483e-bb85-cd72d701b6bd"}}]
18:51:38 INFO - 1499133098138 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:51:38 INFO - 1499133098140 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d7abbbba-d81e-4607-87c1-361a58fba526","ELEMENT":"d7abbbba-d81e-4607-87c1-361a58fba526"}}]
18:51:38 INFO - 1499133098145 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e260c65c-afd4-483e-bb85-cd72d701b6bd"}]
18:51:38 INFO - 1499133098385 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:51:38 INFO - 1499133098386 Marionette TRACE 394 <- [1,5,null,{}]
18:51:38 INFO - 1499133098387 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d7abbbba-d81e-4607-87c1-361a58fba526"}]
18:51:38 INFO - 1499133098403 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]
18:51:38 INFO - 1499133098405 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:51:38 INFO - 1499133098408 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:51:38 INFO - 1499133098409 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}]
18:51:38 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499133098412 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:51:38 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 355ms
18:51:38 INFO - 1499133098413 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:51:38 INFO - 1499133098415 Marionette TRACE 394 <- [1,9,null,{}]
18:51:38 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:51:38 INFO - 1499133098416 Marionette DEBUG Closed connection 394
18:51:38 INFO - 1499133098418 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49813
18:51:38 INFO - 1499133098419 Marionette DEBUG Closed connection 395
18:51:38 INFO - 1499133098420 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49814
18:51:38 INFO - 1499133098421 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:38 INFO - 1499133098421 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:38 INFO - 1499133098425 Marionette DEBUG Register listener.js for window 4294967297
18:51:38 INFO - 1499133098428 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9de61f60-59e4-4094-b0dc-e76fc19190db","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:38 INFO - 1499133098430 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:51:38 INFO - 1499133098430 Marionette TRACE 396 <- [1,2,null,{}]
18:51:38 INFO - 1499133098431 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:51:38 INFO - 1499133098432 Marionette TRACE 396 <- [1,3,null,{}]
18:51:38 INFO - 1499133098432 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:51:38 INFO - 1499133098433 Marionette TRACE 396 <- [1,4,null,{}]
18:51:38 INFO - 1499133098435 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}]
18:51:38 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499133098438 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:51:38 INFO - 1499133098439 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:51:38 INFO - 1499133098440 Marionette TRACE 396 <- [1,6,null,{}]
18:51:38 INFO - 1499133098442 Marionette DEBUG Closed connection 396
18:51:38 INFO - 1499133098443 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49815
18:51:38 INFO - 1499133098443 Marionette DEBUG Closed connection 397
18:51:38 INFO - 1499133098444 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49816
18:51:38 INFO - 1499133098445 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:51:38 INFO - 1499133098446 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:38 INFO - 1499133098448 Marionette DEBUG Register listener.js for window 4294967297
18:51:38 INFO - 1499133098452 Marionette TRACE 398 <- [1,1,null,{"sessionId":"e4b49211-0d7b-44e6-88a6-c8627a5be3d6","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:38 INFO - 1499133098453 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:51:38 INFO - 1499133098454 Marionette TRACE 398 <- [1,2,null,{}]
18:51:38 INFO - 1499133098456 Marionette DEBUG Closed connection 398
18:51:38 INFO - 1499133098456 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49817
18:51:38 INFO - 1499133098457 Marionette DEBUG Closed connection 399
18:51:38 INFO - 1499133098457 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49818
18:51:38 INFO - 1499133098458 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:51:38 INFO - 1499133098459 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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}
18:51:38 INFO - 1499133098462 Marionette DEBUG Register listener.js for window 4294967297
18:51:38 INFO - 1499133098465 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b4b4221d-0d80-4a3a-b98b-7e8e0ff6aef1","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":3564,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-235.001\\AppData\\Local\\Temp\\tmpdbkspf.mozrunner","moz:accessibilityChecks":false}}]
18:51:38 INFO - 1499133098466 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"}]
18:51:38 INFO - 1499133098468 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"
18:51:38 INFO - 1499133098470 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"
18:51:38 INFO - 1499133098471 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"
18:51:38 INFO - 1499133098480 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"
18:51:38 INFO - 1499133098481 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"
18:51:38 INFO - 1499133098491 Marionette TRACE 400 <- [1,2,null,{}]
18:51:38 INFO - 1499133098500 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:51:38 INFO - 1499133098502 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e96ec67-1c09-4a06-a1ac-5f48d283887b","ELEMENT":"6e96ec67-1c09-4a06-a1ac-5f48d283887b"}}]
18:51:38 INFO - 1499133098503 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6e96ec67-1c09-4a06-a1ac-5f48d283887b"}]
18:51:38 INFO - 1499133098528 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:51:38 INFO - 1499133098529 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"6e96ec67-1c09-4a06-a1ac-5f48d283887b"}]
18:51:38 INFO - 1499133098546 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element