could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
04:57:41 INFO - 1498996661748 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
04:57:41 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498996661750 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:57:41 INFO - 1498996661751 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:57:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 112ms
04:57:41 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:57:41 INFO - 1498996661752 Marionette TRACE 352 <- [1,6,null,{}]
04:57:41 INFO - 1498996661754 Marionette DEBUG Closed connection 352
04:57:41 INFO - 1498996661755 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49781
04:57:41 INFO - 1498996661755 Marionette DEBUG Closed connection 353
04:57:41 INFO - 1498996661756 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49782
04:57:41 INFO - 1498996661757 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:41 INFO - 1498996661757 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:41 INFO - 1498996661759 Marionette DEBUG Register listener.js for window 4294967297
04:57:41 INFO - 1498996661762 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f9ef7c2c-a992-445c-9dac-ddf68ae68090","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:41 INFO - 1498996661763 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:57:41 INFO - 1498996661763 Marionette TRACE 354 <- [1,2,null,{}]
04:57:41 INFO - 1498996661764 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:57:41 INFO - 1498996661765 Marionette TRACE 354 <- [1,3,null,{}]
04:57:41 INFO - 1498996661765 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:57:41 INFO - 1498996661766 Marionette TRACE 354 <- [1,4,null,{}]
04:57:41 INFO - 1498996661767 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
04:57:41 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498996661769 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:57:41 INFO - 1498996661770 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:57:41 INFO - 1498996661772 Marionette TRACE 354 <- [1,6,null,{}]
04:57:41 INFO - 1498996661773 Marionette DEBUG Closed connection 354
04:57:41 INFO - 1498996661773 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49783
04:57:41 INFO - 1498996661774 Marionette DEBUG Closed connection 355
04:57:41 INFO - 1498996661774 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49784
04:57:41 INFO - 1498996661775 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:41 INFO - 1498996661776 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:41 INFO - 1498996661778 Marionette DEBUG Register listener.js for window 4294967297
04:57:41 INFO - 1498996661781 Marionette TRACE 356 <- [1,1,null,{"sessionId":"328dfd12-d195-4f6d-b66e-88c26d1b88c1","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:41 INFO - 1498996661782 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:57:41 INFO - 1498996661783 Marionette TRACE 356 <- [1,2,null,{}]
04:57:41 INFO - 1498996661784 Marionette DEBUG Closed connection 356
04:57:41 INFO - 1498996661785 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49785
04:57:41 INFO - 1498996661785 Marionette DEBUG Closed connection 357
04:57:41 INFO - 1498996661786 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49786
04:57:41 INFO - 1498996661787 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:57:41 INFO - 1498996661787 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:41 INFO - 1498996661789 Marionette DEBUG Register listener.js for window 4294967297
04:57:41 INFO - 1498996661792 Marionette TRACE 358 <- [1,1,null,{"sessionId":"704fc60b-89da-41a9-aae1-c24753df8430","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:41 INFO - 1498996661793 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49346/clicks.html"}]
04:57:41 INFO - 1498996661795 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/hidden.html"
04:57:41 INFO - 1498996661801 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/hidden.html"
04:57:41 INFO - 1498996661801 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/hidden.html"
04:57:41 INFO - 1498996661806 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49346/clicks.html"
04:57:41 INFO - 1498996661811 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49346/clicks.html"
04:57:41 INFO - 1498996661812 Marionette TRACE 358 <- [1,2,null,{}]
04:57:41 INFO - 1498996661817 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:57:41 INFO - 1498996661820 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d30acc6d-ba33-4b13-8eb6-7a1c904cb922","ELEMENT":"d30acc6d-ba33-4b13-8eb6-7a1c904cb922"}}]
04:57:41 INFO - 1498996661821 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"d30acc6d-ba33-4b13-8eb6-7a1c904cb922"}]
04:57:41 INFO - 1498996661866 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/clicks.html"
04:57:41 INFO - 1498996661871 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/clicks.html"
04:57:41 INFO - 1498996661872 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/clicks.html"
04:57:41 INFO - 1498996661877 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49346/test.html"
04:57:41 INFO - 1498996661880 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49346/test.html"
04:57:41 INFO - 1498996661883 Marionette TRACE 358 <- [1,4,null,{}]
04:57:41 INFO - 1498996661886 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:57:41 INFO - 1498996661890 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d26eb3f0-f7de-476a-bd85-d3a87adc28a6","ELEMENT":"d26eb3f0-f7de-476a-bd85-d3a87adc28a6"}}]
04:57:41 INFO - 1498996661891 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:57:41 INFO - 1498996661893 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:57:41 INFO - 1498996661895 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
04:57:41 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498996661899 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:57:41 INFO - 1498996661901 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:57:41 INFO - 1498996661902 Marionette TRACE 358 <- [1,8,null,{}]
04:57:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 149ms
04:57:41 INFO - TEST-START | test_click.py TestClick.test_container_element
04:57:41 INFO - 1498996661903 Marionette DEBUG Closed connection 358
04:57:41 INFO - 1498996661904 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49789
04:57:41 INFO - 1498996661905 Marionette DEBUG Closed connection 359
04:57:41 INFO - 1498996661905 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49790
04:57:41 INFO - 1498996661906 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:41 INFO - 1498996661907 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:41 INFO - 1498996661910 Marionette DEBUG Register listener.js for window 4294967297
04:57:41 INFO - 1498996661915 Marionette TRACE 360 <- [1,1,null,{"sessionId":"bf8f331a-f6f7-40cb-8ccc-d79c9ea5f9fb","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:41 INFO - 1498996661916 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:57:41 INFO - 1498996661917 Marionette TRACE 360 <- [1,2,null,{}]
04:57:41 INFO - 1498996661917 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:57:41 INFO - 1498996661918 Marionette TRACE 360 <- [1,3,null,{}]
04:57:41 INFO - 1498996661918 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:57:41 INFO - 1498996661919 Marionette TRACE 360 <- [1,4,null,{}]
04:57:41 INFO - 1498996661920 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
04:57:41 INFO - TEST-START: test_click.py TestClick.test_container_element1498996661922 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:57:41 INFO - 1498996661923 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:57:41 INFO - 1498996661924 Marionette TRACE 360 <- [1,6,null,{}]
04:57:41 INFO - 1498996661926 Marionette DEBUG Closed connection 360
04:57:41 INFO - 1498996661926 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49791
04:57:41 INFO - 1498996661927 Marionette DEBUG Closed connection 361
04:57:41 INFO - 1498996661928 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49792
04:57:41 INFO - 1498996661928 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:41 INFO - 1498996661929 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:41 INFO - 1498996661931 Marionette DEBUG Register listener.js for window 4294967297
04:57:41 INFO - 1498996661934 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c49f725e-d4a6-4140-8221-38cfe9ad1f44","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:41 INFO - 1498996661943 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:57:41 INFO - 1498996661944 Marionette TRACE 362 <- [1,2,null,{}]
04:57:41 INFO - 1498996661945 Marionette DEBUG Closed connection 362
04:57:41 INFO - 1498996661946 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49793
04:57:41 INFO - 1498996661946 Marionette DEBUG Closed connection 363
04:57:41 INFO - 1498996661947 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49794
04:57:41 INFO - 1498996661948 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:57:41 INFO - 1498996661949 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:41 INFO - 1498996661951 Marionette DEBUG Register listener.js for window 4294967297
04:57:41 INFO - 1498996661953 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1c72c9e2-f9d2-4630-b26e-d69be2d56563","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:41 INFO - 1498996661954 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:57:41 INFO - 1498996661956 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/test.html"
04:57:41 INFO - 1498996661959 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/test.html"
04:57:41 INFO - 1498996661959 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/test.html"
04:57:41 INFO - 1498996661966 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:41 INFO - 1498996661971 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:41 INFO - 1498996661977 Marionette TRACE 364 <- [1,2,null,{}]
04:57:41 INFO - 1498996661983 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:57:41 INFO - 1498996661985 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8f49fd77-2d23-4079-bc32-850ae31c7c07","ELEMENT":"8f49fd77-2d23-4079-bc32-850ae31c7c07"}}]
04:57:41 INFO - 1498996661986 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8f49fd77-2d23-4079-bc32-850ae31c7c07"}]
04:57:42 INFO - 1498996662223 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:57:42 INFO - 1498996662224 Marionette TRACE 364 <- [1,4,null,{}]
04:57:42 INFO - 1498996662225 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8f49fd77-2d23-4079-bc32-850ae31c7c07","name":"selected"}]
04:57:42 INFO - 1498996662229 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:57:42 INFO - 1498996662231 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
04:57:42 INFO - TEST-END: test_click.py TestClick.test_container_element1498996662233 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:57:42 INFO - 1498996662234 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:57:42 INFO - 1498996662235 Marionette TRACE 364 <- [1,7,null,{}]
04:57:42 INFO - 1498996662236 Marionette DEBUG Closed connection 364
04:57:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 333ms
04:57:42 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:57:42 INFO - 1498996662239 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49795
04:57:42 INFO - 1498996662239 Marionette DEBUG Closed connection 365
04:57:42 INFO - 1498996662240 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49796
04:57:42 INFO - 1498996662241 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:42 INFO - 1498996662241 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662243 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662246 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8fccb469-fb2a-48cc-ba8d-c635449a912a","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662249 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:57:42 INFO - 1498996662249 Marionette TRACE 366 <- [1,2,null,{}]
04:57:42 INFO - 1498996662250 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:57:42 INFO - 1498996662251 Marionette TRACE 366 <- [1,3,null,{}]
04:57:42 INFO - 1498996662251 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:57:42 INFO - 1498996662252 Marionette TRACE 366 <- [1,4,null,{}]
04:57:42 INFO - 1498996662253 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
04:57:42 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498996662256 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:57:42 INFO - 1498996662257 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:57:42 INFO - 1498996662258 Marionette TRACE 366 <- [1,6,null,{}]
04:57:42 INFO - 1498996662259 Marionette DEBUG Closed connection 366
04:57:42 INFO - 1498996662259 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49797
04:57:42 INFO - 1498996662260 Marionette DEBUG Closed connection 367
04:57:42 INFO - 1498996662261 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49798
04:57:42 INFO - 1498996662262 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:42 INFO - 1498996662262 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662264 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662267 Marionette TRACE 368 <- [1,1,null,{"sessionId":"1b268627-91cc-41e5-8bb1-a250168d4e45","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662268 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:57:42 INFO - 1498996662269 Marionette TRACE 368 <- [1,2,null,{}]
04:57:42 INFO - 1498996662270 Marionette DEBUG Closed connection 368
04:57:42 INFO - 1498996662271 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49799
04:57:42 INFO - 1498996662272 Marionette DEBUG Closed connection 369
04:57:42 INFO - 1498996662273 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49800
04:57:42 INFO - 1498996662274 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:57:42 INFO - 1498996662274 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662276 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662278 Marionette TRACE 370 <- [1,1,null,{"sessionId":"8686ee88-33d6-4ad3-bb37-98a22b3e67db","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662280 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:57:42 INFO - 1498996662281 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662283 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662283 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662288 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662291 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662292 Marionette TRACE 370 <- [1,2,null,{}]
04:57:42 INFO - 1498996662295 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:57:42 INFO - 1498996662300 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c55bffe4-dcbc-491b-927a-62dc99bef1dc","ELEMENT":"c55bffe4-dcbc-491b-927a-62dc99bef1dc"}}]
04:57:42 INFO - 1498996662301 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c55bffe4-dcbc-491b-927a-62dc99bef1dc"}]
04:57:42 INFO - 1498996662541 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:57:42 INFO - 1498996662542 Marionette TRACE 370 <- [1,4,null,{}]
04:57:42 INFO - 1498996662543 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c55bffe4-dcbc-491b-927a-62dc99bef1dc","name":"selected"}]
04:57:42 INFO - 1498996662548 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:57:42 INFO - 1498996662549 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
04:57:42 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498996662552 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:57:42 INFO - 1498996662552 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:57:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 316ms
04:57:42 INFO - 1498996662554 Marionette TRACE 370 <- [1,7,null,{}]
04:57:42 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:57:42 INFO - 1498996662555 Marionette DEBUG Closed connection 370
04:57:42 INFO - 1498996662556 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49801
04:57:42 INFO - 1498996662557 Marionette DEBUG Closed connection 371
04:57:42 INFO - 1498996662558 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49802
04:57:42 INFO - 1498996662559 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:42 INFO - 1498996662559 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662562 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662564 Marionette TRACE 372 <- [1,1,null,{"sessionId":"58c26a82-58a7-41c6-a073-4eeeb385e647","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662565 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:57:42 INFO - 1498996662566 Marionette TRACE 372 <- [1,2,null,{}]
04:57:42 INFO - 1498996662566 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:57:42 INFO - 1498996662567 Marionette TRACE 372 <- [1,3,null,{}]
04:57:42 INFO - 1498996662568 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:57:42 INFO - 1498996662568 Marionette TRACE 372 <- [1,4,null,{}]
04:57:42 INFO - 1498996662570 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
04:57:42 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498996662572 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:57:42 INFO - 1498996662573 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:57:42 INFO - 1498996662574 Marionette TRACE 372 <- [1,6,null,{}]
04:57:42 INFO - 1498996662575 Marionette DEBUG Closed connection 372
04:57:42 INFO - 1498996662576 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49803
04:57:42 INFO - 1498996662577 Marionette DEBUG Closed connection 373
04:57:42 INFO - 1498996662577 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49804
04:57:42 INFO - 1498996662578 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:42 INFO - 1498996662579 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662581 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662583 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c291dd2b-2719-460d-8679-abdea70c4af3","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662584 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:57:42 INFO - 1498996662586 Marionette TRACE 374 <- [1,2,null,{}]
04:57:42 INFO - 1498996662587 Marionette DEBUG Closed connection 374
04:57:42 INFO - 1498996662587 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49805
04:57:42 INFO - 1498996662588 Marionette DEBUG Closed connection 375
04:57:42 INFO - 1498996662589 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49806
04:57:42 INFO - 1498996662590 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:57:42 INFO - 1498996662590 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662592 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662594 Marionette TRACE 376 <- [1,1,null,{"sessionId":"93dd6445-ccea-40a3-bb79-446e1c6008db","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662595 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
04:57:42 INFO - 1498996662597 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662599 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662599 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662610 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:57:42 INFO - 1498996662612 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:57:42 INFO - 1498996662613 Marionette TRACE 376 <- [1,2,null,{}]
04:57:42 INFO - 1498996662620 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:57:42 INFO - 1498996662623 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6aa8ab62-880d-49d6-9b02-84ef598de6b2","ELEMENT":"6aa8ab62-880d-49d6-9b02-84ef598de6b2"}}]
04:57:42 INFO - 1498996662624 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6aa8ab62-880d-49d6-9b02-84ef598de6b2"}]
04:57:42 INFO - 1498996662862 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:57:42 INFO - 1498996662862 Marionette TRACE 376 <- [1,4,null,{}]
04:57:42 INFO - 1498996662864 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
04:57:42 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498996662866 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:57:42 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 314ms
04:57:42 INFO - 1498996662867 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:57:42 INFO - 1498996662868 Marionette TRACE 376 <- [1,6,null,{}]
04:57:42 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:57:42 INFO - 1498996662870 Marionette DEBUG Closed connection 376
04:57:42 INFO - 1498996662872 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49807
04:57:42 INFO - 1498996662872 Marionette DEBUG Closed connection 377
04:57:42 INFO - 1498996662873 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49808
04:57:42 INFO - 1498996662874 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:42 INFO - 1498996662874 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662876 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662879 Marionette TRACE 378 <- [1,1,null,{"sessionId":"fdc81ea3-3ca2-40e6-b090-5ef8f6276324","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662880 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:57:42 INFO - 1498996662880 Marionette TRACE 378 <- [1,2,null,{}]
04:57:42 INFO - 1498996662881 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:57:42 INFO - 1498996662881 Marionette TRACE 378 <- [1,3,null,{}]
04:57:42 INFO - 1498996662882 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:57:42 INFO - 1498996662883 Marionette TRACE 378 <- [1,4,null,{}]
04:57:42 INFO - 1498996662884 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
04:57:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498996662886 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:57:42 INFO - 1498996662887 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:57:42 INFO - 1498996662888 Marionette TRACE 378 <- [1,6,null,{}]
04:57:42 INFO - 1498996662890 Marionette DEBUG Closed connection 378
04:57:42 INFO - 1498996662890 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49809
04:57:42 INFO - 1498996662891 Marionette DEBUG Closed connection 379
04:57:42 INFO - 1498996662892 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49810
04:57:42 INFO - 1498996662893 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:42 INFO - 1498996662893 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662895 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662898 Marionette TRACE 380 <- [1,1,null,{"sessionId":"edbdb6c6-69c6-4bf9-a4db-170097174719","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662899 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:57:42 INFO - 1498996662900 Marionette TRACE 380 <- [1,2,null,{}]
04:57:42 INFO - 1498996662902 Marionette DEBUG Closed connection 380
04:57:42 INFO - 1498996662902 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49811
04:57:42 INFO - 1498996662903 Marionette DEBUG Closed connection 381
04:57:42 INFO - 1498996662903 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49812
04:57:42 INFO - 1498996662904 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:57:42 INFO - 1498996662905 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:42 INFO - 1498996662907 Marionette DEBUG Register listener.js for window 4294967297
04:57:42 INFO - 1498996662909 Marionette TRACE 382 <- [1,1,null,{"sessionId":"363c8e16-3372-443a-9a3b-c9d3f11cf225","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:42 INFO - 1498996662910 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:57:42 INFO - 1498996662912 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:57:42 INFO - 1498996662914 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:57:42 INFO - 1498996662914 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:57:42 INFO - 1498996662919 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662922 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:42 INFO - 1498996662923 Marionette TRACE 382 <- [1,2,null,{}]
04:57:42 INFO - 1498996662926 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:57:42 INFO - 1498996662928 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b70086fa-df6f-4b1b-90da-8af50211dce8","ELEMENT":"b70086fa-df6f-4b1b-90da-8af50211dce8"}}]
04:57:42 INFO - 1498996662929 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"b70086fa-df6f-4b1b-90da-8af50211dce8"}]
04:57:43 INFO - 1498996663169 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:57:43 INFO - 1498996663170 Marionette TRACE 382 <- [1,4,null,{}]
04:57:43 INFO - 1498996663172 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
04:57:43 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 307ms
04:57:43 INFO - TEST-START | test_click.py TestClick.test_input_file
04:57:43 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498996663174 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:57:43 INFO - 1498996663175 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:57:43 INFO - 1498996663176 Marionette TRACE 382 <- [1,6,null,{}]
04:57:43 INFO - 1498996663177 Marionette DEBUG Closed connection 382
04:57:43 INFO - 1498996663178 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49813
04:57:43 INFO - 1498996663179 Marionette DEBUG Closed connection 383
04:57:43 INFO - 1498996663180 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49814
04:57:43 INFO - 1498996663181 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:43 INFO - 1498996663181 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663183 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663186 Marionette TRACE 384 <- [1,1,null,{"sessionId":"913f5a63-c158-46a1-b145-5d549fdf7eb2","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663187 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:57:43 INFO - 1498996663187 Marionette TRACE 384 <- [1,2,null,{}]
04:57:43 INFO - 1498996663188 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:57:43 INFO - 1498996663188 Marionette TRACE 384 <- [1,3,null,{}]
04:57:43 INFO - 1498996663189 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:57:43 INFO - 1498996663190 Marionette TRACE 384 <- [1,4,null,{}]
04:57:43 INFO - 1498996663191 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
04:57:43 INFO - TEST-START: test_click.py TestClick.test_input_file1498996663194 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:57:43 INFO - 1498996663195 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:57:43 INFO - 1498996663196 Marionette TRACE 384 <- [1,6,null,{}]
04:57:43 INFO - 1498996663197 Marionette DEBUG Closed connection 384
04:57:43 INFO - 1498996663197 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49815
04:57:43 INFO - 1498996663198 Marionette DEBUG Closed connection 385
04:57:43 INFO - 1498996663199 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49816
04:57:43 INFO - 1498996663200 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:43 INFO - 1498996663200 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663202 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663205 Marionette TRACE 386 <- [1,1,null,{"sessionId":"2b9c0e01-a2a8-4283-bf94-db12e8c660bd","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663206 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:57:43 INFO - 1498996663207 Marionette TRACE 386 <- [1,2,null,{}]
04:57:43 INFO - 1498996663208 Marionette DEBUG Closed connection 386
04:57:43 INFO - 1498996663209 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49817
04:57:43 INFO - 1498996663209 Marionette DEBUG Closed connection 387
04:57:43 INFO - 1498996663210 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49818
04:57:43 INFO - 1498996663211 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:57:43 INFO - 1498996663212 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663214 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663216 Marionette TRACE 388 <- [1,1,null,{"sessionId":"c49b6c4e-37d4-448c-9eba-cc82a1b6109d","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663217 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:57:43 INFO - 1498996663219 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:43 INFO - 1498996663220 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:43 INFO - 1498996663220 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:57:43 INFO - 1498996663226 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:57:43 INFO - 1498996663229 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:57:43 INFO - 1498996663234 Marionette TRACE 388 <- [1,2,null,{}]
04:57:43 INFO - 1498996663236 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:57:43 INFO - 1498996663239 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf88e7cd-c10e-4f41-9ade-017911ceeaf0","ELEMENT":"cf88e7cd-c10e-4f41-9ade-017911ceeaf0"}}]
04:57:43 INFO - 1498996663240 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"cf88e7cd-c10e-4f41-9ade-017911ceeaf0"}]
04:57:43 INFO - 1498996663270 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
04:57:43 INFO - 1498996663273 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
04:57:43 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 101ms
04:57:43 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:57:43 INFO - TEST-END: test_click.py TestClick.test_input_file1498996663275 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:57:43 INFO - 1498996663276 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:57:43 INFO - 1498996663277 Marionette TRACE 388 <- [1,6,null,{}]
04:57:43 INFO - 1498996663279 Marionette DEBUG Closed connection 388
04:57:43 INFO - 1498996663279 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49819
04:57:43 INFO - 1498996663280 Marionette DEBUG Closed connection 389
04:57:43 INFO - 1498996663281 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49820
04:57:43 INFO - 1498996663282 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:43 INFO - 1498996663282 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663285 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663287 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d6d8a7b3-b2cb-43d4-8558-20c792a3e275","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663288 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:57:43 INFO - 1498996663289 Marionette TRACE 390 <- [1,2,null,{}]
04:57:43 INFO - 1498996663290 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:57:43 INFO - 1498996663290 Marionette TRACE 390 <- [1,3,null,{}]
04:57:43 INFO - 1498996663291 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:57:43 INFO - 1498996663291 Marionette TRACE 390 <- [1,4,null,{}]
04:57:43 INFO - 1498996663293 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
04:57:43 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498996663295 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:57:43 INFO - 1498996663296 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:57:43 INFO - 1498996663297 Marionette TRACE 390 <- [1,6,null,{}]
04:57:43 INFO - 1498996663298 Marionette DEBUG Closed connection 390
04:57:43 INFO - 1498996663299 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49821
04:57:43 INFO - 1498996663300 Marionette DEBUG Closed connection 391
04:57:43 INFO - 1498996663300 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49822
04:57:43 INFO - 1498996663301 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:43 INFO - 1498996663302 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663304 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663306 Marionette TRACE 392 <- [1,1,null,{"sessionId":"c133eb46-a098-4e30-a15c-4037d40941aa","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663307 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:57:43 INFO - 1498996663308 Marionette TRACE 392 <- [1,2,null,{}]
04:57:43 INFO - 1498996663310 Marionette DEBUG Closed connection 392
04:57:43 INFO - 1498996663310 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49823
04:57:43 INFO - 1498996663311 Marionette DEBUG Closed connection 393
04:57:43 INFO - 1498996663312 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49824
04:57:43 INFO - 1498996663312 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:57:43 INFO - 1498996663313 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663315 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663318 Marionette TRACE 394 <- [1,1,null,{"sessionId":"260722d1-7b7e-4847-8d14-f07deca0f0cf","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663319 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
04:57:43 INFO - 1498996663320 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:57:43 INFO - 1498996663322 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:57:43 INFO - 1498996663322 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:57:43 INFO - 1498996663328 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:57:43 INFO - 1498996663330 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:57:43 INFO - 1498996663335 Marionette TRACE 394 <- [1,2,null,{}]
04:57:43 INFO - 1498996663338 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:57:43 INFO - 1498996663340 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f68ecc68-8971-4319-bc7a-16101fbcbd1b","ELEMENT":"f68ecc68-8971-4319-bc7a-16101fbcbd1b"}}]
04:57:43 INFO - 1498996663341 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:57:43 INFO - 1498996663343 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bef4d548-8fb3-4972-8d1c-497ec6554aeb","ELEMENT":"bef4d548-8fb3-4972-8d1c-497ec6554aeb"}}]
04:57:43 INFO - 1498996663344 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"f68ecc68-8971-4319-bc7a-16101fbcbd1b"}]
04:57:43 INFO - 1498996663579 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:57:43 INFO - 1498996663580 Marionette TRACE 394 <- [1,5,null,{}]
04:57:43 INFO - 1498996663581 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"bef4d548-8fb3-4972-8d1c-497ec6554aeb"}]
04:57:43 INFO - 1498996663594 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
04:57:43 INFO - 1498996663596 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:57:43 INFO - 1498996663599 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:57:43 INFO - 1498996663601 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
04:57:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498996663603 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:57:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 327ms
04:57:43 INFO - 1498996663603 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:57:43 INFO - 1498996663605 Marionette TRACE 394 <- [1,9,null,{}]
04:57:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:57:43 INFO - 1498996663606 Marionette DEBUG Closed connection 394
04:57:43 INFO - 1498996663608 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49825
04:57:43 INFO - 1498996663608 Marionette DEBUG Closed connection 395
04:57:43 INFO - 1498996663609 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49826
04:57:43 INFO - 1498996663610 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:43 INFO - 1498996663610 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663615 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663625 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9d8dabd7-6128-4c0b-af01-2f33f226cfc4","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663626 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:57:43 INFO - 1498996663626 Marionette TRACE 396 <- [1,2,null,{}]
04:57:43 INFO - 1498996663627 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:57:43 INFO - 1498996663628 Marionette TRACE 396 <- [1,3,null,{}]
04:57:43 INFO - 1498996663629 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:57:43 INFO - 1498996663629 Marionette TRACE 396 <- [1,4,null,{}]
04:57:43 INFO - 1498996663631 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
04:57:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498996663633 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:57:43 INFO - 1498996663634 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:57:43 INFO - 1498996663635 Marionette TRACE 396 <- [1,6,null,{}]
04:57:43 INFO - 1498996663637 Marionette DEBUG Closed connection 396
04:57:43 INFO - 1498996663637 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49827
04:57:43 INFO - 1498996663638 Marionette DEBUG Closed connection 397
04:57:43 INFO - 1498996663638 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49828
04:57:43 INFO - 1498996663639 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:57:43 INFO - 1498996663640 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663642 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663644 Marionette TRACE 398 <- [1,1,null,{"sessionId":"aca2d0dc-e896-4c89-9152-beb0ea227c1d","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663645 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:57:43 INFO - 1498996663647 Marionette TRACE 398 <- [1,2,null,{}]
04:57:43 INFO - 1498996663648 Marionette DEBUG Closed connection 398
04:57:43 INFO - 1498996663648 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49829
04:57:43 INFO - 1498996663649 Marionette DEBUG Closed connection 399
04:57:43 INFO - 1498996663650 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49830
04:57:43 INFO - 1498996663651 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:57:43 INFO - 1498996663651 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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}
04:57:43 INFO - 1498996663653 Marionette DEBUG Register listener.js for window 4294967297
04:57:43 INFO - 1498996663656 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d304ef31-49d5-448a-8100-9a4f82d4416b","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":1744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-284.000\\AppData\\Local\\Temp\\tmpudog2d.mozrunner","moz:accessibilityChecks":false}}]
04:57:43 INFO - 1498996663657 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
04:57:43 INFO - 1498996663659 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:57:43 INFO - 1498996663660 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:57:43 INFO - 1498996663661 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:57:43 INFO - 1498996663666 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
04:57:43 INFO - 1498996663669 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
04:57:43 INFO - 1498996663673 Marionette TRACE 400 <- [1,2,null,{}]
04:57:43 INFO - 1498996663676 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:57:43 INFO - 1498996663678 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d58ee45c-c109-4a02-b103-646d54f09fca","ELEMENT":"d58ee45c-c109-4a02-b103-646d54f09fca"}}]
04:57:43 INFO - 1498996663679 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"d58ee45c-c109-4a02-b103-646d54f09fca"}]
04:57:43 INFO - 1498996663705 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:57:43 INFO - 1498996663707 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"d58ee45c-c109-4a02-b103-646d54f09fca"}]
04:57:43 INFO - 1498996663722 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element