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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:58:21 INFO - 1499997501560 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
18:58:21 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:58:21 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499997501562 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:58:21 INFO - 1499997501563 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:58:21 INFO - 1499997501564 Marionette TRACE 352 <- [1,6,null,{}]
18:58:21 INFO - 1499997501566 Marionette DEBUG Closed connection 352
18:58:21 INFO - 1499997501568 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49756
18:58:21 INFO - 1499997501568 Marionette DEBUG Closed connection 353
18:58:21 INFO - 1499997501569 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49757
18:58:21 INFO - 1499997501570 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:21 INFO - 1499997501570 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:21 INFO - 1499997501572 Marionette DEBUG Register listener.js for window 15
18:58:21 INFO - 1499997501574 Marionette TRACE 354 <- [1,1,null,{"sessionId":"2be290b5-0cae-45ba-bc15-da7a55d99c51","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:21 INFO - 1499997501576 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:58:21 INFO - 1499997501577 Marionette TRACE 354 <- [1,2,null,{}]
18:58:21 INFO - 1499997501578 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:58:21 INFO - 1499997501578 Marionette TRACE 354 <- [1,3,null,{}]
18:58:21 INFO - 1499997501579 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:58:21 INFO - 1499997501579 Marionette TRACE 354 <- [1,4,null,{}]
18:58:21 INFO - 1499997501581 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
18:58:21 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499997501583 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:58:21 INFO - 1499997501584 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:58:21 INFO - 1499997501585 Marionette TRACE 354 <- [1,6,null,{}]
18:58:21 INFO - 1499997501586 Marionette DEBUG Closed connection 354
18:58:21 INFO - 1499997501587 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49758
18:58:21 INFO - 1499997501587 Marionette DEBUG Closed connection 355
18:58:21 INFO - 1499997501588 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49759
18:58:21 INFO - 1499997501589 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:21 INFO - 1499997501589 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:21 INFO - 1499997501591 Marionette DEBUG Register listener.js for window 15
18:58:21 INFO - 1499997501594 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4895c68e-cfbf-4c4d-9b39-fd126a1c4f9a","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:21 INFO - 1499997501596 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:58:21 INFO - 1499997501597 Marionette TRACE 356 <- [1,2,null,{}]
18:58:21 INFO - 1499997501599 Marionette DEBUG Closed connection 356
18:58:21 INFO - 1499997501599 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49760
18:58:21 INFO - 1499997501600 Marionette DEBUG Closed connection 357
18:58:21 INFO - 1499997501600 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49761
18:58:21 INFO - 1499997501601 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:58:21 INFO - 1499997501602 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:21 INFO - 1499997501604 Marionette DEBUG Register listener.js for window 15
18:58:21 INFO - 1499997501606 Marionette TRACE 358 <- [1,1,null,{"sessionId":"050919d9-8880-4dd6-9493-56bf8cc97dc1","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:21 INFO - 1499997501608 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49327/clicks.html"}]
18:58:21 INFO - 1499997501609 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/hidden.html"
18:58:21 INFO - 1499997501615 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/hidden.html"
18:58:21 INFO - 1499997501615 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/hidden.html"
18:58:21 INFO - 1499997501625 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49327/clicks.html"
18:58:21 INFO - 1499997501634 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49327/clicks.html"
18:58:21 INFO - 1499997501637 Marionette TRACE 358 <- [1,2,null,{}]
18:58:21 INFO - 1499997501638 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:58:21 INFO - 1499997501640 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2c842f8f-936d-42b3-b9f2-59730cda0b52","ELEMENT":"2c842f8f-936d-42b3-b9f2-59730cda0b52"}}]
18:58:21 INFO - 1499997501641 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"2c842f8f-936d-42b3-b9f2-59730cda0b52"}]
18:58:21 INFO - 1499997501685 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/clicks.html"
18:58:21 INFO - 1499997501689 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/clicks.html"
18:58:21 INFO - 1499997501690 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/clicks.html"
18:58:21 INFO - 1499997501706 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49327/test.html"
18:58:21 INFO - 1499997501708 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49327/test.html"
18:58:21 INFO - 1499997501710 Marionette TRACE 358 <- [1,4,null,{}]
18:58:21 INFO - 1499997501717 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:58:21 INFO - 1499997501719 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b08452bd-0d9b-451f-96b1-3c0446ede4b0","ELEMENT":"b08452bd-0d9b-451f-96b1-3c0446ede4b0"}}]
18:58:21 INFO - 1499997501720 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:58:21 INFO - 1499997501721 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:58:21 INFO - 1499997501722 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
18:58:21 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499997501724 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:58:21 INFO - 1499997501725 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:58:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 161ms
18:58:21 INFO - TEST-START | test_click.py TestClick.test_container_element
18:58:21 INFO - 1499997501726 Marionette TRACE 358 <- [1,8,null,{}]
18:58:21 INFO - 1499997501728 Marionette DEBUG Closed connection 358
18:58:21 INFO - 1499997501729 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49764
18:58:21 INFO - 1499997501730 Marionette DEBUG Closed connection 359
18:58:21 INFO - 1499997501730 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49765
18:58:21 INFO - 1499997501731 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:21 INFO - 1499997501732 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:21 INFO - 1499997501734 Marionette DEBUG Register listener.js for window 15
18:58:21 INFO - 1499997501736 Marionette TRACE 360 <- [1,1,null,{"sessionId":"ff2c87a4-d4c3-40e2-b620-891f6c97a4bc","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:21 INFO - 1499997501738 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:58:21 INFO - 1499997501738 Marionette TRACE 360 <- [1,2,null,{}]
18:58:21 INFO - 1499997501739 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:58:21 INFO - 1499997501740 Marionette TRACE 360 <- [1,3,null,{}]
18:58:21 INFO - 1499997501740 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:58:21 INFO - 1499997501741 Marionette TRACE 360 <- [1,4,null,{}]
18:58:21 INFO - 1499997501743 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
18:58:21 INFO - TEST-START: test_click.py TestClick.test_container_element1499997501745 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:58:21 INFO - 1499997501746 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:58:21 INFO - 1499997501747 Marionette TRACE 360 <- [1,6,null,{}]
18:58:21 INFO - 1499997501748 Marionette DEBUG Closed connection 360
18:58:21 INFO - 1499997501749 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49766
18:58:21 INFO - 1499997501749 Marionette DEBUG Closed connection 361
18:58:21 INFO - 1499997501750 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49767
18:58:21 INFO - 1499997501751 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:21 INFO - 1499997501751 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:21 INFO - 1499997501753 Marionette DEBUG Register listener.js for window 15
18:58:21 INFO - 1499997501756 Marionette TRACE 362 <- [1,1,null,{"sessionId":"7b46ddd1-ead0-45db-a556-78a5904f9529","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:21 INFO - 1499997501757 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:58:21 INFO - 1499997501758 Marionette TRACE 362 <- [1,2,null,{}]
18:58:21 INFO - 1499997501760 Marionette DEBUG Closed connection 362
18:58:21 INFO - 1499997501761 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49768
18:58:21 INFO - 1499997501761 Marionette DEBUG Closed connection 363
18:58:21 INFO - 1499997501762 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49769
18:58:21 INFO - 1499997501763 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:58:21 INFO - 1499997501764 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:21 INFO - 1499997501766 Marionette DEBUG Register listener.js for window 15
18:58:21 INFO - 1499997501768 Marionette TRACE 364 <- [1,1,null,{"sessionId":"99df6512-cc06-48a6-af86-ef9c83f9f9a0","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:21 INFO - 1499997501770 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:58:21 INFO - 1499997501771 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/test.html"
18:58:21 INFO - 1499997501773 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/test.html"
18:58:21 INFO - 1499997501773 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/test.html"
18:58:21 INFO - 1499997501792 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:21 INFO - 1499997501795 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:21 INFO - 1499997501803 Marionette TRACE 364 <- [1,2,null,{}]
18:58:21 INFO - 1499997501804 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:58:21 INFO - 1499997501806 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40c077d4-4d9a-463d-b2a1-c9d231beae9b","ELEMENT":"40c077d4-4d9a-463d-b2a1-c9d231beae9b"}}]
18:58:21 INFO - 1499997501807 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"40c077d4-4d9a-463d-b2a1-c9d231beae9b"}]
18:58:22 INFO - 1499997502045 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:58:22 INFO - 1499997502046 Marionette TRACE 364 <- [1,4,null,{}]
18:58:22 INFO - 1499997502047 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"40c077d4-4d9a-463d-b2a1-c9d231beae9b","name":"selected"}]
18:58:22 INFO - 1499997502050 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:58:22 INFO - 1499997502052 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
18:58:22 INFO - TEST-END: test_click.py TestClick.test_container_element1499997502054 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:58:22 INFO - 1499997502055 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:58:22 INFO - 1499997502056 Marionette TRACE 364 <- [1,7,null,{}]
18:58:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 331ms
18:58:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:58:22 INFO - 1499997502058 Marionette DEBUG Closed connection 364
18:58:22 INFO - 1499997502059 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49770
18:58:22 INFO - 1499997502060 Marionette DEBUG Closed connection 365
18:58:22 INFO - 1499997502060 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49771
18:58:22 INFO - 1499997502061 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:22 INFO - 1499997502062 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502063 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502066 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3331d598-b244-4941-b3ad-80faf6e3836b","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502068 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:58:22 INFO - 1499997502068 Marionette TRACE 366 <- [1,2,null,{}]
18:58:22 INFO - 1499997502069 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:58:22 INFO - 1499997502069 Marionette TRACE 366 <- [1,3,null,{}]
18:58:22 INFO - 1499997502070 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:58:22 INFO - 1499997502071 Marionette TRACE 366 <- [1,4,null,{}]
18:58:22 INFO - 1499997502072 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
18:58:22 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499997502075 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:58:22 INFO - 1499997502076 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:58:22 INFO - 1499997502077 Marionette TRACE 366 <- [1,6,null,{}]
18:58:22 INFO - 1499997502078 Marionette DEBUG Closed connection 366
18:58:22 INFO - 1499997502079 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49772
18:58:22 INFO - 1499997502080 Marionette DEBUG Closed connection 367
18:58:22 INFO - 1499997502080 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49773
18:58:22 INFO - 1499997502081 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:22 INFO - 1499997502081 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502083 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502086 Marionette TRACE 368 <- [1,1,null,{"sessionId":"60c13cd6-013d-4118-8fc5-a55ce74b9e2b","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502088 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:58:22 INFO - 1499997502089 Marionette TRACE 368 <- [1,2,null,{}]
18:58:22 INFO - 1499997502091 Marionette DEBUG Closed connection 368
18:58:22 INFO - 1499997502091 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49774
18:58:22 INFO - 1499997502092 Marionette DEBUG Closed connection 369
18:58:22 INFO - 1499997502093 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49775
18:58:22 INFO - 1499997502094 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:58:22 INFO - 1499997502095 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502097 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502108 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c87685a9-4673-40bc-918e-40f8fa605a7b","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502110 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:58:22 INFO - 1499997502111 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502113 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502114 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502122 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502126 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502128 Marionette TRACE 370 <- [1,2,null,{}]
18:58:22 INFO - 1499997502137 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:58:22 INFO - 1499997502139 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8824b95f-50f9-4ba4-8826-a01c6ce759c0","ELEMENT":"8824b95f-50f9-4ba4-8826-a01c6ce759c0"}}]
18:58:22 INFO - 1499997502140 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"8824b95f-50f9-4ba4-8826-a01c6ce759c0"}]
18:58:22 INFO - 1499997502377 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:58:22 INFO - 1499997502378 Marionette TRACE 370 <- [1,4,null,{}]
18:58:22 INFO - 1499997502379 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"8824b95f-50f9-4ba4-8826-a01c6ce759c0","name":"selected"}]
18:58:22 INFO - 1499997502383 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:58:22 INFO - 1499997502384 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
18:58:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499997502386 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:58:22 INFO - 1499997502387 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:58:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 331ms
18:58:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:58:22 INFO - 1499997502388 Marionette TRACE 370 <- [1,7,null,{}]
18:58:22 INFO - 1499997502390 Marionette DEBUG Closed connection 370
18:58:22 INFO - 1499997502391 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49776
18:58:22 INFO - 1499997502391 Marionette DEBUG Closed connection 371
18:58:22 INFO - 1499997502392 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49777
18:58:22 INFO - 1499997502393 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:22 INFO - 1499997502393 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502395 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502398 Marionette TRACE 372 <- [1,1,null,{"sessionId":"fe0e3f66-32f6-4c54-8a27-889f9d390234","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502399 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:58:22 INFO - 1499997502400 Marionette TRACE 372 <- [1,2,null,{}]
18:58:22 INFO - 1499997502401 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:58:22 INFO - 1499997502401 Marionette TRACE 372 <- [1,3,null,{}]
18:58:22 INFO - 1499997502402 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:58:22 INFO - 1499997502402 Marionette TRACE 372 <- [1,4,null,{}]
18:58:22 INFO - 1499997502404 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
18:58:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499997502405 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:58:22 INFO - 1499997502406 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:58:22 INFO - 1499997502407 Marionette TRACE 372 <- [1,6,null,{}]
18:58:22 INFO - 1499997502409 Marionette DEBUG Closed connection 372
18:58:22 INFO - 1499997502410 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49778
18:58:22 INFO - 1499997502411 Marionette DEBUG Closed connection 373
18:58:22 INFO - 1499997502411 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49779
18:58:22 INFO - 1499997502412 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:22 INFO - 1499997502413 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502414 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502417 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c2032685-c95f-4b00-a499-9a2097f45ee1","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502418 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:58:22 INFO - 1499997502419 Marionette TRACE 374 <- [1,2,null,{}]
18:58:22 INFO - 1499997502421 Marionette DEBUG Closed connection 374
18:58:22 INFO - 1499997502421 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49780
18:58:22 INFO - 1499997502422 Marionette DEBUG Closed connection 375
18:58:22 INFO - 1499997502423 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49781
18:58:22 INFO - 1499997502424 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:58:22 INFO - 1499997502424 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502426 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502428 Marionette TRACE 376 <- [1,1,null,{"sessionId":"175ade7f-0ce1-449a-a045-850d1e2a9632","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502430 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
18:58:22 INFO - 1499997502431 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502433 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502433 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502443 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:58:22 INFO - 1499997502446 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:58:22 INFO - 1499997502455 Marionette TRACE 376 <- [1,2,null,{}]
18:58:22 INFO - 1499997502457 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:58:22 INFO - 1499997502459 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cdbd94c9-7a5d-4814-b0d9-2bff01e2bd0e","ELEMENT":"cdbd94c9-7a5d-4814-b0d9-2bff01e2bd0e"}}]
18:58:22 INFO - 1499997502460 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"cdbd94c9-7a5d-4814-b0d9-2bff01e2bd0e"}]
18:58:22 INFO - 1499997502700 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:58:22 INFO - 1499997502701 Marionette TRACE 376 <- [1,4,null,{}]
18:58:22 INFO - 1499997502702 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
18:58:22 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499997502705 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:58:22 INFO - 1499997502705 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:58:22 INFO - 1499997502707 Marionette TRACE 376 <- [1,6,null,{}]
18:58:22 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 318ms
18:58:22 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:58:22 INFO - 1499997502708 Marionette DEBUG Closed connection 376
18:58:22 INFO - 1499997502709 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49782
18:58:22 INFO - 1499997502710 Marionette DEBUG Closed connection 377
18:58:22 INFO - 1499997502710 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49783
18:58:22 INFO - 1499997502711 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:22 INFO - 1499997502712 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502713 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502716 Marionette TRACE 378 <- [1,1,null,{"sessionId":"cda12f84-6523-4a36-8686-e77c50a387c1","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502717 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:58:22 INFO - 1499997502718 Marionette TRACE 378 <- [1,2,null,{}]
18:58:22 INFO - 1499997502719 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:58:22 INFO - 1499997502719 Marionette TRACE 378 <- [1,3,null,{}]
18:58:22 INFO - 1499997502720 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:58:22 INFO - 1499997502720 Marionette TRACE 378 <- [1,4,null,{}]
18:58:22 INFO - 1499997502721 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
18:58:22 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499997502723 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:58:22 INFO - 1499997502724 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:58:22 INFO - 1499997502725 Marionette TRACE 378 <- [1,6,null,{}]
18:58:22 INFO - 1499997502727 Marionette DEBUG Closed connection 378
18:58:22 INFO - 1499997502727 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49784
18:58:22 INFO - 1499997502728 Marionette DEBUG Closed connection 379
18:58:22 INFO - 1499997502729 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49785
18:58:22 INFO - 1499997502730 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:22 INFO - 1499997502730 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502732 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502734 Marionette TRACE 380 <- [1,1,null,{"sessionId":"5b092f18-237c-418d-9bfa-f00ed93bcf70","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502736 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:58:22 INFO - 1499997502737 Marionette TRACE 380 <- [1,2,null,{}]
18:58:22 INFO - 1499997502739 Marionette DEBUG Closed connection 380
18:58:22 INFO - 1499997502739 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49786
18:58:22 INFO - 1499997502740 Marionette DEBUG Closed connection 381
18:58:22 INFO - 1499997502740 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49787
18:58:22 INFO - 1499997502741 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:58:22 INFO - 1499997502742 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:22 INFO - 1499997502744 Marionette DEBUG Register listener.js for window 15
18:58:22 INFO - 1499997502746 Marionette TRACE 382 <- [1,1,null,{"sessionId":"6d6318c7-5c42-4c26-bde8-d3b6239f480b","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:22 INFO - 1499997502748 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:58:22 INFO - 1499997502749 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:58:22 INFO - 1499997502751 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:58:22 INFO - 1499997502751 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:58:22 INFO - 1499997502760 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502768 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:22 INFO - 1499997502771 Marionette TRACE 382 <- [1,2,null,{}]
18:58:22 INFO - 1499997502772 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:58:22 INFO - 1499997502774 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d50612b1-1682-4d1b-93cb-dfad2cb9478e","ELEMENT":"d50612b1-1682-4d1b-93cb-dfad2cb9478e"}}]
18:58:22 INFO - 1499997502775 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d50612b1-1682-4d1b-93cb-dfad2cb9478e"}]
18:58:23 INFO - 1499997503017 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:58:23 INFO - 1499997503017 Marionette TRACE 382 <- [1,4,null,{}]
18:58:23 INFO - 1499997503019 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
18:58:23 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499997503021 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:58:23 INFO - 1499997503022 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:58:23 INFO - 1499997503023 Marionette TRACE 382 <- [1,6,null,{}]
18:58:23 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
18:58:23 INFO - TEST-START | test_click.py TestClick.test_input_file
18:58:23 INFO - 1499997503025 Marionette DEBUG Closed connection 382
18:58:23 INFO - 1499997503025 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49788
18:58:23 INFO - 1499997503026 Marionette DEBUG Closed connection 383
18:58:23 INFO - 1499997503027 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49789
18:58:23 INFO - 1499997503028 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:23 INFO - 1499997503028 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503030 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503033 Marionette TRACE 384 <- [1,1,null,{"sessionId":"74bfe5c4-db2d-4e9f-ae94-e29acdfa13ba","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503034 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:58:23 INFO - 1499997503035 Marionette TRACE 384 <- [1,2,null,{}]
18:58:23 INFO - 1499997503035 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:58:23 INFO - 1499997503036 Marionette TRACE 384 <- [1,3,null,{}]
18:58:23 INFO - 1499997503037 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:58:23 INFO - 1499997503037 Marionette TRACE 384 <- [1,4,null,{}]
18:58:23 INFO - 1499997503039 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
18:58:23 INFO - TEST-START: test_click.py TestClick.test_input_file1499997503041 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:58:23 INFO - 1499997503042 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:58:23 INFO - 1499997503043 Marionette TRACE 384 <- [1,6,null,{}]
18:58:23 INFO - 1499997503044 Marionette DEBUG Closed connection 384
18:58:23 INFO - 1499997503045 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49790
18:58:23 INFO - 1499997503045 Marionette DEBUG Closed connection 385
18:58:23 INFO - 1499997503046 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49791
18:58:23 INFO - 1499997503047 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:23 INFO - 1499997503047 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503049 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503052 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cb004afa-ae75-45e9-a7e3-922a2e30c6ad","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503054 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:58:23 INFO - 1499997503055 Marionette TRACE 386 <- [1,2,null,{}]
18:58:23 INFO - 1499997503056 Marionette DEBUG Closed connection 386
18:58:23 INFO - 1499997503057 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49792
18:58:23 INFO - 1499997503058 Marionette DEBUG Closed connection 387
18:58:23 INFO - 1499997503058 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49793
18:58:23 INFO - 1499997503059 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:58:23 INFO - 1499997503060 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503062 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503064 Marionette TRACE 388 <- [1,1,null,{"sessionId":"be4946dc-a853-4242-9a7d-a9d94ddd9d0e","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503066 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:58:23 INFO - 1499997503067 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:23 INFO - 1499997503069 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:23 INFO - 1499997503069 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:58:23 INFO - 1499997503086 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:58:23 INFO - 1499997503087 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:58:23 INFO - 1499997503090 Marionette TRACE 388 <- [1,2,null,{}]
18:58:23 INFO - 1499997503091 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:58:23 INFO - 1499997503093 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4683ff0d-3243-468e-8e61-9253a97d2279","ELEMENT":"4683ff0d-3243-468e-8e61-9253a97d2279"}}]
18:58:23 INFO - 1499997503099 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4683ff0d-3243-468e-8e61-9253a97d2279"}]
18:58:23 INFO - 1499997503131 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:58:23 INFO - 1499997503133 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
18:58:23 INFO - TEST-END: test_click.py TestClick.test_input_file1499997503135 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:58:23 INFO - 1499997503136 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:58:23 INFO - 1499997503137 Marionette TRACE 388 <- [1,6,null,{}]
18:58:23 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 113ms
18:58:23 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:58:23 INFO - 1499997503139 Marionette DEBUG Closed connection 388
18:58:23 INFO - 1499997503140 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49794
18:58:23 INFO - 1499997503141 Marionette DEBUG Closed connection 389
18:58:23 INFO - 1499997503141 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49795
18:58:23 INFO - 1499997503142 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:23 INFO - 1499997503143 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503145 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503147 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c314ced7-d1ca-4a4d-97f6-4f571b449aae","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503149 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:58:23 INFO - 1499997503149 Marionette TRACE 390 <- [1,2,null,{}]
18:58:23 INFO - 1499997503150 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:58:23 INFO - 1499997503150 Marionette TRACE 390 <- [1,3,null,{}]
18:58:23 INFO - 1499997503151 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:58:23 INFO - 1499997503151 Marionette TRACE 390 <- [1,4,null,{}]
18:58:23 INFO - 1499997503153 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
18:58:23 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499997503155 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:58:23 INFO - 1499997503156 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:58:23 INFO - 1499997503157 Marionette TRACE 390 <- [1,6,null,{}]
18:58:23 INFO - 1499997503158 Marionette DEBUG Closed connection 390
18:58:23 INFO - 1499997503159 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49796
18:58:23 INFO - 1499997503159 Marionette DEBUG Closed connection 391
18:58:23 INFO - 1499997503160 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49797
18:58:23 INFO - 1499997503161 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:23 INFO - 1499997503161 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503163 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503166 Marionette TRACE 392 <- [1,1,null,{"sessionId":"b66261d0-3bc8-48b3-9a5b-c0580ea8cd62","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503167 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:58:23 INFO - 1499997503168 Marionette TRACE 392 <- [1,2,null,{}]
18:58:23 INFO - 1499997503170 Marionette DEBUG Closed connection 392
18:58:23 INFO - 1499997503170 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49798
18:58:23 INFO - 1499997503171 Marionette DEBUG Closed connection 393
18:58:23 INFO - 1499997503172 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49799
18:58:23 INFO - 1499997503172 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:58:23 INFO - 1499997503173 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503175 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503177 Marionette TRACE 394 <- [1,1,null,{"sessionId":"5671f73e-b0f9-47f8-83ae-fa99a77b2016","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503179 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
18:58:23 INFO - 1499997503180 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:58:23 INFO - 1499997503182 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:58:23 INFO - 1499997503182 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:58:23 INFO - 1499997503191 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:58:23 INFO - 1499997503195 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:58:23 INFO - 1499997503203 Marionette TRACE 394 <- [1,2,null,{}]
18:58:23 INFO - 1499997503204 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:58:23 INFO - 1499997503206 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a1dadbe2-d2e6-4e55-94c1-c566eb73675e","ELEMENT":"a1dadbe2-d2e6-4e55-94c1-c566eb73675e"}}]
18:58:23 INFO - 1499997503207 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:58:23 INFO - 1499997503208 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f10dff9e-ed71-496e-a3c7-6dd9f34dbfd0","ELEMENT":"f10dff9e-ed71-496e-a3c7-6dd9f34dbfd0"}}]
18:58:23 INFO - 1499997503209 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a1dadbe2-d2e6-4e55-94c1-c566eb73675e"}]
18:58:23 INFO - 1499997503449 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:58:23 INFO - 1499997503449 Marionette TRACE 394 <- [1,5,null,{}]
18:58:23 INFO - 1499997503451 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"f10dff9e-ed71-496e-a3c7-6dd9f34dbfd0"}]
18:58:23 INFO - 1499997503464 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:58:23 INFO - 1499997503466 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:58:23 INFO - 1499997503468 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:58:23 INFO - 1499997503469 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
18:58:23 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499997503471 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:58:23 INFO - 1499997503472 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:58:23 INFO - 1499997503473 Marionette TRACE 394 <- [1,9,null,{}]
18:58:23 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 335ms
18:58:23 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:58:23 INFO - 1499997503475 Marionette DEBUG Closed connection 394
18:58:23 INFO - 1499997503476 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49800
18:58:23 INFO - 1499997503476 Marionette DEBUG Closed connection 395
18:58:23 INFO - 1499997503477 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49801
18:58:23 INFO - 1499997503478 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:23 INFO - 1499997503478 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503480 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503483 Marionette TRACE 396 <- [1,1,null,{"sessionId":"138fcda1-bbb6-49f4-b5b5-1ebd07a52b6c","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503484 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:58:23 INFO - 1499997503485 Marionette TRACE 396 <- [1,2,null,{}]
18:58:23 INFO - 1499997503486 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:58:23 INFO - 1499997503486 Marionette TRACE 396 <- [1,3,null,{}]
18:58:23 INFO - 1499997503487 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:58:23 INFO - 1499997503487 Marionette TRACE 396 <- [1,4,null,{}]
18:58:23 INFO - 1499997503489 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
18:58:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499997503491 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:58:23 INFO - 1499997503491 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:58:23 INFO - 1499997503492 Marionette TRACE 396 <- [1,6,null,{}]
18:58:23 INFO - 1499997503494 Marionette DEBUG Closed connection 396
18:58:23 INFO - 1499997503494 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49802
18:58:23 INFO - 1499997503495 Marionette DEBUG Closed connection 397
18:58:23 INFO - 1499997503496 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49803
18:58:23 INFO - 1499997503496 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:58:23 INFO - 1499997503497 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503499 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503501 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4e9b6655-4217-45f3-bb11-ea07e36cb8be","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503503 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:58:23 INFO - 1499997503504 Marionette TRACE 398 <- [1,2,null,{}]
18:58:23 INFO - 1499997503506 Marionette DEBUG Closed connection 398
18:58:23 INFO - 1499997503506 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49804
18:58:23 INFO - 1499997503507 Marionette DEBUG Closed connection 399
18:58:23 INFO - 1499997503508 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49805
18:58:23 INFO - 1499997503508 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:58:23 INFO - 1499997503509 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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}
18:58:23 INFO - 1499997503511 Marionette DEBUG Register listener.js for window 15
18:58:23 INFO - 1499997503513 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e8518b91-96cd-4619-b4d2-1f69ee23c10b","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":2828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-314.001\\AppData\\Local\\Temp\\tmpxlpvv2.mozrunner","moz:accessibilityChecks":false}}]
18:58:23 INFO - 1499997503515 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
18:58:23 INFO - 1499997503516 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:58:23 INFO - 1499997503518 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:58:23 INFO - 1499997503518 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:58:23 INFO - 1499997503535 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
18:58:23 INFO - 1499997503537 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
18:58:23 INFO - 1499997503539 Marionette TRACE 400 <- [1,2,null,{}]
18:58:23 INFO - 1499997503540 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:58:23 INFO - 1499997503542 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"94b99400-06bd-41e2-a3f1-09b50dfe6836","ELEMENT":"94b99400-06bd-41e2-a3f1-09b50dfe6836"}}]
18:58:23 INFO - 1499997503543 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"94b99400-06bd-41e2-a3f1-09b50dfe6836"}]
18:58:23 INFO - 1499997503569 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:58:23 INFO - 1499997503570 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"94b99400-06bd-41e2-a3f1-09b50dfe6836"}]
18:58:23 INFO - 1499997503587 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element