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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
23:01:40 INFO - 1500962500608 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}]
23:01:40 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500962500611 Marionette TRACE 352 <- [1,5,null,{"value":null}]
23:01:40 INFO - 1500962500612 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
23:01:40 INFO - 1500962500613 Marionette TRACE 352 <- [1,6,null,{}]
23:01:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 129ms
23:01:40 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
23:01:40 INFO - 1500962500615 Marionette DEBUG Closed connection 352
23:01:40 INFO - 1500962500615 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49779
23:01:40 INFO - 1500962500616 Marionette DEBUG Closed connection 353
23:01:40 INFO - 1500962500617 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49780
23:01:40 INFO - 1500962500618 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:40 INFO - 1500962500619 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:40 INFO - 1500962500622 Marionette DEBUG Register listener.js for window 4294967297
23:01:40 INFO - 1500962500633 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1a210994-ce5a-459b-8b77-219d1252c3a9","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:40 INFO - 1500962500634 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
23:01:40 INFO - 1500962500635 Marionette TRACE 354 <- [1,2,null,{}]
23:01:40 INFO - 1500962500636 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:40 INFO - 1500962500636 Marionette TRACE 354 <- [1,3,null,{}]
23:01:40 INFO - 1500962500637 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:40 INFO - 1500962500638 Marionette TRACE 354 <- [1,4,null,{}]
23:01:40 INFO - 1500962500639 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}]
23:01:40 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500962500642 Marionette TRACE 354 <- [1,5,null,{"value":null}]
23:01:40 INFO - 1500962500643 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
23:01:40 INFO - 1500962500645 Marionette TRACE 354 <- [1,6,null,{}]
23:01:40 INFO - 1500962500646 Marionette DEBUG Closed connection 354
23:01:40 INFO - 1500962500647 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49781
23:01:40 INFO - 1500962500648 Marionette DEBUG Closed connection 355
23:01:40 INFO - 1500962500648 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49782
23:01:40 INFO - 1500962500649 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:40 INFO - 1500962500650 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:40 INFO - 1500962500653 Marionette DEBUG Register listener.js for window 4294967297
23:01:40 INFO - 1500962500657 Marionette TRACE 356 <- [1,1,null,{"sessionId":"23563006-1d7c-4670-b4da-8a9a5fb784ec","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:40 INFO - 1500962500658 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
23:01:40 INFO - 1500962500659 Marionette TRACE 356 <- [1,2,null,{}]
23:01:40 INFO - 1500962500661 Marionette DEBUG Closed connection 356
23:01:40 INFO - 1500962500662 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49783
23:01:40 INFO - 1500962500663 Marionette DEBUG Closed connection 357
23:01:40 INFO - 1500962500663 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49784
23:01:40 INFO - 1500962500664 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:40 INFO - 1500962500665 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:40 INFO - 1500962500668 Marionette DEBUG Register listener.js for window 4294967297
23:01:40 INFO - 1500962500671 Marionette TRACE 358 <- [1,1,null,{"sessionId":"55e58c9e-a4e4-4e2c-9561-826854b8438e","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:40 INFO - 1500962500672 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49343/clicks.html"}]
23:01:40 INFO - 1500962500674 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49343/hidden.html"
23:01:40 INFO - 1500962500683 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49343/hidden.html"
23:01:40 INFO - 1500962500684 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49343/hidden.html"
23:01:40 INFO - 1500962500701 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49343/clicks.html"
23:01:40 INFO - 1500962500703 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49343/clicks.html"
23:01:40 INFO - 1500962500712 Marionette TRACE 358 <- [1,2,null,{}]
23:01:40 INFO - 1500962500722 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
23:01:40 INFO - 1500962500727 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"764cfd30-55fb-4593-aaf6-d18c8285f010","ELEMENT":"764cfd30-55fb-4593-aaf6-d18c8285f010"}}]
23:01:40 INFO - 1500962500728 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"764cfd30-55fb-4593-aaf6-d18c8285f010"}]
23:01:40 INFO - 1500962500768 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49343/clicks.html"
23:01:40 INFO - 1500962500778 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49343/clicks.html"
23:01:40 INFO - 1500962500779 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49343/clicks.html"
23:01:40 INFO - 1500962500787 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49343/test.html"
23:01:40 INFO - 1500962500795 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49343/test.html"
23:01:40 INFO - 1500962500797 Marionette TRACE 358 <- [1,4,null,{}]
23:01:40 INFO - 1500962500802 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
23:01:40 INFO - 1500962500805 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"29dccecb-d51f-4ac1-a900-62e0ec292179","ELEMENT":"29dccecb-d51f-4ac1-a900-62e0ec292179"}}]
23:01:40 INFO - 1500962500811 Marionette TRACE 358 -> [0,6,"getTitle",{}]
23:01:40 INFO - 1500962500811 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
23:01:40 INFO - 1500962500813 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}]
23:01:40 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500962500816 Marionette TRACE 358 <- [1,7,null,{"value":null}]
23:01:40 INFO - 1500962500817 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
23:01:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 206ms
23:01:40 INFO - TEST-START | test_click.py TestClick.test_container_element
23:01:40 INFO - 1500962500819 Marionette TRACE 358 <- [1,8,null,{}]
23:01:40 INFO - 1500962500820 Marionette DEBUG Closed connection 358
23:01:40 INFO - 1500962500821 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49787
23:01:40 INFO - 1500962500822 Marionette DEBUG Closed connection 359
23:01:40 INFO - 1500962500823 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49788
23:01:40 INFO - 1500962500824 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:40 INFO - 1500962500824 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:40 INFO - 1500962500827 Marionette DEBUG Register listener.js for window 4294967297
23:01:40 INFO - 1500962500831 Marionette TRACE 360 <- [1,1,null,{"sessionId":"368790ed-a76f-4478-97d5-20ed2323a49f","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:40 INFO - 1500962500832 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
23:01:40 INFO - 1500962500832 Marionette TRACE 360 <- [1,2,null,{}]
23:01:40 INFO - 1500962500833 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:40 INFO - 1500962500834 Marionette TRACE 360 <- [1,3,null,{}]
23:01:40 INFO - 1500962500835 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:40 INFO - 1500962500835 Marionette TRACE 360 <- [1,4,null,{}]
23:01:40 INFO - 1500962500837 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}]
23:01:40 INFO - TEST-START: test_click.py TestClick.test_container_element1500962500840 Marionette TRACE 360 <- [1,5,null,{"value":null}]
23:01:40 INFO - 1500962500841 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
23:01:40 INFO - 1500962500842 Marionette TRACE 360 <- [1,6,null,{}]
23:01:40 INFO - 1500962500844 Marionette DEBUG Closed connection 360
23:01:40 INFO - 1500962500844 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49789
23:01:40 INFO - 1500962500845 Marionette DEBUG Closed connection 361
23:01:40 INFO - 1500962500846 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49790
23:01:40 INFO - 1500962500847 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:40 INFO - 1500962500848 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:40 INFO - 1500962500851 Marionette DEBUG Register listener.js for window 4294967297
23:01:40 INFO - 1500962500854 Marionette TRACE 362 <- [1,1,null,{"sessionId":"24714a1b-bcfd-4428-aac3-b708b65f9b75","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:40 INFO - 1500962500856 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
23:01:40 INFO - 1500962500858 Marionette TRACE 362 <- [1,2,null,{}]
23:01:40 INFO - 1500962500860 Marionette DEBUG Closed connection 362
23:01:40 INFO - 1500962500860 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49791
23:01:40 INFO - 1500962500861 Marionette DEBUG Closed connection 363
23:01:40 INFO - 1500962500862 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49792
23:01:40 INFO - 1500962500863 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:40 INFO - 1500962500864 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:40 INFO - 1500962500867 Marionette DEBUG Register listener.js for window 4294967297
23:01:40 INFO - 1500962500870 Marionette TRACE 364 <- [1,1,null,{"sessionId":"95db0028-c9e6-40b7-ba28-8ea56242874d","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:40 INFO - 1500962500872 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"}]
23:01:40 INFO - 1500962500874 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49343/test.html"
23:01:40 INFO - 1500962500876 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49343/test.html"
23:01:40 INFO - 1500962500876 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49343/test.html"
23:01:40 INFO - 1500962500883 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"
23:01:40 INFO - 1500962500886 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"
23:01:40 INFO - 1500962500893 Marionette TRACE 364 <- [1,2,null,{}]
23:01:40 INFO - 1500962500902 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
23:01:40 INFO - 1500962500905 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1a5b711f-73d8-4f55-88be-557ad0fa6cad","ELEMENT":"1a5b711f-73d8-4f55-88be-557ad0fa6cad"}}]
23:01:40 INFO - 1500962500906 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"1a5b711f-73d8-4f55-88be-557ad0fa6cad"}]
23:01:41 INFO - 1500962501160 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:41 INFO - 1500962501161 Marionette TRACE 364 <- [1,4,null,{}]
23:01:41 INFO - 1500962501162 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"1a5b711f-73d8-4f55-88be-557ad0fa6cad","name":"selected"}]
23:01:41 INFO - 1500962501166 Marionette TRACE 364 <- [1,5,null,{"value":true}]
23:01:41 INFO - 1500962501168 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}]
23:01:41 INFO - TEST-END: test_click.py TestClick.test_container_element1500962501171 Marionette TRACE 364 <- [1,6,null,{"value":null}]
23:01:41 INFO - 1500962501172 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
23:01:41 INFO - 1500962501173 Marionette TRACE 364 <- [1,7,null,{}]
23:01:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 354ms
23:01:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
23:01:41 INFO - 1500962501175 Marionette DEBUG Closed connection 364
23:01:41 INFO - 1500962501176 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49793
23:01:41 INFO - 1500962501177 Marionette DEBUG Closed connection 365
23:01:41 INFO - 1500962501177 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49794
23:01:41 INFO - 1500962501178 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:41 INFO - 1500962501179 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501182 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501185 Marionette TRACE 366 <- [1,1,null,{"sessionId":"cf2784cc-754d-49d6-9ede-e014211dc8a1","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501186 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
23:01:41 INFO - 1500962501187 Marionette TRACE 366 <- [1,2,null,{}]
23:01:41 INFO - 1500962501188 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:41 INFO - 1500962501188 Marionette TRACE 366 <- [1,3,null,{}]
23:01:41 INFO - 1500962501189 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:41 INFO - 1500962501190 Marionette TRACE 366 <- [1,4,null,{}]
23:01:41 INFO - 1500962501192 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}]
23:01:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500962501194 Marionette TRACE 366 <- [1,5,null,{"value":null}]
23:01:41 INFO - 1500962501196 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
23:01:41 INFO - 1500962501197 Marionette TRACE 366 <- [1,6,null,{}]
23:01:41 INFO - 1500962501198 Marionette DEBUG Closed connection 366
23:01:41 INFO - 1500962501199 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49795
23:01:41 INFO - 1500962501200 Marionette DEBUG Closed connection 367
23:01:41 INFO - 1500962501200 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49796
23:01:41 INFO - 1500962501201 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:41 INFO - 1500962501202 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501205 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501208 Marionette TRACE 368 <- [1,1,null,{"sessionId":"bc956e66-5979-4afa-9ac5-1f41d1a3f3fd","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501210 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
23:01:41 INFO - 1500962501211 Marionette TRACE 368 <- [1,2,null,{}]
23:01:41 INFO - 1500962501213 Marionette DEBUG Closed connection 368
23:01:41 INFO - 1500962501213 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49797
23:01:41 INFO - 1500962501214 Marionette DEBUG Closed connection 369
23:01:41 INFO - 1500962501215 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49798
23:01:41 INFO - 1500962501216 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:41 INFO - 1500962501216 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501219 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501222 Marionette TRACE 370 <- [1,1,null,{"sessionId":"843c7c96-937a-4198-8ba6-8a409b30b884","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501224 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"}]
23:01:41 INFO - 1500962501226 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"
23:01:41 INFO - 1500962501228 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"
23:01:41 INFO - 1500962501228 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"
23:01:41 INFO - 1500962501234 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"
23:01:41 INFO - 1500962501237 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"
23:01:41 INFO - 1500962501241 Marionette TRACE 370 <- [1,2,null,{}]
23:01:41 INFO - 1500962501250 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
23:01:41 INFO - 1500962501253 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c5484f49-919a-4282-a7b3-8a06d0c82b96","ELEMENT":"c5484f49-919a-4282-a7b3-8a06d0c82b96"}}]
23:01:41 INFO - 1500962501254 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c5484f49-919a-4282-a7b3-8a06d0c82b96"}]
23:01:41 INFO - 1500962501498 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:41 INFO - 1500962501499 Marionette TRACE 370 <- [1,4,null,{}]
23:01:41 INFO - 1500962501500 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c5484f49-919a-4282-a7b3-8a06d0c82b96","name":"selected"}]
23:01:41 INFO - 1500962501505 Marionette TRACE 370 <- [1,5,null,{"value":true}]
23:01:41 INFO - 1500962501507 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}]
23:01:41 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500962501509 Marionette TRACE 370 <- [1,6,null,{"value":null}]
23:01:41 INFO - 1500962501511 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
23:01:41 INFO - 1500962501512 Marionette TRACE 370 <- [1,7,null,{}]
23:01:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 339ms
23:01:41 INFO - TEST-START | test_click.py TestClick.test_css_transforms
23:01:41 INFO - 1500962501514 Marionette DEBUG Closed connection 370
23:01:41 INFO - 1500962501515 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49799
23:01:41 INFO - 1500962501516 Marionette DEBUG Closed connection 371
23:01:41 INFO - 1500962501516 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49800
23:01:41 INFO - 1500962501518 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:41 INFO - 1500962501518 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501521 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501525 Marionette TRACE 372 <- [1,1,null,{"sessionId":"76f7bf7b-336f-4937-b401-b668b0970785","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501526 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
23:01:41 INFO - 1500962501526 Marionette TRACE 372 <- [1,2,null,{}]
23:01:41 INFO - 1500962501528 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:41 INFO - 1500962501528 Marionette TRACE 372 <- [1,3,null,{}]
23:01:41 INFO - 1500962501529 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:41 INFO - 1500962501529 Marionette TRACE 372 <- [1,4,null,{}]
23:01:41 INFO - 1500962501531 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}]
23:01:41 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500962501534 Marionette TRACE 372 <- [1,5,null,{"value":null}]
23:01:41 INFO - 1500962501535 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
23:01:41 INFO - 1500962501537 Marionette TRACE 372 <- [1,6,null,{}]
23:01:41 INFO - 1500962501538 Marionette DEBUG Closed connection 372
23:01:41 INFO - 1500962501539 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49801
23:01:41 INFO - 1500962501540 Marionette DEBUG Closed connection 373
23:01:41 INFO - 1500962501540 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49802
23:01:41 INFO - 1500962501542 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:41 INFO - 1500962501542 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501545 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501548 Marionette TRACE 374 <- [1,1,null,{"sessionId":"8de537fc-d088-4bfd-b6a0-e8ee03fd6e29","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501550 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
23:01:41 INFO - 1500962501551 Marionette TRACE 374 <- [1,2,null,{}]
23:01:41 INFO - 1500962501553 Marionette DEBUG Closed connection 374
23:01:41 INFO - 1500962501553 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49803
23:01:41 INFO - 1500962501554 Marionette DEBUG Closed connection 375
23:01:41 INFO - 1500962501555 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49804
23:01:41 INFO - 1500962501556 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:41 INFO - 1500962501556 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501559 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501562 Marionette TRACE 376 <- [1,1,null,{"sessionId":"cad788f9-e6e7-4dbe-bf00-9ebd292aa254","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501564 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"}]
23:01:41 INFO - 1500962501566 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"
23:01:41 INFO - 1500962501568 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"
23:01:41 INFO - 1500962501568 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"
23:01:41 INFO - 1500962501578 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"
23:01:41 INFO - 1500962501579 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"
23:01:41 INFO - 1500962501586 Marionette TRACE 376 <- [1,2,null,{}]
23:01:41 INFO - 1500962501590 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
23:01:41 INFO - 1500962501595 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"26e393aa-6c5c-44bd-b8d4-97709d2dfa0d","ELEMENT":"26e393aa-6c5c-44bd-b8d4-97709d2dfa0d"}}]
23:01:41 INFO - 1500962501596 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"26e393aa-6c5c-44bd-b8d4-97709d2dfa0d"}]
23:01:41 INFO - 1500962501841 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:41 INFO - 1500962501842 Marionette TRACE 376 <- [1,4,null,{}]
23:01:41 INFO - 1500962501843 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}]
23:01:41 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500962501847 Marionette TRACE 376 <- [1,5,null,{"value":null}]
23:01:41 INFO - 1500962501848 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
23:01:41 INFO - 1500962501849 Marionette TRACE 376 <- [1,6,null,{}]
23:01:41 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 336ms
23:01:41 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
23:01:41 INFO - 1500962501851 Marionette DEBUG Closed connection 376
23:01:41 INFO - 1500962501852 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49805
23:01:41 INFO - 1500962501853 Marionette DEBUG Closed connection 377
23:01:41 INFO - 1500962501853 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49806
23:01:41 INFO - 1500962501854 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:41 INFO - 1500962501855 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501858 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501862 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4b172090-a453-432e-b9d7-7bab40ded510","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501863 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
23:01:41 INFO - 1500962501863 Marionette TRACE 378 <- [1,2,null,{}]
23:01:41 INFO - 1500962501864 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:41 INFO - 1500962501864 Marionette TRACE 378 <- [1,3,null,{}]
23:01:41 INFO - 1500962501865 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:41 INFO - 1500962501866 Marionette TRACE 378 <- [1,4,null,{}]
23:01:41 INFO - 1500962501868 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}]
23:01:41 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500962501870 Marionette TRACE 378 <- [1,5,null,{"value":null}]
23:01:41 INFO - 1500962501871 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
23:01:41 INFO - 1500962501873 Marionette TRACE 378 <- [1,6,null,{}]
23:01:41 INFO - 1500962501874 Marionette DEBUG Closed connection 378
23:01:41 INFO - 1500962501875 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49807
23:01:41 INFO - 1500962501876 Marionette DEBUG Closed connection 379
23:01:41 INFO - 1500962501877 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49808
23:01:41 INFO - 1500962501878 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:41 INFO - 1500962501878 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501881 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501884 Marionette TRACE 380 <- [1,1,null,{"sessionId":"53ab5f7b-1f5a-4c8e-88d6-a374608eb7b8","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501886 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
23:01:41 INFO - 1500962501887 Marionette TRACE 380 <- [1,2,null,{}]
23:01:41 INFO - 1500962501889 Marionette DEBUG Closed connection 380
23:01:41 INFO - 1500962501889 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49809
23:01:41 INFO - 1500962501890 Marionette DEBUG Closed connection 381
23:01:41 INFO - 1500962501891 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49810
23:01:41 INFO - 1500962501892 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:41 INFO - 1500962501893 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:41 INFO - 1500962501895 Marionette DEBUG Register listener.js for window 4294967297
23:01:41 INFO - 1500962501906 Marionette TRACE 382 <- [1,1,null,{"sessionId":"65a207ab-9f97-41cc-a1ee-dee5aabe8e30","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:41 INFO - 1500962501907 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"}]
23:01:41 INFO - 1500962501909 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"
23:01:41 INFO - 1500962501911 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"
23:01:41 INFO - 1500962501911 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"
23:01:41 INFO - 1500962501918 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"
23:01:41 INFO - 1500962501921 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"
23:01:41 INFO - 1500962501925 Marionette TRACE 382 <- [1,2,null,{}]
23:01:41 INFO - 1500962501934 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
23:01:41 INFO - 1500962501940 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"92760316-f87e-49a3-ad76-59928d72f00d","ELEMENT":"92760316-f87e-49a3-ad76-59928d72f00d"}}]
23:01:41 INFO - 1500962501941 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"92760316-f87e-49a3-ad76-59928d72f00d"}]
23:01:42 INFO - 1500962502183 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:42 INFO - 1500962502184 Marionette TRACE 382 <- [1,4,null,{}]
23:01:42 INFO - 1500962502185 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}]
23:01:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500962502188 Marionette TRACE 382 <- [1,5,null,{"value":null}]
23:01:42 INFO - 1500962502189 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
23:01:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 341ms
23:01:42 INFO - TEST-START | test_click.py TestClick.test_input_file
23:01:42 INFO - 1500962502191 Marionette TRACE 382 <- [1,6,null,{}]
23:01:42 INFO - 1500962502193 Marionette DEBUG Closed connection 382
23:01:42 INFO - 1500962502193 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49811
23:01:42 INFO - 1500962502194 Marionette DEBUG Closed connection 383
23:01:42 INFO - 1500962502194 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49812
23:01:42 INFO - 1500962502196 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:42 INFO - 1500962502196 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502199 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502203 Marionette TRACE 384 <- [1,1,null,{"sessionId":"29d48e3b-1405-4ca4-93fc-182557a449c0","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502204 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
23:01:42 INFO - 1500962502204 Marionette TRACE 384 <- [1,2,null,{}]
23:01:42 INFO - 1500962502205 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:42 INFO - 1500962502205 Marionette TRACE 384 <- [1,3,null,{}]
23:01:42 INFO - 1500962502206 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:42 INFO - 1500962502207 Marionette TRACE 384 <- [1,4,null,{}]
23:01:42 INFO - 1500962502208 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}]
23:01:42 INFO - TEST-START: test_click.py TestClick.test_input_file1500962502211 Marionette TRACE 384 <- [1,5,null,{"value":null}]
23:01:42 INFO - 1500962502212 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
23:01:42 INFO - 1500962502213 Marionette TRACE 384 <- [1,6,null,{}]
23:01:42 INFO - 1500962502215 Marionette DEBUG Closed connection 384
23:01:42 INFO - 1500962502215 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49813
23:01:42 INFO - 1500962502216 Marionette DEBUG Closed connection 385
23:01:42 INFO - 1500962502217 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49814
23:01:42 INFO - 1500962502218 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:42 INFO - 1500962502218 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502221 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502225 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b54ac752-5267-469a-856d-cdc6503a1db3","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502226 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
23:01:42 INFO - 1500962502227 Marionette TRACE 386 <- [1,2,null,{}]
23:01:42 INFO - 1500962502229 Marionette DEBUG Closed connection 386
23:01:42 INFO - 1500962502229 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49815
23:01:42 INFO - 1500962502230 Marionette DEBUG Closed connection 387
23:01:42 INFO - 1500962502231 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49816
23:01:42 INFO - 1500962502232 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:42 INFO - 1500962502232 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502235 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502239 Marionette TRACE 388 <- [1,1,null,{"sessionId":"d810e545-4104-46ff-8d9c-4d346ef9a286","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502240 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
23:01:42 INFO - 1500962502242 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"
23:01:42 INFO - 1500962502243 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"
23:01:42 INFO - 1500962502244 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"
23:01:42 INFO - 1500962502250 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:42 INFO - 1500962502254 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:42 INFO - 1500962502256 Marionette TRACE 388 <- [1,2,null,{}]
23:01:42 INFO - 1500962502264 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
23:01:42 INFO - 1500962502267 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4681b51-6408-434b-b3db-8255243e7d6d","ELEMENT":"c4681b51-6408-434b-b3db-8255243e7d6d"}}]
23:01:42 INFO - 1500962502268 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c4681b51-6408-434b-b3db-8255243e7d6d"}]
23:01:42 INFO - 1500962502303 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
23:01:42 INFO - 1500962502305 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}]
23:01:42 INFO - TEST-END: test_click.py TestClick.test_input_file1500962502307 Marionette TRACE 388 <- [1,5,null,{"value":null}]
23:01:42 INFO - 1500962502308 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
23:01:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 118ms
23:01:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
23:01:42 INFO - 1500962502310 Marionette TRACE 388 <- [1,6,null,{}]
23:01:42 INFO - 1500962502311 Marionette DEBUG Closed connection 388
23:01:42 INFO - 1500962502312 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49817
23:01:42 INFO - 1500962502313 Marionette DEBUG Closed connection 389
23:01:42 INFO - 1500962502314 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49818
23:01:42 INFO - 1500962502314 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:42 INFO - 1500962502315 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502319 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502322 Marionette TRACE 390 <- [1,1,null,{"sessionId":"a2de6f65-f072-42b8-aac2-54a50a91067c","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502323 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
23:01:42 INFO - 1500962502324 Marionette TRACE 390 <- [1,2,null,{}]
23:01:42 INFO - 1500962502325 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:42 INFO - 1500962502325 Marionette TRACE 390 <- [1,3,null,{}]
23:01:42 INFO - 1500962502326 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:42 INFO - 1500962502327 Marionette TRACE 390 <- [1,4,null,{}]
23:01:42 INFO - 1500962502328 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}]
23:01:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500962502331 Marionette TRACE 390 <- [1,5,null,{"value":null}]
23:01:42 INFO - 1500962502332 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
23:01:42 INFO - 1500962502334 Marionette TRACE 390 <- [1,6,null,{}]
23:01:42 INFO - 1500962502335 Marionette DEBUG Closed connection 390
23:01:42 INFO - 1500962502336 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49819
23:01:42 INFO - 1500962502337 Marionette DEBUG Closed connection 391
23:01:42 INFO - 1500962502338 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49820
23:01:42 INFO - 1500962502339 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:42 INFO - 1500962502339 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502342 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502346 Marionette TRACE 392 <- [1,1,null,{"sessionId":"c985be13-7eb5-46a0-b260-b6610c49a580","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502347 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
23:01:42 INFO - 1500962502349 Marionette TRACE 392 <- [1,2,null,{}]
23:01:42 INFO - 1500962502350 Marionette DEBUG Closed connection 392
23:01:42 INFO - 1500962502351 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49821
23:01:42 INFO - 1500962502352 Marionette DEBUG Closed connection 393
23:01:42 INFO - 1500962502352 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49822
23:01:42 INFO - 1500962502353 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:42 INFO - 1500962502354 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502357 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502360 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f27300a7-3ec6-4f2b-84c1-7e5f7afbe80a","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502362 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"}]
23:01:42 INFO - 1500962502363 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:42 INFO - 1500962502365 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:42 INFO - 1500962502366 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:42 INFO - 1500962502373 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"
23:01:42 INFO - 1500962502375 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"
23:01:42 INFO - 1500962502383 Marionette TRACE 394 <- [1,2,null,{}]
23:01:42 INFO - 1500962502387 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
23:01:42 INFO - 1500962502395 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"03a36905-0f11-42ec-9e94-6ef2604c9d40","ELEMENT":"03a36905-0f11-42ec-9e94-6ef2604c9d40"}}]
23:01:42 INFO - 1500962502396 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
23:01:42 INFO - 1500962502398 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"24d17697-9ae4-43ad-88cf-123e7d06b153","ELEMENT":"24d17697-9ae4-43ad-88cf-123e7d06b153"}}]
23:01:42 INFO - 1500962502399 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"03a36905-0f11-42ec-9e94-6ef2604c9d40"}]
23:01:42 INFO - 1500962502643 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:42 INFO - 1500962502643 Marionette TRACE 394 <- [1,5,null,{}]
23:01:42 INFO - 1500962502645 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"24d17697-9ae4-43ad-88cf-123e7d06b153"}]
23:01:42 INFO - 1500962502661 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
23:01:42 INFO - 1500962502663 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
23:01:42 INFO - 1500962502666 Marionette TRACE 394 <- [1,7,null,{"value":false}]
23:01:42 INFO - 1500962502667 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}]
23:01:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500962502670 Marionette TRACE 394 <- [1,8,null,{"value":null}]
23:01:42 INFO - 1500962502670 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
23:01:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 362ms
23:01:42 INFO - 1500962502672 Marionette TRACE 394 <- [1,9,null,{}]
23:01:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
23:01:42 INFO - 1500962502674 Marionette DEBUG Closed connection 394
23:01:42 INFO - 1500962502675 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49823
23:01:42 INFO - 1500962502676 Marionette DEBUG Closed connection 395
23:01:42 INFO - 1500962502677 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49824
23:01:42 INFO - 1500962502678 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:42 INFO - 1500962502678 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502681 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502685 Marionette TRACE 396 <- [1,1,null,{"sessionId":"bf3e68e7-bbd2-4bf6-adab-3750b5ad8834","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502686 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
23:01:42 INFO - 1500962502686 Marionette TRACE 396 <- [1,2,null,{}]
23:01:42 INFO - 1500962502688 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:42 INFO - 1500962502688 Marionette TRACE 396 <- [1,3,null,{}]
23:01:42 INFO - 1500962502689 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:42 INFO - 1500962502690 Marionette TRACE 396 <- [1,4,null,{}]
23:01:42 INFO - 1500962502692 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}]
23:01:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500962502695 Marionette TRACE 396 <- [1,5,null,{"value":null}]
23:01:42 INFO - 1500962502696 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
23:01:42 INFO - 1500962502697 Marionette TRACE 396 <- [1,6,null,{}]
23:01:42 INFO - 1500962502699 Marionette DEBUG Closed connection 396
23:01:42 INFO - 1500962502700 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49825
23:01:42 INFO - 1500962502700 Marionette DEBUG Closed connection 397
23:01:42 INFO - 1500962502701 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49826
23:01:42 INFO - 1500962502702 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:42 INFO - 1500962502703 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502705 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502709 Marionette TRACE 398 <- [1,1,null,{"sessionId":"915a8036-083d-4f2f-9cd4-72b6e863b82d","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502711 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
23:01:42 INFO - 1500962502712 Marionette TRACE 398 <- [1,2,null,{}]
23:01:42 INFO - 1500962502714 Marionette DEBUG Closed connection 398
23:01:42 INFO - 1500962502714 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49827
23:01:42 INFO - 1500962502715 Marionette DEBUG Closed connection 399
23:01:42 INFO - 1500962502716 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49828
23:01:42 INFO - 1500962502717 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:42 INFO - 1500962502717 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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}
23:01:42 INFO - 1500962502720 Marionette DEBUG Register listener.js for window 4294967297
23:01:42 INFO - 1500962502723 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d92ca766-df01-474f-87fe-e49192a064f4","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":1660,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmprwkw0g.mozrunner","moz:accessibilityChecks":false}}]
23:01:42 INFO - 1500962502725 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"}]
23:01:42 INFO - 1500962502727 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"
23:01:42 INFO - 1500962502729 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"
23:01:42 INFO - 1500962502729 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"
23:01:42 INFO - 1500962502736 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"
23:01:42 INFO - 1500962502738 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"
23:01:42 INFO - 1500962502744 Marionette TRACE 400 <- [1,2,null,{}]
23:01:42 INFO - 1500962502752 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
23:01:42 INFO - 1500962502754 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"09673003-75ff-435a-8bf4-9a9589364f2f","ELEMENT":"09673003-75ff-435a-8bf4-9a9589364f2f"}}]
23:01:42 INFO - 1500962502755 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"09673003-75ff-435a-8bf4-9a9589364f2f"}]
23:01:42 INFO - 1500962502781 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
23:01:42 INFO - 1500962502782 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"09673003-75ff-435a-8bf4-9a9589364f2f"}]
23:01:42 INFO - 1500962502800 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element