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:55:39 INFO - 1499478939164 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:55:39 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499478939166 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:55:39 INFO - 1499478939168 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:55:39 INFO - 1499478939169 Marionette TRACE 352 <- [1,6,null,{}]
18:55:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 136ms
18:55:39 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:55:39 INFO - 1499478939171 Marionette DEBUG Closed connection 352
18:55:39 INFO - 1499478939172 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50337
18:55:39 INFO - 1499478939173 Marionette DEBUG Closed connection 353
18:55:39 INFO - 1499478939174 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50338
18:55:39 INFO - 1499478939175 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:39 INFO - 1499478939175 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939178 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939182 Marionette TRACE 354 <- [1,1,null,{"sessionId":"e74b9df8-763e-4499-9824-a457d9cfdad8","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939193 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:55:39 INFO - 1499478939193 Marionette TRACE 354 <- [1,2,null,{}]
18:55:39 INFO - 1499478939194 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:55:39 INFO - 1499478939194 Marionette TRACE 354 <- [1,3,null,{}]
18:55:39 INFO - 1499478939195 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:55:39 INFO - 1499478939196 Marionette TRACE 354 <- [1,4,null,{}]
18:55:39 INFO - 1499478939197 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:55:39 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499478939200 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:55:39 INFO - 1499478939201 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:55:39 INFO - 1499478939202 Marionette TRACE 354 <- [1,6,null,{}]
18:55:39 INFO - 1499478939204 Marionette DEBUG Closed connection 354
18:55:39 INFO - 1499478939205 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50339
18:55:39 INFO - 1499478939206 Marionette DEBUG Closed connection 355
18:55:39 INFO - 1499478939206 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50340
18:55:39 INFO - 1499478939207 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:39 INFO - 1499478939208 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939210 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939212 Marionette TRACE 356 <- [1,1,null,{"sessionId":"2a134bef-f8a6-4186-ab3d-cf6a70ac7878","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939214 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:55:39 INFO - 1499478939215 Marionette TRACE 356 <- [1,2,null,{}]
18:55:39 INFO - 1499478939217 Marionette DEBUG Closed connection 356
18:55:39 INFO - 1499478939218 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50341
18:55:39 INFO - 1499478939219 Marionette DEBUG Closed connection 357
18:55:39 INFO - 1499478939219 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50342
18:55:39 INFO - 1499478939220 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:55:39 INFO - 1499478939221 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939223 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939226 Marionette TRACE 358 <- [1,1,null,{"sessionId":"9726b9fb-eca0-433e-8be9-95e7a2abc94e","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939228 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49908/clicks.html"}]
18:55:39 INFO - 1499478939229 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49908/hidden.html"
18:55:39 INFO - 1499478939234 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49908/hidden.html"
18:55:39 INFO - 1499478939235 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49908/hidden.html"
18:55:39 INFO - 1499478939251 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49908/clicks.html"
18:55:39 INFO - 1499478939257 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49908/clicks.html"
18:55:39 INFO - 1499478939267 Marionette TRACE 358 <- [1,2,null,{}]
18:55:39 INFO - 1499478939269 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:55:39 INFO - 1499478939272 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4fece9df-a221-4be8-80ac-c82c378d4a23","ELEMENT":"4fece9df-a221-4be8-80ac-c82c378d4a23"}}]
18:55:39 INFO - 1499478939273 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"4fece9df-a221-4be8-80ac-c82c378d4a23"}]
18:55:39 INFO - 1499478939313 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49908/clicks.html"
18:55:39 INFO - 1499478939318 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49908/clicks.html"
18:55:39 INFO - 1499478939318 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49908/clicks.html"
18:55:39 INFO - 1499478939336 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49908/test.html"
18:55:39 INFO - 1499478939339 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49908/test.html"
18:55:39 INFO - 1499478939349 Marionette TRACE 358 <- [1,4,null,{}]
18:55:39 INFO - 1499478939351 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:55:39 INFO - 1499478939355 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"08fff99f-cf6e-4f03-84e4-aca55a77cfc4","ELEMENT":"08fff99f-cf6e-4f03-84e4-aca55a77cfc4"}}]
18:55:39 INFO - 1499478939356 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:55:39 INFO - 1499478939357 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:55:39 INFO - 1499478939360 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:55:39 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499478939362 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:55:39 INFO - 1499478939363 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:55:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 196ms
18:55:39 INFO - TEST-START | test_click.py TestClick.test_container_element
18:55:39 INFO - 1499478939365 Marionette TRACE 358 <- [1,8,null,{}]
18:55:39 INFO - 1499478939367 Marionette DEBUG Closed connection 358
18:55:39 INFO - 1499478939368 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50345
18:55:39 INFO - 1499478939368 Marionette DEBUG Closed connection 359
18:55:39 INFO - 1499478939369 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50346
18:55:39 INFO - 1499478939370 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:39 INFO - 1499478939370 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939373 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939378 Marionette TRACE 360 <- [1,1,null,{"sessionId":"c1212a5e-e075-470c-bf7a-9c6f21b7f003","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939380 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:55:39 INFO - 1499478939381 Marionette TRACE 360 <- [1,2,null,{}]
18:55:39 INFO - 1499478939382 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:55:39 INFO - 1499478939382 Marionette TRACE 360 <- [1,3,null,{}]
18:55:39 INFO - 1499478939383 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:55:39 INFO - 1499478939383 Marionette TRACE 360 <- [1,4,null,{}]
18:55:39 INFO - 1499478939385 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:55:39 INFO - TEST-START: test_click.py TestClick.test_container_element1499478939389 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:55:39 INFO - 1499478939390 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:55:39 INFO - 1499478939391 Marionette TRACE 360 <- [1,6,null,{}]
18:55:39 INFO - 1499478939393 Marionette DEBUG Closed connection 360
18:55:39 INFO - 1499478939393 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50347
18:55:39 INFO - 1499478939394 Marionette DEBUG Closed connection 361
18:55:39 INFO - 1499478939395 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50348
18:55:39 INFO - 1499478939396 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:39 INFO - 1499478939396 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939398 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939401 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ecbdc10f-0052-420d-af1e-df97666246db","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939403 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:55:39 INFO - 1499478939404 Marionette TRACE 362 <- [1,2,null,{}]
18:55:39 INFO - 1499478939406 Marionette DEBUG Closed connection 362
18:55:39 INFO - 1499478939407 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50349
18:55:39 INFO - 1499478939408 Marionette DEBUG Closed connection 363
18:55:39 INFO - 1499478939409 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50350
18:55:39 INFO - 1499478939410 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:55:39 INFO - 1499478939410 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939412 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939415 Marionette TRACE 364 <- [1,1,null,{"sessionId":"3bdf0530-1783-41ce-962e-b818b2a81b46","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939417 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:55:39 INFO - 1499478939418 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49908/test.html"
18:55:39 INFO - 1499478939421 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49908/test.html"
18:55:39 INFO - 1499478939422 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49908/test.html"
18:55:39 INFO - 1499478939448 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:55:39 INFO - 1499478939455 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:55:39 INFO - 1499478939465 Marionette TRACE 364 <- [1,2,null,{}]
18:55:39 INFO - 1499478939467 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:55:39 INFO - 1499478939469 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa0ac8cd-f84e-448a-ba28-8d20d5d050b4","ELEMENT":"aa0ac8cd-f84e-448a-ba28-8d20d5d050b4"}}]
18:55:39 INFO - 1499478939470 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"aa0ac8cd-f84e-448a-ba28-8d20d5d050b4"}]
18:55:39 INFO - 1499478939710 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:55:39 INFO - 1499478939711 Marionette TRACE 364 <- [1,4,null,{}]
18:55:39 INFO - 1499478939713 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"aa0ac8cd-f84e-448a-ba28-8d20d5d050b4","name":"selected"}]
18:55:39 INFO - 1499478939717 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:55:39 INFO - 1499478939719 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:55:39 INFO - TEST-END: test_click.py TestClick.test_container_element1499478939721 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:55:39 INFO - 1499478939722 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:55:39 INFO - 1499478939723 Marionette TRACE 364 <- [1,7,null,{}]
18:55:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 358ms
18:55:39 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:55:39 INFO - 1499478939725 Marionette DEBUG Closed connection 364
18:55:39 INFO - 1499478939726 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50351
18:55:39 INFO - 1499478939727 Marionette DEBUG Closed connection 365
18:55:39 INFO - 1499478939728 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50352
18:55:39 INFO - 1499478939729 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:39 INFO - 1499478939729 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939731 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939734 Marionette TRACE 366 <- [1,1,null,{"sessionId":"200a82b0-addf-4ff0-a1f6-9074d47a6f1a","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939736 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:55:39 INFO - 1499478939736 Marionette TRACE 366 <- [1,2,null,{}]
18:55:39 INFO - 1499478939737 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:55:39 INFO - 1499478939737 Marionette TRACE 366 <- [1,3,null,{}]
18:55:39 INFO - 1499478939738 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:55:39 INFO - 1499478939739 Marionette TRACE 366 <- [1,4,null,{}]
18:55:39 INFO - 1499478939740 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:55:39 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499478939743 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:55:39 INFO - 1499478939744 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:55:39 INFO - 1499478939745 Marionette TRACE 366 <- [1,6,null,{}]
18:55:39 INFO - 1499478939747 Marionette DEBUG Closed connection 366
18:55:39 INFO - 1499478939748 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50353
18:55:39 INFO - 1499478939749 Marionette DEBUG Closed connection 367
18:55:39 INFO - 1499478939749 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50354
18:55:39 INFO - 1499478939750 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:39 INFO - 1499478939759 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939761 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939764 Marionette TRACE 368 <- [1,1,null,{"sessionId":"654e78de-a181-4a68-aa5c-a2612883544f","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939766 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:55:39 INFO - 1499478939767 Marionette TRACE 368 <- [1,2,null,{}]
18:55:39 INFO - 1499478939769 Marionette DEBUG Closed connection 368
18:55:39 INFO - 1499478939769 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50355
18:55:39 INFO - 1499478939770 Marionette DEBUG Closed connection 369
18:55:39 INFO - 1499478939771 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50356
18:55:39 INFO - 1499478939772 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:55:39 INFO - 1499478939773 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:39 INFO - 1499478939775 Marionette DEBUG Register listener.js for window 12
18:55:39 INFO - 1499478939777 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c455b3d9-95e6-443c-aa3e-269f6174559c","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:39 INFO - 1499478939779 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:55:39 INFO - 1499478939781 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:55:39 INFO - 1499478939783 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:55:39 INFO - 1499478939783 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:55:39 INFO - 1499478939798 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:55:39 INFO - 1499478939802 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:55:39 INFO - 1499478939805 Marionette TRACE 370 <- [1,2,null,{}]
18:55:39 INFO - 1499478939811 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:55:39 INFO - 1499478939814 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce6fa1c2-d985-46f4-accb-ac8b171747e5","ELEMENT":"ce6fa1c2-d985-46f4-accb-ac8b171747e5"}}]
18:55:39 INFO - 1499478939814 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ce6fa1c2-d985-46f4-accb-ac8b171747e5"}]
18:55:40 INFO - 1499478940055 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:55:40 INFO - 1499478940055 Marionette TRACE 370 <- [1,4,null,{}]
18:55:40 INFO - 1499478940057 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ce6fa1c2-d985-46f4-accb-ac8b171747e5","name":"selected"}]
18:55:40 INFO - 1499478940060 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:55:40 INFO - 1499478940062 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:55:40 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499478940064 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:55:40 INFO - 1499478940065 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:55:40 INFO - 1499478940066 Marionette TRACE 370 <- [1,7,null,{}]
18:55:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 342ms
18:55:40 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:55:40 INFO - 1499478940068 Marionette DEBUG Closed connection 370
18:55:40 INFO - 1499478940069 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50357
18:55:40 INFO - 1499478940070 Marionette DEBUG Closed connection 371
18:55:40 INFO - 1499478940071 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50358
18:55:40 INFO - 1499478940072 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:40 INFO - 1499478940072 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940074 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940077 Marionette TRACE 372 <- [1,1,null,{"sessionId":"5f1e62be-fc41-4e31-bcb5-b65b77d387a6","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940079 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:55:40 INFO - 1499478940079 Marionette TRACE 372 <- [1,2,null,{}]
18:55:40 INFO - 1499478940080 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:55:40 INFO - 1499478940080 Marionette TRACE 372 <- [1,3,null,{}]
18:55:40 INFO - 1499478940081 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:55:40 INFO - 1499478940081 Marionette TRACE 372 <- [1,4,null,{}]
18:55:40 INFO - 1499478940083 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:55:40 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499478940085 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:55:40 INFO - 1499478940086 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:55:40 INFO - 1499478940087 Marionette TRACE 372 <- [1,6,null,{}]
18:55:40 INFO - 1499478940089 Marionette DEBUG Closed connection 372
18:55:40 INFO - 1499478940090 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50359
18:55:40 INFO - 1499478940090 Marionette DEBUG Closed connection 373
18:55:40 INFO - 1499478940091 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50360
18:55:40 INFO - 1499478940092 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:40 INFO - 1499478940092 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940094 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940097 Marionette TRACE 374 <- [1,1,null,{"sessionId":"1e858d4e-11f2-4307-b884-697386287409","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940099 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:55:40 INFO - 1499478940100 Marionette TRACE 374 <- [1,2,null,{}]
18:55:40 INFO - 1499478940101 Marionette DEBUG Closed connection 374
18:55:40 INFO - 1499478940102 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50361
18:55:40 INFO - 1499478940103 Marionette DEBUG Closed connection 375
18:55:40 INFO - 1499478940103 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50362
18:55:40 INFO - 1499478940104 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:55:40 INFO - 1499478940105 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940107 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940110 Marionette TRACE 376 <- [1,1,null,{"sessionId":"c5a45611-f059-47c3-b9e7-2a28cdabcc0a","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940112 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:55:40 INFO - 1499478940113 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:55:40 INFO - 1499478940116 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:55:40 INFO - 1499478940116 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:55:40 INFO - 1499478940132 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:55:40 INFO - 1499478940135 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:55:40 INFO - 1499478940138 Marionette TRACE 376 <- [1,2,null,{}]
18:55:40 INFO - 1499478940146 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:55:40 INFO - 1499478940149 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f0ba3a3-dc6a-44b2-b14a-a36da635e81e","ELEMENT":"9f0ba3a3-dc6a-44b2-b14a-a36da635e81e"}}]
18:55:40 INFO - 1499478940150 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"9f0ba3a3-dc6a-44b2-b14a-a36da635e81e"}]
18:55:40 INFO - 1499478940387 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:55:40 INFO - 1499478940388 Marionette TRACE 376 <- [1,4,null,{}]
18:55:40 INFO - 1499478940390 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:55:40 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499478940392 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:55:40 INFO - 1499478940393 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:55:40 INFO - 1499478940394 Marionette TRACE 376 <- [1,6,null,{}]
18:55:40 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 327ms
18:55:40 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:55:40 INFO - 1499478940396 Marionette DEBUG Closed connection 376
18:55:40 INFO - 1499478940397 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50363
18:55:40 INFO - 1499478940398 Marionette DEBUG Closed connection 377
18:55:40 INFO - 1499478940399 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50364
18:55:40 INFO - 1499478940400 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:40 INFO - 1499478940400 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940402 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940405 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9c1c1675-fe65-4e4f-b64f-ef2efdc4fbde","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940407 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:55:40 INFO - 1499478940407 Marionette TRACE 378 <- [1,2,null,{}]
18:55:40 INFO - 1499478940408 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:55:40 INFO - 1499478940409 Marionette TRACE 378 <- [1,3,null,{}]
18:55:40 INFO - 1499478940410 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:55:40 INFO - 1499478940410 Marionette TRACE 378 <- [1,4,null,{}]
18:55:40 INFO - 1499478940412 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:55:40 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499478940414 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:55:40 INFO - 1499478940415 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:55:40 INFO - 1499478940417 Marionette TRACE 378 <- [1,6,null,{}]
18:55:40 INFO - 1499478940418 Marionette DEBUG Closed connection 378
18:55:40 INFO - 1499478940419 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50365
18:55:40 INFO - 1499478940420 Marionette DEBUG Closed connection 379
18:55:40 INFO - 1499478940421 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50366
18:55:40 INFO - 1499478940422 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:40 INFO - 1499478940422 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940424 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940435 Marionette TRACE 380 <- [1,1,null,{"sessionId":"1c18504e-11a9-453b-833b-b650759f62ac","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940437 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:55:40 INFO - 1499478940438 Marionette TRACE 380 <- [1,2,null,{}]
18:55:40 INFO - 1499478940440 Marionette DEBUG Closed connection 380
18:55:40 INFO - 1499478940440 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50367
18:55:40 INFO - 1499478940441 Marionette DEBUG Closed connection 381
18:55:40 INFO - 1499478940442 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50368
18:55:40 INFO - 1499478940443 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:55:40 INFO - 1499478940443 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940445 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940448 Marionette TRACE 382 <- [1,1,null,{"sessionId":"4dbc6026-9e81-400e-95ae-4d5970afdb54","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940450 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:55:40 INFO - 1499478940451 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:55:40 INFO - 1499478940453 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:55:40 INFO - 1499478940453 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:55:40 INFO - 1499478940470 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:55:40 INFO - 1499478940476 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:55:40 INFO - 1499478940479 Marionette TRACE 382 <- [1,2,null,{}]
18:55:40 INFO - 1499478940481 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:55:40 INFO - 1499478940483 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f2fc56c5-f9ae-41ee-b0e8-1b527049e562","ELEMENT":"f2fc56c5-f9ae-41ee-b0e8-1b527049e562"}}]
18:55:40 INFO - 1499478940484 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f2fc56c5-f9ae-41ee-b0e8-1b527049e562"}]
18:55:40 INFO - 1499478940728 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:55:40 INFO - 1499478940728 Marionette TRACE 382 <- [1,4,null,{}]
18:55:40 INFO - 1499478940730 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:55:40 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499478940733 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:55:40 INFO - 1499478940734 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:55:40 INFO - 1499478940735 Marionette TRACE 382 <- [1,6,null,{}]
18:55:40 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 340ms
18:55:40 INFO - TEST-START | test_click.py TestClick.test_input_file
18:55:40 INFO - 1499478940737 Marionette DEBUG Closed connection 382
18:55:40 INFO - 1499478940738 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50369
18:55:40 INFO - 1499478940739 Marionette DEBUG Closed connection 383
18:55:40 INFO - 1499478940740 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50370
18:55:40 INFO - 1499478940740 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:40 INFO - 1499478940741 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940743 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940746 Marionette TRACE 384 <- [1,1,null,{"sessionId":"73d7c74d-aee5-4de9-85d7-01982debf5f1","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940748 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:55:40 INFO - 1499478940748 Marionette TRACE 384 <- [1,2,null,{}]
18:55:40 INFO - 1499478940749 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:55:40 INFO - 1499478940749 Marionette TRACE 384 <- [1,3,null,{}]
18:55:40 INFO - 1499478940750 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:55:40 INFO - 1499478940751 Marionette TRACE 384 <- [1,4,null,{}]
18:55:40 INFO - 1499478940752 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:55:40 INFO - TEST-START: test_click.py TestClick.test_input_file1499478940755 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:55:40 INFO - 1499478940756 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:55:40 INFO - 1499478940757 Marionette TRACE 384 <- [1,6,null,{}]
18:55:40 INFO - 1499478940759 Marionette DEBUG Closed connection 384
18:55:40 INFO - 1499478940759 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50371
18:55:40 INFO - 1499478940760 Marionette DEBUG Closed connection 385
18:55:40 INFO - 1499478940761 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50372
18:55:40 INFO - 1499478940762 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:40 INFO - 1499478940762 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940764 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940767 Marionette TRACE 386 <- [1,1,null,{"sessionId":"2a8a7f06-fffb-4ebd-bc92-22faac414807","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940769 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:55:40 INFO - 1499478940770 Marionette TRACE 386 <- [1,2,null,{}]
18:55:40 INFO - 1499478940772 Marionette DEBUG Closed connection 386
18:55:40 INFO - 1499478940772 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50373
18:55:40 INFO - 1499478940773 Marionette DEBUG Closed connection 387
18:55:40 INFO - 1499478940774 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50374
18:55:40 INFO - 1499478940775 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:55:40 INFO - 1499478940775 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940777 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940780 Marionette TRACE 388 <- [1,1,null,{"sessionId":"523d554d-456d-4aa0-8b8d-0d87982475c8","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940782 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:55:40 INFO - 1499478940783 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:55:40 INFO - 1499478940785 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:55:40 INFO - 1499478940786 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:55:40 INFO - 1499478940803 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:55:40 INFO - 1499478940809 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:55:40 INFO - 1499478940812 Marionette TRACE 388 <- [1,2,null,{}]
18:55:40 INFO - 1499478940816 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:55:40 INFO - 1499478940818 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ee32263-dd6c-4aed-8d1c-1f7859bd24f2","ELEMENT":"2ee32263-dd6c-4aed-8d1c-1f7859bd24f2"}}]
18:55:40 INFO - 1499478940819 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2ee32263-dd6c-4aed-8d1c-1f7859bd24f2"}]
18:55:40 INFO - 1499478940850 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:55:40 INFO - 1499478940852 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:55:40 INFO - TEST-END: test_click.py TestClick.test_input_file1499478940855 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:55:40 INFO - 1499478940856 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:55:40 INFO - 1499478940857 Marionette TRACE 388 <- [1,6,null,{}]
18:55:40 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 121ms
18:55:40 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:55:40 INFO - 1499478940860 Marionette DEBUG Closed connection 388
18:55:40 INFO - 1499478940861 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50375
18:55:40 INFO - 1499478940862 Marionette DEBUG Closed connection 389
18:55:40 INFO - 1499478940862 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50376
18:55:40 INFO - 1499478940864 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:40 INFO - 1499478940864 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940866 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940869 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fb2b0d9d-6fc9-41c2-ad84-642ad1261ed3","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940871 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:55:40 INFO - 1499478940871 Marionette TRACE 390 <- [1,2,null,{}]
18:55:40 INFO - 1499478940872 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:55:40 INFO - 1499478940872 Marionette TRACE 390 <- [1,3,null,{}]
18:55:40 INFO - 1499478940873 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:55:40 INFO - 1499478940874 Marionette TRACE 390 <- [1,4,null,{}]
18:55:40 INFO - 1499478940875 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:55:40 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499478940878 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:55:40 INFO - 1499478940879 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:55:40 INFO - 1499478940880 Marionette TRACE 390 <- [1,6,null,{}]
18:55:40 INFO - 1499478940882 Marionette DEBUG Closed connection 390
18:55:40 INFO - 1499478940882 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50377
18:55:40 INFO - 1499478940883 Marionette DEBUG Closed connection 391
18:55:40 INFO - 1499478940884 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50378
18:55:40 INFO - 1499478940885 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:40 INFO - 1499478940885 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940887 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940890 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fbf61dd6-ead6-4c43-af54-ef69c0ce98fe","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940892 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:55:40 INFO - 1499478940893 Marionette TRACE 392 <- [1,2,null,{}]
18:55:40 INFO - 1499478940895 Marionette DEBUG Closed connection 392
18:55:40 INFO - 1499478940895 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50379
18:55:40 INFO - 1499478940896 Marionette DEBUG Closed connection 393
18:55:40 INFO - 1499478940897 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50380
18:55:40 INFO - 1499478940898 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:55:40 INFO - 1499478940898 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:40 INFO - 1499478940908 Marionette DEBUG Register listener.js for window 12
18:55:40 INFO - 1499478940911 Marionette TRACE 394 <- [1,1,null,{"sessionId":"b4d8abc8-a4e6-4fc4-b4e6-765a7e55d2b8","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:40 INFO - 1499478940913 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:55:40 INFO - 1499478940914 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:55:40 INFO - 1499478940917 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:55:40 INFO - 1499478940917 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:55:40 INFO - 1499478940935 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:55:40 INFO - 1499478940939 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:55:40 INFO - 1499478940948 Marionette TRACE 394 <- [1,2,null,{}]
18:55:40 INFO - 1499478940949 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:55:40 INFO - 1499478940952 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"db4a41c8-99e0-48d6-89db-c3b21ae1b097","ELEMENT":"db4a41c8-99e0-48d6-89db-c3b21ae1b097"}}]
18:55:40 INFO - 1499478940953 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:55:40 INFO - 1499478940955 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a8d173f4-a7ff-461a-a87e-e8223ba1ac96","ELEMENT":"a8d173f4-a7ff-461a-a87e-e8223ba1ac96"}}]
18:55:40 INFO - 1499478940956 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"db4a41c8-99e0-48d6-89db-c3b21ae1b097"}]
18:55:41 INFO - 1499478941192 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:55:41 INFO - 1499478941193 Marionette TRACE 394 <- [1,5,null,{}]
18:55:41 INFO - 1499478941194 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a8d173f4-a7ff-461a-a87e-e8223ba1ac96"}]
18:55:41 INFO - 1499478941208 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]
18:55:41 INFO - 1499478941210 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:55:41 INFO - 1499478941213 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:55:41 INFO - 1499478941214 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:55:41 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499478941217 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:55:41 INFO - 1499478941217 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:55:41 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 361ms
18:55:41 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:55:41 INFO - 1499478941219 Marionette TRACE 394 <- [1,9,null,{}]
18:55:41 INFO - 1499478941220 Marionette DEBUG Closed connection 394
18:55:41 INFO - 1499478941221 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50381
18:55:41 INFO - 1499478941222 Marionette DEBUG Closed connection 395
18:55:41 INFO - 1499478941223 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50382
18:55:41 INFO - 1499478941224 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:41 INFO - 1499478941224 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:41 INFO - 1499478941226 Marionette DEBUG Register listener.js for window 12
18:55:41 INFO - 1499478941229 Marionette TRACE 396 <- [1,1,null,{"sessionId":"590da0f1-7d66-43c0-a164-446241d7c8cd","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:41 INFO - 1499478941231 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:55:41 INFO - 1499478941232 Marionette TRACE 396 <- [1,2,null,{}]
18:55:41 INFO - 1499478941232 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:55:41 INFO - 1499478941233 Marionette TRACE 396 <- [1,3,null,{}]
18:55:41 INFO - 1499478941234 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:55:41 INFO - 1499478941234 Marionette TRACE 396 <- [1,4,null,{}]
18:55:41 INFO - 1499478941236 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:55:41 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499478941238 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:55:41 INFO - 1499478941239 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:55:41 INFO - 1499478941240 Marionette TRACE 396 <- [1,6,null,{}]
18:55:41 INFO - 1499478941242 Marionette DEBUG Closed connection 396
18:55:41 INFO - 1499478941243 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50383
18:55:41 INFO - 1499478941243 Marionette DEBUG Closed connection 397
18:55:41 INFO - 1499478941244 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50384
18:55:41 INFO - 1499478941245 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:55:41 INFO - 1499478941246 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:41 INFO - 1499478941248 Marionette DEBUG Register listener.js for window 12
18:55:41 INFO - 1499478941251 Marionette TRACE 398 <- [1,1,null,{"sessionId":"8446dc81-e4bd-4f41-a17d-7c65438924e6","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:41 INFO - 1499478941252 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:55:41 INFO - 1499478941254 Marionette TRACE 398 <- [1,2,null,{}]
18:55:41 INFO - 1499478941255 Marionette DEBUG Closed connection 398
18:55:41 INFO - 1499478941256 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50385
18:55:41 INFO - 1499478941257 Marionette DEBUG Closed connection 399
18:55:41 INFO - 1499478941257 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50386
18:55:41 INFO - 1499478941258 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:55:41 INFO - 1499478941259 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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}
18:55:41 INFO - 1499478941261 Marionette DEBUG Register listener.js for window 12
18:55:41 INFO - 1499478941264 Marionette TRACE 400 <- [1,1,null,{"sessionId":"95802b5a-9617-4d45-8acc-01c2983a07b2","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":3508,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvi_9ow.mozrunner","moz:accessibilityChecks":false}}]
18:55:41 INFO - 1499478941266 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:55:41 INFO - 1499478941267 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:55:41 INFO - 1499478941269 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:55:41 INFO - 1499478941270 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:55:41 INFO - 1499478941286 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:55:41 INFO - 1499478941289 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:55:41 INFO - 1499478941297 Marionette TRACE 400 <- [1,2,null,{}]
18:55:41 INFO - 1499478941299 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:55:41 INFO - 1499478941302 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e74d715f-1d95-4e73-8b06-cfc9e923f23b","ELEMENT":"e74d715f-1d95-4e73-8b06-cfc9e923f23b"}}]
18:55:41 INFO - 1499478941303 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e74d715f-1d95-4e73-8b06-cfc9e923f23b"}]
18:55:41 INFO - 1499478941324 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:55:41 INFO - 1499478941325 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e74d715f-1d95-4e73-8b06-cfc9e923f23b"}]
18:55:41 INFO - 1499478941343 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element