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:1318:5\n"},null]
06:28:11 INFO - 1499261291619 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
06:28:11 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499261291622 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:28:11 INFO - 1499261291623 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:28:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 123ms
06:28:11 INFO - 1499261291624 Marionette TRACE 352 <- [1,6,null,{}]
06:28:11 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:28:11 INFO - 1499261291625 Marionette DEBUG Closed connection 352
06:28:11 INFO - 1499261291627 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50247
06:28:11 INFO - 1499261291628 Marionette DEBUG Closed connection 353
06:28:11 INFO - 1499261291629 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50248
06:28:11 INFO - 1499261291630 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:11 INFO - 1499261291630 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:11 INFO - 1499261291632 Marionette DEBUG Register listener.js for window 12
06:28:11 INFO - 1499261291635 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7b9d66e2-194a-4caf-987b-18abf3ce4851","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:11 INFO - 1499261291636 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:28:11 INFO - 1499261291636 Marionette TRACE 354 <- [1,2,null,{}]
06:28:11 INFO - 1499261291637 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:28:11 INFO - 1499261291638 Marionette TRACE 354 <- [1,3,null,{}]
06:28:11 INFO - 1499261291638 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:28:11 INFO - 1499261291639 Marionette TRACE 354 <- [1,4,null,{}]
06:28:11 INFO - 1499261291641 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
06:28:11 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499261291643 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:28:11 INFO - 1499261291644 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:28:11 INFO - 1499261291645 Marionette TRACE 354 <- [1,6,null,{}]
06:28:11 INFO - 1499261291646 Marionette DEBUG Closed connection 354
06:28:11 INFO - 1499261291647 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50249
06:28:11 INFO - 1499261291648 Marionette DEBUG Closed connection 355
06:28:11 INFO - 1499261291649 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50250
06:28:11 INFO - 1499261291649 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:11 INFO - 1499261291650 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:11 INFO - 1499261291652 Marionette DEBUG Register listener.js for window 12
06:28:11 INFO - 1499261291654 Marionette TRACE 356 <- [1,1,null,{"sessionId":"84d5f7e6-8ed7-4444-90f2-84aa69beb4a6","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:11 INFO - 1499261291655 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:28:11 INFO - 1499261291656 Marionette TRACE 356 <- [1,2,null,{}]
06:28:11 INFO - 1499261291658 Marionette DEBUG Closed connection 356
06:28:11 INFO - 1499261291659 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50251
06:28:11 INFO - 1499261291659 Marionette DEBUG Closed connection 357
06:28:11 INFO - 1499261291660 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50252
06:28:11 INFO - 1499261291661 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:28:11 INFO - 1499261291661 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:11 INFO - 1499261291664 Marionette DEBUG Register listener.js for window 12
06:28:11 INFO - 1499261291666 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3aaaf6a3-47b3-4550-949c-66d126c61f06","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:11 INFO - 1499261291668 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49818/clicks.html"}]
06:28:11 INFO - 1499261291669 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49818/hidden.html"
06:28:11 INFO - 1499261291677 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49818/hidden.html"
06:28:11 INFO - 1499261291678 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49818/hidden.html"
06:28:11 INFO - 1499261291696 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49818/clicks.html"
06:28:11 INFO - 1499261291698 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49818/clicks.html"
06:28:11 INFO - 1499261291701 Marionette TRACE 358 <- [1,2,null,{}]
06:28:11 INFO - 1499261291702 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:28:11 INFO - 1499261291704 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"378c3607-9efb-4ccd-921d-51a9411e79f2","ELEMENT":"378c3607-9efb-4ccd-921d-51a9411e79f2"}}]
06:28:11 INFO - 1499261291711 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"378c3607-9efb-4ccd-921d-51a9411e79f2"}]
06:28:11 INFO - 1499261291757 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49818/clicks.html"
06:28:11 INFO - 1499261291764 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49818/clicks.html"
06:28:11 INFO - 1499261291765 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49818/clicks.html"
06:28:11 INFO - 1499261291779 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49818/test.html"
06:28:11 INFO - 1499261291783 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49818/test.html"
06:28:11 INFO - 1499261291785 Marionette TRACE 358 <- [1,4,null,{}]
06:28:11 INFO - 1499261291786 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:28:11 INFO - 1499261291795 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"052f37ac-b841-4cc3-b5ea-4397ea811b80","ELEMENT":"052f37ac-b841-4cc3-b5ea-4397ea811b80"}}]
06:28:11 INFO - 1499261291796 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:28:11 INFO - 1499261291797 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:28:11 INFO - 1499261291799 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
06:28:11 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499261291801 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:28:11 INFO - 1499261291802 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:28:11 INFO - 1499261291803 Marionette TRACE 358 <- [1,8,null,{}]
06:28:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 178ms
06:28:11 INFO - TEST-START | test_click.py TestClick.test_container_element
06:28:11 INFO - 1499261291805 Marionette DEBUG Closed connection 358
06:28:11 INFO - 1499261291806 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50255
06:28:11 INFO - 1499261291806 Marionette DEBUG Closed connection 359
06:28:11 INFO - 1499261291807 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50256
06:28:11 INFO - 1499261291808 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:11 INFO - 1499261291810 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:11 INFO - 1499261291812 Marionette DEBUG Register listener.js for window 12
06:28:11 INFO - 1499261291815 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4c83f701-7240-4a2a-b79c-dd7c62de1768","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:11 INFO - 1499261291816 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:28:11 INFO - 1499261291816 Marionette TRACE 360 <- [1,2,null,{}]
06:28:11 INFO - 1499261291817 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:28:11 INFO - 1499261291817 Marionette TRACE 360 <- [1,3,null,{}]
06:28:11 INFO - 1499261291818 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:28:11 INFO - 1499261291819 Marionette TRACE 360 <- [1,4,null,{}]
06:28:11 INFO - 1499261291820 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
06:28:11 INFO - TEST-START: test_click.py TestClick.test_container_element1499261291823 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:28:11 INFO - 1499261291824 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:28:11 INFO - 1499261291825 Marionette TRACE 360 <- [1,6,null,{}]
06:28:11 INFO - 1499261291826 Marionette DEBUG Closed connection 360
06:28:11 INFO - 1499261291827 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50257
06:28:11 INFO - 1499261291828 Marionette DEBUG Closed connection 361
06:28:11 INFO - 1499261291829 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50258
06:28:11 INFO - 1499261291829 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:11 INFO - 1499261291830 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:11 INFO - 1499261291832 Marionette DEBUG Register listener.js for window 12
06:28:11 INFO - 1499261291835 Marionette TRACE 362 <- [1,1,null,{"sessionId":"196d7afc-95ab-47bf-94a8-f04ea199720a","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:11 INFO - 1499261291835 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:28:11 INFO - 1499261291836 Marionette TRACE 362 <- [1,2,null,{}]
06:28:11 INFO - 1499261291838 Marionette DEBUG Closed connection 362
06:28:11 INFO - 1499261291839 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50259
06:28:11 INFO - 1499261291839 Marionette DEBUG Closed connection 363
06:28:11 INFO - 1499261291840 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50260
06:28:11 INFO - 1499261291841 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:28:11 INFO - 1499261291842 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:11 INFO - 1499261291844 Marionette DEBUG Register listener.js for window 12
06:28:11 INFO - 1499261291846 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5b2007da-17a0-4bbc-918f-11f0819c391b","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:11 INFO - 1499261291847 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:28:11 INFO - 1499261291849 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49818/test.html"
06:28:11 INFO - 1499261291851 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49818/test.html"
06:28:11 INFO - 1499261291851 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49818/test.html"
06:28:11 INFO - 1499261291868 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:11 INFO - 1499261291873 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:11 INFO - 1499261291881 Marionette TRACE 364 <- [1,2,null,{}]
06:28:11 INFO - 1499261291883 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:28:11 INFO - 1499261291886 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4318677-38ae-469d-b666-f0114945186d","ELEMENT":"c4318677-38ae-469d-b666-f0114945186d"}}]
06:28:11 INFO - 1499261291887 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c4318677-38ae-469d-b666-f0114945186d"}]
06:28:12 INFO - 1499261292126 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:28:12 INFO - 1499261292126 Marionette TRACE 364 <- [1,4,null,{}]
06:28:12 INFO - 1499261292127 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"c4318677-38ae-469d-b666-f0114945186d","name":"selected"}]
06:28:12 INFO - 1499261292131 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:28:12 INFO - 1499261292133 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
06:28:12 INFO - TEST-END: test_click.py TestClick.test_container_element1499261292135 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:28:12 INFO - 1499261292136 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:28:12 INFO - 1499261292137 Marionette TRACE 364 <- [1,7,null,{}]
06:28:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 334ms
06:28:12 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:28:12 INFO - 1499261292139 Marionette DEBUG Closed connection 364
06:28:12 INFO - 1499261292140 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50261
06:28:12 INFO - 1499261292141 Marionette DEBUG Closed connection 365
06:28:12 INFO - 1499261292142 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50262
06:28:12 INFO - 1499261292143 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:12 INFO - 1499261292143 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292145 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292148 Marionette TRACE 366 <- [1,1,null,{"sessionId":"7219fb00-3295-45f1-8cd8-7f38abe99b40","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292149 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:28:12 INFO - 1499261292149 Marionette TRACE 366 <- [1,2,null,{}]
06:28:12 INFO - 1499261292150 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:28:12 INFO - 1499261292151 Marionette TRACE 366 <- [1,3,null,{}]
06:28:12 INFO - 1499261292152 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:28:12 INFO - 1499261292152 Marionette TRACE 366 <- [1,4,null,{}]
06:28:12 INFO - 1499261292154 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
06:28:12 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499261292156 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:28:12 INFO - 1499261292157 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:28:12 INFO - 1499261292158 Marionette TRACE 366 <- [1,6,null,{}]
06:28:12 INFO - 1499261292160 Marionette DEBUG Closed connection 366
06:28:12 INFO - 1499261292160 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50263
06:28:12 INFO - 1499261292161 Marionette DEBUG Closed connection 367
06:28:12 INFO - 1499261292162 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50264
06:28:12 INFO - 1499261292163 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:12 INFO - 1499261292163 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292165 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292167 Marionette TRACE 368 <- [1,1,null,{"sessionId":"885252e9-b83a-4ab4-9c17-2359c1bb5953","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292168 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:28:12 INFO - 1499261292170 Marionette TRACE 368 <- [1,2,null,{}]
06:28:12 INFO - 1499261292173 Marionette DEBUG Closed connection 368
06:28:12 INFO - 1499261292174 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50265
06:28:12 INFO - 1499261292174 Marionette DEBUG Closed connection 369
06:28:12 INFO - 1499261292175 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50266
06:28:12 INFO - 1499261292176 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:28:12 INFO - 1499261292177 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292179 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292181 Marionette TRACE 370 <- [1,1,null,{"sessionId":"24ad42ac-68ff-4198-8dbb-cbfa81898c22","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292182 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:28:12 INFO - 1499261292184 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292187 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292187 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292213 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292218 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292222 Marionette TRACE 370 <- [1,2,null,{}]
06:28:12 INFO - 1499261292227 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:28:12 INFO - 1499261292230 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d775bb9d-d648-4e8e-a0ed-af7fb8fe8ddf","ELEMENT":"d775bb9d-d648-4e8e-a0ed-af7fb8fe8ddf"}}]
06:28:12 INFO - 1499261292231 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d775bb9d-d648-4e8e-a0ed-af7fb8fe8ddf"}]
06:28:12 INFO - 1499261292475 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:28:12 INFO - 1499261292475 Marionette TRACE 370 <- [1,4,null,{}]
06:28:12 INFO - 1499261292476 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d775bb9d-d648-4e8e-a0ed-af7fb8fe8ddf","name":"selected"}]
06:28:12 INFO - 1499261292480 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:28:12 INFO - 1499261292482 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
06:28:12 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499261292484 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:28:12 INFO - 1499261292485 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:28:12 INFO - 1499261292486 Marionette TRACE 370 <- [1,7,null,{}]
06:28:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 348ms
06:28:12 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:28:12 INFO - 1499261292488 Marionette DEBUG Closed connection 370
06:28:12 INFO - 1499261292489 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50267
06:28:12 INFO - 1499261292490 Marionette DEBUG Closed connection 371
06:28:12 INFO - 1499261292490 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50268
06:28:12 INFO - 1499261292491 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:12 INFO - 1499261292492 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292494 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292497 Marionette TRACE 372 <- [1,1,null,{"sessionId":"762cb018-ef19-49b6-b29a-4002974cd8c0","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292498 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:28:12 INFO - 1499261292498 Marionette TRACE 372 <- [1,2,null,{}]
06:28:12 INFO - 1499261292499 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:28:12 INFO - 1499261292500 Marionette TRACE 372 <- [1,3,null,{}]
06:28:12 INFO - 1499261292500 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:28:12 INFO - 1499261292501 Marionette TRACE 372 <- [1,4,null,{}]
06:28:12 INFO - 1499261292502 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
06:28:12 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499261292505 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:28:12 INFO - 1499261292506 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:28:12 INFO - 1499261292507 Marionette TRACE 372 <- [1,6,null,{}]
06:28:12 INFO - 1499261292508 Marionette DEBUG Closed connection 372
06:28:12 INFO - 1499261292509 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50269
06:28:12 INFO - 1499261292510 Marionette DEBUG Closed connection 373
06:28:12 INFO - 1499261292510 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50270
06:28:12 INFO - 1499261292511 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:12 INFO - 1499261292512 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292514 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292516 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6e8122ff-f487-4501-97d3-57da18477937","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292517 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:28:12 INFO - 1499261292518 Marionette TRACE 374 <- [1,2,null,{}]
06:28:12 INFO - 1499261292520 Marionette DEBUG Closed connection 374
06:28:12 INFO - 1499261292521 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50271
06:28:12 INFO - 1499261292522 Marionette DEBUG Closed connection 375
06:28:12 INFO - 1499261292522 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50272
06:28:12 INFO - 1499261292523 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:28:12 INFO - 1499261292524 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292526 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292528 Marionette TRACE 376 <- [1,1,null,{"sessionId":"33404c64-d974-4227-9cd7-de8e1a0e44df","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292529 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
06:28:12 INFO - 1499261292531 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292533 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292533 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292550 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:28:12 INFO - 1499261292553 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:28:12 INFO - 1499261292556 Marionette TRACE 376 <- [1,2,null,{}]
06:28:12 INFO - 1499261292564 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:28:12 INFO - 1499261292566 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f07799c6-573e-4f09-a206-478423e4606d","ELEMENT":"f07799c6-573e-4f09-a206-478423e4606d"}}]
06:28:12 INFO - 1499261292567 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f07799c6-573e-4f09-a206-478423e4606d"}]
06:28:12 INFO - 1499261292803 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:28:12 INFO - 1499261292803 Marionette TRACE 376 <- [1,4,null,{}]
06:28:12 INFO - 1499261292805 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
06:28:12 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499261292808 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:28:12 INFO - 1499261292808 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:28:12 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 324ms
06:28:12 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:28:12 INFO - 1499261292810 Marionette TRACE 376 <- [1,6,null,{}]
06:28:12 INFO - 1499261292811 Marionette DEBUG Closed connection 376
06:28:12 INFO - 1499261292812 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50273
06:28:12 INFO - 1499261292813 Marionette DEBUG Closed connection 377
06:28:12 INFO - 1499261292814 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50274
06:28:12 INFO - 1499261292815 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:12 INFO - 1499261292815 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292817 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292820 Marionette TRACE 378 <- [1,1,null,{"sessionId":"022c2a8a-f272-4b6e-9f6d-e4f511b0077b","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292821 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:28:12 INFO - 1499261292821 Marionette TRACE 378 <- [1,2,null,{}]
06:28:12 INFO - 1499261292822 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:28:12 INFO - 1499261292823 Marionette TRACE 378 <- [1,3,null,{}]
06:28:12 INFO - 1499261292823 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:28:12 INFO - 1499261292824 Marionette TRACE 378 <- [1,4,null,{}]
06:28:12 INFO - 1499261292826 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
06:28:12 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499261292828 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:28:12 INFO - 1499261292829 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:28:12 INFO - 1499261292830 Marionette TRACE 378 <- [1,6,null,{}]
06:28:12 INFO - 1499261292831 Marionette DEBUG Closed connection 378
06:28:12 INFO - 1499261292832 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50275
06:28:12 INFO - 1499261292833 Marionette DEBUG Closed connection 379
06:28:12 INFO - 1499261292833 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50276
06:28:12 INFO - 1499261292834 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:12 INFO - 1499261292835 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292837 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292839 Marionette TRACE 380 <- [1,1,null,{"sessionId":"717b7bd8-69f6-4827-bae1-3426f0a697b8","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292840 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:28:12 INFO - 1499261292841 Marionette TRACE 380 <- [1,2,null,{}]
06:28:12 INFO - 1499261292843 Marionette DEBUG Closed connection 380
06:28:12 INFO - 1499261292844 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50277
06:28:12 INFO - 1499261292844 Marionette DEBUG Closed connection 381
06:28:12 INFO - 1499261292845 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50278
06:28:12 INFO - 1499261292846 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:28:12 INFO - 1499261292846 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:12 INFO - 1499261292848 Marionette DEBUG Register listener.js for window 12
06:28:12 INFO - 1499261292851 Marionette TRACE 382 <- [1,1,null,{"sessionId":"7d48dd6a-f6d2-42cf-ab2c-28c7f073700b","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:12 INFO - 1499261292852 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:28:12 INFO - 1499261292854 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:28:12 INFO - 1499261292856 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:28:12 INFO - 1499261292856 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:28:12 INFO - 1499261292871 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292878 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:12 INFO - 1499261292881 Marionette TRACE 382 <- [1,2,null,{}]
06:28:12 INFO - 1499261292911 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:28:12 INFO - 1499261292914 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0d6ae044-5529-4d94-91b2-b6b376a1c32b","ELEMENT":"0d6ae044-5529-4d94-91b2-b6b376a1c32b"}}]
06:28:12 INFO - 1499261292915 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"0d6ae044-5529-4d94-91b2-b6b376a1c32b"}]
06:28:13 INFO - 1499261293153 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:28:13 INFO - 1499261293153 Marionette TRACE 382 <- [1,4,null,{}]
06:28:13 INFO - 1499261293155 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
06:28:13 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499261293158 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:28:13 INFO - 1499261293159 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:28:13 INFO - 1499261293160 Marionette TRACE 382 <- [1,6,null,{}]
06:28:13 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 350ms
06:28:13 INFO - TEST-START | test_click.py TestClick.test_input_file
06:28:13 INFO - 1499261293162 Marionette DEBUG Closed connection 382
06:28:13 INFO - 1499261293163 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50279
06:28:13 INFO - 1499261293164 Marionette DEBUG Closed connection 383
06:28:13 INFO - 1499261293164 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50280
06:28:13 INFO - 1499261293165 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:13 INFO - 1499261293166 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293168 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293177 Marionette TRACE 384 <- [1,1,null,{"sessionId":"68a34de8-6a54-4989-9e92-87f44ffeb46c","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293178 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:28:13 INFO - 1499261293179 Marionette TRACE 384 <- [1,2,null,{}]
06:28:13 INFO - 1499261293179 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:28:13 INFO - 1499261293180 Marionette TRACE 384 <- [1,3,null,{}]
06:28:13 INFO - 1499261293180 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:28:13 INFO - 1499261293181 Marionette TRACE 384 <- [1,4,null,{}]
06:28:13 INFO - 1499261293182 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
06:28:13 INFO - TEST-START: test_click.py TestClick.test_input_file1499261293185 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:28:13 INFO - 1499261293186 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:28:13 INFO - 1499261293187 Marionette TRACE 384 <- [1,6,null,{}]
06:28:13 INFO - 1499261293189 Marionette DEBUG Closed connection 384
06:28:13 INFO - 1499261293189 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50281
06:28:13 INFO - 1499261293190 Marionette DEBUG Closed connection 385
06:28:13 INFO - 1499261293191 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50282
06:28:13 INFO - 1499261293192 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:13 INFO - 1499261293192 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293194 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293197 Marionette TRACE 386 <- [1,1,null,{"sessionId":"dd249d77-12cf-4ca0-ab2b-a35096e4df43","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293198 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:28:13 INFO - 1499261293198 Marionette TRACE 386 <- [1,2,null,{}]
06:28:13 INFO - 1499261293200 Marionette DEBUG Closed connection 386
06:28:13 INFO - 1499261293201 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50283
06:28:13 INFO - 1499261293202 Marionette DEBUG Closed connection 387
06:28:13 INFO - 1499261293202 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50284
06:28:13 INFO - 1499261293203 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:28:13 INFO - 1499261293204 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293205 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293208 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1e3c1d85-ec26-4957-a87a-25b0bb3a6fd0","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293209 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:28:13 INFO - 1499261293210 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:13 INFO - 1499261293212 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:13 INFO - 1499261293213 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:28:13 INFO - 1499261293229 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:28:13 INFO - 1499261293231 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:28:13 INFO - 1499261293235 Marionette TRACE 388 <- [1,2,null,{}]
06:28:13 INFO - 1499261293236 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:28:13 INFO - 1499261293244 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c2542ef7-9c62-4843-a3b9-e3695d03d5be","ELEMENT":"c2542ef7-9c62-4843-a3b9-e3695d03d5be"}}]
06:28:13 INFO - 1499261293245 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c2542ef7-9c62-4843-a3b9-e3695d03d5be"}]
06:28:13 INFO - 1499261293269 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
06:28:13 INFO - 1499261293271 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
06:28:13 INFO - TEST-END: test_click.py TestClick.test_input_file1499261293274 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:28:13 INFO - 1499261293275 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:28:13 INFO - 1499261293276 Marionette TRACE 388 <- [1,6,null,{}]
06:28:13 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 116ms
06:28:13 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:28:13 INFO - 1499261293278 Marionette DEBUG Closed connection 388
06:28:13 INFO - 1499261293279 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50285
06:28:13 INFO - 1499261293279 Marionette DEBUG Closed connection 389
06:28:13 INFO - 1499261293280 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50286
06:28:13 INFO - 1499261293281 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:13 INFO - 1499261293282 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293284 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293286 Marionette TRACE 390 <- [1,1,null,{"sessionId":"522786c1-7eb2-4fb7-b50a-ae71047f5e57","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293287 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:28:13 INFO - 1499261293288 Marionette TRACE 390 <- [1,2,null,{}]
06:28:13 INFO - 1499261293289 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:28:13 INFO - 1499261293289 Marionette TRACE 390 <- [1,3,null,{}]
06:28:13 INFO - 1499261293290 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:28:13 INFO - 1499261293290 Marionette TRACE 390 <- [1,4,null,{}]
06:28:13 INFO - 1499261293292 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
06:28:13 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499261293294 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:28:13 INFO - 1499261293295 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:28:13 INFO - 1499261293296 Marionette TRACE 390 <- [1,6,null,{}]
06:28:13 INFO - 1499261293298 Marionette DEBUG Closed connection 390
06:28:13 INFO - 1499261293299 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50287
06:28:13 INFO - 1499261293299 Marionette DEBUG Closed connection 391
06:28:13 INFO - 1499261293300 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50288
06:28:13 INFO - 1499261293301 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:13 INFO - 1499261293301 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293303 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293306 Marionette TRACE 392 <- [1,1,null,{"sessionId":"5add8d30-cf97-43c5-bc9c-7af55e15a9c1","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293308 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:28:13 INFO - 1499261293309 Marionette TRACE 392 <- [1,2,null,{}]
06:28:13 INFO - 1499261293311 Marionette DEBUG Closed connection 392
06:28:13 INFO - 1499261293312 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50289
06:28:13 INFO - 1499261293312 Marionette DEBUG Closed connection 393
06:28:13 INFO - 1499261293313 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50290
06:28:13 INFO - 1499261293314 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:28:13 INFO - 1499261293315 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293316 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293319 Marionette TRACE 394 <- [1,1,null,{"sessionId":"7eafd9ff-89ac-456c-87fd-a85b9c5e23b0","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293320 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
06:28:13 INFO - 1499261293321 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:28:13 INFO - 1499261293323 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:28:13 INFO - 1499261293324 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:28:13 INFO - 1499261293334 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:28:13 INFO - 1499261293337 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:28:13 INFO - 1499261293340 Marionette TRACE 394 <- [1,2,null,{}]
06:28:13 INFO - 1499261293348 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:28:13 INFO - 1499261293350 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ba1e5129-8cc1-4981-9c1e-5d583c0aae0a","ELEMENT":"ba1e5129-8cc1-4981-9c1e-5d583c0aae0a"}}]
06:28:13 INFO - 1499261293352 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:28:13 INFO - 1499261293354 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e6274708-a600-4b21-a528-53c7e36db9a2","ELEMENT":"e6274708-a600-4b21-a528-53c7e36db9a2"}}]
06:28:13 INFO - 1499261293355 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"ba1e5129-8cc1-4981-9c1e-5d583c0aae0a"}]
06:28:13 INFO - 1499261293591 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:28:13 INFO - 1499261293591 Marionette TRACE 394 <- [1,5,null,{}]
06:28:13 INFO - 1499261293592 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"e6274708-a600-4b21-a528-53c7e36db9a2"}]
06:28:13 INFO - 1499261293606 Marionette TRACE 394 <- [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:1318:5\n"},null]
06:28:13 INFO - 1499261293607 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:28:13 INFO - 1499261293610 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:28:13 INFO - 1499261293611 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
06:28:13 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499261293613 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:28:13 INFO - 1499261293614 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:28:13 INFO - 1499261293615 Marionette TRACE 394 <- [1,9,null,{}]
06:28:13 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 339ms
06:28:13 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:28:13 INFO - 1499261293617 Marionette DEBUG Closed connection 394
06:28:13 INFO - 1499261293618 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50291
06:28:13 INFO - 1499261293619 Marionette DEBUG Closed connection 395
06:28:13 INFO - 1499261293620 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50292
06:28:13 INFO - 1499261293621 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:13 INFO - 1499261293621 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293623 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293626 Marionette TRACE 396 <- [1,1,null,{"sessionId":"01c42459-1fad-439c-8b24-74c9f6497227","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293627 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:28:13 INFO - 1499261293627 Marionette TRACE 396 <- [1,2,null,{}]
06:28:13 INFO - 1499261293628 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:28:13 INFO - 1499261293629 Marionette TRACE 396 <- [1,3,null,{}]
06:28:13 INFO - 1499261293629 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:28:13 INFO - 1499261293630 Marionette TRACE 396 <- [1,4,null,{}]
06:28:13 INFO - 1499261293632 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
06:28:13 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499261293634 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:28:13 INFO - 1499261293635 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:28:13 INFO - 1499261293635 Marionette TRACE 396 <- [1,6,null,{}]
06:28:13 INFO - 1499261293637 Marionette DEBUG Closed connection 396
06:28:13 INFO - 1499261293638 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50293
06:28:13 INFO - 1499261293638 Marionette DEBUG Closed connection 397
06:28:13 INFO - 1499261293639 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50294
06:28:13 INFO - 1499261293640 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:28:13 INFO - 1499261293641 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293643 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293651 Marionette TRACE 398 <- [1,1,null,{"sessionId":"e7637875-3f8e-4718-abcd-af3b140b1af3","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293653 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:28:13 INFO - 1499261293654 Marionette TRACE 398 <- [1,2,null,{}]
06:28:13 INFO - 1499261293655 Marionette DEBUG Closed connection 398
06:28:13 INFO - 1499261293656 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50295
06:28:13 INFO - 1499261293657 Marionette DEBUG Closed connection 399
06:28:13 INFO - 1499261293657 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50296
06:28:13 INFO - 1499261293658 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:28:13 INFO - 1499261293659 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}
06:28:13 INFO - 1499261293661 Marionette DEBUG Register listener.js for window 12
06:28:13 INFO - 1499261293665 Marionette TRACE 400 <- [1,1,null,{"sessionId":"77334646-2301-4b14-9c09-33e613e97abc","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxalytg.mozrunner","moz:accessibilityChecks":false}}]
06:28:13 INFO - 1499261293666 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
06:28:13 INFO - 1499261293668 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:28:13 INFO - 1499261293670 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:28:13 INFO - 1499261293670 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:28:13 INFO - 1499261293685 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
06:28:13 INFO - 1499261293688 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
06:28:13 INFO - 1499261293696 Marionette TRACE 400 <- [1,2,null,{}]
06:28:13 INFO - 1499261293697 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:28:13 INFO - 1499261293699 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bda2536c-978f-4336-90b2-c3f6dbef64df","ELEMENT":"bda2536c-978f-4336-90b2-c3f6dbef64df"}}]
06:28:13 INFO - 1499261293701 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"bda2536c-978f-4336-90b2-c3f6dbef64df"}]
06:28:13 INFO - 1499261293723 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:28:13 INFO - 1499261293724 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"bda2536c-978f-4336-90b2-c3f6dbef64df"}]
06:28:13 INFO - 1499261293742 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element