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]
04:59:39 INFO - 1498910379556 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}]
04:59:39 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498910379558 Marionette TRACE 358 <- [1,5,null,{"value":null}]
04:59:39 INFO - 1498910379559 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
04:59:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 176ms
04:59:39 INFO - 1498910379561 Marionette TRACE 358 <- [1,6,null,{}]
04:59:39 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:59:39 INFO - 1498910379563 Marionette DEBUG Closed connection 358
04:59:39 INFO - 1498910379564 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50580
04:59:39 INFO - 1498910379565 Marionette DEBUG Closed connection 359
04:59:39 INFO - 1498910379565 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50581
04:59:39 INFO - 1498910379566 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:39 INFO - 1498910379567 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:39 INFO - 1498910379569 Marionette DEBUG Register listener.js for window 7
04:59:39 INFO - 1498910379572 Marionette TRACE 360 <- [1,1,null,{"sessionId":"fa9eb12e-c363-4d90-8350-db15e2dcea3b","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:39 INFO - 1498910379573 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:59:39 INFO - 1498910379573 Marionette TRACE 360 <- [1,2,null,{}]
04:59:39 INFO - 1498910379574 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:59:39 INFO - 1498910379575 Marionette TRACE 360 <- [1,3,null,{}]
04:59:39 INFO - 1498910379575 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:59:39 INFO - 1498910379576 Marionette TRACE 360 <- [1,4,null,{}]
04:59:39 INFO - 1498910379577 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}]
04:59:39 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498910379580 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:59:39 INFO - 1498910379581 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:59:39 INFO - 1498910379582 Marionette TRACE 360 <- [1,6,null,{}]
04:59:39 INFO - 1498910379583 Marionette DEBUG Closed connection 360
04:59:39 INFO - 1498910379584 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50582
04:59:39 INFO - 1498910379585 Marionette DEBUG Closed connection 361
04:59:39 INFO - 1498910379586 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50583
04:59:39 INFO - 1498910379586 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:39 INFO - 1498910379587 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:39 INFO - 1498910379589 Marionette DEBUG Register listener.js for window 7
04:59:39 INFO - 1498910379592 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c14b0892-0cb0-4b2d-92e7-f78f4e1a8e6d","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:39 INFO - 1498910379593 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:59:39 INFO - 1498910379594 Marionette TRACE 362 <- [1,2,null,{}]
04:59:39 INFO - 1498910379595 Marionette DEBUG Closed connection 362
04:59:39 INFO - 1498910379596 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50584
04:59:39 INFO - 1498910379596 Marionette DEBUG Closed connection 363
04:59:39 INFO - 1498910379597 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50585
04:59:39 INFO - 1498910379598 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:59:39 INFO - 1498910379599 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:39 INFO - 1498910379601 Marionette DEBUG Register listener.js for window 7
04:59:39 INFO - 1498910379603 Marionette TRACE 364 <- [1,1,null,{"sessionId":"e832f4ef-8e12-4db7-9afa-f4dc01c3e392","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:39 INFO - 1498910379604 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:50185/clicks.html"}]
04:59:39 INFO - 1498910379606 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50185/hidden.html"
04:59:39 INFO - 1498910379611 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50185/hidden.html"
04:59:39 INFO - 1498910379611 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50185/hidden.html"
04:59:39 INFO - 1498910379631 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50185/clicks.html"
04:59:39 INFO - 1498910379633 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50185/clicks.html"
04:59:39 INFO - 1498910379637 Marionette TRACE 364 <- [1,2,null,{}]
04:59:39 INFO - 1498910379645 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:59:39 INFO - 1498910379647 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c656855b-df17-4753-b959-d794127f75ff","ELEMENT":"c656855b-df17-4753-b959-d794127f75ff"}}]
04:59:39 INFO - 1498910379648 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c656855b-df17-4753-b959-d794127f75ff"}]
04:59:39 INFO - 1498910379686 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50185/clicks.html"
04:59:39 INFO - 1498910379694 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50185/clicks.html"
04:59:39 INFO - 1498910379694 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50185/clicks.html"
04:59:39 INFO - 1498910379711 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50185/test.html"
04:59:39 INFO - 1498910379712 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50185/test.html"
04:59:39 INFO - 1498910379715 Marionette TRACE 364 <- [1,4,null,{}]
04:59:39 INFO - 1498910379716 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:59:39 INFO - 1498910379724 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"61aa6e90-13d5-4841-90bd-50f0c02b907c","ELEMENT":"61aa6e90-13d5-4841-90bd-50f0c02b907c"}}]
04:59:39 INFO - 1498910379726 Marionette TRACE 364 -> [0,6,"getTitle",{}]
04:59:39 INFO - 1498910379727 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
04:59:39 INFO - 1498910379729 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}]
04:59:39 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498910379732 Marionette TRACE 364 <- [1,7,null,{"value":null}]
04:59:39 INFO - 1498910379732 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
04:59:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 173ms
04:59:39 INFO - TEST-START | test_click.py TestClick.test_container_element
04:59:39 INFO - 1498910379734 Marionette TRACE 364 <- [1,8,null,{}]
04:59:39 INFO - 1498910379736 Marionette DEBUG Closed connection 364
04:59:39 INFO - 1498910379737 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50588
04:59:39 INFO - 1498910379738 Marionette DEBUG Closed connection 365
04:59:39 INFO - 1498910379739 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50589
04:59:39 INFO - 1498910379740 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:39 INFO - 1498910379740 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:39 INFO - 1498910379743 Marionette DEBUG Register listener.js for window 7
04:59:39 INFO - 1498910379745 Marionette TRACE 366 <- [1,1,null,{"sessionId":"cb738f4f-ad03-41e6-8c2a-16235282343b","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:39 INFO - 1498910379746 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:59:39 INFO - 1498910379747 Marionette TRACE 366 <- [1,2,null,{}]
04:59:39 INFO - 1498910379748 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:59:39 INFO - 1498910379748 Marionette TRACE 366 <- [1,3,null,{}]
04:59:39 INFO - 1498910379749 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:59:39 INFO - 1498910379749 Marionette TRACE 366 <- [1,4,null,{}]
04:59:39 INFO - 1498910379751 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}]
04:59:39 INFO - TEST-START: test_click.py TestClick.test_container_element1498910379754 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:59:39 INFO - 1498910379754 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:59:39 INFO - 1498910379755 Marionette TRACE 366 <- [1,6,null,{}]
04:59:39 INFO - 1498910379757 Marionette DEBUG Closed connection 366
04:59:39 INFO - 1498910379758 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50590
04:59:39 INFO - 1498910379759 Marionette DEBUG Closed connection 367
04:59:39 INFO - 1498910379760 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50591
04:59:39 INFO - 1498910379760 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:39 INFO - 1498910379761 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:39 INFO - 1498910379763 Marionette DEBUG Register listener.js for window 7
04:59:39 INFO - 1498910379766 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d26ac652-0d82-4b9c-9bf9-a779f723c83c","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:39 INFO - 1498910379767 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:59:39 INFO - 1498910379768 Marionette TRACE 368 <- [1,2,null,{}]
04:59:39 INFO - 1498910379770 Marionette DEBUG Closed connection 368
04:59:39 INFO - 1498910379770 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50592
04:59:39 INFO - 1498910379771 Marionette DEBUG Closed connection 369
04:59:39 INFO - 1498910379772 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50593
04:59:39 INFO - 1498910379772 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:59:39 INFO - 1498910379773 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:39 INFO - 1498910379775 Marionette DEBUG Register listener.js for window 7
04:59:39 INFO - 1498910379778 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a50bf03f-992c-426b-b583-b79137ac347e","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:39 INFO - 1498910379779 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"}]
04:59:39 INFO - 1498910379780 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50185/test.html"
04:59:39 INFO - 1498910379782 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50185/test.html"
04:59:39 INFO - 1498910379782 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50185/test.html"
04:59:39 INFO - 1498910379802 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"
04:59:39 INFO - 1498910379808 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"
04:59:39 INFO - 1498910379810 Marionette TRACE 370 <- [1,2,null,{}]
04:59:39 INFO - 1498910379812 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:59:39 INFO - 1498910379814 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"27c4cf09-e221-43e7-a781-e5e30ec6cad7","ELEMENT":"27c4cf09-e221-43e7-a781-e5e30ec6cad7"}}]
04:59:39 INFO - 1498910379815 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"27c4cf09-e221-43e7-a781-e5e30ec6cad7"}]
04:59:40 INFO - 1498910380069 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:59:40 INFO - 1498910380069 Marionette TRACE 370 <- [1,4,null,{}]
04:59:40 INFO - 1498910380071 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"27c4cf09-e221-43e7-a781-e5e30ec6cad7","name":"selected"}]
04:59:40 INFO - 1498910380075 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:59:40 INFO - 1498910380077 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}]
04:59:40 INFO - TEST-END: test_click.py TestClick.test_container_element1498910380079 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:59:40 INFO - 1498910380080 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:59:40 INFO - 1498910380081 Marionette TRACE 370 <- [1,7,null,{}]
04:59:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 347ms
04:59:40 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:59:40 INFO - 1498910380083 Marionette DEBUG Closed connection 370
04:59:40 INFO - 1498910380084 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50594
04:59:40 INFO - 1498910380085 Marionette DEBUG Closed connection 371
04:59:40 INFO - 1498910380086 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50595
04:59:40 INFO - 1498910380086 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:40 INFO - 1498910380087 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380089 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380092 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ee984264-3066-47b2-be06-95f87d5fe31e","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380093 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:59:40 INFO - 1498910380094 Marionette TRACE 372 <- [1,2,null,{}]
04:59:40 INFO - 1498910380095 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:59:40 INFO - 1498910380095 Marionette TRACE 372 <- [1,3,null,{}]
04:59:40 INFO - 1498910380096 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:59:40 INFO - 1498910380096 Marionette TRACE 372 <- [1,4,null,{}]
04:59:40 INFO - 1498910380098 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}]
04:59:40 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498910380100 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:59:40 INFO - 1498910380104 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:59:40 INFO - 1498910380105 Marionette TRACE 372 <- [1,6,null,{}]
04:59:40 INFO - 1498910380107 Marionette DEBUG Closed connection 372
04:59:40 INFO - 1498910380108 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50596
04:59:40 INFO - 1498910380108 Marionette DEBUG Closed connection 373
04:59:40 INFO - 1498910380109 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50597
04:59:40 INFO - 1498910380110 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:40 INFO - 1498910380111 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380113 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380115 Marionette TRACE 374 <- [1,1,null,{"sessionId":"52f1bb41-f261-4696-a061-77c3c30ef8df","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380116 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:59:40 INFO - 1498910380117 Marionette TRACE 374 <- [1,2,null,{}]
04:59:40 INFO - 1498910380119 Marionette DEBUG Closed connection 374
04:59:40 INFO - 1498910380120 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50598
04:59:40 INFO - 1498910380120 Marionette DEBUG Closed connection 375
04:59:40 INFO - 1498910380121 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50599
04:59:40 INFO - 1498910380122 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:59:40 INFO - 1498910380123 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380125 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380128 Marionette TRACE 376 <- [1,1,null,{"sessionId":"9ccd8529-7ff4-42f5-a034-9c156026ad3c","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380129 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"}]
04:59:40 INFO - 1498910380130 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"
04:59:40 INFO - 1498910380132 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"
04:59:40 INFO - 1498910380132 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"
04:59:40 INFO - 1498910380147 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"
04:59:40 INFO - 1498910380150 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"
04:59:40 INFO - 1498910380153 Marionette TRACE 376 <- [1,2,null,{}]
04:59:40 INFO - 1498910380159 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:59:40 INFO - 1498910380162 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3dedcad8-4152-46f9-b898-ea35eaf71e84","ELEMENT":"3dedcad8-4152-46f9-b898-ea35eaf71e84"}}]
04:59:40 INFO - 1498910380163 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"3dedcad8-4152-46f9-b898-ea35eaf71e84"}]
04:59:40 INFO - 1498910380401 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:59:40 INFO - 1498910380402 Marionette TRACE 376 <- [1,4,null,{}]
04:59:40 INFO - 1498910380403 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"3dedcad8-4152-46f9-b898-ea35eaf71e84","name":"selected"}]
04:59:40 INFO - 1498910380407 Marionette TRACE 376 <- [1,5,null,{"value":true}]
04:59:40 INFO - 1498910380408 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}]
04:59:40 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498910380411 Marionette TRACE 376 <- [1,6,null,{"value":null}]
04:59:40 INFO - 1498910380412 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
04:59:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 332ms
04:59:40 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:59:40 INFO - 1498910380413 Marionette TRACE 376 <- [1,7,null,{}]
04:59:40 INFO - 1498910380415 Marionette DEBUG Closed connection 376
04:59:40 INFO - 1498910380416 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50600
04:59:40 INFO - 1498910380417 Marionette DEBUG Closed connection 377
04:59:40 INFO - 1498910380418 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50601
04:59:40 INFO - 1498910380419 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:40 INFO - 1498910380419 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380421 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380424 Marionette TRACE 378 <- [1,1,null,{"sessionId":"12b54794-1885-439c-b681-772f0fe89ca8","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380425 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:59:40 INFO - 1498910380425 Marionette TRACE 378 <- [1,2,null,{}]
04:59:40 INFO - 1498910380426 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:59:40 INFO - 1498910380427 Marionette TRACE 378 <- [1,3,null,{}]
04:59:40 INFO - 1498910380428 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:59:40 INFO - 1498910380428 Marionette TRACE 378 <- [1,4,null,{}]
04:59:40 INFO - 1498910380430 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}]
04:59:40 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498910380432 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:59:40 INFO - 1498910380433 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:59:40 INFO - 1498910380434 Marionette TRACE 378 <- [1,6,null,{}]
04:59:40 INFO - 1498910380436 Marionette DEBUG Closed connection 378
04:59:40 INFO - 1498910380436 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50602
04:59:40 INFO - 1498910380437 Marionette DEBUG Closed connection 379
04:59:40 INFO - 1498910380438 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50603
04:59:40 INFO - 1498910380439 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:40 INFO - 1498910380439 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380441 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380444 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d3b8eccb-0432-4752-bae5-7f02061608bc","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380445 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:59:40 INFO - 1498910380446 Marionette TRACE 380 <- [1,2,null,{}]
04:59:40 INFO - 1498910380448 Marionette DEBUG Closed connection 380
04:59:40 INFO - 1498910380449 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50604
04:59:40 INFO - 1498910380449 Marionette DEBUG Closed connection 381
04:59:40 INFO - 1498910380450 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50605
04:59:40 INFO - 1498910380451 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:59:40 INFO - 1498910380452 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380454 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380456 Marionette TRACE 382 <- [1,1,null,{"sessionId":"4c4cb365-3642-4bf6-a181-06e0914b539c","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380457 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"}]
04:59:40 INFO - 1498910380459 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"
04:59:40 INFO - 1498910380461 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"
04:59:40 INFO - 1498910380461 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"
04:59:40 INFO - 1498910380480 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"
04:59:40 INFO - 1498910380482 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"
04:59:40 INFO - 1498910380485 Marionette TRACE 382 <- [1,2,null,{}]
04:59:40 INFO - 1498910380493 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:59:40 INFO - 1498910380496 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"76db2c85-b3de-47f9-b7a0-02eeab54a3b4","ELEMENT":"76db2c85-b3de-47f9-b7a0-02eeab54a3b4"}}]
04:59:40 INFO - 1498910380497 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"76db2c85-b3de-47f9-b7a0-02eeab54a3b4"}]
04:59:40 INFO - 1498910380740 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:59:40 INFO - 1498910380740 Marionette TRACE 382 <- [1,4,null,{}]
04:59:40 INFO - 1498910380742 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}]
04:59:40 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498910380745 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:59:40 INFO - 1498910380746 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:59:40 INFO - 1498910380747 Marionette TRACE 382 <- [1,6,null,{}]
04:59:40 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 333ms
04:59:40 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:59:40 INFO - 1498910380749 Marionette DEBUG Closed connection 382
04:59:40 INFO - 1498910380750 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50606
04:59:40 INFO - 1498910380751 Marionette DEBUG Closed connection 383
04:59:40 INFO - 1498910380752 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50607
04:59:40 INFO - 1498910380753 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:40 INFO - 1498910380753 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380755 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380758 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a3b7b235-d7ed-4ff8-ad22-49e511f2ffb6","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380759 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:59:40 INFO - 1498910380759 Marionette TRACE 384 <- [1,2,null,{}]
04:59:40 INFO - 1498910380760 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:59:40 INFO - 1498910380761 Marionette TRACE 384 <- [1,3,null,{}]
04:59:40 INFO - 1498910380761 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:59:40 INFO - 1498910380762 Marionette TRACE 384 <- [1,4,null,{}]
04:59:40 INFO - 1498910380763 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}]
04:59:40 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498910380766 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:59:40 INFO - 1498910380766 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:59:40 INFO - 1498910380767 Marionette TRACE 384 <- [1,6,null,{}]
04:59:40 INFO - 1498910380769 Marionette DEBUG Closed connection 384
04:59:40 INFO - 1498910380770 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50608
04:59:40 INFO - 1498910380771 Marionette DEBUG Closed connection 385
04:59:40 INFO - 1498910380771 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50609
04:59:40 INFO - 1498910380772 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:40 INFO - 1498910380773 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380775 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380777 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e650bdc4-45e7-4f56-9829-5c0aa10f8e61","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380778 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:59:40 INFO - 1498910380779 Marionette TRACE 386 <- [1,2,null,{}]
04:59:40 INFO - 1498910380781 Marionette DEBUG Closed connection 386
04:59:40 INFO - 1498910380782 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50610
04:59:40 INFO - 1498910380782 Marionette DEBUG Closed connection 387
04:59:40 INFO - 1498910380783 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50611
04:59:40 INFO - 1498910380784 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:59:40 INFO - 1498910380785 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:40 INFO - 1498910380787 Marionette DEBUG Register listener.js for window 7
04:59:40 INFO - 1498910380789 Marionette TRACE 388 <- [1,1,null,{"sessionId":"0253dcdf-25d8-4a26-bd65-d2de83a83709","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:40 INFO - 1498910380790 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"}]
04:59:40 INFO - 1498910380791 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"
04:59:40 INFO - 1498910380793 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"
04:59:40 INFO - 1498910380794 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"
04:59:40 INFO - 1498910380809 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"
04:59:40 INFO - 1498910380812 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"
04:59:40 INFO - 1498910380815 Marionette TRACE 388 <- [1,2,null,{}]
04:59:40 INFO - 1498910380816 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:59:40 INFO - 1498910380824 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7705d27e-eb01-4e46-bdd9-b78859eb09ec","ELEMENT":"7705d27e-eb01-4e46-bdd9-b78859eb09ec"}}]
04:59:40 INFO - 1498910380826 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"7705d27e-eb01-4e46-bdd9-b78859eb09ec"}]
04:59:41 INFO - 1498910381067 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:59:41 INFO - 1498910381067 Marionette TRACE 388 <- [1,4,null,{}]
04:59:41 INFO - 1498910381069 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}]
04:59:41 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498910381072 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:59:41 INFO - 1498910381073 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:59:41 INFO - 1498910381074 Marionette TRACE 388 <- [1,6,null,{}]
04:59:41 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 326ms
04:59:41 INFO - TEST-START | test_click.py TestClick.test_input_file
04:59:41 INFO - 1498910381076 Marionette DEBUG Closed connection 388
04:59:41 INFO - 1498910381077 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50612
04:59:41 INFO - 1498910381078 Marionette DEBUG Closed connection 389
04:59:41 INFO - 1498910381079 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50613
04:59:41 INFO - 1498910381080 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:41 INFO - 1498910381080 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381083 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381085 Marionette TRACE 390 <- [1,1,null,{"sessionId":"a6220a7d-b018-4cc8-be53-cb7ecbbd331c","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381086 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:59:41 INFO - 1498910381087 Marionette TRACE 390 <- [1,2,null,{}]
04:59:41 INFO - 1498910381088 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:59:41 INFO - 1498910381088 Marionette TRACE 390 <- [1,3,null,{}]
04:59:41 INFO - 1498910381089 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:59:41 INFO - 1498910381089 Marionette TRACE 390 <- [1,4,null,{}]
04:59:41 INFO - 1498910381091 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}]
04:59:41 INFO - TEST-START: test_click.py TestClick.test_input_file1498910381093 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:59:41 INFO - 1498910381094 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:59:41 INFO - 1498910381095 Marionette TRACE 390 <- [1,6,null,{}]
04:59:41 INFO - 1498910381097 Marionette DEBUG Closed connection 390
04:59:41 INFO - 1498910381098 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50614
04:59:41 INFO - 1498910381098 Marionette DEBUG Closed connection 391
04:59:41 INFO - 1498910381099 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50615
04:59:41 INFO - 1498910381100 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:41 INFO - 1498910381101 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381102 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381105 Marionette TRACE 392 <- [1,1,null,{"sessionId":"4846aa16-0f7e-42f0-aa37-ff88a32b5cc4","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381106 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:59:41 INFO - 1498910381107 Marionette TRACE 392 <- [1,2,null,{}]
04:59:41 INFO - 1498910381109 Marionette DEBUG Closed connection 392
04:59:41 INFO - 1498910381110 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50616
04:59:41 INFO - 1498910381110 Marionette DEBUG Closed connection 393
04:59:41 INFO - 1498910381111 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50617
04:59:41 INFO - 1498910381112 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:59:41 INFO - 1498910381113 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381115 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381118 Marionette TRACE 394 <- [1,1,null,{"sessionId":"cfd6d562-c7a7-412e-98c3-6c6d837238dd","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381119 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:59:41 INFO - 1498910381120 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"
04:59:41 INFO - 1498910381122 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"
04:59:41 INFO - 1498910381123 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"
04:59:41 INFO - 1498910381133 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:59:41 INFO - 1498910381137 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:59:41 INFO - 1498910381146 Marionette TRACE 394 <- [1,2,null,{}]
04:59:41 INFO - 1498910381148 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:59:41 INFO - 1498910381152 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ec4dc698-0ece-4f0b-9933-8dda5a3b50b6","ELEMENT":"ec4dc698-0ece-4f0b-9933-8dda5a3b50b6"}}]
04:59:41 INFO - 1498910381153 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"ec4dc698-0ece-4f0b-9933-8dda5a3b50b6"}]
04:59:41 INFO - 1498910381184 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]
04:59:41 INFO - 1498910381186 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}]
04:59:41 INFO - TEST-END: test_click.py TestClick.test_input_file1498910381189 Marionette TRACE 394 <- [1,5,null,{"value":null}]
04:59:41 INFO - 1498910381190 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
04:59:41 INFO - 1498910381191 Marionette TRACE 394 <- [1,6,null,{}]
04:59:41 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 116ms
04:59:41 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:59:41 INFO - 1498910381193 Marionette DEBUG Closed connection 394
04:59:41 INFO - 1498910381194 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50618
04:59:41 INFO - 1498910381194 Marionette DEBUG Closed connection 395
04:59:41 INFO - 1498910381195 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50619
04:59:41 INFO - 1498910381196 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:41 INFO - 1498910381197 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381199 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381202 Marionette TRACE 396 <- [1,1,null,{"sessionId":"43cb872b-18ce-4388-be7c-9c957b30db6e","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381203 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:59:41 INFO - 1498910381203 Marionette TRACE 396 <- [1,2,null,{}]
04:59:41 INFO - 1498910381204 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:59:41 INFO - 1498910381204 Marionette TRACE 396 <- [1,3,null,{}]
04:59:41 INFO - 1498910381205 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:59:41 INFO - 1498910381205 Marionette TRACE 396 <- [1,4,null,{}]
04:59:41 INFO - 1498910381207 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}]
04:59:41 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498910381209 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:59:41 INFO - 1498910381210 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:59:41 INFO - 1498910381211 Marionette TRACE 396 <- [1,6,null,{}]
04:59:41 INFO - 1498910381213 Marionette DEBUG Closed connection 396
04:59:41 INFO - 1498910381214 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50620
04:59:41 INFO - 1498910381214 Marionette DEBUG Closed connection 397
04:59:41 INFO - 1498910381215 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50621
04:59:41 INFO - 1498910381216 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:41 INFO - 1498910381216 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381218 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381221 Marionette TRACE 398 <- [1,1,null,{"sessionId":"623119c4-994e-48fa-b17f-623dd8ebaf85","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381222 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:59:41 INFO - 1498910381223 Marionette TRACE 398 <- [1,2,null,{}]
04:59:41 INFO - 1498910381225 Marionette DEBUG Closed connection 398
04:59:41 INFO - 1498910381226 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50622
04:59:41 INFO - 1498910381226 Marionette DEBUG Closed connection 399
04:59:41 INFO - 1498910381227 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50623
04:59:41 INFO - 1498910381228 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:59:41 INFO - 1498910381228 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381230 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381233 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e20d0ef1-900f-4923-b9b2-9c1a37d7c48d","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381234 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"}]
04:59:41 INFO - 1498910381235 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:59:41 INFO - 1498910381238 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:59:41 INFO - 1498910381238 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:59:41 INFO - 1498910381248 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"
04:59:41 INFO - 1498910381251 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"
04:59:41 INFO - 1498910381260 Marionette TRACE 400 <- [1,2,null,{}]
04:59:41 INFO - 1498910381261 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:59:41 INFO - 1498910381263 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d733f002-51ce-4860-9cb1-c2c8736e03c7","ELEMENT":"d733f002-51ce-4860-9cb1-c2c8736e03c7"}}]
04:59:41 INFO - 1498910381264 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:59:41 INFO - 1498910381266 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86f34b79-7522-4e85-9289-1789a0e509dd","ELEMENT":"86f34b79-7522-4e85-9289-1789a0e509dd"}}]
04:59:41 INFO - 1498910381267 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"d733f002-51ce-4860-9cb1-c2c8736e03c7"}]
04:59:41 INFO - 1498910381502 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:59:41 INFO - 1498910381502 Marionette TRACE 400 <- [1,5,null,{}]
04:59:41 INFO - 1498910381503 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"86f34b79-7522-4e85-9289-1789a0e509dd"}]
04:59:41 INFO - 1498910381517 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]
04:59:41 INFO - 1498910381520 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:59:41 INFO - 1498910381522 Marionette TRACE 400 <- [1,7,null,{"value":false}]
04:59:41 INFO - 1498910381524 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}]
04:59:41 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498910381526 Marionette TRACE 400 <- [1,8,null,{"value":null}]
04:59:41 INFO - 1498910381527 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
04:59:41 INFO - 1498910381528 Marionette TRACE 400 <- [1,9,null,{}]
04:59:41 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
04:59:41 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:59:41 INFO - 1498910381530 Marionette DEBUG Closed connection 400
04:59:41 INFO - 1498910381531 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50624
04:59:41 INFO - 1498910381532 Marionette DEBUG Closed connection 401
04:59:41 INFO - 1498910381533 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50625
04:59:41 INFO - 1498910381534 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:41 INFO - 1498910381534 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381536 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381539 Marionette TRACE 402 <- [1,1,null,{"sessionId":"01e15f8d-b0dc-4418-b5af-ca028b56d2d7","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381540 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
04:59:41 INFO - 1498910381541 Marionette TRACE 402 <- [1,2,null,{}]
04:59:41 INFO - 1498910381541 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:59:41 INFO - 1498910381542 Marionette TRACE 402 <- [1,3,null,{}]
04:59:41 INFO - 1498910381543 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
04:59:41 INFO - 1498910381543 Marionette TRACE 402 <- [1,4,null,{}]
04:59:41 INFO - 1498910381545 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}]
04:59:41 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498910381547 Marionette TRACE 402 <- [1,5,null,{"value":null}]
04:59:41 INFO - 1498910381548 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
04:59:41 INFO - 1498910381549 Marionette TRACE 402 <- [1,6,null,{}]
04:59:41 INFO - 1498910381551 Marionette DEBUG Closed connection 402
04:59:41 INFO - 1498910381552 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50626
04:59:41 INFO - 1498910381553 Marionette DEBUG Closed connection 403
04:59:41 INFO - 1498910381553 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50627
04:59:41 INFO - 1498910381554 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:59:41 INFO - 1498910381555 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381557 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381559 Marionette TRACE 404 <- [1,1,null,{"sessionId":"c5dc267c-228b-409b-b2f4-4f92f6bc8e3e","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381560 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
04:59:41 INFO - 1498910381561 Marionette TRACE 404 <- [1,2,null,{}]
04:59:41 INFO - 1498910381563 Marionette DEBUG Closed connection 404
04:59:41 INFO - 1498910381564 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50628
04:59:41 INFO - 1498910381564 Marionette DEBUG Closed connection 405
04:59:41 INFO - 1498910381565 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50629
04:59:41 INFO - 1498910381566 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:59:41 INFO - 1498910381567 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}
04:59:41 INFO - 1498910381569 Marionette DEBUG Register listener.js for window 7
04:59:41 INFO - 1498910381572 Marionette TRACE 406 <- [1,1,null,{"sessionId":"cef5a15b-6da5-4cef-ba70-b6605838c69b","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphmautt.mozrunner","moz:accessibilityChecks":false}}]
04:59:41 INFO - 1498910381573 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"}]
04:59:41 INFO - 1498910381574 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"
04:59:41 INFO - 1498910381577 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"
04:59:41 INFO - 1498910381577 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"
04:59:41 INFO - 1498910381594 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"
04:59:41 INFO - 1498910381596 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"
04:59:41 INFO - 1498910381599 Marionette TRACE 406 <- [1,2,null,{}]
04:59:41 INFO - 1498910381600 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:59:41 INFO - 1498910381608 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"109a6bfe-098c-4d58-868a-87fdb876282f","ELEMENT":"109a6bfe-098c-4d58-868a-87fdb876282f"}}]
04:59:41 INFO - 1498910381609 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"109a6bfe-098c-4d58-868a-87fdb876282f"}]
04:59:41 INFO - 1498910381631 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
04:59:41 INFO - 1498910381632 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"109a6bfe-098c-4d58-868a-87fdb876282f"}]
04:59:41 INFO - 1498910381646 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element