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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:30:23 INFO - 1499430623906 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:30:23 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499430623908 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:30:23 INFO - 1499430623909 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:30:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 121ms
05:30:23 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:30:23 INFO - 1499430623910 Marionette TRACE 352 <- [1,6,null,{}]
05:30:23 INFO - 1499430623912 Marionette DEBUG Closed connection 352
05:30:23 INFO - 1499430623913 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49744
05:30:23 INFO - 1499430623913 Marionette DEBUG Closed connection 353
05:30:23 INFO - 1499430623914 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49745
05:30:23 INFO - 1499430623915 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:23 INFO - 1499430623915 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:23 INFO - 1499430623917 Marionette DEBUG Register listener.js for window 12
05:30:23 INFO - 1499430623919 Marionette TRACE 354 <- [1,1,null,{"sessionId":"642171e3-16fb-444c-ac4e-5a853c9ce46a","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:23 INFO - 1499430623921 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:30:23 INFO - 1499430623922 Marionette TRACE 354 <- [1,2,null,{}]
05:30:23 INFO - 1499430623922 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:23 INFO - 1499430623923 Marionette TRACE 354 <- [1,3,null,{}]
05:30:23 INFO - 1499430623924 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:23 INFO - 1499430623924 Marionette TRACE 354 <- [1,4,null,{}]
05:30:23 INFO - 1499430623926 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:30:23 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499430623928 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:30:23 INFO - 1499430623928 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:30:23 INFO - 1499430623930 Marionette TRACE 354 <- [1,6,null,{}]
05:30:23 INFO - 1499430623931 Marionette DEBUG Closed connection 354
05:30:23 INFO - 1499430623932 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49746
05:30:23 INFO - 1499430623932 Marionette DEBUG Closed connection 355
05:30:23 INFO - 1499430623933 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49747
05:30:23 INFO - 1499430623934 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:23 INFO - 1499430623934 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:23 INFO - 1499430623936 Marionette DEBUG Register listener.js for window 12
05:30:23 INFO - 1499430623939 Marionette TRACE 356 <- [1,1,null,{"sessionId":"bbed0299-878b-4c8d-ad7d-bf412e93e3c7","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:23 INFO - 1499430623941 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:30:23 INFO - 1499430623942 Marionette TRACE 356 <- [1,2,null,{}]
05:30:23 INFO - 1499430623944 Marionette DEBUG Closed connection 356
05:30:23 INFO - 1499430623944 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49748
05:30:23 INFO - 1499430623945 Marionette DEBUG Closed connection 357
05:30:23 INFO - 1499430623945 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49749
05:30:23 INFO - 1499430623946 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:23 INFO - 1499430623947 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:23 INFO - 1499430623949 Marionette DEBUG Register listener.js for window 12
05:30:23 INFO - 1499430623951 Marionette TRACE 358 <- [1,1,null,{"sessionId":"5f4099b3-b7b2-4114-8741-f7edcd91ea21","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:23 INFO - 1499430623953 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49321/clicks.html"}]
05:30:23 INFO - 1499430623954 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49321/hidden.html"
05:30:23 INFO - 1499430623960 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49321/hidden.html"
05:30:23 INFO - 1499430623960 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49321/hidden.html"
05:30:23 INFO - 1499430623970 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49321/clicks.html"
05:30:23 INFO - 1499430623979 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49321/clicks.html"
05:30:23 INFO - 1499430623982 Marionette TRACE 358 <- [1,2,null,{}]
05:30:23 INFO - 1499430623983 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:30:23 INFO - 1499430623985 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"527a0af4-ef9f-46de-8bac-50981b2e633b","ELEMENT":"527a0af4-ef9f-46de-8bac-50981b2e633b"}}]
05:30:23 INFO - 1499430623986 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"527a0af4-ef9f-46de-8bac-50981b2e633b"}]
05:30:24 INFO - 1499430624029 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49321/clicks.html"
05:30:24 INFO - 1499430624033 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49321/clicks.html"
05:30:24 INFO - 1499430624034 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49321/clicks.html"
05:30:24 INFO - 1499430624050 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49321/test.html"
05:30:24 INFO - 1499430624053 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49321/test.html"
05:30:24 INFO - 1499430624055 Marionette TRACE 358 <- [1,4,null,{}]
05:30:24 INFO - 1499430624062 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:30:24 INFO - 1499430624063 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83bdc69f-e629-457c-9542-9b9cab6044cf","ELEMENT":"83bdc69f-e629-457c-9542-9b9cab6044cf"}}]
05:30:24 INFO - 1499430624065 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:30:24 INFO - 1499430624065 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:30:24 INFO - 1499430624066 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:30:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499430624069 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:30:24 INFO - 1499430624069 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:30:24 INFO - 1499430624070 Marionette TRACE 358 <- [1,8,null,{}]
05:30:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 161ms
05:30:24 INFO - TEST-START | test_click.py TestClick.test_container_element
05:30:24 INFO - 1499430624074 Marionette DEBUG Closed connection 358
05:30:24 INFO - 1499430624075 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49752
05:30:24 INFO - 1499430624078 Marionette DEBUG Closed connection 359
05:30:24 INFO - 1499430624078 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49753
05:30:24 INFO - 1499430624079 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:24 INFO - 1499430624079 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624081 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624084 Marionette TRACE 360 <- [1,1,null,{"sessionId":"8fb6a0e2-6754-4a51-873a-0bf6c4f8f1a5","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624086 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:30:24 INFO - 1499430624086 Marionette TRACE 360 <- [1,2,null,{}]
05:30:24 INFO - 1499430624088 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:24 INFO - 1499430624088 Marionette TRACE 360 <- [1,3,null,{}]
05:30:24 INFO - 1499430624089 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:24 INFO - 1499430624090 Marionette TRACE 360 <- [1,4,null,{}]
05:30:24 INFO - 1499430624092 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:30:24 INFO - TEST-START: test_click.py TestClick.test_container_element1499430624094 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:30:24 INFO - 1499430624094 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:30:24 INFO - 1499430624095 Marionette TRACE 360 <- [1,6,null,{}]
05:30:24 INFO - 1499430624097 Marionette DEBUG Closed connection 360
05:30:24 INFO - 1499430624098 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49754
05:30:24 INFO - 1499430624098 Marionette DEBUG Closed connection 361
05:30:24 INFO - 1499430624099 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49755
05:30:24 INFO - 1499430624100 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:24 INFO - 1499430624100 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624102 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624106 Marionette TRACE 362 <- [1,1,null,{"sessionId":"8a91287f-235f-418d-a54a-8f38f17d0849","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624108 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:30:24 INFO - 1499430624109 Marionette TRACE 362 <- [1,2,null,{}]
05:30:24 INFO - 1499430624112 Marionette DEBUG Closed connection 362
05:30:24 INFO - 1499430624112 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49756
05:30:24 INFO - 1499430624118 Marionette DEBUG Closed connection 363
05:30:24 INFO - 1499430624118 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49757
05:30:24 INFO - 1499430624119 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:24 INFO - 1499430624120 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624122 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624126 Marionette TRACE 364 <- [1,1,null,{"sessionId":"872c88e4-f93f-4814-83d3-d3603f914c26","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624128 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:30:24 INFO - 1499430624129 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49321/test.html"
05:30:24 INFO - 1499430624130 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49321/test.html"
05:30:24 INFO - 1499430624131 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49321/test.html"
05:30:24 INFO - 1499430624151 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:30:24 INFO - 1499430624155 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:30:24 INFO - 1499430624163 Marionette TRACE 364 <- [1,2,null,{}]
05:30:24 INFO - 1499430624168 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:30:24 INFO - 1499430624171 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ded1ab33-1a84-4e49-89ec-bee3dbedbc46","ELEMENT":"ded1ab33-1a84-4e49-89ec-bee3dbedbc46"}}]
05:30:24 INFO - 1499430624172 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"ded1ab33-1a84-4e49-89ec-bee3dbedbc46"}]
05:30:24 INFO - 1499430624414 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:24 INFO - 1499430624415 Marionette TRACE 364 <- [1,4,null,{}]
05:30:24 INFO - 1499430624416 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"ded1ab33-1a84-4e49-89ec-bee3dbedbc46","name":"selected"}]
05:30:24 INFO - 1499430624420 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:30:24 INFO - 1499430624421 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:30:24 INFO - TEST-END: test_click.py TestClick.test_container_element1499430624423 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:30:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 353ms
05:30:24 INFO - 1499430624424 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:30:24 INFO - 1499430624425 Marionette TRACE 364 <- [1,7,null,{}]
05:30:24 INFO - 1499430624427 Marionette DEBUG Closed connection 364
05:30:24 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:30:24 INFO - 1499430624429 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49758
05:30:24 INFO - 1499430624429 Marionette DEBUG Closed connection 365
05:30:24 INFO - 1499430624430 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49759
05:30:24 INFO - 1499430624431 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:24 INFO - 1499430624431 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624433 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624436 Marionette TRACE 366 <- [1,1,null,{"sessionId":"30e4b127-e96a-4767-9627-7b473c8c3507","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624438 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:30:24 INFO - 1499430624438 Marionette TRACE 366 <- [1,2,null,{}]
05:30:24 INFO - 1499430624439 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:24 INFO - 1499430624439 Marionette TRACE 366 <- [1,3,null,{}]
05:30:24 INFO - 1499430624440 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:24 INFO - 1499430624440 Marionette TRACE 366 <- [1,4,null,{}]
05:30:24 INFO - 1499430624442 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:30:24 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499430624444 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:30:24 INFO - 1499430624445 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:30:24 INFO - 1499430624445 Marionette TRACE 366 <- [1,6,null,{}]
05:30:24 INFO - 1499430624447 Marionette DEBUG Closed connection 366
05:30:24 INFO - 1499430624447 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49760
05:30:24 INFO - 1499430624448 Marionette DEBUG Closed connection 367
05:30:24 INFO - 1499430624449 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49761
05:30:24 INFO - 1499430624449 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:24 INFO - 1499430624450 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624452 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624454 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f6f08e4c-a68a-4653-a8c3-bd14ccfc0732","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624456 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:30:24 INFO - 1499430624457 Marionette TRACE 368 <- [1,2,null,{}]
05:30:24 INFO - 1499430624458 Marionette DEBUG Closed connection 368
05:30:24 INFO - 1499430624459 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49762
05:30:24 INFO - 1499430624459 Marionette DEBUG Closed connection 369
05:30:24 INFO - 1499430624460 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49763
05:30:24 INFO - 1499430624461 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:24 INFO - 1499430624461 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624464 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624466 Marionette TRACE 370 <- [1,1,null,{"sessionId":"227e69fa-22a7-4fab-851b-3df9943ef1bd","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624468 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:30:24 INFO - 1499430624469 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:30:24 INFO - 1499430624471 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:30:24 INFO - 1499430624471 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:30:24 INFO - 1499430624480 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:30:24 INFO - 1499430624483 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:30:24 INFO - 1499430624485 Marionette TRACE 370 <- [1,2,null,{}]
05:30:24 INFO - 1499430624494 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:30:24 INFO - 1499430624497 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"880d7013-5bcc-47a2-9913-ed9e49a8d9ec","ELEMENT":"880d7013-5bcc-47a2-9913-ed9e49a8d9ec"}}]
05:30:24 INFO - 1499430624498 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"880d7013-5bcc-47a2-9913-ed9e49a8d9ec"}]
05:30:24 INFO - 1499430624742 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:24 INFO - 1499430624743 Marionette TRACE 370 <- [1,4,null,{}]
05:30:24 INFO - 1499430624744 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"880d7013-5bcc-47a2-9913-ed9e49a8d9ec","name":"selected"}]
05:30:24 INFO - 1499430624748 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:30:24 INFO - 1499430624749 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:30:24 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499430624751 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:30:24 INFO - 1499430624752 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:30:24 INFO - 1499430624753 Marionette TRACE 370 <- [1,7,null,{}]
05:30:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 328ms
05:30:24 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:30:24 INFO - 1499430624756 Marionette DEBUG Closed connection 370
05:30:24 INFO - 1499430624757 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49764
05:30:24 INFO - 1499430624758 Marionette DEBUG Closed connection 371
05:30:24 INFO - 1499430624758 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49765
05:30:24 INFO - 1499430624759 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:24 INFO - 1499430624760 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624762 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624764 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2f6b985c-6ce9-4ed4-bcb3-b975335ddbce","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624766 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:30:24 INFO - 1499430624766 Marionette TRACE 372 <- [1,2,null,{}]
05:30:24 INFO - 1499430624767 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:24 INFO - 1499430624767 Marionette TRACE 372 <- [1,3,null,{}]
05:30:24 INFO - 1499430624768 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:24 INFO - 1499430624769 Marionette TRACE 372 <- [1,4,null,{}]
05:30:24 INFO - 1499430624770 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:30:24 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499430624772 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:30:24 INFO - 1499430624773 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:30:24 INFO - 1499430624774 Marionette TRACE 372 <- [1,6,null,{}]
05:30:24 INFO - 1499430624776 Marionette DEBUG Closed connection 372
05:30:24 INFO - 1499430624777 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49766
05:30:24 INFO - 1499430624777 Marionette DEBUG Closed connection 373
05:30:24 INFO - 1499430624778 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49767
05:30:24 INFO - 1499430624779 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:24 INFO - 1499430624779 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624781 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624784 Marionette TRACE 374 <- [1,1,null,{"sessionId":"b522eb95-0edd-4876-aa72-3892e5df303f","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624786 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:30:24 INFO - 1499430624787 Marionette TRACE 374 <- [1,2,null,{}]
05:30:24 INFO - 1499430624789 Marionette DEBUG Closed connection 374
05:30:24 INFO - 1499430624789 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49768
05:30:24 INFO - 1499430624790 Marionette DEBUG Closed connection 375
05:30:24 INFO - 1499430624791 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49769
05:30:24 INFO - 1499430624791 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:24 INFO - 1499430624792 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:24 INFO - 1499430624794 Marionette DEBUG Register listener.js for window 12
05:30:24 INFO - 1499430624796 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a28fd3d1-b553-4646-9254-3dbfe6216468","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:24 INFO - 1499430624798 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:30:24 INFO - 1499430624800 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:30:24 INFO - 1499430624801 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:30:24 INFO - 1499430624802 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:30:24 INFO - 1499430624818 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:30:24 INFO - 1499430624821 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:30:24 INFO - 1499430624828 Marionette TRACE 376 <- [1,2,null,{}]
05:30:24 INFO - 1499430624830 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:30:24 INFO - 1499430624831 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b0e14b1d-5c66-459a-9e68-576e4863de00","ELEMENT":"b0e14b1d-5c66-459a-9e68-576e4863de00"}}]
05:30:24 INFO - 1499430624832 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b0e14b1d-5c66-459a-9e68-576e4863de00"}]
05:30:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 319ms
05:30:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:30:25 INFO - 1499430625066 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:25 INFO - 1499430625067 Marionette TRACE 376 <- [1,4,null,{}]
05:30:25 INFO - 1499430625069 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:30:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499430625071 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:30:25 INFO - 1499430625072 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:30:25 INFO - 1499430625073 Marionette TRACE 376 <- [1,6,null,{}]
05:30:25 INFO - 1499430625075 Marionette DEBUG Closed connection 376
05:30:25 INFO - 1499430625076 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49770
05:30:25 INFO - 1499430625077 Marionette DEBUG Closed connection 377
05:30:25 INFO - 1499430625077 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49771
05:30:25 INFO - 1499430625078 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:25 INFO - 1499430625079 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625081 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625083 Marionette TRACE 378 <- [1,1,null,{"sessionId":"26f63d29-11ad-46e7-a5c6-1104cee6995b","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625086 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:30:25 INFO - 1499430625086 Marionette TRACE 378 <- [1,2,null,{}]
05:30:25 INFO - 1499430625087 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:25 INFO - 1499430625088 Marionette TRACE 378 <- [1,3,null,{}]
05:30:25 INFO - 1499430625089 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:25 INFO - 1499430625090 Marionette TRACE 378 <- [1,4,null,{}]
05:30:25 INFO - 1499430625091 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:30:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499430625093 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:30:25 INFO - 1499430625094 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:30:25 INFO - 1499430625095 Marionette TRACE 378 <- [1,6,null,{}]
05:30:25 INFO - 1499430625096 Marionette DEBUG Closed connection 378
05:30:25 INFO - 1499430625097 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49772
05:30:25 INFO - 1499430625098 Marionette DEBUG Closed connection 379
05:30:25 INFO - 1499430625098 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49773
05:30:25 INFO - 1499430625099 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:25 INFO - 1499430625100 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625102 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625104 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9ccd6d20-beb9-4c34-9950-36db10ef1e51","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625106 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:30:25 INFO - 1499430625107 Marionette TRACE 380 <- [1,2,null,{}]
05:30:25 INFO - 1499430625108 Marionette DEBUG Closed connection 380
05:30:25 INFO - 1499430625109 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49774
05:30:25 INFO - 1499430625109 Marionette DEBUG Closed connection 381
05:30:25 INFO - 1499430625110 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49775
05:30:25 INFO - 1499430625111 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:25 INFO - 1499430625112 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625113 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625116 Marionette TRACE 382 <- [1,1,null,{"sessionId":"156f45e3-d9cd-4860-b3ff-3dda1e089d27","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625117 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:30:25 INFO - 1499430625118 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:30:25 INFO - 1499430625120 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:30:25 INFO - 1499430625120 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:30:25 INFO - 1499430625129 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:30:25 INFO - 1499430625133 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:30:25 INFO - 1499430625135 Marionette TRACE 382 <- [1,2,null,{}]
05:30:25 INFO - 1499430625136 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:30:25 INFO - 1499430625146 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c10bec17-8c35-4c42-aceb-5e908fa6041d","ELEMENT":"c10bec17-8c35-4c42-aceb-5e908fa6041d"}}]
05:30:25 INFO - 1499430625147 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c10bec17-8c35-4c42-aceb-5e908fa6041d"}]
05:30:25 INFO - 1499430625390 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:25 INFO - 1499430625391 Marionette TRACE 382 <- [1,4,null,{}]
05:30:25 INFO - 1499430625393 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:30:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499430625395 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:30:25 INFO - 1499430625395 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:30:25 INFO - 1499430625396 Marionette TRACE 382 <- [1,6,null,{}]
05:30:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 323ms
05:30:25 INFO - TEST-START | test_click.py TestClick.test_input_file
05:30:25 INFO - 1499430625398 Marionette DEBUG Closed connection 382
05:30:25 INFO - 1499430625399 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49776
05:30:25 INFO - 1499430625400 Marionette DEBUG Closed connection 383
05:30:25 INFO - 1499430625400 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49777
05:30:25 INFO - 1499430625401 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:25 INFO - 1499430625402 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625404 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625406 Marionette TRACE 384 <- [1,1,null,{"sessionId":"c9ae1bcf-5934-455d-b653-d8c875e1ece1","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625408 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:30:25 INFO - 1499430625408 Marionette TRACE 384 <- [1,2,null,{}]
05:30:25 INFO - 1499430625409 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:25 INFO - 1499430625409 Marionette TRACE 384 <- [1,3,null,{}]
05:30:25 INFO - 1499430625410 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:25 INFO - 1499430625411 Marionette TRACE 384 <- [1,4,null,{}]
05:30:25 INFO - 1499430625412 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:30:25 INFO - TEST-START: test_click.py TestClick.test_input_file1499430625414 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:30:25 INFO - 1499430625415 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:30:25 INFO - 1499430625416 Marionette TRACE 384 <- [1,6,null,{}]
05:30:25 INFO - 1499430625417 Marionette DEBUG Closed connection 384
05:30:25 INFO - 1499430625418 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49778
05:30:25 INFO - 1499430625418 Marionette DEBUG Closed connection 385
05:30:25 INFO - 1499430625419 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49779
05:30:25 INFO - 1499430625420 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:25 INFO - 1499430625420 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625422 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625425 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1590cff6-f831-4d69-8218-5441588c0170","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625426 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:30:25 INFO - 1499430625427 Marionette TRACE 386 <- [1,2,null,{}]
05:30:25 INFO - 1499430625429 Marionette DEBUG Closed connection 386
05:30:25 INFO - 1499430625429 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49780
05:30:25 INFO - 1499430625430 Marionette DEBUG Closed connection 387
05:30:25 INFO - 1499430625431 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49781
05:30:25 INFO - 1499430625432 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:25 INFO - 1499430625432 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625434 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625436 Marionette TRACE 388 <- [1,1,null,{"sessionId":"c6067a96-1fb7-4f52-96ea-90f8ad0b4ff6","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625438 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:30:25 INFO - 1499430625439 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:30:25 INFO - 1499430625441 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:30:25 INFO - 1499430625441 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:30:25 INFO - 1499430625451 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:25 INFO - 1499430625454 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:25 INFO - 1499430625462 Marionette TRACE 388 <- [1,2,null,{}]
05:30:25 INFO - 1499430625464 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:30:25 INFO - 1499430625466 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"29092d6b-a338-47a2-b5ff-0773e15d6878","ELEMENT":"29092d6b-a338-47a2-b5ff-0773e15d6878"}}]
05:30:25 INFO - 1499430625467 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"29092d6b-a338-47a2-b5ff-0773e15d6878"}]
05:30:25 INFO - 1499430625493 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:30:25 INFO - 1499430625495 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:30:25 INFO - TEST-END: test_click.py TestClick.test_input_file1499430625497 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:30:25 INFO - 1499430625498 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:30:25 INFO - 1499430625499 Marionette TRACE 388 <- [1,6,null,{}]
05:30:25 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 102ms
05:30:25 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:30:25 INFO - 1499430625501 Marionette DEBUG Closed connection 388
05:30:25 INFO - 1499430625502 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49782
05:30:25 INFO - 1499430625503 Marionette DEBUG Closed connection 389
05:30:25 INFO - 1499430625503 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49783
05:30:25 INFO - 1499430625504 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:25 INFO - 1499430625505 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625507 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625515 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fb056af8-e64c-4573-904a-66c9e1b103ce","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625517 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:30:25 INFO - 1499430625517 Marionette TRACE 390 <- [1,2,null,{}]
05:30:25 INFO - 1499430625518 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:25 INFO - 1499430625519 Marionette TRACE 390 <- [1,3,null,{}]
05:30:25 INFO - 1499430625520 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:25 INFO - 1499430625520 Marionette TRACE 390 <- [1,4,null,{}]
05:30:25 INFO - 1499430625521 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:30:25 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499430625524 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:30:25 INFO - 1499430625525 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:30:25 INFO - 1499430625526 Marionette TRACE 390 <- [1,6,null,{}]
05:30:25 INFO - 1499430625527 Marionette DEBUG Closed connection 390
05:30:25 INFO - 1499430625528 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49784
05:30:25 INFO - 1499430625529 Marionette DEBUG Closed connection 391
05:30:25 INFO - 1499430625529 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49785
05:30:25 INFO - 1499430625530 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:25 INFO - 1499430625531 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625533 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625535 Marionette TRACE 392 <- [1,1,null,{"sessionId":"4ade9fb2-3632-4ee9-abf4-691e6f4ea856","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625537 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:30:25 INFO - 1499430625538 Marionette TRACE 392 <- [1,2,null,{}]
05:30:25 INFO - 1499430625539 Marionette DEBUG Closed connection 392
05:30:25 INFO - 1499430625540 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49786
05:30:25 INFO - 1499430625540 Marionette DEBUG Closed connection 393
05:30:25 INFO - 1499430625541 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49787
05:30:25 INFO - 1499430625542 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:25 INFO - 1499430625543 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625545 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625547 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c600cbd6-8e09-40bb-a94d-777b4274774e","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625549 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:30:25 INFO - 1499430625550 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:25 INFO - 1499430625552 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:25 INFO - 1499430625552 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:25 INFO - 1499430625568 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:30:25 INFO - 1499430625571 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:30:25 INFO - 1499430625579 Marionette TRACE 394 <- [1,2,null,{}]
05:30:25 INFO - 1499430625580 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:30:25 INFO - 1499430625582 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"318172ca-0cee-4f42-8682-9f22eee883ea","ELEMENT":"318172ca-0cee-4f42-8682-9f22eee883ea"}}]
05:30:25 INFO - 1499430625583 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:30:25 INFO - 1499430625584 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa7fd51e-3bd6-43c0-83c0-9330fd992c4b","ELEMENT":"aa7fd51e-3bd6-43c0-83c0-9330fd992c4b"}}]
05:30:25 INFO - 1499430625585 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"318172ca-0cee-4f42-8682-9f22eee883ea"}]
05:30:25 INFO - 1499430625818 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:25 INFO - 1499430625819 Marionette TRACE 394 <- [1,5,null,{}]
05:30:25 INFO - 1499430625820 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"aa7fd51e-3bd6-43c0-83c0-9330fd992c4b"}]
05:30:25 INFO - 1499430625833 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:30:25 INFO - 1499430625835 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:30:25 INFO - 1499430625837 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:30:25 INFO - 1499430625838 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:30:25 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499430625841 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:30:25 INFO - 1499430625841 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:30:25 INFO - 1499430625843 Marionette TRACE 394 <- [1,9,null,{}]
05:30:25 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 343ms
05:30:25 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:30:25 INFO - 1499430625844 Marionette DEBUG Closed connection 394
05:30:25 INFO - 1499430625845 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49788
05:30:25 INFO - 1499430625846 Marionette DEBUG Closed connection 395
05:30:25 INFO - 1499430625846 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49789
05:30:25 INFO - 1499430625847 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:25 INFO - 1499430625848 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625850 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625852 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a747333d-c40a-4e40-b436-2c8f2233e42e","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625854 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:30:25 INFO - 1499430625854 Marionette TRACE 396 <- [1,2,null,{}]
05:30:25 INFO - 1499430625855 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:25 INFO - 1499430625855 Marionette TRACE 396 <- [1,3,null,{}]
05:30:25 INFO - 1499430625856 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:25 INFO - 1499430625857 Marionette TRACE 396 <- [1,4,null,{}]
05:30:25 INFO - 1499430625858 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:30:25 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499430625860 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:30:25 INFO - 1499430625861 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:30:25 INFO - 1499430625862 Marionette TRACE 396 <- [1,6,null,{}]
05:30:25 INFO - 1499430625864 Marionette DEBUG Closed connection 396
05:30:25 INFO - 1499430625864 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49790
05:30:25 INFO - 1499430625865 Marionette DEBUG Closed connection 397
05:30:25 INFO - 1499430625866 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49791
05:30:25 INFO - 1499430625867 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:25 INFO - 1499430625867 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625869 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625872 Marionette TRACE 398 <- [1,1,null,{"sessionId":"8226c24f-d8d5-4c74-921f-4747582162aa","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625873 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:30:25 INFO - 1499430625874 Marionette TRACE 398 <- [1,2,null,{}]
05:30:25 INFO - 1499430625876 Marionette DEBUG Closed connection 398
05:30:25 INFO - 1499430625877 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49792
05:30:25 INFO - 1499430625877 Marionette DEBUG Closed connection 399
05:30:25 INFO - 1499430625878 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49793
05:30:25 INFO - 1499430625879 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:25 INFO - 1499430625880 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}
05:30:25 INFO - 1499430625882 Marionette DEBUG Register listener.js for window 12
05:30:25 INFO - 1499430625884 Marionette TRACE 400 <- [1,1,null,{"sessionId":"72b4f988-9a67-4a2f-b21a-e4639ad95eb1","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpnnvj9d.mozrunner","moz:accessibilityChecks":false}}]
05:30:25 INFO - 1499430625886 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:30:25 INFO - 1499430625887 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:30:25 INFO - 1499430625889 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:30:25 INFO - 1499430625889 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:30:25 INFO - 1499430625901 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:30:25 INFO - 1499430625905 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:30:25 INFO - 1499430625913 Marionette TRACE 400 <- [1,2,null,{}]
05:30:25 INFO - 1499430625919 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:30:25 INFO - 1499430625921 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a56a27f-7962-4536-8b1a-a9ff0ea283d9","ELEMENT":"9a56a27f-7962-4536-8b1a-a9ff0ea283d9"}}]
05:30:25 INFO - 1499430625922 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9a56a27f-7962-4536-8b1a-a9ff0ea283d9"}]
05:30:25 INFO - 1499430625952 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:30:25 INFO - 1499430625952 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9a56a27f-7962-4536-8b1a-a9ff0ea283d9"}]
05:30:25 INFO - 1499430625966 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element