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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:12:21 INFO - 1500984741554 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:12:21 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500984741557 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:12:21 INFO - 1500984741558 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:12:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 174ms
05:12:21 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:12:21 INFO - 1500984741559 Marionette TRACE 352 <- [1,6,null,{}]
05:12:21 INFO - 1500984741561 Marionette DEBUG Closed connection 352
05:12:21 INFO - 1500984741562 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49793
05:12:21 INFO - 1500984741562 Marionette DEBUG Closed connection 353
05:12:21 INFO - 1500984741563 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49794
05:12:21 INFO - 1500984741564 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:21 INFO - 1500984741565 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:21 INFO - 1500984741567 Marionette DEBUG Register listener.js for window 12
05:12:21 INFO - 1500984741569 Marionette TRACE 354 <- [1,1,null,{"sessionId":"6a4529ca-86c2-4f13-9ee5-1c3a0e6dc331","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:21 INFO - 1500984741571 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:12:21 INFO - 1500984741572 Marionette TRACE 354 <- [1,2,null,{}]
05:12:21 INFO - 1500984741572 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:12:21 INFO - 1500984741573 Marionette TRACE 354 <- [1,3,null,{}]
05:12:21 INFO - 1500984741574 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:12:21 INFO - 1500984741574 Marionette TRACE 354 <- [1,4,null,{}]
05:12:21 INFO - 1500984741576 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:12:21 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500984741578 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:12:21 INFO - 1500984741579 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:12:21 INFO - 1500984741580 Marionette TRACE 354 <- [1,6,null,{}]
05:12:21 INFO - 1500984741582 Marionette DEBUG Closed connection 354
05:12:21 INFO - 1500984741583 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49795
05:12:21 INFO - 1500984741583 Marionette DEBUG Closed connection 355
05:12:21 INFO - 1500984741584 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49796
05:12:21 INFO - 1500984741585 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:21 INFO - 1500984741586 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:21 INFO - 1500984741588 Marionette DEBUG Register listener.js for window 12
05:12:21 INFO - 1500984741590 Marionette TRACE 356 <- [1,1,null,{"sessionId":"00facca7-7b6b-4d7a-95b7-19b6b3c315f5","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:21 INFO - 1500984741592 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:12:21 INFO - 1500984741593 Marionette TRACE 356 <- [1,2,null,{}]
05:12:21 INFO - 1500984741595 Marionette DEBUG Closed connection 356
05:12:21 INFO - 1500984741595 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49797
05:12:21 INFO - 1500984741596 Marionette DEBUG Closed connection 357
05:12:21 INFO - 1500984741597 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49798
05:12:21 INFO - 1500984741598 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:12:21 INFO - 1500984741598 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:21 INFO - 1500984741600 Marionette DEBUG Register listener.js for window 12
05:12:21 INFO - 1500984741602 Marionette TRACE 358 <- [1,1,null,{"sessionId":"69b8efb5-1203-4084-a316-c6c18bbae239","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:21 INFO - 1500984741604 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49361/clicks.html"}]
05:12:21 INFO - 1500984741605 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/hidden.html"
05:12:21 INFO - 1500984741610 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/hidden.html"
05:12:21 INFO - 1500984741611 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/hidden.html"
05:12:21 INFO - 1500984741626 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49361/clicks.html"
05:12:21 INFO - 1500984741630 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49361/clicks.html"
05:12:21 INFO - 1500984741640 Marionette TRACE 358 <- [1,2,null,{}]
05:12:21 INFO - 1500984741641 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:12:21 INFO - 1500984741644 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"16dfe582-1504-4db3-a410-2bb3e1d33adb","ELEMENT":"16dfe582-1504-4db3-a410-2bb3e1d33adb"}}]
05:12:21 INFO - 1500984741645 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"16dfe582-1504-4db3-a410-2bb3e1d33adb"}]
05:12:21 INFO - 1500984741683 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/clicks.html"
05:12:21 INFO - 1500984741690 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/clicks.html"
05:12:21 INFO - 1500984741690 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/clicks.html"
05:12:21 INFO - 1500984741707 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49361/test.html"
05:12:21 INFO - 1500984741710 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49361/test.html"
05:12:21 INFO - 1500984741713 Marionette TRACE 358 <- [1,4,null,{}]
05:12:21 INFO - 1500984741721 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:12:21 INFO - 1500984741724 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b3523789-835a-4a01-a130-c0259b7f369a","ELEMENT":"b3523789-835a-4a01-a130-c0259b7f369a"}}]
05:12:21 INFO - 1500984741725 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:12:21 INFO - 1500984741726 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:12:21 INFO - 1500984741727 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:12:21 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500984741730 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:12:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 180ms
05:12:21 INFO - TEST-START | test_click.py TestClick.test_container_element
05:12:21 INFO - 1500984741738 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:12:21 INFO - 1500984741739 Marionette TRACE 358 <- [1,8,null,{}]
05:12:21 INFO - 1500984741741 Marionette DEBUG Closed connection 358
05:12:21 INFO - 1500984741742 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49801
05:12:21 INFO - 1500984741743 Marionette DEBUG Closed connection 359
05:12:21 INFO - 1500984741744 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49802
05:12:21 INFO - 1500984741745 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:21 INFO - 1500984741745 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:21 INFO - 1500984741747 Marionette DEBUG Register listener.js for window 12
05:12:21 INFO - 1500984741753 Marionette TRACE 360 <- [1,1,null,{"sessionId":"8757855e-cba2-42c4-9bba-34bff2caccda","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:21 INFO - 1500984741755 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:12:21 INFO - 1500984741755 Marionette TRACE 360 <- [1,2,null,{}]
05:12:21 INFO - 1500984741757 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:12:21 INFO - 1500984741757 Marionette TRACE 360 <- [1,3,null,{}]
05:12:21 INFO - 1500984741758 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:12:21 INFO - 1500984741759 Marionette TRACE 360 <- [1,4,null,{}]
05:12:21 INFO - 1500984741760 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:12:21 INFO - TEST-START: test_click.py TestClick.test_container_element1500984741764 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:12:21 INFO - 1500984741765 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:12:21 INFO - 1500984741766 Marionette TRACE 360 <- [1,6,null,{}]
05:12:21 INFO - 1500984741767 Marionette DEBUG Closed connection 360
05:12:21 INFO - 1500984741768 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49803
05:12:21 INFO - 1500984741769 Marionette DEBUG Closed connection 361
05:12:21 INFO - 1500984741770 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49804
05:12:21 INFO - 1500984741770 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:21 INFO - 1500984741771 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:21 INFO - 1500984741773 Marionette DEBUG Register listener.js for window 12
05:12:21 INFO - 1500984741775 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e609a0c2-2423-48f2-ac16-9c77a026abc0","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:21 INFO - 1500984741777 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:12:21 INFO - 1500984741778 Marionette TRACE 362 <- [1,2,null,{}]
05:12:21 INFO - 1500984741781 Marionette DEBUG Closed connection 362
05:12:21 INFO - 1500984741782 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49805
05:12:21 INFO - 1500984741783 Marionette DEBUG Closed connection 363
05:12:21 INFO - 1500984741784 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49806
05:12:21 INFO - 1500984741785 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:12:21 INFO - 1500984741785 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:21 INFO - 1500984741787 Marionette DEBUG Register listener.js for window 12
05:12:21 INFO - 1500984741790 Marionette TRACE 364 <- [1,1,null,{"sessionId":"011b5682-0124-4a74-bb6a-b5da62186fcf","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:21 INFO - 1500984741791 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:12:21 INFO - 1500984741793 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/test.html"
05:12:21 INFO - 1500984741796 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/test.html"
05:12:21 INFO - 1500984741796 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/test.html"
05:12:21 INFO - 1500984741823 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"
05:12:21 INFO - 1500984741828 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"
05:12:21 INFO - 1500984741832 Marionette TRACE 364 <- [1,2,null,{}]
05:12:21 INFO - 1500984741841 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:12:21 INFO - 1500984741844 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0912d69d-9809-4484-b72f-f185459e20cd","ELEMENT":"0912d69d-9809-4484-b72f-f185459e20cd"}}]
05:12:21 INFO - 1500984741845 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0912d69d-9809-4484-b72f-f185459e20cd"}]
05:12:22 INFO - 1500984742092 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:12:22 INFO - 1500984742093 Marionette TRACE 364 <- [1,4,null,{}]
05:12:22 INFO - 1500984742094 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0912d69d-9809-4484-b72f-f185459e20cd","name":"selected"}]
05:12:22 INFO - 1500984742098 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:12:22 INFO - 1500984742100 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:12:22 INFO - TEST-END: test_click.py TestClick.test_container_element1500984742102 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:12:22 INFO - 1500984742103 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:12:22 INFO - 1500984742104 Marionette TRACE 364 <- [1,7,null,{}]
05:12:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 364ms
05:12:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:12:22 INFO - 1500984742106 Marionette DEBUG Closed connection 364
05:12:22 INFO - 1500984742107 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49807
05:12:22 INFO - 1500984742107 Marionette DEBUG Closed connection 365
05:12:22 INFO - 1500984742108 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49808
05:12:22 INFO - 1500984742109 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:22 INFO - 1500984742110 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742112 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742115 Marionette TRACE 366 <- [1,1,null,{"sessionId":"580a7dd6-9a21-412e-abed-417efc87b805","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742116 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:12:22 INFO - 1500984742117 Marionette TRACE 366 <- [1,2,null,{}]
05:12:22 INFO - 1500984742118 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:12:22 INFO - 1500984742118 Marionette TRACE 366 <- [1,3,null,{}]
05:12:22 INFO - 1500984742119 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:12:22 INFO - 1500984742119 Marionette TRACE 366 <- [1,4,null,{}]
05:12:22 INFO - 1500984742121 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:12:22 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500984742123 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:12:22 INFO - 1500984742124 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:12:22 INFO - 1500984742125 Marionette TRACE 366 <- [1,6,null,{}]
05:12:22 INFO - 1500984742127 Marionette DEBUG Closed connection 366
05:12:22 INFO - 1500984742128 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49809
05:12:22 INFO - 1500984742128 Marionette DEBUG Closed connection 367
05:12:22 INFO - 1500984742129 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49810
05:12:22 INFO - 1500984742130 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:22 INFO - 1500984742130 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742132 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742135 Marionette TRACE 368 <- [1,1,null,{"sessionId":"7fc59ba9-a66a-497a-9058-2b7ec6075e58","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742137 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:12:22 INFO - 1500984742138 Marionette TRACE 368 <- [1,2,null,{}]
05:12:22 INFO - 1500984742139 Marionette DEBUG Closed connection 368
05:12:22 INFO - 1500984742140 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49811
05:12:22 INFO - 1500984742141 Marionette DEBUG Closed connection 369
05:12:22 INFO - 1500984742141 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49812
05:12:22 INFO - 1500984742142 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:12:22 INFO - 1500984742143 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742145 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742148 Marionette TRACE 370 <- [1,1,null,{"sessionId":"12ec90e9-80ce-41bf-9ebd-6923970c6d77","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742150 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:12:22 INFO - 1500984742151 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"
05:12:22 INFO - 1500984742153 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"
05:12:22 INFO - 1500984742153 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"
05:12:22 INFO - 1500984742169 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"
05:12:22 INFO - 1500984742172 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"
05:12:22 INFO - 1500984742175 Marionette TRACE 370 <- [1,2,null,{}]
05:12:22 INFO - 1500984742176 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:12:22 INFO - 1500984742183 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"406532d8-7299-4e0f-aa10-5b4141af3060","ELEMENT":"406532d8-7299-4e0f-aa10-5b4141af3060"}}]
05:12:22 INFO - 1500984742186 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"406532d8-7299-4e0f-aa10-5b4141af3060"}]
05:12:22 INFO - 1500984742423 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:12:22 INFO - 1500984742424 Marionette TRACE 370 <- [1,4,null,{}]
05:12:22 INFO - 1500984742425 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"406532d8-7299-4e0f-aa10-5b4141af3060","name":"selected"}]
05:12:22 INFO - 1500984742428 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:12:22 INFO - 1500984742430 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:12:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500984742432 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:12:22 INFO - 1500984742433 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:12:22 INFO - 1500984742434 Marionette TRACE 370 <- [1,7,null,{}]
05:12:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 330ms
05:12:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:12:22 INFO - 1500984742436 Marionette DEBUG Closed connection 370
05:12:22 INFO - 1500984742437 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49813
05:12:22 INFO - 1500984742438 Marionette DEBUG Closed connection 371
05:12:22 INFO - 1500984742439 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49814
05:12:22 INFO - 1500984742439 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:22 INFO - 1500984742440 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742442 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742445 Marionette TRACE 372 <- [1,1,null,{"sessionId":"faa7453a-b406-4c4c-81cc-902354cd043e","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742447 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:12:22 INFO - 1500984742447 Marionette TRACE 372 <- [1,2,null,{}]
05:12:22 INFO - 1500984742448 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:12:22 INFO - 1500984742448 Marionette TRACE 372 <- [1,3,null,{}]
05:12:22 INFO - 1500984742453 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:12:22 INFO - 1500984742454 Marionette TRACE 372 <- [1,4,null,{}]
05:12:22 INFO - 1500984742504 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:12:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500984742506 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:12:22 INFO - 1500984742524 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:12:22 INFO - 1500984742525 Marionette TRACE 372 <- [1,6,null,{}]
05:12:22 INFO - 1500984742537 Marionette DEBUG Closed connection 372
05:12:22 INFO - 1500984742538 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49815
05:12:22 INFO - 1500984742539 Marionette DEBUG Closed connection 373
05:12:22 INFO - 1500984742541 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49816
05:12:22 INFO - 1500984742543 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:22 INFO - 1500984742543 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742545 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742548 Marionette TRACE 374 <- [1,1,null,{"sessionId":"69a3dbd9-7fc7-44de-a538-881f481a4033","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742550 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:12:22 INFO - 1500984742551 Marionette TRACE 374 <- [1,2,null,{}]
05:12:22 INFO - 1500984742553 Marionette DEBUG Closed connection 374
05:12:22 INFO - 1500984742553 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49817
05:12:22 INFO - 1500984742554 Marionette DEBUG Closed connection 375
05:12:22 INFO - 1500984742555 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49818
05:12:22 INFO - 1500984742556 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:12:22 INFO - 1500984742556 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742558 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742561 Marionette TRACE 376 <- [1,1,null,{"sessionId":"adb41ffd-87ba-4d06-bbba-2d3c218354c3","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742564 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:12:22 INFO - 1500984742566 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"
05:12:22 INFO - 1500984742568 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"
05:12:22 INFO - 1500984742568 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"
05:12:22 INFO - 1500984742586 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"
05:12:22 INFO - 1500984742587 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"
05:12:22 INFO - 1500984742591 Marionette TRACE 376 <- [1,2,null,{}]
05:12:22 INFO - 1500984742592 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:12:22 INFO - 1500984742594 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e94cd51d-8b95-4ef2-ab75-50def84778ee","ELEMENT":"e94cd51d-8b95-4ef2-ab75-50def84778ee"}}]
05:12:22 INFO - 1500984742595 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e94cd51d-8b95-4ef2-ab75-50def84778ee"}]
05:12:22 INFO - 1500984742844 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:12:22 INFO - 1500984742845 Marionette TRACE 376 <- [1,4,null,{}]
05:12:22 INFO - 1500984742846 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:12:22 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500984742849 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:12:22 INFO - 1500984742850 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:12:22 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 417ms
05:12:22 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:12:22 INFO - 1500984742851 Marionette TRACE 376 <- [1,6,null,{}]
05:12:22 INFO - 1500984742853 Marionette DEBUG Closed connection 376
05:12:22 INFO - 1500984742854 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49819
05:12:22 INFO - 1500984742855 Marionette DEBUG Closed connection 377
05:12:22 INFO - 1500984742855 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49820
05:12:22 INFO - 1500984742856 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:22 INFO - 1500984742857 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742859 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742862 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ef497a75-48e5-4968-8b5b-d0efc4e95073","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742863 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:12:22 INFO - 1500984742864 Marionette TRACE 378 <- [1,2,null,{}]
05:12:22 INFO - 1500984742865 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:12:22 INFO - 1500984742865 Marionette TRACE 378 <- [1,3,null,{}]
05:12:22 INFO - 1500984742866 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:12:22 INFO - 1500984742866 Marionette TRACE 378 <- [1,4,null,{}]
05:12:22 INFO - 1500984742868 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:12:22 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500984742870 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:12:22 INFO - 1500984742871 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:12:22 INFO - 1500984742872 Marionette TRACE 378 <- [1,6,null,{}]
05:12:22 INFO - 1500984742874 Marionette DEBUG Closed connection 378
05:12:22 INFO - 1500984742874 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49821
05:12:22 INFO - 1500984742875 Marionette DEBUG Closed connection 379
05:12:22 INFO - 1500984742876 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49822
05:12:22 INFO - 1500984742877 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:22 INFO - 1500984742877 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742879 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742882 Marionette TRACE 380 <- [1,1,null,{"sessionId":"801b24db-5ce7-4fab-9448-add46279ea72","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742883 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:12:22 INFO - 1500984742884 Marionette TRACE 380 <- [1,2,null,{}]
05:12:22 INFO - 1500984742886 Marionette DEBUG Closed connection 380
05:12:22 INFO - 1500984742887 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49823
05:12:22 INFO - 1500984742887 Marionette DEBUG Closed connection 381
05:12:22 INFO - 1500984742888 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49824
05:12:22 INFO - 1500984742889 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:12:22 INFO - 1500984742890 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:22 INFO - 1500984742891 Marionette DEBUG Register listener.js for window 12
05:12:22 INFO - 1500984742894 Marionette TRACE 382 <- [1,1,null,{"sessionId":"0c05a93a-dff8-4984-b484-5c1f4e322857","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:22 INFO - 1500984742896 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:12:22 INFO - 1500984742897 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"
05:12:22 INFO - 1500984742899 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"
05:12:22 INFO - 1500984742899 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"
05:12:22 INFO - 1500984742910 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"
05:12:22 INFO - 1500984742917 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"
05:12:22 INFO - 1500984742921 Marionette TRACE 382 <- [1,2,null,{}]
05:12:22 INFO - 1500984742922 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:12:22 INFO - 1500984742925 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"18768f0b-1807-43da-bacd-68649d2e6ad2","ELEMENT":"18768f0b-1807-43da-bacd-68649d2e6ad2"}}]
05:12:22 INFO - 1500984742925 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"18768f0b-1807-43da-bacd-68649d2e6ad2"}]
05:12:23 INFO - 1500984743168 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:12:23 INFO - 1500984743168 Marionette TRACE 382 <- [1,4,null,{}]
05:12:23 INFO - 1500984743170 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:12:23 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500984743173 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:12:23 INFO - 1500984743174 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:12:23 INFO - 1500984743175 Marionette TRACE 382 <- [1,6,null,{}]
05:12:23 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 324ms
05:12:23 INFO - TEST-START | test_click.py TestClick.test_input_file
05:12:23 INFO - 1500984743177 Marionette DEBUG Closed connection 382
05:12:23 INFO - 1500984743178 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49825
05:12:23 INFO - 1500984743178 Marionette DEBUG Closed connection 383
05:12:23 INFO - 1500984743179 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49826
05:12:23 INFO - 1500984743180 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:23 INFO - 1500984743181 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743183 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743185 Marionette TRACE 384 <- [1,1,null,{"sessionId":"fe4546f1-9ac4-4875-ba5e-ea0d70072cc2","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743187 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:12:23 INFO - 1500984743188 Marionette TRACE 384 <- [1,2,null,{}]
05:12:23 INFO - 1500984743188 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:12:23 INFO - 1500984743189 Marionette TRACE 384 <- [1,3,null,{}]
05:12:23 INFO - 1500984743190 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:12:23 INFO - 1500984743190 Marionette TRACE 384 <- [1,4,null,{}]
05:12:23 INFO - 1500984743192 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:12:23 INFO - TEST-START: test_click.py TestClick.test_input_file1500984743194 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:12:23 INFO - 1500984743195 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:12:23 INFO - 1500984743196 Marionette TRACE 384 <- [1,6,null,{}]
05:12:23 INFO - 1500984743197 Marionette DEBUG Closed connection 384
05:12:23 INFO - 1500984743198 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49827
05:12:23 INFO - 1500984743199 Marionette DEBUG Closed connection 385
05:12:23 INFO - 1500984743199 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49828
05:12:23 INFO - 1500984743200 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:23 INFO - 1500984743201 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743203 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743205 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c309792b-21ca-4af0-80ef-3dd52c9988d3","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743207 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:12:23 INFO - 1500984743208 Marionette TRACE 386 <- [1,2,null,{}]
05:12:23 INFO - 1500984743210 Marionette DEBUG Closed connection 386
05:12:23 INFO - 1500984743211 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49829
05:12:23 INFO - 1500984743211 Marionette DEBUG Closed connection 387
05:12:23 INFO - 1500984743212 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49830
05:12:23 INFO - 1500984743213 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:12:23 INFO - 1500984743213 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743215 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743218 Marionette TRACE 388 <- [1,1,null,{"sessionId":"bc54d49a-52bb-4c53-92da-8a5b96c523b6","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743220 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:12:23 INFO - 1500984743221 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"
05:12:23 INFO - 1500984743223 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"
05:12:23 INFO - 1500984743223 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"
05:12:23 INFO - 1500984743241 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:12:23 INFO - 1500984743244 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:12:23 INFO - 1500984743246 Marionette TRACE 388 <- [1,2,null,{}]
05:12:23 INFO - 1500984743256 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:12:23 INFO - 1500984743258 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c120b682-1dfa-44a1-9b93-da4e07a5d372","ELEMENT":"c120b682-1dfa-44a1-9b93-da4e07a5d372"}}]
05:12:23 INFO - 1500984743259 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c120b682-1dfa-44a1-9b93-da4e07a5d372"}]
05:12:23 INFO - 1500984743291 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:12:23 INFO - 1500984743293 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:12:23 INFO - TEST-END: test_click.py TestClick.test_input_file1500984743295 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:12:23 INFO - 1500984743296 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:12:23 INFO - 1500984743297 Marionette TRACE 388 <- [1,6,null,{}]
05:12:23 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 122ms
05:12:23 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:12:23 INFO - 1500984743300 Marionette DEBUG Closed connection 388
05:12:23 INFO - 1500984743300 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49831
05:12:23 INFO - 1500984743301 Marionette DEBUG Closed connection 389
05:12:23 INFO - 1500984743302 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49832
05:12:23 INFO - 1500984743303 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:23 INFO - 1500984743303 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743305 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743308 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d075550c-aefb-4cd0-8633-d69f1ba6b842","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743309 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:12:23 INFO - 1500984743310 Marionette TRACE 390 <- [1,2,null,{}]
05:12:23 INFO - 1500984743311 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:12:23 INFO - 1500984743311 Marionette TRACE 390 <- [1,3,null,{}]
05:12:23 INFO - 1500984743312 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:12:23 INFO - 1500984743313 Marionette TRACE 390 <- [1,4,null,{}]
05:12:23 INFO - 1500984743314 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:12:23 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500984743317 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:12:23 INFO - 1500984743317 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:12:23 INFO - 1500984743318 Marionette TRACE 390 <- [1,6,null,{}]
05:12:23 INFO - 1500984743320 Marionette DEBUG Closed connection 390
05:12:23 INFO - 1500984743321 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49833
05:12:23 INFO - 1500984743321 Marionette DEBUG Closed connection 391
05:12:23 INFO - 1500984743322 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49834
05:12:23 INFO - 1500984743323 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:23 INFO - 1500984743323 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743325 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743328 Marionette TRACE 392 <- [1,1,null,{"sessionId":"2060b6b1-c629-4030-8606-a934455a2a72","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743330 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:12:23 INFO - 1500984743331 Marionette TRACE 392 <- [1,2,null,{}]
05:12:23 INFO - 1500984743332 Marionette DEBUG Closed connection 392
05:12:23 INFO - 1500984743333 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49835
05:12:23 INFO - 1500984743333 Marionette DEBUG Closed connection 393
05:12:23 INFO - 1500984743334 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49836
05:12:23 INFO - 1500984743335 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:12:23 INFO - 1500984743335 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743337 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743340 Marionette TRACE 394 <- [1,1,null,{"sessionId":"07757040-0509-4a99-b49f-9ad4c87cc548","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743341 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:12:23 INFO - 1500984743343 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:12:23 INFO - 1500984743344 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:12:23 INFO - 1500984743345 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:12:23 INFO - 1500984743364 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"
05:12:23 INFO - 1500984743365 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"
05:12:23 INFO - 1500984743368 Marionette TRACE 394 <- [1,2,null,{}]
05:12:23 INFO - 1500984743370 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:12:23 INFO - 1500984743372 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43ab009c-0734-4955-ad2c-a45aba297f95","ELEMENT":"43ab009c-0734-4955-ad2c-a45aba297f95"}}]
05:12:23 INFO - 1500984743373 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:12:23 INFO - 1500984743375 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7d214932-2f7d-49a9-9404-30e963334925","ELEMENT":"7d214932-2f7d-49a9-9404-30e963334925"}}]
05:12:23 INFO - 1500984743376 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"43ab009c-0734-4955-ad2c-a45aba297f95"}]
05:12:23 INFO - 1500984743612 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:12:23 INFO - 1500984743612 Marionette TRACE 394 <- [1,5,null,{}]
05:12:23 INFO - 1500984743613 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"7d214932-2f7d-49a9-9404-30e963334925"}]
05:12:23 INFO - 1500984743627 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:12:23 INFO - 1500984743629 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:12:23 INFO - 1500984743631 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:12:23 INFO - 1500984743632 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:12:23 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500984743635 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:12:23 INFO - 1500984743635 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:12:23 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 339ms
05:12:23 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:12:23 INFO - 1500984743637 Marionette TRACE 394 <- [1,9,null,{}]
05:12:23 INFO - 1500984743638 Marionette DEBUG Closed connection 394
05:12:23 INFO - 1500984743639 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49837
05:12:23 INFO - 1500984743640 Marionette DEBUG Closed connection 395
05:12:23 INFO - 1500984743641 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49838
05:12:23 INFO - 1500984743642 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:23 INFO - 1500984743642 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743644 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743647 Marionette TRACE 396 <- [1,1,null,{"sessionId":"05611327-b1d2-47cd-9104-361dcdf6bb3f","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743649 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:12:23 INFO - 1500984743649 Marionette TRACE 396 <- [1,2,null,{}]
05:12:23 INFO - 1500984743650 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:12:23 INFO - 1500984743651 Marionette TRACE 396 <- [1,3,null,{}]
05:12:23 INFO - 1500984743651 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:12:23 INFO - 1500984743652 Marionette TRACE 396 <- [1,4,null,{}]
05:12:23 INFO - 1500984743653 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:12:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500984743656 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:12:23 INFO - 1500984743656 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:12:23 INFO - 1500984743657 Marionette TRACE 396 <- [1,6,null,{}]
05:12:23 INFO - 1500984743659 Marionette DEBUG Closed connection 396
05:12:23 INFO - 1500984743660 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49839
05:12:23 INFO - 1500984743661 Marionette DEBUG Closed connection 397
05:12:23 INFO - 1500984743661 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49840
05:12:23 INFO - 1500984743662 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:12:23 INFO - 1500984743663 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743665 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743667 Marionette TRACE 398 <- [1,1,null,{"sessionId":"ad9f0008-da2a-435c-8705-86787714c4ee","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743669 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:12:23 INFO - 1500984743670 Marionette TRACE 398 <- [1,2,null,{}]
05:12:23 INFO - 1500984743672 Marionette DEBUG Closed connection 398
05:12:23 INFO - 1500984743672 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49841
05:12:23 INFO - 1500984743673 Marionette DEBUG Closed connection 399
05:12:23 INFO - 1500984743674 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49842
05:12:23 INFO - 1500984743675 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:12:23 INFO - 1500984743675 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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}
05:12:23 INFO - 1500984743677 Marionette DEBUG Register listener.js for window 12
05:12:23 INFO - 1500984743679 Marionette TRACE 400 <- [1,1,null,{"sessionId":"5b8c7607-63be-428e-9392-fd4c549e1cd2","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":2996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvw4r06.mozrunner","moz:accessibilityChecks":false}}]
05:12:23 INFO - 1500984743681 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:12:23 INFO - 1500984743683 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"
05:12:23 INFO - 1500984743685 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"
05:12:23 INFO - 1500984743685 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"
05:12:23 INFO - 1500984743705 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"
05:12:23 INFO - 1500984743707 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"
05:12:23 INFO - 1500984743709 Marionette TRACE 400 <- [1,2,null,{}]
05:12:23 INFO - 1500984743710 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:12:23 INFO - 1500984743719 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"860584ae-5055-49bb-a00b-6f799ce51c39","ELEMENT":"860584ae-5055-49bb-a00b-6f799ce51c39"}}]
05:12:23 INFO - 1500984743720 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"860584ae-5055-49bb-a00b-6f799ce51c39"}]
05:12:23 INFO - 1500984743747 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:12:23 INFO - 1500984743748 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"860584ae-5055-49bb-a00b-6f799ce51c39"}]
05:12:23 INFO - 1500984743766 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element