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]
06:26:49 INFO - 1499866009628 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:26:49 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499866009632 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:26:49 INFO - 1499866009633 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:26:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 157ms
06:26:49 INFO - 1499866009634 Marionette TRACE 352 <- [1,6,null,{}]
06:26:49 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:26:49 INFO - 1499866009636 Marionette DEBUG Closed connection 352
06:26:49 INFO - 1499866009637 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49752
06:26:49 INFO - 1499866009638 Marionette DEBUG Closed connection 353
06:26:49 INFO - 1499866009639 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49753
06:26:49 INFO - 1499866009639 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:49 INFO - 1499866009640 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:49 INFO - 1499866009642 Marionette DEBUG Register listener.js for window 12
06:26:49 INFO - 1499866009645 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7bb1ff12-3213-4daf-a160-9b095b450fe4","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:49 INFO - 1499866009647 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:26:49 INFO - 1499866009647 Marionette TRACE 354 <- [1,2,null,{}]
06:26:49 INFO - 1499866009648 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:49 INFO - 1499866009648 Marionette TRACE 354 <- [1,3,null,{}]
06:26:49 INFO - 1499866009649 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:49 INFO - 1499866009649 Marionette TRACE 354 <- [1,4,null,{}]
06:26:49 INFO - 1499866009651 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:26:49 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499866009653 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:26:49 INFO - 1499866009654 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:26:49 INFO - 1499866009655 Marionette TRACE 354 <- [1,6,null,{}]
06:26:49 INFO - 1499866009657 Marionette DEBUG Closed connection 354
06:26:49 INFO - 1499866009658 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49754
06:26:49 INFO - 1499866009658 Marionette DEBUG Closed connection 355
06:26:49 INFO - 1499866009659 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49755
06:26:49 INFO - 1499866009660 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:49 INFO - 1499866009660 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:49 INFO - 1499866009662 Marionette DEBUG Register listener.js for window 12
06:26:49 INFO - 1499866009665 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4a5bd097-63f6-4974-ac82-aa3d22fbb894","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:49 INFO - 1499866009666 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:26:49 INFO - 1499866009667 Marionette TRACE 356 <- [1,2,null,{}]
06:26:49 INFO - 1499866009669 Marionette DEBUG Closed connection 356
06:26:49 INFO - 1499866009670 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49756
06:26:49 INFO - 1499866009670 Marionette DEBUG Closed connection 357
06:26:49 INFO - 1499866009671 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49757
06:26:49 INFO - 1499866009672 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:49 INFO - 1499866009672 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:49 INFO - 1499866009674 Marionette DEBUG Register listener.js for window 12
06:26:49 INFO - 1499866009677 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c3f55620-20c2-40f9-a2b3-c1d4a7245027","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:49 INFO - 1499866009679 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49327/clicks.html"}]
06:26:49 INFO - 1499866009680 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/hidden.html"
06:26:49 INFO - 1499866009686 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/hidden.html"
06:26:49 INFO - 1499866009687 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/hidden.html"
06:26:49 INFO - 1499866009705 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49327/clicks.html"
06:26:49 INFO - 1499866009707 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49327/clicks.html"
06:26:49 INFO - 1499866009710 Marionette TRACE 358 <- [1,2,null,{}]
06:26:49 INFO - 1499866009711 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:26:49 INFO - 1499866009713 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6186f493-b885-4f89-900a-b5bfe5db9427","ELEMENT":"6186f493-b885-4f89-900a-b5bfe5db9427"}}]
06:26:49 INFO - 1499866009714 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"6186f493-b885-4f89-900a-b5bfe5db9427"}]
06:26:49 INFO - 1499866009762 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/clicks.html"
06:26:49 INFO - 1499866009770 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/clicks.html"
06:26:49 INFO - 1499866009771 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/clicks.html"
06:26:49 INFO - 1499866009781 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49327/test.html"
06:26:49 INFO - 1499866009788 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49327/test.html"
06:26:49 INFO - 1499866009791 Marionette TRACE 358 <- [1,4,null,{}]
06:26:49 INFO - 1499866009793 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:26:49 INFO - 1499866009794 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"979914ce-d837-4ce2-a820-da1d4823f556","ELEMENT":"979914ce-d837-4ce2-a820-da1d4823f556"}}]
06:26:49 INFO - 1499866009795 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:26:49 INFO - 1499866009796 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:26:49 INFO - 1499866009797 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:26:49 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499866009806 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:26:49 INFO - 1499866009807 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:26:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 173ms
06:26:49 INFO - TEST-START | test_click.py TestClick.test_container_element
06:26:49 INFO - 1499866009808 Marionette TRACE 358 <- [1,8,null,{}]
06:26:49 INFO - 1499866009810 Marionette DEBUG Closed connection 358
06:26:49 INFO - 1499866009811 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49760
06:26:49 INFO - 1499866009811 Marionette DEBUG Closed connection 359
06:26:49 INFO - 1499866009812 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49761
06:26:49 INFO - 1499866009813 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:49 INFO - 1499866009814 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:49 INFO - 1499866009816 Marionette DEBUG Register listener.js for window 12
06:26:49 INFO - 1499866009819 Marionette TRACE 360 <- [1,1,null,{"sessionId":"e6cea911-f14a-4a46-9410-47bb719452db","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:49 INFO - 1499866009821 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:26:49 INFO - 1499866009821 Marionette TRACE 360 <- [1,2,null,{}]
06:26:49 INFO - 1499866009822 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:49 INFO - 1499866009822 Marionette TRACE 360 <- [1,3,null,{}]
06:26:49 INFO - 1499866009823 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:49 INFO - 1499866009824 Marionette TRACE 360 <- [1,4,null,{}]
06:26:49 INFO - 1499866009825 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:26:49 INFO - TEST-START: test_click.py TestClick.test_container_element1499866009827 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:26:49 INFO - 1499866009828 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:26:49 INFO - 1499866009829 Marionette TRACE 360 <- [1,6,null,{}]
06:26:49 INFO - 1499866009831 Marionette DEBUG Closed connection 360
06:26:49 INFO - 1499866009831 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49762
06:26:49 INFO - 1499866009832 Marionette DEBUG Closed connection 361
06:26:49 INFO - 1499866009832 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49763
06:26:49 INFO - 1499866009833 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:49 INFO - 1499866009834 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:49 INFO - 1499866009836 Marionette DEBUG Register listener.js for window 12
06:26:49 INFO - 1499866009839 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3673a7a2-0cde-48b8-99b5-1249acc3016d","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:49 INFO - 1499866009840 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:26:49 INFO - 1499866009841 Marionette TRACE 362 <- [1,2,null,{}]
06:26:49 INFO - 1499866009844 Marionette DEBUG Closed connection 362
06:26:49 INFO - 1499866009844 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49764
06:26:49 INFO - 1499866009845 Marionette DEBUG Closed connection 363
06:26:49 INFO - 1499866009845 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49765
06:26:49 INFO - 1499866009846 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:49 INFO - 1499866009847 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:49 INFO - 1499866009849 Marionette DEBUG Register listener.js for window 12
06:26:49 INFO - 1499866009851 Marionette TRACE 364 <- [1,1,null,{"sessionId":"07bad9b2-4091-495b-af16-795f87a6ccff","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:49 INFO - 1499866009853 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:26:49 INFO - 1499866009854 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/test.html"
06:26:49 INFO - 1499866009856 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/test.html"
06:26:49 INFO - 1499866009856 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/test.html"
06:26:49 INFO - 1499866009875 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:26:49 INFO - 1499866009878 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:26:49 INFO - 1499866009881 Marionette TRACE 364 <- [1,2,null,{}]
06:26:49 INFO - 1499866009887 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:26:49 INFO - 1499866009889 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"11aff969-261f-4e9d-825a-8459a330a1fb","ELEMENT":"11aff969-261f-4e9d-825a-8459a330a1fb"}}]
06:26:49 INFO - 1499866009890 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"11aff969-261f-4e9d-825a-8459a330a1fb"}]
06:26:50 INFO - 1499866010129 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:50 INFO - 1499866010130 Marionette TRACE 364 <- [1,4,null,{}]
06:26:50 INFO - 1499866010132 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"11aff969-261f-4e9d-825a-8459a330a1fb","name":"selected"}]
06:26:50 INFO - 1499866010136 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:26:50 INFO - 1499866010138 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:26:50 INFO - TEST-END: test_click.py TestClick.test_container_element1499866010140 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:26:50 INFO - 1499866010141 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:26:50 INFO - 1499866010142 Marionette TRACE 364 <- [1,7,null,{}]
06:26:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 334ms
06:26:50 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:26:50 INFO - 1499866010144 Marionette DEBUG Closed connection 364
06:26:50 INFO - 1499866010145 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49766
06:26:50 INFO - 1499866010146 Marionette DEBUG Closed connection 365
06:26:50 INFO - 1499866010146 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49767
06:26:50 INFO - 1499866010147 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:50 INFO - 1499866010148 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010150 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010152 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9ba7c7c2-2064-441e-adc4-2b9ba0e16915","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010154 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:26:50 INFO - 1499866010154 Marionette TRACE 366 <- [1,2,null,{}]
06:26:50 INFO - 1499866010155 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:50 INFO - 1499866010156 Marionette TRACE 366 <- [1,3,null,{}]
06:26:50 INFO - 1499866010156 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:50 INFO - 1499866010157 Marionette TRACE 366 <- [1,4,null,{}]
06:26:50 INFO - 1499866010158 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:26:50 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499866010160 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:26:50 INFO - 1499866010162 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:26:50 INFO - 1499866010163 Marionette TRACE 366 <- [1,6,null,{}]
06:26:50 INFO - 1499866010164 Marionette DEBUG Closed connection 366
06:26:50 INFO - 1499866010165 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49768
06:26:50 INFO - 1499866010166 Marionette DEBUG Closed connection 367
06:26:50 INFO - 1499866010166 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49769
06:26:50 INFO - 1499866010167 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:50 INFO - 1499866010168 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010170 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010179 Marionette TRACE 368 <- [1,1,null,{"sessionId":"acdecd73-ba0f-4e04-ac61-502045f5db32","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010181 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:26:50 INFO - 1499866010182 Marionette TRACE 368 <- [1,2,null,{}]
06:26:50 INFO - 1499866010183 Marionette DEBUG Closed connection 368
06:26:50 INFO - 1499866010184 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49770
06:26:50 INFO - 1499866010184 Marionette DEBUG Closed connection 369
06:26:50 INFO - 1499866010185 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49771
06:26:50 INFO - 1499866010186 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:50 INFO - 1499866010186 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010189 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010192 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d763726b-a6be-4680-a6c7-5462577d6f9a","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010194 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:26:50 INFO - 1499866010195 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:26:50 INFO - 1499866010197 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:26:50 INFO - 1499866010197 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:26:50 INFO - 1499866010212 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:26:50 INFO - 1499866010214 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:26:50 INFO - 1499866010224 Marionette TRACE 370 <- [1,2,null,{}]
06:26:50 INFO - 1499866010225 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:26:50 INFO - 1499866010227 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d92ce1e3-393e-4781-ade4-cf1a6390043b","ELEMENT":"d92ce1e3-393e-4781-ade4-cf1a6390043b"}}]
06:26:50 INFO - 1499866010228 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d92ce1e3-393e-4781-ade4-cf1a6390043b"}]
06:26:50 INFO - 1499866010465 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:50 INFO - 1499866010466 Marionette TRACE 370 <- [1,4,null,{}]
06:26:50 INFO - 1499866010467 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d92ce1e3-393e-4781-ade4-cf1a6390043b","name":"selected"}]
06:26:50 INFO - 1499866010470 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:26:50 INFO - 1499866010472 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:26:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499866010474 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:26:50 INFO - 1499866010475 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:26:50 INFO - 1499866010476 Marionette TRACE 370 <- [1,7,null,{}]
06:26:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
06:26:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:26:50 INFO - 1499866010478 Marionette DEBUG Closed connection 370
06:26:50 INFO - 1499866010479 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49772
06:26:50 INFO - 1499866010480 Marionette DEBUG Closed connection 371
06:26:50 INFO - 1499866010480 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49773
06:26:50 INFO - 1499866010481 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:50 INFO - 1499866010482 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010484 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010486 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2d8a647b-a88a-462b-ab6d-be365947c676","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010488 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:26:50 INFO - 1499866010488 Marionette TRACE 372 <- [1,2,null,{}]
06:26:50 INFO - 1499866010489 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:50 INFO - 1499866010489 Marionette TRACE 372 <- [1,3,null,{}]
06:26:50 INFO - 1499866010490 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:50 INFO - 1499866010490 Marionette TRACE 372 <- [1,4,null,{}]
06:26:50 INFO - 1499866010493 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:26:50 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499866010494 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:26:50 INFO - 1499866010495 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:26:50 INFO - 1499866010496 Marionette TRACE 372 <- [1,6,null,{}]
06:26:50 INFO - 1499866010498 Marionette DEBUG Closed connection 372
06:26:50 INFO - 1499866010499 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49774
06:26:50 INFO - 1499866010499 Marionette DEBUG Closed connection 373
06:26:50 INFO - 1499866010500 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49775
06:26:50 INFO - 1499866010501 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:50 INFO - 1499866010501 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010503 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010506 Marionette TRACE 374 <- [1,1,null,{"sessionId":"661b807a-b719-4919-b63e-1302a5c74ed9","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010507 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:26:50 INFO - 1499866010508 Marionette TRACE 374 <- [1,2,null,{}]
06:26:50 INFO - 1499866010510 Marionette DEBUG Closed connection 374
06:26:50 INFO - 1499866010510 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49776
06:26:50 INFO - 1499866010511 Marionette DEBUG Closed connection 375
06:26:50 INFO - 1499866010512 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49777
06:26:50 INFO - 1499866010513 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:50 INFO - 1499866010513 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010515 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010517 Marionette TRACE 376 <- [1,1,null,{"sessionId":"163bd69d-e992-46e5-9fb5-2ecffad4fd1c","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010519 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:26:50 INFO - 1499866010521 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:26:50 INFO - 1499866010522 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:26:50 INFO - 1499866010523 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:26:50 INFO - 1499866010539 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:26:50 INFO - 1499866010540 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:26:50 INFO - 1499866010544 Marionette TRACE 376 <- [1,2,null,{}]
06:26:50 INFO - 1499866010545 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:26:50 INFO - 1499866010547 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"51a8aab6-02e7-4fb9-945a-d2898ce42cb6","ELEMENT":"51a8aab6-02e7-4fb9-945a-d2898ce42cb6"}}]
06:26:50 INFO - 1499866010553 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"51a8aab6-02e7-4fb9-945a-d2898ce42cb6"}]
06:26:50 INFO - 1499866010795 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:50 INFO - 1499866010796 Marionette TRACE 376 <- [1,4,null,{}]
06:26:50 INFO - 1499866010797 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:26:50 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499866010799 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:26:50 INFO - 1499866010800 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:26:50 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 325ms
06:26:50 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:26:50 INFO - 1499866010801 Marionette TRACE 376 <- [1,6,null,{}]
06:26:50 INFO - 1499866010803 Marionette DEBUG Closed connection 376
06:26:50 INFO - 1499866010804 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49778
06:26:50 INFO - 1499866010805 Marionette DEBUG Closed connection 377
06:26:50 INFO - 1499866010805 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49779
06:26:50 INFO - 1499866010806 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:50 INFO - 1499866010807 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010809 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010811 Marionette TRACE 378 <- [1,1,null,{"sessionId":"97d45274-93e6-46d0-97aa-da76a6c8c680","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010813 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:26:50 INFO - 1499866010813 Marionette TRACE 378 <- [1,2,null,{}]
06:26:50 INFO - 1499866010814 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:50 INFO - 1499866010814 Marionette TRACE 378 <- [1,3,null,{}]
06:26:50 INFO - 1499866010815 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:50 INFO - 1499866010817 Marionette TRACE 378 <- [1,4,null,{}]
06:26:50 INFO - 1499866010818 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:26:50 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499866010820 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:26:50 INFO - 1499866010821 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:26:50 INFO - 1499866010822 Marionette TRACE 378 <- [1,6,null,{}]
06:26:50 INFO - 1499866010824 Marionette DEBUG Closed connection 378
06:26:50 INFO - 1499866010825 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49780
06:26:50 INFO - 1499866010826 Marionette DEBUG Closed connection 379
06:26:50 INFO - 1499866010827 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49781
06:26:50 INFO - 1499866010828 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:50 INFO - 1499866010828 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010830 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010833 Marionette TRACE 380 <- [1,1,null,{"sessionId":"76fbccd4-b674-47e3-8c90-3bb4ab98f2e3","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010835 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:26:50 INFO - 1499866010836 Marionette TRACE 380 <- [1,2,null,{}]
06:26:50 INFO - 1499866010838 Marionette DEBUG Closed connection 380
06:26:50 INFO - 1499866010838 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49782
06:26:50 INFO - 1499866010839 Marionette DEBUG Closed connection 381
06:26:50 INFO - 1499866010839 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49783
06:26:50 INFO - 1499866010840 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:50 INFO - 1499866010841 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:50 INFO - 1499866010843 Marionette DEBUG Register listener.js for window 12
06:26:50 INFO - 1499866010845 Marionette TRACE 382 <- [1,1,null,{"sessionId":"3b1243a4-c818-493b-8045-aa9ea12951a2","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:50 INFO - 1499866010847 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:26:50 INFO - 1499866010848 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:26:50 INFO - 1499866010850 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:26:50 INFO - 1499866010850 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:26:50 INFO - 1499866010859 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:26:50 INFO - 1499866010863 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:26:50 INFO - 1499866010865 Marionette TRACE 382 <- [1,2,null,{}]
06:26:50 INFO - 1499866010874 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:26:50 INFO - 1499866010876 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"637cb9bb-a879-4888-83df-9437f9ae6dc9","ELEMENT":"637cb9bb-a879-4888-83df-9437f9ae6dc9"}}]
06:26:50 INFO - 1499866010877 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"637cb9bb-a879-4888-83df-9437f9ae6dc9"}]
06:26:51 INFO - 1499866011113 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:51 INFO - 1499866011114 Marionette TRACE 382 <- [1,4,null,{}]
06:26:51 INFO - 1499866011115 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:26:51 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499866011117 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:26:51 INFO - 1499866011118 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:26:51 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 318ms
06:26:51 INFO - TEST-START | test_click.py TestClick.test_input_file
06:26:51 INFO - 1499866011119 Marionette TRACE 382 <- [1,6,null,{}]
06:26:51 INFO - 1499866011121 Marionette DEBUG Closed connection 382
06:26:51 INFO - 1499866011122 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49784
06:26:51 INFO - 1499866011123 Marionette DEBUG Closed connection 383
06:26:51 INFO - 1499866011123 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49785
06:26:51 INFO - 1499866011124 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:51 INFO - 1499866011124 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011126 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011129 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f5c1f7e6-3fdc-428e-bcd2-c3721a360055","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011130 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:26:51 INFO - 1499866011131 Marionette TRACE 384 <- [1,2,null,{}]
06:26:51 INFO - 1499866011132 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:51 INFO - 1499866011132 Marionette TRACE 384 <- [1,3,null,{}]
06:26:51 INFO - 1499866011133 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:51 INFO - 1499866011133 Marionette TRACE 384 <- [1,4,null,{}]
06:26:51 INFO - 1499866011135 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:26:51 INFO - TEST-START: test_click.py TestClick.test_input_file1499866011137 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:26:51 INFO - 1499866011137 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:26:51 INFO - 1499866011138 Marionette TRACE 384 <- [1,6,null,{}]
06:26:51 INFO - 1499866011140 Marionette DEBUG Closed connection 384
06:26:51 INFO - 1499866011140 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49786
06:26:51 INFO - 1499866011141 Marionette DEBUG Closed connection 385
06:26:51 INFO - 1499866011142 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49787
06:26:51 INFO - 1499866011143 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:51 INFO - 1499866011143 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011145 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011147 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b6b067d4-47a8-4612-b226-2cd934c453b1","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011149 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:26:51 INFO - 1499866011150 Marionette TRACE 386 <- [1,2,null,{}]
06:26:51 INFO - 1499866011152 Marionette DEBUG Closed connection 386
06:26:51 INFO - 1499866011152 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49788
06:26:51 INFO - 1499866011153 Marionette DEBUG Closed connection 387
06:26:51 INFO - 1499866011153 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49789
06:26:51 INFO - 1499866011154 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:51 INFO - 1499866011155 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011157 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011166 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e678eb4a-686d-4aca-9dbf-58f2c77a6245","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011168 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:26:51 INFO - 1499866011169 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:26:51 INFO - 1499866011171 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:26:51 INFO - 1499866011171 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:26:51 INFO - 1499866011180 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:51 INFO - 1499866011189 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:51 INFO - 1499866011194 Marionette TRACE 388 <- [1,2,null,{}]
06:26:51 INFO - 1499866011195 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:26:51 INFO - 1499866011197 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bb9dc016-dd28-40e9-b0f8-1c367b9bab57","ELEMENT":"bb9dc016-dd28-40e9-b0f8-1c367b9bab57"}}]
06:26:51 INFO - 1499866011203 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"bb9dc016-dd28-40e9-b0f8-1c367b9bab57"}]
06:26:51 INFO - 1499866011227 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]
06:26:51 INFO - 1499866011229 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:26:51 INFO - TEST-END: test_click.py TestClick.test_input_file1499866011231 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:26:51 INFO - 1499866011232 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:26:51 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 114ms
06:26:51 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:26:51 INFO - 1499866011233 Marionette TRACE 388 <- [1,6,null,{}]
06:26:51 INFO - 1499866011235 Marionette DEBUG Closed connection 388
06:26:51 INFO - 1499866011236 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49790
06:26:51 INFO - 1499866011237 Marionette DEBUG Closed connection 389
06:26:51 INFO - 1499866011237 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49791
06:26:51 INFO - 1499866011238 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:51 INFO - 1499866011239 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011241 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011243 Marionette TRACE 390 <- [1,1,null,{"sessionId":"730d942e-daa8-42aa-a256-b8ee96c7a22a","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011245 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:26:51 INFO - 1499866011245 Marionette TRACE 390 <- [1,2,null,{}]
06:26:51 INFO - 1499866011246 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:51 INFO - 1499866011246 Marionette TRACE 390 <- [1,3,null,{}]
06:26:51 INFO - 1499866011247 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:51 INFO - 1499866011247 Marionette TRACE 390 <- [1,4,null,{}]
06:26:51 INFO - 1499866011249 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:26:51 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499866011251 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:26:51 INFO - 1499866011252 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:26:51 INFO - 1499866011253 Marionette TRACE 390 <- [1,6,null,{}]
06:26:51 INFO - 1499866011255 Marionette DEBUG Closed connection 390
06:26:51 INFO - 1499866011255 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49792
06:26:51 INFO - 1499866011256 Marionette DEBUG Closed connection 391
06:26:51 INFO - 1499866011256 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49793
06:26:51 INFO - 1499866011257 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:51 INFO - 1499866011258 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011260 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011262 Marionette TRACE 392 <- [1,1,null,{"sessionId":"4b148873-7e36-4f1a-b66b-d7420064f8bc","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011292 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:26:51 INFO - 1499866011293 Marionette TRACE 392 <- [1,2,null,{}]
06:26:51 INFO - 1499866011295 Marionette DEBUG Closed connection 392
06:26:51 INFO - 1499866011296 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49794
06:26:51 INFO - 1499866011296 Marionette DEBUG Closed connection 393
06:26:51 INFO - 1499866011297 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49795
06:26:51 INFO - 1499866011298 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:51 INFO - 1499866011299 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011301 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011303 Marionette TRACE 394 <- [1,1,null,{"sessionId":"bb68b938-931a-4e26-91ce-095a9bf1c066","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011305 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:26:51 INFO - 1499866011306 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:51 INFO - 1499866011308 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:51 INFO - 1499866011308 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:51 INFO - 1499866011324 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:26:51 INFO - 1499866011325 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:26:51 INFO - 1499866011327 Marionette TRACE 394 <- [1,2,null,{}]
06:26:51 INFO - 1499866011328 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:26:51 INFO - 1499866011330 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"90f6db7f-8a69-410d-a227-a07f63386bb5","ELEMENT":"90f6db7f-8a69-410d-a227-a07f63386bb5"}}]
06:26:51 INFO - 1499866011336 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:26:51 INFO - 1499866011338 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0e216317-7242-4b1a-9051-fd1a4c3b2f17","ELEMENT":"0e216317-7242-4b1a-9051-fd1a4c3b2f17"}}]
06:26:51 INFO - 1499866011339 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"90f6db7f-8a69-410d-a227-a07f63386bb5"}]
06:26:51 INFO - 1499866011580 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:51 INFO - 1499866011581 Marionette TRACE 394 <- [1,5,null,{}]
06:26:51 INFO - 1499866011582 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"0e216317-7242-4b1a-9051-fd1a4c3b2f17"}]
06:26:51 INFO - 1499866011595 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]
06:26:51 INFO - 1499866011597 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:26:51 INFO - 1499866011599 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:26:51 INFO - 1499866011600 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:26:51 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499866011602 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:26:51 INFO - 1499866011603 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:26:51 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 370ms
06:26:51 INFO - 1499866011604 Marionette TRACE 394 <- [1,9,null,{}]
06:26:51 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:26:51 INFO - 1499866011606 Marionette DEBUG Closed connection 394
06:26:51 INFO - 1499866011606 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49796
06:26:51 INFO - 1499866011607 Marionette DEBUG Closed connection 395
06:26:51 INFO - 1499866011608 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49797
06:26:51 INFO - 1499866011609 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:51 INFO - 1499866011609 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011611 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011614 Marionette TRACE 396 <- [1,1,null,{"sessionId":"e7a0e20e-fe03-40b7-bfde-a1a33f9783f2","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011616 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:26:51 INFO - 1499866011616 Marionette TRACE 396 <- [1,2,null,{}]
06:26:51 INFO - 1499866011617 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:51 INFO - 1499866011617 Marionette TRACE 396 <- [1,3,null,{}]
06:26:51 INFO - 1499866011618 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:51 INFO - 1499866011618 Marionette TRACE 396 <- [1,4,null,{}]
06:26:51 INFO - 1499866011620 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:26:51 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499866011622 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:26:51 INFO - 1499866011623 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:26:51 INFO - 1499866011625 Marionette TRACE 396 <- [1,6,null,{}]
06:26:51 INFO - 1499866011626 Marionette DEBUG Closed connection 396
06:26:51 INFO - 1499866011627 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49798
06:26:51 INFO - 1499866011627 Marionette DEBUG Closed connection 397
06:26:51 INFO - 1499866011628 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49799
06:26:51 INFO - 1499866011629 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:51 INFO - 1499866011629 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011631 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011633 Marionette TRACE 398 <- [1,1,null,{"sessionId":"338c2120-5ee2-468e-8444-459b483f4841","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011635 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:26:51 INFO - 1499866011636 Marionette TRACE 398 <- [1,2,null,{}]
06:26:51 INFO - 1499866011638 Marionette DEBUG Closed connection 398
06:26:51 INFO - 1499866011638 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49800
06:26:51 INFO - 1499866011639 Marionette DEBUG Closed connection 399
06:26:51 INFO - 1499866011639 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49801
06:26:51 INFO - 1499866011640 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:51 INFO - 1499866011641 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}
06:26:51 INFO - 1499866011643 Marionette DEBUG Register listener.js for window 12
06:26:51 INFO - 1499866011645 Marionette TRACE 400 <- [1,1,null,{"sessionId":"74230a6c-df97-4597-9b9a-48215669a235","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-239.001\\AppData\\Local\\Temp\\tmpqjo1qu.mozrunner","moz:accessibilityChecks":false}}]
06:26:51 INFO - 1499866011647 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:26:51 INFO - 1499866011649 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:26:51 INFO - 1499866011651 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:26:51 INFO - 1499866011651 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:26:51 INFO - 1499866011661 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:26:51 INFO - 1499866011663 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:26:51 INFO - 1499866011672 Marionette TRACE 400 <- [1,2,null,{}]
06:26:51 INFO - 1499866011674 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:26:51 INFO - 1499866011676 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84aff447-d3f3-4aad-9f12-2925eb035141","ELEMENT":"84aff447-d3f3-4aad-9f12-2925eb035141"}}]
06:26:51 INFO - 1499866011676 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"84aff447-d3f3-4aad-9f12-2925eb035141"}]
06:26:51 INFO - 1499866011700 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:26:51 INFO - 1499866011701 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"84aff447-d3f3-4aad-9f12-2925eb035141"}]
06:26:51 INFO - 1499866011718 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element