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]
21:45:41 INFO - 1500439541036 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}]
21:45:41 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500439541038 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:45:41 INFO - 1500439541039 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:45:41 INFO - 1500439541041 Marionette TRACE 352 <- [1,6,null,{}]
21:45:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 123ms
21:45:41 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:45:41 INFO - 1500439541043 Marionette DEBUG Closed connection 352
21:45:41 INFO - 1500439541044 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49751
21:45:41 INFO - 1500439541044 Marionette DEBUG Closed connection 353
21:45:41 INFO - 1500439541045 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49752
21:45:41 INFO - 1500439541046 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:41 INFO - 1500439541047 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541048 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541051 Marionette TRACE 354 <- [1,1,null,{"sessionId":"6669da32-fa7a-4ee4-8e11-6d0d686bdae0","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541053 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:45:41 INFO - 1500439541054 Marionette TRACE 354 <- [1,2,null,{}]
21:45:41 INFO - 1500439541055 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:45:41 INFO - 1500439541055 Marionette TRACE 354 <- [1,3,null,{}]
21:45:41 INFO - 1500439541056 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:45:41 INFO - 1500439541057 Marionette TRACE 354 <- [1,4,null,{}]
21:45:41 INFO - 1500439541058 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}]
21:45:41 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500439541060 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:45:41 INFO - 1500439541061 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:45:41 INFO - 1500439541062 Marionette TRACE 354 <- [1,6,null,{}]
21:45:41 INFO - 1500439541064 Marionette DEBUG Closed connection 354
21:45:41 INFO - 1500439541065 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49753
21:45:41 INFO - 1500439541065 Marionette DEBUG Closed connection 355
21:45:41 INFO - 1500439541066 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49754
21:45:41 INFO - 1500439541067 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:41 INFO - 1500439541067 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541069 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541071 Marionette TRACE 356 <- [1,1,null,{"sessionId":"97cb1a7e-2537-4f99-9838-6d7a151bf029","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541073 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:45:41 INFO - 1500439541074 Marionette TRACE 356 <- [1,2,null,{}]
21:45:41 INFO - 1500439541076 Marionette DEBUG Closed connection 356
21:45:41 INFO - 1500439541077 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49755
21:45:41 INFO - 1500439541078 Marionette DEBUG Closed connection 357
21:45:41 INFO - 1500439541078 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49756
21:45:41 INFO - 1500439541079 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:45:41 INFO - 1500439541080 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541082 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541084 Marionette TRACE 358 <- [1,1,null,{"sessionId":"be0995a4-e18a-409f-9e58-71d5aad060cd","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541086 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49326/clicks.html"}]
21:45:41 INFO - 1500439541087 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/hidden.html"
21:45:41 INFO - 1500439541096 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/hidden.html"
21:45:41 INFO - 1500439541096 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/hidden.html"
21:45:41 INFO - 1500439541107 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/clicks.html"
21:45:41 INFO - 1500439541114 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/clicks.html"
21:45:41 INFO - 1500439541117 Marionette TRACE 358 <- [1,2,null,{}]
21:45:41 INFO - 1500439541119 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:45:41 INFO - 1500439541121 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6ee25631-e9c0-49cf-8229-3ae4923d9a9f","ELEMENT":"6ee25631-e9c0-49cf-8229-3ae4923d9a9f"}}]
21:45:41 INFO - 1500439541121 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"6ee25631-e9c0-49cf-8229-3ae4923d9a9f"}]
21:45:41 INFO - 1500439541168 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/clicks.html"
21:45:41 INFO - 1500439541172 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/clicks.html"
21:45:41 INFO - 1500439541173 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/clicks.html"
21:45:41 INFO - 1500439541190 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/test.html"
21:45:41 INFO - 1500439541193 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/test.html"
21:45:41 INFO - 1500439541200 Marionette TRACE 358 <- [1,4,null,{}]
21:45:41 INFO - 1500439541202 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:45:41 INFO - 1500439541204 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a287668-1a49-4d77-84a4-07d135bb12ff","ELEMENT":"9a287668-1a49-4d77-84a4-07d135bb12ff"}}]
21:45:41 INFO - 1500439541205 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:45:41 INFO - 1500439541205 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:45:41 INFO - 1500439541206 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}]
21:45:41 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500439541209 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:45:41 INFO - 1500439541212 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:45:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 171ms
21:45:41 INFO - 1500439541213 Marionette TRACE 358 <- [1,8,null,{}]
21:45:41 INFO - TEST-START | test_click.py TestClick.test_container_element
21:45:41 INFO - 1500439541215 Marionette DEBUG Closed connection 358
21:45:41 INFO - 1500439541223 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49759
21:45:41 INFO - 1500439541224 Marionette DEBUG Closed connection 359
21:45:41 INFO - 1500439541225 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49760
21:45:41 INFO - 1500439541226 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:41 INFO - 1500439541226 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541229 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541231 Marionette TRACE 360 <- [1,1,null,{"sessionId":"74bf0e61-2438-4e74-91e1-b3d30f21323b","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541238 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:45:41 INFO - 1500439541238 Marionette TRACE 360 <- [1,2,null,{}]
21:45:41 INFO - 1500439541239 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:45:41 INFO - 1500439541240 Marionette TRACE 360 <- [1,3,null,{}]
21:45:41 INFO - 1500439541241 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:45:41 INFO - 1500439541241 Marionette TRACE 360 <- [1,4,null,{}]
21:45:41 INFO - 1500439541242 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}]
21:45:41 INFO - TEST-START: test_click.py TestClick.test_container_element1500439541244 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:45:41 INFO - 1500439541245 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:45:41 INFO - 1500439541246 Marionette TRACE 360 <- [1,6,null,{}]
21:45:41 INFO - 1500439541248 Marionette DEBUG Closed connection 360
21:45:41 INFO - 1500439541249 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49761
21:45:41 INFO - 1500439541249 Marionette DEBUG Closed connection 361
21:45:41 INFO - 1500439541250 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49762
21:45:41 INFO - 1500439541251 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:41 INFO - 1500439541251 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541253 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541256 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c2df3781-f864-47ea-8ba7-bd847b6aaaac","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541258 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:45:41 INFO - 1500439541259 Marionette TRACE 362 <- [1,2,null,{}]
21:45:41 INFO - 1500439541261 Marionette DEBUG Closed connection 362
21:45:41 INFO - 1500439541261 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49763
21:45:41 INFO - 1500439541262 Marionette DEBUG Closed connection 363
21:45:41 INFO - 1500439541262 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49764
21:45:41 INFO - 1500439541263 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:45:41 INFO - 1500439541264 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541266 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541268 Marionette TRACE 364 <- [1,1,null,{"sessionId":"210c6eb6-c16c-4248-b483-3eb662330cb0","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541270 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"}]
21:45:41 INFO - 1500439541271 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/test.html"
21:45:41 INFO - 1500439541273 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/test.html"
21:45:41 INFO - 1500439541273 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/test.html"
21:45:41 INFO - 1500439541294 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"
21:45:41 INFO - 1500439541295 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"
21:45:41 INFO - 1500439541299 Marionette TRACE 364 <- [1,2,null,{}]
21:45:41 INFO - 1500439541300 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:45:41 INFO - 1500439541305 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b5665764-e9c5-4c87-a495-f2d1572afc0a","ELEMENT":"b5665764-e9c5-4c87-a495-f2d1572afc0a"}}]
21:45:41 INFO - 1500439541306 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b5665764-e9c5-4c87-a495-f2d1572afc0a"}]
21:45:41 INFO - 1500439541545 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:45:41 INFO - 1500439541546 Marionette TRACE 364 <- [1,4,null,{}]
21:45:41 INFO - 1500439541547 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b5665764-e9c5-4c87-a495-f2d1572afc0a","name":"selected"}]
21:45:41 INFO - 1500439541551 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:45:41 INFO - 1500439541553 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}]
21:45:41 INFO - TEST-END: test_click.py TestClick.test_container_element1500439541554 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:45:41 INFO - 1500439541555 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:45:41 INFO - 1500439541557 Marionette TRACE 364 <- [1,7,null,{}]
21:45:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 343ms
21:45:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:45:41 INFO - 1500439541558 Marionette DEBUG Closed connection 364
21:45:41 INFO - 1500439541559 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49765
21:45:41 INFO - 1500439541560 Marionette DEBUG Closed connection 365
21:45:41 INFO - 1500439541561 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49766
21:45:41 INFO - 1500439541562 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:41 INFO - 1500439541562 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541564 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541566 Marionette TRACE 366 <- [1,1,null,{"sessionId":"dcfba5e9-ab5e-4d44-be89-e912c034d945","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541568 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:45:41 INFO - 1500439541569 Marionette TRACE 366 <- [1,2,null,{}]
21:45:41 INFO - 1500439541569 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:45:41 INFO - 1500439541570 Marionette TRACE 366 <- [1,3,null,{}]
21:45:41 INFO - 1500439541571 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:45:41 INFO - 1500439541572 Marionette TRACE 366 <- [1,4,null,{}]
21:45:41 INFO - 1500439541573 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}]
21:45:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500439541577 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:45:41 INFO - 1500439541578 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:45:41 INFO - 1500439541579 Marionette TRACE 366 <- [1,6,null,{}]
21:45:41 INFO - 1500439541580 Marionette DEBUG Closed connection 366
21:45:41 INFO - 1500439541581 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49767
21:45:41 INFO - 1500439541581 Marionette DEBUG Closed connection 367
21:45:41 INFO - 1500439541582 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49768
21:45:41 INFO - 1500439541583 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:41 INFO - 1500439541584 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541586 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541596 Marionette TRACE 368 <- [1,1,null,{"sessionId":"22bd3d4a-9e7c-4a4f-8121-237a08b9c796","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541598 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:45:41 INFO - 1500439541599 Marionette TRACE 368 <- [1,2,null,{}]
21:45:41 INFO - 1500439541600 Marionette DEBUG Closed connection 368
21:45:41 INFO - 1500439541601 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49769
21:45:41 INFO - 1500439541601 Marionette DEBUG Closed connection 369
21:45:41 INFO - 1500439541603 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49770
21:45:41 INFO - 1500439541604 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:45:41 INFO - 1500439541605 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541607 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541609 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c310fcb8-fee1-4fbd-9f1e-c2e089fcc4b2","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541611 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"}]
21:45:41 INFO - 1500439541612 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"
21:45:41 INFO - 1500439541614 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"
21:45:41 INFO - 1500439541614 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"
21:45:41 INFO - 1500439541623 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"
21:45:41 INFO - 1500439541631 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"
21:45:41 INFO - 1500439541633 Marionette TRACE 370 <- [1,2,null,{}]
21:45:41 INFO - 1500439541635 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:45:41 INFO - 1500439541637 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"845243e9-00cb-4b39-9ee6-fbf34e403b8c","ELEMENT":"845243e9-00cb-4b39-9ee6-fbf34e403b8c"}}]
21:45:41 INFO - 1500439541638 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"845243e9-00cb-4b39-9ee6-fbf34e403b8c"}]
21:45:41 INFO - 1500439541879 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:45:41 INFO - 1500439541880 Marionette TRACE 370 <- [1,4,null,{}]
21:45:41 INFO - 1500439541882 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"845243e9-00cb-4b39-9ee6-fbf34e403b8c","name":"selected"}]
21:45:41 INFO - 1500439541885 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:45:41 INFO - 1500439541887 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}]
21:45:41 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500439541888 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:45:41 INFO - 1500439541889 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:45:41 INFO - 1500439541890 Marionette TRACE 370 <- [1,7,null,{}]
21:45:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
21:45:41 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:45:41 INFO - 1500439541892 Marionette DEBUG Closed connection 370
21:45:41 INFO - 1500439541894 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49771
21:45:41 INFO - 1500439541894 Marionette DEBUG Closed connection 371
21:45:41 INFO - 1500439541895 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49772
21:45:41 INFO - 1500439541896 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:41 INFO - 1500439541896 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541898 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541900 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c667e2ca-48c9-48a1-998c-cf034f3bd379","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541902 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:45:41 INFO - 1500439541903 Marionette TRACE 372 <- [1,2,null,{}]
21:45:41 INFO - 1500439541903 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:45:41 INFO - 1500439541904 Marionette TRACE 372 <- [1,3,null,{}]
21:45:41 INFO - 1500439541904 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:45:41 INFO - 1500439541905 Marionette TRACE 372 <- [1,4,null,{}]
21:45:41 INFO - 1500439541906 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}]
21:45:41 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500439541908 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:45:41 INFO - 1500439541909 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:45:41 INFO - 1500439541910 Marionette TRACE 372 <- [1,6,null,{}]
21:45:41 INFO - 1500439541912 Marionette DEBUG Closed connection 372
21:45:41 INFO - 1500439541912 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49773
21:45:41 INFO - 1500439541913 Marionette DEBUG Closed connection 373
21:45:41 INFO - 1500439541914 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49774
21:45:41 INFO - 1500439541914 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:41 INFO - 1500439541915 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541917 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541919 Marionette TRACE 374 <- [1,1,null,{"sessionId":"515ab910-75ae-4ee0-89e3-b68946b73987","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541921 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:45:41 INFO - 1500439541922 Marionette TRACE 374 <- [1,2,null,{}]
21:45:41 INFO - 1500439541924 Marionette DEBUG Closed connection 374
21:45:41 INFO - 1500439541924 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49775
21:45:41 INFO - 1500439541925 Marionette DEBUG Closed connection 375
21:45:41 INFO - 1500439541926 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49776
21:45:41 INFO - 1500439541926 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:45:41 INFO - 1500439541927 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:41 INFO - 1500439541929 Marionette DEBUG Register listener.js for window 15
21:45:41 INFO - 1500439541932 Marionette TRACE 376 <- [1,1,null,{"sessionId":"d9dfe78e-5919-43a5-a80c-43d31c0e2bc5","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:41 INFO - 1500439541934 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"}]
21:45:41 INFO - 1500439541935 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"
21:45:41 INFO - 1500439541937 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"
21:45:41 INFO - 1500439541937 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"
21:45:41 INFO - 1500439541954 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"
21:45:41 INFO - 1500439541957 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"
21:45:41 INFO - 1500439541960 Marionette TRACE 376 <- [1,2,null,{}]
21:45:41 INFO - 1500439541965 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:45:41 INFO - 1500439541967 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e83323a5-9479-43f7-8604-6eb1e65f6805","ELEMENT":"e83323a5-9479-43f7-8604-6eb1e65f6805"}}]
21:45:41 INFO - 1500439541968 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e83323a5-9479-43f7-8604-6eb1e65f6805"}]
21:45:42 INFO - 1500439542209 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:45:42 INFO - 1500439542209 Marionette TRACE 376 <- [1,4,null,{}]
21:45:42 INFO - 1500439542211 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}]
21:45:42 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500439542213 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:45:42 INFO - 1500439542214 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:45:42 INFO - 1500439542215 Marionette TRACE 376 <- [1,6,null,{}]
21:45:42 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 323ms
21:45:42 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:45:42 INFO - 1500439542217 Marionette DEBUG Closed connection 376
21:45:42 INFO - 1500439542218 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49777
21:45:42 INFO - 1500439542219 Marionette DEBUG Closed connection 377
21:45:42 INFO - 1500439542219 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49778
21:45:42 INFO - 1500439542220 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:42 INFO - 1500439542221 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542222 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542225 Marionette TRACE 378 <- [1,1,null,{"sessionId":"8c9d15cd-6451-49fa-8a3b-a7ea8e015624","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542227 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:45:42 INFO - 1500439542227 Marionette TRACE 378 <- [1,2,null,{}]
21:45:42 INFO - 1500439542228 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:45:42 INFO - 1500439542228 Marionette TRACE 378 <- [1,3,null,{}]
21:45:42 INFO - 1500439542229 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:45:42 INFO - 1500439542229 Marionette TRACE 378 <- [1,4,null,{}]
21:45:42 INFO - 1500439542231 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}]
21:45:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500439542233 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:45:42 INFO - 1500439542234 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:45:42 INFO - 1500439542235 Marionette TRACE 378 <- [1,6,null,{}]
21:45:42 INFO - 1500439542237 Marionette DEBUG Closed connection 378
21:45:42 INFO - 1500439542237 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49779
21:45:42 INFO - 1500439542238 Marionette DEBUG Closed connection 379
21:45:42 INFO - 1500439542239 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49780
21:45:42 INFO - 1500439542240 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:42 INFO - 1500439542240 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542242 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542244 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b906501c-d535-4d91-b9c5-a077e3b41879","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542246 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:45:42 INFO - 1500439542247 Marionette TRACE 380 <- [1,2,null,{}]
21:45:42 INFO - 1500439542249 Marionette DEBUG Closed connection 380
21:45:42 INFO - 1500439542249 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49781
21:45:42 INFO - 1500439542250 Marionette DEBUG Closed connection 381
21:45:42 INFO - 1500439542251 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49782
21:45:42 INFO - 1500439542252 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:45:42 INFO - 1500439542252 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542254 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542256 Marionette TRACE 382 <- [1,1,null,{"sessionId":"39b722aa-6232-40ae-8eac-92003a8df999","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542259 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"}]
21:45:42 INFO - 1500439542260 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"
21:45:42 INFO - 1500439542261 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"
21:45:42 INFO - 1500439542262 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"
21:45:42 INFO - 1500439542270 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"
21:45:42 INFO - 1500439542273 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"
21:45:42 INFO - 1500439542276 Marionette TRACE 382 <- [1,2,null,{}]
21:45:42 INFO - 1500439542284 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:45:42 INFO - 1500439542286 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d5fbff51-ddfa-4b51-a457-6401eb974f8b","ELEMENT":"d5fbff51-ddfa-4b51-a457-6401eb974f8b"}}]
21:45:42 INFO - 1500439542287 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d5fbff51-ddfa-4b51-a457-6401eb974f8b"}]
21:45:42 INFO - 1500439542525 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:45:42 INFO - 1500439542525 Marionette TRACE 382 <- [1,4,null,{}]
21:45:42 INFO - 1500439542527 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}]
21:45:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500439542529 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:45:42 INFO - 1500439542530 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:45:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
21:45:42 INFO - TEST-START | test_click.py TestClick.test_input_file
21:45:42 INFO - 1500439542531 Marionette TRACE 382 <- [1,6,null,{}]
21:45:42 INFO - 1500439542533 Marionette DEBUG Closed connection 382
21:45:42 INFO - 1500439542534 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49783
21:45:42 INFO - 1500439542535 Marionette DEBUG Closed connection 383
21:45:42 INFO - 1500439542535 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49784
21:45:42 INFO - 1500439542536 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:42 INFO - 1500439542536 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542538 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542541 Marionette TRACE 384 <- [1,1,null,{"sessionId":"c7541216-8630-44f8-a75e-01974ef5c4b7","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542542 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:45:42 INFO - 1500439542543 Marionette TRACE 384 <- [1,2,null,{}]
21:45:42 INFO - 1500439542544 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:45:42 INFO - 1500439542544 Marionette TRACE 384 <- [1,3,null,{}]
21:45:42 INFO - 1500439542545 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:45:42 INFO - 1500439542545 Marionette TRACE 384 <- [1,4,null,{}]
21:45:42 INFO - 1500439542547 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}]
21:45:42 INFO - TEST-START: test_click.py TestClick.test_input_file1500439542549 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:45:42 INFO - 1500439542550 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:45:42 INFO - 1500439542551 Marionette TRACE 384 <- [1,6,null,{}]
21:45:42 INFO - 1500439542553 Marionette DEBUG Closed connection 384
21:45:42 INFO - 1500439542554 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49785
21:45:42 INFO - 1500439542554 Marionette DEBUG Closed connection 385
21:45:42 INFO - 1500439542555 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49786
21:45:42 INFO - 1500439542556 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:42 INFO - 1500439542556 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542558 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542561 Marionette TRACE 386 <- [1,1,null,{"sessionId":"82b1800e-6ec6-4b6d-9752-12f25a9d9dea","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542562 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:45:42 INFO - 1500439542563 Marionette TRACE 386 <- [1,2,null,{}]
21:45:42 INFO - 1500439542565 Marionette DEBUG Closed connection 386
21:45:42 INFO - 1500439542566 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49787
21:45:42 INFO - 1500439542566 Marionette DEBUG Closed connection 387
21:45:42 INFO - 1500439542567 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49788
21:45:42 INFO - 1500439542568 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:45:42 INFO - 1500439542568 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542570 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542579 Marionette TRACE 388 <- [1,1,null,{"sessionId":"79e5533e-abdb-4e03-87aa-b80c0b3b79db","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542581 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:45:42 INFO - 1500439542582 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"
21:45:42 INFO - 1500439542584 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"
21:45:42 INFO - 1500439542584 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"
21:45:42 INFO - 1500439542601 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:45:42 INFO - 1500439542602 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:45:42 INFO - 1500439542605 Marionette TRACE 388 <- [1,2,null,{}]
21:45:42 INFO - 1500439542607 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:45:42 INFO - 1500439542614 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5c0b031c-19e6-4bc3-b961-b9610b475c6a","ELEMENT":"5c0b031c-19e6-4bc3-b961-b9610b475c6a"}}]
21:45:42 INFO - 1500439542615 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"5c0b031c-19e6-4bc3-b961-b9610b475c6a"}]
21:45:42 INFO - 1500439542640 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]
21:45:42 INFO - 1500439542642 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}]
21:45:42 INFO - TEST-END: test_click.py TestClick.test_input_file1500439542644 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:45:42 INFO - 1500439542645 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:45:42 INFO - 1500439542646 Marionette TRACE 388 <- [1,6,null,{}]
21:45:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 115ms
21:45:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:45:42 INFO - 1500439542648 Marionette DEBUG Closed connection 388
21:45:42 INFO - 1500439542650 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49789
21:45:42 INFO - 1500439542651 Marionette DEBUG Closed connection 389
21:45:42 INFO - 1500439542651 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49790
21:45:42 INFO - 1500439542652 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:42 INFO - 1500439542653 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542654 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542657 Marionette TRACE 390 <- [1,1,null,{"sessionId":"76c39c30-3a68-41d6-bf3b-567a9566aa25","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542659 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:45:42 INFO - 1500439542659 Marionette TRACE 390 <- [1,2,null,{}]
21:45:42 INFO - 1500439542660 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:45:42 INFO - 1500439542660 Marionette TRACE 390 <- [1,3,null,{}]
21:45:42 INFO - 1500439542661 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:45:42 INFO - 1500439542661 Marionette TRACE 390 <- [1,4,null,{}]
21:45:42 INFO - 1500439542663 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}]
21:45:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500439542665 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:45:42 INFO - 1500439542666 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:45:42 INFO - 1500439542667 Marionette TRACE 390 <- [1,6,null,{}]
21:45:42 INFO - 1500439542669 Marionette DEBUG Closed connection 390
21:45:42 INFO - 1500439542669 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49791
21:45:42 INFO - 1500439542670 Marionette DEBUG Closed connection 391
21:45:42 INFO - 1500439542670 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49792
21:45:42 INFO - 1500439542671 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:42 INFO - 1500439542672 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542674 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542676 Marionette TRACE 392 <- [1,1,null,{"sessionId":"e961272d-5a99-4d63-a7da-f94598bb607e","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542678 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:45:42 INFO - 1500439542679 Marionette TRACE 392 <- [1,2,null,{}]
21:45:42 INFO - 1500439542681 Marionette DEBUG Closed connection 392
21:45:42 INFO - 1500439542682 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49793
21:45:42 INFO - 1500439542683 Marionette DEBUG Closed connection 393
21:45:42 INFO - 1500439542683 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49794
21:45:42 INFO - 1500439542684 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:45:42 INFO - 1500439542684 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542686 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542689 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3e06606c-e210-4022-ab5a-de3dc60e74ef","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:42 INFO - 1500439542691 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"}]
21:45:42 INFO - 1500439542692 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:45:42 INFO - 1500439542694 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:45:42 INFO - 1500439542694 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:45:42 INFO - 1500439542703 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"
21:45:42 INFO - 1500439542706 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"
21:45:42 INFO - 1500439542709 Marionette TRACE 394 <- [1,2,null,{}]
21:45:42 INFO - 1500439542715 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:45:42 INFO - 1500439542717 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"58e8ed71-e6a2-4a5b-a6b7-25222de17de9","ELEMENT":"58e8ed71-e6a2-4a5b-a6b7-25222de17de9"}}]
21:45:42 INFO - 1500439542718 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:45:42 INFO - 1500439542719 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c90fb20-a449-41d8-8614-96a81f5a46cd","ELEMENT":"6c90fb20-a449-41d8-8614-96a81f5a46cd"}}]
21:45:42 INFO - 1500439542720 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"58e8ed71-e6a2-4a5b-a6b7-25222de17de9"}]
21:45:42 INFO - 1500439542962 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:45:42 INFO - 1500439542963 Marionette TRACE 394 <- [1,5,null,{}]
21:45:42 INFO - 1500439542964 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"6c90fb20-a449-41d8-8614-96a81f5a46cd"}]
21:45:42 INFO - 1500439542977 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]
21:45:42 INFO - 1500439542978 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
21:45:42 INFO - 1500439542980 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:45:42 INFO - 1500439542982 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}]
21:45:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500439542983 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:45:42 INFO - 1500439542984 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:45:42 INFO - 1500439542985 Marionette TRACE 394 <- [1,9,null,{}]
21:45:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 338ms
21:45:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:45:42 INFO - 1500439542987 Marionette DEBUG Closed connection 394
21:45:42 INFO - 1500439542988 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49795
21:45:42 INFO - 1500439542989 Marionette DEBUG Closed connection 395
21:45:42 INFO - 1500439542989 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49796
21:45:42 INFO - 1500439542990 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:42 INFO - 1500439542991 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:42 INFO - 1500439542993 Marionette DEBUG Register listener.js for window 15
21:45:42 INFO - 1500439542996 Marionette TRACE 396 <- [1,1,null,{"sessionId":"19f7fee2-12c5-4542-9d75-765e95e48a9a","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:43 INFO - 1500439542997 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:45:43 INFO - 1500439542998 Marionette TRACE 396 <- [1,2,null,{}]
21:45:43 INFO - 1500439542998 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:45:43 INFO - 1500439542999 Marionette TRACE 396 <- [1,3,null,{}]
21:45:43 INFO - 1500439542999 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:45:43 INFO - 1500439543000 Marionette TRACE 396 <- [1,4,null,{}]
21:45:43 INFO - 1500439543001 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}]
21:45:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500439543003 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:45:43 INFO - 1500439543003 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:45:43 INFO - 1500439543004 Marionette TRACE 396 <- [1,6,null,{}]
21:45:43 INFO - 1500439543006 Marionette DEBUG Closed connection 396
21:45:43 INFO - 1500439543006 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49797
21:45:43 INFO - 1500439543007 Marionette DEBUG Closed connection 397
21:45:43 INFO - 1500439543008 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49798
21:45:43 INFO - 1500439543009 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:45:43 INFO - 1500439543009 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:43 INFO - 1500439543011 Marionette DEBUG Register listener.js for window 15
21:45:43 INFO - 1500439543013 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d44312b5-8ebe-4a18-aca9-9a473d49f543","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:43 INFO - 1500439543015 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:45:43 INFO - 1500439543016 Marionette TRACE 398 <- [1,2,null,{}]
21:45:43 INFO - 1500439543018 Marionette DEBUG Closed connection 398
21:45:43 INFO - 1500439543018 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49799
21:45:43 INFO - 1500439543019 Marionette DEBUG Closed connection 399
21:45:43 INFO - 1500439543020 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49800
21:45:43 INFO - 1500439543021 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:45:43 INFO - 1500439543021 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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}
21:45:43 INFO - 1500439543023 Marionette DEBUG Register listener.js for window 15
21:45:43 INFO - 1500439543026 Marionette TRACE 400 <- [1,1,null,{"sessionId":"9a22fc37-6d1d-4cf8-8ba3-ba525db5b68f","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":2208,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-069.001\\AppData\\Local\\Temp\\tmpz8zpvj.mozrunner","moz:accessibilityChecks":false}}]
21:45:43 INFO - 1500439543028 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"}]
21:45:43 INFO - 1500439543029 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"
21:45:43 INFO - 1500439543030 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"
21:45:43 INFO - 1500439543031 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"
21:45:43 INFO - 1500439543041 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"
21:45:43 INFO - 1500439543044 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"
21:45:43 INFO - 1500439543053 Marionette TRACE 400 <- [1,2,null,{}]
21:45:43 INFO - 1500439543057 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:45:43 INFO - 1500439543059 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3b09418b-075c-450f-b464-cda20a2d22d2","ELEMENT":"3b09418b-075c-450f-b464-cda20a2d22d2"}}]
21:45:43 INFO - 1500439543060 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"3b09418b-075c-450f-b464-cda20a2d22d2"}]
21:45:43 INFO - 1500439543081 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:45:43 INFO - 1500439543090 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"3b09418b-075c-450f-b464-cda20a2d22d2"}]
21:45:43 INFO - 1500439543103 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element