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]
06:53:46 INFO - 1499435626292 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}]
06:53:46 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499435626294 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:53:46 INFO - 1499435626295 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:53:46 INFO - 1499435626296 Marionette TRACE 352 <- [1,6,null,{}]
06:53:46 INFO - 1499435626298 Marionette DEBUG Closed connection 352
06:53:46 INFO - 1499435626299 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49760
06:53:46 INFO - 1499435626299 Marionette DEBUG Closed connection 353
06:53:46 INFO - 1499435626300 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49761
06:53:46 INFO - 1499435626301 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:46 INFO - 1499435626301 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626303 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626306 Marionette TRACE 354 <- [1,1,null,{"sessionId":"fa8eed87-8bf6-492d-a6d4-0e6f8091473d","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626307 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:53:46 INFO - 1499435626308 Marionette TRACE 354 <- [1,2,null,{}]
06:53:46 INFO - 1499435626309 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:46 INFO - 1499435626309 Marionette TRACE 354 <- [1,3,null,{}]
06:53:46 INFO - 1499435626310 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:46 INFO - 1499435626310 Marionette TRACE 354 <- [1,4,null,{}]
06:53:46 INFO - 1499435626312 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}]
06:53:46 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499435626314 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:53:46 INFO - 1499435626315 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:53:46 INFO - 1499435626316 Marionette TRACE 354 <- [1,6,null,{}]
06:53:46 INFO - 1499435626318 Marionette DEBUG Closed connection 354
06:53:46 INFO - 1499435626318 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49762
06:53:46 INFO - 1499435626319 Marionette DEBUG Closed connection 355
06:53:46 INFO - 1499435626319 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49763
06:53:46 INFO - 1499435626320 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:46 INFO - 1499435626321 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626323 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626332 Marionette TRACE 356 <- [1,1,null,{"sessionId":"b30009f5-3ac9-420a-871c-9967daeb9f26","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626334 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:53:46 INFO - 1499435626335 Marionette TRACE 356 <- [1,2,null,{}]
06:53:46 INFO - 1499435626336 Marionette DEBUG Closed connection 356
06:53:46 INFO - 1499435626337 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49764
06:53:46 INFO - 1499435626337 Marionette DEBUG Closed connection 357
06:53:46 INFO - 1499435626338 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49765
06:53:46 INFO - 1499435626339 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:46 INFO - 1499435626340 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626342 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626344 Marionette TRACE 358 <- [1,1,null,{"sessionId":"5a4c35cc-4ff3-4dd7-880e-1ebd1331a227","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626346 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49339/clicks.html"}]
06:53:46 INFO - 1499435626347 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/hidden.html"
06:53:46 INFO - 1499435626352 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/hidden.html"
06:53:46 INFO - 1499435626352 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/hidden.html"
06:53:46 INFO - 1499435626373 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49339/clicks.html"
06:53:46 INFO - 1499435626374 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49339/clicks.html"
06:53:46 INFO - 1499435626378 Marionette TRACE 358 <- [1,2,null,{}]
06:53:46 INFO - 1499435626384 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:53:46 INFO - 1499435626386 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0dd235fd-3072-4a5f-bf88-ad1accac40d4","ELEMENT":"0dd235fd-3072-4a5f-bf88-ad1accac40d4"}}]
06:53:46 INFO - 1499435626386 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0dd235fd-3072-4a5f-bf88-ad1accac40d4"}]
06:53:46 INFO - 1499435626422 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/clicks.html"
06:53:46 INFO - 1499435626430 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/clicks.html"
06:53:46 INFO - 1499435626430 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/clicks.html"
06:53:46 INFO - 1499435626441 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49339/test.html"
06:53:46 INFO - 1499435626444 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49339/test.html"
06:53:46 INFO - 1499435626452 Marionette TRACE 358 <- [1,4,null,{}]
06:53:46 INFO - 1499435626454 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:53:46 INFO - 1499435626456 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8b8360bf-61e5-4126-8196-64f3d36d8cdb","ELEMENT":"8b8360bf-61e5-4126-8196-64f3d36d8cdb"}}]
06:53:46 INFO - 1499435626457 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:53:46 INFO - 1499435626457 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:53:46 INFO - 1499435626459 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}]
06:53:46 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499435626462 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:53:46 INFO - 1499435626463 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:53:46 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 168ms
06:53:46 INFO - TEST-START | test_click.py TestClick.test_container_element
06:53:46 INFO - 1499435626464 Marionette TRACE 358 <- [1,8,null,{}]
06:53:46 INFO - 1499435626466 Marionette DEBUG Closed connection 358
06:53:46 INFO - 1499435626467 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49768
06:53:46 INFO - 1499435626467 Marionette DEBUG Closed connection 359
06:53:46 INFO - 1499435626468 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49769
06:53:46 INFO - 1499435626469 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:46 INFO - 1499435626469 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626471 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626473 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d7619ed0-2ea3-4301-82bb-2b02c47c6bdd","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626475 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:53:46 INFO - 1499435626476 Marionette TRACE 360 <- [1,2,null,{}]
06:53:46 INFO - 1499435626477 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:46 INFO - 1499435626477 Marionette TRACE 360 <- [1,3,null,{}]
06:53:46 INFO - 1499435626478 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:46 INFO - 1499435626478 Marionette TRACE 360 <- [1,4,null,{}]
06:53:46 INFO - 1499435626480 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}]
06:53:46 INFO - TEST-START: test_click.py TestClick.test_container_element1499435626482 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:53:46 INFO - 1499435626483 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:53:46 INFO - 1499435626484 Marionette TRACE 360 <- [1,6,null,{}]
06:53:46 INFO - 1499435626486 Marionette DEBUG Closed connection 360
06:53:46 INFO - 1499435626486 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49770
06:53:46 INFO - 1499435626487 Marionette DEBUG Closed connection 361
06:53:46 INFO - 1499435626487 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49771
06:53:46 INFO - 1499435626488 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:46 INFO - 1499435626489 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626491 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626493 Marionette TRACE 362 <- [1,1,null,{"sessionId":"629608f4-e767-45aa-9281-2105ca43114c","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626495 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:53:46 INFO - 1499435626496 Marionette TRACE 362 <- [1,2,null,{}]
06:53:46 INFO - 1499435626498 Marionette DEBUG Closed connection 362
06:53:46 INFO - 1499435626498 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49772
06:53:46 INFO - 1499435626499 Marionette DEBUG Closed connection 363
06:53:46 INFO - 1499435626500 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49773
06:53:46 INFO - 1499435626501 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:46 INFO - 1499435626501 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626503 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626505 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a6f10cf7-63eb-4aba-bbab-51c4f889703b","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626507 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"}]
06:53:46 INFO - 1499435626508 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/test.html"
06:53:46 INFO - 1499435626510 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/test.html"
06:53:46 INFO - 1499435626510 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/test.html"
06:53:46 INFO - 1499435626522 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"
06:53:46 INFO - 1499435626528 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"
06:53:46 INFO - 1499435626536 Marionette TRACE 364 <- [1,2,null,{}]
06:53:46 INFO - 1499435626538 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:46 INFO - 1499435626540 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"47f71115-051f-4eec-8416-17acf2fe2af9","ELEMENT":"47f71115-051f-4eec-8416-17acf2fe2af9"}}]
06:53:46 INFO - 1499435626541 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"47f71115-051f-4eec-8416-17acf2fe2af9"}]
06:53:46 INFO - 1499435626783 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:46 INFO - 1499435626784 Marionette TRACE 364 <- [1,4,null,{}]
06:53:46 INFO - 1499435626785 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"47f71115-051f-4eec-8416-17acf2fe2af9","name":"selected"}]
06:53:46 INFO - 1499435626789 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:53:46 INFO - 1499435626790 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}]
06:53:46 INFO - TEST-END: test_click.py TestClick.test_container_element1499435626792 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:53:46 INFO - 1499435626793 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:53:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 330ms
06:53:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:53:46 INFO - 1499435626794 Marionette TRACE 364 <- [1,7,null,{}]
06:53:46 INFO - 1499435626796 Marionette DEBUG Closed connection 364
06:53:46 INFO - 1499435626797 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49774
06:53:46 INFO - 1499435626797 Marionette DEBUG Closed connection 365
06:53:46 INFO - 1499435626798 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49775
06:53:46 INFO - 1499435626799 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:46 INFO - 1499435626800 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626801 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626804 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b3437162-3ab8-41d4-82b0-66080375dea4","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626805 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:53:46 INFO - 1499435626806 Marionette TRACE 366 <- [1,2,null,{}]
06:53:46 INFO - 1499435626807 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:46 INFO - 1499435626807 Marionette TRACE 366 <- [1,3,null,{}]
06:53:46 INFO - 1499435626808 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:46 INFO - 1499435626808 Marionette TRACE 366 <- [1,4,null,{}]
06:53:46 INFO - 1499435626810 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}]
06:53:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499435626812 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:53:46 INFO - 1499435626813 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:53:46 INFO - 1499435626814 Marionette TRACE 366 <- [1,6,null,{}]
06:53:46 INFO - 1499435626815 Marionette DEBUG Closed connection 366
06:53:46 INFO - 1499435626816 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49776
06:53:46 INFO - 1499435626817 Marionette DEBUG Closed connection 367
06:53:46 INFO - 1499435626817 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49777
06:53:46 INFO - 1499435626818 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:46 INFO - 1499435626819 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626820 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626823 Marionette TRACE 368 <- [1,1,null,{"sessionId":"855183bd-3a7a-478c-8bf6-ecdcaa80bf4f","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626825 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:53:46 INFO - 1499435626826 Marionette TRACE 368 <- [1,2,null,{}]
06:53:46 INFO - 1499435626828 Marionette DEBUG Closed connection 368
06:53:46 INFO - 1499435626828 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49778
06:53:46 INFO - 1499435626829 Marionette DEBUG Closed connection 369
06:53:46 INFO - 1499435626829 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49779
06:53:46 INFO - 1499435626830 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:46 INFO - 1499435626831 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:46 INFO - 1499435626833 Marionette DEBUG Register listener.js for window 12
06:53:46 INFO - 1499435626836 Marionette TRACE 370 <- [1,1,null,{"sessionId":"9a73c16a-40d6-4ebd-a35a-a7849564a13a","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:46 INFO - 1499435626838 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"}]
06:53:46 INFO - 1499435626839 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"
06:53:46 INFO - 1499435626841 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"
06:53:46 INFO - 1499435626841 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"
06:53:46 INFO - 1499435626856 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"
06:53:46 INFO - 1499435626859 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"
06:53:46 INFO - 1499435626868 Marionette TRACE 370 <- [1,2,null,{}]
06:53:46 INFO - 1499435626870 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:46 INFO - 1499435626872 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"739ea14d-9e40-4476-b34d-cb96c802e7d6","ELEMENT":"739ea14d-9e40-4476-b34d-cb96c802e7d6"}}]
06:53:46 INFO - 1499435626873 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"739ea14d-9e40-4476-b34d-cb96c802e7d6"}]
06:53:47 INFO - 1499435627109 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:47 INFO - 1499435627110 Marionette TRACE 370 <- [1,4,null,{}]
06:53:47 INFO - 1499435627111 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"739ea14d-9e40-4476-b34d-cb96c802e7d6","name":"selected"}]
06:53:47 INFO - 1499435627114 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:53:47 INFO - 1499435627116 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}]
06:53:47 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499435627118 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:53:47 INFO - 1499435627119 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:53:47 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 325ms
06:53:47 INFO - 1499435627120 Marionette TRACE 370 <- [1,7,null,{}]
06:53:47 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:53:47 INFO - 1499435627121 Marionette DEBUG Closed connection 370
06:53:47 INFO - 1499435627123 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49780
06:53:47 INFO - 1499435627124 Marionette DEBUG Closed connection 371
06:53:47 INFO - 1499435627124 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49781
06:53:47 INFO - 1499435627125 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:47 INFO - 1499435627126 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627128 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627130 Marionette TRACE 372 <- [1,1,null,{"sessionId":"0931a51a-09e8-4a6c-8f0d-4a56bb3eded5","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627132 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:53:47 INFO - 1499435627132 Marionette TRACE 372 <- [1,2,null,{}]
06:53:47 INFO - 1499435627133 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:47 INFO - 1499435627133 Marionette TRACE 372 <- [1,3,null,{}]
06:53:47 INFO - 1499435627134 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:47 INFO - 1499435627135 Marionette TRACE 372 <- [1,4,null,{}]
06:53:47 INFO - 1499435627137 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}]
06:53:47 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499435627139 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:53:47 INFO - 1499435627140 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:53:47 INFO - 1499435627141 Marionette TRACE 372 <- [1,6,null,{}]
06:53:47 INFO - 1499435627142 Marionette DEBUG Closed connection 372
06:53:47 INFO - 1499435627143 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49782
06:53:47 INFO - 1499435627143 Marionette DEBUG Closed connection 373
06:53:47 INFO - 1499435627144 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49783
06:53:47 INFO - 1499435627145 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:47 INFO - 1499435627146 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627147 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627150 Marionette TRACE 374 <- [1,1,null,{"sessionId":"172d68b9-cb32-4d1e-bcce-9d8cd2e98057","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627152 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:53:47 INFO - 1499435627153 Marionette TRACE 374 <- [1,2,null,{}]
06:53:47 INFO - 1499435627154 Marionette DEBUG Closed connection 374
06:53:47 INFO - 1499435627155 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49784
06:53:47 INFO - 1499435627155 Marionette DEBUG Closed connection 375
06:53:47 INFO - 1499435627156 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49785
06:53:47 INFO - 1499435627157 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:47 INFO - 1499435627157 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627166 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627168 Marionette TRACE 376 <- [1,1,null,{"sessionId":"9d4dfd64-5215-42d6-b4f4-b0c9b288939f","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627170 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"}]
06:53:47 INFO - 1499435627171 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"
06:53:47 INFO - 1499435627173 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"
06:53:47 INFO - 1499435627173 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"
06:53:47 INFO - 1499435627190 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"
06:53:47 INFO - 1499435627193 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"
06:53:47 INFO - 1499435627200 Marionette TRACE 376 <- [1,2,null,{}]
06:53:47 INFO - 1499435627202 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:53:47 INFO - 1499435627204 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"10ba5deb-616e-420a-8517-1c27841bd6b4","ELEMENT":"10ba5deb-616e-420a-8517-1c27841bd6b4"}}]
06:53:47 INFO - 1499435627205 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"10ba5deb-616e-420a-8517-1c27841bd6b4"}]
06:53:47 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 324ms
06:53:47 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:53:47 INFO - 1499435627438 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:47 INFO - 1499435627439 Marionette TRACE 376 <- [1,4,null,{}]
06:53:47 INFO - 1499435627441 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}]
06:53:47 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499435627443 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:53:47 INFO - 1499435627444 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:53:47 INFO - 1499435627445 Marionette TRACE 376 <- [1,6,null,{}]
06:53:47 INFO - 1499435627446 Marionette DEBUG Closed connection 376
06:53:47 INFO - 1499435627447 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49786
06:53:47 INFO - 1499435627448 Marionette DEBUG Closed connection 377
06:53:47 INFO - 1499435627448 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49787
06:53:47 INFO - 1499435627449 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:47 INFO - 1499435627450 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627452 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627454 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ed38048f-59e2-4dd9-93e5-838930691d47","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627456 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:53:47 INFO - 1499435627456 Marionette TRACE 378 <- [1,2,null,{}]
06:53:47 INFO - 1499435627457 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:47 INFO - 1499435627457 Marionette TRACE 378 <- [1,3,null,{}]
06:53:47 INFO - 1499435627458 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:47 INFO - 1499435627458 Marionette TRACE 378 <- [1,4,null,{}]
06:53:47 INFO - 1499435627460 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}]
06:53:47 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499435627462 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:53:47 INFO - 1499435627463 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:53:47 INFO - 1499435627464 Marionette TRACE 378 <- [1,6,null,{}]
06:53:47 INFO - 1499435627466 Marionette DEBUG Closed connection 378
06:53:47 INFO - 1499435627466 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49788
06:53:47 INFO - 1499435627467 Marionette DEBUG Closed connection 379
06:53:47 INFO - 1499435627467 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49789
06:53:47 INFO - 1499435627468 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:47 INFO - 1499435627469 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627471 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627473 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3254d2eb-db1d-41f8-a7f9-7453f1863b88","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627475 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:53:47 INFO - 1499435627476 Marionette TRACE 380 <- [1,2,null,{}]
06:53:47 INFO - 1499435627478 Marionette DEBUG Closed connection 380
06:53:47 INFO - 1499435627478 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49790
06:53:47 INFO - 1499435627479 Marionette DEBUG Closed connection 381
06:53:47 INFO - 1499435627480 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49791
06:53:47 INFO - 1499435627481 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:47 INFO - 1499435627481 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627483 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627486 Marionette TRACE 382 <- [1,1,null,{"sessionId":"08424e35-6e24-4add-9c66-1db983334245","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627488 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"}]
06:53:47 INFO - 1499435627489 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"
06:53:47 INFO - 1499435627491 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"
06:53:47 INFO - 1499435627491 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"
06:53:47 INFO - 1499435627506 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"
06:53:47 INFO - 1499435627509 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"
06:53:47 INFO - 1499435627517 Marionette TRACE 382 <- [1,2,null,{}]
06:53:47 INFO - 1499435627518 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:53:47 INFO - 1499435627520 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2626c4d8-6bad-423b-86bc-3c39bcb9e46e","ELEMENT":"2626c4d8-6bad-423b-86bc-3c39bcb9e46e"}}]
06:53:47 INFO - 1499435627521 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"2626c4d8-6bad-423b-86bc-3c39bcb9e46e"}]
06:53:47 INFO - 1499435627762 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:47 INFO - 1499435627763 Marionette TRACE 382 <- [1,4,null,{}]
06:53:47 INFO - 1499435627765 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}]
06:53:47 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499435627767 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:53:47 INFO - 1499435627767 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:53:47 INFO - 1499435627768 Marionette TRACE 382 <- [1,6,null,{}]
06:53:47 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 325ms
06:53:47 INFO - TEST-START | test_click.py TestClick.test_input_file
06:53:47 INFO - 1499435627770 Marionette DEBUG Closed connection 382
06:53:47 INFO - 1499435627772 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49792
06:53:47 INFO - 1499435627773 Marionette DEBUG Closed connection 383
06:53:47 INFO - 1499435627773 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49793
06:53:47 INFO - 1499435627774 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:47 INFO - 1499435627774 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627776 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627779 Marionette TRACE 384 <- [1,1,null,{"sessionId":"048843c1-fa7b-4666-9ecd-f5f23f151521","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627782 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:53:47 INFO - 1499435627782 Marionette TRACE 384 <- [1,2,null,{}]
06:53:47 INFO - 1499435627783 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:47 INFO - 1499435627784 Marionette TRACE 384 <- [1,3,null,{}]
06:53:47 INFO - 1499435627785 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:47 INFO - 1499435627785 Marionette TRACE 384 <- [1,4,null,{}]
06:53:47 INFO - 1499435627787 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}]
06:53:47 INFO - TEST-START: test_click.py TestClick.test_input_file1499435627789 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:53:47 INFO - 1499435627790 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:53:47 INFO - 1499435627791 Marionette TRACE 384 <- [1,6,null,{}]
06:53:47 INFO - 1499435627792 Marionette DEBUG Closed connection 384
06:53:47 INFO - 1499435627793 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49794
06:53:47 INFO - 1499435627794 Marionette DEBUG Closed connection 385
06:53:47 INFO - 1499435627794 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49795
06:53:47 INFO - 1499435627795 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:47 INFO - 1499435627796 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627798 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627801 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6831f76e-4640-41d4-80f8-450d0d2d1d37","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627802 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:53:47 INFO - 1499435627803 Marionette TRACE 386 <- [1,2,null,{}]
06:53:47 INFO - 1499435627805 Marionette DEBUG Closed connection 386
06:53:47 INFO - 1499435627806 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49796
06:53:47 INFO - 1499435627806 Marionette DEBUG Closed connection 387
06:53:47 INFO - 1499435627807 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49797
06:53:47 INFO - 1499435627808 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:47 INFO - 1499435627808 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627810 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627813 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f8b8b5f2-29a9-492e-a4ce-f50a776a5f4b","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627814 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:53:47 INFO - 1499435627815 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"
06:53:47 INFO - 1499435627817 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"
06:53:47 INFO - 1499435627817 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"
06:53:47 INFO - 1499435627834 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:47 INFO - 1499435627836 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:47 INFO - 1499435627838 Marionette TRACE 388 <- [1,2,null,{}]
06:53:47 INFO - 1499435627840 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:53:47 INFO - 1499435627841 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f1ab5d1f-afe0-4e2d-8864-83a25f1a456d","ELEMENT":"f1ab5d1f-afe0-4e2d-8864-83a25f1a456d"}}]
06:53:47 INFO - 1499435627842 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"f1ab5d1f-afe0-4e2d-8864-83a25f1a456d"}]
06:53:47 INFO - 1499435627872 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]
06:53:47 INFO - 1499435627874 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}]
06:53:47 INFO - TEST-END: test_click.py TestClick.test_input_file1499435627876 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:53:47 INFO - 1499435627877 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:53:47 INFO - 1499435627878 Marionette TRACE 388 <- [1,6,null,{}]
06:53:47 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 109ms
06:53:47 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:53:47 INFO - 1499435627880 Marionette DEBUG Closed connection 388
06:53:47 INFO - 1499435627881 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49798
06:53:47 INFO - 1499435627882 Marionette DEBUG Closed connection 389
06:53:47 INFO - 1499435627883 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49799
06:53:47 INFO - 1499435627883 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:47 INFO - 1499435627884 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627886 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627888 Marionette TRACE 390 <- [1,1,null,{"sessionId":"13a07288-4566-45ab-a1dc-632e3f7c4ea6","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627890 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:53:47 INFO - 1499435627890 Marionette TRACE 390 <- [1,2,null,{}]
06:53:47 INFO - 1499435627891 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:47 INFO - 1499435627891 Marionette TRACE 390 <- [1,3,null,{}]
06:53:47 INFO - 1499435627892 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:47 INFO - 1499435627892 Marionette TRACE 390 <- [1,4,null,{}]
06:53:47 INFO - 1499435627894 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}]
06:53:47 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499435627896 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:53:47 INFO - 1499435627897 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:53:47 INFO - 1499435627898 Marionette TRACE 390 <- [1,6,null,{}]
06:53:47 INFO - 1499435627900 Marionette DEBUG Closed connection 390
06:53:47 INFO - 1499435627901 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49800
06:53:47 INFO - 1499435627901 Marionette DEBUG Closed connection 391
06:53:47 INFO - 1499435627902 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49801
06:53:47 INFO - 1499435627903 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:47 INFO - 1499435627903 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627905 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627907 Marionette TRACE 392 <- [1,1,null,{"sessionId":"51e8b089-6225-46e8-8c3d-fccf1a6bdd36","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627909 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:53:47 INFO - 1499435627910 Marionette TRACE 392 <- [1,2,null,{}]
06:53:47 INFO - 1499435627912 Marionette DEBUG Closed connection 392
06:53:47 INFO - 1499435627912 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49802
06:53:47 INFO - 1499435627913 Marionette DEBUG Closed connection 393
06:53:47 INFO - 1499435627914 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49803
06:53:47 INFO - 1499435627915 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:47 INFO - 1499435627915 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:47 INFO - 1499435627917 Marionette DEBUG Register listener.js for window 12
06:53:47 INFO - 1499435627920 Marionette TRACE 394 <- [1,1,null,{"sessionId":"37b3a220-0dc9-4346-89b5-8b38237e4e79","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:47 INFO - 1499435627921 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"}]
06:53:47 INFO - 1499435627923 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:47 INFO - 1499435627924 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:47 INFO - 1499435627925 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:47 INFO - 1499435627950 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"
06:53:47 INFO - 1499435627952 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"
06:53:47 INFO - 1499435627954 Marionette TRACE 394 <- [1,2,null,{}]
06:53:47 INFO - 1499435627955 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:53:47 INFO - 1499435627957 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e2365e9-18d9-4a9f-b259-e0b948ac04da","ELEMENT":"6e2365e9-18d9-4a9f-b259-e0b948ac04da"}}]
06:53:47 INFO - 1499435627957 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:53:47 INFO - 1499435627959 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e978d4d6-b5a0-4da2-b491-18227bb2ba6e","ELEMENT":"e978d4d6-b5a0-4da2-b491-18227bb2ba6e"}}]
06:53:47 INFO - 1499435627965 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"6e2365e9-18d9-4a9f-b259-e0b948ac04da"}]
06:53:48 INFO - 1499435628196 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:48 INFO - 1499435628197 Marionette TRACE 394 <- [1,5,null,{}]
06:53:48 INFO - 1499435628198 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"e978d4d6-b5a0-4da2-b491-18227bb2ba6e"}]
06:53:48 INFO - 1499435628211 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]
06:53:48 INFO - 1499435628213 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:53:48 INFO - 1499435628214 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:53:48 INFO - 1499435628216 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}]
06:53:48 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499435628218 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:53:48 INFO - 1499435628219 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:53:48 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 340ms
06:53:48 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:53:48 INFO - 1499435628220 Marionette TRACE 394 <- [1,9,null,{}]
06:53:48 INFO - 1499435628221 Marionette DEBUG Closed connection 394
06:53:48 INFO - 1499435628222 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49804
06:53:48 INFO - 1499435628223 Marionette DEBUG Closed connection 395
06:53:48 INFO - 1499435628223 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49805
06:53:48 INFO - 1499435628224 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:48 INFO - 1499435628225 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:48 INFO - 1499435628227 Marionette DEBUG Register listener.js for window 12
06:53:48 INFO - 1499435628229 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a32b6401-c36b-4f2b-a361-6d8fd985bb1c","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:48 INFO - 1499435628231 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:53:48 INFO - 1499435628231 Marionette TRACE 396 <- [1,2,null,{}]
06:53:48 INFO - 1499435628232 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:48 INFO - 1499435628232 Marionette TRACE 396 <- [1,3,null,{}]
06:53:48 INFO - 1499435628233 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:48 INFO - 1499435628234 Marionette TRACE 396 <- [1,4,null,{}]
06:53:48 INFO - 1499435628235 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}]
06:53:48 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499435628237 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:53:48 INFO - 1499435628238 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:53:48 INFO - 1499435628239 Marionette TRACE 396 <- [1,6,null,{}]
06:53:48 INFO - 1499435628240 Marionette DEBUG Closed connection 396
06:53:48 INFO - 1499435628241 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49806
06:53:48 INFO - 1499435628242 Marionette DEBUG Closed connection 397
06:53:48 INFO - 1499435628242 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49807
06:53:48 INFO - 1499435628243 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:48 INFO - 1499435628244 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:48 INFO - 1499435628245 Marionette DEBUG Register listener.js for window 12
06:53:48 INFO - 1499435628248 Marionette TRACE 398 <- [1,1,null,{"sessionId":"3993f11c-2289-441d-af4e-c92d7af329ba","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:48 INFO - 1499435628249 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:53:48 INFO - 1499435628250 Marionette TRACE 398 <- [1,2,null,{}]
06:53:48 INFO - 1499435628252 Marionette DEBUG Closed connection 398
06:53:48 INFO - 1499435628252 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49808
06:53:48 INFO - 1499435628253 Marionette DEBUG Closed connection 399
06:53:48 INFO - 1499435628254 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49809
06:53:48 INFO - 1499435628255 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:48 INFO - 1499435628255 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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}
06:53:48 INFO - 1499435628257 Marionette DEBUG Register listener.js for window 12
06:53:48 INFO - 1499435628259 Marionette TRACE 400 <- [1,1,null,{"sessionId":"75357d8a-4c3c-4dad-98c4-3bd640656ee6","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":1280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-013.001\\AppData\\Local\\Temp\\tmprs9nrz.mozrunner","moz:accessibilityChecks":false}}]
06:53:48 INFO - 1499435628261 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"}]
06:53:48 INFO - 1499435628262 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"
06:53:48 INFO - 1499435628264 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"
06:53:48 INFO - 1499435628264 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"
06:53:48 INFO - 1499435628275 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"
06:53:48 INFO - 1499435628278 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"
06:53:48 INFO - 1499435628286 Marionette TRACE 400 <- [1,2,null,{}]
06:53:48 INFO - 1499435628289 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:53:48 INFO - 1499435628290 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7286feab-64db-4865-ac7c-ce32cbc0b3ff","ELEMENT":"7286feab-64db-4865-ac7c-ce32cbc0b3ff"}}]
06:53:48 INFO - 1499435628291 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"7286feab-64db-4865-ac7c-ce32cbc0b3ff"}]
06:53:48 INFO - 1499435628319 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:53:48 INFO - 1499435628321 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"7286feab-64db-4865-ac7c-ce32cbc0b3ff"}]
06:53:48 INFO - 1499435628334 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element