could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
18:53:39 INFO - 1499651619905 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
18:53:39 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499651619907 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:53:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 105ms
18:53:39 INFO - 1499651619908 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:53:39 INFO - 1499651619909 Marionette TRACE 352 <- [1,6,null,{}]
18:53:39 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:53:39 INFO - 1499651619911 Marionette DEBUG Closed connection 352
18:53:39 INFO - 1499651619912 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49777
18:53:39 INFO - 1499651619913 Marionette DEBUG Closed connection 353
18:53:39 INFO - 1499651619913 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49778
18:53:39 INFO - 1499651619914 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:39 INFO - 1499651619914 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:39 INFO - 1499651619917 Marionette DEBUG Register listener.js for window 4294967297
18:53:39 INFO - 1499651619926 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b8343562-cce4-46ef-960f-c0e29d16a4c8","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:39 INFO - 1499651619928 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:53:39 INFO - 1499651619928 Marionette TRACE 354 <- [1,2,null,{}]
18:53:39 INFO - 1499651619929 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:39 INFO - 1499651619930 Marionette TRACE 354 <- [1,3,null,{}]
18:53:39 INFO - 1499651619930 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:39 INFO - 1499651619931 Marionette TRACE 354 <- [1,4,null,{}]
18:53:39 INFO - 1499651619932 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
18:53:39 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499651619935 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:53:39 INFO - 1499651619936 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:53:39 INFO - 1499651619937 Marionette TRACE 354 <- [1,6,null,{}]
18:53:39 INFO - 1499651619938 Marionette DEBUG Closed connection 354
18:53:39 INFO - 1499651619939 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49779
18:53:39 INFO - 1499651619939 Marionette DEBUG Closed connection 355
18:53:39 INFO - 1499651619940 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49780
18:53:39 INFO - 1499651619941 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:39 INFO - 1499651619941 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:39 INFO - 1499651619943 Marionette DEBUG Register listener.js for window 4294967297
18:53:39 INFO - 1499651619946 Marionette TRACE 356 <- [1,1,null,{"sessionId":"57f5b25b-3b6b-4399-a15d-6b94ea9f223c","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:39 INFO - 1499651619947 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:53:39 INFO - 1499651619948 Marionette TRACE 356 <- [1,2,null,{}]
18:53:39 INFO - 1499651619950 Marionette DEBUG Closed connection 356
18:53:39 INFO - 1499651619950 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49781
18:53:39 INFO - 1499651619951 Marionette DEBUG Closed connection 357
18:53:39 INFO - 1499651619951 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49782
18:53:39 INFO - 1499651619952 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:39 INFO - 1499651619953 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:39 INFO - 1499651619955 Marionette DEBUG Register listener.js for window 4294967297
18:53:39 INFO - 1499651619957 Marionette TRACE 358 <- [1,1,null,{"sessionId":"9cb9f083-bf44-41de-bed9-ae294a39109e","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:39 INFO - 1499651619958 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49340/clicks.html"}]
18:53:39 INFO - 1499651619960 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/hidden.html"
18:53:39 INFO - 1499651619967 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/hidden.html"
18:53:39 INFO - 1499651619968 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/hidden.html"
18:53:39 INFO - 1499651619974 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49340/clicks.html"
18:53:39 INFO - 1499651619978 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49340/clicks.html"
18:53:39 INFO - 1499651619989 Marionette TRACE 358 <- [1,2,null,{}]
18:53:40 INFO - 1499651619999 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:53:40 INFO - 1499651620002 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7d43f98a-1689-442b-aa1c-f5bfb8778b9e","ELEMENT":"7d43f98a-1689-442b-aa1c-f5bfb8778b9e"}}]
18:53:40 INFO - 1499651620003 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"7d43f98a-1689-442b-aa1c-f5bfb8778b9e"}]
18:53:40 INFO - 1499651620047 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/clicks.html"
18:53:40 INFO - 1499651620053 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/clicks.html"
18:53:40 INFO - 1499651620053 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/clicks.html"
18:53:40 INFO - 1499651620059 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49340/test.html"
18:53:40 INFO - 1499651620061 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49340/test.html"
18:53:40 INFO - 1499651620066 Marionette TRACE 358 <- [1,4,null,{}]
18:53:40 INFO - 1499651620069 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:53:40 INFO - 1499651620072 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"558ad9d5-f3ed-4e2d-b975-9eab36abd53a","ELEMENT":"558ad9d5-f3ed-4e2d-b975-9eab36abd53a"}}]
18:53:40 INFO - 1499651620073 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:53:40 INFO - 1499651620074 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:53:40 INFO - 1499651620075 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
18:53:40 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499651620077 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:53:40 INFO - 1499651620078 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:53:40 INFO - 1499651620079 Marionette TRACE 358 <- [1,8,null,{}]
18:53:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 172ms
18:53:40 INFO - TEST-START | test_click.py TestClick.test_container_element
18:53:40 INFO - 1499651620083 Marionette DEBUG Closed connection 358
18:53:40 INFO - 1499651620084 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49785
18:53:40 INFO - 1499651620085 Marionette DEBUG Closed connection 359
18:53:40 INFO - 1499651620085 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49786
18:53:40 INFO - 1499651620086 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:40 INFO - 1499651620087 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620089 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620092 Marionette TRACE 360 <- [1,1,null,{"sessionId":"f9865521-f3b5-401d-962c-bfa2a1e6a6d0","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620093 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:53:40 INFO - 1499651620093 Marionette TRACE 360 <- [1,2,null,{}]
18:53:40 INFO - 1499651620094 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:40 INFO - 1499651620095 Marionette TRACE 360 <- [1,3,null,{}]
18:53:40 INFO - 1499651620097 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:40 INFO - 1499651620097 Marionette TRACE 360 <- [1,4,null,{}]
18:53:40 INFO - 1499651620099 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
18:53:40 INFO - TEST-START: test_click.py TestClick.test_container_element1499651620101 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:53:40 INFO - 1499651620101 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:53:40 INFO - 1499651620103 Marionette TRACE 360 <- [1,6,null,{}]
18:53:40 INFO - 1499651620104 Marionette DEBUG Closed connection 360
18:53:40 INFO - 1499651620104 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49787
18:53:40 INFO - 1499651620105 Marionette DEBUG Closed connection 361
18:53:40 INFO - 1499651620106 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49788
18:53:40 INFO - 1499651620107 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:40 INFO - 1499651620107 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620109 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620112 Marionette TRACE 362 <- [1,1,null,{"sessionId":"165a990f-682e-4101-b2eb-571bdf897c19","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620113 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:53:40 INFO - 1499651620114 Marionette TRACE 362 <- [1,2,null,{}]
18:53:40 INFO - 1499651620116 Marionette DEBUG Closed connection 362
18:53:40 INFO - 1499651620116 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49789
18:53:40 INFO - 1499651620117 Marionette DEBUG Closed connection 363
18:53:40 INFO - 1499651620117 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49790
18:53:40 INFO - 1499651620118 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:40 INFO - 1499651620119 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620121 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620124 Marionette TRACE 364 <- [1,1,null,{"sessionId":"ded7f29f-6484-44f2-a8d7-555b13f3a18d","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620125 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:53:40 INFO - 1499651620126 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/test.html"
18:53:40 INFO - 1499651620129 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/test.html"
18:53:40 INFO - 1499651620130 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/test.html"
18:53:40 INFO - 1499651620136 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620141 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620144 Marionette TRACE 364 <- [1,2,null,{}]
18:53:40 INFO - 1499651620151 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:53:40 INFO - 1499651620153 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7b598146-76a4-42d1-907d-b6fa33d178a4","ELEMENT":"7b598146-76a4-42d1-907d-b6fa33d178a4"}}]
18:53:40 INFO - 1499651620154 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7b598146-76a4-42d1-907d-b6fa33d178a4"}]
18:53:40 INFO - 1499651620393 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:40 INFO - 1499651620394 Marionette TRACE 364 <- [1,4,null,{}]
18:53:40 INFO - 1499651620395 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7b598146-76a4-42d1-907d-b6fa33d178a4","name":"selected"}]
18:53:40 INFO - 1499651620399 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:53:40 INFO - 1499651620401 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
18:53:40 INFO - TEST-END: test_click.py TestClick.test_container_element1499651620403 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:53:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 323ms
18:53:40 INFO - 1499651620404 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:53:40 INFO - 1499651620405 Marionette TRACE 364 <- [1,7,null,{}]
18:53:40 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:53:40 INFO - 1499651620407 Marionette DEBUG Closed connection 364
18:53:40 INFO - 1499651620408 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49791
18:53:40 INFO - 1499651620409 Marionette DEBUG Closed connection 365
18:53:40 INFO - 1499651620409 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49792
18:53:40 INFO - 1499651620410 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:40 INFO - 1499651620411 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620413 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620416 Marionette TRACE 366 <- [1,1,null,{"sessionId":"0ed28aba-012f-401d-af1f-cf885fc1a03d","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620417 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:53:40 INFO - 1499651620417 Marionette TRACE 366 <- [1,2,null,{}]
18:53:40 INFO - 1499651620418 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:40 INFO - 1499651620418 Marionette TRACE 366 <- [1,3,null,{}]
18:53:40 INFO - 1499651620419 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:40 INFO - 1499651620419 Marionette TRACE 366 <- [1,4,null,{}]
18:53:40 INFO - 1499651620421 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
18:53:40 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499651620423 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:53:40 INFO - 1499651620424 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:53:40 INFO - 1499651620425 Marionette TRACE 366 <- [1,6,null,{}]
18:53:40 INFO - 1499651620427 Marionette DEBUG Closed connection 366
18:53:40 INFO - 1499651620427 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49793
18:53:40 INFO - 1499651620428 Marionette DEBUG Closed connection 367
18:53:40 INFO - 1499651620429 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49794
18:53:40 INFO - 1499651620430 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:40 INFO - 1499651620430 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620432 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620435 Marionette TRACE 368 <- [1,1,null,{"sessionId":"3e0d8082-449e-46cb-820f-0af16e14988b","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620436 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:53:40 INFO - 1499651620437 Marionette TRACE 368 <- [1,2,null,{}]
18:53:40 INFO - 1499651620438 Marionette DEBUG Closed connection 368
18:53:40 INFO - 1499651620439 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49795
18:53:40 INFO - 1499651620440 Marionette DEBUG Closed connection 369
18:53:40 INFO - 1499651620440 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49796
18:53:40 INFO - 1499651620441 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:40 INFO - 1499651620442 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620444 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620446 Marionette TRACE 370 <- [1,1,null,{"sessionId":"502d6a5e-0133-4f41-bc5c-38f816b6cf91","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620447 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:53:40 INFO - 1499651620449 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620450 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620451 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620456 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620459 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620461 Marionette TRACE 370 <- [1,2,null,{}]
18:53:40 INFO - 1499651620468 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:53:40 INFO - 1499651620470 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"522e3c43-f257-49ef-a21b-700df2708a86","ELEMENT":"522e3c43-f257-49ef-a21b-700df2708a86"}}]
18:53:40 INFO - 1499651620471 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"522e3c43-f257-49ef-a21b-700df2708a86"}]
18:53:40 INFO - 1499651620707 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:40 INFO - 1499651620708 Marionette TRACE 370 <- [1,4,null,{}]
18:53:40 INFO - 1499651620709 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"522e3c43-f257-49ef-a21b-700df2708a86","name":"selected"}]
18:53:40 INFO - 1499651620713 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:53:40 INFO - 1499651620715 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
18:53:40 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499651620717 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:53:40 INFO - 1499651620718 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:53:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 313ms
18:53:40 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:53:40 INFO - 1499651620719 Marionette TRACE 370 <- [1,7,null,{}]
18:53:40 INFO - 1499651620720 Marionette DEBUG Closed connection 370
18:53:40 INFO - 1499651620721 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49797
18:53:40 INFO - 1499651620722 Marionette DEBUG Closed connection 371
18:53:40 INFO - 1499651620722 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49798
18:53:40 INFO - 1499651620723 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:40 INFO - 1499651620724 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620726 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620729 Marionette TRACE 372 <- [1,1,null,{"sessionId":"4833938a-097c-4c4c-be97-9dcf0018a6f8","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620730 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:53:40 INFO - 1499651620730 Marionette TRACE 372 <- [1,2,null,{}]
18:53:40 INFO - 1499651620731 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:40 INFO - 1499651620732 Marionette TRACE 372 <- [1,3,null,{}]
18:53:40 INFO - 1499651620732 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:40 INFO - 1499651620733 Marionette TRACE 372 <- [1,4,null,{}]
18:53:40 INFO - 1499651620734 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
18:53:40 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499651620737 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:53:40 INFO - 1499651620738 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:53:40 INFO - 1499651620739 Marionette TRACE 372 <- [1,6,null,{}]
18:53:40 INFO - 1499651620740 Marionette DEBUG Closed connection 372
18:53:40 INFO - 1499651620741 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49799
18:53:40 INFO - 1499651620741 Marionette DEBUG Closed connection 373
18:53:40 INFO - 1499651620742 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49800
18:53:40 INFO - 1499651620743 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:40 INFO - 1499651620744 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620746 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620748 Marionette TRACE 374 <- [1,1,null,{"sessionId":"8dc0447c-c11a-42cb-b198-4865f1feea22","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620750 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:53:40 INFO - 1499651620751 Marionette TRACE 374 <- [1,2,null,{}]
18:53:40 INFO - 1499651620752 Marionette DEBUG Closed connection 374
18:53:40 INFO - 1499651620753 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49801
18:53:40 INFO - 1499651620754 Marionette DEBUG Closed connection 375
18:53:40 INFO - 1499651620754 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49802
18:53:40 INFO - 1499651620755 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:40 INFO - 1499651620755 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:40 INFO - 1499651620757 Marionette DEBUG Register listener.js for window 4294967297
18:53:40 INFO - 1499651620760 Marionette TRACE 376 <- [1,1,null,{"sessionId":"be1eb4e6-09bc-409a-9fd8-db37b397338e","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:40 INFO - 1499651620762 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
18:53:40 INFO - 1499651620764 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620766 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620766 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:40 INFO - 1499651620777 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:53:40 INFO - 1499651620780 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:53:40 INFO - 1499651620784 Marionette TRACE 376 <- [1,2,null,{}]
18:53:40 INFO - 1499651620787 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:53:40 INFO - 1499651620790 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"68047d85-ef81-408e-8f55-6dfad1bf36fa","ELEMENT":"68047d85-ef81-408e-8f55-6dfad1bf36fa"}}]
18:53:40 INFO - 1499651620791 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"68047d85-ef81-408e-8f55-6dfad1bf36fa"}]
18:53:41 INFO - 1499651621030 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:41 INFO - 1499651621031 Marionette TRACE 376 <- [1,4,null,{}]
18:53:41 INFO - 1499651621033 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
18:53:41 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499651621035 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:53:41 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 318ms
18:53:41 INFO - 1499651621036 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:53:41 INFO - 1499651621037 Marionette TRACE 376 <- [1,6,null,{}]
18:53:41 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:53:41 INFO - 1499651621039 Marionette DEBUG Closed connection 376
18:53:41 INFO - 1499651621041 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49803
18:53:41 INFO - 1499651621041 Marionette DEBUG Closed connection 377
18:53:41 INFO - 1499651621042 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49804
18:53:41 INFO - 1499651621043 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:41 INFO - 1499651621044 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621046 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621048 Marionette TRACE 378 <- [1,1,null,{"sessionId":"65588f0a-ed52-4870-a519-7ceb3fc80a2a","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621049 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:53:41 INFO - 1499651621050 Marionette TRACE 378 <- [1,2,null,{}]
18:53:41 INFO - 1499651621051 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:41 INFO - 1499651621051 Marionette TRACE 378 <- [1,3,null,{}]
18:53:41 INFO - 1499651621052 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:41 INFO - 1499651621053 Marionette TRACE 378 <- [1,4,null,{}]
18:53:41 INFO - 1499651621054 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
18:53:41 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499651621056 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:53:41 INFO - 1499651621057 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:53:41 INFO - 1499651621058 Marionette TRACE 378 <- [1,6,null,{}]
18:53:41 INFO - 1499651621060 Marionette DEBUG Closed connection 378
18:53:41 INFO - 1499651621060 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49805
18:53:41 INFO - 1499651621061 Marionette DEBUG Closed connection 379
18:53:41 INFO - 1499651621062 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49806
18:53:41 INFO - 1499651621063 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:41 INFO - 1499651621063 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621065 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621068 Marionette TRACE 380 <- [1,1,null,{"sessionId":"38918e99-7827-4e1d-beb1-8790a4381312","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621069 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:53:41 INFO - 1499651621070 Marionette TRACE 380 <- [1,2,null,{}]
18:53:41 INFO - 1499651621071 Marionette DEBUG Closed connection 380
18:53:41 INFO - 1499651621072 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49807
18:53:41 INFO - 1499651621072 Marionette DEBUG Closed connection 381
18:53:41 INFO - 1499651621073 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49808
18:53:41 INFO - 1499651621074 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:41 INFO - 1499651621074 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621076 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621086 Marionette TRACE 382 <- [1,1,null,{"sessionId":"e8f6c2db-17dc-41a1-8af5-cc6470ad7bb4","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621087 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:53:41 INFO - 1499651621089 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:53:41 INFO - 1499651621091 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:53:41 INFO - 1499651621091 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
18:53:41 INFO - 1499651621098 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:41 INFO - 1499651621099 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:41 INFO - 1499651621105 Marionette TRACE 382 <- [1,2,null,{}]
18:53:41 INFO - 1499651621107 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:53:41 INFO - 1499651621110 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"09261f49-b794-4e91-a235-0c21c42d8fc0","ELEMENT":"09261f49-b794-4e91-a235-0c21c42d8fc0"}}]
18:53:41 INFO - 1499651621110 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"09261f49-b794-4e91-a235-0c21c42d8fc0"}]
18:53:41 INFO - 1499651621346 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:41 INFO - 1499651621347 Marionette TRACE 382 <- [1,4,null,{}]
18:53:41 INFO - 1499651621349 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
18:53:41 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499651621351 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:53:41 INFO - 1499651621352 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:53:41 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
18:53:41 INFO - TEST-START | test_click.py TestClick.test_input_file
18:53:41 INFO - 1499651621354 Marionette TRACE 382 <- [1,6,null,{}]
18:53:41 INFO - 1499651621355 Marionette DEBUG Closed connection 382
18:53:41 INFO - 1499651621356 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49809
18:53:41 INFO - 1499651621356 Marionette DEBUG Closed connection 383
18:53:41 INFO - 1499651621357 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49810
18:53:41 INFO - 1499651621358 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:41 INFO - 1499651621359 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621361 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621364 Marionette TRACE 384 <- [1,1,null,{"sessionId":"72979ee3-4f20-4bf3-8067-2b99901bf4d2","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621365 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:53:41 INFO - 1499651621365 Marionette TRACE 384 <- [1,2,null,{}]
18:53:41 INFO - 1499651621366 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:41 INFO - 1499651621366 Marionette TRACE 384 <- [1,3,null,{}]
18:53:41 INFO - 1499651621367 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:41 INFO - 1499651621367 Marionette TRACE 384 <- [1,4,null,{}]
18:53:41 INFO - 1499651621369 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
18:53:41 INFO - TEST-START: test_click.py TestClick.test_input_file1499651621371 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:53:41 INFO - 1499651621372 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:53:41 INFO - 1499651621373 Marionette TRACE 384 <- [1,6,null,{}]
18:53:41 INFO - 1499651621374 Marionette DEBUG Closed connection 384
18:53:41 INFO - 1499651621375 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49811
18:53:41 INFO - 1499651621376 Marionette DEBUG Closed connection 385
18:53:41 INFO - 1499651621376 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49812
18:53:41 INFO - 1499651621377 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:41 INFO - 1499651621378 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621380 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621383 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e88d69c5-7eb0-4529-8474-b0d39e7a91ea","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621384 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:53:41 INFO - 1499651621385 Marionette TRACE 386 <- [1,2,null,{}]
18:53:41 INFO - 1499651621387 Marionette DEBUG Closed connection 386
18:53:41 INFO - 1499651621387 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49813
18:53:41 INFO - 1499651621388 Marionette DEBUG Closed connection 387
18:53:41 INFO - 1499651621388 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49814
18:53:41 INFO - 1499651621389 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:41 INFO - 1499651621390 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621393 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621395 Marionette TRACE 388 <- [1,1,null,{"sessionId":"863f9090-6a0f-4fd0-978b-e49b09286fb1","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621397 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:53:41 INFO - 1499651621399 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:41 INFO - 1499651621400 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:41 INFO - 1499651621401 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
18:53:41 INFO - 1499651621406 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:41 INFO - 1499651621409 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:41 INFO - 1499651621412 Marionette TRACE 388 <- [1,2,null,{}]
18:53:41 INFO - 1499651621418 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:53:41 INFO - 1499651621420 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9c62a4bc-9ad3-46ff-b17a-cc08627d855c","ELEMENT":"9c62a4bc-9ad3-46ff-b17a-cc08627d855c"}}]
18:53:41 INFO - 1499651621421 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"9c62a4bc-9ad3-46ff-b17a-cc08627d855c"}]
18:53:41 INFO - 1499651621452 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
18:53:41 INFO - 1499651621454 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
18:53:41 INFO - TEST-END: test_click.py TestClick.test_input_file1499651621456 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:53:41 INFO - 1499651621457 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:53:41 INFO - 1499651621458 Marionette TRACE 388 <- [1,6,null,{}]
18:53:41 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 104ms
18:53:41 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:53:41 INFO - 1499651621459 Marionette DEBUG Closed connection 388
18:53:41 INFO - 1499651621460 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49815
18:53:41 INFO - 1499651621461 Marionette DEBUG Closed connection 389
18:53:41 INFO - 1499651621462 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49816
18:53:41 INFO - 1499651621463 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:41 INFO - 1499651621463 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621465 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621468 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7e66aa99-e1fc-4827-987f-78dcb425f366","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621469 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:53:41 INFO - 1499651621469 Marionette TRACE 390 <- [1,2,null,{}]
18:53:41 INFO - 1499651621470 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:41 INFO - 1499651621471 Marionette TRACE 390 <- [1,3,null,{}]
18:53:41 INFO - 1499651621471 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:41 INFO - 1499651621472 Marionette TRACE 390 <- [1,4,null,{}]
18:53:41 INFO - 1499651621473 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
18:53:41 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499651621475 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:53:41 INFO - 1499651621476 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:53:41 INFO - 1499651621477 Marionette TRACE 390 <- [1,6,null,{}]
18:53:41 INFO - 1499651621479 Marionette DEBUG Closed connection 390
18:53:41 INFO - 1499651621480 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49817
18:53:41 INFO - 1499651621480 Marionette DEBUG Closed connection 391
18:53:41 INFO - 1499651621481 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49818
18:53:41 INFO - 1499651621482 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:41 INFO - 1499651621483 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621485 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621488 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ced5dc7e-afb4-4510-a91a-40b18de9b47d","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621490 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:53:41 INFO - 1499651621491 Marionette TRACE 392 <- [1,2,null,{}]
18:53:41 INFO - 1499651621492 Marionette DEBUG Closed connection 392
18:53:41 INFO - 1499651621492 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49819
18:53:41 INFO - 1499651621493 Marionette DEBUG Closed connection 393
18:53:41 INFO - 1499651621494 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49820
18:53:41 INFO - 1499651621495 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:41 INFO - 1499651621495 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621497 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621500 Marionette TRACE 394 <- [1,1,null,{"sessionId":"d78fa532-ed2e-4a46-9daa-fa0883f376d8","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621501 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
18:53:41 INFO - 1499651621502 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:41 INFO - 1499651621504 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:41 INFO - 1499651621505 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:41 INFO - 1499651621510 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:53:41 INFO - 1499651621512 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:53:41 INFO - 1499651621518 Marionette TRACE 394 <- [1,2,null,{}]
18:53:41 INFO - 1499651621520 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:53:41 INFO - 1499651621522 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a528b4b8-4315-45bc-b73f-0e601bb54f80","ELEMENT":"a528b4b8-4315-45bc-b73f-0e601bb54f80"}}]
18:53:41 INFO - 1499651621523 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:53:41 INFO - 1499651621525 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6314f3f8-dc4b-40ca-ae8a-95e3890b4851","ELEMENT":"6314f3f8-dc4b-40ca-ae8a-95e3890b4851"}}]
18:53:41 INFO - 1499651621526 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a528b4b8-4315-45bc-b73f-0e601bb54f80"}]
18:53:41 INFO - 1499651621761 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:41 INFO - 1499651621761 Marionette TRACE 394 <- [1,5,null,{}]
18:53:41 INFO - 1499651621762 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"6314f3f8-dc4b-40ca-ae8a-95e3890b4851"}]
18:53:41 INFO - 1499651621776 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
18:53:41 INFO - 1499651621777 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:53:41 INFO - 1499651621782 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:53:41 INFO - 1499651621783 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
18:53:41 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499651621785 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:53:41 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 328ms
18:53:41 INFO - 1499651621786 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:53:41 INFO - 1499651621787 Marionette TRACE 394 <- [1,9,null,{}]
18:53:41 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:53:41 INFO - 1499651621789 Marionette DEBUG Closed connection 394
18:53:41 INFO - 1499651621790 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49821
18:53:41 INFO - 1499651621791 Marionette DEBUG Closed connection 395
18:53:41 INFO - 1499651621791 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49822
18:53:41 INFO - 1499651621792 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:41 INFO - 1499651621793 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621795 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621798 Marionette TRACE 396 <- [1,1,null,{"sessionId":"848b2f81-0757-4f41-a66b-8b88b3799519","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621799 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:53:41 INFO - 1499651621799 Marionette TRACE 396 <- [1,2,null,{}]
18:53:41 INFO - 1499651621800 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:41 INFO - 1499651621800 Marionette TRACE 396 <- [1,3,null,{}]
18:53:41 INFO - 1499651621801 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:41 INFO - 1499651621801 Marionette TRACE 396 <- [1,4,null,{}]
18:53:41 INFO - 1499651621802 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
18:53:41 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499651621804 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:53:41 INFO - 1499651621805 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:53:41 INFO - 1499651621806 Marionette TRACE 396 <- [1,6,null,{}]
18:53:41 INFO - 1499651621808 Marionette DEBUG Closed connection 396
18:53:41 INFO - 1499651621808 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49823
18:53:41 INFO - 1499651621809 Marionette DEBUG Closed connection 397
18:53:41 INFO - 1499651621810 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49824
18:53:41 INFO - 1499651621811 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:41 INFO - 1499651621811 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621813 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621816 Marionette TRACE 398 <- [1,1,null,{"sessionId":"959e7301-0cf3-4696-aee0-bd5ca2b26773","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621817 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:53:41 INFO - 1499651621818 Marionette TRACE 398 <- [1,2,null,{}]
18:53:41 INFO - 1499651621819 Marionette DEBUG Closed connection 398
18:53:41 INFO - 1499651621820 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49825
18:53:41 INFO - 1499651621821 Marionette DEBUG Closed connection 399
18:53:41 INFO - 1499651621822 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49826
18:53:41 INFO - 1499651621823 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:41 INFO - 1499651621823 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}
18:53:41 INFO - 1499651621825 Marionette DEBUG Register listener.js for window 4294967297
18:53:41 INFO - 1499651621827 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a87af41d-9fcc-41ae-99fc-e19c5cdd19ff","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-062.001\\AppData\\Local\\Temp\\tmpogpuwj.mozrunner","moz:accessibilityChecks":false}}]
18:53:41 INFO - 1499651621828 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
18:53:41 INFO - 1499651621830 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:53:41 INFO - 1499651621832 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:53:41 INFO - 1499651621832 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
18:53:41 INFO - 1499651621839 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
18:53:41 INFO - 1499651621841 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
18:53:41 INFO - 1499651621843 Marionette TRACE 400 <- [1,2,null,{}]
18:53:41 INFO - 1499651621849 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:53:41 INFO - 1499651621851 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f3ac1d13-283b-4521-b2cb-b2092cba79e6","ELEMENT":"f3ac1d13-283b-4521-b2cb-b2092cba79e6"}}]
18:53:41 INFO - 1499651621852 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f3ac1d13-283b-4521-b2cb-b2092cba79e6"}]
18:53:41 INFO - 1499651621879 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:53:41 INFO - 1499651621880 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f3ac1d13-283b-4521-b2cb-b2092cba79e6"}]
18:53:41 INFO - 1499651621894 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element