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:25:44 INFO - 1499775944964 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:25:44 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499775944966 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:25:44 INFO - 1499775944966 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:25:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 146ms
05:25:44 INFO - 1499775944967 Marionette TRACE 352 <- [1,6,null,{}]
05:25:44 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:25:44 INFO - 1499775944969 Marionette DEBUG Closed connection 352
05:25:44 INFO - 1499775944970 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49819
05:25:44 INFO - 1499775944971 Marionette DEBUG Closed connection 353
05:25:44 INFO - 1499775944972 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49820
05:25:44 INFO - 1499775944973 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:44 INFO - 1499775944973 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:44 INFO - 1499775944975 Marionette DEBUG Register listener.js for window 12
05:25:44 INFO - 1499775944978 Marionette TRACE 354 <- [1,1,null,{"sessionId":"2e5c75fd-26df-4325-a991-3ad7e2ec509c","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:44 INFO - 1499775944979 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:25:44 INFO - 1499775944980 Marionette TRACE 354 <- [1,2,null,{}]
05:25:44 INFO - 1499775944981 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:25:44 INFO - 1499775944981 Marionette TRACE 354 <- [1,3,null,{}]
05:25:44 INFO - 1499775944982 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:25:44 INFO - 1499775944982 Marionette TRACE 354 <- [1,4,null,{}]
05:25:44 INFO - 1499775944984 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:25:44 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499775944986 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:25:44 INFO - 1499775944987 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:25:44 INFO - 1499775944988 Marionette TRACE 354 <- [1,6,null,{}]
05:25:44 INFO - 1499775944990 Marionette DEBUG Closed connection 354
05:25:44 INFO - 1499775944990 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49821
05:25:44 INFO - 1499775944991 Marionette DEBUG Closed connection 355
05:25:44 INFO - 1499775944992 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49822
05:25:44 INFO - 1499775944993 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:44 INFO - 1499775944993 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:44 INFO - 1499775944995 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775944999 Marionette TRACE 356 <- [1,1,null,{"sessionId":"1d57dc07-f018-4c16-864f-362714095b6d","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945007 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:25:45 INFO - 1499775945009 Marionette TRACE 356 <- [1,2,null,{}]
05:25:45 INFO - 1499775945010 Marionette DEBUG Closed connection 356
05:25:45 INFO - 1499775945011 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49823
05:25:45 INFO - 1499775945011 Marionette DEBUG Closed connection 357
05:25:45 INFO - 1499775945012 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49824
05:25:45 INFO - 1499775945013 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:25:45 INFO - 1499775945013 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945016 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945018 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c1e1b858-898c-4eb2-a99a-665becdc5234","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945021 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49392/clicks.html"}]
05:25:45 INFO - 1499775945022 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49392/hidden.html"
05:25:45 INFO - 1499775945027 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49392/hidden.html"
05:25:45 INFO - 1499775945027 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49392/hidden.html"
05:25:45 INFO - 1499775945045 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49392/clicks.html"
05:25:45 INFO - 1499775945049 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49392/clicks.html"
05:25:45 INFO - 1499775945053 Marionette TRACE 358 <- [1,2,null,{}]
05:25:45 INFO - 1499775945061 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:25:45 INFO - 1499775945063 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f6ce584-61b3-4a2e-8242-d619998d7140","ELEMENT":"3f6ce584-61b3-4a2e-8242-d619998d7140"}}]
05:25:45 INFO - 1499775945063 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"3f6ce584-61b3-4a2e-8242-d619998d7140"}]
05:25:45 INFO - 1499775945120 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49392/clicks.html"
05:25:45 INFO - 1499775945128 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49392/clicks.html"
05:25:45 INFO - 1499775945129 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49392/clicks.html"
05:25:45 INFO - 1499775945146 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49392/test.html"
05:25:45 INFO - 1499775945147 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49392/test.html"
05:25:45 INFO - 1499775945149 Marionette TRACE 358 <- [1,4,null,{}]
05:25:45 INFO - 1499775945150 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:25:45 INFO - 1499775945158 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0670df52-7545-4a57-8f4e-4227f346ef5a","ELEMENT":"0670df52-7545-4a57-8f4e-4227f346ef5a"}}]
05:25:45 INFO - 1499775945163 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:25:45 INFO - 1499775945164 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:25:45 INFO - 1499775945165 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:25:45 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499775945167 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:25:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 206ms
05:25:45 INFO - TEST-START | test_click.py TestClick.test_container_element
05:25:45 INFO - 1499775945173 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:25:45 INFO - 1499775945174 Marionette TRACE 358 <- [1,8,null,{}]
05:25:45 INFO - 1499775945176 Marionette DEBUG Closed connection 358
05:25:45 INFO - 1499775945177 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49827
05:25:45 INFO - 1499775945178 Marionette DEBUG Closed connection 359
05:25:45 INFO - 1499775945178 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49828
05:25:45 INFO - 1499775945179 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:45 INFO - 1499775945180 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945182 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945184 Marionette TRACE 360 <- [1,1,null,{"sessionId":"32873b27-8e69-4894-bbee-1f5a299354f4","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945186 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:25:45 INFO - 1499775945186 Marionette TRACE 360 <- [1,2,null,{}]
05:25:45 INFO - 1499775945187 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:25:45 INFO - 1499775945187 Marionette TRACE 360 <- [1,3,null,{}]
05:25:45 INFO - 1499775945188 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:25:45 INFO - 1499775945189 Marionette TRACE 360 <- [1,4,null,{}]
05:25:45 INFO - 1499775945190 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:25:45 INFO - TEST-START: test_click.py TestClick.test_container_element1499775945192 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:25:45 INFO - 1499775945193 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:25:45 INFO - 1499775945194 Marionette TRACE 360 <- [1,6,null,{}]
05:25:45 INFO - 1499775945196 Marionette DEBUG Closed connection 360
05:25:45 INFO - 1499775945196 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49829
05:25:45 INFO - 1499775945197 Marionette DEBUG Closed connection 361
05:25:45 INFO - 1499775945198 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49830
05:25:45 INFO - 1499775945198 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:45 INFO - 1499775945199 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945201 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945203 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3bdefcc0-69cc-498a-88b3-fd23d9131103","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945205 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:25:45 INFO - 1499775945206 Marionette TRACE 362 <- [1,2,null,{}]
05:25:45 INFO - 1499775945208 Marionette DEBUG Closed connection 362
05:25:45 INFO - 1499775945208 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49831
05:25:45 INFO - 1499775945209 Marionette DEBUG Closed connection 363
05:25:45 INFO - 1499775945209 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49832
05:25:45 INFO - 1499775945210 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:25:45 INFO - 1499775945211 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945213 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945215 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1850f3f6-574b-4401-a781-f150e21a9816","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945217 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:25:45 INFO - 1499775945219 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49392/test.html"
05:25:45 INFO - 1499775945220 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49392/test.html"
05:25:45 INFO - 1499775945221 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49392/test.html"
05:25:45 INFO - 1499775945234 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:25:45 INFO - 1499775945243 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:25:45 INFO - 1499775945246 Marionette TRACE 364 <- [1,2,null,{}]
05:25:45 INFO - 1499775945247 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:25:45 INFO - 1499775945249 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"340ba81f-84be-45e7-8949-277f3c7b8f2a","ELEMENT":"340ba81f-84be-45e7-8949-277f3c7b8f2a"}}]
05:25:45 INFO - 1499775945250 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"340ba81f-84be-45e7-8949-277f3c7b8f2a"}]
05:25:45 INFO - 1499775945500 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:25:45 INFO - 1499775945500 Marionette TRACE 364 <- [1,4,null,{}]
05:25:45 INFO - 1499775945502 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"340ba81f-84be-45e7-8949-277f3c7b8f2a","name":"selected"}]
05:25:45 INFO - 1499775945505 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:25:45 INFO - 1499775945507 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:25:45 INFO - TEST-END: test_click.py TestClick.test_container_element1499775945509 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:25:45 INFO - 1499775945510 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:25:45 INFO - 1499775945511 Marionette TRACE 364 <- [1,7,null,{}]
05:25:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 337ms
05:25:45 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:25:45 INFO - 1499775945513 Marionette DEBUG Closed connection 364
05:25:45 INFO - 1499775945514 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49833
05:25:45 INFO - 1499775945514 Marionette DEBUG Closed connection 365
05:25:45 INFO - 1499775945515 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49834
05:25:45 INFO - 1499775945516 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:45 INFO - 1499775945516 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945518 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945521 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b4756bf9-b170-4f34-9e25-0cdf02dc859d","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945522 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:25:45 INFO - 1499775945523 Marionette TRACE 366 <- [1,2,null,{}]
05:25:45 INFO - 1499775945524 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:25:45 INFO - 1499775945524 Marionette TRACE 366 <- [1,3,null,{}]
05:25:45 INFO - 1499775945525 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:25:45 INFO - 1499775945525 Marionette TRACE 366 <- [1,4,null,{}]
05:25:45 INFO - 1499775945527 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:25:45 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499775945529 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:25:45 INFO - 1499775945530 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:25:45 INFO - 1499775945531 Marionette TRACE 366 <- [1,6,null,{}]
05:25:45 INFO - 1499775945532 Marionette DEBUG Closed connection 366
05:25:45 INFO - 1499775945533 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49835
05:25:45 INFO - 1499775945534 Marionette DEBUG Closed connection 367
05:25:45 INFO - 1499775945535 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49836
05:25:45 INFO - 1499775945536 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:45 INFO - 1499775945536 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945538 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945541 Marionette TRACE 368 <- [1,1,null,{"sessionId":"85a528ea-8133-40a9-b144-025234dbf78f","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945542 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:25:45 INFO - 1499775945543 Marionette TRACE 368 <- [1,2,null,{}]
05:25:45 INFO - 1499775945545 Marionette DEBUG Closed connection 368
05:25:45 INFO - 1499775945545 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49837
05:25:45 INFO - 1499775945546 Marionette DEBUG Closed connection 369
05:25:45 INFO - 1499775945547 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49838
05:25:45 INFO - 1499775945548 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:25:45 INFO - 1499775945548 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945550 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945552 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3bcf7a93-fbc2-425a-885d-3d280927af22","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945554 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:25:45 INFO - 1499775945555 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:25:45 INFO - 1499775945557 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:25:45 INFO - 1499775945557 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:25:45 INFO - 1499775945566 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:25:45 INFO - 1499775945574 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:25:45 INFO - 1499775945577 Marionette TRACE 370 <- [1,2,null,{}]
05:25:45 INFO - 1499775945579 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:25:45 INFO - 1499775945580 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f2bcfe8-2619-4779-897c-e6002f53b65f","ELEMENT":"3f2bcfe8-2619-4779-897c-e6002f53b65f"}}]
05:25:45 INFO - 1499775945581 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"3f2bcfe8-2619-4779-897c-e6002f53b65f"}]
05:25:45 INFO - 1499775945824 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:25:45 INFO - 1499775945825 Marionette TRACE 370 <- [1,4,null,{}]
05:25:45 INFO - 1499775945827 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"3f2bcfe8-2619-4779-897c-e6002f53b65f","name":"selected"}]
05:25:45 INFO - 1499775945832 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:25:45 INFO - 1499775945834 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:25:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 341ms
05:25:45 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:25:45 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499775945848 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:25:45 INFO - 1499775945850 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:25:45 INFO - 1499775945852 Marionette TRACE 370 <- [1,7,null,{}]
05:25:45 INFO - 1499775945854 Marionette DEBUG Closed connection 370
05:25:45 INFO - 1499775945855 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49839
05:25:45 INFO - 1499775945856 Marionette DEBUG Closed connection 371
05:25:45 INFO - 1499775945857 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49840
05:25:45 INFO - 1499775945858 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:45 INFO - 1499775945860 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945863 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945868 Marionette TRACE 372 <- [1,1,null,{"sessionId":"aebd2f22-db17-4123-aaba-089d8c19f010","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945908 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:25:45 INFO - 1499775945909 Marionette TRACE 372 <- [1,2,null,{}]
05:25:45 INFO - 1499775945911 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:25:45 INFO - 1499775945911 Marionette TRACE 372 <- [1,3,null,{}]
05:25:45 INFO - 1499775945912 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:25:45 INFO - 1499775945913 Marionette TRACE 372 <- [1,4,null,{}]
05:25:45 INFO - 1499775945914 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:25:45 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499775945917 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:25:45 INFO - 1499775945919 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:25:45 INFO - 1499775945920 Marionette TRACE 372 <- [1,6,null,{}]
05:25:45 INFO - 1499775945923 Marionette DEBUG Closed connection 372
05:25:45 INFO - 1499775945924 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49841
05:25:45 INFO - 1499775945930 Marionette DEBUG Closed connection 373
05:25:45 INFO - 1499775945931 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49842
05:25:45 INFO - 1499775945932 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:45 INFO - 1499775945933 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945936 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945940 Marionette TRACE 374 <- [1,1,null,{"sessionId":"8abca251-9fa2-447a-bdc1-3ddca17ee9a0","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945943 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:25:45 INFO - 1499775945945 Marionette TRACE 374 <- [1,2,null,{}]
05:25:45 INFO - 1499775945947 Marionette DEBUG Closed connection 374
05:25:45 INFO - 1499775945948 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49843
05:25:45 INFO - 1499775945949 Marionette DEBUG Closed connection 375
05:25:45 INFO - 1499775945950 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49844
05:25:45 INFO - 1499775945952 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:25:45 INFO - 1499775945953 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:45 INFO - 1499775945955 Marionette DEBUG Register listener.js for window 12
05:25:45 INFO - 1499775945959 Marionette TRACE 376 <- [1,1,null,{"sessionId":"037ef816-da43-478d-8913-8b43fe696ac3","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:45 INFO - 1499775945961 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:25:45 INFO - 1499775945963 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:25:45 INFO - 1499775945966 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:25:45 INFO - 1499775945966 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:25:45 INFO - 1499775945984 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:25:45 INFO - 1499775945987 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:25:46 INFO - 1499775945996 Marionette TRACE 376 <- [1,2,null,{}]
05:25:46 INFO - 1499775945998 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:25:46 INFO - 1499775946000 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c3facf63-b626-4af1-9fee-af66626c6d5c","ELEMENT":"c3facf63-b626-4af1-9fee-af66626c6d5c"}}]
05:25:46 INFO - 1499775946002 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"c3facf63-b626-4af1-9fee-af66626c6d5c"}]
05:25:46 INFO - 1499775946242 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:25:46 INFO - 1499775946243 Marionette TRACE 376 <- [1,4,null,{}]
05:25:46 INFO - 1499775946248 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:25:46 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499775946252 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:25:46 INFO - 1499775946254 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:25:46 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 404ms
05:25:46 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:25:46 INFO - 1499775946256 Marionette TRACE 376 <- [1,6,null,{}]
05:25:46 INFO - 1499775946258 Marionette DEBUG Closed connection 376
05:25:46 INFO - 1499775946259 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49845
05:25:46 INFO - 1499775946260 Marionette DEBUG Closed connection 377
05:25:46 INFO - 1499775946261 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49846
05:25:46 INFO - 1499775946262 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:46 INFO - 1499775946265 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946267 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946272 Marionette TRACE 378 <- [1,1,null,{"sessionId":"3acc0186-623d-488e-9bf8-58e6a5507aa4","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946282 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:25:46 INFO - 1499775946283 Marionette TRACE 378 <- [1,2,null,{}]
05:25:46 INFO - 1499775946286 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:25:46 INFO - 1499775946287 Marionette TRACE 378 <- [1,3,null,{}]
05:25:46 INFO - 1499775946288 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:25:46 INFO - 1499775946288 Marionette TRACE 378 <- [1,4,null,{}]
05:25:46 INFO - 1499775946290 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:25:46 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499775946293 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:25:46 INFO - 1499775946295 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:25:46 INFO - 1499775946296 Marionette TRACE 378 <- [1,6,null,{}]
05:25:46 INFO - 1499775946299 Marionette DEBUG Closed connection 378
05:25:46 INFO - 1499775946300 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49847
05:25:46 INFO - 1499775946300 Marionette DEBUG Closed connection 379
05:25:46 INFO - 1499775946301 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49848
05:25:46 INFO - 1499775946302 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:46 INFO - 1499775946303 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946305 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946308 Marionette TRACE 380 <- [1,1,null,{"sessionId":"f0389371-cd8f-4415-9dc2-de981d29fc0e","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946311 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:25:46 INFO - 1499775946312 Marionette TRACE 380 <- [1,2,null,{}]
05:25:46 INFO - 1499775946314 Marionette DEBUG Closed connection 380
05:25:46 INFO - 1499775946315 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49849
05:25:46 INFO - 1499775946316 Marionette DEBUG Closed connection 381
05:25:46 INFO - 1499775946316 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49850
05:25:46 INFO - 1499775946318 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:25:46 INFO - 1499775946320 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946322 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946327 Marionette TRACE 382 <- [1,1,null,{"sessionId":"269e5010-d74b-4fa0-887a-628f55a13f4f","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946329 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:25:46 INFO - 1499775946330 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:25:46 INFO - 1499775946333 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:25:46 INFO - 1499775946333 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:25:46 INFO - 1499775946350 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:25:46 INFO - 1499775946357 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:25:46 INFO - 1499775946361 Marionette TRACE 382 <- [1,2,null,{}]
05:25:46 INFO - 1499775946362 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:25:46 INFO - 1499775946364 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ba94d054-0b13-473e-bffe-61e64d297595","ELEMENT":"ba94d054-0b13-473e-bffe-61e64d297595"}}]
05:25:46 INFO - 1499775946365 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ba94d054-0b13-473e-bffe-61e64d297595"}]
05:25:46 INFO - 1499775946632 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:25:46 INFO - 1499775946633 Marionette TRACE 382 <- [1,4,null,{}]
05:25:46 INFO - 1499775946635 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:25:46 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499775946637 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:25:46 INFO - 1499775946638 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:25:46 INFO - 1499775946639 Marionette TRACE 382 <- [1,6,null,{}]
05:25:46 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 383ms
05:25:46 INFO - TEST-START | test_click.py TestClick.test_input_file
05:25:46 INFO - 1499775946641 Marionette DEBUG Closed connection 382
05:25:46 INFO - 1499775946642 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49851
05:25:46 INFO - 1499775946643 Marionette DEBUG Closed connection 383
05:25:46 INFO - 1499775946643 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49852
05:25:46 INFO - 1499775946645 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:46 INFO - 1499775946645 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946648 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946651 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5c54a4d3-8c65-43df-829f-257c15dc17e9","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946654 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:25:46 INFO - 1499775946654 Marionette TRACE 384 <- [1,2,null,{}]
05:25:46 INFO - 1499775946655 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:25:46 INFO - 1499775946656 Marionette TRACE 384 <- [1,3,null,{}]
05:25:46 INFO - 1499775946657 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:25:46 INFO - 1499775946657 Marionette TRACE 384 <- [1,4,null,{}]
05:25:46 INFO - 1499775946659 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:25:46 INFO - TEST-START: test_click.py TestClick.test_input_file1499775946661 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:25:46 INFO - 1499775946662 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:25:46 INFO - 1499775946664 Marionette TRACE 384 <- [1,6,null,{}]
05:25:46 INFO - 1499775946666 Marionette DEBUG Closed connection 384
05:25:46 INFO - 1499775946666 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49853
05:25:46 INFO - 1499775946667 Marionette DEBUG Closed connection 385
05:25:46 INFO - 1499775946668 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49854
05:25:46 INFO - 1499775946669 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:46 INFO - 1499775946670 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946672 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946676 Marionette TRACE 386 <- [1,1,null,{"sessionId":"927c5e3f-f978-47d5-b3af-d25f50767c14","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946678 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:25:46 INFO - 1499775946679 Marionette TRACE 386 <- [1,2,null,{}]
05:25:46 INFO - 1499775946682 Marionette DEBUG Closed connection 386
05:25:46 INFO - 1499775946683 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49855
05:25:46 INFO - 1499775946686 Marionette DEBUG Closed connection 387
05:25:46 INFO - 1499775946686 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49856
05:25:46 INFO - 1499775946687 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:25:46 INFO - 1499775946688 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946690 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946693 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e2b8f736-9b64-4dca-8f51-b5a5964ec450","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946695 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:25:46 INFO - 1499775946696 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:25:46 INFO - 1499775946699 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:25:46 INFO - 1499775946699 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:25:46 INFO - 1499775946723 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:25:46 INFO - 1499775946725 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:25:46 INFO - 1499775946729 Marionette TRACE 388 <- [1,2,null,{}]
05:25:46 INFO - 1499775946730 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:25:46 INFO - 1499775946732 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21fc44b8-0866-4b92-904d-21037c3d7a6c","ELEMENT":"21fc44b8-0866-4b92-904d-21037c3d7a6c"}}]
05:25:46 INFO - 1499775946733 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"21fc44b8-0866-4b92-904d-21037c3d7a6c"}]
05:25:46 INFO - 1499775946773 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:25:46 INFO - 1499775946776 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:25:46 INFO - TEST-END: test_click.py TestClick.test_input_file1499775946779 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:25:46 INFO - 1499775946780 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:25:46 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 143ms
05:25:46 INFO - 1499775946782 Marionette TRACE 388 <- [1,6,null,{}]
05:25:46 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:25:46 INFO - 1499775946784 Marionette DEBUG Closed connection 388
05:25:46 INFO - 1499775946785 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49857
05:25:46 INFO - 1499775946786 Marionette DEBUG Closed connection 389
05:25:46 INFO - 1499775946786 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49858
05:25:46 INFO - 1499775946787 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:46 INFO - 1499775946788 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946790 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946793 Marionette TRACE 390 <- [1,1,null,{"sessionId":"acabecad-bb33-47aa-a68f-82c6180673ed","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946795 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:25:46 INFO - 1499775946795 Marionette TRACE 390 <- [1,2,null,{}]
05:25:46 INFO - 1499775946796 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:25:46 INFO - 1499775946797 Marionette TRACE 390 <- [1,3,null,{}]
05:25:46 INFO - 1499775946798 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:25:46 INFO - 1499775946798 Marionette TRACE 390 <- [1,4,null,{}]
05:25:46 INFO - 1499775946800 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:25:46 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499775946802 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:25:46 INFO - 1499775946803 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:25:46 INFO - 1499775946804 Marionette TRACE 390 <- [1,6,null,{}]
05:25:46 INFO - 1499775946806 Marionette DEBUG Closed connection 390
05:25:46 INFO - 1499775946807 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49859
05:25:46 INFO - 1499775946807 Marionette DEBUG Closed connection 391
05:25:46 INFO - 1499775946808 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49860
05:25:46 INFO - 1499775946809 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:46 INFO - 1499775946810 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946812 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946815 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ec958b67-866d-4258-973d-ccc371aa4372","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946817 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:25:46 INFO - 1499775946818 Marionette TRACE 392 <- [1,2,null,{}]
05:25:46 INFO - 1499775946870 Marionette DEBUG Closed connection 392
05:25:46 INFO - 1499775946870 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49861
05:25:46 INFO - 1499775946926 Marionette DEBUG Closed connection 393
05:25:46 INFO - 1499775946927 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49862
05:25:46 INFO - 1499775946929 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:25:46 INFO - 1499775946930 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:46 INFO - 1499775946932 Marionette DEBUG Register listener.js for window 12
05:25:46 INFO - 1499775946935 Marionette TRACE 394 <- [1,1,null,{"sessionId":"aa68f514-68ad-412c-89e0-6b28a7979e55","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:46 INFO - 1499775946942 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:25:46 INFO - 1499775946943 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:25:46 INFO - 1499775946945 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:25:46 INFO - 1499775946946 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:25:46 INFO - 1499775946964 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:25:46 INFO - 1499775946968 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:25:46 INFO - 1499775946982 Marionette TRACE 394 <- [1,2,null,{}]
05:25:46 INFO - 1499775946984 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:25:46 INFO - 1499775946987 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"35b862e1-3d9d-4d6c-b89a-d34427952197","ELEMENT":"35b862e1-3d9d-4d6c-b89a-d34427952197"}}]
05:25:46 INFO - 1499775946988 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:25:46 INFO - 1499775946990 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f4956b61-cbc5-4d4c-8283-646a0086b6a5","ELEMENT":"f4956b61-cbc5-4d4c-8283-646a0086b6a5"}}]
05:25:46 INFO - 1499775946991 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"35b862e1-3d9d-4d6c-b89a-d34427952197"}]
05:25:47 INFO - 1499775947225 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:25:47 INFO - 1499775947226 Marionette TRACE 394 <- [1,5,null,{}]
05:25:47 INFO - 1499775947227 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"f4956b61-cbc5-4d4c-8283-646a0086b6a5"}]
05:25:47 INFO - 1499775947241 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:25:47 INFO - 1499775947243 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:25:47 INFO - 1499775947245 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:25:47 INFO - 1499775947246 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:25:47 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499775947248 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:25:47 INFO - 1499775947249 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:25:47 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 467ms
05:25:47 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:25:47 INFO - 1499775947250 Marionette TRACE 394 <- [1,9,null,{}]
05:25:47 INFO - 1499775947252 Marionette DEBUG Closed connection 394
05:25:47 INFO - 1499775947253 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49863
05:25:47 INFO - 1499775947254 Marionette DEBUG Closed connection 395
05:25:47 INFO - 1499775947255 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49864
05:25:47 INFO - 1499775947256 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:47 INFO - 1499775947256 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:47 INFO - 1499775947258 Marionette DEBUG Register listener.js for window 12
05:25:47 INFO - 1499775947261 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8cc84a7e-dece-40dc-82d9-4e2f4ea84fc3","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:47 INFO - 1499775947263 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:25:47 INFO - 1499775947263 Marionette TRACE 396 <- [1,2,null,{}]
05:25:47 INFO - 1499775947264 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:25:47 INFO - 1499775947264 Marionette TRACE 396 <- [1,3,null,{}]
05:25:47 INFO - 1499775947265 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:25:47 INFO - 1499775947265 Marionette TRACE 396 <- [1,4,null,{}]
05:25:47 INFO - 1499775947267 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:25:47 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499775947269 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:25:47 INFO - 1499775947270 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:25:47 INFO - 1499775947271 Marionette TRACE 396 <- [1,6,null,{}]
05:25:47 INFO - 1499775947273 Marionette DEBUG Closed connection 396
05:25:47 INFO - 1499775947273 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49865
05:25:47 INFO - 1499775947274 Marionette DEBUG Closed connection 397
05:25:47 INFO - 1499775947274 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49866
05:25:47 INFO - 1499775947275 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:25:47 INFO - 1499775947276 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:47 INFO - 1499775947279 Marionette DEBUG Register listener.js for window 12
05:25:47 INFO - 1499775947289 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b58e5917-e01f-4c03-ad97-a326053d1d50","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:47 INFO - 1499775947291 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:25:47 INFO - 1499775947292 Marionette TRACE 398 <- [1,2,null,{}]
05:25:47 INFO - 1499775947294 Marionette DEBUG Closed connection 398
05:25:47 INFO - 1499775947294 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49867
05:25:47 INFO - 1499775947295 Marionette DEBUG Closed connection 399
05:25:47 INFO - 1499775947296 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49868
05:25:47 INFO - 1499775947297 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:25:47 INFO - 1499775947297 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}
05:25:47 INFO - 1499775947299 Marionette DEBUG Register listener.js for window 12
05:25:47 INFO - 1499775947302 Marionette TRACE 400 <- [1,1,null,{"sessionId":"54e1f938-363a-49fa-ba73-6d218f91e5a5","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-185.000\\AppData\\Local\\Temp\\tmpdzvpg6.mozrunner","moz:accessibilityChecks":false}}]
05:25:47 INFO - 1499775947304 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:25:47 INFO - 1499775947306 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:25:47 INFO - 1499775947309 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:25:47 INFO - 1499775947309 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:25:47 INFO - 1499775947329 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:25:47 INFO - 1499775947331 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:25:47 INFO - 1499775947334 Marionette TRACE 400 <- [1,2,null,{}]
05:25:47 INFO - 1499775947341 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:25:47 INFO - 1499775947343 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f9bd3c1e-15f6-4edf-ae80-44010e3e2e58","ELEMENT":"f9bd3c1e-15f6-4edf-ae80-44010e3e2e58"}}]
05:25:47 INFO - 1499775947344 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f9bd3c1e-15f6-4edf-ae80-44010e3e2e58"}]
05:25:47 INFO - 1499775947366 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:25:47 INFO - 1499775947368 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f9bd3c1e-15f6-4edf-ae80-44010e3e2e58"}]
05:25:47 INFO - 1499775947382 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element