could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
09:52:24 INFO - 1498841544466 Marionette TRACE 358 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
09:52:24 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498841544470 Marionette TRACE 358 <- [1,5,null,{"value":null}]
09:52:24 INFO - 1498841544471 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
09:52:24 INFO - 1498841544474 Marionette TRACE 358 <- [1,6,null,{}]
09:52:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 258ms
09:52:24 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:52:24 INFO - 1498841544476 Marionette DEBUG Closed connection 358
09:52:24 INFO - 1498841544477 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50311
09:52:24 INFO - 1498841544478 Marionette DEBUG Closed connection 359
09:52:24 INFO - 1498841544479 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50312
09:52:24 INFO - 1498841544481 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:24 INFO - 1498841544481 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:24 INFO - 1498841544485 Marionette DEBUG Register listener.js for window 7
09:52:24 INFO - 1498841544490 Marionette TRACE 360 <- [1,1,null,{"sessionId":"5da2e605-25ae-4e85-b44a-d7e16e06049a","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:24 INFO - 1498841544491 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:52:24 INFO - 1498841544492 Marionette TRACE 360 <- [1,2,null,{}]
09:52:24 INFO - 1498841544493 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:52:24 INFO - 1498841544494 Marionette TRACE 360 <- [1,3,null,{}]
09:52:24 INFO - 1498841544495 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:52:24 INFO - 1498841544495 Marionette TRACE 360 <- [1,4,null,{}]
09:52:24 INFO - 1498841544498 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
09:52:24 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498841544501 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:52:24 INFO - 1498841544503 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:52:24 INFO - 1498841544504 Marionette TRACE 360 <- [1,6,null,{}]
09:52:24 INFO - 1498841544507 Marionette DEBUG Closed connection 360
09:52:24 INFO - 1498841544508 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50313
09:52:24 INFO - 1498841544509 Marionette DEBUG Closed connection 361
09:52:24 INFO - 1498841544509 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50314
09:52:24 INFO - 1498841544511 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:24 INFO - 1498841544511 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:24 INFO - 1498841544515 Marionette DEBUG Register listener.js for window 7
09:52:24 INFO - 1498841544521 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d8905591-69dd-45d2-b4d7-29f84aafa6ff","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:24 INFO - 1498841544522 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:52:24 INFO - 1498841544524 Marionette TRACE 362 <- [1,2,null,{}]
09:52:24 INFO - 1498841544526 Marionette DEBUG Closed connection 362
09:52:24 INFO - 1498841544528 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50315
09:52:24 INFO - 1498841544530 Marionette DEBUG Closed connection 363
09:52:24 INFO - 1498841544531 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50316
09:52:24 INFO - 1498841544532 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:52:24 INFO - 1498841544533 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:24 INFO - 1498841544536 Marionette DEBUG Register listener.js for window 7
09:52:24 INFO - 1498841544541 Marionette TRACE 364 <- [1,1,null,{"sessionId":"4a3de4d5-34cb-48d4-94b8-5d52d36ca0de","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:24 INFO - 1498841544542 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49912/clicks.html"}]
09:52:24 INFO - 1498841544544 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49912/hidden.html"
09:52:24 INFO - 1498841544555 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49912/hidden.html"
09:52:24 INFO - 1498841544555 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49912/hidden.html"
09:52:24 INFO - 1498841544581 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49912/clicks.html"
09:52:24 INFO - 1498841544584 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49912/clicks.html"
09:52:24 INFO - 1498841544597 Marionette TRACE 364 <- [1,2,null,{}]
09:52:24 INFO - 1498841544610 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:52:24 INFO - 1498841544614 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ca746e39-ec88-4330-91f4-ddc1bbd77562","ELEMENT":"ca746e39-ec88-4330-91f4-ddc1bbd77562"}}]
09:52:24 INFO - 1498841544615 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"ca746e39-ec88-4330-91f4-ddc1bbd77562"}]
09:52:24 INFO - 1498841544663 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49912/clicks.html"
09:52:24 INFO - 1498841544673 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49912/clicks.html"
09:52:24 INFO - 1498841544674 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49912/clicks.html"
09:52:24 INFO - 1498841544695 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49912/test.html"
09:52:24 INFO - 1498841544700 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49912/test.html"
09:52:24 INFO - 1498841544712 Marionette TRACE 364 <- [1,4,null,{}]
09:52:24 INFO - 1498841544725 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:52:24 INFO - 1498841544729 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4a81189-9d77-4915-804a-11d45eb4ab0f","ELEMENT":"c4a81189-9d77-4915-804a-11d45eb4ab0f"}}]
09:52:24 INFO - 1498841544730 Marionette TRACE 364 -> [0,6,"getTitle",{}]
09:52:24 INFO - 1498841544733 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
09:52:24 INFO - 1498841544735 Marionette TRACE 364 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
09:52:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498841544739 Marionette TRACE 364 <- [1,7,null,{"value":null}]
09:52:24 INFO - 1498841544740 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
09:52:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 268ms
09:52:24 INFO - TEST-START | test_click.py TestClick.test_container_element
09:52:24 INFO - 1498841544742 Marionette TRACE 364 <- [1,8,null,{}]
09:52:24 INFO - 1498841544744 Marionette DEBUG Closed connection 364
09:52:24 INFO - 1498841544745 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50319
09:52:24 INFO - 1498841544746 Marionette DEBUG Closed connection 365
09:52:24 INFO - 1498841544747 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50320
09:52:24 INFO - 1498841544748 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:24 INFO - 1498841544749 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:24 INFO - 1498841544752 Marionette DEBUG Register listener.js for window 7
09:52:24 INFO - 1498841544756 Marionette TRACE 366 <- [1,1,null,{"sessionId":"89298781-c80e-48ca-9fda-19711b1ca4cf","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:24 INFO - 1498841544757 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:52:24 INFO - 1498841544758 Marionette TRACE 366 <- [1,2,null,{}]
09:52:24 INFO - 1498841544759 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:52:24 INFO - 1498841544759 Marionette TRACE 366 <- [1,3,null,{}]
09:52:24 INFO - 1498841544760 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:52:24 INFO - 1498841544761 Marionette TRACE 366 <- [1,4,null,{}]
09:52:24 INFO - 1498841544763 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
09:52:24 INFO - TEST-START: test_click.py TestClick.test_container_element1498841544766 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:52:24 INFO - 1498841544767 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:52:24 INFO - 1498841544769 Marionette TRACE 366 <- [1,6,null,{}]
09:52:24 INFO - 1498841544771 Marionette DEBUG Closed connection 366
09:52:24 INFO - 1498841544772 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50321
09:52:24 INFO - 1498841544773 Marionette DEBUG Closed connection 367
09:52:24 INFO - 1498841544773 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50322
09:52:24 INFO - 1498841544775 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:24 INFO - 1498841544775 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:24 INFO - 1498841544778 Marionette DEBUG Register listener.js for window 7
09:52:24 INFO - 1498841544782 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b38cfbfb-ba00-4cf6-ac92-a7a4184838ab","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:24 INFO - 1498841544784 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:52:24 INFO - 1498841544785 Marionette TRACE 368 <- [1,2,null,{}]
09:52:24 INFO - 1498841544787 Marionette DEBUG Closed connection 368
09:52:24 INFO - 1498841544788 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50323
09:52:24 INFO - 1498841544789 Marionette DEBUG Closed connection 369
09:52:24 INFO - 1498841544790 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50324
09:52:24 INFO - 1498841544791 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:52:24 INFO - 1498841544792 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:24 INFO - 1498841544795 Marionette DEBUG Register listener.js for window 7
09:52:24 INFO - 1498841544799 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d9b57864-45c0-4c7f-9e46-e7734b0eed4f","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:24 INFO - 1498841544801 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:52:24 INFO - 1498841544802 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49912/test.html"
09:52:24 INFO - 1498841544806 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49912/test.html"
09:52:24 INFO - 1498841544806 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49912/test.html"
09:52:24 INFO - 1498841544838 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"
09:52:24 INFO - 1498841544851 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"
09:52:24 INFO - 1498841544862 Marionette TRACE 370 <- [1,2,null,{}]
09:52:24 INFO - 1498841544864 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:52:24 INFO - 1498841544868 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ee050b96-ebc4-456d-93b8-6a68c36fc5c3","ELEMENT":"ee050b96-ebc4-456d-93b8-6a68c36fc5c3"}}]
09:52:24 INFO - 1498841544869 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ee050b96-ebc4-456d-93b8-6a68c36fc5c3"}]
09:52:25 INFO - 1498841545120 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:52:25 INFO - 1498841545121 Marionette TRACE 370 <- [1,4,null,{}]
09:52:25 INFO - 1498841545141 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ee050b96-ebc4-456d-93b8-6a68c36fc5c3","name":"selected"}]
09:52:25 INFO - 1498841545148 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:52:25 INFO - 1498841545150 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
09:52:25 INFO - TEST-END: test_click.py TestClick.test_container_element1498841545155 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:52:25 INFO - 1498841545158 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:52:25 INFO - 1498841545160 Marionette TRACE 370 <- [1,7,null,{}]
09:52:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 418ms
09:52:25 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:52:25 INFO - 1498841545165 Marionette DEBUG Closed connection 370
09:52:25 INFO - 1498841545166 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50325
09:52:25 INFO - 1498841545168 Marionette DEBUG Closed connection 371
09:52:25 INFO - 1498841545169 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50326
09:52:25 INFO - 1498841545171 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:25 INFO - 1498841545173 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:25 INFO - 1498841545176 Marionette DEBUG Register listener.js for window 7
09:52:25 INFO - 1498841545183 Marionette TRACE 372 <- [1,1,null,{"sessionId":"f337ac48-6ffc-4909-a0a4-23a5688289af","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:25 INFO - 1498841545185 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:52:25 INFO - 1498841545186 Marionette TRACE 372 <- [1,2,null,{}]
09:52:25 INFO - 1498841545188 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:52:25 INFO - 1498841545188 Marionette TRACE 372 <- [1,3,null,{}]
09:52:25 INFO - 1498841545190 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:52:25 INFO - 1498841545190 Marionette TRACE 372 <- [1,4,null,{}]
09:52:25 INFO - 1498841545192 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
09:52:25 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498841545196 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:52:25 INFO - 1498841545198 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:52:25 INFO - 1498841545200 Marionette TRACE 372 <- [1,6,null,{}]
09:52:25 INFO - 1498841545203 Marionette DEBUG Closed connection 372
09:52:25 INFO - 1498841545204 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50327
09:52:25 INFO - 1498841545235 Marionette DEBUG Closed connection 373
09:52:25 INFO - 1498841545236 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50328
09:52:25 INFO - 1498841545237 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:25 INFO - 1498841545239 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:25 INFO - 1498841545242 Marionette DEBUG Register listener.js for window 7
09:52:25 INFO - 1498841545248 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4aabc921-bd35-4b45-989a-80ba35b7312d","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:25 INFO - 1498841545250 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:52:25 INFO - 1498841545253 Marionette TRACE 374 <- [1,2,null,{}]
09:52:25 INFO - 1498841545257 Marionette DEBUG Closed connection 374
09:52:25 INFO - 1498841545258 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50329
09:52:25 INFO - 1498841545260 Marionette DEBUG Closed connection 375
09:52:25 INFO - 1498841545261 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50330
09:52:25 INFO - 1498841545262 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:52:25 INFO - 1498841545263 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:25 INFO - 1498841545266 Marionette DEBUG Register listener.js for window 7
09:52:25 INFO - 1498841545271 Marionette TRACE 376 <- [1,1,null,{"sessionId":"1e4d8640-d77a-4008-b79e-f4f79466816f","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:25 INFO - 1498841545277 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:52:25 INFO - 1498841545279 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"
09:52:25 INFO - 1498841545282 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"
09:52:25 INFO - 1498841545283 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"
09:52:25 INFO - 1498841545305 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"
09:52:25 INFO - 1498841545310 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"
09:52:25 INFO - 1498841545315 Marionette TRACE 376 <- [1,2,null,{}]
09:52:25 INFO - 1498841545322 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:52:25 INFO - 1498841545326 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83377d2f-8a92-43fb-bd9a-ab71e409248f","ELEMENT":"83377d2f-8a92-43fb-bd9a-ab71e409248f"}}]
09:52:25 INFO - 1498841545327 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"83377d2f-8a92-43fb-bd9a-ab71e409248f"}]
09:52:25 INFO - 1498841545575 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:52:25 INFO - 1498841545576 Marionette TRACE 376 <- [1,4,null,{}]
09:52:25 INFO - 1498841545626 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"83377d2f-8a92-43fb-bd9a-ab71e409248f","name":"selected"}]
09:52:25 INFO - 1498841545632 Marionette TRACE 376 <- [1,5,null,{"value":true}]
09:52:25 INFO - 1498841545644 Marionette TRACE 376 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
09:52:25 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498841545648 Marionette TRACE 376 <- [1,6,null,{"value":null}]
09:52:25 INFO - 1498841545662 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
09:52:25 INFO - 1498841545664 Marionette TRACE 376 <- [1,7,null,{}]
09:52:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 503ms
09:52:25 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:52:25 INFO - 1498841545667 Marionette DEBUG Closed connection 376
09:52:25 INFO - 1498841545668 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50331
09:52:25 INFO - 1498841545670 Marionette DEBUG Closed connection 377
09:52:25 INFO - 1498841545672 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50332
09:52:25 INFO - 1498841545673 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:25 INFO - 1498841545674 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:25 INFO - 1498841545677 Marionette DEBUG Register listener.js for window 7
09:52:25 INFO - 1498841545682 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d94775bc-ab0c-4d0d-86a4-a2d086efe19d","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:25 INFO - 1498841545683 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:52:25 INFO - 1498841545684 Marionette TRACE 378 <- [1,2,null,{}]
09:52:25 INFO - 1498841545685 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:52:25 INFO - 1498841545686 Marionette TRACE 378 <- [1,3,null,{}]
09:52:25 INFO - 1498841545687 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:52:25 INFO - 1498841545688 Marionette TRACE 378 <- [1,4,null,{}]
09:52:25 INFO - 1498841545690 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
09:52:25 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498841545693 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:52:25 INFO - 1498841545694 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:52:25 INFO - 1498841545695 Marionette TRACE 378 <- [1,6,null,{}]
09:52:25 INFO - 1498841545698 Marionette DEBUG Closed connection 378
09:52:25 INFO - 1498841545699 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50333
09:52:25 INFO - 1498841545700 Marionette DEBUG Closed connection 379
09:52:25 INFO - 1498841545700 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50334
09:52:25 INFO - 1498841545702 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:25 INFO - 1498841545702 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:25 INFO - 1498841545705 Marionette DEBUG Register listener.js for window 7
09:52:25 INFO - 1498841545710 Marionette TRACE 380 <- [1,1,null,{"sessionId":"44cb9f45-7268-40c9-b4a3-d560cf820fd1","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:25 INFO - 1498841545712 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:52:25 INFO - 1498841545714 Marionette TRACE 380 <- [1,2,null,{}]
09:52:25 INFO - 1498841545717 Marionette DEBUG Closed connection 380
09:52:25 INFO - 1498841545719 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50335
09:52:25 INFO - 1498841545720 Marionette DEBUG Closed connection 381
09:52:25 INFO - 1498841545721 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50336
09:52:25 INFO - 1498841545722 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:52:25 INFO - 1498841545723 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:25 INFO - 1498841545726 Marionette DEBUG Register listener.js for window 7
09:52:25 INFO - 1498841545731 Marionette TRACE 382 <- [1,1,null,{"sessionId":"224d0b54-8e88-481d-9c5b-d00270540fc0","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:25 INFO - 1498841545732 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
09:52:25 INFO - 1498841545737 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"
09:52:25 INFO - 1498841545741 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"
09:52:25 INFO - 1498841545741 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"
09:52:25 INFO - 1498841545764 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"
09:52:25 INFO - 1498841545769 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"
09:52:25 INFO - 1498841545782 Marionette TRACE 382 <- [1,2,null,{}]
09:52:25 INFO - 1498841545783 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:52:25 INFO - 1498841545788 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e02c007a-8930-492d-b22a-0dc3bdf9caf3","ELEMENT":"e02c007a-8930-492d-b22a-0dc3bdf9caf3"}}]
09:52:25 INFO - 1498841545789 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e02c007a-8930-492d-b22a-0dc3bdf9caf3"}]
09:52:26 INFO - 1498841546032 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:52:26 INFO - 1498841546033 Marionette TRACE 382 <- [1,4,null,{}]
09:52:26 INFO - 1498841546035 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
09:52:26 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498841546038 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:52:26 INFO - 1498841546039 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:52:26 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 376ms
09:52:26 INFO - 1498841546041 Marionette TRACE 382 <- [1,6,null,{}]
09:52:26 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:52:26 INFO - 1498841546043 Marionette DEBUG Closed connection 382
09:52:26 INFO - 1498841546045 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50337
09:52:26 INFO - 1498841546045 Marionette DEBUG Closed connection 383
09:52:26 INFO - 1498841546046 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50338
09:52:26 INFO - 1498841546047 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:26 INFO - 1498841546048 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546051 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546055 Marionette TRACE 384 <- [1,1,null,{"sessionId":"036ce5f9-ce17-4ff2-ba7b-6461ffc96070","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546056 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:52:26 INFO - 1498841546057 Marionette TRACE 384 <- [1,2,null,{}]
09:52:26 INFO - 1498841546058 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:52:26 INFO - 1498841546058 Marionette TRACE 384 <- [1,3,null,{}]
09:52:26 INFO - 1498841546059 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:52:26 INFO - 1498841546060 Marionette TRACE 384 <- [1,4,null,{}]
09:52:26 INFO - 1498841546062 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
09:52:26 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498841546065 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:52:26 INFO - 1498841546066 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:52:26 INFO - 1498841546068 Marionette TRACE 384 <- [1,6,null,{}]
09:52:26 INFO - 1498841546070 Marionette DEBUG Closed connection 384
09:52:26 INFO - 1498841546071 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50339
09:52:26 INFO - 1498841546072 Marionette DEBUG Closed connection 385
09:52:26 INFO - 1498841546072 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50340
09:52:26 INFO - 1498841546074 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:26 INFO - 1498841546074 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546084 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546088 Marionette TRACE 386 <- [1,1,null,{"sessionId":"02ec2b73-c7ac-40b6-b6b3-b52f987149f5","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546089 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:52:26 INFO - 1498841546091 Marionette TRACE 386 <- [1,2,null,{}]
09:52:26 INFO - 1498841546093 Marionette DEBUG Closed connection 386
09:52:26 INFO - 1498841546094 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50341
09:52:26 INFO - 1498841546095 Marionette DEBUG Closed connection 387
09:52:26 INFO - 1498841546096 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50342
09:52:26 INFO - 1498841546097 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:52:26 INFO - 1498841546098 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546101 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546105 Marionette TRACE 388 <- [1,1,null,{"sessionId":"cfce7ea8-89b1-4d57-b7c9-f6402bc4d177","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546106 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:52:26 INFO - 1498841546108 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"
09:52:26 INFO - 1498841546111 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"
09:52:26 INFO - 1498841546112 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"
09:52:26 INFO - 1498841546133 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"
09:52:26 INFO - 1498841546140 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"
09:52:26 INFO - 1498841546144 Marionette TRACE 388 <- [1,2,null,{}]
09:52:26 INFO - 1498841546145 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:52:26 INFO - 1498841546149 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40538301-d2e5-490a-bef0-d86b86df7982","ELEMENT":"40538301-d2e5-490a-bef0-d86b86df7982"}}]
09:52:26 INFO - 1498841546150 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"40538301-d2e5-490a-bef0-d86b86df7982"}]
09:52:26 INFO - 1498841546403 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:52:26 INFO - 1498841546404 Marionette TRACE 388 <- [1,4,null,{}]
09:52:26 INFO - 1498841546406 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
09:52:26 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498841546409 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:52:26 INFO - 1498841546410 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:52:26 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 370ms
09:52:26 INFO - TEST-START | test_click.py TestClick.test_input_file
09:52:26 INFO - 1498841546411 Marionette TRACE 388 <- [1,6,null,{}]
09:52:26 INFO - 1498841546414 Marionette DEBUG Closed connection 388
09:52:26 INFO - 1498841546415 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50343
09:52:26 INFO - 1498841546415 Marionette DEBUG Closed connection 389
09:52:26 INFO - 1498841546416 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50344
09:52:26 INFO - 1498841546417 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:26 INFO - 1498841546418 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546421 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546425 Marionette TRACE 390 <- [1,1,null,{"sessionId":"494a790d-6da4-4af7-abbe-328f33d043cd","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546426 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:52:26 INFO - 1498841546427 Marionette TRACE 390 <- [1,2,null,{}]
09:52:26 INFO - 1498841546428 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:52:26 INFO - 1498841546429 Marionette TRACE 390 <- [1,3,null,{}]
09:52:26 INFO - 1498841546430 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:52:26 INFO - 1498841546430 Marionette TRACE 390 <- [1,4,null,{}]
09:52:26 INFO - 1498841546432 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
09:52:26 INFO - TEST-START: test_click.py TestClick.test_input_file1498841546435 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:52:26 INFO - 1498841546436 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:52:26 INFO - 1498841546438 Marionette TRACE 390 <- [1,6,null,{}]
09:52:26 INFO - 1498841546440 Marionette DEBUG Closed connection 390
09:52:26 INFO - 1498841546441 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50345
09:52:26 INFO - 1498841546442 Marionette DEBUG Closed connection 391
09:52:26 INFO - 1498841546442 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50346
09:52:26 INFO - 1498841546443 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:26 INFO - 1498841546444 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546449 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546453 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d6be48f5-45e3-4038-b09b-041cf9a60e4d","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546455 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:52:26 INFO - 1498841546456 Marionette TRACE 392 <- [1,2,null,{}]
09:52:26 INFO - 1498841546458 Marionette DEBUG Closed connection 392
09:52:26 INFO - 1498841546459 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50347
09:52:26 INFO - 1498841546460 Marionette DEBUG Closed connection 393
09:52:26 INFO - 1498841546460 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50348
09:52:26 INFO - 1498841546462 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:52:26 INFO - 1498841546462 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546465 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546469 Marionette TRACE 394 <- [1,1,null,{"sessionId":"b020c0c6-2456-46d4-bbcf-81888d56f764","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546470 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:52:26 INFO - 1498841546472 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"
09:52:26 INFO - 1498841546476 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"
09:52:26 INFO - 1498841546477 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"
09:52:26 INFO - 1498841546499 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:52:26 INFO - 1498841546507 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:52:26 INFO - 1498841546511 Marionette TRACE 394 <- [1,2,null,{}]
09:52:26 INFO - 1498841546513 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:52:26 INFO - 1498841546516 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b766e4cc-e98c-4db4-b321-ce2e145d7a56","ELEMENT":"b766e4cc-e98c-4db4-b321-ce2e145d7a56"}}]
09:52:26 INFO - 1498841546525 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"b766e4cc-e98c-4db4-b321-ce2e145d7a56"}]
09:52:26 INFO - 1498841546553 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
09:52:26 INFO - 1498841546555 Marionette TRACE 394 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
09:52:26 INFO - TEST-END: test_click.py TestClick.test_input_file1498841546558 Marionette TRACE 394 <- [1,5,null,{"value":null}]
09:52:26 INFO - 1498841546559 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
09:52:26 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 149ms
09:52:26 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:52:26 INFO - 1498841546561 Marionette TRACE 394 <- [1,6,null,{}]
09:52:26 INFO - 1498841546563 Marionette DEBUG Closed connection 394
09:52:26 INFO - 1498841546564 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50349
09:52:26 INFO - 1498841546565 Marionette DEBUG Closed connection 395
09:52:26 INFO - 1498841546566 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50350
09:52:26 INFO - 1498841546567 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:26 INFO - 1498841546568 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546571 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546574 Marionette TRACE 396 <- [1,1,null,{"sessionId":"7c819b28-69f5-4c59-a2c7-60b5c4607fa7","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546575 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:52:26 INFO - 1498841546576 Marionette TRACE 396 <- [1,2,null,{}]
09:52:26 INFO - 1498841546577 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:52:26 INFO - 1498841546578 Marionette TRACE 396 <- [1,3,null,{}]
09:52:26 INFO - 1498841546579 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:52:26 INFO - 1498841546579 Marionette TRACE 396 <- [1,4,null,{}]
09:52:26 INFO - 1498841546581 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
09:52:26 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498841546584 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:52:26 INFO - 1498841546585 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:52:26 INFO - 1498841546587 Marionette TRACE 396 <- [1,6,null,{}]
09:52:26 INFO - 1498841546589 Marionette DEBUG Closed connection 396
09:52:26 INFO - 1498841546590 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50351
09:52:26 INFO - 1498841546591 Marionette DEBUG Closed connection 397
09:52:26 INFO - 1498841546591 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50352
09:52:26 INFO - 1498841546592 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:26 INFO - 1498841546593 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546596 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546600 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b7d340d6-26e3-42aa-80d1-6ca04a8c72f1","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546601 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:52:26 INFO - 1498841546603 Marionette TRACE 398 <- [1,2,null,{}]
09:52:26 INFO - 1498841546605 Marionette DEBUG Closed connection 398
09:52:26 INFO - 1498841546606 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50353
09:52:26 INFO - 1498841546607 Marionette DEBUG Closed connection 399
09:52:26 INFO - 1498841546608 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50354
09:52:26 INFO - 1498841546609 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:52:26 INFO - 1498841546610 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546612 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546625 Marionette TRACE 400 <- [1,1,null,{"sessionId":"2cc38b55-a2cc-418b-9be5-f5d99a8130e4","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:26 INFO - 1498841546626 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
09:52:26 INFO - 1498841546628 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:52:26 INFO - 1498841546631 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:52:26 INFO - 1498841546632 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:52:26 INFO - 1498841546658 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"
09:52:26 INFO - 1498841546661 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"
09:52:26 INFO - 1498841546665 Marionette TRACE 400 <- [1,2,null,{}]
09:52:26 INFO - 1498841546672 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:52:26 INFO - 1498841546676 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ada79ac7-a646-412e-92bf-fd8bf4cccf94","ELEMENT":"ada79ac7-a646-412e-92bf-fd8bf4cccf94"}}]
09:52:26 INFO - 1498841546678 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:52:26 INFO - 1498841546681 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce77690f-b85f-48d9-9ff3-013405bc895b","ELEMENT":"ce77690f-b85f-48d9-9ff3-013405bc895b"}}]
09:52:26 INFO - 1498841546682 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"ada79ac7-a646-412e-92bf-fd8bf4cccf94"}]
09:52:26 INFO - 1498841546924 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:52:26 INFO - 1498841546925 Marionette TRACE 400 <- [1,5,null,{}]
09:52:26 INFO - 1498841546926 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"ce77690f-b85f-48d9-9ff3-013405bc895b"}]
09:52:26 INFO - 1498841546944 Marionette TRACE 400 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
09:52:26 INFO - 1498841546946 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:52:26 INFO - 1498841546950 Marionette TRACE 400 <- [1,7,null,{"value":false}]
09:52:26 INFO - 1498841546952 Marionette TRACE 400 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
09:52:26 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498841546955 Marionette TRACE 400 <- [1,8,null,{"value":null}]
09:52:26 INFO - 1498841546956 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
09:52:26 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 396ms
09:52:26 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:52:26 INFO - 1498841546958 Marionette TRACE 400 <- [1,9,null,{}]
09:52:26 INFO - 1498841546960 Marionette DEBUG Closed connection 400
09:52:26 INFO - 1498841546961 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50355
09:52:26 INFO - 1498841546962 Marionette DEBUG Closed connection 401
09:52:26 INFO - 1498841546962 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50356
09:52:26 INFO - 1498841546963 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:26 INFO - 1498841546964 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:26 INFO - 1498841546967 Marionette DEBUG Register listener.js for window 7
09:52:26 INFO - 1498841546971 Marionette TRACE 402 <- [1,1,null,{"sessionId":"f6f4dabe-00e6-4e4a-9647-c29d63c43afb","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:27 INFO - 1498841546972 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
09:52:27 INFO - 1498841546972 Marionette TRACE 402 <- [1,2,null,{}]
09:52:27 INFO - 1498841546973 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:52:27 INFO - 1498841546974 Marionette TRACE 402 <- [1,3,null,{}]
09:52:27 INFO - 1498841546975 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
09:52:27 INFO - 1498841546975 Marionette TRACE 402 <- [1,4,null,{}]
09:52:27 INFO - 1498841546977 Marionette TRACE 402 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
09:52:27 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498841546980 Marionette TRACE 402 <- [1,5,null,{"value":null}]
09:52:27 INFO - 1498841546981 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
09:52:27 INFO - 1498841546983 Marionette TRACE 402 <- [1,6,null,{}]
09:52:27 INFO - 1498841546985 Marionette DEBUG Closed connection 402
09:52:27 INFO - 1498841546986 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50357
09:52:27 INFO - 1498841546986 Marionette DEBUG Closed connection 403
09:52:27 INFO - 1498841546987 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50358
09:52:27 INFO - 1498841546988 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:52:27 INFO - 1498841546989 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:27 INFO - 1498841546992 Marionette DEBUG Register listener.js for window 7
09:52:27 INFO - 1498841546995 Marionette TRACE 404 <- [1,1,null,{"sessionId":"2a79e6c5-1192-4a2e-9ec0-f2f4a794adae","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:27 INFO - 1498841546996 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
09:52:27 INFO - 1498841546998 Marionette TRACE 404 <- [1,2,null,{}]
09:52:27 INFO - 1498841547000 Marionette DEBUG Closed connection 404
09:52:27 INFO - 1498841547001 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50359
09:52:27 INFO - 1498841547002 Marionette DEBUG Closed connection 405
09:52:27 INFO - 1498841547003 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50360
09:52:27 INFO - 1498841547004 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:52:27 INFO - 1498841547004 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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}
09:52:27 INFO - 1498841547007 Marionette DEBUG Register listener.js for window 7
09:52:27 INFO - 1498841547011 Marionette TRACE 406 <- [1,1,null,{"sessionId":"ea4ea1db-75eb-4201-ba7d-274b67905e87","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":3924,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprnrr4d.mozrunner","moz:accessibilityChecks":false}}]
09:52:27 INFO - 1498841547013 Marionette TRACE 406 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
09:52:27 INFO - 1498841547014 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"
09:52:27 INFO - 1498841547018 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"
09:52:27 INFO - 1498841547019 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"
09:52:27 INFO - 1498841547047 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"
09:52:27 INFO - 1498841547050 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"
09:52:27 INFO - 1498841547061 Marionette TRACE 406 <- [1,2,null,{}]
09:52:27 INFO - 1498841547062 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:52:27 INFO - 1498841547065 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a848f8a2-99dc-415a-aa3e-c5e3d5270e29","ELEMENT":"a848f8a2-99dc-415a-aa3e-c5e3d5270e29"}}]
09:52:27 INFO - 1498841547067 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a848f8a2-99dc-415a-aa3e-c5e3d5270e29"}]
09:52:27 INFO - 1498841547091 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
09:52:27 INFO - 1498841547092 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"a848f8a2-99dc-415a-aa3e-c5e3d5270e29"}]
09:52:27 INFO - 1498841547111 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element