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]
19:31:27 INFO - 1500949887712 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}]
19:31:27 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500949887715 Marionette TRACE 352 <- [1,5,null,{"value":null}]
19:31:27 INFO - 1500949887716 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
19:31:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 145ms
19:31:27 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:31:27 INFO - 1500949887717 Marionette TRACE 352 <- [1,6,null,{}]
19:31:27 INFO - 1500949887719 Marionette DEBUG Closed connection 352
19:31:27 INFO - 1500949887720 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49766
19:31:27 INFO - 1500949887720 Marionette DEBUG Closed connection 353
19:31:27 INFO - 1500949887721 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49767
19:31:27 INFO - 1500949887722 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:27 INFO - 1500949887729 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:27 INFO - 1500949887733 Marionette DEBUG Register listener.js for window 4294967297
19:31:27 INFO - 1500949887736 Marionette TRACE 354 <- [1,1,null,{"sessionId":"9f3add15-1466-41d5-a13f-cd863a5ae43f","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:27 INFO - 1500949887738 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
19:31:27 INFO - 1500949887738 Marionette TRACE 354 <- [1,2,null,{}]
19:31:27 INFO - 1500949887739 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:31:27 INFO - 1500949887740 Marionette TRACE 354 <- [1,3,null,{}]
19:31:27 INFO - 1500949887741 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
19:31:27 INFO - 1500949887741 Marionette TRACE 354 <- [1,4,null,{}]
19:31:27 INFO - 1500949887743 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}]
19:31:27 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500949887746 Marionette TRACE 354 <- [1,5,null,{"value":null}]
19:31:27 INFO - 1500949887747 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
19:31:27 INFO - 1500949887748 Marionette TRACE 354 <- [1,6,null,{}]
19:31:27 INFO - 1500949887750 Marionette DEBUG Closed connection 354
19:31:27 INFO - 1500949887750 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49768
19:31:27 INFO - 1500949887751 Marionette DEBUG Closed connection 355
19:31:27 INFO - 1500949887752 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49769
19:31:27 INFO - 1500949887753 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:27 INFO - 1500949887753 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:27 INFO - 1500949887756 Marionette DEBUG Register listener.js for window 4294967297
19:31:27 INFO - 1500949887760 Marionette TRACE 356 <- [1,1,null,{"sessionId":"40ef8f40-06d5-4fc9-84a0-18f5348d4096","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:27 INFO - 1500949887761 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
19:31:27 INFO - 1500949887762 Marionette TRACE 356 <- [1,2,null,{}]
19:31:27 INFO - 1500949887764 Marionette DEBUG Closed connection 356
19:31:27 INFO - 1500949887764 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49770
19:31:27 INFO - 1500949887765 Marionette DEBUG Closed connection 357
19:31:27 INFO - 1500949887766 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49771
19:31:27 INFO - 1500949887767 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:31:27 INFO - 1500949887767 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:27 INFO - 1500949887770 Marionette DEBUG Register listener.js for window 4294967297
19:31:27 INFO - 1500949887774 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3cf5faf5-6708-4790-9cfb-f7847ec37ac9","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:27 INFO - 1500949887775 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
19:31:27 INFO - 1500949887777 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
19:31:27 INFO - 1500949887787 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
19:31:27 INFO - 1500949887788 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
19:31:27 INFO - 1500949887794 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
19:31:27 INFO - 1500949887799 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
19:31:27 INFO - 1500949887804 Marionette TRACE 358 <- [1,2,null,{}]
19:31:27 INFO - 1500949887808 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:31:27 INFO - 1500949887811 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f35498c-af7e-468e-a3e2-92ef368ba617","ELEMENT":"9f35498c-af7e-468e-a3e2-92ef368ba617"}}]
19:31:27 INFO - 1500949887811 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"9f35498c-af7e-468e-a3e2-92ef368ba617"}]
19:31:27 INFO - 1500949887855 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
19:31:27 INFO - 1500949887864 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
19:31:27 INFO - 1500949887865 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
19:31:27 INFO - 1500949887874 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
19:31:27 INFO - 1500949887880 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
19:31:27 INFO - 1500949887883 Marionette TRACE 358 <- [1,4,null,{}]
19:31:27 INFO - 1500949887891 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:31:27 INFO - 1500949887904 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4c453eb-eada-4178-ad21-82d34f18840f","ELEMENT":"a4c453eb-eada-4178-ad21-82d34f18840f"}}]
19:31:27 INFO - 1500949887905 Marionette TRACE 358 -> [0,6,"getTitle",{}]
19:31:27 INFO - 1500949887906 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
19:31:27 INFO - 1500949887907 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}]
19:31:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 202ms
19:31:27 INFO - TEST-START | test_click.py TestClick.test_container_element
19:31:27 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500949887917 Marionette TRACE 358 <- [1,7,null,{"value":null}]
19:31:27 INFO - 1500949887918 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
19:31:27 INFO - 1500949887920 Marionette TRACE 358 <- [1,8,null,{}]
19:31:27 INFO - 1500949887921 Marionette DEBUG Closed connection 358
19:31:27 INFO - 1500949887922 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49774
19:31:27 INFO - 1500949887923 Marionette DEBUG Closed connection 359
19:31:27 INFO - 1500949887923 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49775
19:31:27 INFO - 1500949887924 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:27 INFO - 1500949887925 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:27 INFO - 1500949887928 Marionette DEBUG Register listener.js for window 4294967297
19:31:27 INFO - 1500949887931 Marionette TRACE 360 <- [1,1,null,{"sessionId":"7b47994d-bd42-434f-9cfb-a0ef6dec6865","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:27 INFO - 1500949887932 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
19:31:27 INFO - 1500949887933 Marionette TRACE 360 <- [1,2,null,{}]
19:31:27 INFO - 1500949887934 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:31:27 INFO - 1500949887934 Marionette TRACE 360 <- [1,3,null,{}]
19:31:27 INFO - 1500949887935 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
19:31:27 INFO - 1500949887936 Marionette TRACE 360 <- [1,4,null,{}]
19:31:27 INFO - 1500949887937 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}]
19:31:27 INFO - TEST-START: test_click.py TestClick.test_container_element1500949887941 Marionette TRACE 360 <- [1,5,null,{"value":null}]
19:31:27 INFO - 1500949887942 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
19:31:27 INFO - 1500949887944 Marionette TRACE 360 <- [1,6,null,{}]
19:31:27 INFO - 1500949887945 Marionette DEBUG Closed connection 360
19:31:27 INFO - 1500949887946 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49776
19:31:27 INFO - 1500949887947 Marionette DEBUG Closed connection 361
19:31:27 INFO - 1500949887947 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49777
19:31:27 INFO - 1500949887948 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:27 INFO - 1500949887949 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:27 INFO - 1500949887952 Marionette DEBUG Register listener.js for window 4294967297
19:31:27 INFO - 1500949887955 Marionette TRACE 362 <- [1,1,null,{"sessionId":"76b3705b-cc5d-4d29-bc20-d45faa45e8b5","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:27 INFO - 1500949887957 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
19:31:27 INFO - 1500949887958 Marionette TRACE 362 <- [1,2,null,{}]
19:31:27 INFO - 1500949887960 Marionette DEBUG Closed connection 362
19:31:27 INFO - 1500949887960 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49778
19:31:27 INFO - 1500949887961 Marionette DEBUG Closed connection 363
19:31:27 INFO - 1500949887962 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49779
19:31:27 INFO - 1500949887963 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:31:27 INFO - 1500949887963 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:27 INFO - 1500949887967 Marionette DEBUG Register listener.js for window 4294967297
19:31:27 INFO - 1500949887970 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1ea3a7e5-198d-4ac4-87bd-24efe75ffa7c","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:27 INFO - 1500949887971 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"}]
19:31:27 INFO - 1500949887973 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
19:31:27 INFO - 1500949887975 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
19:31:27 INFO - 1500949887975 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
19:31:27 INFO - 1500949887988 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"
19:31:27 INFO - 1500949887991 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"
19:31:28 INFO - 1500949887998 Marionette TRACE 364 <- [1,2,null,{}]
19:31:28 INFO - 1500949888006 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:31:28 INFO - 1500949888020 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d687cdb2-9d35-4b3e-a0ad-55588a542a59","ELEMENT":"d687cdb2-9d35-4b3e-a0ad-55588a542a59"}}]
19:31:28 INFO - 1500949888021 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d687cdb2-9d35-4b3e-a0ad-55588a542a59"}]
19:31:28 INFO - 1500949888268 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:31:28 INFO - 1500949888269 Marionette TRACE 364 <- [1,4,null,{}]
19:31:28 INFO - 1500949888270 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d687cdb2-9d35-4b3e-a0ad-55588a542a59","name":"selected"}]
19:31:28 INFO - 1500949888275 Marionette TRACE 364 <- [1,5,null,{"value":true}]
19:31:28 INFO - 1500949888277 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}]
19:31:28 INFO - TEST-END: test_click.py TestClick.test_container_element1500949888280 Marionette TRACE 364 <- [1,6,null,{"value":null}]
19:31:28 INFO - 1500949888281 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
19:31:28 INFO - 1500949888282 Marionette TRACE 364 <- [1,7,null,{}]
19:31:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 363ms
19:31:28 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:31:28 INFO - 1500949888284 Marionette DEBUG Closed connection 364
19:31:28 INFO - 1500949888286 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49780
19:31:28 INFO - 1500949888287 Marionette DEBUG Closed connection 365
19:31:28 INFO - 1500949888287 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49781
19:31:28 INFO - 1500949888288 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:28 INFO - 1500949888289 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:28 INFO - 1500949888292 Marionette DEBUG Register listener.js for window 4294967297
19:31:28 INFO - 1500949888295 Marionette TRACE 366 <- [1,1,null,{"sessionId":"416c9695-8f8d-4a71-890c-29d59ffb1f2e","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:28 INFO - 1500949888296 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
19:31:28 INFO - 1500949888297 Marionette TRACE 366 <- [1,2,null,{}]
19:31:28 INFO - 1500949888298 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:31:28 INFO - 1500949888298 Marionette TRACE 366 <- [1,3,null,{}]
19:31:28 INFO - 1500949888299 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
19:31:28 INFO - 1500949888299 Marionette TRACE 366 <- [1,4,null,{}]
19:31:28 INFO - 1500949888301 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}]
19:31:28 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500949888304 Marionette TRACE 366 <- [1,5,null,{"value":null}]
19:31:28 INFO - 1500949888304 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
19:31:28 INFO - 1500949888306 Marionette TRACE 366 <- [1,6,null,{}]
19:31:28 INFO - 1500949888307 Marionette DEBUG Closed connection 366
19:31:28 INFO - 1500949888308 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49782
19:31:28 INFO - 1500949888309 Marionette DEBUG Closed connection 367
19:31:28 INFO - 1500949888309 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49783
19:31:28 INFO - 1500949888310 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:28 INFO - 1500949888311 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:28 INFO - 1500949888314 Marionette DEBUG Register listener.js for window 4294967297
19:31:28 INFO - 1500949888320 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ee26a531-21f0-44c4-bc64-13e31bff71c2","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:28 INFO - 1500949888321 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
19:31:28 INFO - 1500949888322 Marionette TRACE 368 <- [1,2,null,{}]
19:31:28 INFO - 1500949888324 Marionette DEBUG Closed connection 368
19:31:28 INFO - 1500949888324 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49784
19:31:28 INFO - 1500949888325 Marionette DEBUG Closed connection 369
19:31:28 INFO - 1500949888326 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49785
19:31:28 INFO - 1500949888327 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:31:28 INFO - 1500949888328 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:28 INFO - 1500949888331 Marionette DEBUG Register listener.js for window 4294967297
19:31:28 INFO - 1500949888334 Marionette TRACE 370 <- [1,1,null,{"sessionId":"12c2b01c-72f2-4ca3-b09c-37b17b59f595","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:28 INFO - 1500949888335 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"}]
19:31:28 INFO - 1500949888338 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"
19:31:28 INFO - 1500949888340 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"
19:31:28 INFO - 1500949888340 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"
19:31:28 INFO - 1500949888347 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"
19:31:28 INFO - 1500949888350 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"
19:31:28 INFO - 1500949888358 Marionette TRACE 370 <- [1,2,null,{}]
19:31:28 INFO - 1500949888361 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:31:28 INFO - 1500949888364 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fcc47ebb-b200-4505-85c4-ca131fba45dc","ELEMENT":"fcc47ebb-b200-4505-85c4-ca131fba45dc"}}]
19:31:28 INFO - 1500949888369 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fcc47ebb-b200-4505-85c4-ca131fba45dc"}]
19:31:28 INFO - 1500949888612 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:31:28 INFO - 1500949888613 Marionette TRACE 370 <- [1,4,null,{}]
19:31:28 INFO - 1500949888614 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fcc47ebb-b200-4505-85c4-ca131fba45dc","name":"selected"}]
19:31:28 INFO - 1500949888618 Marionette TRACE 370 <- [1,5,null,{"value":true}]
19:31:28 INFO - 1500949888620 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}]
19:31:28 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500949888623 Marionette TRACE 370 <- [1,6,null,{"value":null}]
19:31:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 341ms
19:31:28 INFO - 1500949888624 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
19:31:28 INFO - 1500949888625 Marionette TRACE 370 <- [1,7,null,{}]
19:31:28 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:31:28 INFO - 1500949888627 Marionette DEBUG Closed connection 370
19:31:28 INFO - 1500949888628 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49786
19:31:28 INFO - 1500949888629 Marionette DEBUG Closed connection 371
19:31:28 INFO - 1500949888629 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49787
19:31:28 INFO - 1500949888630 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:28 INFO - 1500949888631 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:28 INFO - 1500949888633 Marionette DEBUG Register listener.js for window 4294967297
19:31:28 INFO - 1500949888637 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ab99d260-659b-4e33-9ce3-3afff4d85125","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:28 INFO - 1500949888638 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
19:31:28 INFO - 1500949888639 Marionette TRACE 372 <- [1,2,null,{}]
19:31:28 INFO - 1500949888640 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:31:28 INFO - 1500949888640 Marionette TRACE 372 <- [1,3,null,{}]
19:31:28 INFO - 1500949888641 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
19:31:28 INFO - 1500949888642 Marionette TRACE 372 <- [1,4,null,{}]
19:31:28 INFO - 1500949888643 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}]
19:31:28 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500949888646 Marionette TRACE 372 <- [1,5,null,{"value":null}]
19:31:28 INFO - 1500949888647 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
19:31:28 INFO - 1500949888648 Marionette TRACE 372 <- [1,6,null,{}]
19:31:28 INFO - 1500949888650 Marionette DEBUG Closed connection 372
19:31:28 INFO - 1500949888651 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49788
19:31:28 INFO - 1500949888652 Marionette DEBUG Closed connection 373
19:31:28 INFO - 1500949888652 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49789
19:31:28 INFO - 1500949888653 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:28 INFO - 1500949888654 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:28 INFO - 1500949888656 Marionette DEBUG Register listener.js for window 4294967297
19:31:28 INFO - 1500949888660 Marionette TRACE 374 <- [1,1,null,{"sessionId":"0caed8da-92a1-4e76-ba24-7fd96b2e883e","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:28 INFO - 1500949888661 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
19:31:28 INFO - 1500949888662 Marionette TRACE 374 <- [1,2,null,{}]
19:31:28 INFO - 1500949888664 Marionette DEBUG Closed connection 374
19:31:28 INFO - 1500949888665 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49790
19:31:28 INFO - 1500949888666 Marionette DEBUG Closed connection 375
19:31:28 INFO - 1500949888666 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49791
19:31:28 INFO - 1500949888667 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:31:28 INFO - 1500949888668 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:28 INFO - 1500949888670 Marionette DEBUG Register listener.js for window 4294967297
19:31:28 INFO - 1500949888674 Marionette TRACE 376 <- [1,1,null,{"sessionId":"20a187eb-4402-4c9a-9fc6-cd4897a2f429","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:28 INFO - 1500949888675 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"}]
19:31:28 INFO - 1500949888677 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"
19:31:28 INFO - 1500949888679 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"
19:31:28 INFO - 1500949888679 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"
19:31:28 INFO - 1500949888687 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"
19:31:28 INFO - 1500949888689 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"
19:31:28 INFO - 1500949888694 Marionette TRACE 376 <- [1,2,null,{}]
19:31:28 INFO - 1500949888697 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:31:28 INFO - 1500949888704 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6d75263b-8c06-4744-bd1b-52fd25a3ae47","ELEMENT":"6d75263b-8c06-4744-bd1b-52fd25a3ae47"}}]
19:31:28 INFO - 1500949888705 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6d75263b-8c06-4744-bd1b-52fd25a3ae47"}]
19:31:28 INFO - 1500949888950 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:31:28 INFO - 1500949888951 Marionette TRACE 376 <- [1,4,null,{}]
19:31:28 INFO - 1500949888953 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}]
19:31:28 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 331ms
19:31:28 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500949888956 Marionette TRACE 376 <- [1,5,null,{"value":null}]
19:31:28 INFO - 1500949888956 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
19:31:28 INFO - 1500949888958 Marionette TRACE 376 <- [1,6,null,{}]
19:31:28 INFO - 1500949888960 Marionette DEBUG Closed connection 376
19:31:28 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:31:28 INFO - 1500949888962 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49792
19:31:28 INFO - 1500949888963 Marionette DEBUG Closed connection 377
19:31:28 INFO - 1500949888963 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49793
19:31:28 INFO - 1500949888964 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:28 INFO - 1500949888965 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:28 INFO - 1500949888968 Marionette DEBUG Register listener.js for window 4294967297
19:31:28 INFO - 1500949888971 Marionette TRACE 378 <- [1,1,null,{"sessionId":"31413d9b-9f35-4be2-bf38-ceec33f4e97c","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:28 INFO - 1500949888972 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
19:31:28 INFO - 1500949888972 Marionette TRACE 378 <- [1,2,null,{}]
19:31:28 INFO - 1500949888974 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:31:28 INFO - 1500949888974 Marionette TRACE 378 <- [1,3,null,{}]
19:31:28 INFO - 1500949888975 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
19:31:28 INFO - 1500949888976 Marionette TRACE 378 <- [1,4,null,{}]
19:31:28 INFO - 1500949888977 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}]
19:31:28 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500949888980 Marionette TRACE 378 <- [1,5,null,{"value":null}]
19:31:28 INFO - 1500949888981 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
19:31:28 INFO - 1500949888983 Marionette TRACE 378 <- [1,6,null,{}]
19:31:28 INFO - 1500949888984 Marionette DEBUG Closed connection 378
19:31:28 INFO - 1500949888985 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49794
19:31:28 INFO - 1500949888985 Marionette DEBUG Closed connection 379
19:31:28 INFO - 1500949888986 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49795
19:31:28 INFO - 1500949888987 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:28 INFO - 1500949888988 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949888991 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949888994 Marionette TRACE 380 <- [1,1,null,{"sessionId":"e0a2a4ce-3c34-4d01-acee-59c86558db9c","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889003 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
19:31:29 INFO - 1500949889004 Marionette TRACE 380 <- [1,2,null,{}]
19:31:29 INFO - 1500949889006 Marionette DEBUG Closed connection 380
19:31:29 INFO - 1500949889006 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49796
19:31:29 INFO - 1500949889007 Marionette DEBUG Closed connection 381
19:31:29 INFO - 1500949889008 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49797
19:31:29 INFO - 1500949889009 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:31:29 INFO - 1500949889010 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889012 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889016 Marionette TRACE 382 <- [1,1,null,{"sessionId":"4d5f6093-f07b-4977-b48f-83988d80461a","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889018 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"}]
19:31:29 INFO - 1500949889020 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"
19:31:29 INFO - 1500949889022 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"
19:31:29 INFO - 1500949889022 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"
19:31:29 INFO - 1500949889028 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"
19:31:29 INFO - 1500949889032 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"
19:31:29 INFO - 1500949889039 Marionette TRACE 382 <- [1,2,null,{}]
19:31:29 INFO - 1500949889043 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:31:29 INFO - 1500949889045 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ecda375a-37f1-4839-b6fd-81ead149c948","ELEMENT":"ecda375a-37f1-4839-b6fd-81ead149c948"}}]
19:31:29 INFO - 1500949889046 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ecda375a-37f1-4839-b6fd-81ead149c948"}]
19:31:29 INFO - 1500949889289 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:31:29 INFO - 1500949889290 Marionette TRACE 382 <- [1,4,null,{}]
19:31:29 INFO - 1500949889292 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}]
19:31:29 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500949889294 Marionette TRACE 382 <- [1,5,null,{"value":null}]
19:31:29 INFO - 1500949889296 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
19:31:29 INFO - 1500949889297 Marionette TRACE 382 <- [1,6,null,{}]
19:31:29 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 338ms
19:31:29 INFO - TEST-START | test_click.py TestClick.test_input_file
19:31:29 INFO - 1500949889299 Marionette DEBUG Closed connection 382
19:31:29 INFO - 1500949889299 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49798
19:31:29 INFO - 1500949889300 Marionette DEBUG Closed connection 383
19:31:29 INFO - 1500949889301 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49799
19:31:29 INFO - 1500949889302 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:29 INFO - 1500949889302 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889305 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889309 Marionette TRACE 384 <- [1,1,null,{"sessionId":"be800fc2-1f28-4b52-890b-bd10ee4bbbcd","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889310 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
19:31:29 INFO - 1500949889310 Marionette TRACE 384 <- [1,2,null,{}]
19:31:29 INFO - 1500949889311 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:31:29 INFO - 1500949889311 Marionette TRACE 384 <- [1,3,null,{}]
19:31:29 INFO - 1500949889312 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
19:31:29 INFO - 1500949889313 Marionette TRACE 384 <- [1,4,null,{}]
19:31:29 INFO - 1500949889315 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}]
19:31:29 INFO - TEST-START: test_click.py TestClick.test_input_file1500949889317 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:31:29 INFO - 1500949889318 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:31:29 INFO - 1500949889320 Marionette TRACE 384 <- [1,6,null,{}]
19:31:29 INFO - 1500949889321 Marionette DEBUG Closed connection 384
19:31:29 INFO - 1500949889322 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49800
19:31:29 INFO - 1500949889322 Marionette DEBUG Closed connection 385
19:31:29 INFO - 1500949889323 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49801
19:31:29 INFO - 1500949889324 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:29 INFO - 1500949889325 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889327 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889330 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b31768da-09ae-408b-bd96-18e04e05d91b","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889332 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
19:31:29 INFO - 1500949889333 Marionette TRACE 386 <- [1,2,null,{}]
19:31:29 INFO - 1500949889334 Marionette DEBUG Closed connection 386
19:31:29 INFO - 1500949889335 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49802
19:31:29 INFO - 1500949889336 Marionette DEBUG Closed connection 387
19:31:29 INFO - 1500949889337 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49803
19:31:29 INFO - 1500949889338 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:31:29 INFO - 1500949889338 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889341 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889344 Marionette TRACE 388 <- [1,1,null,{"sessionId":"b7fba3e1-37af-47c0-8d7d-b310925b6d70","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889346 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:31:29 INFO - 1500949889347 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"
19:31:29 INFO - 1500949889349 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"
19:31:29 INFO - 1500949889350 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"
19:31:29 INFO - 1500949889356 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:31:29 INFO - 1500949889360 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:31:29 INFO - 1500949889361 Marionette TRACE 388 <- [1,2,null,{}]
19:31:29 INFO - 1500949889370 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:31:29 INFO - 1500949889374 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dce2820f-1db0-4f50-bb38-53858041ee54","ELEMENT":"dce2820f-1db0-4f50-bb38-53858041ee54"}}]
19:31:29 INFO - 1500949889375 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"dce2820f-1db0-4f50-bb38-53858041ee54"}]
19:31:29 INFO - 1500949889410 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]
19:31:29 INFO - 1500949889412 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}]
19:31:29 INFO - TEST-END: test_click.py TestClick.test_input_file1500949889415 Marionette TRACE 388 <- [1,5,null,{"value":null}]
19:31:29 INFO - 1500949889416 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
19:31:29 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 120ms
19:31:29 INFO - 1500949889417 Marionette TRACE 388 <- [1,6,null,{}]
19:31:29 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:31:29 INFO - 1500949889419 Marionette DEBUG Closed connection 388
19:31:29 INFO - 1500949889420 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49804
19:31:29 INFO - 1500949889421 Marionette DEBUG Closed connection 389
19:31:29 INFO - 1500949889421 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49805
19:31:29 INFO - 1500949889422 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:29 INFO - 1500949889423 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889426 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889430 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9e46206c-c61e-46c9-9c35-5cba1ef0e2b2","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889431 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
19:31:29 INFO - 1500949889431 Marionette TRACE 390 <- [1,2,null,{}]
19:31:29 INFO - 1500949889432 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:31:29 INFO - 1500949889433 Marionette TRACE 390 <- [1,3,null,{}]
19:31:29 INFO - 1500949889434 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
19:31:29 INFO - 1500949889434 Marionette TRACE 390 <- [1,4,null,{}]
19:31:29 INFO - 1500949889436 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}]
19:31:29 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500949889438 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:31:29 INFO - 1500949889439 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:31:29 INFO - 1500949889441 Marionette TRACE 390 <- [1,6,null,{}]
19:31:29 INFO - 1500949889442 Marionette DEBUG Closed connection 390
19:31:29 INFO - 1500949889443 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49806
19:31:29 INFO - 1500949889444 Marionette DEBUG Closed connection 391
19:31:29 INFO - 1500949889444 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49807
19:31:29 INFO - 1500949889445 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:29 INFO - 1500949889446 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889449 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889452 Marionette TRACE 392 <- [1,1,null,{"sessionId":"dae2d645-a9af-4bc7-a110-ea902095f51f","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889453 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
19:31:29 INFO - 1500949889455 Marionette TRACE 392 <- [1,2,null,{}]
19:31:29 INFO - 1500949889456 Marionette DEBUG Closed connection 392
19:31:29 INFO - 1500949889457 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49808
19:31:29 INFO - 1500949889458 Marionette DEBUG Closed connection 393
19:31:29 INFO - 1500949889458 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49809
19:31:29 INFO - 1500949889460 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:31:29 INFO - 1500949889460 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889463 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889466 Marionette TRACE 394 <- [1,1,null,{"sessionId":"afdb0ff3-f1fe-4cc4-a94c-46d898c08cc8","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889468 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"}]
19:31:29 INFO - 1500949889470 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:31:29 INFO - 1500949889472 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:31:29 INFO - 1500949889472 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:31:29 INFO - 1500949889479 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"
19:31:29 INFO - 1500949889482 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"
19:31:29 INFO - 1500949889490 Marionette TRACE 394 <- [1,2,null,{}]
19:31:29 INFO - 1500949889493 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:31:29 INFO - 1500949889496 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf2774b2-2ebf-48e4-996f-700dd24ee23b","ELEMENT":"cf2774b2-2ebf-48e4-996f-700dd24ee23b"}}]
19:31:29 INFO - 1500949889497 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:31:29 INFO - 1500949889502 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"98581ad9-091c-4b14-9fbc-30b82a9c56ca","ELEMENT":"98581ad9-091c-4b14-9fbc-30b82a9c56ca"}}]
19:31:29 INFO - 1500949889503 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"cf2774b2-2ebf-48e4-996f-700dd24ee23b"}]
19:31:29 INFO - 1500949889749 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:31:29 INFO - 1500949889750 Marionette TRACE 394 <- [1,5,null,{}]
19:31:29 INFO - 1500949889751 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"98581ad9-091c-4b14-9fbc-30b82a9c56ca"}]
19:31:29 INFO - 1500949889768 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]
19:31:29 INFO - 1500949889770 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
19:31:29 INFO - 1500949889773 Marionette TRACE 394 <- [1,7,null,{"value":false}]
19:31:29 INFO - 1500949889774 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}]
19:31:29 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500949889776 Marionette TRACE 394 <- [1,8,null,{"value":null}]
19:31:29 INFO - 1500949889777 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
19:31:29 INFO - 1500949889779 Marionette TRACE 394 <- [1,9,null,{}]
19:31:29 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 361ms
19:31:29 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:31:29 INFO - 1500949889780 Marionette DEBUG Closed connection 394
19:31:29 INFO - 1500949889781 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49810
19:31:29 INFO - 1500949889782 Marionette DEBUG Closed connection 395
19:31:29 INFO - 1500949889783 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49811
19:31:29 INFO - 1500949889784 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:29 INFO - 1500949889785 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889788 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889791 Marionette TRACE 396 <- [1,1,null,{"sessionId":"13424274-35f0-4308-b777-87e1dbe94414","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889792 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
19:31:29 INFO - 1500949889793 Marionette TRACE 396 <- [1,2,null,{}]
19:31:29 INFO - 1500949889794 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:31:29 INFO - 1500949889794 Marionette TRACE 396 <- [1,3,null,{}]
19:31:29 INFO - 1500949889795 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
19:31:29 INFO - 1500949889796 Marionette TRACE 396 <- [1,4,null,{}]
19:31:29 INFO - 1500949889797 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}]
19:31:29 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500949889801 Marionette TRACE 396 <- [1,5,null,{"value":null}]
19:31:29 INFO - 1500949889802 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
19:31:29 INFO - 1500949889803 Marionette TRACE 396 <- [1,6,null,{}]
19:31:29 INFO - 1500949889805 Marionette DEBUG Closed connection 396
19:31:29 INFO - 1500949889805 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49812
19:31:29 INFO - 1500949889806 Marionette DEBUG Closed connection 397
19:31:29 INFO - 1500949889807 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49813
19:31:29 INFO - 1500949889808 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:31:29 INFO - 1500949889808 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889811 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889814 Marionette TRACE 398 <- [1,1,null,{"sessionId":"e8fedba8-a6a0-4aa6-a8e8-2765cb84f684","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889815 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
19:31:29 INFO - 1500949889817 Marionette TRACE 398 <- [1,2,null,{}]
19:31:29 INFO - 1500949889818 Marionette DEBUG Closed connection 398
19:31:29 INFO - 1500949889819 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49814
19:31:29 INFO - 1500949889820 Marionette DEBUG Closed connection 399
19:31:29 INFO - 1500949889820 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49815
19:31:29 INFO - 1500949889821 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:31:29 INFO - 1500949889822 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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}
19:31:29 INFO - 1500949889825 Marionette DEBUG Register listener.js for window 4294967297
19:31:29 INFO - 1500949889828 Marionette TRACE 400 <- [1,1,null,{"sessionId":"217ba964-73b8-4037-8f4e-6a862be9a458","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":4040,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-081.001\\AppData\\Local\\Temp\\tmpskk6c0.mozrunner","moz:accessibilityChecks":false}}]
19:31:29 INFO - 1500949889829 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"}]
19:31:29 INFO - 1500949889831 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"
19:31:29 INFO - 1500949889833 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"
19:31:29 INFO - 1500949889834 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"
19:31:29 INFO - 1500949889841 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"
19:31:29 INFO - 1500949889843 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"
19:31:29 INFO - 1500949889848 Marionette TRACE 400 <- [1,2,null,{}]
19:31:29 INFO - 1500949889856 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:31:29 INFO - 1500949889859 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9c8e6998-7daf-43f6-a20f-9b907a995acc","ELEMENT":"9c8e6998-7daf-43f6-a20f-9b907a995acc"}}]
19:31:29 INFO - 1500949889860 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9c8e6998-7daf-43f6-a20f-9b907a995acc"}]
19:31:29 INFO - 1500949889885 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
19:31:29 INFO - 1500949889886 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9c8e6998-7daf-43f6-a20f-9b907a995acc"}]
19:31:29 INFO - 1500949889903 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element