could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:08:56 INFO - 1499598536281 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}]
04:08:56 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499598536283 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:08:56 INFO - 1499598536284 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:08:56 INFO - 1499598536286 Marionette TRACE 352 <- [1,6,null,{}]
04:08:56 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 151ms
04:08:56 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:08:56 INFO - 1499598536288 Marionette DEBUG Closed connection 352
04:08:56 INFO - 1499598536288 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49750
04:08:56 INFO - 1499598536289 Marionette DEBUG Closed connection 353
04:08:56 INFO - 1499598536290 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49751
04:08:56 INFO - 1499598536291 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:56 INFO - 1499598536291 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536294 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536297 Marionette TRACE 354 <- [1,1,null,{"sessionId":"5800bbd2-1905-4f0e-ba01-309aa2006996","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536299 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:08:56 INFO - 1499598536300 Marionette TRACE 354 <- [1,2,null,{}]
04:08:56 INFO - 1499598536301 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:56 INFO - 1499598536302 Marionette TRACE 354 <- [1,3,null,{}]
04:08:56 INFO - 1499598536303 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:56 INFO - 1499598536304 Marionette TRACE 354 <- [1,4,null,{}]
04:08:56 INFO - 1499598536306 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}]
04:08:56 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499598536309 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:08:56 INFO - 1499598536310 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:08:56 INFO - 1499598536311 Marionette TRACE 354 <- [1,6,null,{}]
04:08:56 INFO - 1499598536314 Marionette DEBUG Closed connection 354
04:08:56 INFO - 1499598536315 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49752
04:08:56 INFO - 1499598536316 Marionette DEBUG Closed connection 355
04:08:56 INFO - 1499598536316 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49753
04:08:56 INFO - 1499598536317 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:56 INFO - 1499598536318 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536320 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536323 Marionette TRACE 356 <- [1,1,null,{"sessionId":"b249e6af-b09b-46fb-9396-d2e2b18d8339","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536325 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:08:56 INFO - 1499598536327 Marionette TRACE 356 <- [1,2,null,{}]
04:08:56 INFO - 1499598536329 Marionette DEBUG Closed connection 356
04:08:56 INFO - 1499598536329 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49754
04:08:56 INFO - 1499598536330 Marionette DEBUG Closed connection 357
04:08:56 INFO - 1499598536331 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49755
04:08:56 INFO - 1499598536332 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:56 INFO - 1499598536333 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536336 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536339 Marionette TRACE 358 <- [1,1,null,{"sessionId":"863dc102-6b61-499a-b940-e01eec488fa2","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536341 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49326/clicks.html"}]
04:08:56 INFO - 1499598536342 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/hidden.html"
04:08:56 INFO - 1499598536347 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/hidden.html"
04:08:56 INFO - 1499598536348 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/hidden.html"
04:08:56 INFO - 1499598536368 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/clicks.html"
04:08:56 INFO - 1499598536374 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/clicks.html"
04:08:56 INFO - 1499598536384 Marionette TRACE 358 <- [1,2,null,{}]
04:08:56 INFO - 1499598536386 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:08:56 INFO - 1499598536388 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5ba073d4-d959-468c-a2ad-20ae5eeaea86","ELEMENT":"5ba073d4-d959-468c-a2ad-20ae5eeaea86"}}]
04:08:56 INFO - 1499598536390 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5ba073d4-d959-468c-a2ad-20ae5eeaea86"}]
04:08:56 INFO - 1499598536438 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/clicks.html"
04:08:56 INFO - 1499598536447 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/clicks.html"
04:08:56 INFO - 1499598536447 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/clicks.html"
04:08:56 INFO - 1499598536467 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/test.html"
04:08:56 INFO - 1499598536469 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/test.html"
04:08:56 INFO - 1499598536471 Marionette TRACE 358 <- [1,4,null,{}]
04:08:56 INFO - 1499598536479 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:08:56 INFO - 1499598536482 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6bf05005-e5ff-4a80-be27-0bcf8cee5bfb","ELEMENT":"6bf05005-e5ff-4a80-be27-0bcf8cee5bfb"}}]
04:08:56 INFO - 1499598536483 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:08:56 INFO - 1499598536483 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:08:56 INFO - 1499598536485 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}]
04:08:56 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499598536487 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:08:56 INFO - 1499598536489 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:08:56 INFO - 1499598536490 Marionette TRACE 358 <- [1,8,null,{}]
04:08:56 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 204ms
04:08:56 INFO - TEST-START | test_click.py TestClick.test_container_element
04:08:56 INFO - 1499598536493 Marionette DEBUG Closed connection 358
04:08:56 INFO - 1499598536494 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49758
04:08:56 INFO - 1499598536494 Marionette DEBUG Closed connection 359
04:08:56 INFO - 1499598536495 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49759
04:08:56 INFO - 1499598536496 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:56 INFO - 1499598536497 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536500 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536503 Marionette TRACE 360 <- [1,1,null,{"sessionId":"7a4b8ca6-6f2f-46fe-940a-ed6f78f59690","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536506 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:08:56 INFO - 1499598536506 Marionette TRACE 360 <- [1,2,null,{}]
04:08:56 INFO - 1499598536507 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:56 INFO - 1499598536508 Marionette TRACE 360 <- [1,3,null,{}]
04:08:56 INFO - 1499598536509 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:56 INFO - 1499598536509 Marionette TRACE 360 <- [1,4,null,{}]
04:08:56 INFO - 1499598536511 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}]
04:08:56 INFO - TEST-START: test_click.py TestClick.test_container_element1499598536513 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:08:56 INFO - 1499598536514 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:08:56 INFO - 1499598536516 Marionette TRACE 360 <- [1,6,null,{}]
04:08:56 INFO - 1499598536518 Marionette DEBUG Closed connection 360
04:08:56 INFO - 1499598536518 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49760
04:08:56 INFO - 1499598536519 Marionette DEBUG Closed connection 361
04:08:56 INFO - 1499598536520 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49761
04:08:56 INFO - 1499598536521 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:56 INFO - 1499598536521 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536524 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536527 Marionette TRACE 362 <- [1,1,null,{"sessionId":"7742cdfd-b6cf-4104-9782-ef80ebca4a2a","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536529 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:08:56 INFO - 1499598536530 Marionette TRACE 362 <- [1,2,null,{}]
04:08:56 INFO - 1499598536532 Marionette DEBUG Closed connection 362
04:08:56 INFO - 1499598536533 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49762
04:08:56 INFO - 1499598536534 Marionette DEBUG Closed connection 363
04:08:56 INFO - 1499598536534 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49763
04:08:56 INFO - 1499598536535 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:56 INFO - 1499598536536 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536538 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536541 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f00332da-c7d5-4fd0-a515-fe71d80274d9","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536543 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"}]
04:08:56 INFO - 1499598536545 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/test.html"
04:08:56 INFO - 1499598536547 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/test.html"
04:08:56 INFO - 1499598536547 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/test.html"
04:08:56 INFO - 1499598536570 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"
04:08:56 INFO - 1499598536578 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"
04:08:56 INFO - 1499598536581 Marionette TRACE 364 <- [1,2,null,{}]
04:08:56 INFO - 1499598536583 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:08:56 INFO - 1499598536585 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"75fe77c2-a1e4-4f5b-ac56-ea0d00442076","ELEMENT":"75fe77c2-a1e4-4f5b-ac56-ea0d00442076"}}]
04:08:56 INFO - 1499598536586 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"75fe77c2-a1e4-4f5b-ac56-ea0d00442076"}]
04:08:56 INFO - 1499598536838 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:56 INFO - 1499598536839 Marionette TRACE 364 <- [1,4,null,{}]
04:08:56 INFO - 1499598536840 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"75fe77c2-a1e4-4f5b-ac56-ea0d00442076","name":"selected"}]
04:08:56 INFO - 1499598536845 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:08:56 INFO - 1499598536846 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}]
04:08:56 INFO - TEST-END: test_click.py TestClick.test_container_element1499598536849 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:08:56 INFO - 1499598536849 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:08:56 INFO - 1499598536851 Marionette TRACE 364 <- [1,7,null,{}]
04:08:56 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 360ms
04:08:56 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:08:56 INFO - 1499598536853 Marionette DEBUG Closed connection 364
04:08:56 INFO - 1499598536853 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49764
04:08:56 INFO - 1499598536855 Marionette DEBUG Closed connection 365
04:08:56 INFO - 1499598536856 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49765
04:08:56 INFO - 1499598536857 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:56 INFO - 1499598536858 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536860 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536863 Marionette TRACE 366 <- [1,1,null,{"sessionId":"0fef0d6f-840e-4f52-bf23-fcf8c05349fd","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536865 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:08:56 INFO - 1499598536866 Marionette TRACE 366 <- [1,2,null,{}]
04:08:56 INFO - 1499598536867 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:56 INFO - 1499598536867 Marionette TRACE 366 <- [1,3,null,{}]
04:08:56 INFO - 1499598536868 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:56 INFO - 1499598536870 Marionette TRACE 366 <- [1,4,null,{}]
04:08:56 INFO - 1499598536871 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}]
04:08:56 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499598536874 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:08:56 INFO - 1499598536875 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:08:56 INFO - 1499598536876 Marionette TRACE 366 <- [1,6,null,{}]
04:08:56 INFO - 1499598536878 Marionette DEBUG Closed connection 366
04:08:56 INFO - 1499598536879 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49766
04:08:56 INFO - 1499598536880 Marionette DEBUG Closed connection 367
04:08:56 INFO - 1499598536881 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49767
04:08:56 INFO - 1499598536882 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:56 INFO - 1499598536883 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536885 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536889 Marionette TRACE 368 <- [1,1,null,{"sessionId":"deb8f491-785c-4864-af28-c6531562fe86","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536891 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:08:56 INFO - 1499598536892 Marionette TRACE 368 <- [1,2,null,{}]
04:08:56 INFO - 1499598536894 Marionette DEBUG Closed connection 368
04:08:56 INFO - 1499598536895 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49768
04:08:56 INFO - 1499598536895 Marionette DEBUG Closed connection 369
04:08:56 INFO - 1499598536896 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49769
04:08:56 INFO - 1499598536897 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:56 INFO - 1499598536898 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:56 INFO - 1499598536907 Marionette DEBUG Register listener.js for window 12
04:08:56 INFO - 1499598536911 Marionette TRACE 370 <- [1,1,null,{"sessionId":"86ccf753-aa04-4e45-86ab-c1c4b2b87898","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:56 INFO - 1499598536913 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"}]
04:08:56 INFO - 1499598536915 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"
04:08:56 INFO - 1499598536917 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"
04:08:56 INFO - 1499598536917 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"
04:08:56 INFO - 1499598536935 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"
04:08:56 INFO - 1499598536940 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"
04:08:56 INFO - 1499598536950 Marionette TRACE 370 <- [1,2,null,{}]
04:08:56 INFO - 1499598536952 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:08:56 INFO - 1499598536954 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b1bd1440-0944-4f07-894e-ff05549d802a","ELEMENT":"b1bd1440-0944-4f07-894e-ff05549d802a"}}]
04:08:56 INFO - 1499598536955 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"b1bd1440-0944-4f07-894e-ff05549d802a"}]
04:08:57 INFO - 1499598537206 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:57 INFO - 1499598537207 Marionette TRACE 370 <- [1,4,null,{}]
04:08:57 INFO - 1499598537208 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"b1bd1440-0944-4f07-894e-ff05549d802a","name":"selected"}]
04:08:57 INFO - 1499598537212 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:08:57 INFO - 1499598537214 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}]
04:08:57 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499598537217 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:08:57 INFO - 1499598537218 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:08:57 INFO - 1499598537219 Marionette TRACE 370 <- [1,7,null,{}]
04:08:57 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
04:08:57 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:08:57 INFO - 1499598537222 Marionette DEBUG Closed connection 370
04:08:57 INFO - 1499598537222 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49770
04:08:57 INFO - 1499598537223 Marionette DEBUG Closed connection 371
04:08:57 INFO - 1499598537224 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49771
04:08:57 INFO - 1499598537225 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:57 INFO - 1499598537225 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537228 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537231 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c6557a24-5c8e-434c-9823-eab47c12d526","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537233 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:08:57 INFO - 1499598537233 Marionette TRACE 372 <- [1,2,null,{}]
04:08:57 INFO - 1499598537234 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:57 INFO - 1499598537234 Marionette TRACE 372 <- [1,3,null,{}]
04:08:57 INFO - 1499598537235 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:57 INFO - 1499598537236 Marionette TRACE 372 <- [1,4,null,{}]
04:08:57 INFO - 1499598537237 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}]
04:08:57 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499598537240 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:08:57 INFO - 1499598537241 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:08:57 INFO - 1499598537242 Marionette TRACE 372 <- [1,6,null,{}]
04:08:57 INFO - 1499598537245 Marionette DEBUG Closed connection 372
04:08:57 INFO - 1499598537245 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49772
04:08:57 INFO - 1499598537246 Marionette DEBUG Closed connection 373
04:08:57 INFO - 1499598537246 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49773
04:08:57 INFO - 1499598537248 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:57 INFO - 1499598537248 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537251 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537254 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d158b010-5822-4e51-ab2b-ac87366a6400","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537257 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:08:57 INFO - 1499598537259 Marionette TRACE 374 <- [1,2,null,{}]
04:08:57 INFO - 1499598537261 Marionette DEBUG Closed connection 374
04:08:57 INFO - 1499598537261 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49774
04:08:57 INFO - 1499598537262 Marionette DEBUG Closed connection 375
04:08:57 INFO - 1499598537263 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49775
04:08:57 INFO - 1499598537264 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:57 INFO - 1499598537265 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537267 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537270 Marionette TRACE 376 <- [1,1,null,{"sessionId":"0eb45898-f623-40ae-aa1b-1f543337b33f","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537272 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"}]
04:08:57 INFO - 1499598537274 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"
04:08:57 INFO - 1499598537276 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"
04:08:57 INFO - 1499598537276 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"
04:08:57 INFO - 1499598537297 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"
04:08:57 INFO - 1499598537299 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"
04:08:57 INFO - 1499598537302 Marionette TRACE 376 <- [1,2,null,{}]
04:08:57 INFO - 1499598537304 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:08:57 INFO - 1499598537314 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7b6aefc8-87c7-45b6-88ae-6a79609fd60d","ELEMENT":"7b6aefc8-87c7-45b6-88ae-6a79609fd60d"}}]
04:08:57 INFO - 1499598537316 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"7b6aefc8-87c7-45b6-88ae-6a79609fd60d"}]
04:08:57 INFO - 1499598537564 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:57 INFO - 1499598537565 Marionette TRACE 376 <- [1,4,null,{}]
04:08:57 INFO - 1499598537567 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}]
04:08:57 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499598537569 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:08:57 INFO - 1499598537570 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:08:57 INFO - 1499598537572 Marionette TRACE 376 <- [1,6,null,{}]
04:08:57 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 352ms
04:08:57 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:08:57 INFO - 1499598537574 Marionette DEBUG Closed connection 376
04:08:57 INFO - 1499598537575 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49776
04:08:57 INFO - 1499598537576 Marionette DEBUG Closed connection 377
04:08:57 INFO - 1499598537576 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49777
04:08:57 INFO - 1499598537578 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:57 INFO - 1499598537578 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537581 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537584 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ac85a807-7ddd-4233-8c05-f57b468807cd","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537586 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:08:57 INFO - 1499598537587 Marionette TRACE 378 <- [1,2,null,{}]
04:08:57 INFO - 1499598537588 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:57 INFO - 1499598537588 Marionette TRACE 378 <- [1,3,null,{}]
04:08:57 INFO - 1499598537589 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:57 INFO - 1499598537590 Marionette TRACE 378 <- [1,4,null,{}]
04:08:57 INFO - 1499598537592 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}]
04:08:57 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499598537594 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:08:57 INFO - 1499598537595 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:08:57 INFO - 1499598537596 Marionette TRACE 378 <- [1,6,null,{}]
04:08:57 INFO - 1499598537598 Marionette DEBUG Closed connection 378
04:08:57 INFO - 1499598537599 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49778
04:08:57 INFO - 1499598537600 Marionette DEBUG Closed connection 379
04:08:57 INFO - 1499598537600 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49779
04:08:57 INFO - 1499598537602 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:57 INFO - 1499598537602 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537605 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537608 Marionette TRACE 380 <- [1,1,null,{"sessionId":"811f7619-b61f-4cdf-b448-4b09fde6927f","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537610 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:08:57 INFO - 1499598537611 Marionette TRACE 380 <- [1,2,null,{}]
04:08:57 INFO - 1499598537613 Marionette DEBUG Closed connection 380
04:08:57 INFO - 1499598537614 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49780
04:08:57 INFO - 1499598537615 Marionette DEBUG Closed connection 381
04:08:57 INFO - 1499598537615 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49781
04:08:57 INFO - 1499598537616 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:57 INFO - 1499598537617 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537619 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537622 Marionette TRACE 382 <- [1,1,null,{"sessionId":"cc326fcf-d9b5-41a8-88a5-c1e6cf768ff6","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537624 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"}]
04:08:57 INFO - 1499598537626 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"
04:08:57 INFO - 1499598537628 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"
04:08:57 INFO - 1499598537628 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"
04:08:57 INFO - 1499598537648 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"
04:08:57 INFO - 1499598537650 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"
04:08:57 INFO - 1499598537654 Marionette TRACE 382 <- [1,2,null,{}]
04:08:57 INFO - 1499598537661 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:08:57 INFO - 1499598537663 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6817e7fa-3b35-45bb-8b5d-60845090a145","ELEMENT":"6817e7fa-3b35-45bb-8b5d-60845090a145"}}]
04:08:57 INFO - 1499598537664 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"6817e7fa-3b35-45bb-8b5d-60845090a145"}]
04:08:57 INFO - 1499598537911 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:57 INFO - 1499598537912 Marionette TRACE 382 <- [1,4,null,{}]
04:08:57 INFO - 1499598537913 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}]
04:08:57 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499598537916 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:08:57 INFO - 1499598537917 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:08:57 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 346ms
04:08:57 INFO - 1499598537918 Marionette TRACE 382 <- [1,6,null,{}]
04:08:57 INFO - TEST-START | test_click.py TestClick.test_input_file
04:08:57 INFO - 1499598537921 Marionette DEBUG Closed connection 382
04:08:57 INFO - 1499598537923 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49782
04:08:57 INFO - 1499598537923 Marionette DEBUG Closed connection 383
04:08:57 INFO - 1499598537924 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49783
04:08:57 INFO - 1499598537925 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:57 INFO - 1499598537925 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537928 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537931 Marionette TRACE 384 <- [1,1,null,{"sessionId":"c861e16d-fe83-4e2a-bffa-4609002d5c6d","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537933 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:08:57 INFO - 1499598537933 Marionette TRACE 384 <- [1,2,null,{}]
04:08:57 INFO - 1499598537934 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:57 INFO - 1499598537935 Marionette TRACE 384 <- [1,3,null,{}]
04:08:57 INFO - 1499598537936 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:57 INFO - 1499598537936 Marionette TRACE 384 <- [1,4,null,{}]
04:08:57 INFO - 1499598537938 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}]
04:08:57 INFO - TEST-START: test_click.py TestClick.test_input_file1499598537940 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:08:57 INFO - 1499598537941 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:08:57 INFO - 1499598537943 Marionette TRACE 384 <- [1,6,null,{}]
04:08:57 INFO - 1499598537945 Marionette DEBUG Closed connection 384
04:08:57 INFO - 1499598537945 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49784
04:08:57 INFO - 1499598537946 Marionette DEBUG Closed connection 385
04:08:57 INFO - 1499598537947 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49785
04:08:57 INFO - 1499598537948 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:57 INFO - 1499598537949 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537951 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537954 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1a537654-ed09-4a37-8aed-26b83cadbee8","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537956 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:08:57 INFO - 1499598537958 Marionette TRACE 386 <- [1,2,null,{}]
04:08:57 INFO - 1499598537960 Marionette DEBUG Closed connection 386
04:08:57 INFO - 1499598537960 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49786
04:08:57 INFO - 1499598537961 Marionette DEBUG Closed connection 387
04:08:57 INFO - 1499598537962 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49787
04:08:57 INFO - 1499598537963 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:57 INFO - 1499598537964 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:57 INFO - 1499598537967 Marionette DEBUG Register listener.js for window 12
04:08:57 INFO - 1499598537970 Marionette TRACE 388 <- [1,1,null,{"sessionId":"08851a58-47ee-4a28-bf08-bf867ff9311e","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:57 INFO - 1499598537972 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:08:57 INFO - 1499598537973 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"
04:08:57 INFO - 1499598537976 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"
04:08:57 INFO - 1499598537976 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"
04:08:58 INFO - 1499598538007 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:58 INFO - 1499598538017 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:58 INFO - 1499598538019 Marionette TRACE 388 <- [1,2,null,{}]
04:08:58 INFO - 1499598538025 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:08:58 INFO - 1499598538028 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"866ae966-225d-4ffd-95cb-5833e930d46c","ELEMENT":"866ae966-225d-4ffd-95cb-5833e930d46c"}}]
04:08:58 INFO - 1499598538029 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"866ae966-225d-4ffd-95cb-5833e930d46c"}]
04:08:58 INFO - 1499598538057 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:08:58 INFO - 1499598538059 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}]
04:08:58 INFO - TEST-END: test_click.py TestClick.test_input_file1499598538061 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:08:58 INFO - 1499598538062 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:08:58 INFO - 1499598538064 Marionette TRACE 388 <- [1,6,null,{}]
04:08:58 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 144ms
04:08:58 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:08:58 INFO - 1499598538066 Marionette DEBUG Closed connection 388
04:08:58 INFO - 1499598538066 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49788
04:08:58 INFO - 1499598538068 Marionette DEBUG Closed connection 389
04:08:58 INFO - 1499598538068 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49789
04:08:58 INFO - 1499598538069 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:58 INFO - 1499598538070 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:58 INFO - 1499598538073 Marionette DEBUG Register listener.js for window 12
04:08:58 INFO - 1499598538076 Marionette TRACE 390 <- [1,1,null,{"sessionId":"90da4593-1ee7-4096-9f72-0d8e3417935a","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:58 INFO - 1499598538078 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:08:58 INFO - 1499598538078 Marionette TRACE 390 <- [1,2,null,{}]
04:08:58 INFO - 1499598538079 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:58 INFO - 1499598538080 Marionette TRACE 390 <- [1,3,null,{}]
04:08:58 INFO - 1499598538081 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:58 INFO - 1499598538081 Marionette TRACE 390 <- [1,4,null,{}]
04:08:58 INFO - 1499598538083 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}]
04:08:58 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499598538085 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:08:58 INFO - 1499598538086 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:08:58 INFO - 1499598538087 Marionette TRACE 390 <- [1,6,null,{}]
04:08:58 INFO - 1499598538090 Marionette DEBUG Closed connection 390
04:08:58 INFO - 1499598538090 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49790
04:08:58 INFO - 1499598538091 Marionette DEBUG Closed connection 391
04:08:58 INFO - 1499598538092 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49791
04:08:58 INFO - 1499598538093 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:58 INFO - 1499598538093 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:58 INFO - 1499598538096 Marionette DEBUG Register listener.js for window 12
04:08:58 INFO - 1499598538099 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a3314501-2b3f-4a26-896c-d497fb8f5170","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:58 INFO - 1499598538101 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:08:58 INFO - 1499598538103 Marionette TRACE 392 <- [1,2,null,{}]
04:08:58 INFO - 1499598538105 Marionette DEBUG Closed connection 392
04:08:58 INFO - 1499598538105 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49792
04:08:58 INFO - 1499598538106 Marionette DEBUG Closed connection 393
04:08:58 INFO - 1499598538107 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49793
04:08:58 INFO - 1499598538108 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:58 INFO - 1499598538109 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:58 INFO - 1499598538111 Marionette DEBUG Register listener.js for window 12
04:08:58 INFO - 1499598538114 Marionette TRACE 394 <- [1,1,null,{"sessionId":"4be29179-3e1e-4084-8107-41bdb6c41e5a","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:58 INFO - 1499598538116 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"}]
04:08:58 INFO - 1499598538118 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:58 INFO - 1499598538120 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:58 INFO - 1499598538120 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:58 INFO - 1499598538142 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"
04:08:58 INFO - 1499598538144 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"
04:08:58 INFO - 1499598538148 Marionette TRACE 394 <- [1,2,null,{}]
04:08:58 INFO - 1499598538149 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:08:58 INFO - 1499598538151 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84c9606d-e969-4957-bf6a-13f143cbda5d","ELEMENT":"84c9606d-e969-4957-bf6a-13f143cbda5d"}}]
04:08:58 INFO - 1499598538152 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:08:58 INFO - 1499598538153 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f22d2d1-befb-4c55-b817-c83fb0673775","ELEMENT":"3f22d2d1-befb-4c55-b817-c83fb0673775"}}]
04:08:58 INFO - 1499598538154 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"84c9606d-e969-4957-bf6a-13f143cbda5d"}]
04:08:58 INFO - 1499598538467 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:58 INFO - 1499598538468 Marionette TRACE 394 <- [1,5,null,{}]
04:08:58 INFO - 1499598538471 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"3f22d2d1-befb-4c55-b817-c83fb0673775"}]
04:08:58 INFO - 1499598538493 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:08:58 INFO - 1499598538496 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:08:58 INFO - 1499598538501 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:08:58 INFO - 1499598538503 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}]
04:08:58 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499598538507 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:08:58 INFO - 1499598538509 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:08:58 INFO - 1499598538511 Marionette TRACE 394 <- [1,9,null,{}]
04:08:58 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 447ms
04:08:58 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:08:58 INFO - 1499598538515 Marionette DEBUG Closed connection 394
04:08:58 INFO - 1499598538516 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49794
04:08:58 INFO - 1499598538518 Marionette DEBUG Closed connection 395
04:08:58 INFO - 1499598538518 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49795
04:08:58 INFO - 1499598538520 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:58 INFO - 1499598538523 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:58 INFO - 1499598538526 Marionette DEBUG Register listener.js for window 12
04:08:58 INFO - 1499598538533 Marionette TRACE 396 <- [1,1,null,{"sessionId":"275ec273-3bea-4027-b7f1-85cf7889dc51","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:58 INFO - 1499598538537 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:08:58 INFO - 1499598538538 Marionette TRACE 396 <- [1,2,null,{}]
04:08:58 INFO - 1499598538539 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:58 INFO - 1499598538540 Marionette TRACE 396 <- [1,3,null,{}]
04:08:58 INFO - 1499598538541 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:58 INFO - 1499598538541 Marionette TRACE 396 <- [1,4,null,{}]
04:08:58 INFO - 1499598538544 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}]
04:08:58 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499598538548 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:08:58 INFO - 1499598538549 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:08:58 INFO - 1499598538551 Marionette TRACE 396 <- [1,6,null,{}]
04:08:58 INFO - 1499598538554 Marionette DEBUG Closed connection 396
04:08:58 INFO - 1499598538555 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49796
04:08:58 INFO - 1499598538593 Marionette DEBUG Closed connection 397
04:08:58 INFO - 1499598538594 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49797
04:08:58 INFO - 1499598538601 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:58 INFO - 1499598538603 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:58 INFO - 1499598538606 Marionette DEBUG Register listener.js for window 12
04:08:58 INFO - 1499598538612 Marionette TRACE 398 <- [1,1,null,{"sessionId":"21b9d282-8fb0-45b3-bb3f-815b52bae115","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:58 INFO - 1499598538622 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:08:58 INFO - 1499598538625 Marionette TRACE 398 <- [1,2,null,{}]
04:08:58 INFO - 1499598538628 Marionette DEBUG Closed connection 398
04:08:58 INFO - 1499598538628 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49798
04:08:58 INFO - 1499598538630 Marionette DEBUG Closed connection 399
04:08:58 INFO - 1499598538631 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49799
04:08:58 INFO - 1499598538632 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:58 INFO - 1499598538633 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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}
04:08:58 INFO - 1499598538637 Marionette DEBUG Register listener.js for window 12
04:08:58 INFO - 1499598538643 Marionette TRACE 400 <- [1,1,null,{"sessionId":"87e12d91-0ad5-4e06-952f-a4fbe2961ade","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":2300,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpw0ky_x.mozrunner","moz:accessibilityChecks":false}}]
04:08:58 INFO - 1499598538646 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"}]
04:08:58 INFO - 1499598538650 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"
04:08:58 INFO - 1499598538654 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"
04:08:58 INFO - 1499598538655 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"
04:08:58 INFO - 1499598538680 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"
04:08:58 INFO - 1499598538683 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"
04:08:58 INFO - 1499598538688 Marionette TRACE 400 <- [1,2,null,{}]
04:08:58 INFO - 1499598538699 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:08:58 INFO - 1499598538703 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1ae29da5-f84f-4702-a48c-c08f57dadb70","ELEMENT":"1ae29da5-f84f-4702-a48c-c08f57dadb70"}}]
04:08:58 INFO - 1499598538704 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1ae29da5-f84f-4702-a48c-c08f57dadb70"}]
04:08:58 INFO - 1499598538733 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:08:58 INFO - 1499598538735 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"1ae29da5-f84f-4702-a48c-c08f57dadb70"}]
04:08:58 INFO - 1499598538754 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element