could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
14:59:55 INFO - 1498859995043 Marionette TRACE 358 -> [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}]
14:59:55 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498859995047 Marionette TRACE 358 <- [1,5,null,{"value":null}]
14:59:55 INFO - 1498859995048 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
14:59:55 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 145ms
14:59:55 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
14:59:55 INFO - 1498859995049 Marionette TRACE 358 <- [1,6,null,{}]
14:59:55 INFO - 1498859995051 Marionette DEBUG Closed connection 358
14:59:55 INFO - 1498859995052 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49729
14:59:55 INFO - 1498859995053 Marionette DEBUG Closed connection 359
14:59:55 INFO - 1498859995053 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49730
14:59:55 INFO - 1498859995054 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:55 INFO - 1498859995055 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995058 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995061 Marionette TRACE 360 <- [1,1,null,{"sessionId":"75756d9b-48b1-4305-8822-cb130ff9e92b","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995063 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
14:59:55 INFO - 1498859995063 Marionette TRACE 360 <- [1,2,null,{}]
14:59:55 INFO - 1498859995064 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:59:55 INFO - 1498859995065 Marionette TRACE 360 <- [1,3,null,{}]
14:59:55 INFO - 1498859995066 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
14:59:55 INFO - 1498859995066 Marionette TRACE 360 <- [1,4,null,{}]
14:59:55 INFO - 1498859995068 Marionette TRACE 360 -> [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}]
14:59:55 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498859995071 Marionette TRACE 360 <- [1,5,null,{"value":null}]
14:59:55 INFO - 1498859995072 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
14:59:55 INFO - 1498859995074 Marionette TRACE 360 <- [1,6,null,{}]
14:59:55 INFO - 1498859995075 Marionette DEBUG Closed connection 360
14:59:55 INFO - 1498859995076 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49731
14:59:55 INFO - 1498859995077 Marionette DEBUG Closed connection 361
14:59:55 INFO - 1498859995078 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49732
14:59:55 INFO - 1498859995079 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:55 INFO - 1498859995079 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995083 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995086 Marionette TRACE 362 <- [1,1,null,{"sessionId":"7d9953bb-8cdd-40dc-b7dc-4abb67b96a30","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995087 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
14:59:55 INFO - 1498859995089 Marionette TRACE 362 <- [1,2,null,{}]
14:59:55 INFO - 1498859995090 Marionette DEBUG Closed connection 362
14:59:55 INFO - 1498859995091 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49733
14:59:55 INFO - 1498859995092 Marionette DEBUG Closed connection 363
14:59:55 INFO - 1498859995092 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49734
14:59:55 INFO - 1498859995094 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:59:55 INFO - 1498859995094 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995097 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995101 Marionette TRACE 364 <- [1,1,null,{"sessionId":"7bbd54b3-55c3-4449-9e42-cd279dcb5cc2","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995102 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
14:59:55 INFO - 1498859995105 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
14:59:55 INFO - 1498859995118 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
14:59:55 INFO - 1498859995118 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
14:59:55 INFO - 1498859995125 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
14:59:55 INFO - 1498859995132 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
14:59:55 INFO - 1498859995137 Marionette TRACE 364 <- [1,2,null,{}]
14:59:55 INFO - 1498859995142 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
14:59:55 INFO - 1498859995148 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"73a68fc8-c6b7-475a-8d11-8dc710870f3f","ELEMENT":"73a68fc8-c6b7-475a-8d11-8dc710870f3f"}}]
14:59:55 INFO - 1498859995149 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"73a68fc8-c6b7-475a-8d11-8dc710870f3f"}]
14:59:55 INFO - 1498859995191 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
14:59:55 INFO - 1498859995199 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
14:59:55 INFO - 1498859995200 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
14:59:55 INFO - 1498859995207 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
14:59:55 INFO - 1498859995212 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
14:59:55 INFO - 1498859995216 Marionette TRACE 364 <- [1,4,null,{}]
14:59:55 INFO - 1498859995220 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
14:59:55 INFO - 1498859995223 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f66b2a97-e716-40aa-8b5b-ccd397aa9ff1","ELEMENT":"f66b2a97-e716-40aa-8b5b-ccd397aa9ff1"}}]
14:59:55 INFO - 1498859995224 Marionette TRACE 364 -> [0,6,"getTitle",{}]
14:59:55 INFO - 1498859995229 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
14:59:55 INFO - 1498859995231 Marionette TRACE 364 -> [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}]
14:59:55 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498859995234 Marionette TRACE 364 <- [1,7,null,{"value":null}]
14:59:55 INFO - 1498859995235 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
14:59:55 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 186ms
14:59:55 INFO - TEST-START | test_click.py TestClick.test_container_element
14:59:55 INFO - 1498859995236 Marionette TRACE 364 <- [1,8,null,{}]
14:59:55 INFO - 1498859995238 Marionette DEBUG Closed connection 364
14:59:55 INFO - 1498859995239 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49737
14:59:55 INFO - 1498859995240 Marionette DEBUG Closed connection 365
14:59:55 INFO - 1498859995240 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49738
14:59:55 INFO - 1498859995241 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:55 INFO - 1498859995242 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995245 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995257 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4a7a5470-02b4-44fe-8557-7c0810a8a60d","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995258 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
14:59:55 INFO - 1498859995259 Marionette TRACE 366 <- [1,2,null,{}]
14:59:55 INFO - 1498859995260 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:59:55 INFO - 1498859995261 Marionette TRACE 366 <- [1,3,null,{}]
14:59:55 INFO - 1498859995262 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
14:59:55 INFO - 1498859995262 Marionette TRACE 366 <- [1,4,null,{}]
14:59:55 INFO - 1498859995264 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')","sandbox":"simpletest","line":316}]
14:59:55 INFO - TEST-START: test_click.py TestClick.test_container_element1498859995267 Marionette TRACE 366 <- [1,5,null,{"value":null}]
14:59:55 INFO - 1498859995268 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
14:59:55 INFO - 1498859995269 Marionette TRACE 366 <- [1,6,null,{}]
14:59:55 INFO - 1498859995271 Marionette DEBUG Closed connection 366
14:59:55 INFO - 1498859995272 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49739
14:59:55 INFO - 1498859995273 Marionette DEBUG Closed connection 367
14:59:55 INFO - 1498859995273 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49740
14:59:55 INFO - 1498859995274 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:55 INFO - 1498859995275 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995278 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995281 Marionette TRACE 368 <- [1,1,null,{"sessionId":"9145e9a4-4450-41ca-8497-bffc56102548","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995282 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
14:59:55 INFO - 1498859995284 Marionette TRACE 368 <- [1,2,null,{}]
14:59:55 INFO - 1498859995286 Marionette DEBUG Closed connection 368
14:59:55 INFO - 1498859995286 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49741
14:59:55 INFO - 1498859995287 Marionette DEBUG Closed connection 369
14:59:55 INFO - 1498859995288 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49742
14:59:55 INFO - 1498859995289 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:59:55 INFO - 1498859995290 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995294 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995297 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b947b0fb-9fdd-4f91-96b6-3a0115e0f6a1","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995298 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%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"}]
14:59:55 INFO - 1498859995300 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
14:59:55 INFO - 1498859995302 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
14:59:55 INFO - 1498859995303 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
14:59:55 INFO - 1498859995312 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"
14:59:55 INFO - 1498859995313 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"
14:59:55 INFO - 1498859995320 Marionette TRACE 370 <- [1,2,null,{}]
14:59:55 INFO - 1498859995323 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:59:55 INFO - 1498859995330 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d79749c5-ad6d-49d1-b919-496b1223213f","ELEMENT":"d79749c5-ad6d-49d1-b919-496b1223213f"}}]
14:59:55 INFO - 1498859995331 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d79749c5-ad6d-49d1-b919-496b1223213f"}]
14:59:55 INFO - 1498859995580 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:59:55 INFO - 1498859995581 Marionette TRACE 370 <- [1,4,null,{}]
14:59:55 INFO - 1498859995583 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d79749c5-ad6d-49d1-b919-496b1223213f","name":"selected"}]
14:59:55 INFO - 1498859995587 Marionette TRACE 370 <- [1,5,null,{"value":true}]
14:59:55 INFO - 1498859995589 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')","sandbox":"simpletest","line":328}]
14:59:55 INFO - TEST-END: test_click.py TestClick.test_container_element1498859995592 Marionette TRACE 370 <- [1,6,null,{"value":null}]
14:59:55 INFO - 1498859995594 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
14:59:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 358ms
14:59:55 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
14:59:55 INFO - 1498859995595 Marionette TRACE 370 <- [1,7,null,{}]
14:59:55 INFO - 1498859995597 Marionette DEBUG Closed connection 370
14:59:55 INFO - 1498859995597 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49743
14:59:55 INFO - 1498859995598 Marionette DEBUG Closed connection 371
14:59:55 INFO - 1498859995599 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49744
14:59:55 INFO - 1498859995600 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:55 INFO - 1498859995600 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995603 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995607 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9d1a4392-4441-4f5c-9adc-ceb3a8141c2c","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995608 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
14:59:55 INFO - 1498859995609 Marionette TRACE 372 <- [1,2,null,{}]
14:59:55 INFO - 1498859995611 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:59:55 INFO - 1498859995612 Marionette TRACE 372 <- [1,3,null,{}]
14:59:55 INFO - 1498859995613 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
14:59:55 INFO - 1498859995613 Marionette TRACE 372 <- [1,4,null,{}]
14:59:55 INFO - 1498859995615 Marionette TRACE 372 -> [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}]
14:59:55 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498859995619 Marionette TRACE 372 <- [1,5,null,{"value":null}]
14:59:55 INFO - 1498859995620 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
14:59:55 INFO - 1498859995622 Marionette TRACE 372 <- [1,6,null,{}]
14:59:55 INFO - 1498859995623 Marionette DEBUG Closed connection 372
14:59:55 INFO - 1498859995624 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49745
14:59:55 INFO - 1498859995625 Marionette DEBUG Closed connection 373
14:59:55 INFO - 1498859995625 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49746
14:59:55 INFO - 1498859995626 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:55 INFO - 1498859995627 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995630 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995633 Marionette TRACE 374 <- [1,1,null,{"sessionId":"024c4d83-1ec4-4be5-8a79-4adc41d25dc0","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995635 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
14:59:55 INFO - 1498859995636 Marionette TRACE 374 <- [1,2,null,{}]
14:59:55 INFO - 1498859995638 Marionette DEBUG Closed connection 374
14:59:55 INFO - 1498859995638 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49747
14:59:55 INFO - 1498859995639 Marionette DEBUG Closed connection 375
14:59:55 INFO - 1498859995640 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49748
14:59:55 INFO - 1498859995641 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:59:55 INFO - 1498859995641 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995644 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995648 Marionette TRACE 376 <- [1,1,null,{"sessionId":"1e9664ff-e706-4a44-a95c-dedf507c00fa","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995650 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%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"}]
14:59:55 INFO - 1498859995652 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"
14:59:55 INFO - 1498859995654 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"
14:59:55 INFO - 1498859995654 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"
14:59:55 INFO - 1498859995661 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"
14:59:55 INFO - 1498859995665 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"
14:59:55 INFO - 1498859995672 Marionette TRACE 376 <- [1,2,null,{}]
14:59:55 INFO - 1498859995676 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:59:55 INFO - 1498859995683 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8296bdb6-3822-4cd0-8f60-db6630aaaf65","ELEMENT":"8296bdb6-3822-4cd0-8f60-db6630aaaf65"}}]
14:59:55 INFO - 1498859995685 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8296bdb6-3822-4cd0-8f60-db6630aaaf65"}]
14:59:55 INFO - 1498859995927 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:59:55 INFO - 1498859995928 Marionette TRACE 376 <- [1,4,null,{}]
14:59:55 INFO - 1498859995930 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"8296bdb6-3822-4cd0-8f60-db6630aaaf65","name":"selected"}]
14:59:55 INFO - 1498859995934 Marionette TRACE 376 <- [1,5,null,{"value":true}]
14:59:55 INFO - 1498859995937 Marionette TRACE 376 -> [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}]
14:59:55 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498859995940 Marionette TRACE 376 <- [1,6,null,{"value":null}]
14:59:55 INFO - 1498859995941 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
14:59:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 348ms
14:59:55 INFO - TEST-START | test_click.py TestClick.test_css_transforms
14:59:55 INFO - 1498859995942 Marionette TRACE 376 <- [1,7,null,{}]
14:59:55 INFO - 1498859995945 Marionette DEBUG Closed connection 376
14:59:55 INFO - 1498859995946 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49749
14:59:55 INFO - 1498859995948 Marionette DEBUG Closed connection 377
14:59:55 INFO - 1498859995948 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49750
14:59:55 INFO - 1498859995949 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:55 INFO - 1498859995952 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:55 INFO - 1498859995955 Marionette DEBUG Register listener.js for window 2147483649
14:59:55 INFO - 1498859995959 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e28c56ee-5d5c-4b23-9dcb-f95f0fb54d42","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:55 INFO - 1498859995960 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
14:59:55 INFO - 1498859995961 Marionette TRACE 378 <- [1,2,null,{}]
14:59:55 INFO - 1498859995962 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:59:55 INFO - 1498859995962 Marionette TRACE 378 <- [1,3,null,{}]
14:59:55 INFO - 1498859995963 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
14:59:55 INFO - 1498859995964 Marionette TRACE 378 <- [1,4,null,{}]
14:59:55 INFO - 1498859995965 Marionette TRACE 378 -> [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}]
14:59:55 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498859995968 Marionette TRACE 378 <- [1,5,null,{"value":null}]
14:59:55 INFO - 1498859995969 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
14:59:55 INFO - 1498859995971 Marionette TRACE 378 <- [1,6,null,{}]
14:59:55 INFO - 1498859995972 Marionette DEBUG Closed connection 378
14:59:55 INFO - 1498859995973 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49751
14:59:55 INFO - 1498859995974 Marionette DEBUG Closed connection 379
14:59:56 INFO - 1498859995974 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49752
14:59:56 INFO - 1498859995975 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:56 INFO - 1498859995976 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859995979 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859995982 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9c58341f-381b-4e9e-ac98-43ee3e6ce73d","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859995984 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
14:59:56 INFO - 1498859995985 Marionette TRACE 380 <- [1,2,null,{}]
14:59:56 INFO - 1498859995986 Marionette DEBUG Closed connection 380
14:59:56 INFO - 1498859995987 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49753
14:59:56 INFO - 1498859995988 Marionette DEBUG Closed connection 381
14:59:56 INFO - 1498859995988 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49754
14:59:56 INFO - 1498859995990 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:59:56 INFO - 1498859995990 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859995993 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859995997 Marionette TRACE 382 <- [1,1,null,{"sessionId":"406a7c86-4b24-44ba-a864-5f286c7fbecc","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859995998 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%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"}]
14:59:56 INFO - 1498859996000 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"
14:59:56 INFO - 1498859996002 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"
14:59:56 INFO - 1498859996002 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"
14:59:56 INFO - 1498859996012 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"
14:59:56 INFO - 1498859996014 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"
14:59:56 INFO - 1498859996021 Marionette TRACE 382 <- [1,2,null,{}]
14:59:56 INFO - 1498859996025 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
14:59:56 INFO - 1498859996031 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"44aec74f-d236-48ef-b78b-f947ec0e181e","ELEMENT":"44aec74f-d236-48ef-b78b-f947ec0e181e"}}]
14:59:56 INFO - 1498859996032 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"44aec74f-d236-48ef-b78b-f947ec0e181e"}]
14:59:56 INFO - 1498859996277 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:59:56 INFO - 1498859996278 Marionette TRACE 382 <- [1,4,null,{}]
14:59:56 INFO - 1498859996280 Marionette TRACE 382 -> [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}]
14:59:56 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498859996283 Marionette TRACE 382 <- [1,5,null,{"value":null}]
14:59:56 INFO - 1498859996284 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
14:59:56 INFO - 1498859996286 Marionette TRACE 382 <- [1,6,null,{}]
14:59:56 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 343ms
14:59:56 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
14:59:56 INFO - 1498859996287 Marionette DEBUG Closed connection 382
14:59:56 INFO - 1498859996288 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49755
14:59:56 INFO - 1498859996289 Marionette DEBUG Closed connection 383
14:59:56 INFO - 1498859996290 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49756
14:59:56 INFO - 1498859996291 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:56 INFO - 1498859996291 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996294 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996298 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f0c7b76a-51c4-482a-9d80-2e2184ccc17d","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996299 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
14:59:56 INFO - 1498859996299 Marionette TRACE 384 <- [1,2,null,{}]
14:59:56 INFO - 1498859996300 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:59:56 INFO - 1498859996301 Marionette TRACE 384 <- [1,3,null,{}]
14:59:56 INFO - 1498859996302 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
14:59:56 INFO - 1498859996302 Marionette TRACE 384 <- [1,4,null,{}]
14:59:56 INFO - 1498859996305 Marionette TRACE 384 -> [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}]
14:59:56 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498859996307 Marionette TRACE 384 <- [1,5,null,{"value":null}]
14:59:56 INFO - 1498859996308 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
14:59:56 INFO - 1498859996311 Marionette TRACE 384 <- [1,6,null,{}]
14:59:56 INFO - 1498859996313 Marionette DEBUG Closed connection 384
14:59:56 INFO - 1498859996314 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49757
14:59:56 INFO - 1498859996315 Marionette DEBUG Closed connection 385
14:59:56 INFO - 1498859996316 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49758
14:59:56 INFO - 1498859996317 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:56 INFO - 1498859996318 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996321 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996324 Marionette TRACE 386 <- [1,1,null,{"sessionId":"22e17fb2-bc23-4f31-b995-759ed52b8fe5","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996325 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
14:59:56 INFO - 1498859996327 Marionette TRACE 386 <- [1,2,null,{}]
14:59:56 INFO - 1498859996328 Marionette DEBUG Closed connection 386
14:59:56 INFO - 1498859996329 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49759
14:59:56 INFO - 1498859996330 Marionette DEBUG Closed connection 387
14:59:56 INFO - 1498859996331 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49760
14:59:56 INFO - 1498859996332 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:59:56 INFO - 1498859996333 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996336 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996339 Marionette TRACE 388 <- [1,1,null,{"sessionId":"a70fff2a-80fa-44ae-92aa-f1a774046acc","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996340 Marionette TRACE 388 -> [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"}]
14:59:56 INFO - 1498859996342 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"
14:59:56 INFO - 1498859996344 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"
14:59:56 INFO - 1498859996344 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"
14:59:56 INFO - 1498859996350 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"
14:59:56 INFO - 1498859996354 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"
14:59:56 INFO - 1498859996371 Marionette TRACE 388 <- [1,2,null,{}]
14:59:56 INFO - 1498859996383 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
14:59:56 INFO - 1498859996388 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e87ee50e-6ae0-401f-924f-b75902413d75","ELEMENT":"e87ee50e-6ae0-401f-924f-b75902413d75"}}]
14:59:56 INFO - 1498859996389 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"e87ee50e-6ae0-401f-924f-b75902413d75"}]
14:59:56 INFO - 1498859996635 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:59:56 INFO - 1498859996636 Marionette TRACE 388 <- [1,4,null,{}]
14:59:56 INFO - 1498859996638 Marionette TRACE 388 -> [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}]
14:59:56 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498859996641 Marionette TRACE 388 <- [1,5,null,{"value":null}]
14:59:56 INFO - 1498859996642 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
14:59:56 INFO - 1498859996643 Marionette TRACE 388 <- [1,6,null,{}]
14:59:56 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 358ms
14:59:56 INFO - TEST-START | test_click.py TestClick.test_input_file
14:59:56 INFO - 1498859996645 Marionette DEBUG Closed connection 388
14:59:56 INFO - 1498859996646 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49761
14:59:56 INFO - 1498859996647 Marionette DEBUG Closed connection 389
14:59:56 INFO - 1498859996648 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49762
14:59:56 INFO - 1498859996649 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:56 INFO - 1498859996649 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996652 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996655 Marionette TRACE 390 <- [1,1,null,{"sessionId":"cee56f81-cab4-4cbe-95f1-d31ebe58a76f","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996657 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
14:59:56 INFO - 1498859996657 Marionette TRACE 390 <- [1,2,null,{}]
14:59:56 INFO - 1498859996658 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:59:56 INFO - 1498859996659 Marionette TRACE 390 <- [1,3,null,{}]
14:59:56 INFO - 1498859996660 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
14:59:56 INFO - 1498859996661 Marionette TRACE 390 <- [1,4,null,{}]
14:59:56 INFO - 1498859996662 Marionette TRACE 390 -> [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}]
14:59:56 INFO - TEST-START: test_click.py TestClick.test_input_file1498859996665 Marionette TRACE 390 <- [1,5,null,{"value":null}]
14:59:56 INFO - 1498859996666 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
14:59:56 INFO - 1498859996668 Marionette TRACE 390 <- [1,6,null,{}]
14:59:56 INFO - 1498859996669 Marionette DEBUG Closed connection 390
14:59:56 INFO - 1498859996670 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49763
14:59:56 INFO - 1498859996671 Marionette DEBUG Closed connection 391
14:59:56 INFO - 1498859996671 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49764
14:59:56 INFO - 1498859996672 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:56 INFO - 1498859996673 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996676 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996679 Marionette TRACE 392 <- [1,1,null,{"sessionId":"380de27b-5d2c-4dc8-8058-0e1b2d8322fe","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996680 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
14:59:56 INFO - 1498859996681 Marionette TRACE 392 <- [1,2,null,{}]
14:59:56 INFO - 1498859996683 Marionette DEBUG Closed connection 392
14:59:56 INFO - 1498859996683 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49765
14:59:56 INFO - 1498859996684 Marionette DEBUG Closed connection 393
14:59:56 INFO - 1498859996685 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49766
14:59:56 INFO - 1498859996686 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:59:56 INFO - 1498859996686 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996689 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996692 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2cde00b8-1ec7-4df6-b1af-209ad4670b17","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996694 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
14:59:56 INFO - 1498859996695 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"
14:59:56 INFO - 1498859996699 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"
14:59:56 INFO - 1498859996700 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"
14:59:56 INFO - 1498859996708 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:59:56 INFO - 1498859996715 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:59:56 INFO - 1498859996718 Marionette TRACE 394 <- [1,2,null,{}]
14:59:56 INFO - 1498859996721 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
14:59:56 INFO - 1498859996724 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8501f28-676a-4d51-ae39-d6c8f1ea6248","ELEMENT":"e8501f28-676a-4d51-ae39-d6c8f1ea6248"}}]
14:59:56 INFO - 1498859996725 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"e8501f28-676a-4d51-ae39-d6c8f1ea6248"}]
14:59:56 INFO - 1498859996756 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
14:59:56 INFO - 1498859996758 Marionette TRACE 394 -> [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}]
14:59:56 INFO - TEST-END: test_click.py TestClick.test_input_file1498859996761 Marionette TRACE 394 <- [1,5,null,{"value":null}]
14:59:56 INFO - 1498859996762 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
14:59:56 INFO - 1498859996764 Marionette TRACE 394 <- [1,6,null,{}]
14:59:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 120ms
14:59:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
14:59:56 INFO - 1498859996765 Marionette DEBUG Closed connection 394
14:59:56 INFO - 1498859996766 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49767
14:59:56 INFO - 1498859996767 Marionette DEBUG Closed connection 395
14:59:56 INFO - 1498859996767 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49768
14:59:56 INFO - 1498859996769 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:56 INFO - 1498859996769 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996772 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996776 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6d92d76c-5fd8-42c0-b430-520baf409369","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996777 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
14:59:56 INFO - 1498859996778 Marionette TRACE 396 <- [1,2,null,{}]
14:59:56 INFO - 1498859996779 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:59:56 INFO - 1498859996779 Marionette TRACE 396 <- [1,3,null,{}]
14:59:56 INFO - 1498859996780 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
14:59:56 INFO - 1498859996780 Marionette TRACE 396 <- [1,4,null,{}]
14:59:56 INFO - 1498859996782 Marionette TRACE 396 -> [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}]
14:59:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498859996785 Marionette TRACE 396 <- [1,5,null,{"value":null}]
14:59:56 INFO - 1498859996786 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
14:59:56 INFO - 1498859996788 Marionette TRACE 396 <- [1,6,null,{}]
14:59:56 INFO - 1498859996789 Marionette DEBUG Closed connection 396
14:59:56 INFO - 1498859996790 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49769
14:59:56 INFO - 1498859996791 Marionette DEBUG Closed connection 397
14:59:56 INFO - 1498859996791 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49770
14:59:56 INFO - 1498859996792 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:56 INFO - 1498859996793 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996796 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996806 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a5403853-1108-4da2-bd6a-98f736cfbfec","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996807 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
14:59:56 INFO - 1498859996809 Marionette TRACE 398 <- [1,2,null,{}]
14:59:56 INFO - 1498859996811 Marionette DEBUG Closed connection 398
14:59:56 INFO - 1498859996811 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49771
14:59:56 INFO - 1498859996812 Marionette DEBUG Closed connection 399
14:59:56 INFO - 1498859996813 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49772
14:59:56 INFO - 1498859996814 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:59:56 INFO - 1498859996815 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:56 INFO - 1498859996818 Marionette DEBUG Register listener.js for window 2147483649
14:59:56 INFO - 1498859996821 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c79c3e88-cc02-4898-a3d5-ef86dd43d8f2","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:56 INFO - 1498859996822 Marionette TRACE 400 -> [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"}]
14:59:56 INFO - 1498859996824 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:59:56 INFO - 1498859996826 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:59:56 INFO - 1498859996827 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:59:56 INFO - 1498859996834 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"
14:59:56 INFO - 1498859996837 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"
14:59:56 INFO - 1498859996838 Marionette TRACE 400 <- [1,2,null,{}]
14:59:56 INFO - 1498859996841 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
14:59:56 INFO - 1498859996848 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"09881b7b-d8f1-4390-b32d-d64c51e858c2","ELEMENT":"09881b7b-d8f1-4390-b32d-d64c51e858c2"}}]
14:59:56 INFO - 1498859996849 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
14:59:56 INFO - 1498859996852 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"100d459b-3ba6-4144-bc16-01c72b457f44","ELEMENT":"100d459b-3ba6-4144-bc16-01c72b457f44"}}]
14:59:56 INFO - 1498859996853 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"09881b7b-d8f1-4390-b32d-d64c51e858c2"}]
14:59:57 INFO - 1498859997098 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:59:57 INFO - 1498859997099 Marionette TRACE 400 <- [1,5,null,{}]
14:59:57 INFO - 1498859997100 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"100d459b-3ba6-4144-bc16-01c72b457f44"}]
14:59:57 INFO - 1498859997117 Marionette TRACE 400 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
14:59:57 INFO - 1498859997119 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
14:59:57 INFO - 1498859997122 Marionette TRACE 400 <- [1,7,null,{"value":false}]
14:59:57 INFO - 1498859997123 Marionette TRACE 400 -> [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}]
14:59:57 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498859997126 Marionette TRACE 400 <- [1,8,null,{"value":null}]
14:59:57 INFO - 1498859997127 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
14:59:57 INFO - 1498859997129 Marionette TRACE 400 <- [1,9,null,{}]
14:59:57 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 365ms
14:59:57 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
14:59:57 INFO - 1498859997131 Marionette DEBUG Closed connection 400
14:59:57 INFO - 1498859997131 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49773
14:59:57 INFO - 1498859997132 Marionette DEBUG Closed connection 401
14:59:57 INFO - 1498859997133 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49774
14:59:57 INFO - 1498859997134 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:57 INFO - 1498859997134 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:57 INFO - 1498859997137 Marionette DEBUG Register listener.js for window 2147483649
14:59:57 INFO - 1498859997140 Marionette TRACE 402 <- [1,1,null,{"sessionId":"f310ee4e-6bcb-4b0b-add5-f31c7ec1444d","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:57 INFO - 1498859997142 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
14:59:57 INFO - 1498859997142 Marionette TRACE 402 <- [1,2,null,{}]
14:59:57 INFO - 1498859997143 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:59:57 INFO - 1498859997144 Marionette TRACE 402 <- [1,3,null,{}]
14:59:57 INFO - 1498859997145 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
14:59:57 INFO - 1498859997145 Marionette TRACE 402 <- [1,4,null,{}]
14:59:57 INFO - 1498859997147 Marionette TRACE 402 -> [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}]
14:59:57 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498859997150 Marionette TRACE 402 <- [1,5,null,{"value":null}]
14:59:57 INFO - 1498859997151 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
14:59:57 INFO - 1498859997152 Marionette TRACE 402 <- [1,6,null,{}]
14:59:57 INFO - 1498859997154 Marionette DEBUG Closed connection 402
14:59:57 INFO - 1498859997154 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49775
14:59:57 INFO - 1498859997155 Marionette DEBUG Closed connection 403
14:59:57 INFO - 1498859997156 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49776
14:59:57 INFO - 1498859997157 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:59:57 INFO - 1498859997157 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:57 INFO - 1498859997160 Marionette DEBUG Register listener.js for window 2147483649
14:59:57 INFO - 1498859997164 Marionette TRACE 404 <- [1,1,null,{"sessionId":"0dbd573c-1abd-43b8-b9f6-5eea3703a9e7","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:57 INFO - 1498859997165 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
14:59:57 INFO - 1498859997166 Marionette TRACE 404 <- [1,2,null,{}]
14:59:57 INFO - 1498859997168 Marionette DEBUG Closed connection 404
14:59:57 INFO - 1498859997168 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49777
14:59:57 INFO - 1498859997169 Marionette DEBUG Closed connection 405
14:59:57 INFO - 1498859997170 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49778
14:59:57 INFO - 1498859997171 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:59:57 INFO - 1498859997171 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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}
14:59:57 INFO - 1498859997174 Marionette DEBUG Register listener.js for window 2147483649
14:59:57 INFO - 1498859997177 Marionette TRACE 406 <- [1,1,null,{"sessionId":"1e1195d3-dd43-48a2-ad35-2848ae71351a","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":4056,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmpyr3ztz.mozrunner","moz:accessibilityChecks":false}}]
14:59:57 INFO - 1498859997179 Marionette TRACE 406 -> [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"}]
14:59:57 INFO - 1498859997181 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"
14:59:57 INFO - 1498859997184 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"
14:59:57 INFO - 1498859997184 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"
14:59:57 INFO - 1498859997194 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"
14:59:57 INFO - 1498859997195 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"
14:59:57 INFO - 1498859997201 Marionette TRACE 406 <- [1,2,null,{}]
14:59:57 INFO - 1498859997205 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
14:59:57 INFO - 1498859997208 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"716c65ac-cbb0-43b8-9704-fec705d5b0ab","ELEMENT":"716c65ac-cbb0-43b8-9704-fec705d5b0ab"}}]
14:59:57 INFO - 1498859997213 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"716c65ac-cbb0-43b8-9704-fec705d5b0ab"}]
14:59:57 INFO - 1498859997238 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
14:59:57 INFO - 1498859997239 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"716c65ac-cbb0-43b8-9704-fec705d5b0ab"}]
14:59:57 INFO - 1498859997256 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element