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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
12:19:29 INFO - 1499282368995 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}]
12:19:29 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499282368999 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:19:29 INFO - 1499282369000 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:19:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 115ms
12:19:29 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:19:29 INFO - 1499282369001 Marionette TRACE 352 <- [1,6,null,{}]
12:19:29 INFO - 1499282369003 Marionette DEBUG Closed connection 352
12:19:29 INFO - 1499282369004 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49852
12:19:29 INFO - 1499282369004 Marionette DEBUG Closed connection 353
12:19:29 INFO - 1499282369005 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49853
12:19:29 INFO - 1499282369006 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:29 INFO - 1499282369007 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369009 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369011 Marionette TRACE 354 <- [1,1,null,{"sessionId":"a1b31e99-5518-42be-a203-5ef0a7cd9c81","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369012 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:19:29 INFO - 1499282369013 Marionette TRACE 354 <- [1,2,null,{}]
12:19:29 INFO - 1499282369014 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:29 INFO - 1499282369014 Marionette TRACE 354 <- [1,3,null,{}]
12:19:29 INFO - 1499282369015 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:29 INFO - 1499282369015 Marionette TRACE 354 <- [1,4,null,{}]
12:19:29 INFO - 1499282369017 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}]
12:19:29 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499282369019 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:19:29 INFO - 1499282369020 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:19:29 INFO - 1499282369021 Marionette TRACE 354 <- [1,6,null,{}]
12:19:29 INFO - 1499282369023 Marionette DEBUG Closed connection 354
12:19:29 INFO - 1499282369023 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49854
12:19:29 INFO - 1499282369024 Marionette DEBUG Closed connection 355
12:19:29 INFO - 1499282369025 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49855
12:19:29 INFO - 1499282369026 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:29 INFO - 1499282369026 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369028 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369031 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4b18acc1-4b61-47a3-a2d7-748a1ea7fe1f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369032 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:19:29 INFO - 1499282369033 Marionette TRACE 356 <- [1,2,null,{}]
12:19:29 INFO - 1499282369035 Marionette DEBUG Closed connection 356
12:19:29 INFO - 1499282369035 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49856
12:19:29 INFO - 1499282369036 Marionette DEBUG Closed connection 357
12:19:29 INFO - 1499282369037 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49857
12:19:29 INFO - 1499282369038 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:29 INFO - 1499282369038 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369040 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369043 Marionette TRACE 358 <- [1,1,null,{"sessionId":"7906bf2d-e835-4ab5-a40b-57196bbd725e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369044 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49412/clicks.html"}]
12:19:29 INFO - 1499282369045 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49412/hidden.html"
12:19:29 INFO - 1499282369055 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49412/hidden.html"
12:19:29 INFO - 1499282369055 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49412/hidden.html"
12:19:29 INFO - 1499282369061 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49412/clicks.html"
12:19:29 INFO - 1499282369067 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49412/clicks.html"
12:19:29 INFO - 1499282369069 Marionette TRACE 358 <- [1,2,null,{}]
12:19:29 INFO - 1499282369073 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:19:29 INFO - 1499282369076 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6bf0bcea-9123-4a23-ac97-2e6548bd1f16","ELEMENT":"6bf0bcea-9123-4a23-ac97-2e6548bd1f16"}}]
12:19:29 INFO - 1499282369077 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"6bf0bcea-9123-4a23-ac97-2e6548bd1f16"}]
12:19:29 INFO - 1499282369121 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49412/clicks.html"
12:19:29 INFO - 1499282369128 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49412/clicks.html"
12:19:29 INFO - 1499282369128 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49412/clicks.html"
12:19:29 INFO - 1499282369136 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49412/test.html"
12:19:29 INFO - 1499282369138 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49412/test.html"
12:19:29 INFO - 1499282369150 Marionette TRACE 358 <- [1,4,null,{}]
12:19:29 INFO - 1499282369158 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:19:29 INFO - 1499282369160 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b3fcfada-c76a-4843-b0d7-294a4cd4937c","ELEMENT":"b3fcfada-c76a-4843-b0d7-294a4cd4937c"}}]
12:19:29 INFO - 1499282369161 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:19:29 INFO - 1499282369162 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:19:29 INFO - 1499282369163 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}]
12:19:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 169ms
12:19:29 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499282369169 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:19:29 INFO - 1499282369170 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:19:29 INFO - TEST-START | test_click.py TestClick.test_container_element
12:19:29 INFO - 1499282369171 Marionette TRACE 358 <- [1,8,null,{}]
12:19:29 INFO - 1499282369173 Marionette DEBUG Closed connection 358
12:19:29 INFO - 1499282369174 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49860
12:19:29 INFO - 1499282369175 Marionette DEBUG Closed connection 359
12:19:29 INFO - 1499282369176 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49861
12:19:29 INFO - 1499282369177 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:29 INFO - 1499282369177 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369180 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369183 Marionette TRACE 360 <- [1,1,null,{"sessionId":"557c0eca-9d49-44df-b7e5-4a7377acd663","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369184 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:19:29 INFO - 1499282369184 Marionette TRACE 360 <- [1,2,null,{}]
12:19:29 INFO - 1499282369185 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:29 INFO - 1499282369185 Marionette TRACE 360 <- [1,3,null,{}]
12:19:29 INFO - 1499282369186 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:29 INFO - 1499282369187 Marionette TRACE 360 <- [1,4,null,{}]
12:19:29 INFO - 1499282369188 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}]
12:19:29 INFO - TEST-START: test_click.py TestClick.test_container_element1499282369191 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:19:29 INFO - 1499282369192 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:19:29 INFO - 1499282369193 Marionette TRACE 360 <- [1,6,null,{}]
12:19:29 INFO - 1499282369194 Marionette DEBUG Closed connection 360
12:19:29 INFO - 1499282369195 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49862
12:19:29 INFO - 1499282369196 Marionette DEBUG Closed connection 361
12:19:29 INFO - 1499282369197 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49863
12:19:29 INFO - 1499282369197 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:29 INFO - 1499282369198 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369200 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369202 Marionette TRACE 362 <- [1,1,null,{"sessionId":"2d95d239-2763-487d-8832-366f20c86299","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369203 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:19:29 INFO - 1499282369204 Marionette TRACE 362 <- [1,2,null,{}]
12:19:29 INFO - 1499282369206 Marionette DEBUG Closed connection 362
12:19:29 INFO - 1499282369206 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49864
12:19:29 INFO - 1499282369207 Marionette DEBUG Closed connection 363
12:19:29 INFO - 1499282369208 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49865
12:19:29 INFO - 1499282369209 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:29 INFO - 1499282369210 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369211 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369214 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8e427c2d-be4f-4d7f-8197-76f29f610595","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369215 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"}]
12:19:29 INFO - 1499282369217 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49412/test.html"
12:19:29 INFO - 1499282369218 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49412/test.html"
12:19:29 INFO - 1499282369218 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49412/test.html"
12:19:29 INFO - 1499282369224 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"
12:19:29 INFO - 1499282369227 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"
12:19:29 INFO - 1499282369238 Marionette TRACE 364 <- [1,2,null,{}]
12:19:29 INFO - 1499282369242 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:19:29 INFO - 1499282369246 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6633f5e9-6079-470f-bff7-f581c41d2457","ELEMENT":"6633f5e9-6079-470f-bff7-f581c41d2457"}}]
12:19:29 INFO - 1499282369247 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6633f5e9-6079-470f-bff7-f581c41d2457"}]
12:19:29 INFO - 1499282369486 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:29 INFO - 1499282369487 Marionette TRACE 364 <- [1,4,null,{}]
12:19:29 INFO - 1499282369488 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6633f5e9-6079-470f-bff7-f581c41d2457","name":"selected"}]
12:19:29 INFO - 1499282369492 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:19:29 INFO - 1499282369494 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}]
12:19:29 INFO - TEST-END: test_click.py TestClick.test_container_element1499282369496 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:19:29 INFO - 1499282369497 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:19:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 326ms
12:19:29 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:19:29 INFO - 1499282369498 Marionette TRACE 364 <- [1,7,null,{}]
12:19:29 INFO - 1499282369500 Marionette DEBUG Closed connection 364
12:19:29 INFO - 1499282369501 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49866
12:19:29 INFO - 1499282369501 Marionette DEBUG Closed connection 365
12:19:29 INFO - 1499282369502 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49867
12:19:29 INFO - 1499282369503 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:29 INFO - 1499282369504 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369506 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369509 Marionette TRACE 366 <- [1,1,null,{"sessionId":"0a749f65-d8b1-4d15-8838-16c486ee3da7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369510 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:19:29 INFO - 1499282369510 Marionette TRACE 366 <- [1,2,null,{}]
12:19:29 INFO - 1499282369511 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:29 INFO - 1499282369511 Marionette TRACE 366 <- [1,3,null,{}]
12:19:29 INFO - 1499282369512 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:29 INFO - 1499282369512 Marionette TRACE 366 <- [1,4,null,{}]
12:19:29 INFO - 1499282369514 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}]
12:19:29 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499282369517 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:19:29 INFO - 1499282369518 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:19:29 INFO - 1499282369519 Marionette TRACE 366 <- [1,6,null,{}]
12:19:29 INFO - 1499282369520 Marionette DEBUG Closed connection 366
12:19:29 INFO - 1499282369521 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49868
12:19:29 INFO - 1499282369521 Marionette DEBUG Closed connection 367
12:19:29 INFO - 1499282369522 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49869
12:19:29 INFO - 1499282369523 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:29 INFO - 1499282369523 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369525 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369528 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b35a3917-c96e-4836-8b2f-dc74b2c68ca8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369529 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:19:29 INFO - 1499282369530 Marionette TRACE 368 <- [1,2,null,{}]
12:19:29 INFO - 1499282369532 Marionette DEBUG Closed connection 368
12:19:29 INFO - 1499282369533 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49870
12:19:29 INFO - 1499282369533 Marionette DEBUG Closed connection 369
12:19:29 INFO - 1499282369534 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49871
12:19:29 INFO - 1499282369535 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:29 INFO - 1499282369536 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369538 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369540 Marionette TRACE 370 <- [1,1,null,{"sessionId":"131baff0-25b7-4493-9a96-8e1192db67ef","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369541 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"}]
12:19:29 INFO - 1499282369543 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"
12:19:29 INFO - 1499282369546 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"
12:19:29 INFO - 1499282369546 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"
12:19:29 INFO - 1499282369554 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"
12:19:29 INFO - 1499282369558 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"
12:19:29 INFO - 1499282369559 Marionette TRACE 370 <- [1,2,null,{}]
12:19:29 INFO - 1499282369561 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:19:29 INFO - 1499282369568 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8e10b26-99a0-4d22-9210-1a1471cc766a","ELEMENT":"e8e10b26-99a0-4d22-9210-1a1471cc766a"}}]
12:19:29 INFO - 1499282369570 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"e8e10b26-99a0-4d22-9210-1a1471cc766a"}]
12:19:29 INFO - 1499282369809 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:29 INFO - 1499282369810 Marionette TRACE 370 <- [1,4,null,{}]
12:19:29 INFO - 1499282369811 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"e8e10b26-99a0-4d22-9210-1a1471cc766a","name":"selected"}]
12:19:29 INFO - 1499282369815 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:19:29 INFO - 1499282369817 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}]
12:19:29 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499282369819 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:19:29 INFO - 1499282369820 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:19:29 INFO - 1499282369822 Marionette TRACE 370 <- [1,7,null,{}]
12:19:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 323ms
12:19:29 INFO - 1499282369823 Marionette DEBUG Closed connection 370
12:19:29 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:19:29 INFO - 1499282369825 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49872
12:19:29 INFO - 1499282369826 Marionette DEBUG Closed connection 371
12:19:29 INFO - 1499282369827 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49873
12:19:29 INFO - 1499282369827 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:29 INFO - 1499282369828 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369830 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369833 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3a0997dc-6eac-452f-8a17-b023a43726eb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369834 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:19:29 INFO - 1499282369835 Marionette TRACE 372 <- [1,2,null,{}]
12:19:29 INFO - 1499282369836 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:29 INFO - 1499282369836 Marionette TRACE 372 <- [1,3,null,{}]
12:19:29 INFO - 1499282369837 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:29 INFO - 1499282369837 Marionette TRACE 372 <- [1,4,null,{}]
12:19:29 INFO - 1499282369839 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}]
12:19:29 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499282369841 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:19:29 INFO - 1499282369842 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:19:29 INFO - 1499282369843 Marionette TRACE 372 <- [1,6,null,{}]
12:19:29 INFO - 1499282369844 Marionette DEBUG Closed connection 372
12:19:29 INFO - 1499282369845 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49874
12:19:29 INFO - 1499282369846 Marionette DEBUG Closed connection 373
12:19:29 INFO - 1499282369847 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49875
12:19:29 INFO - 1499282369848 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:29 INFO - 1499282369848 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369850 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369853 Marionette TRACE 374 <- [1,1,null,{"sessionId":"22b4cd24-94b6-4b88-aeab-324f4cc330ed","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369854 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:19:29 INFO - 1499282369855 Marionette TRACE 374 <- [1,2,null,{}]
12:19:29 INFO - 1499282369856 Marionette DEBUG Closed connection 374
12:19:29 INFO - 1499282369857 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49876
12:19:29 INFO - 1499282369858 Marionette DEBUG Closed connection 375
12:19:29 INFO - 1499282369858 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49877
12:19:29 INFO - 1499282369859 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:29 INFO - 1499282369860 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:29 INFO - 1499282369862 Marionette DEBUG Register listener.js for window 4294967297
12:19:29 INFO - 1499282369864 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8a2435dc-2eaf-4734-a79d-7302939fe447","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:29 INFO - 1499282369865 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"}]
12:19:29 INFO - 1499282369867 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"
12:19:29 INFO - 1499282369869 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"
12:19:29 INFO - 1499282369869 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"
12:19:29 INFO - 1499282369875 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"
12:19:29 INFO - 1499282369877 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"
12:19:29 INFO - 1499282369880 Marionette TRACE 376 <- [1,2,null,{}]
12:19:29 INFO - 1499282369890 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:19:29 INFO - 1499282369892 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2c47f4fa-21f1-4bc7-b2c3-6986f536cba8","ELEMENT":"2c47f4fa-21f1-4bc7-b2c3-6986f536cba8"}}]
12:19:29 INFO - 1499282369893 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"2c47f4fa-21f1-4bc7-b2c3-6986f536cba8"}]
12:19:30 INFO - 1499282370132 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:30 INFO - 1499282370133 Marionette TRACE 376 <- [1,4,null,{}]
12:19:30 INFO - 1499282370135 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}]
12:19:30 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499282370137 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:19:30 INFO - 1499282370138 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:19:30 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 316ms
12:19:30 INFO - 1499282370139 Marionette TRACE 376 <- [1,6,null,{}]
12:19:30 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:19:30 INFO - 1499282370141 Marionette DEBUG Closed connection 376
12:19:30 INFO - 1499282370142 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49878
12:19:30 INFO - 1499282370143 Marionette DEBUG Closed connection 377
12:19:30 INFO - 1499282370143 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49879
12:19:30 INFO - 1499282370144 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:30 INFO - 1499282370145 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370147 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370150 Marionette TRACE 378 <- [1,1,null,{"sessionId":"a54fd62f-c84b-43e4-8f22-515c703301dd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370151 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:19:30 INFO - 1499282370151 Marionette TRACE 378 <- [1,2,null,{}]
12:19:30 INFO - 1499282370152 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:30 INFO - 1499282370152 Marionette TRACE 378 <- [1,3,null,{}]
12:19:30 INFO - 1499282370153 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:30 INFO - 1499282370153 Marionette TRACE 378 <- [1,4,null,{}]
12:19:30 INFO - 1499282370155 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}]
12:19:30 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499282370157 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:19:30 INFO - 1499282370158 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:19:30 INFO - 1499282370159 Marionette TRACE 378 <- [1,6,null,{}]
12:19:30 INFO - 1499282370161 Marionette DEBUG Closed connection 378
12:19:30 INFO - 1499282370161 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49880
12:19:30 INFO - 1499282370162 Marionette DEBUG Closed connection 379
12:19:30 INFO - 1499282370163 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49881
12:19:30 INFO - 1499282370164 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:30 INFO - 1499282370164 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370166 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370169 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b0da5c8b-b660-4a97-97e7-0932c0e673cd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370170 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:19:30 INFO - 1499282370171 Marionette TRACE 380 <- [1,2,null,{}]
12:19:30 INFO - 1499282370172 Marionette DEBUG Closed connection 380
12:19:30 INFO - 1499282370173 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49882
12:19:30 INFO - 1499282370174 Marionette DEBUG Closed connection 381
12:19:30 INFO - 1499282370174 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49883
12:19:30 INFO - 1499282370175 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:30 INFO - 1499282370176 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370178 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370180 Marionette TRACE 382 <- [1,1,null,{"sessionId":"a91bb69c-e3d3-46af-8279-25d01ae1c930","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370181 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"}]
12:19:30 INFO - 1499282370183 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"
12:19:30 INFO - 1499282370185 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"
12:19:30 INFO - 1499282370185 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"
12:19:30 INFO - 1499282370190 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"
12:19:30 INFO - 1499282370193 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"
12:19:30 INFO - 1499282370195 Marionette TRACE 382 <- [1,2,null,{}]
12:19:30 INFO - 1499282370197 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:19:30 INFO - 1499282370203 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4fdad302-7f4d-4d66-b768-bf0984f52137","ELEMENT":"4fdad302-7f4d-4d66-b768-bf0984f52137"}}]
12:19:30 INFO - 1499282370205 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"4fdad302-7f4d-4d66-b768-bf0984f52137"}]
12:19:30 INFO - 1499282370440 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:30 INFO - 1499282370441 Marionette TRACE 382 <- [1,4,null,{}]
12:19:30 INFO - 1499282370443 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}]
12:19:30 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499282370445 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:19:30 INFO - 1499282370446 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:19:30 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 307ms
12:19:30 INFO - 1499282370447 Marionette TRACE 382 <- [1,6,null,{}]
12:19:30 INFO - TEST-START | test_click.py TestClick.test_input_file
12:19:30 INFO - 1499282370449 Marionette DEBUG Closed connection 382
12:19:30 INFO - 1499282370450 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49884
12:19:30 INFO - 1499282370451 Marionette DEBUG Closed connection 383
12:19:30 INFO - 1499282370452 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49885
12:19:30 INFO - 1499282370453 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:30 INFO - 1499282370453 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370455 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370458 Marionette TRACE 384 <- [1,1,null,{"sessionId":"3ab1b819-8547-4fe3-a2c3-e99ed80975dd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370459 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:19:30 INFO - 1499282370459 Marionette TRACE 384 <- [1,2,null,{}]
12:19:30 INFO - 1499282370460 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:30 INFO - 1499282370461 Marionette TRACE 384 <- [1,3,null,{}]
12:19:30 INFO - 1499282370461 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:30 INFO - 1499282370462 Marionette TRACE 384 <- [1,4,null,{}]
12:19:30 INFO - 1499282370464 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}]
12:19:30 INFO - TEST-START: test_click.py TestClick.test_input_file1499282370466 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:19:30 INFO - 1499282370467 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:19:30 INFO - 1499282370468 Marionette TRACE 384 <- [1,6,null,{}]
12:19:30 INFO - 1499282370469 Marionette DEBUG Closed connection 384
12:19:30 INFO - 1499282370470 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49886
12:19:30 INFO - 1499282370470 Marionette DEBUG Closed connection 385
12:19:30 INFO - 1499282370471 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49887
12:19:30 INFO - 1499282370472 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:30 INFO - 1499282370473 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370475 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370478 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f84d8d56-3598-46e1-bd0f-0cdc2cd70bd1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370478 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:19:30 INFO - 1499282370480 Marionette TRACE 386 <- [1,2,null,{}]
12:19:30 INFO - 1499282370481 Marionette DEBUG Closed connection 386
12:19:30 INFO - 1499282370482 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49888
12:19:30 INFO - 1499282370482 Marionette DEBUG Closed connection 387
12:19:30 INFO - 1499282370483 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49889
12:19:30 INFO - 1499282370484 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:30 INFO - 1499282370484 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370486 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370496 Marionette TRACE 388 <- [1,1,null,{"sessionId":"b7896218-b6f8-49ff-91e6-71c6a0cf47cc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370498 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:19:30 INFO - 1499282370500 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"
12:19:30 INFO - 1499282370501 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"
12:19:30 INFO - 1499282370502 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"
12:19:30 INFO - 1499282370508 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:30 INFO - 1499282370512 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:30 INFO - 1499282370513 Marionette TRACE 388 <- [1,2,null,{}]
12:19:30 INFO - 1499282370526 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:19:30 INFO - 1499282370529 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"35c2513f-ce30-4444-bab5-b2595a5a204b","ELEMENT":"35c2513f-ce30-4444-bab5-b2595a5a204b"}}]
12:19:30 INFO - 1499282370530 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"35c2513f-ce30-4444-bab5-b2595a5a204b"}]
12:19:30 INFO - 1499282370555 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
12:19:30 INFO - 1499282370557 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}]
12:19:30 INFO - TEST-END: test_click.py TestClick.test_input_file1499282370559 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:19:30 INFO - 1499282370560 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:19:30 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 113ms
12:19:30 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:19:30 INFO - 1499282370561 Marionette TRACE 388 <- [1,6,null,{}]
12:19:30 INFO - 1499282370563 Marionette DEBUG Closed connection 388
12:19:30 INFO - 1499282370564 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49890
12:19:30 INFO - 1499282370565 Marionette DEBUG Closed connection 389
12:19:30 INFO - 1499282370565 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49891
12:19:30 INFO - 1499282370566 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:30 INFO - 1499282370567 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370569 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370572 Marionette TRACE 390 <- [1,1,null,{"sessionId":"f7a5ea97-6f3d-46e2-8439-2ea022e61163","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370573 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:19:30 INFO - 1499282370573 Marionette TRACE 390 <- [1,2,null,{}]
12:19:30 INFO - 1499282370574 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:30 INFO - 1499282370574 Marionette TRACE 390 <- [1,3,null,{}]
12:19:30 INFO - 1499282370575 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:30 INFO - 1499282370576 Marionette TRACE 390 <- [1,4,null,{}]
12:19:30 INFO - 1499282370577 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}]
12:19:30 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499282370580 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:19:30 INFO - 1499282370580 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:19:30 INFO - 1499282370582 Marionette TRACE 390 <- [1,6,null,{}]
12:19:30 INFO - 1499282370583 Marionette DEBUG Closed connection 390
12:19:30 INFO - 1499282370584 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49892
12:19:30 INFO - 1499282370584 Marionette DEBUG Closed connection 391
12:19:30 INFO - 1499282370585 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49893
12:19:30 INFO - 1499282370586 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:30 INFO - 1499282370586 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370588 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370591 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f7d90570-d2f1-4f40-85ca-125f098bef6d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370592 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:19:30 INFO - 1499282370593 Marionette TRACE 392 <- [1,2,null,{}]
12:19:30 INFO - 1499282370594 Marionette DEBUG Closed connection 392
12:19:30 INFO - 1499282370595 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49894
12:19:30 INFO - 1499282370596 Marionette DEBUG Closed connection 393
12:19:30 INFO - 1499282370596 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49895
12:19:30 INFO - 1499282370597 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:30 INFO - 1499282370598 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370600 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370602 Marionette TRACE 394 <- [1,1,null,{"sessionId":"34fac363-e544-46df-8da4-f23a111d4e51","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370603 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"}]
12:19:30 INFO - 1499282370605 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:30 INFO - 1499282370606 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:30 INFO - 1499282370607 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:19:30 INFO - 1499282370613 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"
12:19:30 INFO - 1499282370615 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"
12:19:30 INFO - 1499282370620 Marionette TRACE 394 <- [1,2,null,{}]
12:19:30 INFO - 1499282370624 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:19:30 INFO - 1499282370626 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5e174886-846b-447a-b749-680529bd385b","ELEMENT":"5e174886-846b-447a-b749-680529bd385b"}}]
12:19:30 INFO - 1499282370627 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:19:30 INFO - 1499282370629 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"38bb798e-b268-4937-bae7-7b080542e251","ELEMENT":"38bb798e-b268-4937-bae7-7b080542e251"}}]
12:19:30 INFO - 1499282370630 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"5e174886-846b-447a-b749-680529bd385b"}]
12:19:30 INFO - 1499282370872 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:19:30 INFO - 1499282370873 Marionette TRACE 394 <- [1,5,null,{}]
12:19:30 INFO - 1499282370874 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"38bb798e-b268-4937-bae7-7b080542e251"}]
12:19:30 INFO - 1499282370887 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
12:19:30 INFO - 1499282370889 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:19:30 INFO - 1499282370891 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:19:30 INFO - 1499282370893 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}]
12:19:30 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499282370895 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:19:30 INFO - 1499282370896 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:19:30 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 335ms
12:19:30 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:19:30 INFO - 1499282370897 Marionette TRACE 394 <- [1,9,null,{}]
12:19:30 INFO - 1499282370899 Marionette DEBUG Closed connection 394
12:19:30 INFO - 1499282370900 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49896
12:19:30 INFO - 1499282370900 Marionette DEBUG Closed connection 395
12:19:30 INFO - 1499282370901 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49897
12:19:30 INFO - 1499282370902 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:30 INFO - 1499282370903 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370905 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370908 Marionette TRACE 396 <- [1,1,null,{"sessionId":"d909754e-6047-4137-b272-529057471efb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370909 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:19:30 INFO - 1499282370909 Marionette TRACE 396 <- [1,2,null,{}]
12:19:30 INFO - 1499282370910 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:19:30 INFO - 1499282370910 Marionette TRACE 396 <- [1,3,null,{}]
12:19:30 INFO - 1499282370911 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:19:30 INFO - 1499282370912 Marionette TRACE 396 <- [1,4,null,{}]
12:19:30 INFO - 1499282370913 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}]
12:19:30 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499282370916 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:19:30 INFO - 1499282370916 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:19:30 INFO - 1499282370917 Marionette TRACE 396 <- [1,6,null,{}]
12:19:30 INFO - 1499282370919 Marionette DEBUG Closed connection 396
12:19:30 INFO - 1499282370919 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49898
12:19:30 INFO - 1499282370920 Marionette DEBUG Closed connection 397
12:19:30 INFO - 1499282370921 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49899
12:19:30 INFO - 1499282370922 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:19:30 INFO - 1499282370923 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370924 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370927 Marionette TRACE 398 <- [1,1,null,{"sessionId":"595b5e36-0a07-4e7e-b3f2-7d18fd31cc9b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370928 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:19:30 INFO - 1499282370929 Marionette TRACE 398 <- [1,2,null,{}]
12:19:30 INFO - 1499282370930 Marionette DEBUG Closed connection 398
12:19:30 INFO - 1499282370931 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49900
12:19:30 INFO - 1499282370932 Marionette DEBUG Closed connection 399
12:19:30 INFO - 1499282370933 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49901
12:19:30 INFO - 1499282370933 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:19:30 INFO - 1499282370934 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}
12:19:30 INFO - 1499282370936 Marionette DEBUG Register listener.js for window 4294967297
12:19:30 INFO - 1499282370939 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8d1d76a2-dbd0-4cfa-8eb0-aaf6a270c9ec","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmiprq3.mozrunner","moz:accessibilityChecks":false}}]
12:19:30 INFO - 1499282370940 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"}]
12:19:30 INFO - 1499282370941 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"
12:19:30 INFO - 1499282370943 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"
12:19:30 INFO - 1499282370943 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"
12:19:30 INFO - 1499282370951 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"
12:19:30 INFO - 1499282370952 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"
12:19:30 INFO - 1499282370959 Marionette TRACE 400 <- [1,2,null,{}]
12:19:30 INFO - 1499282370961 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:19:30 INFO - 1499282370967 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43891e8b-6656-4bbe-a54d-08c717cb6797","ELEMENT":"43891e8b-6656-4bbe-a54d-08c717cb6797"}}]
12:19:30 INFO - 1499282370969 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"43891e8b-6656-4bbe-a54d-08c717cb6797"}]
12:19:30 INFO - 1499282370991 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:19:30 INFO - 1499282370992 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"43891e8b-6656-4bbe-a54d-08c717cb6797"}]
12:19:31 INFO - 1499282371005 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element