could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:31:21 INFO - 1500726681711 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:31:21 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500726681713 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:31:21 INFO - 1500726681714 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:31:21 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:31:21 INFO - 1500726681715 Marionette TRACE 352 <- [1,6,null,{}]
05:31:21 INFO - 1500726681719 Marionette DEBUG Closed connection 352
05:31:21 INFO - 1500726681720 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49765
05:31:21 INFO - 1500726681728 Marionette DEBUG Closed connection 353
05:31:21 INFO - 1500726681729 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49766
05:31:21 INFO - 1500726681730 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:21 INFO - 1500726681730 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:21 INFO - 1500726681733 Marionette DEBUG Register listener.js for window 15
05:31:21 INFO - 1500726681736 Marionette TRACE 354 <- [1,1,null,{"sessionId":"228459ca-47ee-49e1-bc81-45d7a6ac5856","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:21 INFO - 1500726681738 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:31:21 INFO - 1500726681738 Marionette TRACE 354 <- [1,2,null,{}]
05:31:21 INFO - 1500726681739 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:21 INFO - 1500726681740 Marionette TRACE 354 <- [1,3,null,{}]
05:31:21 INFO - 1500726681740 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:21 INFO - 1500726681741 Marionette TRACE 354 <- [1,4,null,{}]
05:31:21 INFO - 1500726681742 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:31:21 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500726681744 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:31:21 INFO - 1500726681745 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:31:21 INFO - 1500726681746 Marionette TRACE 354 <- [1,6,null,{}]
05:31:21 INFO - 1500726681748 Marionette DEBUG Closed connection 354
05:31:21 INFO - 1500726681748 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49767
05:31:21 INFO - 1500726681749 Marionette DEBUG Closed connection 355
05:31:21 INFO - 1500726681749 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49768
05:31:21 INFO - 1500726681750 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:21 INFO - 1500726681751 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:21 INFO - 1500726681753 Marionette DEBUG Register listener.js for window 15
05:31:21 INFO - 1500726681756 Marionette TRACE 356 <- [1,1,null,{"sessionId":"89bb4b8f-3423-4253-bd7e-b1d6fe2577b1","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:21 INFO - 1500726681757 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:31:21 INFO - 1500726681758 Marionette TRACE 356 <- [1,2,null,{}]
05:31:21 INFO - 1500726681760 Marionette DEBUG Closed connection 356
05:31:21 INFO - 1500726681760 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49769
05:31:21 INFO - 1500726681761 Marionette DEBUG Closed connection 357
05:31:21 INFO - 1500726681762 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49770
05:31:21 INFO - 1500726681762 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:21 INFO - 1500726681763 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:21 INFO - 1500726681765 Marionette DEBUG Register listener.js for window 15
05:31:21 INFO - 1500726681768 Marionette TRACE 358 <- [1,1,null,{"sessionId":"43255bd8-557f-4105-9a86-9ebe4734cf79","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:21 INFO - 1500726681770 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49338/clicks.html"}]
05:31:21 INFO - 1500726681771 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/hidden.html"
05:31:21 INFO - 1500726681775 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/hidden.html"
05:31:21 INFO - 1500726681775 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/hidden.html"
05:31:21 INFO - 1500726681791 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49338/clicks.html"
05:31:21 INFO - 1500726681794 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49338/clicks.html"
05:31:21 INFO - 1500726681797 Marionette TRACE 358 <- [1,2,null,{}]
05:31:21 INFO - 1500726681808 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:31:21 INFO - 1500726681810 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"218087f0-ca83-4127-a531-facc050c8ed1","ELEMENT":"218087f0-ca83-4127-a531-facc050c8ed1"}}]
05:31:21 INFO - 1500726681811 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"218087f0-ca83-4127-a531-facc050c8ed1"}]
05:31:21 INFO - 1500726681856 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/clicks.html"
05:31:21 INFO - 1500726681860 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/clicks.html"
05:31:21 INFO - 1500726681861 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/clicks.html"
05:31:21 INFO - 1500726681887 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49338/test.html"
05:31:21 INFO - 1500726681889 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49338/test.html"
05:31:21 INFO - 1500726681890 Marionette TRACE 358 <- [1,4,null,{}]
05:31:21 INFO - 1500726681894 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:31:21 INFO - 1500726681896 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"15658467-fc0d-428f-aa2d-6a09493e2e12","ELEMENT":"15658467-fc0d-428f-aa2d-6a09493e2e12"}}]
05:31:21 INFO - 1500726681896 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:31:21 INFO - 1500726681897 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:31:21 INFO - 1500726681904 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:31:21 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500726681906 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:31:21 INFO - 1500726681907 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:31:21 INFO - 1500726681908 Marionette TRACE 358 <- [1,8,null,{}]
05:31:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 191ms
05:31:21 INFO - TEST-START | test_click.py TestClick.test_container_element
05:31:21 INFO - 1500726681910 Marionette DEBUG Closed connection 358
05:31:21 INFO - 1500726681911 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49773
05:31:21 INFO - 1500726681911 Marionette DEBUG Closed connection 359
05:31:21 INFO - 1500726681912 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49774
05:31:21 INFO - 1500726681912 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:21 INFO - 1500726681913 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:21 INFO - 1500726681915 Marionette DEBUG Register listener.js for window 15
05:31:21 INFO - 1500726681919 Marionette TRACE 360 <- [1,1,null,{"sessionId":"53a41dc1-a355-4a5c-bd58-68dfdbbb34a7","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:21 INFO - 1500726681921 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:31:21 INFO - 1500726681921 Marionette TRACE 360 <- [1,2,null,{}]
05:31:21 INFO - 1500726681922 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:21 INFO - 1500726681922 Marionette TRACE 360 <- [1,3,null,{}]
05:31:21 INFO - 1500726681923 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:21 INFO - 1500726681923 Marionette TRACE 360 <- [1,4,null,{}]
05:31:21 INFO - 1500726681925 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:31:21 INFO - TEST-START: test_click.py TestClick.test_container_element1500726681927 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:31:21 INFO - 1500726681927 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:31:21 INFO - 1500726681928 Marionette TRACE 360 <- [1,6,null,{}]
05:31:21 INFO - 1500726681930 Marionette DEBUG Closed connection 360
05:31:21 INFO - 1500726681930 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49775
05:31:21 INFO - 1500726681932 Marionette DEBUG Closed connection 361
05:31:21 INFO - 1500726681933 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49776
05:31:21 INFO - 1500726681934 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:21 INFO - 1500726681934 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:21 INFO - 1500726681937 Marionette DEBUG Register listener.js for window 15
05:31:21 INFO - 1500726681939 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d6ff8179-d3c2-44c6-b40f-b506f3eb31d7","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:21 INFO - 1500726681941 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:31:21 INFO - 1500726681942 Marionette TRACE 362 <- [1,2,null,{}]
05:31:21 INFO - 1500726681944 Marionette DEBUG Closed connection 362
05:31:21 INFO - 1500726681944 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49777
05:31:21 INFO - 1500726681945 Marionette DEBUG Closed connection 363
05:31:21 INFO - 1500726681945 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49778
05:31:21 INFO - 1500726681946 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:21 INFO - 1500726681947 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:21 INFO - 1500726681949 Marionette DEBUG Register listener.js for window 15
05:31:21 INFO - 1500726681953 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5b503e39-4934-4b2c-869a-a5cf79906d81","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:21 INFO - 1500726681955 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:31:21 INFO - 1500726681957 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/test.html"
05:31:21 INFO - 1500726681958 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/test.html"
05:31:21 INFO - 1500726681959 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/test.html"
05:31:21 INFO - 1500726681979 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:21 INFO - 1500726681981 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726681989 Marionette TRACE 364 <- [1,2,null,{}]
05:31:22 INFO - 1500726681990 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:31:22 INFO - 1500726681992 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5241124e-6904-4bc1-bb65-a2b7f402ed69","ELEMENT":"5241124e-6904-4bc1-bb65-a2b7f402ed69"}}]
05:31:22 INFO - 1500726681993 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5241124e-6904-4bc1-bb65-a2b7f402ed69"}]
05:31:22 INFO - 1500726682231 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:22 INFO - 1500726682231 Marionette TRACE 364 <- [1,4,null,{}]
05:31:22 INFO - 1500726682232 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5241124e-6904-4bc1-bb65-a2b7f402ed69","name":"selected"}]
05:31:22 INFO - 1500726682236 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:31:22 INFO - 1500726682237 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:31:22 INFO - TEST-END: test_click.py TestClick.test_container_element1500726682239 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:31:22 INFO - 1500726682240 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:31:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 332ms
05:31:22 INFO - 1500726682241 Marionette TRACE 364 <- [1,7,null,{}]
05:31:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:31:22 INFO - 1500726682243 Marionette DEBUG Closed connection 364
05:31:22 INFO - 1500726682244 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49779
05:31:22 INFO - 1500726682245 Marionette DEBUG Closed connection 365
05:31:22 INFO - 1500726682245 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49780
05:31:22 INFO - 1500726682246 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:22 INFO - 1500726682247 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682249 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682251 Marionette TRACE 366 <- [1,1,null,{"sessionId":"87d4f277-6ae1-42f0-8b9d-0ea4621b1a13","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682253 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:31:22 INFO - 1500726682253 Marionette TRACE 366 <- [1,2,null,{}]
05:31:22 INFO - 1500726682254 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:22 INFO - 1500726682254 Marionette TRACE 366 <- [1,3,null,{}]
05:31:22 INFO - 1500726682255 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:22 INFO - 1500726682257 Marionette TRACE 366 <- [1,4,null,{}]
05:31:22 INFO - 1500726682258 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:31:22 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500726682260 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:31:22 INFO - 1500726682261 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:31:22 INFO - 1500726682262 Marionette TRACE 366 <- [1,6,null,{}]
05:31:22 INFO - 1500726682264 Marionette DEBUG Closed connection 366
05:31:22 INFO - 1500726682264 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49781
05:31:22 INFO - 1500726682265 Marionette DEBUG Closed connection 367
05:31:22 INFO - 1500726682266 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49782
05:31:22 INFO - 1500726682267 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:22 INFO - 1500726682267 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682269 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682279 Marionette TRACE 368 <- [1,1,null,{"sessionId":"2e90b3d5-291e-488a-949c-95685cb566b1","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682281 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:31:22 INFO - 1500726682283 Marionette TRACE 368 <- [1,2,null,{}]
05:31:22 INFO - 1500726682285 Marionette DEBUG Closed connection 368
05:31:22 INFO - 1500726682285 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49783
05:31:22 INFO - 1500726682286 Marionette DEBUG Closed connection 369
05:31:22 INFO - 1500726682286 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49784
05:31:22 INFO - 1500726682288 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:22 INFO - 1500726682288 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682290 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682292 Marionette TRACE 370 <- [1,1,null,{"sessionId":"1c1af1d2-a7e8-495d-b9fb-53edeacbd769","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682294 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:31:22 INFO - 1500726682295 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682297 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682297 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682312 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682314 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682323 Marionette TRACE 370 <- [1,2,null,{}]
05:31:22 INFO - 1500726682324 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:31:22 INFO - 1500726682327 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe86ae4e-129f-47df-9f4d-681242ec5c12","ELEMENT":"fe86ae4e-129f-47df-9f4d-681242ec5c12"}}]
05:31:22 INFO - 1500726682328 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fe86ae4e-129f-47df-9f4d-681242ec5c12"}]
05:31:22 INFO - 1500726682565 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:22 INFO - 1500726682566 Marionette TRACE 370 <- [1,4,null,{}]
05:31:22 INFO - 1500726682567 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fe86ae4e-129f-47df-9f4d-681242ec5c12","name":"selected"}]
05:31:22 INFO - 1500726682570 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:31:22 INFO - 1500726682572 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:31:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500726682574 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:31:22 INFO - 1500726682575 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:31:22 INFO - 1500726682576 Marionette TRACE 370 <- [1,7,null,{}]
05:31:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
05:31:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:31:22 INFO - 1500726682577 Marionette DEBUG Closed connection 370
05:31:22 INFO - 1500726682580 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49785
05:31:22 INFO - 1500726682580 Marionette DEBUG Closed connection 371
05:31:22 INFO - 1500726682582 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49786
05:31:22 INFO - 1500726682583 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:22 INFO - 1500726682584 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682586 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682589 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2cea8a9d-a9fe-456f-9931-41b5f672d084","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682592 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:31:22 INFO - 1500726682593 Marionette TRACE 372 <- [1,2,null,{}]
05:31:22 INFO - 1500726682593 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:22 INFO - 1500726682594 Marionette TRACE 372 <- [1,3,null,{}]
05:31:22 INFO - 1500726682595 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:22 INFO - 1500726682595 Marionette TRACE 372 <- [1,4,null,{}]
05:31:22 INFO - 1500726682596 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:31:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500726682598 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:31:22 INFO - 1500726682599 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:31:22 INFO - 1500726682600 Marionette TRACE 372 <- [1,6,null,{}]
05:31:22 INFO - 1500726682602 Marionette DEBUG Closed connection 372
05:31:22 INFO - 1500726682603 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49787
05:31:22 INFO - 1500726682604 Marionette DEBUG Closed connection 373
05:31:22 INFO - 1500726682604 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49788
05:31:22 INFO - 1500726682605 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:22 INFO - 1500726682605 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682607 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682610 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f4117d43-e31f-413c-b2bb-427b452714d0","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682612 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:31:22 INFO - 1500726682613 Marionette TRACE 374 <- [1,2,null,{}]
05:31:22 INFO - 1500726682615 Marionette DEBUG Closed connection 374
05:31:22 INFO - 1500726682615 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49789
05:31:22 INFO - 1500726682616 Marionette DEBUG Closed connection 375
05:31:22 INFO - 1500726682616 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49790
05:31:22 INFO - 1500726682617 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:22 INFO - 1500726682618 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682620 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682622 Marionette TRACE 376 <- [1,1,null,{"sessionId":"061bcf2e-439b-4596-aa03-f3d9516d41db","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682624 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:31:22 INFO - 1500726682625 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682627 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682627 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682644 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:22 INFO - 1500726682645 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:22 INFO - 1500726682648 Marionette TRACE 376 <- [1,2,null,{}]
05:31:22 INFO - 1500726682654 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:31:22 INFO - 1500726682656 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2b607140-89a9-470e-9177-e5f78c675dc3","ELEMENT":"2b607140-89a9-470e-9177-e5f78c675dc3"}}]
05:31:22 INFO - 1500726682657 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"2b607140-89a9-470e-9177-e5f78c675dc3"}]
05:31:22 INFO - 1500726682898 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:22 INFO - 1500726682899 Marionette TRACE 376 <- [1,4,null,{}]
05:31:22 INFO - 1500726682900 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:31:22 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500726682903 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:31:22 INFO - 1500726682903 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:31:22 INFO - 1500726682905 Marionette TRACE 376 <- [1,6,null,{}]
05:31:22 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 327ms
05:31:22 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:31:22 INFO - 1500726682906 Marionette DEBUG Closed connection 376
05:31:22 INFO - 1500726682907 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49791
05:31:22 INFO - 1500726682908 Marionette DEBUG Closed connection 377
05:31:22 INFO - 1500726682909 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49792
05:31:22 INFO - 1500726682910 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:22 INFO - 1500726682910 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682912 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682915 Marionette TRACE 378 <- [1,1,null,{"sessionId":"79b42278-1c11-4b7a-b30b-4b21b56b1a07","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682916 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:31:22 INFO - 1500726682917 Marionette TRACE 378 <- [1,2,null,{}]
05:31:22 INFO - 1500726682918 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:22 INFO - 1500726682918 Marionette TRACE 378 <- [1,3,null,{}]
05:31:22 INFO - 1500726682919 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:22 INFO - 1500726682919 Marionette TRACE 378 <- [1,4,null,{}]
05:31:22 INFO - 1500726682920 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:31:22 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500726682922 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:31:22 INFO - 1500726682923 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:31:22 INFO - 1500726682924 Marionette TRACE 378 <- [1,6,null,{}]
05:31:22 INFO - 1500726682926 Marionette DEBUG Closed connection 378
05:31:22 INFO - 1500726682927 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49793
05:31:22 INFO - 1500726682928 Marionette DEBUG Closed connection 379
05:31:22 INFO - 1500726682928 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49794
05:31:22 INFO - 1500726682929 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:22 INFO - 1500726682930 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682932 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682934 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9a60df95-070f-4ffc-bfc1-90d034d84c6b","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682936 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:31:22 INFO - 1500726682937 Marionette TRACE 380 <- [1,2,null,{}]
05:31:22 INFO - 1500726682938 Marionette DEBUG Closed connection 380
05:31:22 INFO - 1500726682939 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49795
05:31:22 INFO - 1500726682940 Marionette DEBUG Closed connection 381
05:31:22 INFO - 1500726682940 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49796
05:31:22 INFO - 1500726682941 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:22 INFO - 1500726682942 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:22 INFO - 1500726682943 Marionette DEBUG Register listener.js for window 15
05:31:22 INFO - 1500726682946 Marionette TRACE 382 <- [1,1,null,{"sessionId":"eb5837c1-8f28-4e58-940b-82fee497ec53","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:22 INFO - 1500726682947 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:31:22 INFO - 1500726682949 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:22 INFO - 1500726682950 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:22 INFO - 1500726682950 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:31:22 INFO - 1500726682959 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682962 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:22 INFO - 1500726682965 Marionette TRACE 382 <- [1,2,null,{}]
05:31:22 INFO - 1500726682973 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:31:22 INFO - 1500726682975 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f01efe5c-4592-4064-b39e-a9e0895688e8","ELEMENT":"f01efe5c-4592-4064-b39e-a9e0895688e8"}}]
05:31:22 INFO - 1500726682976 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f01efe5c-4592-4064-b39e-a9e0895688e8"}]
05:31:23 INFO - 1500726683214 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:23 INFO - 1500726683215 Marionette TRACE 382 <- [1,4,null,{}]
05:31:23 INFO - 1500726683217 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:31:23 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500726683219 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:31:23 INFO - 1500726683220 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:31:23 INFO - 1500726683221 Marionette TRACE 382 <- [1,6,null,{}]
05:31:23 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
05:31:23 INFO - TEST-START | test_click.py TestClick.test_input_file
05:31:23 INFO - 1500726683223 Marionette DEBUG Closed connection 382
05:31:23 INFO - 1500726683224 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49797
05:31:23 INFO - 1500726683225 Marionette DEBUG Closed connection 383
05:31:23 INFO - 1500726683225 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49798
05:31:23 INFO - 1500726683226 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:23 INFO - 1500726683227 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683228 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683231 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4cbc7bd7-c36d-4185-b2c1-366675040b74","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683233 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:31:23 INFO - 1500726683233 Marionette TRACE 384 <- [1,2,null,{}]
05:31:23 INFO - 1500726683234 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:23 INFO - 1500726683234 Marionette TRACE 384 <- [1,3,null,{}]
05:31:23 INFO - 1500726683235 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:23 INFO - 1500726683235 Marionette TRACE 384 <- [1,4,null,{}]
05:31:23 INFO - 1500726683237 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:31:23 INFO - TEST-START: test_click.py TestClick.test_input_file1500726683239 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:31:23 INFO - 1500726683239 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:31:23 INFO - 1500726683240 Marionette TRACE 384 <- [1,6,null,{}]
05:31:23 INFO - 1500726683242 Marionette DEBUG Closed connection 384
05:31:23 INFO - 1500726683243 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49799
05:31:23 INFO - 1500726683243 Marionette DEBUG Closed connection 385
05:31:23 INFO - 1500726683244 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49800
05:31:23 INFO - 1500726683245 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:23 INFO - 1500726683245 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683247 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683250 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e66d7faf-b6e0-44bf-b249-863a5cec44a1","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683252 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:31:23 INFO - 1500726683253 Marionette TRACE 386 <- [1,2,null,{}]
05:31:23 INFO - 1500726683255 Marionette DEBUG Closed connection 386
05:31:23 INFO - 1500726683255 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49801
05:31:23 INFO - 1500726683256 Marionette DEBUG Closed connection 387
05:31:23 INFO - 1500726683256 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49802
05:31:23 INFO - 1500726683257 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:23 INFO - 1500726683258 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683260 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683269 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1d5ee8a6-c341-4ca8-85ba-5718d586ed6d","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683271 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:31:23 INFO - 1500726683272 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:23 INFO - 1500726683274 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:23 INFO - 1500726683275 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:31:23 INFO - 1500726683291 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:23 INFO - 1500726683293 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:23 INFO - 1500726683295 Marionette TRACE 388 <- [1,2,null,{}]
05:31:23 INFO - 1500726683297 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:31:23 INFO - 1500726683303 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"320d26ff-cfb3-49e7-a56e-5f9471f0ef3b","ELEMENT":"320d26ff-cfb3-49e7-a56e-5f9471f0ef3b"}}]
05:31:23 INFO - 1500726683305 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"320d26ff-cfb3-49e7-a56e-5f9471f0ef3b"}]
05:31:23 INFO - 1500726683330 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:31:23 INFO - 1500726683332 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:31:23 INFO - TEST-END: test_click.py TestClick.test_input_file1500726683334 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:31:23 INFO - 1500726683335 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:31:23 INFO - 1500726683336 Marionette TRACE 388 <- [1,6,null,{}]
05:31:23 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 114ms
05:31:23 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:31:23 INFO - 1500726683338 Marionette DEBUG Closed connection 388
05:31:23 INFO - 1500726683338 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49803
05:31:23 INFO - 1500726683340 Marionette DEBUG Closed connection 389
05:31:23 INFO - 1500726683340 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49804
05:31:23 INFO - 1500726683341 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:23 INFO - 1500726683342 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683344 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683346 Marionette TRACE 390 <- [1,1,null,{"sessionId":"05033442-af20-45f4-923c-6cdc4f2ce44e","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683348 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:31:23 INFO - 1500726683348 Marionette TRACE 390 <- [1,2,null,{}]
05:31:23 INFO - 1500726683349 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:23 INFO - 1500726683349 Marionette TRACE 390 <- [1,3,null,{}]
05:31:23 INFO - 1500726683350 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:23 INFO - 1500726683350 Marionette TRACE 390 <- [1,4,null,{}]
05:31:23 INFO - 1500726683352 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:31:23 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500726683354 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:31:23 INFO - 1500726683355 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:31:23 INFO - 1500726683356 Marionette TRACE 390 <- [1,6,null,{}]
05:31:23 INFO - 1500726683358 Marionette DEBUG Closed connection 390
05:31:23 INFO - 1500726683358 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49805
05:31:23 INFO - 1500726683359 Marionette DEBUG Closed connection 391
05:31:23 INFO - 1500726683359 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49806
05:31:23 INFO - 1500726683360 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:23 INFO - 1500726683361 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683363 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683365 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f4f431a7-3106-4779-9b00-442bd7b1d49c","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683367 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:31:23 INFO - 1500726683368 Marionette TRACE 392 <- [1,2,null,{}]
05:31:23 INFO - 1500726683370 Marionette DEBUG Closed connection 392
05:31:23 INFO - 1500726683370 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49807
05:31:23 INFO - 1500726683371 Marionette DEBUG Closed connection 393
05:31:23 INFO - 1500726683371 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49808
05:31:23 INFO - 1500726683372 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:23 INFO - 1500726683373 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683375 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683377 Marionette TRACE 394 <- [1,1,null,{"sessionId":"375225f0-299a-4392-8f87-e357b702aed3","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683379 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:31:23 INFO - 1500726683380 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:23 INFO - 1500726683382 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:23 INFO - 1500726683382 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:31:23 INFO - 1500726683392 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:23 INFO - 1500726683395 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:23 INFO - 1500726683398 Marionette TRACE 394 <- [1,2,null,{}]
05:31:23 INFO - 1500726683404 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:31:23 INFO - 1500726683406 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5b859693-70a2-4590-a013-61a0c614a775","ELEMENT":"5b859693-70a2-4590-a013-61a0c614a775"}}]
05:31:23 INFO - 1500726683407 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:31:23 INFO - 1500726683408 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0ae57aa0-0042-430f-99dd-e7007447f88d","ELEMENT":"0ae57aa0-0042-430f-99dd-e7007447f88d"}}]
05:31:23 INFO - 1500726683409 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"5b859693-70a2-4590-a013-61a0c614a775"}]
05:31:23 INFO - 1500726683651 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:31:23 INFO - 1500726683651 Marionette TRACE 394 <- [1,5,null,{}]
05:31:23 INFO - 1500726683653 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"0ae57aa0-0042-430f-99dd-e7007447f88d"}]
05:31:23 INFO - 1500726683666 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:31:23 INFO - 1500726683667 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:31:23 INFO - 1500726683669 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:31:23 INFO - 1500726683671 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:31:23 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500726683672 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:31:23 INFO - 1500726683673 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:31:23 INFO - 1500726683674 Marionette TRACE 394 <- [1,9,null,{}]
05:31:23 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
05:31:23 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:31:23 INFO - 1500726683676 Marionette DEBUG Closed connection 394
05:31:23 INFO - 1500726683677 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49809
05:31:23 INFO - 1500726683678 Marionette DEBUG Closed connection 395
05:31:23 INFO - 1500726683678 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49810
05:31:23 INFO - 1500726683679 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:23 INFO - 1500726683680 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683682 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683684 Marionette TRACE 396 <- [1,1,null,{"sessionId":"24a7197a-8181-4057-9f53-f9dad8e24da3","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683686 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:31:23 INFO - 1500726683686 Marionette TRACE 396 <- [1,2,null,{}]
05:31:23 INFO - 1500726683688 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:31:23 INFO - 1500726683688 Marionette TRACE 396 <- [1,3,null,{}]
05:31:23 INFO - 1500726683689 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:31:23 INFO - 1500726683689 Marionette TRACE 396 <- [1,4,null,{}]
05:31:23 INFO - 1500726683690 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:31:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500726683692 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:31:23 INFO - 1500726683694 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:31:23 INFO - 1500726683694 Marionette TRACE 396 <- [1,6,null,{}]
05:31:23 INFO - 1500726683696 Marionette DEBUG Closed connection 396
05:31:23 INFO - 1500726683697 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49811
05:31:23 INFO - 1500726683697 Marionette DEBUG Closed connection 397
05:31:23 INFO - 1500726683698 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49812
05:31:23 INFO - 1500726683699 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:31:23 INFO - 1500726683699 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683701 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683705 Marionette TRACE 398 <- [1,1,null,{"sessionId":"c48c9e32-7e6f-4a8d-83d4-dcf90241a484","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683707 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:31:23 INFO - 1500726683708 Marionette TRACE 398 <- [1,2,null,{}]
05:31:23 INFO - 1500726683710 Marionette DEBUG Closed connection 398
05:31:23 INFO - 1500726683710 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49813
05:31:23 INFO - 1500726683711 Marionette DEBUG Closed connection 399
05:31:23 INFO - 1500726683712 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49814
05:31:23 INFO - 1500726683713 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:31:23 INFO - 1500726683713 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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}
05:31:23 INFO - 1500726683715 Marionette DEBUG Register listener.js for window 15
05:31:23 INFO - 1500726683718 Marionette TRACE 400 <- [1,1,null,{"sessionId":"64276dd0-4d2b-43a7-946f-446feab14cf3","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":4076,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-354.001\\AppData\\Local\\Temp\\tmpcaibbg.mozrunner","moz:accessibilityChecks":false}}]
05:31:23 INFO - 1500726683720 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:31:23 INFO - 1500726683721 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:23 INFO - 1500726683723 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:23 INFO - 1500726683723 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:31:23 INFO - 1500726683741 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:31:23 INFO - 1500726683742 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:31:23 INFO - 1500726683745 Marionette TRACE 400 <- [1,2,null,{}]
05:31:23 INFO - 1500726683746 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:31:23 INFO - 1500726683747 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63001975-bd96-4121-a97d-551879a8597f","ELEMENT":"63001975-bd96-4121-a97d-551879a8597f"}}]
05:31:23 INFO - 1500726683754 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"63001975-bd96-4121-a97d-551879a8597f"}]
05:31:23 INFO - 1500726683776 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:31:23 INFO - 1500726683777 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"63001975-bd96-4121-a97d-551879a8597f"}]
05:31:23 INFO - 1500726683791 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element