could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:26:14 INFO - 1499603174181 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}]
05:26:14 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499603174183 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:26:14 INFO - 1499603174184 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:26:14 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 131ms
05:26:14 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:26:14 INFO - 1499603174185 Marionette TRACE 352 <- [1,6,null,{}]
05:26:14 INFO - 1499603174187 Marionette DEBUG Closed connection 352
05:26:14 INFO - 1499603174188 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49770
05:26:14 INFO - 1499603174188 Marionette DEBUG Closed connection 353
05:26:14 INFO - 1499603174189 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49771
05:26:14 INFO - 1499603174190 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:14 INFO - 1499603174190 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174193 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174196 Marionette TRACE 354 <- [1,1,null,{"sessionId":"bbafaa50-676c-4ed9-8ed0-f684822a0f85","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174197 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:26:14 INFO - 1499603174198 Marionette TRACE 354 <- [1,2,null,{}]
05:26:14 INFO - 1499603174198 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:26:14 INFO - 1499603174199 Marionette TRACE 354 <- [1,3,null,{}]
05:26:14 INFO - 1499603174200 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:26:14 INFO - 1499603174200 Marionette TRACE 354 <- [1,4,null,{}]
05:26:14 INFO - 1499603174201 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}]
05:26:14 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499603174204 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:26:14 INFO - 1499603174204 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:26:14 INFO - 1499603174205 Marionette TRACE 354 <- [1,6,null,{}]
05:26:14 INFO - 1499603174207 Marionette DEBUG Closed connection 354
05:26:14 INFO - 1499603174207 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49772
05:26:14 INFO - 1499603174208 Marionette DEBUG Closed connection 355
05:26:14 INFO - 1499603174209 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49773
05:26:14 INFO - 1499603174209 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:14 INFO - 1499603174210 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174212 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174215 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5d09abbc-d3a6-424f-be09-dc13e083d3aa","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174216 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:26:14 INFO - 1499603174217 Marionette TRACE 356 <- [1,2,null,{}]
05:26:14 INFO - 1499603174218 Marionette DEBUG Closed connection 356
05:26:14 INFO - 1499603174219 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49774
05:26:14 INFO - 1499603174220 Marionette DEBUG Closed connection 357
05:26:14 INFO - 1499603174220 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49775
05:26:14 INFO - 1499603174221 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:26:14 INFO - 1499603174222 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174224 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174227 Marionette TRACE 358 <- [1,1,null,{"sessionId":"2b02e366-d372-480b-9a4f-d879a0012ba9","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174228 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49336/clicks.html"}]
05:26:14 INFO - 1499603174230 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/hidden.html"
05:26:14 INFO - 1499603174236 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/hidden.html"
05:26:14 INFO - 1499603174237 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/hidden.html"
05:26:14 INFO - 1499603174242 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/clicks.html"
05:26:14 INFO - 1499603174247 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/clicks.html"
05:26:14 INFO - 1499603174250 Marionette TRACE 358 <- [1,2,null,{}]
05:26:14 INFO - 1499603174253 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:26:14 INFO - 1499603174256 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6fcec1a9-71db-4835-a535-efba626d172b","ELEMENT":"6fcec1a9-71db-4835-a535-efba626d172b"}}]
05:26:14 INFO - 1499603174257 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"6fcec1a9-71db-4835-a535-efba626d172b"}]
05:26:14 INFO - 1499603174295 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/clicks.html"
05:26:14 INFO - 1499603174300 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/clicks.html"
05:26:14 INFO - 1499603174301 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/clicks.html"
05:26:14 INFO - 1499603174307 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/test.html"
05:26:14 INFO - 1499603174310 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/test.html"
05:26:14 INFO - 1499603174313 Marionette TRACE 358 <- [1,4,null,{}]
05:26:14 INFO - 1499603174316 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:26:14 INFO - 1499603174320 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"39eacf33-014e-4d4b-bb62-a0b4361f9ffd","ELEMENT":"39eacf33-014e-4d4b-bb62-a0b4361f9ffd"}}]
05:26:14 INFO - 1499603174321 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:26:14 INFO - 1499603174321 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:26:14 INFO - 1499603174323 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}]
05:26:14 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499603174325 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:26:14 INFO - 1499603174326 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:26:14 INFO - 1499603174327 Marionette TRACE 358 <- [1,8,null,{}]
05:26:14 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 145ms
05:26:14 INFO - TEST-START | test_click.py TestClick.test_container_element
05:26:14 INFO - 1499603174332 Marionette DEBUG Closed connection 358
05:26:14 INFO - 1499603174332 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49778
05:26:14 INFO - 1499603174333 Marionette DEBUG Closed connection 359
05:26:14 INFO - 1499603174333 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49779
05:26:14 INFO - 1499603174334 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:14 INFO - 1499603174335 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174337 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174340 Marionette TRACE 360 <- [1,1,null,{"sessionId":"be403324-55db-47c7-a547-bd71a8773a94","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174341 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:26:14 INFO - 1499603174342 Marionette TRACE 360 <- [1,2,null,{}]
05:26:14 INFO - 1499603174343 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:26:14 INFO - 1499603174343 Marionette TRACE 360 <- [1,3,null,{}]
05:26:14 INFO - 1499603174344 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:26:14 INFO - 1499603174344 Marionette TRACE 360 <- [1,4,null,{}]
05:26:14 INFO - 1499603174346 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}]
05:26:14 INFO - TEST-START: test_click.py TestClick.test_container_element1499603174349 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:26:14 INFO - 1499603174350 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:26:14 INFO - 1499603174351 Marionette TRACE 360 <- [1,6,null,{}]
05:26:14 INFO - 1499603174352 Marionette DEBUG Closed connection 360
05:26:14 INFO - 1499603174353 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49780
05:26:14 INFO - 1499603174354 Marionette DEBUG Closed connection 361
05:26:14 INFO - 1499603174354 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49781
05:26:14 INFO - 1499603174355 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:14 INFO - 1499603174356 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174358 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174361 Marionette TRACE 362 <- [1,1,null,{"sessionId":"0354117d-9c3e-4467-96bd-e8b9bbdd6677","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174362 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:26:14 INFO - 1499603174363 Marionette TRACE 362 <- [1,2,null,{}]
05:26:14 INFO - 1499603174364 Marionette DEBUG Closed connection 362
05:26:14 INFO - 1499603174365 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49782
05:26:14 INFO - 1499603174366 Marionette DEBUG Closed connection 363
05:26:14 INFO - 1499603174366 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49783
05:26:14 INFO - 1499603174367 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:26:14 INFO - 1499603174367 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174370 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174372 Marionette TRACE 364 <- [1,1,null,{"sessionId":"28009dab-865a-49ef-9048-1f6f5bb051dc","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174374 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"}]
05:26:14 INFO - 1499603174375 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/test.html"
05:26:14 INFO - 1499603174378 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/test.html"
05:26:14 INFO - 1499603174379 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/test.html"
05:26:14 INFO - 1499603174385 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"
05:26:14 INFO - 1499603174390 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"
05:26:14 INFO - 1499603174398 Marionette TRACE 364 <- [1,2,null,{}]
05:26:14 INFO - 1499603174402 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:26:14 INFO - 1499603174404 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d31ac644-1d7e-40eb-a126-e39c76269a52","ELEMENT":"d31ac644-1d7e-40eb-a126-e39c76269a52"}}]
05:26:14 INFO - 1499603174405 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d31ac644-1d7e-40eb-a126-e39c76269a52"}]
05:26:14 INFO - 1499603174643 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:26:14 INFO - 1499603174644 Marionette TRACE 364 <- [1,4,null,{}]
05:26:14 INFO - 1499603174646 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d31ac644-1d7e-40eb-a126-e39c76269a52","name":"selected"}]
05:26:14 INFO - 1499603174649 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:26:14 INFO - 1499603174651 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}]
05:26:14 INFO - TEST-END: test_click.py TestClick.test_container_element1499603174653 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:26:14 INFO - 1499603174654 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:26:14 INFO - 1499603174655 Marionette TRACE 364 <- [1,7,null,{}]
05:26:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 325ms
05:26:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:26:14 INFO - 1499603174657 Marionette DEBUG Closed connection 364
05:26:14 INFO - 1499603174658 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49784
05:26:14 INFO - 1499603174659 Marionette DEBUG Closed connection 365
05:26:14 INFO - 1499603174660 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49785
05:26:14 INFO - 1499603174660 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:14 INFO - 1499603174661 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174663 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174666 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8f9ac4bb-8eaf-4d16-a675-3913fe96f8ec","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174667 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:26:14 INFO - 1499603174668 Marionette TRACE 366 <- [1,2,null,{}]
05:26:14 INFO - 1499603174668 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:26:14 INFO - 1499603174669 Marionette TRACE 366 <- [1,3,null,{}]
05:26:14 INFO - 1499603174670 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:26:14 INFO - 1499603174670 Marionette TRACE 366 <- [1,4,null,{}]
05:26:14 INFO - 1499603174672 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}]
05:26:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499603174674 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:26:14 INFO - 1499603174675 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:26:14 INFO - 1499603174676 Marionette TRACE 366 <- [1,6,null,{}]
05:26:14 INFO - 1499603174677 Marionette DEBUG Closed connection 366
05:26:14 INFO - 1499603174678 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49786
05:26:14 INFO - 1499603174679 Marionette DEBUG Closed connection 367
05:26:14 INFO - 1499603174679 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49787
05:26:14 INFO - 1499603174680 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:14 INFO - 1499603174681 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174683 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174685 Marionette TRACE 368 <- [1,1,null,{"sessionId":"a0374ff6-3340-48a1-87cc-de37e922e959","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174686 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:26:14 INFO - 1499603174687 Marionette TRACE 368 <- [1,2,null,{}]
05:26:14 INFO - 1499603174689 Marionette DEBUG Closed connection 368
05:26:14 INFO - 1499603174689 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49788
05:26:14 INFO - 1499603174690 Marionette DEBUG Closed connection 369
05:26:14 INFO - 1499603174691 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49789
05:26:14 INFO - 1499603174692 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:26:14 INFO - 1499603174692 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174694 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174697 Marionette TRACE 370 <- [1,1,null,{"sessionId":"00bfaca7-cc66-4778-aa25-e9d473ecd518","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174698 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"}]
05:26:14 INFO - 1499603174700 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"
05:26:14 INFO - 1499603174701 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"
05:26:14 INFO - 1499603174702 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"
05:26:14 INFO - 1499603174707 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"
05:26:14 INFO - 1499603174710 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"
05:26:14 INFO - 1499603174715 Marionette TRACE 370 <- [1,2,null,{}]
05:26:14 INFO - 1499603174718 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:26:14 INFO - 1499603174720 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f88dbb0d-4f14-4060-8bde-f02ff1b6e14d","ELEMENT":"f88dbb0d-4f14-4060-8bde-f02ff1b6e14d"}}]
05:26:14 INFO - 1499603174720 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"f88dbb0d-4f14-4060-8bde-f02ff1b6e14d"}]
05:26:14 INFO - 1499603174965 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:26:14 INFO - 1499603174966 Marionette TRACE 370 <- [1,4,null,{}]
05:26:14 INFO - 1499603174967 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"f88dbb0d-4f14-4060-8bde-f02ff1b6e14d","name":"selected"}]
05:26:14 INFO - 1499603174971 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:26:14 INFO - 1499603174972 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}]
05:26:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 320ms
05:26:14 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499603174975 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:26:14 INFO - 1499603174976 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:26:14 INFO - 1499603174977 Marionette TRACE 370 <- [1,7,null,{}]
05:26:14 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:26:14 INFO - 1499603174978 Marionette DEBUG Closed connection 370
05:26:14 INFO - 1499603174979 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49790
05:26:14 INFO - 1499603174980 Marionette DEBUG Closed connection 371
05:26:14 INFO - 1499603174981 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49791
05:26:14 INFO - 1499603174982 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:14 INFO - 1499603174982 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:14 INFO - 1499603174984 Marionette DEBUG Register listener.js for window 4294967297
05:26:14 INFO - 1499603174987 Marionette TRACE 372 <- [1,1,null,{"sessionId":"bf50f807-8eca-47dd-a635-e79fdb993f1b","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:14 INFO - 1499603174988 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:26:14 INFO - 1499603174989 Marionette TRACE 372 <- [1,2,null,{}]
05:26:14 INFO - 1499603174990 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:26:14 INFO - 1499603174990 Marionette TRACE 372 <- [1,3,null,{}]
05:26:14 INFO - 1499603174991 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:26:14 INFO - 1499603174991 Marionette TRACE 372 <- [1,4,null,{}]
05:26:14 INFO - 1499603174993 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}]
05:26:14 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499603174996 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:26:14 INFO - 1499603174996 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:26:14 INFO - 1499603174997 Marionette TRACE 372 <- [1,6,null,{}]
05:26:15 INFO - 1499603174999 Marionette DEBUG Closed connection 372
05:26:15 INFO - 1499603174999 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49792
05:26:15 INFO - 1499603175000 Marionette DEBUG Closed connection 373
05:26:15 INFO - 1499603175001 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49793
05:26:15 INFO - 1499603175002 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:15 INFO - 1499603175002 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175004 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175007 Marionette TRACE 374 <- [1,1,null,{"sessionId":"b4073a03-58ae-433b-8194-4b26736aec73","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175008 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:26:15 INFO - 1499603175010 Marionette TRACE 374 <- [1,2,null,{}]
05:26:15 INFO - 1499603175011 Marionette DEBUG Closed connection 374
05:26:15 INFO - 1499603175011 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49794
05:26:15 INFO - 1499603175012 Marionette DEBUG Closed connection 375
05:26:15 INFO - 1499603175013 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49795
05:26:15 INFO - 1499603175014 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:26:15 INFO - 1499603175014 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175016 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175019 Marionette TRACE 376 <- [1,1,null,{"sessionId":"15ab91b1-09d5-4d11-ab77-f622c5fef7df","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175020 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"}]
05:26:15 INFO - 1499603175026 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"
05:26:15 INFO - 1499603175028 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"
05:26:15 INFO - 1499603175028 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"
05:26:15 INFO - 1499603175034 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"
05:26:15 INFO - 1499603175037 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"
05:26:15 INFO - 1499603175039 Marionette TRACE 376 <- [1,2,null,{}]
05:26:15 INFO - 1499603175041 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:26:15 INFO - 1499603175048 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f80dd1e5-3fef-4baa-91bf-45e8e22a86b6","ELEMENT":"f80dd1e5-3fef-4baa-91bf-45e8e22a86b6"}}]
05:26:15 INFO - 1499603175049 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f80dd1e5-3fef-4baa-91bf-45e8e22a86b6"}]
05:26:15 INFO - 1499603175283 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:26:15 INFO - 1499603175284 Marionette TRACE 376 <- [1,4,null,{}]
05:26:15 INFO - 1499603175286 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}]
05:26:15 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 312ms
05:26:15 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499603175288 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:26:15 INFO - 1499603175289 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:26:15 INFO - 1499603175290 Marionette TRACE 376 <- [1,6,null,{}]
05:26:15 INFO - 1499603175291 Marionette DEBUG Closed connection 376
05:26:15 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:26:15 INFO - 1499603175294 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49796
05:26:15 INFO - 1499603175295 Marionette DEBUG Closed connection 377
05:26:15 INFO - 1499603175295 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49797
05:26:15 INFO - 1499603175296 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:15 INFO - 1499603175297 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175299 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175302 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d718c743-550c-448f-9729-b2201b79a065","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175303 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:26:15 INFO - 1499603175303 Marionette TRACE 378 <- [1,2,null,{}]
05:26:15 INFO - 1499603175304 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:26:15 INFO - 1499603175305 Marionette TRACE 378 <- [1,3,null,{}]
05:26:15 INFO - 1499603175306 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:26:15 INFO - 1499603175306 Marionette TRACE 378 <- [1,4,null,{}]
05:26:15 INFO - 1499603175308 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}]
05:26:15 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499603175310 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:26:15 INFO - 1499603175311 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:26:15 INFO - 1499603175312 Marionette TRACE 378 <- [1,6,null,{}]
05:26:15 INFO - 1499603175313 Marionette DEBUG Closed connection 378
05:26:15 INFO - 1499603175314 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49798
05:26:15 INFO - 1499603175314 Marionette DEBUG Closed connection 379
05:26:15 INFO - 1499603175315 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49799
05:26:15 INFO - 1499603175316 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:15 INFO - 1499603175317 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175319 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175329 Marionette TRACE 380 <- [1,1,null,{"sessionId":"164abc92-4026-4992-ad10-6689f17870d6","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175330 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:26:15 INFO - 1499603175332 Marionette TRACE 380 <- [1,2,null,{}]
05:26:15 INFO - 1499603175333 Marionette DEBUG Closed connection 380
05:26:15 INFO - 1499603175334 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49800
05:26:15 INFO - 1499603175334 Marionette DEBUG Closed connection 381
05:26:15 INFO - 1499603175335 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49801
05:26:15 INFO - 1499603175336 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:26:15 INFO - 1499603175336 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175339 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175341 Marionette TRACE 382 <- [1,1,null,{"sessionId":"7fd89e95-19ec-4ca6-9072-c6429504b2d2","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175342 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"}]
05:26:15 INFO - 1499603175345 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"
05:26:15 INFO - 1499603175346 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"
05:26:15 INFO - 1499603175346 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"
05:26:15 INFO - 1499603175352 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"
05:26:15 INFO - 1499603175354 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"
05:26:15 INFO - 1499603175356 Marionette TRACE 382 <- [1,2,null,{}]
05:26:15 INFO - 1499603175358 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:26:15 INFO - 1499603175364 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"679d55f9-7808-4d3e-a15a-8259a4565915","ELEMENT":"679d55f9-7808-4d3e-a15a-8259a4565915"}}]
05:26:15 INFO - 1499603175365 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"679d55f9-7808-4d3e-a15a-8259a4565915"}]
05:26:15 INFO - 1499603175608 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:26:15 INFO - 1499603175609 Marionette TRACE 382 <- [1,4,null,{}]
05:26:15 INFO - 1499603175611 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}]
05:26:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499603175613 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:26:15 INFO - 1499603175614 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:26:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 323ms
05:26:15 INFO - 1499603175615 Marionette TRACE 382 <- [1,6,null,{}]
05:26:15 INFO - TEST-START | test_click.py TestClick.test_input_file
05:26:15 INFO - 1499603175616 Marionette DEBUG Closed connection 382
05:26:15 INFO - 1499603175619 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49802
05:26:15 INFO - 1499603175619 Marionette DEBUG Closed connection 383
05:26:15 INFO - 1499603175620 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49803
05:26:15 INFO - 1499603175621 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:15 INFO - 1499603175622 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175624 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175627 Marionette TRACE 384 <- [1,1,null,{"sessionId":"8e4fd421-3f64-43e3-98db-b01a1288a2f2","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175628 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:26:15 INFO - 1499603175628 Marionette TRACE 384 <- [1,2,null,{}]
05:26:15 INFO - 1499603175629 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:26:15 INFO - 1499603175629 Marionette TRACE 384 <- [1,3,null,{}]
05:26:15 INFO - 1499603175630 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:26:15 INFO - 1499603175631 Marionette TRACE 384 <- [1,4,null,{}]
05:26:15 INFO - 1499603175632 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}]
05:26:15 INFO - TEST-START: test_click.py TestClick.test_input_file1499603175635 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:26:15 INFO - 1499603175635 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:26:15 INFO - 1499603175637 Marionette TRACE 384 <- [1,6,null,{}]
05:26:15 INFO - 1499603175638 Marionette DEBUG Closed connection 384
05:26:15 INFO - 1499603175639 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49804
05:26:15 INFO - 1499603175639 Marionette DEBUG Closed connection 385
05:26:15 INFO - 1499603175640 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49805
05:26:15 INFO - 1499603175641 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:15 INFO - 1499603175641 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175643 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175646 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6378756f-ff1d-4741-82c4-f83079b1801d","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175647 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:26:15 INFO - 1499603175648 Marionette TRACE 386 <- [1,2,null,{}]
05:26:15 INFO - 1499603175650 Marionette DEBUG Closed connection 386
05:26:15 INFO - 1499603175650 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49806
05:26:15 INFO - 1499603175651 Marionette DEBUG Closed connection 387
05:26:15 INFO - 1499603175652 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49807
05:26:15 INFO - 1499603175653 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:26:15 INFO - 1499603175653 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175655 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175658 Marionette TRACE 388 <- [1,1,null,{"sessionId":"de8cf91a-f31c-4382-add3-bdaf54f0fb72","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175659 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:26:15 INFO - 1499603175660 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"
05:26:15 INFO - 1499603175662 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"
05:26:15 INFO - 1499603175663 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"
05:26:15 INFO - 1499603175668 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:26:15 INFO - 1499603175672 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:26:15 INFO - 1499603175673 Marionette TRACE 388 <- [1,2,null,{}]
05:26:15 INFO - 1499603175676 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:26:15 INFO - 1499603175681 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86ad26e7-2b29-4bfe-9584-d6ca2db16430","ELEMENT":"86ad26e7-2b29-4bfe-9584-d6ca2db16430"}}]
05:26:15 INFO - 1499603175682 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"86ad26e7-2b29-4bfe-9584-d6ca2db16430"}]
05:26:15 INFO - 1499603175708 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:26:15 INFO - 1499603175710 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}]
05:26:15 INFO - TEST-END: test_click.py TestClick.test_input_file1499603175712 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:26:15 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 97ms
05:26:15 INFO - 1499603175713 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:26:15 INFO - 1499603175714 Marionette TRACE 388 <- [1,6,null,{}]
05:26:15 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:26:15 INFO - 1499603175716 Marionette DEBUG Closed connection 388
05:26:15 INFO - 1499603175717 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49808
05:26:15 INFO - 1499603175717 Marionette DEBUG Closed connection 389
05:26:15 INFO - 1499603175718 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49809
05:26:15 INFO - 1499603175719 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:15 INFO - 1499603175720 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175722 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175725 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1232a700-24c2-4c1c-aae9-5ad79e23f8bf","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175726 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:26:15 INFO - 1499603175726 Marionette TRACE 390 <- [1,2,null,{}]
05:26:15 INFO - 1499603175727 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:26:15 INFO - 1499603175728 Marionette TRACE 390 <- [1,3,null,{}]
05:26:15 INFO - 1499603175729 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:26:15 INFO - 1499603175729 Marionette TRACE 390 <- [1,4,null,{}]
05:26:15 INFO - 1499603175731 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}]
05:26:15 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499603175733 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:26:15 INFO - 1499603175734 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:26:15 INFO - 1499603175735 Marionette TRACE 390 <- [1,6,null,{}]
05:26:15 INFO - 1499603175736 Marionette DEBUG Closed connection 390
05:26:15 INFO - 1499603175736 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49810
05:26:15 INFO - 1499603175737 Marionette DEBUG Closed connection 391
05:26:15 INFO - 1499603175738 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49811
05:26:15 INFO - 1499603175739 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:15 INFO - 1499603175739 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175741 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175744 Marionette TRACE 392 <- [1,1,null,{"sessionId":"299fa47a-2c3f-47bc-9d47-2ddd8c95e191","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175745 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:26:15 INFO - 1499603175747 Marionette TRACE 392 <- [1,2,null,{}]
05:26:15 INFO - 1499603175748 Marionette DEBUG Closed connection 392
05:26:15 INFO - 1499603175748 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49812
05:26:15 INFO - 1499603175749 Marionette DEBUG Closed connection 393
05:26:15 INFO - 1499603175750 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49813
05:26:15 INFO - 1499603175751 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:26:15 INFO - 1499603175751 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:15 INFO - 1499603175754 Marionette DEBUG Register listener.js for window 4294967297
05:26:15 INFO - 1499603175757 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f38a2054-bac3-4e79-b392-d6afd700bbf4","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:15 INFO - 1499603175758 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"}]
05:26:15 INFO - 1499603175759 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:26:15 INFO - 1499603175761 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:26:15 INFO - 1499603175761 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:26:15 INFO - 1499603175767 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"
05:26:15 INFO - 1499603175769 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"
05:26:15 INFO - 1499603175771 Marionette TRACE 394 <- [1,2,null,{}]
05:26:15 INFO - 1499603175774 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:26:15 INFO - 1499603175776 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"49ed417f-c936-4ac7-ad56-4db50da8bdf6","ELEMENT":"49ed417f-c936-4ac7-ad56-4db50da8bdf6"}}]
05:26:15 INFO - 1499603175782 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:26:15 INFO - 1499603175784 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bec9d849-7361-42fb-90d5-6b4e9c6cf17a","ELEMENT":"bec9d849-7361-42fb-90d5-6b4e9c6cf17a"}}]
05:26:15 INFO - 1499603175785 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"49ed417f-c936-4ac7-ad56-4db50da8bdf6"}]
05:26:16 INFO - 1499603176024 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:26:16 INFO - 1499603176025 Marionette TRACE 394 <- [1,5,null,{}]
05:26:16 INFO - 1499603176026 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"bec9d849-7361-42fb-90d5-6b4e9c6cf17a"}]
05:26:16 INFO - 1499603176040 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:26:16 INFO - 1499603176042 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:26:16 INFO - 1499603176044 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:26:16 INFO - 1499603176046 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}]
05:26:16 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499603176048 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:26:16 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 335ms
05:26:16 INFO - 1499603176049 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:26:16 INFO - 1499603176050 Marionette TRACE 394 <- [1,9,null,{}]
05:26:16 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:26:16 INFO - 1499603176051 Marionette DEBUG Closed connection 394
05:26:16 INFO - 1499603176052 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49814
05:26:16 INFO - 1499603176054 Marionette DEBUG Closed connection 395
05:26:16 INFO - 1499603176054 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49815
05:26:16 INFO - 1499603176055 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:16 INFO - 1499603176056 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:16 INFO - 1499603176058 Marionette DEBUG Register listener.js for window 4294967297
05:26:16 INFO - 1499603176061 Marionette TRACE 396 <- [1,1,null,{"sessionId":"33de9010-ba84-4c77-9bc7-a97377f34dcd","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:16 INFO - 1499603176062 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:26:16 INFO - 1499603176063 Marionette TRACE 396 <- [1,2,null,{}]
05:26:16 INFO - 1499603176063 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:26:16 INFO - 1499603176064 Marionette TRACE 396 <- [1,3,null,{}]
05:26:16 INFO - 1499603176065 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:26:16 INFO - 1499603176065 Marionette TRACE 396 <- [1,4,null,{}]
05:26:16 INFO - 1499603176067 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}]
05:26:16 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499603176069 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:26:16 INFO - 1499603176070 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:26:16 INFO - 1499603176071 Marionette TRACE 396 <- [1,6,null,{}]
05:26:16 INFO - 1499603176072 Marionette DEBUG Closed connection 396
05:26:16 INFO - 1499603176073 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49816
05:26:16 INFO - 1499603176074 Marionette DEBUG Closed connection 397
05:26:16 INFO - 1499603176074 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49817
05:26:16 INFO - 1499603176075 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:26:16 INFO - 1499603176076 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:16 INFO - 1499603176078 Marionette DEBUG Register listener.js for window 4294967297
05:26:16 INFO - 1499603176081 Marionette TRACE 398 <- [1,1,null,{"sessionId":"dc5a3683-6fcd-420c-84a8-078a09df1c40","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:16 INFO - 1499603176081 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:26:16 INFO - 1499603176083 Marionette TRACE 398 <- [1,2,null,{}]
05:26:16 INFO - 1499603176084 Marionette DEBUG Closed connection 398
05:26:16 INFO - 1499603176085 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49818
05:26:16 INFO - 1499603176086 Marionette DEBUG Closed connection 399
05:26:16 INFO - 1499603176086 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49819
05:26:16 INFO - 1499603176087 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:26:16 INFO - 1499603176088 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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}
05:26:16 INFO - 1499603176090 Marionette DEBUG Register listener.js for window 4294967297
05:26:16 INFO - 1499603176092 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ff178859-3ef4-4505-ac66-9735f88cfac8","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":2756,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmp7d4ts3.mozrunner","moz:accessibilityChecks":false}}]
05:26:16 INFO - 1499603176094 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"}]
05:26:16 INFO - 1499603176095 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"
05:26:16 INFO - 1499603176098 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"
05:26:16 INFO - 1499603176098 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"
05:26:16 INFO - 1499603176104 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"
05:26:16 INFO - 1499603176106 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"
05:26:16 INFO - 1499603176109 Marionette TRACE 400 <- [1,2,null,{}]
05:26:16 INFO - 1499603176116 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:26:16 INFO - 1499603176130 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa4e5bb7-f9b6-45c4-989e-8b3f911ddbc3","ELEMENT":"fa4e5bb7-f9b6-45c4-989e-8b3f911ddbc3"}}]
05:26:16 INFO - 1499603176131 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"fa4e5bb7-f9b6-45c4-989e-8b3f911ddbc3"}]
05:26:16 INFO - 1499603176163 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:26:16 INFO - 1499603176164 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"fa4e5bb7-f9b6-45c4-989e-8b3f911ddbc3"}]
05:26:16 INFO - 1499603176178 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element