could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
22:18:19 INFO - 1500095898998 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}]
22:18:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500095899001 Marionette TRACE 352 <- [1,5,null,{"value":null}]
22:18:19 INFO - 1500095899002 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
22:18:19 INFO - 1500095899003 Marionette TRACE 352 <- [1,6,null,{}]
22:18:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 153ms
22:18:19 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
22:18:19 INFO - 1500095899005 Marionette DEBUG Closed connection 352
22:18:19 INFO - 1500095899006 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49836
22:18:19 INFO - 1500095899007 Marionette DEBUG Closed connection 353
22:18:19 INFO - 1500095899008 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49837
22:18:19 INFO - 1500095899009 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:19 INFO - 1500095899009 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899012 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899015 Marionette TRACE 354 <- [1,1,null,{"sessionId":"6a86381b-76bc-421c-bb7c-b74790965fc1","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899017 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
22:18:19 INFO - 1500095899018 Marionette TRACE 354 <- [1,2,null,{}]
22:18:19 INFO - 1500095899019 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:18:19 INFO - 1500095899020 Marionette TRACE 354 <- [1,3,null,{}]
22:18:19 INFO - 1500095899021 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
22:18:19 INFO - 1500095899021 Marionette TRACE 354 <- [1,4,null,{}]
22:18:19 INFO - 1500095899023 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}]
22:18:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500095899025 Marionette TRACE 354 <- [1,5,null,{"value":null}]
22:18:19 INFO - 1500095899026 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
22:18:19 INFO - 1500095899028 Marionette TRACE 354 <- [1,6,null,{}]
22:18:19 INFO - 1500095899030 Marionette DEBUG Closed connection 354
22:18:19 INFO - 1500095899031 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49838
22:18:19 INFO - 1500095899032 Marionette DEBUG Closed connection 355
22:18:19 INFO - 1500095899032 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49839
22:18:19 INFO - 1500095899033 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:19 INFO - 1500095899034 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899037 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899040 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9b5d67ea-854a-487a-a3a7-df477f1e64f8","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899042 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
22:18:19 INFO - 1500095899043 Marionette TRACE 356 <- [1,2,null,{}]
22:18:19 INFO - 1500095899045 Marionette DEBUG Closed connection 356
22:18:19 INFO - 1500095899046 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49840
22:18:19 INFO - 1500095899047 Marionette DEBUG Closed connection 357
22:18:19 INFO - 1500095899048 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49841
22:18:19 INFO - 1500095899049 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:18:19 INFO - 1500095899050 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899053 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899056 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3fe38b53-ed4f-463f-8999-26dbc5a746f2","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899058 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49404/clicks.html"}]
22:18:19 INFO - 1500095899060 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49404/hidden.html"
22:18:19 INFO - 1500095899065 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49404/hidden.html"
22:18:19 INFO - 1500095899065 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49404/hidden.html"
22:18:19 INFO - 1500095899083 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49404/clicks.html"
22:18:19 INFO - 1500095899091 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49404/clicks.html"
22:18:19 INFO - 1500095899101 Marionette TRACE 358 <- [1,2,null,{}]
22:18:19 INFO - 1500095899102 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
22:18:19 INFO - 1500095899106 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e797d8c4-d3ea-4969-99b0-1c9127e2b360","ELEMENT":"e797d8c4-d3ea-4969-99b0-1c9127e2b360"}}]
22:18:19 INFO - 1500095899107 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"e797d8c4-d3ea-4969-99b0-1c9127e2b360"}]
22:18:19 INFO - 1500095899157 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49404/clicks.html"
22:18:19 INFO - 1500095899162 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49404/clicks.html"
22:18:19 INFO - 1500095899163 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49404/clicks.html"
22:18:19 INFO - 1500095899185 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49404/test.html"
22:18:19 INFO - 1500095899186 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49404/test.html"
22:18:19 INFO - 1500095899194 Marionette TRACE 358 <- [1,4,null,{}]
22:18:19 INFO - 1500095899196 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
22:18:19 INFO - 1500095899198 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ddc11abb-6739-4746-9540-ac7dce7f07bf","ELEMENT":"ddc11abb-6739-4746-9540-ac7dce7f07bf"}}]
22:18:19 INFO - 1500095899199 Marionette TRACE 358 -> [0,6,"getTitle",{}]
22:18:19 INFO - 1500095899200 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
22:18:19 INFO - 1500095899201 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}]
22:18:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500095899205 Marionette TRACE 358 <- [1,7,null,{"value":null}]
22:18:19 INFO - 1500095899206 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
22:18:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 203ms
22:18:19 INFO - 1500095899207 Marionette TRACE 358 <- [1,8,null,{}]
22:18:19 INFO - TEST-START | test_click.py TestClick.test_container_element
22:18:19 INFO - 1500095899209 Marionette DEBUG Closed connection 358
22:18:19 INFO - 1500095899210 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49844
22:18:19 INFO - 1500095899211 Marionette DEBUG Closed connection 359
22:18:19 INFO - 1500095899212 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49845
22:18:19 INFO - 1500095899213 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:19 INFO - 1500095899213 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899216 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899219 Marionette TRACE 360 <- [1,1,null,{"sessionId":"6694cb2c-3829-433a-8ecd-b5aaba23e46f","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899222 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
22:18:19 INFO - 1500095899223 Marionette TRACE 360 <- [1,2,null,{}]
22:18:19 INFO - 1500095899224 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:18:19 INFO - 1500095899224 Marionette TRACE 360 <- [1,3,null,{}]
22:18:19 INFO - 1500095899225 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
22:18:19 INFO - 1500095899226 Marionette TRACE 360 <- [1,4,null,{}]
22:18:19 INFO - 1500095899227 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}]
22:18:19 INFO - TEST-START: test_click.py TestClick.test_container_element1500095899230 Marionette TRACE 360 <- [1,5,null,{"value":null}]
22:18:19 INFO - 1500095899231 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
22:18:19 INFO - 1500095899232 Marionette TRACE 360 <- [1,6,null,{}]
22:18:19 INFO - 1500095899234 Marionette DEBUG Closed connection 360
22:18:19 INFO - 1500095899235 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49846
22:18:19 INFO - 1500095899236 Marionette DEBUG Closed connection 361
22:18:19 INFO - 1500095899236 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49847
22:18:19 INFO - 1500095899239 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:19 INFO - 1500095899239 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899242 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899246 Marionette TRACE 362 <- [1,1,null,{"sessionId":"edbd1395-5788-4ffc-b95c-69ec1dc484a7","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899248 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
22:18:19 INFO - 1500095899249 Marionette TRACE 362 <- [1,2,null,{}]
22:18:19 INFO - 1500095899252 Marionette DEBUG Closed connection 362
22:18:19 INFO - 1500095899253 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49848
22:18:19 INFO - 1500095899255 Marionette DEBUG Closed connection 363
22:18:19 INFO - 1500095899256 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49849
22:18:19 INFO - 1500095899257 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:18:19 INFO - 1500095899258 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899261 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899264 Marionette TRACE 364 <- [1,1,null,{"sessionId":"7447d8ee-bfa7-4382-abb7-e724a36b0c7d","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899267 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"}]
22:18:19 INFO - 1500095899268 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49404/test.html"
22:18:19 INFO - 1500095899271 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49404/test.html"
22:18:19 INFO - 1500095899271 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49404/test.html"
22:18:19 INFO - 1500095899294 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"
22:18:19 INFO - 1500095899298 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"
22:18:19 INFO - 1500095899302 Marionette TRACE 364 <- [1,2,null,{}]
22:18:19 INFO - 1500095899303 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:18:19 INFO - 1500095899311 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f960acb-3612-4d19-b602-ae5f0f8d0064","ELEMENT":"3f960acb-3612-4d19-b602-ae5f0f8d0064"}}]
22:18:19 INFO - 1500095899313 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"3f960acb-3612-4d19-b602-ae5f0f8d0064"}]
22:18:19 INFO - 1500095899556 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:18:19 INFO - 1500095899556 Marionette TRACE 364 <- [1,4,null,{}]
22:18:19 INFO - 1500095899558 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"3f960acb-3612-4d19-b602-ae5f0f8d0064","name":"selected"}]
22:18:19 INFO - 1500095899562 Marionette TRACE 364 <- [1,5,null,{"value":true}]
22:18:19 INFO - 1500095899564 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}]
22:18:19 INFO - TEST-END: test_click.py TestClick.test_container_element1500095899566 Marionette TRACE 364 <- [1,6,null,{"value":null}]
22:18:19 INFO - 1500095899567 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
22:18:19 INFO - 1500095899569 Marionette TRACE 364 <- [1,7,null,{}]
22:18:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 361ms
22:18:19 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
22:18:19 INFO - 1500095899571 Marionette DEBUG Closed connection 364
22:18:19 INFO - 1500095899572 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49850
22:18:19 INFO - 1500095899573 Marionette DEBUG Closed connection 365
22:18:19 INFO - 1500095899573 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49851
22:18:19 INFO - 1500095899574 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:19 INFO - 1500095899575 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899578 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899581 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9da6f850-af45-4327-b817-9e0540c38d90","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899583 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
22:18:19 INFO - 1500095899583 Marionette TRACE 366 <- [1,2,null,{}]
22:18:19 INFO - 1500095899584 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:18:19 INFO - 1500095899585 Marionette TRACE 366 <- [1,3,null,{}]
22:18:19 INFO - 1500095899586 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
22:18:19 INFO - 1500095899587 Marionette TRACE 366 <- [1,4,null,{}]
22:18:19 INFO - 1500095899589 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}]
22:18:19 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500095899592 Marionette TRACE 366 <- [1,5,null,{"value":null}]
22:18:19 INFO - 1500095899592 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
22:18:19 INFO - 1500095899594 Marionette TRACE 366 <- [1,6,null,{}]
22:18:19 INFO - 1500095899596 Marionette DEBUG Closed connection 366
22:18:19 INFO - 1500095899597 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49852
22:18:19 INFO - 1500095899597 Marionette DEBUG Closed connection 367
22:18:19 INFO - 1500095899598 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49853
22:18:19 INFO - 1500095899599 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:19 INFO - 1500095899600 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899603 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899614 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f2a117b2-f2d6-459f-8204-5483dc9d144f","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899616 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
22:18:19 INFO - 1500095899617 Marionette TRACE 368 <- [1,2,null,{}]
22:18:19 INFO - 1500095899619 Marionette DEBUG Closed connection 368
22:18:19 INFO - 1500095899620 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49854
22:18:19 INFO - 1500095899621 Marionette DEBUG Closed connection 369
22:18:19 INFO - 1500095899621 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49855
22:18:19 INFO - 1500095899622 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:18:19 INFO - 1500095899623 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899626 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899628 Marionette TRACE 370 <- [1,1,null,{"sessionId":"139a7e4d-1985-40f2-87a4-25ab72b098d4","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899630 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"}]
22:18:19 INFO - 1500095899633 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"
22:18:19 INFO - 1500095899635 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"
22:18:19 INFO - 1500095899635 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"
22:18:19 INFO - 1500095899653 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"
22:18:19 INFO - 1500095899659 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"
22:18:19 INFO - 1500095899663 Marionette TRACE 370 <- [1,2,null,{}]
22:18:19 INFO - 1500095899664 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:18:19 INFO - 1500095899666 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9b50b4ce-e011-437e-b5f8-f876ffb87a72","ELEMENT":"9b50b4ce-e011-437e-b5f8-f876ffb87a72"}}]
22:18:19 INFO - 1500095899667 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9b50b4ce-e011-437e-b5f8-f876ffb87a72"}]
22:18:19 INFO - 1500095899923 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:18:19 INFO - 1500095899924 Marionette TRACE 370 <- [1,4,null,{}]
22:18:19 INFO - 1500095899925 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9b50b4ce-e011-437e-b5f8-f876ffb87a72","name":"selected"}]
22:18:19 INFO - 1500095899929 Marionette TRACE 370 <- [1,5,null,{"value":true}]
22:18:19 INFO - 1500095899931 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}]
22:18:19 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500095899934 Marionette TRACE 370 <- [1,6,null,{"value":null}]
22:18:19 INFO - 1500095899935 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
22:18:19 INFO - 1500095899936 Marionette TRACE 370 <- [1,7,null,{}]
22:18:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
22:18:19 INFO - TEST-START | test_click.py TestClick.test_css_transforms
22:18:19 INFO - 1500095899938 Marionette DEBUG Closed connection 370
22:18:19 INFO - 1500095899939 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49856
22:18:19 INFO - 1500095899940 Marionette DEBUG Closed connection 371
22:18:19 INFO - 1500095899940 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49857
22:18:19 INFO - 1500095899941 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:19 INFO - 1500095899942 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:19 INFO - 1500095899944 Marionette DEBUG Register listener.js for window 15
22:18:19 INFO - 1500095899947 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c5db39a7-cfaa-413c-92ae-3a5a52cd8256","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:19 INFO - 1500095899949 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
22:18:19 INFO - 1500095899950 Marionette TRACE 372 <- [1,2,null,{}]
22:18:19 INFO - 1500095899951 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:18:19 INFO - 1500095899951 Marionette TRACE 372 <- [1,3,null,{}]
22:18:19 INFO - 1500095899952 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
22:18:19 INFO - 1500095899953 Marionette TRACE 372 <- [1,4,null,{}]
22:18:19 INFO - 1500095899954 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}]
22:18:19 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500095899957 Marionette TRACE 372 <- [1,5,null,{"value":null}]
22:18:19 INFO - 1500095899958 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
22:18:19 INFO - 1500095899959 Marionette TRACE 372 <- [1,6,null,{}]
22:18:19 INFO - 1500095899961 Marionette DEBUG Closed connection 372
22:18:19 INFO - 1500095899962 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49858
22:18:19 INFO - 1500095899963 Marionette DEBUG Closed connection 373
22:18:20 INFO - 1500095899963 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49859
22:18:20 INFO - 1500095899965 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:20 INFO - 1500095899965 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095899968 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095899971 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a3191fe1-7626-44b0-81af-7f1453595c8a","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095899973 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
22:18:20 INFO - 1500095899974 Marionette TRACE 374 <- [1,2,null,{}]
22:18:20 INFO - 1500095899976 Marionette DEBUG Closed connection 374
22:18:20 INFO - 1500095899977 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49860
22:18:20 INFO - 1500095899978 Marionette DEBUG Closed connection 375
22:18:20 INFO - 1500095899978 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49861
22:18:20 INFO - 1500095899979 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:18:20 INFO - 1500095899980 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095899983 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095899986 Marionette TRACE 376 <- [1,1,null,{"sessionId":"83de30cc-10b5-4006-891c-2d69dc4ba1df","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095899989 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"}]
22:18:20 INFO - 1500095899991 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"
22:18:20 INFO - 1500095899993 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"
22:18:20 INFO - 1500095899994 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"
22:18:20 INFO - 1500095900014 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"
22:18:20 INFO - 1500095900017 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"
22:18:20 INFO - 1500095900021 Marionette TRACE 376 <- [1,2,null,{}]
22:18:20 INFO - 1500095900028 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
22:18:20 INFO - 1500095900031 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"010294a7-8b4c-4379-a69f-1e5e903f1e46","ELEMENT":"010294a7-8b4c-4379-a69f-1e5e903f1e46"}}]
22:18:20 INFO - 1500095900032 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"010294a7-8b4c-4379-a69f-1e5e903f1e46"}]
22:18:20 INFO - 1500095900282 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:18:20 INFO - 1500095900283 Marionette TRACE 376 <- [1,4,null,{}]
22:18:20 INFO - 1500095900284 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}]
22:18:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500095900287 Marionette TRACE 376 <- [1,5,null,{"value":null}]
22:18:20 INFO - 1500095900288 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
22:18:20 INFO - 1500095900289 Marionette TRACE 376 <- [1,6,null,{}]
22:18:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 352ms
22:18:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
22:18:20 INFO - 1500095900292 Marionette DEBUG Closed connection 376
22:18:20 INFO - 1500095900292 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49862
22:18:20 INFO - 1500095900293 Marionette DEBUG Closed connection 377
22:18:20 INFO - 1500095900293 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49863
22:18:20 INFO - 1500095900294 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:20 INFO - 1500095900295 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900298 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900301 Marionette TRACE 378 <- [1,1,null,{"sessionId":"7de285e7-cfbe-443c-b0f4-6f1ab2fae752","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900303 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
22:18:20 INFO - 1500095900303 Marionette TRACE 378 <- [1,2,null,{}]
22:18:20 INFO - 1500095900304 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:18:20 INFO - 1500095900305 Marionette TRACE 378 <- [1,3,null,{}]
22:18:20 INFO - 1500095900305 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
22:18:20 INFO - 1500095900306 Marionette TRACE 378 <- [1,4,null,{}]
22:18:20 INFO - 1500095900307 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}]
22:18:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500095900310 Marionette TRACE 378 <- [1,5,null,{"value":null}]
22:18:20 INFO - 1500095900311 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
22:18:20 INFO - 1500095900312 Marionette TRACE 378 <- [1,6,null,{}]
22:18:20 INFO - 1500095900315 Marionette DEBUG Closed connection 378
22:18:20 INFO - 1500095900315 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49864
22:18:20 INFO - 1500095900316 Marionette DEBUG Closed connection 379
22:18:20 INFO - 1500095900317 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49865
22:18:20 INFO - 1500095900318 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:20 INFO - 1500095900318 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900321 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900324 Marionette TRACE 380 <- [1,1,null,{"sessionId":"bf068f31-54f9-4160-88c8-e443b2749433","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900326 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
22:18:20 INFO - 1500095900327 Marionette TRACE 380 <- [1,2,null,{}]
22:18:20 INFO - 1500095900329 Marionette DEBUG Closed connection 380
22:18:20 INFO - 1500095900330 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49866
22:18:20 INFO - 1500095900331 Marionette DEBUG Closed connection 381
22:18:20 INFO - 1500095900331 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49867
22:18:20 INFO - 1500095900332 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:18:20 INFO - 1500095900333 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900336 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900339 Marionette TRACE 382 <- [1,1,null,{"sessionId":"eb1b0d0e-e939-4572-b3f6-428f499f03f2","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900341 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"}]
22:18:20 INFO - 1500095900343 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"
22:18:20 INFO - 1500095900345 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"
22:18:20 INFO - 1500095900345 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"
22:18:20 INFO - 1500095900364 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"
22:18:20 INFO - 1500095900367 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"
22:18:20 INFO - 1500095900370 Marionette TRACE 382 <- [1,2,null,{}]
22:18:20 INFO - 1500095900377 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
22:18:20 INFO - 1500095900380 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"849b86c8-17bb-4a4f-a7e0-5c084005e9e4","ELEMENT":"849b86c8-17bb-4a4f-a7e0-5c084005e9e4"}}]
22:18:20 INFO - 1500095900381 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"849b86c8-17bb-4a4f-a7e0-5c084005e9e4"}]
22:18:20 INFO - 1500095900626 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:18:20 INFO - 1500095900627 Marionette TRACE 382 <- [1,4,null,{}]
22:18:20 INFO - 1500095900630 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}]
22:18:20 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500095900632 Marionette TRACE 382 <- [1,5,null,{"value":null}]
22:18:20 INFO - 1500095900633 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
22:18:20 INFO - 1500095900634 Marionette TRACE 382 <- [1,6,null,{}]
22:18:20 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 345ms
22:18:20 INFO - TEST-START | test_click.py TestClick.test_input_file
22:18:20 INFO - 1500095900637 Marionette DEBUG Closed connection 382
22:18:20 INFO - 1500095900637 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49868
22:18:20 INFO - 1500095900638 Marionette DEBUG Closed connection 383
22:18:20 INFO - 1500095900639 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49869
22:18:20 INFO - 1500095900640 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:20 INFO - 1500095900641 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900643 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900646 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b08e4f7c-7194-4e3e-a8cc-5f259628244f","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900648 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
22:18:20 INFO - 1500095900649 Marionette TRACE 384 <- [1,2,null,{}]
22:18:20 INFO - 1500095900649 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:18:20 INFO - 1500095900650 Marionette TRACE 384 <- [1,3,null,{}]
22:18:20 INFO - 1500095900651 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
22:18:20 INFO - 1500095900651 Marionette TRACE 384 <- [1,4,null,{}]
22:18:20 INFO - 1500095900653 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}]
22:18:20 INFO - TEST-START: test_click.py TestClick.test_input_file1500095900655 Marionette TRACE 384 <- [1,5,null,{"value":null}]
22:18:20 INFO - 1500095900657 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
22:18:20 INFO - 1500095900658 Marionette TRACE 384 <- [1,6,null,{}]
22:18:20 INFO - 1500095900660 Marionette DEBUG Closed connection 384
22:18:20 INFO - 1500095900661 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49870
22:18:20 INFO - 1500095900662 Marionette DEBUG Closed connection 385
22:18:20 INFO - 1500095900662 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49871
22:18:20 INFO - 1500095900663 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:20 INFO - 1500095900664 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900667 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900670 Marionette TRACE 386 <- [1,1,null,{"sessionId":"0d519995-c6fe-4537-98d5-7662a370b2ba","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900672 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
22:18:20 INFO - 1500095900673 Marionette TRACE 386 <- [1,2,null,{}]
22:18:20 INFO - 1500095900675 Marionette DEBUG Closed connection 386
22:18:20 INFO - 1500095900676 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49872
22:18:20 INFO - 1500095900677 Marionette DEBUG Closed connection 387
22:18:20 INFO - 1500095900678 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49873
22:18:20 INFO - 1500095900679 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:18:20 INFO - 1500095900679 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900688 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900692 Marionette TRACE 388 <- [1,1,null,{"sessionId":"798dde54-6ff7-483d-9e6e-437defdd541d","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900694 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
22:18:20 INFO - 1500095900696 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"
22:18:20 INFO - 1500095900698 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"
22:18:20 INFO - 1500095900698 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"
22:18:20 INFO - 1500095900717 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:18:20 INFO - 1500095900720 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:18:20 INFO - 1500095900728 Marionette TRACE 388 <- [1,2,null,{}]
22:18:20 INFO - 1500095900730 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
22:18:20 INFO - 1500095900732 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe96296e-7c66-4dd1-bbee-53f4515fc1db","ELEMENT":"fe96296e-7c66-4dd1-bbee-53f4515fc1db"}}]
22:18:20 INFO - 1500095900733 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"fe96296e-7c66-4dd1-bbee-53f4515fc1db"}]
22:18:20 INFO - 1500095900762 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
22:18:20 INFO - 1500095900764 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}]
22:18:20 INFO - TEST-END: test_click.py TestClick.test_input_file1500095900766 Marionette TRACE 388 <- [1,5,null,{"value":null}]
22:18:20 INFO - 1500095900767 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
22:18:20 INFO - 1500095900769 Marionette TRACE 388 <- [1,6,null,{}]
22:18:20 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 133ms
22:18:20 INFO - TEST-START | test_click.py TestClick.test_obscured_element
22:18:20 INFO - 1500095900771 Marionette DEBUG Closed connection 388
22:18:20 INFO - 1500095900771 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49874
22:18:20 INFO - 1500095900773 Marionette DEBUG Closed connection 389
22:18:20 INFO - 1500095900773 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49875
22:18:20 INFO - 1500095900774 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:20 INFO - 1500095900775 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900777 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900780 Marionette TRACE 390 <- [1,1,null,{"sessionId":"82fcf26f-e33a-408e-b8d5-172d6b8ab94a","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900783 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
22:18:20 INFO - 1500095900783 Marionette TRACE 390 <- [1,2,null,{}]
22:18:20 INFO - 1500095900784 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:18:20 INFO - 1500095900785 Marionette TRACE 390 <- [1,3,null,{}]
22:18:20 INFO - 1500095900785 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
22:18:20 INFO - 1500095900786 Marionette TRACE 390 <- [1,4,null,{}]
22:18:20 INFO - 1500095900788 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}]
22:18:20 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500095900790 Marionette TRACE 390 <- [1,5,null,{"value":null}]
22:18:20 INFO - 1500095900791 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
22:18:20 INFO - 1500095900793 Marionette TRACE 390 <- [1,6,null,{}]
22:18:20 INFO - 1500095900795 Marionette DEBUG Closed connection 390
22:18:20 INFO - 1500095900795 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49876
22:18:20 INFO - 1500095900796 Marionette DEBUG Closed connection 391
22:18:20 INFO - 1500095900797 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49877
22:18:20 INFO - 1500095900798 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:20 INFO - 1500095900798 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900801 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900804 Marionette TRACE 392 <- [1,1,null,{"sessionId":"5da38792-f0d9-467f-ab29-219a105fe01e","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900806 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
22:18:20 INFO - 1500095900807 Marionette TRACE 392 <- [1,2,null,{}]
22:18:20 INFO - 1500095900809 Marionette DEBUG Closed connection 392
22:18:20 INFO - 1500095900810 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49878
22:18:20 INFO - 1500095900810 Marionette DEBUG Closed connection 393
22:18:20 INFO - 1500095900811 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49879
22:18:20 INFO - 1500095900812 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:18:20 INFO - 1500095900813 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:20 INFO - 1500095900815 Marionette DEBUG Register listener.js for window 15
22:18:20 INFO - 1500095900819 Marionette TRACE 394 <- [1,1,null,{"sessionId":"29e18d0b-3497-4fbd-a3fd-58a4d9a46f5a","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:20 INFO - 1500095900821 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"}]
22:18:20 INFO - 1500095900822 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:18:20 INFO - 1500095900824 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:18:20 INFO - 1500095900825 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:18:20 INFO - 1500095900844 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"
22:18:20 INFO - 1500095900847 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"
22:18:20 INFO - 1500095900850 Marionette TRACE 394 <- [1,2,null,{}]
22:18:20 INFO - 1500095900851 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
22:18:20 INFO - 1500095900859 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ba4df8ac-36a2-46dc-af2f-03e1d257bc20","ELEMENT":"ba4df8ac-36a2-46dc-af2f-03e1d257bc20"}}]
22:18:20 INFO - 1500095900860 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
22:18:20 INFO - 1500095900862 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"46078a60-3529-4ffb-b9d2-dbf25520fe88","ELEMENT":"46078a60-3529-4ffb-b9d2-dbf25520fe88"}}]
22:18:20 INFO - 1500095900863 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"ba4df8ac-36a2-46dc-af2f-03e1d257bc20"}]
22:18:21 INFO - 1500095901173 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:18:21 INFO - 1500095901175 Marionette TRACE 394 <- [1,5,null,{}]
22:18:21 INFO - 1500095901177 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"46078a60-3529-4ffb-b9d2-dbf25520fe88"}]
22:18:21 INFO - 1500095901200 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
22:18:21 INFO - 1500095901202 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
22:18:21 INFO - 1500095901207 Marionette TRACE 394 <- [1,7,null,{"value":false}]
22:18:21 INFO - 1500095901209 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}]
22:18:21 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500095901214 Marionette TRACE 394 <- [1,8,null,{"value":null}]
22:18:21 INFO - 1500095901215 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
22:18:21 INFO - 1500095901218 Marionette TRACE 394 <- [1,9,null,{}]
22:18:21 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 450ms
22:18:21 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
22:18:21 INFO - 1500095901223 Marionette DEBUG Closed connection 394
22:18:21 INFO - 1500095901224 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49880
22:18:21 INFO - 1500095901234 Marionette DEBUG Closed connection 395
22:18:21 INFO - 1500095901235 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49881
22:18:21 INFO - 1500095901237 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:21 INFO - 1500095901240 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:21 INFO - 1500095901244 Marionette DEBUG Register listener.js for window 15
22:18:21 INFO - 1500095901251 Marionette TRACE 396 <- [1,1,null,{"sessionId":"5e3c0df5-c8dc-4143-9b82-5e4fe163065f","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:21 INFO - 1500095901255 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
22:18:21 INFO - 1500095901256 Marionette TRACE 396 <- [1,2,null,{}]
22:18:21 INFO - 1500095901257 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:18:21 INFO - 1500095901258 Marionette TRACE 396 <- [1,3,null,{}]
22:18:21 INFO - 1500095901259 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
22:18:21 INFO - 1500095901260 Marionette TRACE 396 <- [1,4,null,{}]
22:18:21 INFO - 1500095901262 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}]
22:18:21 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500095901266 Marionette TRACE 396 <- [1,5,null,{"value":null}]
22:18:21 INFO - 1500095901268 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
22:18:21 INFO - 1500095901270 Marionette TRACE 396 <- [1,6,null,{}]
22:18:21 INFO - 1500095901275 Marionette DEBUG Closed connection 396
22:18:21 INFO - 1500095901276 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49882
22:18:21 INFO - 1500095901277 Marionette DEBUG Closed connection 397
22:18:21 INFO - 1500095901278 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49883
22:18:21 INFO - 1500095901281 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:18:21 INFO - 1500095901282 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:21 INFO - 1500095901286 Marionette DEBUG Register listener.js for window 15
22:18:21 INFO - 1500095901291 Marionette TRACE 398 <- [1,1,null,{"sessionId":"545c76c1-f043-4173-974f-9ef73e403a8f","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:21 INFO - 1500095901294 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
22:18:21 INFO - 1500095901296 Marionette TRACE 398 <- [1,2,null,{}]
22:18:21 INFO - 1500095901299 Marionette DEBUG Closed connection 398
22:18:21 INFO - 1500095901299 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49884
22:18:21 INFO - 1500095901300 Marionette DEBUG Closed connection 399
22:18:21 INFO - 1500095901301 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49885
22:18:21 INFO - 1500095901302 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:18:21 INFO - 1500095901303 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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}
22:18:21 INFO - 1500095901306 Marionette DEBUG Register listener.js for window 15
22:18:21 INFO - 1500095901311 Marionette TRACE 400 <- [1,1,null,{"sessionId":"33060389-2dc6-480e-ba65-95800b65b44b","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":3200,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmplur5qm.mozrunner","moz:accessibilityChecks":false}}]
22:18:21 INFO - 1500095901313 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"}]
22:18:21 INFO - 1500095901316 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"
22:18:21 INFO - 1500095901320 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"
22:18:21 INFO - 1500095901321 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"
22:18:21 INFO - 1500095901345 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"
22:18:21 INFO - 1500095901348 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"
22:18:21 INFO - 1500095901354 Marionette TRACE 400 <- [1,2,null,{}]
22:18:21 INFO - 1500095901371 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
22:18:21 INFO - 1500095901375 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3920adba-a197-4349-9fb5-35a775733d7b","ELEMENT":"3920adba-a197-4349-9fb5-35a775733d7b"}}]
22:18:21 INFO - 1500095901379 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"3920adba-a197-4349-9fb5-35a775733d7b"}]
22:18:21 INFO - 1500095901408 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
22:18:21 INFO - 1500095901409 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"3920adba-a197-4349-9fb5-35a775733d7b"}]
22:18:21 INFO - 1500095901431 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element