could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:34:45 INFO - 1500298485385 Marionette TRACE 353 -> [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:34:45 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500298485388 Marionette TRACE 353 <- [1,5,null,{"value":null}]
06:34:45 INFO - 1500298485401 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
06:34:45 INFO - 1500298485403 Marionette TRACE 353 <- [1,6,null,{}]
06:34:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 182ms
06:34:45 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:34:45 INFO - 1500298485406 Marionette DEBUG Closed connection 353
06:34:45 INFO - 1500298485407 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50186
06:34:45 INFO - 1500298485408 Marionette DEBUG Closed connection 354
06:34:45 INFO - 1500298485409 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50187
06:34:45 INFO - 1500298485410 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:45 INFO - 1500298485411 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:45 INFO - 1500298485414 Marionette DEBUG Register listener.js for window 15
06:34:45 INFO - 1500298485417 Marionette TRACE 355 <- [1,1,null,{"sessionId":"2f9787bb-1e75-4b8f-bac6-d3255c490307","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:45 INFO - 1500298485419 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
06:34:45 INFO - 1500298485420 Marionette TRACE 355 <- [1,2,null,{}]
06:34:45 INFO - 1500298485421 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:34:45 INFO - 1500298485422 Marionette TRACE 355 <- [1,3,null,{}]
06:34:45 INFO - 1500298485423 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
06:34:45 INFO - 1500298485423 Marionette TRACE 355 <- [1,4,null,{}]
06:34:45 INFO - 1500298485425 Marionette TRACE 355 -> [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:34:45 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500298485428 Marionette TRACE 355 <- [1,5,null,{"value":null}]
06:34:45 INFO - 1500298485429 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
06:34:45 INFO - 1500298485430 Marionette TRACE 355 <- [1,6,null,{}]
06:34:45 INFO - 1500298485433 Marionette DEBUG Closed connection 355
06:34:45 INFO - 1500298485434 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50188
06:34:45 INFO - 1500298485435 Marionette DEBUG Closed connection 356
06:34:45 INFO - 1500298485435 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50189
06:34:45 INFO - 1500298485436 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:45 INFO - 1500298485437 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:45 INFO - 1500298485440 Marionette DEBUG Register listener.js for window 15
06:34:45 INFO - 1500298485443 Marionette TRACE 357 <- [1,1,null,{"sessionId":"37cddea1-634c-41ed-8a15-303a0804cd8e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:45 INFO - 1500298485445 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
06:34:45 INFO - 1500298485447 Marionette TRACE 357 <- [1,2,null,{}]
06:34:45 INFO - 1500298485449 Marionette DEBUG Closed connection 357
06:34:45 INFO - 1500298485450 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50190
06:34:45 INFO - 1500298485450 Marionette DEBUG Closed connection 358
06:34:45 INFO - 1500298485451 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50191
06:34:45 INFO - 1500298485452 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:34:45 INFO - 1500298485453 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:45 INFO - 1500298485456 Marionette DEBUG Register listener.js for window 15
06:34:45 INFO - 1500298485459 Marionette TRACE 359 <- [1,1,null,{"sessionId":"28191ab4-7d49-4252-9d9f-db8f8e15b37e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:45 INFO - 1500298485461 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49749/clicks.html"}]
06:34:45 INFO - 1500298485463 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49749/hidden.html"
06:34:45 INFO - 1500298485470 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49749/hidden.html"
06:34:45 INFO - 1500298485470 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49749/hidden.html"
06:34:45 INFO - 1500298485501 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49749/clicks.html"
06:34:45 INFO - 1500298485503 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49749/clicks.html"
06:34:45 INFO - 1500298485515 Marionette TRACE 359 <- [1,2,null,{}]
06:34:45 INFO - 1500298485516 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:34:45 INFO - 1500298485519 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e7a57434-cf98-4ab7-bf7e-272f5618218e","ELEMENT":"e7a57434-cf98-4ab7-bf7e-272f5618218e"}}]
06:34:45 INFO - 1500298485537 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"e7a57434-cf98-4ab7-bf7e-272f5618218e"}]
06:34:45 INFO - 1500298485582 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49749/clicks.html"
06:34:45 INFO - 1500298485594 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49749/clicks.html"
06:34:45 INFO - 1500298485595 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49749/clicks.html"
06:34:45 INFO - 1500298485637 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49749/test.html"
06:34:45 INFO - 1500298485647 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49749/test.html"
06:34:45 INFO - 1500298485652 Marionette TRACE 359 <- [1,4,null,{}]
06:34:45 INFO - 1500298485661 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:34:45 INFO - 1500298485664 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b19e1cea-1f19-41e8-b48a-80ebfe8d51ac","ELEMENT":"b19e1cea-1f19-41e8-b48a-80ebfe8d51ac"}}]
06:34:45 INFO - 1500298485665 Marionette TRACE 359 -> [0,6,"getTitle",{}]
06:34:45 INFO - 1500298485666 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
06:34:45 INFO - 1500298485668 Marionette TRACE 359 -> [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:34:45 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500298485671 Marionette TRACE 359 <- [1,7,null,{"value":null}]
06:34:45 INFO - 1500298485688 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
06:34:45 INFO - 1500298485690 Marionette TRACE 359 <- [1,8,null,{}]
06:34:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 286ms
06:34:45 INFO - TEST-START | test_click.py TestClick.test_container_element
06:34:45 INFO - 1500298485707 Marionette DEBUG Closed connection 359
06:34:45 INFO - 1500298485708 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50194
06:34:45 INFO - 1500298485709 Marionette DEBUG Closed connection 360
06:34:45 INFO - 1500298485710 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50195
06:34:45 INFO - 1500298485711 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:45 INFO - 1500298485711 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:45 INFO - 1500298485714 Marionette DEBUG Register listener.js for window 15
06:34:45 INFO - 1500298485718 Marionette TRACE 361 <- [1,1,null,{"sessionId":"05e1c750-f479-45a3-a4a1-2049723ce755","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:45 INFO - 1500298485720 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
06:34:45 INFO - 1500298485721 Marionette TRACE 361 <- [1,2,null,{}]
06:34:45 INFO - 1500298485722 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:34:45 INFO - 1500298485723 Marionette TRACE 361 <- [1,3,null,{}]
06:34:45 INFO - 1500298485724 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
06:34:45 INFO - 1500298485724 Marionette TRACE 361 <- [1,4,null,{}]
06:34:45 INFO - 1500298485726 Marionette TRACE 361 -> [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:34:45 INFO - TEST-START: test_click.py TestClick.test_container_element1500298485729 Marionette TRACE 361 <- [1,5,null,{"value":null}]
06:34:45 INFO - 1500298485730 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
06:34:45 INFO - 1500298485732 Marionette TRACE 361 <- [1,6,null,{}]
06:34:45 INFO - 1500298485734 Marionette DEBUG Closed connection 361
06:34:45 INFO - 1500298485735 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50196
06:34:45 INFO - 1500298485736 Marionette DEBUG Closed connection 362
06:34:45 INFO - 1500298485737 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50197
06:34:45 INFO - 1500298485770 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:45 INFO - 1500298485771 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:45 INFO - 1500298485774 Marionette DEBUG Register listener.js for window 15
06:34:45 INFO - 1500298485777 Marionette TRACE 363 <- [1,1,null,{"sessionId":"3492eafd-2f17-4f03-983f-16a550ac1450","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:45 INFO - 1500298485782 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
06:34:45 INFO - 1500298485783 Marionette TRACE 363 <- [1,2,null,{}]
06:34:45 INFO - 1500298485799 Marionette DEBUG Closed connection 363
06:34:45 INFO - 1500298485800 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50198
06:34:45 INFO - 1500298485801 Marionette DEBUG Closed connection 364
06:34:45 INFO - 1500298485802 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50199
06:34:45 INFO - 1500298485804 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:34:45 INFO - 1500298485805 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:45 INFO - 1500298485808 Marionette DEBUG Register listener.js for window 15
06:34:45 INFO - 1500298485811 Marionette TRACE 365 <- [1,1,null,{"sessionId":"5205261a-fe5a-4fd6-b7c5-96276d51c0e2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:45 INFO - 1500298485814 Marionette TRACE 365 -> [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:34:45 INFO - 1500298485816 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49749/test.html"
06:34:45 INFO - 1500298485819 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49749/test.html"
06:34:45 INFO - 1500298485819 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49749/test.html"
06:34:45 INFO - 1500298485846 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:34:45 INFO - 1500298485851 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:34:45 INFO - 1500298485855 Marionette TRACE 365 <- [1,2,null,{}]
06:34:45 INFO - 1500298485865 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:34:45 INFO - 1500298485868 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aba52745-be3a-4259-ad6b-c2eb0b5a118e","ELEMENT":"aba52745-be3a-4259-ad6b-c2eb0b5a118e"}}]
06:34:45 INFO - 1500298485869 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"aba52745-be3a-4259-ad6b-c2eb0b5a118e"}]
06:34:46 INFO - 1500298486113 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:34:46 INFO - 1500298486113 Marionette TRACE 365 <- [1,4,null,{}]
06:34:46 INFO - 1500298486115 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"aba52745-be3a-4259-ad6b-c2eb0b5a118e","name":"selected"}]
06:34:46 INFO - 1500298486120 Marionette TRACE 365 <- [1,5,null,{"value":true}]
06:34:46 INFO - 1500298486121 Marionette TRACE 365 -> [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:34:46 INFO - TEST-END: test_click.py TestClick.test_container_element1500298486124 Marionette TRACE 365 <- [1,6,null,{"value":null}]
06:34:46 INFO - 1500298486125 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
06:34:46 INFO - 1500298486127 Marionette TRACE 365 <- [1,7,null,{}]
06:34:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 437ms
06:34:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:34:46 INFO - 1500298486129 Marionette DEBUG Closed connection 365
06:34:46 INFO - 1500298486130 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50200
06:34:46 INFO - 1500298486131 Marionette DEBUG Closed connection 366
06:34:46 INFO - 1500298486132 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50201
06:34:46 INFO - 1500298486133 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:46 INFO - 1500298486141 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486144 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486147 Marionette TRACE 367 <- [1,1,null,{"sessionId":"6e7ea356-955a-46c2-a3b4-f9bdcb034e87","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486149 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
06:34:46 INFO - 1500298486150 Marionette TRACE 367 <- [1,2,null,{}]
06:34:46 INFO - 1500298486151 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:34:46 INFO - 1500298486151 Marionette TRACE 367 <- [1,3,null,{}]
06:34:46 INFO - 1500298486152 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
06:34:46 INFO - 1500298486153 Marionette TRACE 367 <- [1,4,null,{}]
06:34:46 INFO - 1500298486155 Marionette TRACE 367 -> [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:34:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500298486158 Marionette TRACE 367 <- [1,5,null,{"value":null}]
06:34:46 INFO - 1500298486159 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
06:34:46 INFO - 1500298486161 Marionette TRACE 367 <- [1,6,null,{}]
06:34:46 INFO - 1500298486163 Marionette DEBUG Closed connection 367
06:34:46 INFO - 1500298486164 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50202
06:34:46 INFO - 1500298486165 Marionette DEBUG Closed connection 368
06:34:46 INFO - 1500298486166 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50203
06:34:46 INFO - 1500298486167 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:46 INFO - 1500298486168 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486170 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486174 Marionette TRACE 369 <- [1,1,null,{"sessionId":"dc601188-f8bd-4170-8902-5630627a5e44","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486176 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
06:34:46 INFO - 1500298486178 Marionette TRACE 369 <- [1,2,null,{}]
06:34:46 INFO - 1500298486180 Marionette DEBUG Closed connection 369
06:34:46 INFO - 1500298486181 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50204
06:34:46 INFO - 1500298486181 Marionette DEBUG Closed connection 370
06:34:46 INFO - 1500298486182 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50205
06:34:46 INFO - 1500298486183 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:34:46 INFO - 1500298486184 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486187 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486190 Marionette TRACE 371 <- [1,1,null,{"sessionId":"56bb2246-1cbb-4ced-a648-10fabf346912","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486192 Marionette TRACE 371 -> [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:34:46 INFO - 1500298486194 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:34:46 INFO - 1500298486198 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:34:46 INFO - 1500298486198 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:34:46 INFO - 1500298486218 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:34:46 INFO - 1500298486222 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:34:46 INFO - 1500298486232 Marionette TRACE 371 <- [1,2,null,{}]
06:34:46 INFO - 1500298486233 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:34:46 INFO - 1500298486236 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"71c18a26-1794-44fc-bcdc-22d36a958e9e","ELEMENT":"71c18a26-1794-44fc-bcdc-22d36a958e9e"}}]
06:34:46 INFO - 1500298486239 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"71c18a26-1794-44fc-bcdc-22d36a958e9e"}]
06:34:46 INFO - 1500298486485 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:34:46 INFO - 1500298486485 Marionette TRACE 371 <- [1,4,null,{}]
06:34:46 INFO - 1500298486487 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"71c18a26-1794-44fc-bcdc-22d36a958e9e","name":"selected"}]
06:34:46 INFO - 1500298486492 Marionette TRACE 371 <- [1,5,null,{"value":true}]
06:34:46 INFO - 1500298486493 Marionette TRACE 371 -> [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:34:46 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500298486496 Marionette TRACE 371 <- [1,6,null,{"value":null}]
06:34:46 INFO - 1500298486497 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
06:34:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 371ms
06:34:46 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:34:46 INFO - 1500298486499 Marionette TRACE 371 <- [1,7,null,{}]
06:34:46 INFO - 1500298486501 Marionette DEBUG Closed connection 371
06:34:46 INFO - 1500298486502 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50206
06:34:46 INFO - 1500298486503 Marionette DEBUG Closed connection 372
06:34:46 INFO - 1500298486504 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50207
06:34:46 INFO - 1500298486505 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:46 INFO - 1500298486505 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486508 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486512 Marionette TRACE 373 <- [1,1,null,{"sessionId":"4ae1f662-776c-4bac-abc0-49d3c407944e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486514 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
06:34:46 INFO - 1500298486514 Marionette TRACE 373 <- [1,2,null,{}]
06:34:46 INFO - 1500298486515 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:34:46 INFO - 1500298486516 Marionette TRACE 373 <- [1,3,null,{}]
06:34:46 INFO - 1500298486517 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
06:34:46 INFO - 1500298486517 Marionette TRACE 373 <- [1,4,null,{}]
06:34:46 INFO - 1500298486519 Marionette TRACE 373 -> [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:34:46 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500298486522 Marionette TRACE 373 <- [1,5,null,{"value":null}]
06:34:46 INFO - 1500298486523 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
06:34:46 INFO - 1500298486524 Marionette TRACE 373 <- [1,6,null,{}]
06:34:46 INFO - 1500298486526 Marionette DEBUG Closed connection 373
06:34:46 INFO - 1500298486527 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50208
06:34:46 INFO - 1500298486528 Marionette DEBUG Closed connection 374
06:34:46 INFO - 1500298486529 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50209
06:34:46 INFO - 1500298486530 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:46 INFO - 1500298486531 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486533 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486537 Marionette TRACE 375 <- [1,1,null,{"sessionId":"1dadad56-deb0-4b18-88dc-a7ab2224f03c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486539 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
06:34:46 INFO - 1500298486540 Marionette TRACE 375 <- [1,2,null,{}]
06:34:46 INFO - 1500298486542 Marionette DEBUG Closed connection 375
06:34:46 INFO - 1500298486543 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50210
06:34:46 INFO - 1500298486544 Marionette DEBUG Closed connection 376
06:34:46 INFO - 1500298486545 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50211
06:34:46 INFO - 1500298486546 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:34:46 INFO - 1500298486547 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486549 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486552 Marionette TRACE 377 <- [1,1,null,{"sessionId":"31d1d78c-931c-49e1-a9d8-81c39b3fbfdb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486555 Marionette TRACE 377 -> [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:34:46 INFO - 1500298486556 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:34:46 INFO - 1500298486559 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:34:46 INFO - 1500298486559 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:34:46 INFO - 1500298486583 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:34:46 INFO - 1500298486586 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:34:46 INFO - 1500298486597 Marionette TRACE 377 <- [1,2,null,{}]
06:34:46 INFO - 1500298486599 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:34:46 INFO - 1500298486601 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5e01bf1e-cfef-4634-aebf-8672b34e572a","ELEMENT":"5e01bf1e-cfef-4634-aebf-8672b34e572a"}}]
06:34:46 INFO - 1500298486602 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"5e01bf1e-cfef-4634-aebf-8672b34e572a"}]
06:34:46 INFO - 1500298486845 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:34:46 INFO - 1500298486845 Marionette TRACE 377 <- [1,4,null,{}]
06:34:46 INFO - 1500298486847 Marionette TRACE 377 -> [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:34:46 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500298486851 Marionette TRACE 377 <- [1,5,null,{"value":null}]
06:34:46 INFO - 1500298486852 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
06:34:46 INFO - 1500298486853 Marionette TRACE 377 <- [1,6,null,{}]
06:34:46 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 355ms
06:34:46 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:34:46 INFO - 1500298486856 Marionette DEBUG Closed connection 377
06:34:46 INFO - 1500298486856 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50212
06:34:46 INFO - 1500298486857 Marionette DEBUG Closed connection 378
06:34:46 INFO - 1500298486858 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50213
06:34:46 INFO - 1500298486859 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:46 INFO - 1500298486860 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486863 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486873 Marionette TRACE 379 <- [1,1,null,{"sessionId":"13bb55ee-c356-4e72-8b22-390ae8403239","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486876 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
06:34:46 INFO - 1500298486876 Marionette TRACE 379 <- [1,2,null,{}]
06:34:46 INFO - 1500298486877 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:34:46 INFO - 1500298486878 Marionette TRACE 379 <- [1,3,null,{}]
06:34:46 INFO - 1500298486879 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
06:34:46 INFO - 1500298486879 Marionette TRACE 379 <- [1,4,null,{}]
06:34:46 INFO - 1500298486881 Marionette TRACE 379 -> [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:34:46 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500298486884 Marionette TRACE 379 <- [1,5,null,{"value":null}]
06:34:46 INFO - 1500298486885 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
06:34:46 INFO - 1500298486887 Marionette TRACE 379 <- [1,6,null,{}]
06:34:46 INFO - 1500298486889 Marionette DEBUG Closed connection 379
06:34:46 INFO - 1500298486890 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50214
06:34:46 INFO - 1500298486891 Marionette DEBUG Closed connection 380
06:34:46 INFO - 1500298486891 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50215
06:34:46 INFO - 1500298486892 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:46 INFO - 1500298486893 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486896 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486899 Marionette TRACE 381 <- [1,1,null,{"sessionId":"7df75cd2-bf5f-4566-ad9f-3064e2eb8176","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486901 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
06:34:46 INFO - 1500298486903 Marionette TRACE 381 <- [1,2,null,{}]
06:34:46 INFO - 1500298486905 Marionette DEBUG Closed connection 381
06:34:46 INFO - 1500298486906 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50216
06:34:46 INFO - 1500298486906 Marionette DEBUG Closed connection 382
06:34:46 INFO - 1500298486907 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50217
06:34:46 INFO - 1500298486908 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:34:46 INFO - 1500298486909 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:46 INFO - 1500298486912 Marionette DEBUG Register listener.js for window 15
06:34:46 INFO - 1500298486915 Marionette TRACE 383 <- [1,1,null,{"sessionId":"e93283cb-6170-4e8c-ad01-71cdf0df7514","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:46 INFO - 1500298486917 Marionette TRACE 383 -> [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:34:46 INFO - 1500298486919 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:34:46 INFO - 1500298486922 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:34:46 INFO - 1500298486922 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:34:46 INFO - 1500298486937 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:34:46 INFO - 1500298486947 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:34:46 INFO - 1500298486952 Marionette TRACE 383 <- [1,2,null,{}]
06:34:46 INFO - 1500298486960 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:34:46 INFO - 1500298486963 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"736cc8dc-1b9c-4967-b336-5c34c7e990dd","ELEMENT":"736cc8dc-1b9c-4967-b336-5c34c7e990dd"}}]
06:34:46 INFO - 1500298486964 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"736cc8dc-1b9c-4967-b336-5c34c7e990dd"}]
06:34:47 INFO - 1500298487210 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:34:47 INFO - 1500298487210 Marionette TRACE 383 <- [1,4,null,{}]
06:34:47 INFO - 1500298487213 Marionette TRACE 383 -> [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:34:47 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500298487216 Marionette TRACE 383 <- [1,5,null,{"value":null}]
06:34:47 INFO - 1500298487217 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
06:34:47 INFO - 1500298487218 Marionette TRACE 383 <- [1,6,null,{}]
06:34:47 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 364ms
06:34:47 INFO - TEST-START | test_click.py TestClick.test_input_file
06:34:47 INFO - 1500298487221 Marionette DEBUG Closed connection 383
06:34:47 INFO - 1500298487221 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50218
06:34:47 INFO - 1500298487222 Marionette DEBUG Closed connection 384
06:34:47 INFO - 1500298487223 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50219
06:34:47 INFO - 1500298487224 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:47 INFO - 1500298487225 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487228 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487231 Marionette TRACE 385 <- [1,1,null,{"sessionId":"5e19cd7c-ae8a-4a60-aa65-b9a199a4b3bc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487233 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
06:34:47 INFO - 1500298487234 Marionette TRACE 385 <- [1,2,null,{}]
06:34:47 INFO - 1500298487235 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:34:47 INFO - 1500298487235 Marionette TRACE 385 <- [1,3,null,{}]
06:34:47 INFO - 1500298487236 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
06:34:47 INFO - 1500298487237 Marionette TRACE 385 <- [1,4,null,{}]
06:34:47 INFO - 1500298487238 Marionette TRACE 385 -> [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:34:47 INFO - TEST-START: test_click.py TestClick.test_input_file1500298487241 Marionette TRACE 385 <- [1,5,null,{"value":null}]
06:34:47 INFO - 1500298487242 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
06:34:47 INFO - 1500298487244 Marionette TRACE 385 <- [1,6,null,{}]
06:34:47 INFO - 1500298487246 Marionette DEBUG Closed connection 385
06:34:47 INFO - 1500298487247 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50220
06:34:47 INFO - 1500298487248 Marionette DEBUG Closed connection 386
06:34:47 INFO - 1500298487248 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50221
06:34:47 INFO - 1500298487250 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:47 INFO - 1500298487250 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487253 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487256 Marionette TRACE 387 <- [1,1,null,{"sessionId":"e7478b2c-4b05-41a1-9327-0ffd47c77eff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487258 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
06:34:47 INFO - 1500298487260 Marionette TRACE 387 <- [1,2,null,{}]
06:34:47 INFO - 1500298487262 Marionette DEBUG Closed connection 387
06:34:47 INFO - 1500298487263 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50222
06:34:47 INFO - 1500298487264 Marionette DEBUG Closed connection 388
06:34:47 INFO - 1500298487264 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50223
06:34:47 INFO - 1500298487265 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:34:47 INFO - 1500298487266 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487269 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487272 Marionette TRACE 389 <- [1,1,null,{"sessionId":"ce3f418e-f864-4343-aa36-727797f21bd8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487274 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:34:47 INFO - 1500298487276 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:34:47 INFO - 1500298487278 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:34:47 INFO - 1500298487279 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:34:47 INFO - 1500298487300 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:34:47 INFO - 1500298487304 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:34:47 INFO - 1500298487315 Marionette TRACE 389 <- [1,2,null,{}]
06:34:47 INFO - 1500298487317 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:34:47 INFO - 1500298487319 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"806a6bf0-70b9-4280-a944-d7ea874714eb","ELEMENT":"806a6bf0-70b9-4280-a944-d7ea874714eb"}}]
06:34:47 INFO - 1500298487321 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"806a6bf0-70b9-4280-a944-d7ea874714eb"}]
06:34:47 INFO - 1500298487350 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:34:47 INFO - 1500298487352 Marionette TRACE 389 -> [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:34:47 INFO - TEST-END: test_click.py TestClick.test_input_file1500298487355 Marionette TRACE 389 <- [1,5,null,{"value":null}]
06:34:47 INFO - 1500298487356 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
06:34:47 INFO - 1500298487357 Marionette TRACE 389 <- [1,6,null,{}]
06:34:47 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 139ms
06:34:47 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:34:47 INFO - 1500298487360 Marionette DEBUG Closed connection 389
06:34:47 INFO - 1500298487361 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50224
06:34:47 INFO - 1500298487361 Marionette DEBUG Closed connection 390
06:34:47 INFO - 1500298487362 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50225
06:34:47 INFO - 1500298487363 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:47 INFO - 1500298487364 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487367 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487370 Marionette TRACE 391 <- [1,1,null,{"sessionId":"a8e13f9c-6051-4e05-a2f2-0ffd5241a225","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487373 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
06:34:47 INFO - 1500298487373 Marionette TRACE 391 <- [1,2,null,{}]
06:34:47 INFO - 1500298487374 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:34:47 INFO - 1500298487375 Marionette TRACE 391 <- [1,3,null,{}]
06:34:47 INFO - 1500298487376 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
06:34:47 INFO - 1500298487376 Marionette TRACE 391 <- [1,4,null,{}]
06:34:47 INFO - 1500298487378 Marionette TRACE 391 -> [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:34:47 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500298487381 Marionette TRACE 391 <- [1,5,null,{"value":null}]
06:34:47 INFO - 1500298487382 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
06:34:47 INFO - 1500298487384 Marionette TRACE 391 <- [1,6,null,{}]
06:34:47 INFO - 1500298487386 Marionette DEBUG Closed connection 391
06:34:47 INFO - 1500298487387 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50226
06:34:47 INFO - 1500298487389 Marionette DEBUG Closed connection 392
06:34:47 INFO - 1500298487390 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50227
06:34:47 INFO - 1500298487391 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:47 INFO - 1500298487391 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487400 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487404 Marionette TRACE 393 <- [1,1,null,{"sessionId":"8529e9c9-3730-44f0-8e4b-b2b67b00722c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487406 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
06:34:47 INFO - 1500298487408 Marionette TRACE 393 <- [1,2,null,{}]
06:34:47 INFO - 1500298487410 Marionette DEBUG Closed connection 393
06:34:47 INFO - 1500298487411 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50228
06:34:47 INFO - 1500298487412 Marionette DEBUG Closed connection 394
06:34:47 INFO - 1500298487413 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50229
06:34:47 INFO - 1500298487414 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:34:47 INFO - 1500298487414 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487417 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487421 Marionette TRACE 395 <- [1,1,null,{"sessionId":"cb522727-6e0c-4b3d-ba69-5eef6cc1ca51","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487426 Marionette TRACE 395 -> [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:34:47 INFO - 1500298487427 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:34:47 INFO - 1500298487430 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:34:47 INFO - 1500298487430 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:34:47 INFO - 1500298487452 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:34:47 INFO - 1500298487456 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:34:47 INFO - 1500298487467 Marionette TRACE 395 <- [1,2,null,{}]
06:34:47 INFO - 1500298487468 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:34:47 INFO - 1500298487471 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4cc7f1ab-2a01-4fd3-8e25-ef3aac73efa8","ELEMENT":"4cc7f1ab-2a01-4fd3-8e25-ef3aac73efa8"}}]
06:34:47 INFO - 1500298487473 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:34:47 INFO - 1500298487475 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f3f1edcf-ec2b-4709-9df4-61451f41b4d7","ELEMENT":"f3f1edcf-ec2b-4709-9df4-61451f41b4d7"}}]
06:34:47 INFO - 1500298487476 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"4cc7f1ab-2a01-4fd3-8e25-ef3aac73efa8"}]
06:34:47 INFO - 1500298487718 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:34:47 INFO - 1500298487719 Marionette TRACE 395 <- [1,5,null,{}]
06:34:47 INFO - 1500298487720 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"f3f1edcf-ec2b-4709-9df4-61451f41b4d7"}]
06:34:47 INFO - 1500298487738 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:34:47 INFO - 1500298487740 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:34:47 INFO - 1500298487743 Marionette TRACE 395 <- [1,7,null,{"value":false}]
06:34:47 INFO - 1500298487744 Marionette TRACE 395 -> [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:34:47 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500298487747 Marionette TRACE 395 <- [1,8,null,{"value":null}]
06:34:47 INFO - 1500298487748 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
06:34:47 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 392ms
06:34:47 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:34:47 INFO - 1500298487750 Marionette TRACE 395 <- [1,9,null,{}]
06:34:47 INFO - 1500298487752 Marionette DEBUG Closed connection 395
06:34:47 INFO - 1500298487753 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50230
06:34:47 INFO - 1500298487754 Marionette DEBUG Closed connection 396
06:34:47 INFO - 1500298487755 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50231
06:34:47 INFO - 1500298487756 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:47 INFO - 1500298487756 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487759 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487763 Marionette TRACE 397 <- [1,1,null,{"sessionId":"30e360b7-5956-4896-89a9-3fc095e0a910","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487765 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
06:34:47 INFO - 1500298487766 Marionette TRACE 397 <- [1,2,null,{}]
06:34:47 INFO - 1500298487767 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:34:47 INFO - 1500298487767 Marionette TRACE 397 <- [1,3,null,{}]
06:34:47 INFO - 1500298487768 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
06:34:47 INFO - 1500298487769 Marionette TRACE 397 <- [1,4,null,{}]
06:34:47 INFO - 1500298487771 Marionette TRACE 397 -> [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:34:47 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500298487774 Marionette TRACE 397 <- [1,5,null,{"value":null}]
06:34:47 INFO - 1500298487775 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
06:34:47 INFO - 1500298487776 Marionette TRACE 397 <- [1,6,null,{}]
06:34:47 INFO - 1500298487778 Marionette DEBUG Closed connection 397
06:34:47 INFO - 1500298487779 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50232
06:34:47 INFO - 1500298487780 Marionette DEBUG Closed connection 398
06:34:47 INFO - 1500298487781 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50233
06:34:47 INFO - 1500298487782 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:34:47 INFO - 1500298487782 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487785 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487788 Marionette TRACE 399 <- [1,1,null,{"sessionId":"6f3898f4-2ce1-42c0-a3b1-a54cb2a39f3d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487790 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
06:34:47 INFO - 1500298487792 Marionette TRACE 399 <- [1,2,null,{}]
06:34:47 INFO - 1500298487794 Marionette DEBUG Closed connection 399
06:34:47 INFO - 1500298487795 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50234
06:34:47 INFO - 1500298487796 Marionette DEBUG Closed connection 400
06:34:47 INFO - 1500298487796 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50235
06:34:47 INFO - 1500298487798 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:34:47 INFO - 1500298487798 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}
06:34:47 INFO - 1500298487801 Marionette DEBUG Register listener.js for window 15
06:34:47 INFO - 1500298487805 Marionette TRACE 401 <- [1,1,null,{"sessionId":"81daa236-745a-4fa8-923d-c79ce00bdab8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprmaoew.mozrunner","moz:accessibilityChecks":false}}]
06:34:47 INFO - 1500298487807 Marionette TRACE 401 -> [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:34:47 INFO - 1500298487809 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:34:47 INFO - 1500298487812 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:34:47 INFO - 1500298487812 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:34:47 INFO - 1500298487835 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:34:47 INFO - 1500298487837 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:34:47 INFO - 1500298487847 Marionette TRACE 401 <- [1,2,null,{}]
06:34:47 INFO - 1500298487848 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:34:47 INFO - 1500298487851 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b90830aa-d86c-4c9c-9944-ef6e8c5e530e","ELEMENT":"b90830aa-d86c-4c9c-9944-ef6e8c5e530e"}}]
06:34:47 INFO - 1500298487852 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b90830aa-d86c-4c9c-9944-ef6e8c5e530e"}]
06:34:47 INFO - 1500298487877 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
06:34:47 INFO - 1500298487878 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"b90830aa-d86c-4c9c-9944-ef6e8c5e530e"}]
06:34:47 INFO - 1500298487900 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element