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]
18:29:29 INFO - 1499736569980 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}]
18:29:29 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499736569983 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:29:29 INFO - 1499736569983 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:29:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 144ms
18:29:29 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:29:29 INFO - 1499736569985 Marionette TRACE 352 <- [1,6,null,{}]
18:29:29 INFO - 1499736569987 Marionette DEBUG Closed connection 352
18:29:29 INFO - 1499736569987 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49722
18:29:29 INFO - 1499736569988 Marionette DEBUG Closed connection 353
18:29:29 INFO - 1499736569989 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49723
18:29:29 INFO - 1499736569990 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:29 INFO - 1499736569990 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:29 INFO - 1499736569993 Marionette DEBUG Register listener.js for window 12
18:29:29 INFO - 1499736569996 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f8ff56ed-55a9-4851-a1f8-9c5dd11e5a8d","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:29 INFO - 1499736569998 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:29:30 INFO - 1499736569999 Marionette TRACE 354 <- [1,2,null,{}]
18:29:30 INFO - 1499736570000 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:29:30 INFO - 1499736570001 Marionette TRACE 354 <- [1,3,null,{}]
18:29:30 INFO - 1499736570002 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:29:30 INFO - 1499736570002 Marionette TRACE 354 <- [1,4,null,{}]
18:29:30 INFO - 1499736570004 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}]
18:29:30 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499736570007 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:29:30 INFO - 1499736570008 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:29:30 INFO - 1499736570010 Marionette TRACE 354 <- [1,6,null,{}]
18:29:30 INFO - 1499736570013 Marionette DEBUG Closed connection 354
18:29:30 INFO - 1499736570013 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49724
18:29:30 INFO - 1499736570014 Marionette DEBUG Closed connection 355
18:29:30 INFO - 1499736570015 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49725
18:29:30 INFO - 1499736570016 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:30 INFO - 1499736570017 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570020 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570023 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d8937f3b-4393-4663-8cf0-f1ffefec8077","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570025 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:29:30 INFO - 1499736570027 Marionette TRACE 356 <- [1,2,null,{}]
18:29:30 INFO - 1499736570029 Marionette DEBUG Closed connection 356
18:29:30 INFO - 1499736570029 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49726
18:29:30 INFO - 1499736570030 Marionette DEBUG Closed connection 357
18:29:30 INFO - 1499736570031 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49727
18:29:30 INFO - 1499736570032 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:29:30 INFO - 1499736570033 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570035 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570038 Marionette TRACE 358 <- [1,1,null,{"sessionId":"2045d7e3-e957-4678-85b8-8dd62ccee3e2","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570040 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49292/clicks.html"}]
18:29:30 INFO - 1499736570042 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49292/hidden.html"
18:29:30 INFO - 1499736570047 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49292/hidden.html"
18:29:30 INFO - 1499736570048 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49292/hidden.html"
18:29:30 INFO - 1499736570065 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49292/clicks.html"
18:29:30 INFO - 1499736570074 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49292/clicks.html"
18:29:30 INFO - 1499736570078 Marionette TRACE 358 <- [1,2,null,{}]
18:29:30 INFO - 1499736570080 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:29:30 INFO - 1499736570082 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ebb4975-456e-4e23-8fc4-7b6bd70f549e","ELEMENT":"2ebb4975-456e-4e23-8fc4-7b6bd70f549e"}}]
18:29:30 INFO - 1499736570083 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"2ebb4975-456e-4e23-8fc4-7b6bd70f549e"}]
18:29:30 INFO - 1499736570141 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49292/clicks.html"
18:29:30 INFO - 1499736570146 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49292/clicks.html"
18:29:30 INFO - 1499736570147 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49292/clicks.html"
18:29:30 INFO - 1499736570166 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49292/test.html"
18:29:30 INFO - 1499736570174 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49292/test.html"
18:29:30 INFO - 1499736570177 Marionette TRACE 358 <- [1,4,null,{}]
18:29:30 INFO - 1499736570178 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:29:30 INFO - 1499736570180 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dc7bf138-a435-4590-a5f8-cb112cf86d5f","ELEMENT":"dc7bf138-a435-4590-a5f8-cb112cf86d5f"}}]
18:29:30 INFO - 1499736570181 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:29:30 INFO - 1499736570182 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:29:30 INFO - 1499736570183 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}]
18:29:30 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499736570193 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:29:30 INFO - 1499736570194 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:29:30 INFO - 1499736570195 Marionette TRACE 358 <- [1,8,null,{}]
18:29:30 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 210ms
18:29:30 INFO - TEST-START | test_click.py TestClick.test_container_element
18:29:30 INFO - 1499736570197 Marionette DEBUG Closed connection 358
18:29:30 INFO - 1499736570198 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49730
18:29:30 INFO - 1499736570199 Marionette DEBUG Closed connection 359
18:29:30 INFO - 1499736570199 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49731
18:29:30 INFO - 1499736570201 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:30 INFO - 1499736570201 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570204 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570209 Marionette TRACE 360 <- [1,1,null,{"sessionId":"68596185-24e1-4c0d-8ddc-e44324944ce5","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570211 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:29:30 INFO - 1499736570212 Marionette TRACE 360 <- [1,2,null,{}]
18:29:30 INFO - 1499736570213 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:29:30 INFO - 1499736570213 Marionette TRACE 360 <- [1,3,null,{}]
18:29:30 INFO - 1499736570214 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:29:30 INFO - 1499736570215 Marionette TRACE 360 <- [1,4,null,{}]
18:29:30 INFO - 1499736570217 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}]
18:29:30 INFO - TEST-START: test_click.py TestClick.test_container_element1499736570221 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:29:30 INFO - 1499736570222 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:29:30 INFO - 1499736570223 Marionette TRACE 360 <- [1,6,null,{}]
18:29:30 INFO - 1499736570226 Marionette DEBUG Closed connection 360
18:29:30 INFO - 1499736570226 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49732
18:29:30 INFO - 1499736570227 Marionette DEBUG Closed connection 361
18:29:30 INFO - 1499736570227 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49733
18:29:30 INFO - 1499736570228 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:30 INFO - 1499736570229 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570232 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570236 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4286d8a4-f50d-4d01-8645-b307008c6c6d","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570238 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:29:30 INFO - 1499736570240 Marionette TRACE 362 <- [1,2,null,{}]
18:29:30 INFO - 1499736570242 Marionette DEBUG Closed connection 362
18:29:30 INFO - 1499736570242 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49734
18:29:30 INFO - 1499736570243 Marionette DEBUG Closed connection 363
18:29:30 INFO - 1499736570244 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49735
18:29:30 INFO - 1499736570245 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:29:30 INFO - 1499736570246 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570248 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570253 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9e4b0e25-2f4b-41b5-b610-f1640263d4ff","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570255 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"}]
18:29:30 INFO - 1499736570256 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49292/test.html"
18:29:30 INFO - 1499736570258 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49292/test.html"
18:29:30 INFO - 1499736570259 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49292/test.html"
18:29:30 INFO - 1499736570282 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"
18:29:30 INFO - 1499736570290 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"
18:29:30 INFO - 1499736570294 Marionette TRACE 364 <- [1,2,null,{}]
18:29:30 INFO - 1499736570295 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:29:30 INFO - 1499736570297 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bb5fc5c8-045e-4d77-96bf-8984fd503cd4","ELEMENT":"bb5fc5c8-045e-4d77-96bf-8984fd503cd4"}}]
18:29:30 INFO - 1499736570298 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"bb5fc5c8-045e-4d77-96bf-8984fd503cd4"}]
18:29:30 INFO - 1499736570552 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:29:30 INFO - 1499736570552 Marionette TRACE 364 <- [1,4,null,{}]
18:29:30 INFO - 1499736570554 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"bb5fc5c8-045e-4d77-96bf-8984fd503cd4","name":"selected"}]
18:29:30 INFO - 1499736570558 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:29:30 INFO - 1499736570560 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}]
18:29:30 INFO - TEST-END: test_click.py TestClick.test_container_element1499736570562 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:29:30 INFO - 1499736570563 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:29:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
18:29:30 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:29:30 INFO - 1499736570565 Marionette TRACE 364 <- [1,7,null,{}]
18:29:30 INFO - 1499736570567 Marionette DEBUG Closed connection 364
18:29:30 INFO - 1499736570568 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49736
18:29:30 INFO - 1499736570569 Marionette DEBUG Closed connection 365
18:29:30 INFO - 1499736570570 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49737
18:29:30 INFO - 1499736570571 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:30 INFO - 1499736570572 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570574 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570577 Marionette TRACE 366 <- [1,1,null,{"sessionId":"d99bd952-190a-434e-87c0-8d78e874a015","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570580 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:29:30 INFO - 1499736570580 Marionette TRACE 366 <- [1,2,null,{}]
18:29:30 INFO - 1499736570581 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:29:30 INFO - 1499736570581 Marionette TRACE 366 <- [1,3,null,{}]
18:29:30 INFO - 1499736570582 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:29:30 INFO - 1499736570584 Marionette TRACE 366 <- [1,4,null,{}]
18:29:30 INFO - 1499736570586 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}]
18:29:30 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499736570588 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:29:30 INFO - 1499736570589 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:29:30 INFO - 1499736570591 Marionette TRACE 366 <- [1,6,null,{}]
18:29:30 INFO - 1499736570593 Marionette DEBUG Closed connection 366
18:29:30 INFO - 1499736570593 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49738
18:29:30 INFO - 1499736570594 Marionette DEBUG Closed connection 367
18:29:30 INFO - 1499736570595 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49739
18:29:30 INFO - 1499736570596 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:30 INFO - 1499736570596 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570599 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570603 Marionette TRACE 368 <- [1,1,null,{"sessionId":"890ed043-4dd6-4e00-978e-9375af334873","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570605 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:29:30 INFO - 1499736570606 Marionette TRACE 368 <- [1,2,null,{}]
18:29:30 INFO - 1499736570608 Marionette DEBUG Closed connection 368
18:29:30 INFO - 1499736570609 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49740
18:29:30 INFO - 1499736570610 Marionette DEBUG Closed connection 369
18:29:30 INFO - 1499736570610 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49741
18:29:30 INFO - 1499736570611 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:29:30 INFO - 1499736570612 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570615 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570625 Marionette TRACE 370 <- [1,1,null,{"sessionId":"61b8b9af-080f-4149-b079-2cb5a49a7383","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570628 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"}]
18:29:30 INFO - 1499736570629 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"
18:29:30 INFO - 1499736570631 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"
18:29:30 INFO - 1499736570632 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"
18:29:30 INFO - 1499736570654 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"
18:29:30 INFO - 1499736570656 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"
18:29:30 INFO - 1499736570660 Marionette TRACE 370 <- [1,2,null,{}]
18:29:30 INFO - 1499736570661 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:29:30 INFO - 1499736570663 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c69ef56e-8071-43cb-8fb4-dd0f3d3a4f5b","ELEMENT":"c69ef56e-8071-43cb-8fb4-dd0f3d3a4f5b"}}]
18:29:30 INFO - 1499736570664 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c69ef56e-8071-43cb-8fb4-dd0f3d3a4f5b"}]
18:29:30 INFO - 1499736570920 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:29:30 INFO - 1499736570921 Marionette TRACE 370 <- [1,4,null,{}]
18:29:30 INFO - 1499736570922 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c69ef56e-8071-43cb-8fb4-dd0f3d3a4f5b","name":"selected"}]
18:29:30 INFO - 1499736570927 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:29:30 INFO - 1499736570929 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}]
18:29:30 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499736570932 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:29:30 INFO - 1499736570933 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:29:30 INFO - 1499736570934 Marionette TRACE 370 <- [1,7,null,{}]
18:29:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 369ms
18:29:30 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:29:30 INFO - 1499736570936 Marionette DEBUG Closed connection 370
18:29:30 INFO - 1499736570937 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49742
18:29:30 INFO - 1499736570938 Marionette DEBUG Closed connection 371
18:29:30 INFO - 1499736570938 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49743
18:29:30 INFO - 1499736570939 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:30 INFO - 1499736570940 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570943 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570945 Marionette TRACE 372 <- [1,1,null,{"sessionId":"f64cc2c4-947f-4ef3-9b15-94bc31d8017d","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570948 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:29:30 INFO - 1499736570948 Marionette TRACE 372 <- [1,2,null,{}]
18:29:30 INFO - 1499736570949 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:29:30 INFO - 1499736570949 Marionette TRACE 372 <- [1,3,null,{}]
18:29:30 INFO - 1499736570950 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:29:30 INFO - 1499736570950 Marionette TRACE 372 <- [1,4,null,{}]
18:29:30 INFO - 1499736570952 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}]
18:29:30 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499736570954 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:29:30 INFO - 1499736570955 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:29:30 INFO - 1499736570957 Marionette TRACE 372 <- [1,6,null,{}]
18:29:30 INFO - 1499736570959 Marionette DEBUG Closed connection 372
18:29:30 INFO - 1499736570959 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49744
18:29:30 INFO - 1499736570960 Marionette DEBUG Closed connection 373
18:29:30 INFO - 1499736570961 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49745
18:29:30 INFO - 1499736570962 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:30 INFO - 1499736570962 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570965 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570968 Marionette TRACE 374 <- [1,1,null,{"sessionId":"72dda276-62a2-4030-9975-b699c9f1d7ab","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570970 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:29:30 INFO - 1499736570971 Marionette TRACE 374 <- [1,2,null,{}]
18:29:30 INFO - 1499736570973 Marionette DEBUG Closed connection 374
18:29:30 INFO - 1499736570974 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49746
18:29:30 INFO - 1499736570975 Marionette DEBUG Closed connection 375
18:29:30 INFO - 1499736570975 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49747
18:29:30 INFO - 1499736570976 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:29:30 INFO - 1499736570977 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:30 INFO - 1499736570979 Marionette DEBUG Register listener.js for window 12
18:29:30 INFO - 1499736570983 Marionette TRACE 376 <- [1,1,null,{"sessionId":"4074c0be-f154-4f9c-83d2-d11778c49f76","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:30 INFO - 1499736570985 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"}]
18:29:30 INFO - 1499736570986 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"
18:29:30 INFO - 1499736570988 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"
18:29:30 INFO - 1499736570989 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"
18:29:31 INFO - 1499736571010 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"
18:29:31 INFO - 1499736571012 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"
18:29:31 INFO - 1499736571016 Marionette TRACE 376 <- [1,2,null,{}]
18:29:31 INFO - 1499736571023 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:29:31 INFO - 1499736571025 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d109ae53-9a89-4151-b444-78d252468a1f","ELEMENT":"d109ae53-9a89-4151-b444-78d252468a1f"}}]
18:29:31 INFO - 1499736571026 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d109ae53-9a89-4151-b444-78d252468a1f"}]
18:29:31 INFO - 1499736571273 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:29:31 INFO - 1499736571274 Marionette TRACE 376 <- [1,4,null,{}]
18:29:31 INFO - 1499736571275 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}]
18:29:31 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499736571278 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:29:31 INFO - 1499736571279 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:29:31 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 345ms
18:29:31 INFO - 1499736571280 Marionette TRACE 376 <- [1,6,null,{}]
18:29:31 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:29:31 INFO - 1499736571283 Marionette DEBUG Closed connection 376
18:29:31 INFO - 1499736571283 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49748
18:29:31 INFO - 1499736571284 Marionette DEBUG Closed connection 377
18:29:31 INFO - 1499736571285 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49749
18:29:31 INFO - 1499736571286 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:31 INFO - 1499736571287 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571289 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571292 Marionette TRACE 378 <- [1,1,null,{"sessionId":"bcd7fa2f-c89a-43e3-b05f-eee6365e0f92","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571294 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:29:31 INFO - 1499736571295 Marionette TRACE 378 <- [1,2,null,{}]
18:29:31 INFO - 1499736571296 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:29:31 INFO - 1499736571296 Marionette TRACE 378 <- [1,3,null,{}]
18:29:31 INFO - 1499736571298 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:29:31 INFO - 1499736571298 Marionette TRACE 378 <- [1,4,null,{}]
18:29:31 INFO - 1499736571300 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}]
18:29:31 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499736571307 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:29:31 INFO - 1499736571308 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:29:31 INFO - 1499736571309 Marionette TRACE 378 <- [1,6,null,{}]
18:29:31 INFO - 1499736571313 Marionette DEBUG Closed connection 378
18:29:31 INFO - 1499736571313 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49750
18:29:31 INFO - 1499736571314 Marionette DEBUG Closed connection 379
18:29:31 INFO - 1499736571315 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49751
18:29:31 INFO - 1499736571316 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:31 INFO - 1499736571317 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571319 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571322 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0c8187c0-0d8e-4314-92a2-d2c68deefcb2","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571324 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:29:31 INFO - 1499736571326 Marionette TRACE 380 <- [1,2,null,{}]
18:29:31 INFO - 1499736571328 Marionette DEBUG Closed connection 380
18:29:31 INFO - 1499736571328 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49752
18:29:31 INFO - 1499736571329 Marionette DEBUG Closed connection 381
18:29:31 INFO - 1499736571330 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49753
18:29:31 INFO - 1499736571331 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:29:31 INFO - 1499736571332 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571334 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571337 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d7ee2f42-9962-43e6-a2f5-fb8067454970","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571339 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"}]
18:29:31 INFO - 1499736571340 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"
18:29:31 INFO - 1499736571343 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"
18:29:31 INFO - 1499736571343 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"
18:29:31 INFO - 1499736571361 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"
18:29:31 INFO - 1499736571365 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"
18:29:31 INFO - 1499736571368 Marionette TRACE 382 <- [1,2,null,{}]
18:29:31 INFO - 1499736571375 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:29:31 INFO - 1499736571378 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3b018371-a960-43e5-866a-56f130429347","ELEMENT":"3b018371-a960-43e5-866a-56f130429347"}}]
18:29:31 INFO - 1499736571379 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"3b018371-a960-43e5-866a-56f130429347"}]
18:29:31 INFO - 1499736571623 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:29:31 INFO - 1499736571623 Marionette TRACE 382 <- [1,4,null,{}]
18:29:31 INFO - 1499736571625 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}]
18:29:31 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499736571628 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:29:31 INFO - 1499736571629 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:29:31 INFO - 1499736571630 Marionette TRACE 382 <- [1,6,null,{}]
18:29:31 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 349ms
18:29:31 INFO - TEST-START | test_click.py TestClick.test_input_file
18:29:31 INFO - 1499736571632 Marionette DEBUG Closed connection 382
18:29:31 INFO - 1499736571633 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49754
18:29:31 INFO - 1499736571634 Marionette DEBUG Closed connection 383
18:29:31 INFO - 1499736571635 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49755
18:29:31 INFO - 1499736571636 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:31 INFO - 1499736571636 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571639 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571642 Marionette TRACE 384 <- [1,1,null,{"sessionId":"102edf78-8546-4483-8790-3b1fd7660f84","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571644 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:29:31 INFO - 1499736571644 Marionette TRACE 384 <- [1,2,null,{}]
18:29:31 INFO - 1499736571645 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:29:31 INFO - 1499736571646 Marionette TRACE 384 <- [1,3,null,{}]
18:29:31 INFO - 1499736571647 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:29:31 INFO - 1499736571647 Marionette TRACE 384 <- [1,4,null,{}]
18:29:31 INFO - 1499736571649 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}]
18:29:31 INFO - TEST-START: test_click.py TestClick.test_input_file1499736571652 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:29:31 INFO - 1499736571653 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:29:31 INFO - 1499736571654 Marionette TRACE 384 <- [1,6,null,{}]
18:29:31 INFO - 1499736571656 Marionette DEBUG Closed connection 384
18:29:31 INFO - 1499736571657 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49756
18:29:31 INFO - 1499736571658 Marionette DEBUG Closed connection 385
18:29:31 INFO - 1499736571658 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49757
18:29:31 INFO - 1499736571659 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:31 INFO - 1499736571660 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571662 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571665 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cd975b4d-f0a8-4af5-b964-41a0046b3622","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571667 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:29:31 INFO - 1499736571669 Marionette TRACE 386 <- [1,2,null,{}]
18:29:31 INFO - 1499736571671 Marionette DEBUG Closed connection 386
18:29:31 INFO - 1499736571671 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49758
18:29:31 INFO - 1499736571672 Marionette DEBUG Closed connection 387
18:29:31 INFO - 1499736571673 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49759
18:29:31 INFO - 1499736571674 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:29:31 INFO - 1499736571674 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571677 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571680 Marionette TRACE 388 <- [1,1,null,{"sessionId":"27ac9f3f-8b49-4253-a21c-f509a8f363ce","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571682 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:29:31 INFO - 1499736571683 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"
18:29:31 INFO - 1499736571692 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"
18:29:31 INFO - 1499736571693 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"
18:29:31 INFO - 1499736571709 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:29:31 INFO - 1499736571712 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:29:31 INFO - 1499736571716 Marionette TRACE 388 <- [1,2,null,{}]
18:29:31 INFO - 1499736571722 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:29:31 INFO - 1499736571725 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7221f310-c15d-401a-8705-f1e68185d11b","ELEMENT":"7221f310-c15d-401a-8705-f1e68185d11b"}}]
18:29:31 INFO - 1499736571726 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"7221f310-c15d-401a-8705-f1e68185d11b"}]
18:29:31 INFO - 1499736571761 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]
18:29:31 INFO - 1499736571763 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}]
18:29:31 INFO - TEST-END: test_click.py TestClick.test_input_file1499736571766 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:29:31 INFO - 1499736571767 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:29:31 INFO - 1499736571768 Marionette TRACE 388 <- [1,6,null,{}]
18:29:31 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 137ms
18:29:31 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:29:31 INFO - 1499736571771 Marionette DEBUG Closed connection 388
18:29:31 INFO - 1499736571771 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49760
18:29:31 INFO - 1499736571773 Marionette DEBUG Closed connection 389
18:29:31 INFO - 1499736571773 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49761
18:29:31 INFO - 1499736571774 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:31 INFO - 1499736571775 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571777 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571780 Marionette TRACE 390 <- [1,1,null,{"sessionId":"6b4a0ae4-6828-4cf0-8900-d4d15a84b97c","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571782 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:29:31 INFO - 1499736571783 Marionette TRACE 390 <- [1,2,null,{}]
18:29:31 INFO - 1499736571784 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:29:31 INFO - 1499736571784 Marionette TRACE 390 <- [1,3,null,{}]
18:29:31 INFO - 1499736571785 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:29:31 INFO - 1499736571785 Marionette TRACE 390 <- [1,4,null,{}]
18:29:31 INFO - 1499736571787 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}]
18:29:31 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499736571790 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:29:31 INFO - 1499736571790 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:29:31 INFO - 1499736571792 Marionette TRACE 390 <- [1,6,null,{}]
18:29:31 INFO - 1499736571794 Marionette DEBUG Closed connection 390
18:29:31 INFO - 1499736571794 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49762
18:29:31 INFO - 1499736571795 Marionette DEBUG Closed connection 391
18:29:31 INFO - 1499736571796 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49763
18:29:31 INFO - 1499736571797 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:31 INFO - 1499736571797 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571800 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571817 Marionette TRACE 392 <- [1,1,null,{"sessionId":"cdd2d2a2-3ddc-43fc-8a01-a1de06f7b4a9","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571826 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:29:31 INFO - 1499736571828 Marionette TRACE 392 <- [1,2,null,{}]
18:29:31 INFO - 1499736571832 Marionette DEBUG Closed connection 392
18:29:31 INFO - 1499736571833 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49764
18:29:31 INFO - 1499736571835 Marionette DEBUG Closed connection 393
18:29:31 INFO - 1499736571836 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49765
18:29:31 INFO - 1499736571838 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:29:31 INFO - 1499736571839 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:31 INFO - 1499736571842 Marionette DEBUG Register listener.js for window 12
18:29:31 INFO - 1499736571848 Marionette TRACE 394 <- [1,1,null,{"sessionId":"9fd15e67-fe22-46d9-94c6-08eeda092251","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:31 INFO - 1499736571853 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"}]
18:29:31 INFO - 1499736571855 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:29:31 INFO - 1499736571858 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:29:31 INFO - 1499736571859 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:29:31 INFO - 1499736571882 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"
18:29:31 INFO - 1499736571886 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"
18:29:31 INFO - 1499736571897 Marionette TRACE 394 <- [1,2,null,{}]
18:29:31 INFO - 1499736571899 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:29:31 INFO - 1499736571904 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c53be841-bbb7-4bfd-a3ae-fab9c40356ea","ELEMENT":"c53be841-bbb7-4bfd-a3ae-fab9c40356ea"}}]
18:29:31 INFO - 1499736571914 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:29:31 INFO - 1499736571918 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"60d4ab4b-6803-44d1-b679-f1cc2e0beb17","ELEMENT":"60d4ab4b-6803-44d1-b679-f1cc2e0beb17"}}]
18:29:31 INFO - 1499736571920 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"c53be841-bbb7-4bfd-a3ae-fab9c40356ea"}]
18:29:32 INFO - 1499736572216 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:29:32 INFO - 1499736572217 Marionette TRACE 394 <- [1,5,null,{}]
18:29:32 INFO - 1499736572219 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"60d4ab4b-6803-44d1-b679-f1cc2e0beb17"}]
18:29:32 INFO - 1499736572240 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]
18:29:32 INFO - 1499736572243 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:29:32 INFO - 1499736572247 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:29:32 INFO - 1499736572249 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}]
18:29:32 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499736572253 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:29:32 INFO - 1499736572254 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:29:32 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 487ms
18:29:32 INFO - 1499736572257 Marionette TRACE 394 <- [1,9,null,{}]
18:29:32 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:29:32 INFO - 1499736572260 Marionette DEBUG Closed connection 394
18:29:32 INFO - 1499736572261 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49766
18:29:32 INFO - 1499736572262 Marionette DEBUG Closed connection 395
18:29:32 INFO - 1499736572263 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49767
18:29:32 INFO - 1499736572264 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:32 INFO - 1499736572267 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:32 INFO - 1499736572270 Marionette DEBUG Register listener.js for window 12
18:29:32 INFO - 1499736572276 Marionette TRACE 396 <- [1,1,null,{"sessionId":"67cd91f0-15eb-4aee-b5f1-c95d0afdffba","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:32 INFO - 1499736572280 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:29:32 INFO - 1499736572281 Marionette TRACE 396 <- [1,2,null,{}]
18:29:32 INFO - 1499736572282 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:29:32 INFO - 1499736572283 Marionette TRACE 396 <- [1,3,null,{}]
18:29:32 INFO - 1499736572284 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:29:32 INFO - 1499736572284 Marionette TRACE 396 <- [1,4,null,{}]
18:29:32 INFO - 1499736572287 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}]
18:29:32 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499736572291 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:29:32 INFO - 1499736572292 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:29:32 INFO - 1499736572294 Marionette TRACE 396 <- [1,6,null,{}]
18:29:32 INFO - 1499736572297 Marionette DEBUG Closed connection 396
18:29:32 INFO - 1499736572298 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49768
18:29:32 INFO - 1499736572299 Marionette DEBUG Closed connection 397
18:29:32 INFO - 1499736572300 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49769
18:29:32 INFO - 1499736572303 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:29:32 INFO - 1499736572305 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:32 INFO - 1499736572308 Marionette DEBUG Register listener.js for window 12
18:29:32 INFO - 1499736572313 Marionette TRACE 398 <- [1,1,null,{"sessionId":"3e3f9ada-e03a-489e-be74-10f83f51e368","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:32 INFO - 1499736572338 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:29:32 INFO - 1499736572340 Marionette TRACE 398 <- [1,2,null,{}]
18:29:32 INFO - 1499736572348 Marionette DEBUG Closed connection 398
18:29:32 INFO - 1499736572349 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49770
18:29:32 INFO - 1499736572350 Marionette DEBUG Closed connection 399
18:29:32 INFO - 1499736572350 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49771
18:29:32 INFO - 1499736572352 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:29:32 INFO - 1499736572353 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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}
18:29:32 INFO - 1499736572356 Marionette DEBUG Register listener.js for window 12
18:29:32 INFO - 1499736572360 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d2610a46-a3c1-4014-9fd8-478817eddb2c","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":3832,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpuscebr.mozrunner","moz:accessibilityChecks":false}}]
18:29:32 INFO - 1499736572385 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"}]
18:29:32 INFO - 1499736572388 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"
18:29:32 INFO - 1499736572392 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"
18:29:32 INFO - 1499736572392 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"
18:29:32 INFO - 1499736572415 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"
18:29:32 INFO - 1499736572420 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"
18:29:32 INFO - 1499736572430 Marionette TRACE 400 <- [1,2,null,{}]
18:29:32 INFO - 1499736572432 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:29:32 INFO - 1499736572435 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b0ec2688-0934-4f7e-8783-b794cf640170","ELEMENT":"b0ec2688-0934-4f7e-8783-b794cf640170"}}]
18:29:32 INFO - 1499736572437 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b0ec2688-0934-4f7e-8783-b794cf640170"}]
18:29:32 INFO - 1499736572462 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:29:32 INFO - 1499736572463 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b0ec2688-0934-4f7e-8783-b794cf640170"}]
18:29:32 INFO - 1499736572482 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element