could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:40:13 INFO - 1500050413563 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}]
09:40:13 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500050413565 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:40:13 INFO - 1500050413569 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:40:13 INFO - 1500050413570 Marionette TRACE 352 <- [1,6,null,{}]
09:40:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 120ms
09:40:13 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:40:13 INFO - 1500050413572 Marionette DEBUG Closed connection 352
09:40:13 INFO - 1500050413573 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49759
09:40:13 INFO - 1500050413573 Marionette DEBUG Closed connection 353
09:40:13 INFO - 1500050413574 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49760
09:40:13 INFO - 1500050413575 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:13 INFO - 1500050413575 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:13 INFO - 1500050413577 Marionette DEBUG Register listener.js for window 15
09:40:13 INFO - 1500050413580 Marionette TRACE 354 <- [1,1,null,{"sessionId":"2441856d-433a-4afe-9053-5085756de843","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:13 INFO - 1500050413581 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:40:13 INFO - 1500050413582 Marionette TRACE 354 <- [1,2,null,{}]
09:40:13 INFO - 1500050413586 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:40:13 INFO - 1500050413587 Marionette TRACE 354 <- [1,3,null,{}]
09:40:13 INFO - 1500050413589 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:40:13 INFO - 1500050413589 Marionette TRACE 354 <- [1,4,null,{}]
09:40:13 INFO - 1500050413591 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}]
09:40:13 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500050413593 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:40:13 INFO - 1500050413594 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:40:13 INFO - 1500050413595 Marionette TRACE 354 <- [1,6,null,{}]
09:40:13 INFO - 1500050413597 Marionette DEBUG Closed connection 354
09:40:13 INFO - 1500050413597 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49761
09:40:13 INFO - 1500050413598 Marionette DEBUG Closed connection 355
09:40:13 INFO - 1500050413598 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49762
09:40:13 INFO - 1500050413599 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:13 INFO - 1500050413600 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:13 INFO - 1500050413602 Marionette DEBUG Register listener.js for window 15
09:40:13 INFO - 1500050413604 Marionette TRACE 356 <- [1,1,null,{"sessionId":"068cad1b-5d5f-444a-89be-22494a1942ad","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:13 INFO - 1500050413606 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:40:13 INFO - 1500050413607 Marionette TRACE 356 <- [1,2,null,{}]
09:40:13 INFO - 1500050413609 Marionette DEBUG Closed connection 356
09:40:13 INFO - 1500050413610 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49763
09:40:13 INFO - 1500050413610 Marionette DEBUG Closed connection 357
09:40:13 INFO - 1500050413611 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49764
09:40:13 INFO - 1500050413612 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:40:13 INFO - 1500050413612 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:13 INFO - 1500050413614 Marionette DEBUG Register listener.js for window 15
09:40:13 INFO - 1500050413617 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3e3e1c1c-0087-44e1-8b59-57e5d3848b55","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:13 INFO - 1500050413619 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49327/clicks.html"}]
09:40:13 INFO - 1500050413620 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/hidden.html"
09:40:13 INFO - 1500050413624 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/hidden.html"
09:40:13 INFO - 1500050413625 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/hidden.html"
09:40:13 INFO - 1500050413634 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49327/clicks.html"
09:40:13 INFO - 1500050413638 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49327/clicks.html"
09:40:13 INFO - 1500050413647 Marionette TRACE 358 <- [1,2,null,{}]
09:40:13 INFO - 1500050413648 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:40:13 INFO - 1500050413650 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"85a28716-1971-4107-a33f-ebfa412196a5","ELEMENT":"85a28716-1971-4107-a33f-ebfa412196a5"}}]
09:40:13 INFO - 1500050413657 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"85a28716-1971-4107-a33f-ebfa412196a5"}]
09:40:13 INFO - 1500050413701 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/clicks.html"
09:40:13 INFO - 1500050413709 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/clicks.html"
09:40:13 INFO - 1500050413709 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/clicks.html"
09:40:13 INFO - 1500050413720 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49327/test.html"
09:40:13 INFO - 1500050413734 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49327/test.html"
09:40:13 INFO - 1500050413737 Marionette TRACE 358 <- [1,4,null,{}]
09:40:13 INFO - 1500050413744 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:40:13 INFO - 1500050413746 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1f2fae9e-611a-4a2e-a012-e9853789394a","ELEMENT":"1f2fae9e-611a-4a2e-a012-e9853789394a"}}]
09:40:13 INFO - 1500050413749 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:40:13 INFO - 1500050413750 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:40:13 INFO - 1500050413751 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}]
09:40:13 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500050413753 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:40:13 INFO - 1500050413754 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:40:13 INFO - 1500050413756 Marionette TRACE 358 <- [1,8,null,{}]
09:40:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 185ms
09:40:13 INFO - TEST-START | test_click.py TestClick.test_container_element
09:40:13 INFO - 1500050413758 Marionette DEBUG Closed connection 358
09:40:13 INFO - 1500050413759 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49767
09:40:13 INFO - 1500050413760 Marionette DEBUG Closed connection 359
09:40:13 INFO - 1500050413760 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49768
09:40:13 INFO - 1500050413761 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:13 INFO - 1500050413761 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:13 INFO - 1500050413763 Marionette DEBUG Register listener.js for window 15
09:40:13 INFO - 1500050413766 Marionette TRACE 360 <- [1,1,null,{"sessionId":"9bec3add-8df6-4241-bc48-d2e7b4326084","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:13 INFO - 1500050413768 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:40:13 INFO - 1500050413768 Marionette TRACE 360 <- [1,2,null,{}]
09:40:13 INFO - 1500050413769 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:40:13 INFO - 1500050413769 Marionette TRACE 360 <- [1,3,null,{}]
09:40:13 INFO - 1500050413770 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:40:13 INFO - 1500050413771 Marionette TRACE 360 <- [1,4,null,{}]
09:40:13 INFO - 1500050413772 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}]
09:40:13 INFO - TEST-START: test_click.py TestClick.test_container_element1500050413774 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:40:13 INFO - 1500050413775 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:40:13 INFO - 1500050413776 Marionette TRACE 360 <- [1,6,null,{}]
09:40:13 INFO - 1500050413778 Marionette DEBUG Closed connection 360
09:40:13 INFO - 1500050413778 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49769
09:40:13 INFO - 1500050413779 Marionette DEBUG Closed connection 361
09:40:13 INFO - 1500050413780 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49770
09:40:13 INFO - 1500050413781 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:13 INFO - 1500050413781 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:13 INFO - 1500050413783 Marionette DEBUG Register listener.js for window 15
09:40:13 INFO - 1500050413786 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c5254a2f-cf56-4e57-b58c-b33a0ab1fd46","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:13 INFO - 1500050413787 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:40:13 INFO - 1500050413788 Marionette TRACE 362 <- [1,2,null,{}]
09:40:13 INFO - 1500050413790 Marionette DEBUG Closed connection 362
09:40:13 INFO - 1500050413791 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49771
09:40:13 INFO - 1500050413791 Marionette DEBUG Closed connection 363
09:40:13 INFO - 1500050413792 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49772
09:40:13 INFO - 1500050413793 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:40:13 INFO - 1500050413793 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:13 INFO - 1500050413795 Marionette DEBUG Register listener.js for window 15
09:40:13 INFO - 1500050413797 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f9aebe54-0a2c-41ad-8153-d60b7cdd2402","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:13 INFO - 1500050413799 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"}]
09:40:13 INFO - 1500050413801 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/test.html"
09:40:13 INFO - 1500050413802 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/test.html"
09:40:13 INFO - 1500050413802 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/test.html"
09:40:13 INFO - 1500050413825 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:13 INFO - 1500050413826 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:13 INFO - 1500050413830 Marionette TRACE 364 <- [1,2,null,{}]
09:40:13 INFO - 1500050413831 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:40:13 INFO - 1500050413833 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c2c2e334-4343-475f-8fab-ef86ef95badf","ELEMENT":"c2c2e334-4343-475f-8fab-ef86ef95badf"}}]
09:40:13 INFO - 1500050413834 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c2c2e334-4343-475f-8fab-ef86ef95badf"}]
09:40:14 INFO - 1500050414076 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:40:14 INFO - 1500050414077 Marionette TRACE 364 <- [1,4,null,{}]
09:40:14 INFO - 1500050414078 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"c2c2e334-4343-475f-8fab-ef86ef95badf","name":"selected"}]
09:40:14 INFO - 1500050414082 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:40:14 INFO - 1500050414084 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}]
09:40:14 INFO - TEST-END: test_click.py TestClick.test_container_element1500050414086 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:40:14 INFO - 1500050414087 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:40:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 332ms
09:40:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:40:14 INFO - 1500050414088 Marionette TRACE 364 <- [1,7,null,{}]
09:40:14 INFO - 1500050414090 Marionette DEBUG Closed connection 364
09:40:14 INFO - 1500050414091 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49773
09:40:14 INFO - 1500050414092 Marionette DEBUG Closed connection 365
09:40:14 INFO - 1500050414092 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49774
09:40:14 INFO - 1500050414093 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:14 INFO - 1500050414093 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414095 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414098 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3d13f6b9-4860-4c23-8e16-f4c1ab2f67c3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414100 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:40:14 INFO - 1500050414100 Marionette TRACE 366 <- [1,2,null,{}]
09:40:14 INFO - 1500050414101 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:40:14 INFO - 1500050414102 Marionette TRACE 366 <- [1,3,null,{}]
09:40:14 INFO - 1500050414103 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:40:14 INFO - 1500050414104 Marionette TRACE 366 <- [1,4,null,{}]
09:40:14 INFO - 1500050414106 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}]
09:40:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500050414108 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:40:14 INFO - 1500050414109 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:40:14 INFO - 1500050414110 Marionette TRACE 366 <- [1,6,null,{}]
09:40:14 INFO - 1500050414112 Marionette DEBUG Closed connection 366
09:40:14 INFO - 1500050414112 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49775
09:40:14 INFO - 1500050414113 Marionette DEBUG Closed connection 367
09:40:14 INFO - 1500050414114 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49776
09:40:14 INFO - 1500050414115 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:14 INFO - 1500050414115 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414117 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414120 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e7c5aa68-8745-4a8a-8ebe-04e8b9cca4ac","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414122 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:40:14 INFO - 1500050414123 Marionette TRACE 368 <- [1,2,null,{}]
09:40:14 INFO - 1500050414124 Marionette DEBUG Closed connection 368
09:40:14 INFO - 1500050414125 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49777
09:40:14 INFO - 1500050414125 Marionette DEBUG Closed connection 369
09:40:14 INFO - 1500050414126 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49778
09:40:14 INFO - 1500050414127 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:40:14 INFO - 1500050414128 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414129 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414132 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c964c116-1c02-451c-a39b-c75a7fcb779d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414133 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"}]
09:40:14 INFO - 1500050414135 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414136 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414136 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414152 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414162 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414172 Marionette TRACE 370 <- [1,2,null,{}]
09:40:14 INFO - 1500050414173 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:40:14 INFO - 1500050414175 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1d389104-72a2-42d5-99dc-a536071f3a76","ELEMENT":"1d389104-72a2-42d5-99dc-a536071f3a76"}}]
09:40:14 INFO - 1500050414176 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1d389104-72a2-42d5-99dc-a536071f3a76"}]
09:40:14 INFO - 1500050414413 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:40:14 INFO - 1500050414414 Marionette TRACE 370 <- [1,4,null,{}]
09:40:14 INFO - 1500050414415 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1d389104-72a2-42d5-99dc-a536071f3a76","name":"selected"}]
09:40:14 INFO - 1500050414419 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:40:14 INFO - 1500050414420 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}]
09:40:14 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500050414422 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:40:14 INFO - 1500050414423 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:40:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 335ms
09:40:14 INFO - 1500050414424 Marionette TRACE 370 <- [1,7,null,{}]
09:40:14 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:40:14 INFO - 1500050414426 Marionette DEBUG Closed connection 370
09:40:14 INFO - 1500050414428 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49779
09:40:14 INFO - 1500050414428 Marionette DEBUG Closed connection 371
09:40:14 INFO - 1500050414429 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49780
09:40:14 INFO - 1500050414429 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:14 INFO - 1500050414430 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414432 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414434 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ec08938d-a500-48d1-9443-8a8d15d1d1bf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414436 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:40:14 INFO - 1500050414437 Marionette TRACE 372 <- [1,2,null,{}]
09:40:14 INFO - 1500050414437 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:40:14 INFO - 1500050414438 Marionette TRACE 372 <- [1,3,null,{}]
09:40:14 INFO - 1500050414439 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:40:14 INFO - 1500050414439 Marionette TRACE 372 <- [1,4,null,{}]
09:40:14 INFO - 1500050414440 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}]
09:40:14 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500050414443 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:40:14 INFO - 1500050414444 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:40:14 INFO - 1500050414445 Marionette TRACE 372 <- [1,6,null,{}]
09:40:14 INFO - 1500050414446 Marionette DEBUG Closed connection 372
09:40:14 INFO - 1500050414447 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49781
09:40:14 INFO - 1500050414447 Marionette DEBUG Closed connection 373
09:40:14 INFO - 1500050414448 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49782
09:40:14 INFO - 1500050414449 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:14 INFO - 1500050414450 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414452 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414454 Marionette TRACE 374 <- [1,1,null,{"sessionId":"63c30400-9489-40b6-ada4-d4474a766f78","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414456 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:40:14 INFO - 1500050414457 Marionette TRACE 374 <- [1,2,null,{}]
09:40:14 INFO - 1500050414459 Marionette DEBUG Closed connection 374
09:40:14 INFO - 1500050414459 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49783
09:40:14 INFO - 1500050414460 Marionette DEBUG Closed connection 375
09:40:14 INFO - 1500050414460 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49784
09:40:14 INFO - 1500050414461 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:40:14 INFO - 1500050414462 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414464 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414466 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e817f0ea-7728-4856-9b64-4d94fc128974","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414468 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"}]
09:40:14 INFO - 1500050414469 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414471 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414471 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414481 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:40:14 INFO - 1500050414484 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:40:14 INFO - 1500050414486 Marionette TRACE 376 <- [1,2,null,{}]
09:40:14 INFO - 1500050414492 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:40:14 INFO - 1500050414495 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e9b7e9db-a475-4373-9a72-831521cb029d","ELEMENT":"e9b7e9db-a475-4373-9a72-831521cb029d"}}]
09:40:14 INFO - 1500050414496 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e9b7e9db-a475-4373-9a72-831521cb029d"}]
09:40:14 INFO - 1500050414737 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:40:14 INFO - 1500050414738 Marionette TRACE 376 <- [1,4,null,{}]
09:40:14 INFO - 1500050414739 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}]
09:40:14 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500050414741 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:40:14 INFO - 1500050414742 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:40:14 INFO - 1500050414743 Marionette TRACE 376 <- [1,6,null,{}]
09:40:14 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 319ms
09:40:14 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:40:14 INFO - 1500050414745 Marionette DEBUG Closed connection 376
09:40:14 INFO - 1500050414747 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49785
09:40:14 INFO - 1500050414747 Marionette DEBUG Closed connection 377
09:40:14 INFO - 1500050414748 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49786
09:40:14 INFO - 1500050414749 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:14 INFO - 1500050414749 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414751 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414754 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ea11e095-0c1e-4bda-ada2-06e5ed65ad70","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414756 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:40:14 INFO - 1500050414756 Marionette TRACE 378 <- [1,2,null,{}]
09:40:14 INFO - 1500050414757 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:40:14 INFO - 1500050414757 Marionette TRACE 378 <- [1,3,null,{}]
09:40:14 INFO - 1500050414758 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:40:14 INFO - 1500050414759 Marionette TRACE 378 <- [1,4,null,{}]
09:40:14 INFO - 1500050414761 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}]
09:40:14 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500050414763 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:40:14 INFO - 1500050414764 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:40:14 INFO - 1500050414765 Marionette TRACE 378 <- [1,6,null,{}]
09:40:14 INFO - 1500050414766 Marionette DEBUG Closed connection 378
09:40:14 INFO - 1500050414767 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49787
09:40:14 INFO - 1500050414767 Marionette DEBUG Closed connection 379
09:40:14 INFO - 1500050414768 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49788
09:40:14 INFO - 1500050414769 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:14 INFO - 1500050414770 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414771 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414774 Marionette TRACE 380 <- [1,1,null,{"sessionId":"08471488-5154-4fe0-9715-27f7f7d334cc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414776 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:40:14 INFO - 1500050414776 Marionette TRACE 380 <- [1,2,null,{}]
09:40:14 INFO - 1500050414778 Marionette DEBUG Closed connection 380
09:40:14 INFO - 1500050414778 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49789
09:40:14 INFO - 1500050414779 Marionette DEBUG Closed connection 381
09:40:14 INFO - 1500050414780 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49790
09:40:14 INFO - 1500050414781 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:40:14 INFO - 1500050414781 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:14 INFO - 1500050414783 Marionette DEBUG Register listener.js for window 15
09:40:14 INFO - 1500050414785 Marionette TRACE 382 <- [1,1,null,{"sessionId":"3c584148-4b3d-4011-bf16-b1b022b4319f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:14 INFO - 1500050414787 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"}]
09:40:14 INFO - 1500050414788 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:40:14 INFO - 1500050414790 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:40:14 INFO - 1500050414790 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:40:14 INFO - 1500050414799 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414802 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:14 INFO - 1500050414805 Marionette TRACE 382 <- [1,2,null,{}]
09:40:14 INFO - 1500050414814 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:40:14 INFO - 1500050414816 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a6ff3352-ce7c-4050-99db-04938af9bfee","ELEMENT":"a6ff3352-ce7c-4050-99db-04938af9bfee"}}]
09:40:14 INFO - 1500050414817 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"a6ff3352-ce7c-4050-99db-04938af9bfee"}]
09:40:15 INFO - 1500050415053 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:40:15 INFO - 1500050415054 Marionette TRACE 382 <- [1,4,null,{}]
09:40:15 INFO - 1500050415055 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}]
09:40:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500050415058 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:40:15 INFO - 1500050415058 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:40:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 315ms
09:40:15 INFO - TEST-START | test_click.py TestClick.test_input_file
09:40:15 INFO - 1500050415059 Marionette TRACE 382 <- [1,6,null,{}]
09:40:15 INFO - 1500050415061 Marionette DEBUG Closed connection 382
09:40:15 INFO - 1500050415062 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49791
09:40:15 INFO - 1500050415063 Marionette DEBUG Closed connection 383
09:40:15 INFO - 1500050415063 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49792
09:40:15 INFO - 1500050415064 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:15 INFO - 1500050415065 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415067 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415069 Marionette TRACE 384 <- [1,1,null,{"sessionId":"62600690-7e16-4155-90b3-9ea940634dd4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415071 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:40:15 INFO - 1500050415071 Marionette TRACE 384 <- [1,2,null,{}]
09:40:15 INFO - 1500050415072 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:40:15 INFO - 1500050415072 Marionette TRACE 384 <- [1,3,null,{}]
09:40:15 INFO - 1500050415073 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:40:15 INFO - 1500050415074 Marionette TRACE 384 <- [1,4,null,{}]
09:40:15 INFO - 1500050415075 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}]
09:40:15 INFO - TEST-START: test_click.py TestClick.test_input_file1500050415077 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:40:15 INFO - 1500050415078 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:40:15 INFO - 1500050415079 Marionette TRACE 384 <- [1,6,null,{}]
09:40:15 INFO - 1500050415081 Marionette DEBUG Closed connection 384
09:40:15 INFO - 1500050415081 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49793
09:40:15 INFO - 1500050415082 Marionette DEBUG Closed connection 385
09:40:15 INFO - 1500050415083 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49794
09:40:15 INFO - 1500050415083 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:15 INFO - 1500050415084 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415086 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415088 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6073e92d-b31a-449b-866d-6f17f9524185","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415090 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:40:15 INFO - 1500050415091 Marionette TRACE 386 <- [1,2,null,{}]
09:40:15 INFO - 1500050415092 Marionette DEBUG Closed connection 386
09:40:15 INFO - 1500050415093 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49795
09:40:15 INFO - 1500050415093 Marionette DEBUG Closed connection 387
09:40:15 INFO - 1500050415094 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49796
09:40:15 INFO - 1500050415095 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:40:15 INFO - 1500050415095 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415097 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415099 Marionette TRACE 388 <- [1,1,null,{"sessionId":"876caeab-ae90-41fe-a8c1-5812658952ff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415101 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:40:15 INFO - 1500050415102 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:15 INFO - 1500050415104 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:15 INFO - 1500050415105 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:40:15 INFO - 1500050415113 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:40:15 INFO - 1500050415116 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:40:15 INFO - 1500050415119 Marionette TRACE 388 <- [1,2,null,{}]
09:40:15 INFO - 1500050415126 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:40:15 INFO - 1500050415129 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c69d5194-62ad-41d9-bae9-51604c2d61fe","ELEMENT":"c69d5194-62ad-41d9-bae9-51604c2d61fe"}}]
09:40:15 INFO - 1500050415130 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c69d5194-62ad-41d9-bae9-51604c2d61fe"}]
09:40:15 INFO - 1500050415161 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:40:15 INFO - 1500050415163 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}]
09:40:15 INFO - TEST-END: test_click.py TestClick.test_input_file1500050415165 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:40:15 INFO - 1500050415166 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:40:15 INFO - 1500050415168 Marionette TRACE 388 <- [1,6,null,{}]
09:40:15 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 108ms
09:40:15 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:40:15 INFO - 1500050415169 Marionette DEBUG Closed connection 388
09:40:15 INFO - 1500050415170 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49797
09:40:15 INFO - 1500050415171 Marionette DEBUG Closed connection 389
09:40:15 INFO - 1500050415172 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49798
09:40:15 INFO - 1500050415173 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:15 INFO - 1500050415173 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415175 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415178 Marionette TRACE 390 <- [1,1,null,{"sessionId":"5c313d87-2295-4a72-8aca-687f3fb5d5d1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415181 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:40:15 INFO - 1500050415181 Marionette TRACE 390 <- [1,2,null,{}]
09:40:15 INFO - 1500050415186 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:40:15 INFO - 1500050415186 Marionette TRACE 390 <- [1,3,null,{}]
09:40:15 INFO - 1500050415187 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:40:15 INFO - 1500050415188 Marionette TRACE 390 <- [1,4,null,{}]
09:40:15 INFO - 1500050415189 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}]
09:40:15 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500050415191 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:40:15 INFO - 1500050415192 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:40:15 INFO - 1500050415194 Marionette TRACE 390 <- [1,6,null,{}]
09:40:15 INFO - 1500050415195 Marionette DEBUG Closed connection 390
09:40:15 INFO - 1500050415196 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49799
09:40:15 INFO - 1500050415197 Marionette DEBUG Closed connection 391
09:40:15 INFO - 1500050415197 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49800
09:40:15 INFO - 1500050415198 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:15 INFO - 1500050415198 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415200 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415203 Marionette TRACE 392 <- [1,1,null,{"sessionId":"38c34da7-fe56-4cff-abbf-6e8b9fa4a5b8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415205 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:40:15 INFO - 1500050415206 Marionette TRACE 392 <- [1,2,null,{}]
09:40:15 INFO - 1500050415207 Marionette DEBUG Closed connection 392
09:40:15 INFO - 1500050415208 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49801
09:40:15 INFO - 1500050415208 Marionette DEBUG Closed connection 393
09:40:15 INFO - 1500050415209 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49802
09:40:15 INFO - 1500050415210 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:40:15 INFO - 1500050415210 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415212 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415215 Marionette TRACE 394 <- [1,1,null,{"sessionId":"0f71b2cd-c9bc-416b-a854-ca88f67252f3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415217 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"}]
09:40:15 INFO - 1500050415218 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:40:15 INFO - 1500050415219 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:40:15 INFO - 1500050415220 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:40:15 INFO - 1500050415235 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:40:15 INFO - 1500050415237 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:40:15 INFO - 1500050415245 Marionette TRACE 394 <- [1,2,null,{}]
09:40:15 INFO - 1500050415253 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:40:15 INFO - 1500050415255 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4ec06311-6675-433b-bacd-b0dc6c9019dd","ELEMENT":"4ec06311-6675-433b-bacd-b0dc6c9019dd"}}]
09:40:15 INFO - 1500050415256 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:40:15 INFO - 1500050415258 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"13ce8cab-696f-4630-8c0e-e5e2ad937436","ELEMENT":"13ce8cab-696f-4630-8c0e-e5e2ad937436"}}]
09:40:15 INFO - 1500050415259 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"4ec06311-6675-433b-bacd-b0dc6c9019dd"}]
09:40:15 INFO - 1500050415493 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:40:15 INFO - 1500050415494 Marionette TRACE 394 <- [1,5,null,{}]
09:40:15 INFO - 1500050415495 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"13ce8cab-696f-4630-8c0e-e5e2ad937436"}]
09:40:15 INFO - 1500050415507 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:40:15 INFO - 1500050415509 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:40:15 INFO - 1500050415511 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:40:15 INFO - 1500050415513 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}]
09:40:15 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500050415514 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:40:15 INFO - 1500050415515 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:40:15 INFO - 1500050415516 Marionette TRACE 394 <- [1,9,null,{}]
09:40:15 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 349ms
09:40:15 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:40:15 INFO - 1500050415519 Marionette DEBUG Closed connection 394
09:40:15 INFO - 1500050415519 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49803
09:40:15 INFO - 1500050415520 Marionette DEBUG Closed connection 395
09:40:15 INFO - 1500050415520 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49804
09:40:15 INFO - 1500050415521 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:15 INFO - 1500050415522 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415524 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415533 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ef30b6ba-128d-49a8-bc72-c6d81e4c71ff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415535 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:40:15 INFO - 1500050415535 Marionette TRACE 396 <- [1,2,null,{}]
09:40:15 INFO - 1500050415536 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:40:15 INFO - 1500050415536 Marionette TRACE 396 <- [1,3,null,{}]
09:40:15 INFO - 1500050415537 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:40:15 INFO - 1500050415537 Marionette TRACE 396 <- [1,4,null,{}]
09:40:15 INFO - 1500050415539 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}]
09:40:15 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500050415541 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:40:15 INFO - 1500050415542 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:40:15 INFO - 1500050415543 Marionette TRACE 396 <- [1,6,null,{}]
09:40:15 INFO - 1500050415545 Marionette DEBUG Closed connection 396
09:40:15 INFO - 1500050415545 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49805
09:40:15 INFO - 1500050415546 Marionette DEBUG Closed connection 397
09:40:15 INFO - 1500050415546 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49806
09:40:15 INFO - 1500050415547 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:40:15 INFO - 1500050415548 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415550 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415552 Marionette TRACE 398 <- [1,1,null,{"sessionId":"298de5a8-a17e-4cd8-9e0e-872f7ffcd6ed","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415554 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:40:15 INFO - 1500050415555 Marionette TRACE 398 <- [1,2,null,{}]
09:40:15 INFO - 1500050415556 Marionette DEBUG Closed connection 398
09:40:15 INFO - 1500050415557 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49807
09:40:15 INFO - 1500050415557 Marionette DEBUG Closed connection 399
09:40:15 INFO - 1500050415558 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49808
09:40:15 INFO - 1500050415559 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:40:15 INFO - 1500050415559 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}
09:40:15 INFO - 1500050415561 Marionette DEBUG Register listener.js for window 15
09:40:15 INFO - 1500050415564 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c102439e-0f84-4dc6-9929-64d8a9827275","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-146.001\\AppData\\Local\\Temp\\tmpfhgx37.mozrunner","moz:accessibilityChecks":false}}]
09:40:15 INFO - 1500050415566 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"}]
09:40:15 INFO - 1500050415567 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:40:15 INFO - 1500050415569 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:40:15 INFO - 1500050415569 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:40:15 INFO - 1500050415586 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
09:40:15 INFO - 1500050415588 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
09:40:15 INFO - 1500050415595 Marionette TRACE 400 <- [1,2,null,{}]
09:40:15 INFO - 1500050415597 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:40:15 INFO - 1500050415599 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b3c0d710-d86c-4abb-b5d3-a4fab4d11c48","ELEMENT":"b3c0d710-d86c-4abb-b5d3-a4fab4d11c48"}}]
09:40:15 INFO - 1500050415599 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b3c0d710-d86c-4abb-b5d3-a4fab4d11c48"}]
09:40:15 INFO - 1500050415621 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:40:15 INFO - 1500050415622 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b3c0d710-d86c-4abb-b5d3-a4fab4d11c48"}]
09:40:15 INFO - 1500050415635 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element