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]
13:06:09 INFO - 1499717169217 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}]
13:06:09 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499717169219 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:06:09 INFO - 1499717169220 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:06:09 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 136ms
13:06:09 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:06:09 INFO - 1499717169222 Marionette TRACE 352 <- [1,6,null,{}]
13:06:09 INFO - 1499717169223 Marionette DEBUG Closed connection 352
13:06:09 INFO - 1499717169224 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49775
13:06:09 INFO - 1499717169225 Marionette DEBUG Closed connection 353
13:06:09 INFO - 1499717169226 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49776
13:06:09 INFO - 1499717169227 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:09 INFO - 1499717169227 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169230 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169234 Marionette TRACE 354 <- [1,1,null,{"sessionId":"438b5909-25f5-4a1d-9802-d359e401901b","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169235 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:06:09 INFO - 1499717169236 Marionette TRACE 354 <- [1,2,null,{}]
13:06:09 INFO - 1499717169236 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:06:09 INFO - 1499717169237 Marionette TRACE 354 <- [1,3,null,{}]
13:06:09 INFO - 1499717169238 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:06:09 INFO - 1499717169238 Marionette TRACE 354 <- [1,4,null,{}]
13:06:09 INFO - 1499717169240 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}]
13:06:09 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499717169243 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:06:09 INFO - 1499717169244 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:06:09 INFO - 1499717169245 Marionette TRACE 354 <- [1,6,null,{}]
13:06:09 INFO - 1499717169247 Marionette DEBUG Closed connection 354
13:06:09 INFO - 1499717169248 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49777
13:06:09 INFO - 1499717169248 Marionette DEBUG Closed connection 355
13:06:09 INFO - 1499717169249 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49778
13:06:09 INFO - 1499717169250 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:09 INFO - 1499717169250 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169253 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169257 Marionette TRACE 356 <- [1,1,null,{"sessionId":"3712bd03-05a7-4a6c-8058-282f95f06b02","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169258 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:06:09 INFO - 1499717169259 Marionette TRACE 356 <- [1,2,null,{}]
13:06:09 INFO - 1499717169261 Marionette DEBUG Closed connection 356
13:06:09 INFO - 1499717169262 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49779
13:06:09 INFO - 1499717169262 Marionette DEBUG Closed connection 357
13:06:09 INFO - 1499717169263 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49780
13:06:09 INFO - 1499717169264 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:06:09 INFO - 1499717169272 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169275 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169279 Marionette TRACE 358 <- [1,1,null,{"sessionId":"6c494ce8-4a27-4c79-8e8f-21a201e3115c","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169281 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49335/clicks.html"}]
13:06:09 INFO - 1499717169283 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/hidden.html"
13:06:09 INFO - 1499717169290 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/hidden.html"
13:06:09 INFO - 1499717169290 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/hidden.html"
13:06:09 INFO - 1499717169302 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/clicks.html"
13:06:09 INFO - 1499717169303 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/clicks.html"
13:06:09 INFO - 1499717169306 Marionette TRACE 358 <- [1,2,null,{}]
13:06:09 INFO - 1499717169317 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:06:09 INFO - 1499717169320 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d706a476-e3ed-46cc-b747-bbe64d22f8c5","ELEMENT":"d706a476-e3ed-46cc-b747-bbe64d22f8c5"}}]
13:06:09 INFO - 1499717169321 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"d706a476-e3ed-46cc-b747-bbe64d22f8c5"}]
13:06:09 INFO - 1499717169364 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/clicks.html"
13:06:09 INFO - 1499717169370 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/clicks.html"
13:06:09 INFO - 1499717169371 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/clicks.html"
13:06:09 INFO - 1499717169378 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/test.html"
13:06:09 INFO - 1499717169382 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/test.html"
13:06:09 INFO - 1499717169386 Marionette TRACE 358 <- [1,4,null,{}]
13:06:09 INFO - 1499717169390 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:06:09 INFO - 1499717169392 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1b0da4bc-8878-4eb6-84a7-3f1c43ece998","ELEMENT":"1b0da4bc-8878-4eb6-84a7-3f1c43ece998"}}]
13:06:09 INFO - 1499717169393 Marionette TRACE 358 -> [0,6,"getTitle",{}]
13:06:09 INFO - 1499717169393 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
13:06:09 INFO - 1499717169396 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}]
13:06:09 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499717169402 Marionette TRACE 358 <- [1,7,null,{"value":null}]
13:06:09 INFO - 1499717169404 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
13:06:09 INFO - 1499717169405 Marionette TRACE 358 <- [1,8,null,{}]
13:06:09 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 183ms
13:06:09 INFO - TEST-START | test_click.py TestClick.test_container_element
13:06:09 INFO - 1499717169407 Marionette DEBUG Closed connection 358
13:06:09 INFO - 1499717169408 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49783
13:06:09 INFO - 1499717169409 Marionette DEBUG Closed connection 359
13:06:09 INFO - 1499717169409 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49784
13:06:09 INFO - 1499717169410 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:09 INFO - 1499717169411 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169414 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169418 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d64e4593-da35-4cf2-8504-e52783d67931","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169419 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:06:09 INFO - 1499717169419 Marionette TRACE 360 <- [1,2,null,{}]
13:06:09 INFO - 1499717169420 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:06:09 INFO - 1499717169421 Marionette TRACE 360 <- [1,3,null,{}]
13:06:09 INFO - 1499717169422 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:06:09 INFO - 1499717169422 Marionette TRACE 360 <- [1,4,null,{}]
13:06:09 INFO - 1499717169424 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}]
13:06:09 INFO - TEST-START: test_click.py TestClick.test_container_element1499717169429 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:06:09 INFO - 1499717169431 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:06:09 INFO - 1499717169432 Marionette TRACE 360 <- [1,6,null,{}]
13:06:09 INFO - 1499717169434 Marionette DEBUG Closed connection 360
13:06:09 INFO - 1499717169435 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49785
13:06:09 INFO - 1499717169436 Marionette DEBUG Closed connection 361
13:06:09 INFO - 1499717169436 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49786
13:06:09 INFO - 1499717169437 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:09 INFO - 1499717169438 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169441 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169444 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f48ad93f-2b51-4c20-801a-6df089789e5f","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169445 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
13:06:09 INFO - 1499717169447 Marionette TRACE 362 <- [1,2,null,{}]
13:06:09 INFO - 1499717169449 Marionette DEBUG Closed connection 362
13:06:09 INFO - 1499717169449 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49787
13:06:09 INFO - 1499717169450 Marionette DEBUG Closed connection 363
13:06:09 INFO - 1499717169450 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49788
13:06:09 INFO - 1499717169452 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:06:09 INFO - 1499717169452 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169455 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169458 Marionette TRACE 364 <- [1,1,null,{"sessionId":"65c85ea3-e9b4-4d3b-8101-7f23c8a1d2a2","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169459 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"}]
13:06:09 INFO - 1499717169461 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/test.html"
13:06:09 INFO - 1499717169463 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/test.html"
13:06:09 INFO - 1499717169463 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/test.html"
13:06:09 INFO - 1499717169470 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"
13:06:09 INFO - 1499717169474 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"
13:06:09 INFO - 1499717169487 Marionette TRACE 364 <- [1,2,null,{}]
13:06:09 INFO - 1499717169490 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:06:09 INFO - 1499717169495 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"85482a42-431e-4500-92ad-7c2890da2484","ELEMENT":"85482a42-431e-4500-92ad-7c2890da2484"}}]
13:06:09 INFO - 1499717169500 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"85482a42-431e-4500-92ad-7c2890da2484"}]
13:06:09 INFO - 1499717169760 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:06:09 INFO - 1499717169761 Marionette TRACE 364 <- [1,4,null,{}]
13:06:09 INFO - 1499717169762 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"85482a42-431e-4500-92ad-7c2890da2484","name":"selected"}]
13:06:09 INFO - 1499717169768 Marionette TRACE 364 <- [1,5,null,{"value":true}]
13:06:09 INFO - 1499717169769 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}]
13:06:09 INFO - TEST-END: test_click.py TestClick.test_container_element1499717169772 Marionette TRACE 364 <- [1,6,null,{"value":null}]
13:06:09 INFO - 1499717169773 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
13:06:09 INFO - 1499717169775 Marionette TRACE 364 <- [1,7,null,{}]
13:06:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
13:06:09 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:06:09 INFO - 1499717169776 Marionette DEBUG Closed connection 364
13:06:09 INFO - 1499717169777 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49789
13:06:09 INFO - 1499717169778 Marionette DEBUG Closed connection 365
13:06:09 INFO - 1499717169779 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49790
13:06:09 INFO - 1499717169781 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:09 INFO - 1499717169782 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169785 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169788 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9773b268-b34c-4eab-adde-96ffc11911ce","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169789 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
13:06:09 INFO - 1499717169790 Marionette TRACE 366 <- [1,2,null,{}]
13:06:09 INFO - 1499717169791 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:06:09 INFO - 1499717169791 Marionette TRACE 366 <- [1,3,null,{}]
13:06:09 INFO - 1499717169792 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
13:06:09 INFO - 1499717169793 Marionette TRACE 366 <- [1,4,null,{}]
13:06:09 INFO - 1499717169795 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}]
13:06:09 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499717169798 Marionette TRACE 366 <- [1,5,null,{"value":null}]
13:06:09 INFO - 1499717169799 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
13:06:09 INFO - 1499717169800 Marionette TRACE 366 <- [1,6,null,{}]
13:06:09 INFO - 1499717169802 Marionette DEBUG Closed connection 366
13:06:09 INFO - 1499717169802 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49791
13:06:09 INFO - 1499717169803 Marionette DEBUG Closed connection 367
13:06:09 INFO - 1499717169804 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49792
13:06:09 INFO - 1499717169805 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:09 INFO - 1499717169805 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169808 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169812 Marionette TRACE 368 <- [1,1,null,{"sessionId":"67850d0b-5115-48c3-9929-f4823eb32efe","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169813 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
13:06:09 INFO - 1499717169814 Marionette TRACE 368 <- [1,2,null,{}]
13:06:09 INFO - 1499717169816 Marionette DEBUG Closed connection 368
13:06:09 INFO - 1499717169817 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49793
13:06:09 INFO - 1499717169818 Marionette DEBUG Closed connection 369
13:06:09 INFO - 1499717169818 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49794
13:06:09 INFO - 1499717169819 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:06:09 INFO - 1499717169820 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:09 INFO - 1499717169823 Marionette DEBUG Register listener.js for window 4294967297
13:06:09 INFO - 1499717169826 Marionette TRACE 370 <- [1,1,null,{"sessionId":"0706dd26-f408-41ca-8dd8-9b5802726353","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:09 INFO - 1499717169827 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"}]
13:06:09 INFO - 1499717169829 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"
13:06:09 INFO - 1499717169831 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"
13:06:09 INFO - 1499717169831 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"
13:06:09 INFO - 1499717169838 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"
13:06:09 INFO - 1499717169841 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"
13:06:09 INFO - 1499717169844 Marionette TRACE 370 <- [1,2,null,{}]
13:06:09 INFO - 1499717169851 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:06:09 INFO - 1499717169854 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9c1fe2c4-d0aa-40e2-bcf3-4bd79bbeae19","ELEMENT":"9c1fe2c4-d0aa-40e2-bcf3-4bd79bbeae19"}}]
13:06:09 INFO - 1499717169855 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9c1fe2c4-d0aa-40e2-bcf3-4bd79bbeae19"}]
13:06:10 INFO - 1499717170098 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:06:10 INFO - 1499717170099 Marionette TRACE 370 <- [1,4,null,{}]
13:06:10 INFO - 1499717170101 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9c1fe2c4-d0aa-40e2-bcf3-4bd79bbeae19","name":"selected"}]
13:06:10 INFO - 1499717170106 Marionette TRACE 370 <- [1,5,null,{"value":true}]
13:06:10 INFO - 1499717170107 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}]
13:06:10 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499717170110 Marionette TRACE 370 <- [1,6,null,{"value":null}]
13:06:10 INFO - 1499717170111 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
13:06:10 INFO - 1499717170113 Marionette TRACE 370 <- [1,7,null,{}]
13:06:10 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 338ms
13:06:10 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:06:10 INFO - 1499717170114 Marionette DEBUG Closed connection 370
13:06:10 INFO - 1499717170116 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49795
13:06:10 INFO - 1499717170116 Marionette DEBUG Closed connection 371
13:06:10 INFO - 1499717170117 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49796
13:06:10 INFO - 1499717170118 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:10 INFO - 1499717170118 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170121 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170125 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3b9aafc9-9ad5-4807-9efb-b75179b094ed","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170126 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
13:06:10 INFO - 1499717170126 Marionette TRACE 372 <- [1,2,null,{}]
13:06:10 INFO - 1499717170127 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:06:10 INFO - 1499717170128 Marionette TRACE 372 <- [1,3,null,{}]
13:06:10 INFO - 1499717170129 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
13:06:10 INFO - 1499717170129 Marionette TRACE 372 <- [1,4,null,{}]
13:06:10 INFO - 1499717170131 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}]
13:06:10 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499717170134 Marionette TRACE 372 <- [1,5,null,{"value":null}]
13:06:10 INFO - 1499717170135 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
13:06:10 INFO - 1499717170136 Marionette TRACE 372 <- [1,6,null,{}]
13:06:10 INFO - 1499717170138 Marionette DEBUG Closed connection 372
13:06:10 INFO - 1499717170139 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49797
13:06:10 INFO - 1499717170140 Marionette DEBUG Closed connection 373
13:06:10 INFO - 1499717170140 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49798
13:06:10 INFO - 1499717170141 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:10 INFO - 1499717170142 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170145 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170148 Marionette TRACE 374 <- [1,1,null,{"sessionId":"3ee0a5c9-eb1f-48c9-be3f-0711752009e8","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170149 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
13:06:10 INFO - 1499717170151 Marionette TRACE 374 <- [1,2,null,{}]
13:06:10 INFO - 1499717170152 Marionette DEBUG Closed connection 374
13:06:10 INFO - 1499717170153 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49799
13:06:10 INFO - 1499717170154 Marionette DEBUG Closed connection 375
13:06:10 INFO - 1499717170154 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49800
13:06:10 INFO - 1499717170155 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:06:10 INFO - 1499717170156 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170159 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170162 Marionette TRACE 376 <- [1,1,null,{"sessionId":"b50f779a-858e-4d42-998a-dc2830e03627","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170163 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"}]
13:06:10 INFO - 1499717170165 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"
13:06:10 INFO - 1499717170168 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"
13:06:10 INFO - 1499717170168 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"
13:06:10 INFO - 1499717170174 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"
13:06:10 INFO - 1499717170177 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"
13:06:10 INFO - 1499717170186 Marionette TRACE 376 <- [1,2,null,{}]
13:06:10 INFO - 1499717170189 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:06:10 INFO - 1499717170192 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"53ec1613-ec8b-4893-a0db-b19e2de9797f","ELEMENT":"53ec1613-ec8b-4893-a0db-b19e2de9797f"}}]
13:06:10 INFO - 1499717170193 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"53ec1613-ec8b-4893-a0db-b19e2de9797f"}]
13:06:10 INFO - 1499717170434 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:06:10 INFO - 1499717170451 Marionette TRACE 376 <- [1,4,null,{}]
13:06:10 INFO - 1499717170456 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}]
13:06:10 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499717170461 Marionette TRACE 376 <- [1,5,null,{"value":null}]
13:06:10 INFO - 1499717170464 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
13:06:10 INFO - 1499717170467 Marionette TRACE 376 <- [1,6,null,{}]
13:06:10 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 353ms
13:06:10 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:06:10 INFO - 1499717170473 Marionette DEBUG Closed connection 376
13:06:10 INFO - 1499717170474 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49801
13:06:10 INFO - 1499717170476 Marionette DEBUG Closed connection 377
13:06:10 INFO - 1499717170476 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49802
13:06:10 INFO - 1499717170478 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:10 INFO - 1499717170482 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170485 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170494 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9c117af5-7dcb-4c4d-9a40-6b516474016f","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170501 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
13:06:10 INFO - 1499717170502 Marionette TRACE 378 <- [1,2,null,{}]
13:06:10 INFO - 1499717170503 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:06:10 INFO - 1499717170505 Marionette TRACE 378 <- [1,3,null,{}]
13:06:10 INFO - 1499717170506 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
13:06:10 INFO - 1499717170507 Marionette TRACE 378 <- [1,4,null,{}]
13:06:10 INFO - 1499717170509 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}]
13:06:10 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499717170513 Marionette TRACE 378 <- [1,5,null,{"value":null}]
13:06:10 INFO - 1499717170514 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
13:06:10 INFO - 1499717170517 Marionette TRACE 378 <- [1,6,null,{}]
13:06:10 INFO - 1499717170519 Marionette DEBUG Closed connection 378
13:06:10 INFO - 1499717170520 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49803
13:06:10 INFO - 1499717170521 Marionette DEBUG Closed connection 379
13:06:10 INFO - 1499717170522 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49804
13:06:10 INFO - 1499717170524 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:10 INFO - 1499717170525 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170571 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170576 Marionette TRACE 380 <- [1,1,null,{"sessionId":"89c78a24-edb7-4a4d-9e78-8de987671be5","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170578 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
13:06:10 INFO - 1499717170581 Marionette TRACE 380 <- [1,2,null,{}]
13:06:10 INFO - 1499717170583 Marionette DEBUG Closed connection 380
13:06:10 INFO - 1499717170584 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49805
13:06:10 INFO - 1499717170585 Marionette DEBUG Closed connection 381
13:06:10 INFO - 1499717170586 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49806
13:06:10 INFO - 1499717170589 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:06:10 INFO - 1499717170591 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170593 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170598 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2a4a9613-4bcb-4153-9edf-d7e26614b63f","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170599 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"}]
13:06:10 INFO - 1499717170602 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"
13:06:10 INFO - 1499717170604 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"
13:06:10 INFO - 1499717170604 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"
13:06:10 INFO - 1499717170610 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"
13:06:10 INFO - 1499717170613 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"
13:06:10 INFO - 1499717170627 Marionette TRACE 382 <- [1,2,null,{}]
13:06:10 INFO - 1499717170636 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:06:10 INFO - 1499717170639 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf27978f-6a40-44a1-b85d-8aae62b3c50e","ELEMENT":"bf27978f-6a40-44a1-b85d-8aae62b3c50e"}}]
13:06:10 INFO - 1499717170640 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"bf27978f-6a40-44a1-b85d-8aae62b3c50e"}]
13:06:10 INFO - 1499717170890 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:06:10 INFO - 1499717170891 Marionette TRACE 382 <- [1,4,null,{}]
13:06:10 INFO - 1499717170893 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}]
13:06:10 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499717170896 Marionette TRACE 382 <- [1,5,null,{"value":null}]
13:06:10 INFO - 1499717170897 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
13:06:10 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 432ms
13:06:10 INFO - TEST-START | test_click.py TestClick.test_input_file
13:06:10 INFO - 1499717170899 Marionette TRACE 382 <- [1,6,null,{}]
13:06:10 INFO - 1499717170901 Marionette DEBUG Closed connection 382
13:06:10 INFO - 1499717170902 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49807
13:06:10 INFO - 1499717170903 Marionette DEBUG Closed connection 383
13:06:10 INFO - 1499717170904 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49808
13:06:10 INFO - 1499717170905 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:10 INFO - 1499717170906 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170909 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170913 Marionette TRACE 384 <- [1,1,null,{"sessionId":"0641656b-96e7-4327-818a-bb6d2dc0dbd0","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170915 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
13:06:10 INFO - 1499717170916 Marionette TRACE 384 <- [1,2,null,{}]
13:06:10 INFO - 1499717170917 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:06:10 INFO - 1499717170917 Marionette TRACE 384 <- [1,3,null,{}]
13:06:10 INFO - 1499717170918 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
13:06:10 INFO - 1499717170919 Marionette TRACE 384 <- [1,4,null,{}]
13:06:10 INFO - 1499717170921 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}]
13:06:10 INFO - TEST-START: test_click.py TestClick.test_input_file1499717170924 Marionette TRACE 384 <- [1,5,null,{"value":null}]
13:06:10 INFO - 1499717170925 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
13:06:10 INFO - 1499717170927 Marionette TRACE 384 <- [1,6,null,{}]
13:06:10 INFO - 1499717170929 Marionette DEBUG Closed connection 384
13:06:10 INFO - 1499717170929 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49809
13:06:10 INFO - 1499717170930 Marionette DEBUG Closed connection 385
13:06:10 INFO - 1499717170931 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49810
13:06:10 INFO - 1499717170932 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:10 INFO - 1499717170933 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170936 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170942 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cbc3dbf2-b128-419c-86a3-b516c4ae84d3","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170943 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
13:06:10 INFO - 1499717170945 Marionette TRACE 386 <- [1,2,null,{}]
13:06:10 INFO - 1499717170948 Marionette DEBUG Closed connection 386
13:06:10 INFO - 1499717170950 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49811
13:06:10 INFO - 1499717170951 Marionette DEBUG Closed connection 387
13:06:10 INFO - 1499717170952 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49812
13:06:10 INFO - 1499717170954 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:06:10 INFO - 1499717170955 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:10 INFO - 1499717170958 Marionette DEBUG Register listener.js for window 4294967297
13:06:10 INFO - 1499717170962 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e2365556-dee2-4570-8d9a-5815759922ac","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:10 INFO - 1499717170963 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:06:10 INFO - 1499717170966 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"
13:06:10 INFO - 1499717170967 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"
13:06:10 INFO - 1499717170968 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"
13:06:10 INFO - 1499717170974 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:06:10 INFO - 1499717170978 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:06:10 INFO - 1499717170989 Marionette TRACE 388 <- [1,2,null,{}]
13:06:10 INFO - 1499717170993 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:06:10 INFO - 1499717170996 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"34cbf383-fb71-4cb7-99ce-af64f2f17458","ELEMENT":"34cbf383-fb71-4cb7-99ce-af64f2f17458"}}]
13:06:11 INFO - 1499717171001 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"34cbf383-fb71-4cb7-99ce-af64f2f17458"}]
13:06:11 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 140ms
13:06:11 INFO - 1499717171031 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]
13:06:11 INFO - 1499717171033 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}]
13:06:11 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:06:11 INFO - TEST-END: test_click.py TestClick.test_input_file1499717171037 Marionette TRACE 388 <- [1,5,null,{"value":null}]
13:06:11 INFO - 1499717171038 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
13:06:11 INFO - 1499717171039 Marionette TRACE 388 <- [1,6,null,{}]
13:06:11 INFO - 1499717171041 Marionette DEBUG Closed connection 388
13:06:11 INFO - 1499717171043 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49813
13:06:11 INFO - 1499717171044 Marionette DEBUG Closed connection 389
13:06:11 INFO - 1499717171045 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49814
13:06:11 INFO - 1499717171046 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:11 INFO - 1499717171047 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:11 INFO - 1499717171050 Marionette DEBUG Register listener.js for window 4294967297
13:06:11 INFO - 1499717171053 Marionette TRACE 390 <- [1,1,null,{"sessionId":"74a1712d-0127-435e-9430-8164668dd05a","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:11 INFO - 1499717171055 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
13:06:11 INFO - 1499717171055 Marionette TRACE 390 <- [1,2,null,{}]
13:06:11 INFO - 1499717171056 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:06:11 INFO - 1499717171057 Marionette TRACE 390 <- [1,3,null,{}]
13:06:11 INFO - 1499717171058 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
13:06:11 INFO - 1499717171058 Marionette TRACE 390 <- [1,4,null,{}]
13:06:11 INFO - 1499717171060 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}]
13:06:11 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499717171064 Marionette TRACE 390 <- [1,5,null,{"value":null}]
13:06:11 INFO - 1499717171065 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
13:06:11 INFO - 1499717171066 Marionette TRACE 390 <- [1,6,null,{}]
13:06:11 INFO - 1499717171068 Marionette DEBUG Closed connection 390
13:06:11 INFO - 1499717171069 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49815
13:06:11 INFO - 1499717171069 Marionette DEBUG Closed connection 391
13:06:11 INFO - 1499717171070 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49816
13:06:11 INFO - 1499717171071 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:11 INFO - 1499717171072 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:11 INFO - 1499717171074 Marionette DEBUG Register listener.js for window 4294967297
13:06:11 INFO - 1499717171078 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d1bdca0b-cd75-4c05-8e93-7830c1e34a23","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:11 INFO - 1499717171079 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
13:06:11 INFO - 1499717171081 Marionette TRACE 392 <- [1,2,null,{}]
13:06:11 INFO - 1499717171083 Marionette DEBUG Closed connection 392
13:06:11 INFO - 1499717171083 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49817
13:06:11 INFO - 1499717171084 Marionette DEBUG Closed connection 393
13:06:11 INFO - 1499717171085 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49818
13:06:11 INFO - 1499717171086 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:06:11 INFO - 1499717171087 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:11 INFO - 1499717171090 Marionette DEBUG Register listener.js for window 4294967297
13:06:11 INFO - 1499717171093 Marionette TRACE 394 <- [1,1,null,{"sessionId":"8643b5f1-4a76-4bf9-b22c-94d17e56e987","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:11 INFO - 1499717171094 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"}]
13:06:11 INFO - 1499717171096 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:06:11 INFO - 1499717171098 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:06:11 INFO - 1499717171099 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:06:11 INFO - 1499717171106 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"
13:06:11 INFO - 1499717171109 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"
13:06:11 INFO - 1499717171120 Marionette TRACE 394 <- [1,2,null,{}]
13:06:11 INFO - 1499717171124 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:06:11 INFO - 1499717171126 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a8807b85-d3b6-4c01-b647-57e3b7b12867","ELEMENT":"a8807b85-d3b6-4c01-b647-57e3b7b12867"}}]
13:06:11 INFO - 1499717171127 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:06:11 INFO - 1499717171133 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bd3ba4ec-3463-4978-9218-e20b2614d6bb","ELEMENT":"bd3ba4ec-3463-4978-9218-e20b2614d6bb"}}]
13:06:11 INFO - 1499717171135 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a8807b85-d3b6-4c01-b647-57e3b7b12867"}]
13:06:11 INFO - 1499717171385 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:06:11 INFO - 1499717171386 Marionette TRACE 394 <- [1,5,null,{}]
13:06:11 INFO - 1499717171387 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"bd3ba4ec-3463-4978-9218-e20b2614d6bb"}]
13:06:11 INFO - 1499717171404 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]
13:06:11 INFO - 1499717171406 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:06:11 INFO - 1499717171409 Marionette TRACE 394 <- [1,7,null,{"value":false}]
13:06:11 INFO - 1499717171410 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}]
13:06:11 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499717171413 Marionette TRACE 394 <- [1,8,null,{"value":null}]
13:06:11 INFO - 1499717171414 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
13:06:11 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 375ms
13:06:11 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:06:11 INFO - 1499717171416 Marionette TRACE 394 <- [1,9,null,{}]
13:06:11 INFO - 1499717171417 Marionette DEBUG Closed connection 394
13:06:11 INFO - 1499717171418 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49819
13:06:11 INFO - 1499717171419 Marionette DEBUG Closed connection 395
13:06:11 INFO - 1499717171420 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49820
13:06:11 INFO - 1499717171421 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:11 INFO - 1499717171421 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:11 INFO - 1499717171424 Marionette DEBUG Register listener.js for window 4294967297
13:06:11 INFO - 1499717171428 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1ef7096a-290d-418c-bbeb-5ba2c95eb53e","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:11 INFO - 1499717171429 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
13:06:11 INFO - 1499717171430 Marionette TRACE 396 <- [1,2,null,{}]
13:06:11 INFO - 1499717171431 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:06:11 INFO - 1499717171431 Marionette TRACE 396 <- [1,3,null,{}]
13:06:11 INFO - 1499717171433 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
13:06:11 INFO - 1499717171433 Marionette TRACE 396 <- [1,4,null,{}]
13:06:11 INFO - 1499717171435 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}]
13:06:11 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499717171438 Marionette TRACE 396 <- [1,5,null,{"value":null}]
13:06:11 INFO - 1499717171439 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
13:06:11 INFO - 1499717171440 Marionette TRACE 396 <- [1,6,null,{}]
13:06:11 INFO - 1499717171442 Marionette DEBUG Closed connection 396
13:06:11 INFO - 1499717171444 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49821
13:06:11 INFO - 1499717171449 Marionette DEBUG Closed connection 397
13:06:11 INFO - 1499717171450 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49822
13:06:11 INFO - 1499717171451 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:06:11 INFO - 1499717171452 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:11 INFO - 1499717171457 Marionette DEBUG Register listener.js for window 4294967297
13:06:11 INFO - 1499717171462 Marionette TRACE 398 <- [1,1,null,{"sessionId":"3468bc6a-93b3-4b33-ab56-9ec826b2a69f","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:11 INFO - 1499717171463 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
13:06:11 INFO - 1499717171465 Marionette TRACE 398 <- [1,2,null,{}]
13:06:11 INFO - 1499717171466 Marionette DEBUG Closed connection 398
13:06:11 INFO - 1499717171467 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49823
13:06:11 INFO - 1499717171468 Marionette DEBUG Closed connection 399
13:06:11 INFO - 1499717171469 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49824
13:06:11 INFO - 1499717171470 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:06:11 INFO - 1499717171471 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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}
13:06:11 INFO - 1499717171474 Marionette DEBUG Register listener.js for window 4294967297
13:06:11 INFO - 1499717171477 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1b2c9c0c-bcb3-47a9-9c3c-528d1e7a88e3","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":2648,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-068.001\\AppData\\Local\\Temp\\tmp2q77mq.mozrunner","moz:accessibilityChecks":false}}]
13:06:11 INFO - 1499717171479 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"}]
13:06:11 INFO - 1499717171481 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"
13:06:11 INFO - 1499717171483 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"
13:06:11 INFO - 1499717171483 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"
13:06:11 INFO - 1499717171490 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"
13:06:11 INFO - 1499717171493 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"
13:06:11 INFO - 1499717171502 Marionette TRACE 400 <- [1,2,null,{}]
13:06:11 INFO - 1499717171506 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:06:11 INFO - 1499717171508 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c1ff08d1-03ef-4164-a0f3-651d124b60e3","ELEMENT":"c1ff08d1-03ef-4164-a0f3-651d124b60e3"}}]
13:06:11 INFO - 1499717171509 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c1ff08d1-03ef-4164-a0f3-651d124b60e3"}]
13:06:11 INFO - 1499717171535 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
13:06:11 INFO - 1499717171536 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c1ff08d1-03ef-4164-a0f3-651d124b60e3"}]
13:06:11 INFO - 1499717171554 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element