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]
19:04:38 INFO - 1499479478025 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
19:04:38 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499479478028 Marionette TRACE 352 <- [1,5,null,{"value":null}]
19:04:38 INFO - 1499479478029 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
19:04:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 137ms
19:04:38 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:04:38 INFO - 1499479478031 Marionette TRACE 352 <- [1,6,null,{}]
19:04:38 INFO - 1499479478032 Marionette DEBUG Closed connection 352
19:04:38 INFO - 1499479478033 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49862
19:04:38 INFO - 1499479478034 Marionette DEBUG Closed connection 353
19:04:38 INFO - 1499479478035 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49863
19:04:38 INFO - 1499479478036 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:38 INFO - 1499479478037 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478040 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478044 Marionette TRACE 354 <- [1,1,null,{"sessionId":"e11b8bc0-32af-4687-87dd-8d0ce117521c","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478045 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
19:04:38 INFO - 1499479478045 Marionette TRACE 354 <- [1,2,null,{}]
19:04:38 INFO - 1499479478046 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:04:38 INFO - 1499479478047 Marionette TRACE 354 <- [1,3,null,{}]
19:04:38 INFO - 1499479478048 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
19:04:38 INFO - 1499479478049 Marionette TRACE 354 <- [1,4,null,{}]
19:04:38 INFO - 1499479478050 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
19:04:38 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499479478053 Marionette TRACE 354 <- [1,5,null,{"value":null}]
19:04:38 INFO - 1499479478054 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
19:04:38 INFO - 1499479478056 Marionette TRACE 354 <- [1,6,null,{}]
19:04:38 INFO - 1499479478057 Marionette DEBUG Closed connection 354
19:04:38 INFO - 1499479478058 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49864
19:04:38 INFO - 1499479478059 Marionette DEBUG Closed connection 355
19:04:38 INFO - 1499479478060 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49865
19:04:38 INFO - 1499479478061 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:38 INFO - 1499479478061 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478064 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478067 Marionette TRACE 356 <- [1,1,null,{"sessionId":"fa9d0466-95e0-4c19-a183-4416357611ca","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478069 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
19:04:38 INFO - 1499479478070 Marionette TRACE 356 <- [1,2,null,{}]
19:04:38 INFO - 1499479478072 Marionette DEBUG Closed connection 356
19:04:38 INFO - 1499479478073 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49866
19:04:38 INFO - 1499479478073 Marionette DEBUG Closed connection 357
19:04:38 INFO - 1499479478074 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49867
19:04:38 INFO - 1499479478075 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:04:38 INFO - 1499479478076 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478079 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478082 Marionette TRACE 358 <- [1,1,null,{"sessionId":"7408b3a2-f388-47d4-bc05-682f29ac93cb","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478084 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49421/clicks.html"}]
19:04:38 INFO - 1499479478085 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49421/hidden.html"
19:04:38 INFO - 1499479478099 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49421/hidden.html"
19:04:38 INFO - 1499479478099 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49421/hidden.html"
19:04:38 INFO - 1499479478107 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49421/clicks.html"
19:04:38 INFO - 1499479478115 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49421/clicks.html"
19:04:38 INFO - 1499479478123 Marionette TRACE 358 <- [1,2,null,{}]
19:04:38 INFO - 1499479478133 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:04:38 INFO - 1499479478139 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a37ca57a-091c-48e1-b634-7cac32bbdb02","ELEMENT":"a37ca57a-091c-48e1-b634-7cac32bbdb02"}}]
19:04:38 INFO - 1499479478140 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a37ca57a-091c-48e1-b634-7cac32bbdb02"}]
19:04:38 INFO - 1499479478186 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49421/clicks.html"
19:04:38 INFO - 1499479478196 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49421/clicks.html"
19:04:38 INFO - 1499479478197 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49421/clicks.html"
19:04:38 INFO - 1499479478206 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49421/test.html"
19:04:38 INFO - 1499479478214 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49421/test.html"
19:04:38 INFO - 1499479478215 Marionette TRACE 358 <- [1,4,null,{}]
19:04:38 INFO - 1499479478219 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:04:38 INFO - 1499479478222 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5c592aaa-bb57-480d-84a2-b6df764606e5","ELEMENT":"5c592aaa-bb57-480d-84a2-b6df764606e5"}}]
19:04:38 INFO - 1499479478223 Marionette TRACE 358 -> [0,6,"getTitle",{}]
19:04:38 INFO - 1499479478223 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
19:04:38 INFO - 1499479478228 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
19:04:38 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499479478232 Marionette TRACE 358 <- [1,7,null,{"value":null}]
19:04:38 INFO - 1499479478233 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
19:04:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 203ms
19:04:38 INFO - TEST-START | test_click.py TestClick.test_container_element
19:04:38 INFO - 1499479478235 Marionette TRACE 358 <- [1,8,null,{}]
19:04:38 INFO - 1499479478236 Marionette DEBUG Closed connection 358
19:04:38 INFO - 1499479478237 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49870
19:04:38 INFO - 1499479478238 Marionette DEBUG Closed connection 359
19:04:38 INFO - 1499479478239 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49871
19:04:38 INFO - 1499479478240 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:38 INFO - 1499479478240 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478244 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478247 Marionette TRACE 360 <- [1,1,null,{"sessionId":"0b504fa5-775b-442f-9b8b-2db91d754aaf","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478249 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
19:04:38 INFO - 1499479478249 Marionette TRACE 360 <- [1,2,null,{}]
19:04:38 INFO - 1499479478250 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:04:38 INFO - 1499479478251 Marionette TRACE 360 <- [1,3,null,{}]
19:04:38 INFO - 1499479478252 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
19:04:38 INFO - 1499479478252 Marionette TRACE 360 <- [1,4,null,{}]
19:04:38 INFO - 1499479478254 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
19:04:38 INFO - TEST-START: test_click.py TestClick.test_container_element1499479478257 Marionette TRACE 360 <- [1,5,null,{"value":null}]
19:04:38 INFO - 1499479478258 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
19:04:38 INFO - 1499479478260 Marionette TRACE 360 <- [1,6,null,{}]
19:04:38 INFO - 1499479478262 Marionette DEBUG Closed connection 360
19:04:38 INFO - 1499479478263 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49872
19:04:38 INFO - 1499479478264 Marionette DEBUG Closed connection 361
19:04:38 INFO - 1499479478264 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49873
19:04:38 INFO - 1499479478265 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:38 INFO - 1499479478266 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478269 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478272 Marionette TRACE 362 <- [1,1,null,{"sessionId":"6e555ca0-0bbb-4982-94b2-40a72f6f71ef","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478273 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
19:04:38 INFO - 1499479478275 Marionette TRACE 362 <- [1,2,null,{}]
19:04:38 INFO - 1499479478276 Marionette DEBUG Closed connection 362
19:04:38 INFO - 1499479478277 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49874
19:04:38 INFO - 1499479478278 Marionette DEBUG Closed connection 363
19:04:38 INFO - 1499479478279 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49875
19:04:38 INFO - 1499479478280 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:04:38 INFO - 1499479478281 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478289 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478292 Marionette TRACE 364 <- [1,1,null,{"sessionId":"b3aa1608-fac8-4a0f-93cb-8604ee9b5e08","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478294 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
19:04:38 INFO - 1499479478296 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49421/test.html"
19:04:38 INFO - 1499479478298 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49421/test.html"
19:04:38 INFO - 1499479478298 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49421/test.html"
19:04:38 INFO - 1499479478306 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:38 INFO - 1499479478310 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:38 INFO - 1499479478322 Marionette TRACE 364 <- [1,2,null,{}]
19:04:38 INFO - 1499479478332 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:04:38 INFO - 1499479478335 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eb2f6436-b599-49f5-99b2-7ab273205fe1","ELEMENT":"eb2f6436-b599-49f5-99b2-7ab273205fe1"}}]
19:04:38 INFO - 1499479478336 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"eb2f6436-b599-49f5-99b2-7ab273205fe1"}]
19:04:38 INFO - 1499479478580 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:04:38 INFO - 1499479478581 Marionette TRACE 364 <- [1,4,null,{}]
19:04:38 INFO - 1499479478582 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"eb2f6436-b599-49f5-99b2-7ab273205fe1","name":"selected"}]
19:04:38 INFO - 1499479478587 Marionette TRACE 364 <- [1,5,null,{"value":true}]
19:04:38 INFO - 1499479478589 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
19:04:38 INFO - TEST-END: test_click.py TestClick.test_container_element1499479478593 Marionette TRACE 364 <- [1,6,null,{"value":null}]
19:04:38 INFO - 1499479478594 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
19:04:38 INFO - 1499479478595 Marionette TRACE 364 <- [1,7,null,{}]
19:04:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 360ms
19:04:38 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:04:38 INFO - 1499479478597 Marionette DEBUG Closed connection 364
19:04:38 INFO - 1499479478598 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49876
19:04:38 INFO - 1499479478599 Marionette DEBUG Closed connection 365
19:04:38 INFO - 1499479478600 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49877
19:04:38 INFO - 1499479478601 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:38 INFO - 1499479478601 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478604 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478608 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b360b45e-d54c-4d3a-a3cf-2b41125f470c","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478609 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
19:04:38 INFO - 1499479478610 Marionette TRACE 366 <- [1,2,null,{}]
19:04:38 INFO - 1499479478610 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:04:38 INFO - 1499479478611 Marionette TRACE 366 <- [1,3,null,{}]
19:04:38 INFO - 1499479478612 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
19:04:38 INFO - 1499479478612 Marionette TRACE 366 <- [1,4,null,{}]
19:04:38 INFO - 1499479478614 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
19:04:38 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499479478617 Marionette TRACE 366 <- [1,5,null,{"value":null}]
19:04:38 INFO - 1499479478618 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
19:04:38 INFO - 1499479478620 Marionette TRACE 366 <- [1,6,null,{}]
19:04:38 INFO - 1499479478621 Marionette DEBUG Closed connection 366
19:04:38 INFO - 1499479478622 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49878
19:04:38 INFO - 1499479478623 Marionette DEBUG Closed connection 367
19:04:38 INFO - 1499479478624 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49879
19:04:38 INFO - 1499479478625 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:38 INFO - 1499479478625 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478628 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478632 Marionette TRACE 368 <- [1,1,null,{"sessionId":"73e09293-0ae4-4acf-a1fc-4992add7715e","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478633 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
19:04:38 INFO - 1499479478634 Marionette TRACE 368 <- [1,2,null,{}]
19:04:38 INFO - 1499479478636 Marionette DEBUG Closed connection 368
19:04:38 INFO - 1499479478637 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49880
19:04:38 INFO - 1499479478638 Marionette DEBUG Closed connection 369
19:04:38 INFO - 1499479478638 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49881
19:04:38 INFO - 1499479478639 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:04:38 INFO - 1499479478640 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478643 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478646 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a412f4b3-6d66-48b7-a518-a9d9812bc460","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478648 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
19:04:38 INFO - 1499479478650 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:38 INFO - 1499479478652 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:38 INFO - 1499479478652 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:38 INFO - 1499479478662 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:38 INFO - 1499479478664 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:38 INFO - 1499479478672 Marionette TRACE 370 <- [1,2,null,{}]
19:04:38 INFO - 1499479478680 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:04:38 INFO - 1499479478687 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"54fea391-a5f3-4c02-af27-fa0f36a5d575","ELEMENT":"54fea391-a5f3-4c02-af27-fa0f36a5d575"}}]
19:04:38 INFO - 1499479478689 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"54fea391-a5f3-4c02-af27-fa0f36a5d575"}]
19:04:38 INFO - 1499479478935 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:04:38 INFO - 1499479478936 Marionette TRACE 370 <- [1,4,null,{}]
19:04:38 INFO - 1499479478937 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"54fea391-a5f3-4c02-af27-fa0f36a5d575","name":"selected"}]
19:04:38 INFO - 1499479478942 Marionette TRACE 370 <- [1,5,null,{"value":true}]
19:04:38 INFO - 1499479478944 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
19:04:38 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499479478947 Marionette TRACE 370 <- [1,6,null,{"value":null}]
19:04:38 INFO - 1499479478948 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
19:04:38 INFO - 1499479478949 Marionette TRACE 370 <- [1,7,null,{}]
19:04:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 354ms
19:04:38 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:04:38 INFO - 1499479478951 Marionette DEBUG Closed connection 370
19:04:38 INFO - 1499479478952 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49882
19:04:38 INFO - 1499479478953 Marionette DEBUG Closed connection 371
19:04:38 INFO - 1499479478953 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49883
19:04:38 INFO - 1499479478954 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:38 INFO - 1499479478955 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478958 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478969 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9feb6a74-d995-4fe8-9cfe-edddf20fe8fa","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478970 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
19:04:38 INFO - 1499479478971 Marionette TRACE 372 <- [1,2,null,{}]
19:04:38 INFO - 1499479478972 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:04:38 INFO - 1499479478972 Marionette TRACE 372 <- [1,3,null,{}]
19:04:38 INFO - 1499479478973 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
19:04:38 INFO - 1499479478974 Marionette TRACE 372 <- [1,4,null,{}]
19:04:38 INFO - 1499479478976 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
19:04:38 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499479478979 Marionette TRACE 372 <- [1,5,null,{"value":null}]
19:04:38 INFO - 1499479478980 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
19:04:38 INFO - 1499479478981 Marionette TRACE 372 <- [1,6,null,{}]
19:04:38 INFO - 1499479478983 Marionette DEBUG Closed connection 372
19:04:38 INFO - 1499479478984 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49884
19:04:38 INFO - 1499479478985 Marionette DEBUG Closed connection 373
19:04:38 INFO - 1499479478985 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49885
19:04:38 INFO - 1499479478987 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:38 INFO - 1499479478987 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:38 INFO - 1499479478990 Marionette DEBUG Register listener.js for window 4294967297
19:04:38 INFO - 1499479478994 Marionette TRACE 374 <- [1,1,null,{"sessionId":"bdb02b94-36fe-4693-9e14-b3688651a5b3","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:38 INFO - 1499479478995 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
19:04:38 INFO - 1499479478996 Marionette TRACE 374 <- [1,2,null,{}]
19:04:38 INFO - 1499479478998 Marionette DEBUG Closed connection 374
19:04:39 INFO - 1499479478999 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49886
19:04:39 INFO - 1499479479000 Marionette DEBUG Closed connection 375
19:04:39 INFO - 1499479479000 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49887
19:04:39 INFO - 1499479479001 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:04:39 INFO - 1499479479002 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479005 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479008 Marionette TRACE 376 <- [1,1,null,{"sessionId":"3cc44174-3337-4ffb-959a-61b2fa25674e","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479010 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
19:04:39 INFO - 1499479479011 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:39 INFO - 1499479479014 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:39 INFO - 1499479479014 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:39 INFO - 1499479479021 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
19:04:39 INFO - 1499479479024 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
19:04:39 INFO - 1499479479034 Marionette TRACE 376 <- [1,2,null,{}]
19:04:39 INFO - 1499479479038 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:04:39 INFO - 1499479479040 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e05546b9-ae0b-4292-98f2-2dc33e35ee21","ELEMENT":"e05546b9-ae0b-4292-98f2-2dc33e35ee21"}}]
19:04:39 INFO - 1499479479047 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e05546b9-ae0b-4292-98f2-2dc33e35ee21"}]
19:04:39 INFO - 1499479479288 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:04:39 INFO - 1499479479289 Marionette TRACE 376 <- [1,4,null,{}]
19:04:39 INFO - 1499479479291 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
19:04:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499479479294 Marionette TRACE 376 <- [1,5,null,{"value":null}]
19:04:39 INFO - 1499479479295 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
19:04:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 347ms
19:04:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:04:39 INFO - 1499479479297 Marionette TRACE 376 <- [1,6,null,{}]
19:04:39 INFO - 1499479479298 Marionette DEBUG Closed connection 376
19:04:39 INFO - 1499479479299 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49888
19:04:39 INFO - 1499479479300 Marionette DEBUG Closed connection 377
19:04:39 INFO - 1499479479301 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49889
19:04:39 INFO - 1499479479302 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:39 INFO - 1499479479303 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479306 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479309 Marionette TRACE 378 <- [1,1,null,{"sessionId":"cee90a12-bcaa-4619-ad48-a99bad494eb8","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479310 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
19:04:39 INFO - 1499479479311 Marionette TRACE 378 <- [1,2,null,{}]
19:04:39 INFO - 1499479479312 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:04:39 INFO - 1499479479312 Marionette TRACE 378 <- [1,3,null,{}]
19:04:39 INFO - 1499479479313 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
19:04:39 INFO - 1499479479314 Marionette TRACE 378 <- [1,4,null,{}]
19:04:39 INFO - 1499479479315 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
19:04:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499479479318 Marionette TRACE 378 <- [1,5,null,{"value":null}]
19:04:39 INFO - 1499479479319 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
19:04:39 INFO - 1499479479321 Marionette TRACE 378 <- [1,6,null,{}]
19:04:39 INFO - 1499479479322 Marionette DEBUG Closed connection 378
19:04:39 INFO - 1499479479323 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49890
19:04:39 INFO - 1499479479324 Marionette DEBUG Closed connection 379
19:04:39 INFO - 1499479479325 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49891
19:04:39 INFO - 1499479479326 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:39 INFO - 1499479479327 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479330 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479333 Marionette TRACE 380 <- [1,1,null,{"sessionId":"97bf6177-6238-4aa3-b6d1-8b410174ff79","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479334 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
19:04:39 INFO - 1499479479336 Marionette TRACE 380 <- [1,2,null,{}]
19:04:39 INFO - 1499479479337 Marionette DEBUG Closed connection 380
19:04:39 INFO - 1499479479338 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49892
19:04:39 INFO - 1499479479339 Marionette DEBUG Closed connection 381
19:04:39 INFO - 1499479479340 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49893
19:04:39 INFO - 1499479479341 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:04:39 INFO - 1499479479342 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479344 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479348 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d9e15c5c-39c3-47d0-9dc5-c368de3802ae","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479349 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
19:04:39 INFO - 1499479479351 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
19:04:39 INFO - 1499479479353 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
19:04:39 INFO - 1499479479353 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
19:04:39 INFO - 1499479479362 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:39 INFO - 1499479479367 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:39 INFO - 1499479479372 Marionette TRACE 382 <- [1,2,null,{}]
19:04:39 INFO - 1499479479380 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:04:39 INFO - 1499479479384 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f297893-1aab-4d29-b17e-6d4a972ab0ed","ELEMENT":"6f297893-1aab-4d29-b17e-6d4a972ab0ed"}}]
19:04:39 INFO - 1499479479385 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"6f297893-1aab-4d29-b17e-6d4a972ab0ed"}]
19:04:39 INFO - 1499479479635 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:04:39 INFO - 1499479479636 Marionette TRACE 382 <- [1,4,null,{}]
19:04:39 INFO - 1499479479638 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
19:04:39 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499479479641 Marionette TRACE 382 <- [1,5,null,{"value":null}]
19:04:39 INFO - 1499479479642 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
19:04:39 INFO - 1499479479643 Marionette TRACE 382 <- [1,6,null,{}]
19:04:39 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 346ms
19:04:39 INFO - TEST-START | test_click.py TestClick.test_input_file
19:04:39 INFO - 1499479479645 Marionette DEBUG Closed connection 382
19:04:39 INFO - 1499479479646 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49894
19:04:39 INFO - 1499479479647 Marionette DEBUG Closed connection 383
19:04:39 INFO - 1499479479648 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49895
19:04:39 INFO - 1499479479649 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:39 INFO - 1499479479649 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479652 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479656 Marionette TRACE 384 <- [1,1,null,{"sessionId":"00dec247-408f-4063-a1eb-169e12063d9a","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479657 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
19:04:39 INFO - 1499479479657 Marionette TRACE 384 <- [1,2,null,{}]
19:04:39 INFO - 1499479479658 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:04:39 INFO - 1499479479659 Marionette TRACE 384 <- [1,3,null,{}]
19:04:39 INFO - 1499479479660 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
19:04:39 INFO - 1499479479660 Marionette TRACE 384 <- [1,4,null,{}]
19:04:39 INFO - 1499479479662 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
19:04:39 INFO - TEST-START: test_click.py TestClick.test_input_file1499479479665 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:04:39 INFO - 1499479479666 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:04:39 INFO - 1499479479668 Marionette TRACE 384 <- [1,6,null,{}]
19:04:39 INFO - 1499479479670 Marionette DEBUG Closed connection 384
19:04:39 INFO - 1499479479670 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49896
19:04:39 INFO - 1499479479671 Marionette DEBUG Closed connection 385
19:04:39 INFO - 1499479479672 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49897
19:04:39 INFO - 1499479479673 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:39 INFO - 1499479479674 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479676 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479680 Marionette TRACE 386 <- [1,1,null,{"sessionId":"3ef2da8c-61a3-43d1-822c-c53b20e7224b","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479681 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
19:04:39 INFO - 1499479479682 Marionette TRACE 386 <- [1,2,null,{}]
19:04:39 INFO - 1499479479684 Marionette DEBUG Closed connection 386
19:04:39 INFO - 1499479479685 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49898
19:04:39 INFO - 1499479479686 Marionette DEBUG Closed connection 387
19:04:39 INFO - 1499479479686 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49899
19:04:39 INFO - 1499479479687 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:04:39 INFO - 1499479479688 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479691 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479694 Marionette TRACE 388 <- [1,1,null,{"sessionId":"befd32d5-b891-45b4-90bf-31f9c29d1bba","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479695 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:04:39 INFO - 1499479479697 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:39 INFO - 1499479479699 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:39 INFO - 1499479479700 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
19:04:39 INFO - 1499479479707 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:04:39 INFO - 1499479479712 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:04:39 INFO - 1499479479722 Marionette TRACE 388 <- [1,2,null,{}]
19:04:39 INFO - 1499479479731 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:04:39 INFO - 1499479479736 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b4b03cf9-bd40-4e67-9cbc-7a799e63e77e","ELEMENT":"b4b03cf9-bd40-4e67-9cbc-7a799e63e77e"}}]
19:04:39 INFO - 1499479479737 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"b4b03cf9-bd40-4e67-9cbc-7a799e63e77e"}]
19:04:39 INFO - 1499479479766 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]
19:04:39 INFO - 1499479479768 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
19:04:39 INFO - TEST-END: test_click.py TestClick.test_input_file1499479479771 Marionette TRACE 388 <- [1,5,null,{"value":null}]
19:04:39 INFO - 1499479479772 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
19:04:39 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 130ms
19:04:39 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:04:39 INFO - 1499479479774 Marionette TRACE 388 <- [1,6,null,{}]
19:04:39 INFO - 1499479479775 Marionette DEBUG Closed connection 388
19:04:39 INFO - 1499479479776 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49900
19:04:39 INFO - 1499479479777 Marionette DEBUG Closed connection 389
19:04:39 INFO - 1499479479778 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49901
19:04:39 INFO - 1499479479779 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:39 INFO - 1499479479779 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479782 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479786 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fc3a3ebf-06a9-4167-bef3-d9de98404844","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479787 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
19:04:39 INFO - 1499479479788 Marionette TRACE 390 <- [1,2,null,{}]
19:04:39 INFO - 1499479479789 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:04:39 INFO - 1499479479789 Marionette TRACE 390 <- [1,3,null,{}]
19:04:39 INFO - 1499479479790 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
19:04:39 INFO - 1499479479791 Marionette TRACE 390 <- [1,4,null,{}]
19:04:39 INFO - 1499479479793 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
19:04:39 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499479479796 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:04:39 INFO - 1499479479796 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:04:39 INFO - 1499479479798 Marionette TRACE 390 <- [1,6,null,{}]
19:04:39 INFO - 1499479479800 Marionette DEBUG Closed connection 390
19:04:39 INFO - 1499479479801 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49902
19:04:39 INFO - 1499479479801 Marionette DEBUG Closed connection 391
19:04:39 INFO - 1499479479802 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49903
19:04:39 INFO - 1499479479803 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:39 INFO - 1499479479804 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479806 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479810 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fb9bf2fd-578a-4f96-b704-672e8a80d41b","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479812 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
19:04:39 INFO - 1499479479813 Marionette TRACE 392 <- [1,2,null,{}]
19:04:39 INFO - 1499479479815 Marionette DEBUG Closed connection 392
19:04:39 INFO - 1499479479816 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49904
19:04:39 INFO - 1499479479817 Marionette DEBUG Closed connection 393
19:04:39 INFO - 1499479479817 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49905
19:04:39 INFO - 1499479479818 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:04:39 INFO - 1499479479819 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:39 INFO - 1499479479822 Marionette DEBUG Register listener.js for window 4294967297
19:04:39 INFO - 1499479479825 Marionette TRACE 394 <- [1,1,null,{"sessionId":"5ca3bb4b-73bd-4bdb-b66e-42c1e5718cad","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:39 INFO - 1499479479826 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
19:04:39 INFO - 1499479479828 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:04:39 INFO - 1499479479831 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:04:39 INFO - 1499479479831 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:04:39 INFO - 1499479479838 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
19:04:39 INFO - 1499479479841 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
19:04:39 INFO - 1499479479850 Marionette TRACE 394 <- [1,2,null,{}]
19:04:39 INFO - 1499479479854 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:04:39 INFO - 1499479479862 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fccee241-2339-48fb-8e56-cdcd602f6c51","ELEMENT":"fccee241-2339-48fb-8e56-cdcd602f6c51"}}]
19:04:39 INFO - 1499479479864 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:04:39 INFO - 1499479479866 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f93e073-6dd8-42e1-8aa1-a88924c5f8fb","ELEMENT":"6f93e073-6dd8-42e1-8aa1-a88924c5f8fb"}}]
19:04:39 INFO - 1499479479867 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"fccee241-2339-48fb-8e56-cdcd602f6c51"}]
19:04:40 INFO - 1499479480111 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:04:40 INFO - 1499479480112 Marionette TRACE 394 <- [1,5,null,{}]
19:04:40 INFO - 1499479480113 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"6f93e073-6dd8-42e1-8aa1-a88924c5f8fb"}]
19:04:40 INFO - 1499479480131 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
19:04:40 INFO - 1499479480133 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
19:04:40 INFO - 1499479480136 Marionette TRACE 394 <- [1,7,null,{"value":false}]
19:04:40 INFO - 1499479480138 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
19:04:40 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499479480141 Marionette TRACE 394 <- [1,8,null,{"value":null}]
19:04:40 INFO - 1499479480142 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
19:04:40 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 369ms
19:04:40 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:04:40 INFO - 1499479480143 Marionette TRACE 394 <- [1,9,null,{}]
19:04:40 INFO - 1499479480145 Marionette DEBUG Closed connection 394
19:04:40 INFO - 1499479480146 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49906
19:04:40 INFO - 1499479480147 Marionette DEBUG Closed connection 395
19:04:40 INFO - 1499479480148 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49907
19:04:40 INFO - 1499479480149 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:40 INFO - 1499479480149 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:40 INFO - 1499479480152 Marionette DEBUG Register listener.js for window 4294967297
19:04:40 INFO - 1499479480156 Marionette TRACE 396 <- [1,1,null,{"sessionId":"4b8015db-9c01-4382-a768-185d2ede1283","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:40 INFO - 1499479480157 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
19:04:40 INFO - 1499479480157 Marionette TRACE 396 <- [1,2,null,{}]
19:04:40 INFO - 1499479480158 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:04:40 INFO - 1499479480159 Marionette TRACE 396 <- [1,3,null,{}]
19:04:40 INFO - 1499479480160 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
19:04:40 INFO - 1499479480160 Marionette TRACE 396 <- [1,4,null,{}]
19:04:40 INFO - 1499479480162 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
19:04:40 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499479480165 Marionette TRACE 396 <- [1,5,null,{"value":null}]
19:04:40 INFO - 1499479480166 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
19:04:40 INFO - 1499479480167 Marionette TRACE 396 <- [1,6,null,{}]
19:04:40 INFO - 1499479480169 Marionette DEBUG Closed connection 396
19:04:40 INFO - 1499479480170 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49908
19:04:40 INFO - 1499479480171 Marionette DEBUG Closed connection 397
19:04:40 INFO - 1499479480172 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49909
19:04:40 INFO - 1499479480173 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:04:40 INFO - 1499479480173 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:40 INFO - 1499479480176 Marionette DEBUG Register listener.js for window 4294967297
19:04:40 INFO - 1499479480180 Marionette TRACE 398 <- [1,1,null,{"sessionId":"9ee2b3dd-be4a-4bef-a526-ac561846e593","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:40 INFO - 1499479480181 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
19:04:40 INFO - 1499479480183 Marionette TRACE 398 <- [1,2,null,{}]
19:04:40 INFO - 1499479480184 Marionette DEBUG Closed connection 398
19:04:40 INFO - 1499479480185 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49910
19:04:40 INFO - 1499479480186 Marionette DEBUG Closed connection 399
19:04:40 INFO - 1499479480187 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49911
19:04:40 INFO - 1499479480188 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:04:40 INFO - 1499479480189 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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}
19:04:40 INFO - 1499479480191 Marionette DEBUG Register listener.js for window 4294967297
19:04:40 INFO - 1499479480195 Marionette TRACE 400 <- [1,1,null,{"sessionId":"fd23c4be-f6c8-410b-85bd-f82541d7a087","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":1948,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgpehbl.mozrunner","moz:accessibilityChecks":false}}]
19:04:40 INFO - 1499479480196 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
19:04:40 INFO - 1499479480198 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
19:04:40 INFO - 1499479480200 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
19:04:40 INFO - 1499479480200 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
19:04:40 INFO - 1499479480209 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
19:04:40 INFO - 1499479480211 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
19:04:40 INFO - 1499479480219 Marionette TRACE 400 <- [1,2,null,{}]
19:04:40 INFO - 1499479480223 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:04:40 INFO - 1499479480229 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0ca9940c-b4c1-4493-8a8c-bc936aa51ea9","ELEMENT":"0ca9940c-b4c1-4493-8a8c-bc936aa51ea9"}}]
19:04:40 INFO - 1499479480230 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"0ca9940c-b4c1-4493-8a8c-bc936aa51ea9"}]
19:04:40 INFO - 1499479480260 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
19:04:40 INFO - 1499479480261 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"0ca9940c-b4c1-4493-8a8c-bc936aa51ea9"}]
19:04:40 INFO - 1499479480280 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element