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]
21:21:20 INFO - 1499660480551 Marionette TRACE 353 -> [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}]
21:21:20 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499660480554 Marionette TRACE 353 <- [1,5,null,{"value":null}]
21:21:20 INFO - 1499660480555 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
21:21:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 105ms
21:21:20 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:21:20 INFO - 1499660480556 Marionette TRACE 353 <- [1,6,null,{}]
21:21:20 INFO - 1499660480557 Marionette DEBUG Closed connection 353
21:21:20 INFO - 1499660480558 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49766
21:21:20 INFO - 1499660480559 Marionette DEBUG Closed connection 354
21:21:20 INFO - 1499660480560 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49767
21:21:20 INFO - 1499660480561 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:20 INFO - 1499660480561 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:20 INFO - 1499660480563 Marionette DEBUG Register listener.js for window 4294967297
21:21:20 INFO - 1499660480574 Marionette TRACE 355 <- [1,1,null,{"sessionId":"99783457-b272-4ab7-a2d0-4a8d932097cc","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:20 INFO - 1499660480575 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
21:21:20 INFO - 1499660480575 Marionette TRACE 355 <- [1,2,null,{}]
21:21:20 INFO - 1499660480576 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:20 INFO - 1499660480577 Marionette TRACE 355 <- [1,3,null,{}]
21:21:20 INFO - 1499660480577 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:20 INFO - 1499660480578 Marionette TRACE 355 <- [1,4,null,{}]
21:21:20 INFO - 1499660480579 Marionette TRACE 355 -> [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}]
21:21:20 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499660480581 Marionette TRACE 355 <- [1,5,null,{"value":null}]
21:21:20 INFO - 1499660480582 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
21:21:20 INFO - 1499660480584 Marionette TRACE 355 <- [1,6,null,{}]
21:21:20 INFO - 1499660480585 Marionette DEBUG Closed connection 355
21:21:20 INFO - 1499660480585 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49768
21:21:20 INFO - 1499660480586 Marionette DEBUG Closed connection 356
21:21:20 INFO - 1499660480587 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49769
21:21:20 INFO - 1499660480587 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:20 INFO - 1499660480588 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:20 INFO - 1499660480590 Marionette DEBUG Register listener.js for window 4294967297
21:21:20 INFO - 1499660480593 Marionette TRACE 357 <- [1,1,null,{"sessionId":"d71de793-0526-45e1-8fa0-c1265dc0f662","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:20 INFO - 1499660480594 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
21:21:20 INFO - 1499660480595 Marionette TRACE 357 <- [1,2,null,{}]
21:21:20 INFO - 1499660480596 Marionette DEBUG Closed connection 357
21:21:20 INFO - 1499660480597 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49770
21:21:20 INFO - 1499660480598 Marionette DEBUG Closed connection 358
21:21:20 INFO - 1499660480598 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49771
21:21:20 INFO - 1499660480599 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:20 INFO - 1499660480600 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:20 INFO - 1499660480602 Marionette DEBUG Register listener.js for window 4294967297
21:21:20 INFO - 1499660480605 Marionette TRACE 359 <- [1,1,null,{"sessionId":"631252e8-6e6a-4637-8d4d-f5b2e140cd2b","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:20 INFO - 1499660480606 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
21:21:20 INFO - 1499660480607 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
21:21:20 INFO - 1499660480614 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
21:21:20 INFO - 1499660480614 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
21:21:20 INFO - 1499660480621 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
21:21:20 INFO - 1499660480625 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
21:21:20 INFO - 1499660480635 Marionette TRACE 359 <- [1,2,null,{}]
21:21:20 INFO - 1499660480640 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:21:20 INFO - 1499660480646 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4e5d48ed-0d3e-4d84-b49b-f1b62c84f0d9","ELEMENT":"4e5d48ed-0d3e-4d84-b49b-f1b62c84f0d9"}}]
21:21:20 INFO - 1499660480647 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"4e5d48ed-0d3e-4d84-b49b-f1b62c84f0d9"}]
21:21:20 INFO - 1499660480690 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
21:21:20 INFO - 1499660480697 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
21:21:20 INFO - 1499660480698 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
21:21:20 INFO - 1499660480703 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
21:21:20 INFO - 1499660480706 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
21:21:20 INFO - 1499660480708 Marionette TRACE 359 <- [1,4,null,{}]
21:21:20 INFO - 1499660480714 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:21:20 INFO - 1499660480719 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b1e45597-33d4-4681-b6c8-e07c8a263e40","ELEMENT":"b1e45597-33d4-4681-b6c8-e07c8a263e40"}}]
21:21:20 INFO - 1499660480719 Marionette TRACE 359 -> [0,6,"getTitle",{}]
21:21:20 INFO - 1499660480720 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
21:21:20 INFO - 1499660480721 Marionette TRACE 359 -> [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}]
21:21:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499660480724 Marionette TRACE 359 <- [1,7,null,{"value":null}]
21:21:20 INFO - 1499660480724 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
21:21:20 INFO - 1499660480726 Marionette TRACE 359 <- [1,8,null,{}]
21:21:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 172ms
21:21:20 INFO - TEST-START | test_click.py TestClick.test_container_element
21:21:20 INFO - 1499660480730 Marionette DEBUG Closed connection 359
21:21:20 INFO - 1499660480731 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49774
21:21:20 INFO - 1499660480731 Marionette DEBUG Closed connection 360
21:21:20 INFO - 1499660480732 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49775
21:21:20 INFO - 1499660480733 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:20 INFO - 1499660480734 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:20 INFO - 1499660480736 Marionette DEBUG Register listener.js for window 4294967297
21:21:20 INFO - 1499660480739 Marionette TRACE 361 <- [1,1,null,{"sessionId":"acb0b5c0-59e8-475f-ab93-69ebe11fa65c","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:20 INFO - 1499660480740 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
21:21:20 INFO - 1499660480740 Marionette TRACE 361 <- [1,2,null,{}]
21:21:20 INFO - 1499660480741 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:20 INFO - 1499660480742 Marionette TRACE 361 <- [1,3,null,{}]
21:21:20 INFO - 1499660480742 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:20 INFO - 1499660480743 Marionette TRACE 361 <- [1,4,null,{}]
21:21:20 INFO - 1499660480744 Marionette TRACE 361 -> [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}]
21:21:20 INFO - TEST-START: test_click.py TestClick.test_container_element1499660480747 Marionette TRACE 361 <- [1,5,null,{"value":null}]
21:21:20 INFO - 1499660480747 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
21:21:20 INFO - 1499660480748 Marionette TRACE 361 <- [1,6,null,{}]
21:21:20 INFO - 1499660480750 Marionette DEBUG Closed connection 361
21:21:20 INFO - 1499660480750 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49776
21:21:20 INFO - 1499660480751 Marionette DEBUG Closed connection 362
21:21:20 INFO - 1499660480751 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49777
21:21:20 INFO - 1499660480752 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:20 INFO - 1499660480753 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:20 INFO - 1499660480755 Marionette DEBUG Register listener.js for window 4294967297
21:21:20 INFO - 1499660480758 Marionette TRACE 363 <- [1,1,null,{"sessionId":"cc3ba06c-8587-4811-a859-4006550957f4","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:20 INFO - 1499660480759 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
21:21:20 INFO - 1499660480760 Marionette TRACE 363 <- [1,2,null,{}]
21:21:20 INFO - 1499660480762 Marionette DEBUG Closed connection 363
21:21:20 INFO - 1499660480762 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49778
21:21:20 INFO - 1499660480763 Marionette DEBUG Closed connection 364
21:21:20 INFO - 1499660480764 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49779
21:21:20 INFO - 1499660480764 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:20 INFO - 1499660480765 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:20 INFO - 1499660480768 Marionette DEBUG Register listener.js for window 4294967297
21:21:20 INFO - 1499660480771 Marionette TRACE 365 <- [1,1,null,{"sessionId":"64ca89ef-af17-462c-9bd0-d44a8c362fd2","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:20 INFO - 1499660480772 Marionette TRACE 365 -> [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"}]
21:21:20 INFO - 1499660480774 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
21:21:20 INFO - 1499660480777 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
21:21:20 INFO - 1499660480777 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
21:21:20 INFO - 1499660480783 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"
21:21:20 INFO - 1499660480788 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"
21:21:20 INFO - 1499660480791 Marionette TRACE 365 <- [1,2,null,{}]
21:21:20 INFO - 1499660480798 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:21:20 INFO - 1499660480800 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8241f133-43b4-4c16-92a2-cc120678c561","ELEMENT":"8241f133-43b4-4c16-92a2-cc120678c561"}}]
21:21:20 INFO - 1499660480801 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"8241f133-43b4-4c16-92a2-cc120678c561"}]
21:21:21 INFO - 1499660481039 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:21 INFO - 1499660481040 Marionette TRACE 365 <- [1,4,null,{}]
21:21:21 INFO - 1499660481041 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"8241f133-43b4-4c16-92a2-cc120678c561","name":"selected"}]
21:21:21 INFO - 1499660481045 Marionette TRACE 365 <- [1,5,null,{"value":true}]
21:21:21 INFO - 1499660481046 Marionette TRACE 365 -> [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}]
21:21:21 INFO - TEST-END: test_click.py TestClick.test_container_element1499660481048 Marionette TRACE 365 <- [1,6,null,{"value":null}]
21:21:21 INFO - 1499660481049 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
21:21:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 321ms
21:21:21 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:21:21 INFO - 1499660481050 Marionette TRACE 365 <- [1,7,null,{}]
21:21:21 INFO - 1499660481052 Marionette DEBUG Closed connection 365
21:21:21 INFO - 1499660481052 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49780
21:21:21 INFO - 1499660481053 Marionette DEBUG Closed connection 366
21:21:21 INFO - 1499660481053 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49781
21:21:21 INFO - 1499660481054 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:21 INFO - 1499660481055 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481057 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481059 Marionette TRACE 367 <- [1,1,null,{"sessionId":"64052d34-d84c-450c-9ca0-872524097a8f","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481060 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
21:21:21 INFO - 1499660481061 Marionette TRACE 367 <- [1,2,null,{}]
21:21:21 INFO - 1499660481062 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:21 INFO - 1499660481062 Marionette TRACE 367 <- [1,3,null,{}]
21:21:21 INFO - 1499660481063 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:21 INFO - 1499660481063 Marionette TRACE 367 <- [1,4,null,{}]
21:21:21 INFO - 1499660481065 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
21:21:21 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499660481067 Marionette TRACE 367 <- [1,5,null,{"value":null}]
21:21:21 INFO - 1499660481068 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
21:21:21 INFO - 1499660481069 Marionette TRACE 367 <- [1,6,null,{}]
21:21:21 INFO - 1499660481070 Marionette DEBUG Closed connection 367
21:21:21 INFO - 1499660481071 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49782
21:21:21 INFO - 1499660481071 Marionette DEBUG Closed connection 368
21:21:21 INFO - 1499660481072 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49783
21:21:21 INFO - 1499660481073 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:21 INFO - 1499660481073 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481075 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481078 Marionette TRACE 369 <- [1,1,null,{"sessionId":"dacdf068-bd2e-47cc-8e54-5cf6de955fb6","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481079 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
21:21:21 INFO - 1499660481080 Marionette TRACE 369 <- [1,2,null,{}]
21:21:21 INFO - 1499660481081 Marionette DEBUG Closed connection 369
21:21:21 INFO - 1499660481082 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49784
21:21:21 INFO - 1499660481082 Marionette DEBUG Closed connection 370
21:21:21 INFO - 1499660481083 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49785
21:21:21 INFO - 1499660481084 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:21 INFO - 1499660481085 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481086 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481089 Marionette TRACE 371 <- [1,1,null,{"sessionId":"9f8d0139-7915-4974-aac4-5e083af4403f","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481091 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%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"}]
21:21:21 INFO - 1499660481092 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"
21:21:21 INFO - 1499660481094 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"
21:21:21 INFO - 1499660481094 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"
21:21:21 INFO - 1499660481100 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"
21:21:21 INFO - 1499660481102 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"
21:21:21 INFO - 1499660481104 Marionette TRACE 371 <- [1,2,null,{}]
21:21:21 INFO - 1499660481107 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:21:21 INFO - 1499660481112 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6862d3ca-0484-4165-9aca-d5da3fe3207d","ELEMENT":"6862d3ca-0484-4165-9aca-d5da3fe3207d"}}]
21:21:21 INFO - 1499660481113 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"6862d3ca-0484-4165-9aca-d5da3fe3207d"}]
21:21:21 INFO - 1499660481350 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:21 INFO - 1499660481351 Marionette TRACE 371 <- [1,4,null,{}]
21:21:21 INFO - 1499660481352 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"6862d3ca-0484-4165-9aca-d5da3fe3207d","name":"selected"}]
21:21:21 INFO - 1499660481355 Marionette TRACE 371 <- [1,5,null,{"value":true}]
21:21:21 INFO - 1499660481358 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
21:21:21 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499660481360 Marionette TRACE 371 <- [1,6,null,{"value":null}]
21:21:21 INFO - 1499660481361 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
21:21:21 INFO - 1499660481362 Marionette TRACE 371 <- [1,7,null,{}]
21:21:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 312ms
21:21:21 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:21:21 INFO - 1499660481363 Marionette DEBUG Closed connection 371
21:21:21 INFO - 1499660481365 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49786
21:21:21 INFO - 1499660481366 Marionette DEBUG Closed connection 372
21:21:21 INFO - 1499660481366 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49787
21:21:21 INFO - 1499660481367 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:21 INFO - 1499660481368 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481370 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481373 Marionette TRACE 373 <- [1,1,null,{"sessionId":"d62c8a3a-d50a-49ee-ae0c-eaf33c6ca4bf","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481374 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
21:21:21 INFO - 1499660481374 Marionette TRACE 373 <- [1,2,null,{}]
21:21:21 INFO - 1499660481376 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:21 INFO - 1499660481376 Marionette TRACE 373 <- [1,3,null,{}]
21:21:21 INFO - 1499660481378 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:21 INFO - 1499660481378 Marionette TRACE 373 <- [1,4,null,{}]
21:21:21 INFO - 1499660481379 Marionette TRACE 373 -> [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}]
21:21:21 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499660481382 Marionette TRACE 373 <- [1,5,null,{"value":null}]
21:21:21 INFO - 1499660481383 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
21:21:21 INFO - 1499660481384 Marionette TRACE 373 <- [1,6,null,{}]
21:21:21 INFO - 1499660481385 Marionette DEBUG Closed connection 373
21:21:21 INFO - 1499660481386 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49788
21:21:21 INFO - 1499660481386 Marionette DEBUG Closed connection 374
21:21:21 INFO - 1499660481387 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49789
21:21:21 INFO - 1499660481388 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:21 INFO - 1499660481388 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481390 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481393 Marionette TRACE 375 <- [1,1,null,{"sessionId":"ebc798bf-e25e-4f64-9701-a8cb34138934","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481394 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
21:21:21 INFO - 1499660481395 Marionette TRACE 375 <- [1,2,null,{}]
21:21:21 INFO - 1499660481396 Marionette DEBUG Closed connection 375
21:21:21 INFO - 1499660481397 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49790
21:21:21 INFO - 1499660481398 Marionette DEBUG Closed connection 376
21:21:21 INFO - 1499660481398 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49791
21:21:21 INFO - 1499660481399 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:21 INFO - 1499660481400 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481402 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481404 Marionette TRACE 377 <- [1,1,null,{"sessionId":"bceac1c9-375c-426b-ac28-8a49df20fb5f","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481406 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%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"}]
21:21:21 INFO - 1499660481407 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"
21:21:21 INFO - 1499660481409 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"
21:21:21 INFO - 1499660481410 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"
21:21:21 INFO - 1499660481420 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"
21:21:21 INFO - 1499660481422 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"
21:21:21 INFO - 1499660481423 Marionette TRACE 377 <- [1,2,null,{}]
21:21:21 INFO - 1499660481430 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:21:21 INFO - 1499660481432 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6af5fbdd-2dfa-4b48-8c90-ed89474d0111","ELEMENT":"6af5fbdd-2dfa-4b48-8c90-ed89474d0111"}}]
21:21:21 INFO - 1499660481433 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"6af5fbdd-2dfa-4b48-8c90-ed89474d0111"}]
21:21:21 INFO - 1499660481672 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:21 INFO - 1499660481673 Marionette TRACE 377 <- [1,4,null,{}]
21:21:21 INFO - 1499660481675 Marionette TRACE 377 -> [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}]
21:21:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499660481677 Marionette TRACE 377 <- [1,5,null,{"value":null}]
21:21:21 INFO - 1499660481678 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
21:21:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 316ms
21:21:21 INFO - 1499660481679 Marionette TRACE 377 <- [1,6,null,{}]
21:21:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:21:21 INFO - 1499660481680 Marionette DEBUG Closed connection 377
21:21:21 INFO - 1499660481682 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49792
21:21:21 INFO - 1499660481683 Marionette DEBUG Closed connection 378
21:21:21 INFO - 1499660481684 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49793
21:21:21 INFO - 1499660481684 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:21 INFO - 1499660481685 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481687 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481690 Marionette TRACE 379 <- [1,1,null,{"sessionId":"d459dcb6-8b2d-4a67-8dad-3a79e41b1c2f","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481691 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
21:21:21 INFO - 1499660481691 Marionette TRACE 379 <- [1,2,null,{}]
21:21:21 INFO - 1499660481692 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:21 INFO - 1499660481692 Marionette TRACE 379 <- [1,3,null,{}]
21:21:21 INFO - 1499660481693 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:21 INFO - 1499660481694 Marionette TRACE 379 <- [1,4,null,{}]
21:21:21 INFO - 1499660481695 Marionette TRACE 379 -> [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}]
21:21:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499660481697 Marionette TRACE 379 <- [1,5,null,{"value":null}]
21:21:21 INFO - 1499660481698 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
21:21:21 INFO - 1499660481699 Marionette TRACE 379 <- [1,6,null,{}]
21:21:21 INFO - 1499660481700 Marionette DEBUG Closed connection 379
21:21:21 INFO - 1499660481701 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49794
21:21:21 INFO - 1499660481701 Marionette DEBUG Closed connection 380
21:21:21 INFO - 1499660481702 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49795
21:21:21 INFO - 1499660481703 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:21 INFO - 1499660481703 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481705 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481708 Marionette TRACE 381 <- [1,1,null,{"sessionId":"8b34c273-9e5a-4699-82c0-f598e56adb5e","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481709 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
21:21:21 INFO - 1499660481710 Marionette TRACE 381 <- [1,2,null,{}]
21:21:21 INFO - 1499660481712 Marionette DEBUG Closed connection 381
21:21:21 INFO - 1499660481712 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49796
21:21:21 INFO - 1499660481713 Marionette DEBUG Closed connection 382
21:21:21 INFO - 1499660481714 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49797
21:21:21 INFO - 1499660481715 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:21 INFO - 1499660481715 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:21 INFO - 1499660481717 Marionette DEBUG Register listener.js for window 4294967297
21:21:21 INFO - 1499660481727 Marionette TRACE 383 <- [1,1,null,{"sessionId":"10d26025-fe02-4379-bbda-2c26146b79ed","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:21 INFO - 1499660481728 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%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"}]
21:21:21 INFO - 1499660481730 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"
21:21:21 INFO - 1499660481732 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"
21:21:21 INFO - 1499660481732 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"
21:21:21 INFO - 1499660481738 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"
21:21:21 INFO - 1499660481742 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"
21:21:21 INFO - 1499660481748 Marionette TRACE 383 <- [1,2,null,{}]
21:21:21 INFO - 1499660481751 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:21:21 INFO - 1499660481753 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"48a7740b-f0a6-48dd-b50c-cf20d1665deb","ELEMENT":"48a7740b-f0a6-48dd-b50c-cf20d1665deb"}}]
21:21:21 INFO - 1499660481754 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"48a7740b-f0a6-48dd-b50c-cf20d1665deb"}]
21:21:21 INFO - 1499660481991 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:21 INFO - 1499660481992 Marionette TRACE 383 <- [1,4,null,{}]
21:21:21 INFO - 1499660481993 Marionette TRACE 383 -> [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}]
21:21:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499660481995 Marionette TRACE 383 <- [1,5,null,{"value":null}]
21:21:21 INFO - 1499660481996 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
21:21:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 318ms
21:21:21 INFO - TEST-START | test_click.py TestClick.test_input_file
21:21:22 INFO - 1499660481998 Marionette TRACE 383 <- [1,6,null,{}]
21:21:22 INFO - 1499660481999 Marionette DEBUG Closed connection 383
21:21:22 INFO - 1499660482000 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49798
21:21:22 INFO - 1499660482001 Marionette DEBUG Closed connection 384
21:21:22 INFO - 1499660482001 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49799
21:21:22 INFO - 1499660482002 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:22 INFO - 1499660482002 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482005 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482007 Marionette TRACE 385 <- [1,1,null,{"sessionId":"29e1f26f-79ea-4ab3-bf96-04df37b8cba2","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482008 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
21:21:22 INFO - 1499660482009 Marionette TRACE 385 <- [1,2,null,{}]
21:21:22 INFO - 1499660482010 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:22 INFO - 1499660482010 Marionette TRACE 385 <- [1,3,null,{}]
21:21:22 INFO - 1499660482011 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:22 INFO - 1499660482011 Marionette TRACE 385 <- [1,4,null,{}]
21:21:22 INFO - 1499660482013 Marionette TRACE 385 -> [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}]
21:21:22 INFO - TEST-START: test_click.py TestClick.test_input_file1499660482015 Marionette TRACE 385 <- [1,5,null,{"value":null}]
21:21:22 INFO - 1499660482016 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
21:21:22 INFO - 1499660482017 Marionette TRACE 385 <- [1,6,null,{}]
21:21:22 INFO - 1499660482018 Marionette DEBUG Closed connection 385
21:21:22 INFO - 1499660482019 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49800
21:21:22 INFO - 1499660482020 Marionette DEBUG Closed connection 386
21:21:22 INFO - 1499660482020 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49801
21:21:22 INFO - 1499660482021 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:22 INFO - 1499660482021 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482023 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482026 Marionette TRACE 387 <- [1,1,null,{"sessionId":"85e66b61-6a32-4470-a586-6a701755f836","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482027 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
21:21:22 INFO - 1499660482028 Marionette TRACE 387 <- [1,2,null,{}]
21:21:22 INFO - 1499660482030 Marionette DEBUG Closed connection 387
21:21:22 INFO - 1499660482030 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49802
21:21:22 INFO - 1499660482031 Marionette DEBUG Closed connection 388
21:21:22 INFO - 1499660482032 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49803
21:21:22 INFO - 1499660482032 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:22 INFO - 1499660482033 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482035 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482038 Marionette TRACE 389 <- [1,1,null,{"sessionId":"4106b861-5e64-458f-babc-0a1a08a4382e","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482039 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:21:22 INFO - 1499660482040 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"
21:21:22 INFO - 1499660482041 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"
21:21:22 INFO - 1499660482042 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"
21:21:22 INFO - 1499660482047 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:22 INFO - 1499660482050 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:22 INFO - 1499660482052 Marionette TRACE 389 <- [1,2,null,{}]
21:21:22 INFO - 1499660482054 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:21:22 INFO - 1499660482056 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"280b1dc9-bd06-40a2-a4ec-3ce25db53522","ELEMENT":"280b1dc9-bd06-40a2-a4ec-3ce25db53522"}}]
21:21:22 INFO - 1499660482057 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"280b1dc9-bd06-40a2-a4ec-3ce25db53522"}]
21:21:22 INFO - 1499660482088 Marionette TRACE 389 <- [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]
21:21:22 INFO - 1499660482090 Marionette TRACE 389 -> [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}]
21:21:22 INFO - TEST-END: test_click.py TestClick.test_input_file1499660482093 Marionette TRACE 389 <- [1,5,null,{"value":null}]
21:21:22 INFO - 1499660482094 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
21:21:22 INFO - 1499660482095 Marionette TRACE 389 <- [1,6,null,{}]
21:21:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 98ms
21:21:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:21:22 INFO - 1499660482097 Marionette DEBUG Closed connection 389
21:21:22 INFO - 1499660482098 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49804
21:21:22 INFO - 1499660482099 Marionette DEBUG Closed connection 390
21:21:22 INFO - 1499660482099 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49805
21:21:22 INFO - 1499660482100 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:22 INFO - 1499660482101 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482103 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482106 Marionette TRACE 391 <- [1,1,null,{"sessionId":"4d0208ae-8fc2-4788-863f-3a0304c9ed94","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482107 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
21:21:22 INFO - 1499660482108 Marionette TRACE 391 <- [1,2,null,{}]
21:21:22 INFO - 1499660482109 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:22 INFO - 1499660482109 Marionette TRACE 391 <- [1,3,null,{}]
21:21:22 INFO - 1499660482110 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:22 INFO - 1499660482111 Marionette TRACE 391 <- [1,4,null,{}]
21:21:22 INFO - 1499660482112 Marionette TRACE 391 -> [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}]
21:21:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499660482114 Marionette TRACE 391 <- [1,5,null,{"value":null}]
21:21:22 INFO - 1499660482115 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
21:21:22 INFO - 1499660482117 Marionette TRACE 391 <- [1,6,null,{}]
21:21:22 INFO - 1499660482118 Marionette DEBUG Closed connection 391
21:21:22 INFO - 1499660482119 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49806
21:21:22 INFO - 1499660482119 Marionette DEBUG Closed connection 392
21:21:22 INFO - 1499660482120 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49807
21:21:22 INFO - 1499660482121 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:22 INFO - 1499660482121 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482124 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482127 Marionette TRACE 393 <- [1,1,null,{"sessionId":"b5223ef4-0862-4115-b125-fdf8da84aa10","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482128 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
21:21:22 INFO - 1499660482129 Marionette TRACE 393 <- [1,2,null,{}]
21:21:22 INFO - 1499660482131 Marionette DEBUG Closed connection 393
21:21:22 INFO - 1499660482131 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49808
21:21:22 INFO - 1499660482132 Marionette DEBUG Closed connection 394
21:21:22 INFO - 1499660482132 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49809
21:21:22 INFO - 1499660482133 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:22 INFO - 1499660482134 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482136 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482138 Marionette TRACE 395 <- [1,1,null,{"sessionId":"f851cb25-69b9-449b-9e0a-76261cc919fb","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482140 Marionette TRACE 395 -> [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"}]
21:21:22 INFO - 1499660482141 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:22 INFO - 1499660482143 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:22 INFO - 1499660482143 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:22 INFO - 1499660482149 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"
21:21:22 INFO - 1499660482151 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"
21:21:22 INFO - 1499660482152 Marionette TRACE 395 <- [1,2,null,{}]
21:21:22 INFO - 1499660482155 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:21:22 INFO - 1499660482157 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7b194179-6b15-4ca4-9fe5-375ef9ae0336","ELEMENT":"7b194179-6b15-4ca4-9fe5-375ef9ae0336"}}]
21:21:22 INFO - 1499660482158 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:21:22 INFO - 1499660482164 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"adaf0e78-d12e-424b-8b8c-1d846774ac59","ELEMENT":"adaf0e78-d12e-424b-8b8c-1d846774ac59"}}]
21:21:22 INFO - 1499660482165 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"7b194179-6b15-4ca4-9fe5-375ef9ae0336"}]
21:21:22 INFO - 1499660482399 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:22 INFO - 1499660482400 Marionette TRACE 395 <- [1,5,null,{}]
21:21:22 INFO - 1499660482401 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"adaf0e78-d12e-424b-8b8c-1d846774ac59"}]
21:21:22 INFO - 1499660482414 Marionette TRACE 395 <- [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]
21:21:22 INFO - 1499660482416 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:21:22 INFO - 1499660482418 Marionette TRACE 395 <- [1,7,null,{"value":false}]
21:21:22 INFO - 1499660482420 Marionette TRACE 395 -> [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}]
21:21:22 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499660482424 Marionette TRACE 395 <- [1,8,null,{"value":null}]
21:21:22 INFO - 1499660482425 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
21:21:22 INFO - 1499660482426 Marionette TRACE 395 <- [1,9,null,{}]
21:21:22 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 330ms
21:21:22 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:21:22 INFO - 1499660482428 Marionette DEBUG Closed connection 395
21:21:22 INFO - 1499660482429 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49810
21:21:22 INFO - 1499660482430 Marionette DEBUG Closed connection 396
21:21:22 INFO - 1499660482430 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49811
21:21:22 INFO - 1499660482432 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:22 INFO - 1499660482432 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482434 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482437 Marionette TRACE 397 <- [1,1,null,{"sessionId":"c424c105-d7e1-4abe-a02d-c7aa42cae049","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482438 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
21:21:22 INFO - 1499660482438 Marionette TRACE 397 <- [1,2,null,{}]
21:21:22 INFO - 1499660482439 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:22 INFO - 1499660482440 Marionette TRACE 397 <- [1,3,null,{}]
21:21:22 INFO - 1499660482441 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:22 INFO - 1499660482441 Marionette TRACE 397 <- [1,4,null,{}]
21:21:22 INFO - 1499660482443 Marionette TRACE 397 -> [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}]
21:21:22 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499660482445 Marionette TRACE 397 <- [1,5,null,{"value":null}]
21:21:22 INFO - 1499660482446 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
21:21:22 INFO - 1499660482447 Marionette TRACE 397 <- [1,6,null,{}]
21:21:22 INFO - 1499660482448 Marionette DEBUG Closed connection 397
21:21:22 INFO - 1499660482449 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49812
21:21:22 INFO - 1499660482449 Marionette DEBUG Closed connection 398
21:21:22 INFO - 1499660482450 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49813
21:21:22 INFO - 1499660482451 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:22 INFO - 1499660482451 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482454 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482456 Marionette TRACE 399 <- [1,1,null,{"sessionId":"a5c7e4cf-1a86-45dd-8988-fe659efd7d6c","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482457 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
21:21:22 INFO - 1499660482458 Marionette TRACE 399 <- [1,2,null,{}]
21:21:22 INFO - 1499660482460 Marionette DEBUG Closed connection 399
21:21:22 INFO - 1499660482460 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49814
21:21:22 INFO - 1499660482461 Marionette DEBUG Closed connection 400
21:21:22 INFO - 1499660482462 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49815
21:21:22 INFO - 1499660482463 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:22 INFO - 1499660482463 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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}
21:21:22 INFO - 1499660482465 Marionette DEBUG Register listener.js for window 4294967297
21:21:22 INFO - 1499660482468 Marionette TRACE 401 <- [1,1,null,{"sessionId":"706daf74-f624-4657-9f07-364504cc62c8","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":2428,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-229.001\\AppData\\Local\\Temp\\tmpzk0wgp.mozrunner","moz:accessibilityChecks":false}}]
21:21:22 INFO - 1499660482469 Marionette TRACE 401 -> [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"}]
21:21:22 INFO - 1499660482470 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"
21:21:22 INFO - 1499660482472 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"
21:21:22 INFO - 1499660482472 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"
21:21:22 INFO - 1499660482478 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"
21:21:22 INFO - 1499660482480 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"
21:21:22 INFO - 1499660482486 Marionette TRACE 401 <- [1,2,null,{}]
21:21:22 INFO - 1499660482489 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:21:22 INFO - 1499660482492 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0306a3b8-5165-4379-a567-c3f3906638fe","ELEMENT":"0306a3b8-5165-4379-a567-c3f3906638fe"}}]
21:21:22 INFO - 1499660482493 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"0306a3b8-5165-4379-a567-c3f3906638fe"}]
21:21:22 INFO - 1499660482521 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
21:21:22 INFO - 1499660482522 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"0306a3b8-5165-4379-a567-c3f3906638fe"}]
21:21:22 INFO - 1499660482535 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element