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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
05:14:28 INFO - 1500466468228 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:14:28 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500466468230 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:14:28 INFO - 1500466468231 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:14:28 INFO - 1500466468232 Marionette TRACE 352 <- [1,6,null,{}]
05:14:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 122ms
05:14:28 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:14:28 INFO - 1500466468234 Marionette DEBUG Closed connection 352
05:14:28 INFO - 1500466468235 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49972
05:14:28 INFO - 1500466468236 Marionette DEBUG Closed connection 353
05:14:28 INFO - 1500466468236 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49973
05:14:28 INFO - 1500466468237 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:28 INFO - 1500466468238 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468240 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468243 Marionette TRACE 354 <- [1,1,null,{"sessionId":"e8d6170c-f50e-4089-8593-155796a8518d","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468253 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:14:28 INFO - 1500466468253 Marionette TRACE 354 <- [1,2,null,{}]
05:14:28 INFO - 1500466468254 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:14:28 INFO - 1500466468255 Marionette TRACE 354 <- [1,3,null,{}]
05:14:28 INFO - 1500466468255 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:14:28 INFO - 1500466468256 Marionette TRACE 354 <- [1,4,null,{}]
05:14:28 INFO - 1500466468257 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:14:28 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500466468260 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:14:28 INFO - 1500466468261 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:14:28 INFO - 1500466468262 Marionette TRACE 354 <- [1,6,null,{}]
05:14:28 INFO - 1500466468264 Marionette DEBUG Closed connection 354
05:14:28 INFO - 1500466468264 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49974
05:14:28 INFO - 1500466468265 Marionette DEBUG Closed connection 355
05:14:28 INFO - 1500466468266 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49975
05:14:28 INFO - 1500466468267 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:28 INFO - 1500466468267 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468269 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468272 Marionette TRACE 356 <- [1,1,null,{"sessionId":"cce375bb-7f10-4ae0-8d9c-80c756f976ce","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468273 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:14:28 INFO - 1500466468275 Marionette TRACE 356 <- [1,2,null,{}]
05:14:28 INFO - 1500466468276 Marionette DEBUG Closed connection 356
05:14:28 INFO - 1500466468277 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49976
05:14:28 INFO - 1500466468277 Marionette DEBUG Closed connection 357
05:14:28 INFO - 1500466468278 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49977
05:14:28 INFO - 1500466468279 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:14:28 INFO - 1500466468280 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468282 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468284 Marionette TRACE 358 <- [1,1,null,{"sessionId":"6d16ebeb-528e-4151-b001-65e1e4a5dc79","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468286 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49541/clicks.html"}]
05:14:28 INFO - 1500466468287 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49541/hidden.html"
05:14:28 INFO - 1500466468292 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49541/hidden.html"
05:14:28 INFO - 1500466468292 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49541/hidden.html"
05:14:28 INFO - 1500466468309 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49541/clicks.html"
05:14:28 INFO - 1500466468313 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49541/clicks.html"
05:14:28 INFO - 1500466468317 Marionette TRACE 358 <- [1,2,null,{}]
05:14:28 INFO - 1500466468324 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:14:28 INFO - 1500466468327 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"16a9cc1a-0b31-44e7-9695-6441587f1e53","ELEMENT":"16a9cc1a-0b31-44e7-9695-6441587f1e53"}}]
05:14:28 INFO - 1500466468328 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"16a9cc1a-0b31-44e7-9695-6441587f1e53"}]
05:14:28 INFO - 1500466468367 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49541/clicks.html"
05:14:28 INFO - 1500466468375 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49541/clicks.html"
05:14:28 INFO - 1500466468376 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49541/clicks.html"
05:14:28 INFO - 1500466468390 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49541/test.html"
05:14:28 INFO - 1500466468393 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49541/test.html"
05:14:28 INFO - 1500466468396 Marionette TRACE 358 <- [1,4,null,{}]
05:14:28 INFO - 1500466468403 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:14:28 INFO - 1500466468408 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7c1ca022-7600-47fb-86ae-68702d63f905","ELEMENT":"7c1ca022-7600-47fb-86ae-68702d63f905"}}]
05:14:28 INFO - 1500466468409 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:14:28 INFO - 1500466468409 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:14:28 INFO - 1500466468411 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:14:28 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500466468413 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:14:28 INFO - 1500466468414 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:14:28 INFO - 1500466468415 Marionette TRACE 358 <- [1,8,null,{}]
05:14:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 182ms
05:14:28 INFO - TEST-START | test_click.py TestClick.test_container_element
05:14:28 INFO - 1500466468417 Marionette DEBUG Closed connection 358
05:14:28 INFO - 1500466468418 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49980
05:14:28 INFO - 1500466468419 Marionette DEBUG Closed connection 359
05:14:28 INFO - 1500466468420 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49981
05:14:28 INFO - 1500466468420 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:28 INFO - 1500466468421 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468423 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468426 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b370a3ec-0a2e-4ede-8c91-193954f65622","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468427 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:14:28 INFO - 1500466468428 Marionette TRACE 360 <- [1,2,null,{}]
05:14:28 INFO - 1500466468429 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:14:28 INFO - 1500466468429 Marionette TRACE 360 <- [1,3,null,{}]
05:14:28 INFO - 1500466468430 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:14:28 INFO - 1500466468430 Marionette TRACE 360 <- [1,4,null,{}]
05:14:28 INFO - 1500466468432 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:14:28 INFO - TEST-START: test_click.py TestClick.test_container_element1500466468434 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:14:28 INFO - 1500466468435 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:14:28 INFO - 1500466468436 Marionette TRACE 360 <- [1,6,null,{}]
05:14:28 INFO - 1500466468438 Marionette DEBUG Closed connection 360
05:14:28 INFO - 1500466468439 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49982
05:14:28 INFO - 1500466468439 Marionette DEBUG Closed connection 361
05:14:28 INFO - 1500466468440 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49983
05:14:28 INFO - 1500466468441 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:28 INFO - 1500466468442 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468443 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468446 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e62c0bac-be38-4933-8340-8c77adf08575","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468448 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:14:28 INFO - 1500466468449 Marionette TRACE 362 <- [1,2,null,{}]
05:14:28 INFO - 1500466468451 Marionette DEBUG Closed connection 362
05:14:28 INFO - 1500466468451 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49984
05:14:28 INFO - 1500466468452 Marionette DEBUG Closed connection 363
05:14:28 INFO - 1500466468453 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49985
05:14:28 INFO - 1500466468454 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:14:28 INFO - 1500466468454 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468456 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468459 Marionette TRACE 364 <- [1,1,null,{"sessionId":"78fa48d1-7f4c-46b8-8f70-50954a3bae37","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468460 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:14:28 INFO - 1500466468462 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49541/test.html"
05:14:28 INFO - 1500466468465 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49541/test.html"
05:14:28 INFO - 1500466468465 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49541/test.html"
05:14:28 INFO - 1500466468493 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:28 INFO - 1500466468499 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:28 INFO - 1500466468510 Marionette TRACE 364 <- [1,2,null,{}]
05:14:28 INFO - 1500466468511 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:14:28 INFO - 1500466468513 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b6e75ef4-523e-4f06-a8ec-4023ee926cc6","ELEMENT":"b6e75ef4-523e-4f06-a8ec-4023ee926cc6"}}]
05:14:28 INFO - 1500466468514 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b6e75ef4-523e-4f06-a8ec-4023ee926cc6"}]
05:14:28 INFO - 1500466468752 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:14:28 INFO - 1500466468753 Marionette TRACE 364 <- [1,4,null,{}]
05:14:28 INFO - 1500466468754 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b6e75ef4-523e-4f06-a8ec-4023ee926cc6","name":"selected"}]
05:14:28 INFO - 1500466468759 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:14:28 INFO - 1500466468760 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:14:28 INFO - TEST-END: test_click.py TestClick.test_container_element1500466468763 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:14:28 INFO - 1500466468763 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:14:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 349ms
05:14:28 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:14:28 INFO - 1500466468765 Marionette TRACE 364 <- [1,7,null,{}]
05:14:28 INFO - 1500466468766 Marionette DEBUG Closed connection 364
05:14:28 INFO - 1500466468767 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49986
05:14:28 INFO - 1500466468768 Marionette DEBUG Closed connection 365
05:14:28 INFO - 1500466468769 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49987
05:14:28 INFO - 1500466468770 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:28 INFO - 1500466468770 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468772 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468775 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1fd9bc02-8fc9-4264-b82e-d88f3415fe7d","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468777 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:14:28 INFO - 1500466468777 Marionette TRACE 366 <- [1,2,null,{}]
05:14:28 INFO - 1500466468778 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:14:28 INFO - 1500466468778 Marionette TRACE 366 <- [1,3,null,{}]
05:14:28 INFO - 1500466468779 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:14:28 INFO - 1500466468780 Marionette TRACE 366 <- [1,4,null,{}]
05:14:28 INFO - 1500466468781 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:14:28 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500466468784 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:14:28 INFO - 1500466468784 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:14:28 INFO - 1500466468785 Marionette TRACE 366 <- [1,6,null,{}]
05:14:28 INFO - 1500466468787 Marionette DEBUG Closed connection 366
05:14:28 INFO - 1500466468788 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49988
05:14:28 INFO - 1500466468788 Marionette DEBUG Closed connection 367
05:14:28 INFO - 1500466468789 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49989
05:14:28 INFO - 1500466468790 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:28 INFO - 1500466468797 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468799 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468803 Marionette TRACE 368 <- [1,1,null,{"sessionId":"71509dea-23e2-4a81-9185-c02b1ec33442","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468805 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:14:28 INFO - 1500466468806 Marionette TRACE 368 <- [1,2,null,{}]
05:14:28 INFO - 1500466468808 Marionette DEBUG Closed connection 368
05:14:28 INFO - 1500466468809 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49990
05:14:28 INFO - 1500466468809 Marionette DEBUG Closed connection 369
05:14:28 INFO - 1500466468810 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49991
05:14:28 INFO - 1500466468811 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:14:28 INFO - 1500466468812 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:28 INFO - 1500466468814 Marionette DEBUG Register listener.js for window 15
05:14:28 INFO - 1500466468816 Marionette TRACE 370 <- [1,1,null,{"sessionId":"e94c9bb6-1fe1-4627-9d7f-44f39c644031","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:28 INFO - 1500466468818 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:14:28 INFO - 1500466468820 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:28 INFO - 1500466468822 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:28 INFO - 1500466468822 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:28 INFO - 1500466468839 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:28 INFO - 1500466468840 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:28 INFO - 1500466468843 Marionette TRACE 370 <- [1,2,null,{}]
05:14:28 INFO - 1500466468844 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:14:28 INFO - 1500466468847 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9b2de7a1-3fa5-42ab-a04c-b105b47f69c9","ELEMENT":"9b2de7a1-3fa5-42ab-a04c-b105b47f69c9"}}]
05:14:28 INFO - 1500466468853 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9b2de7a1-3fa5-42ab-a04c-b105b47f69c9"}]
05:14:29 INFO - 1500466469092 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:14:29 INFO - 1500466469092 Marionette TRACE 370 <- [1,4,null,{}]
05:14:29 INFO - 1500466469094 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9b2de7a1-3fa5-42ab-a04c-b105b47f69c9","name":"selected"}]
05:14:29 INFO - 1500466469097 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:14:29 INFO - 1500466469099 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:14:29 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500466469101 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:14:29 INFO - 1500466469102 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:14:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 339ms
05:14:29 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:14:29 INFO - 1500466469104 Marionette TRACE 370 <- [1,7,null,{}]
05:14:29 INFO - 1500466469105 Marionette DEBUG Closed connection 370
05:14:29 INFO - 1500466469106 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49992
05:14:29 INFO - 1500466469107 Marionette DEBUG Closed connection 371
05:14:29 INFO - 1500466469108 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49993
05:14:29 INFO - 1500466469109 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:29 INFO - 1500466469109 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469111 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469114 Marionette TRACE 372 <- [1,1,null,{"sessionId":"4fae4629-5bc9-4789-81e1-d34723b10b14","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469116 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:14:29 INFO - 1500466469116 Marionette TRACE 372 <- [1,2,null,{}]
05:14:29 INFO - 1500466469117 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:14:29 INFO - 1500466469118 Marionette TRACE 372 <- [1,3,null,{}]
05:14:29 INFO - 1500466469118 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:14:29 INFO - 1500466469119 Marionette TRACE 372 <- [1,4,null,{}]
05:14:29 INFO - 1500466469120 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:14:29 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500466469123 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:14:29 INFO - 1500466469123 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:14:29 INFO - 1500466469125 Marionette TRACE 372 <- [1,6,null,{}]
05:14:29 INFO - 1500466469126 Marionette DEBUG Closed connection 372
05:14:29 INFO - 1500466469127 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49994
05:14:29 INFO - 1500466469127 Marionette DEBUG Closed connection 373
05:14:29 INFO - 1500466469128 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49995
05:14:29 INFO - 1500466469129 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:29 INFO - 1500466469130 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469132 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469134 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6e6dfce0-759a-4c3a-b12d-6d7d615e5fe9","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469136 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:14:29 INFO - 1500466469137 Marionette TRACE 374 <- [1,2,null,{}]
05:14:29 INFO - 1500466469139 Marionette DEBUG Closed connection 374
05:14:29 INFO - 1500466469139 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49996
05:14:29 INFO - 1500466469140 Marionette DEBUG Closed connection 375
05:14:29 INFO - 1500466469141 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49997
05:14:29 INFO - 1500466469142 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:14:29 INFO - 1500466469142 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469144 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469147 Marionette TRACE 376 <- [1,1,null,{"sessionId":"bb709bc6-9d02-4fa0-8e2d-1fd681c42f4b","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469149 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:14:29 INFO - 1500466469151 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:29 INFO - 1500466469152 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:29 INFO - 1500466469153 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:29 INFO - 1500466469164 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:14:29 INFO - 1500466469167 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:14:29 INFO - 1500466469177 Marionette TRACE 376 <- [1,2,null,{}]
05:14:29 INFO - 1500466469179 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:14:29 INFO - 1500466469181 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1d730d9a-49d5-4206-91cf-c94f4eeb616b","ELEMENT":"1d730d9a-49d5-4206-91cf-c94f4eeb616b"}}]
05:14:29 INFO - 1500466469182 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"1d730d9a-49d5-4206-91cf-c94f4eeb616b"}]
05:14:29 INFO - 1500466469417 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:14:29 INFO - 1500466469417 Marionette TRACE 376 <- [1,4,null,{}]
05:14:29 INFO - 1500466469419 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:14:29 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500466469422 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:14:29 INFO - 1500466469422 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:14:29 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 320ms
05:14:29 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:14:29 INFO - 1500466469424 Marionette TRACE 376 <- [1,6,null,{}]
05:14:29 INFO - 1500466469425 Marionette DEBUG Closed connection 376
05:14:29 INFO - 1500466469426 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49998
05:14:29 INFO - 1500466469427 Marionette DEBUG Closed connection 377
05:14:29 INFO - 1500466469428 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49999
05:14:29 INFO - 1500466469429 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:29 INFO - 1500466469429 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469431 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469434 Marionette TRACE 378 <- [1,1,null,{"sessionId":"98c94178-e2ca-4eac-83da-473068b8016c","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469436 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:14:29 INFO - 1500466469436 Marionette TRACE 378 <- [1,2,null,{}]
05:14:29 INFO - 1500466469437 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:14:29 INFO - 1500466469438 Marionette TRACE 378 <- [1,3,null,{}]
05:14:29 INFO - 1500466469438 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:14:29 INFO - 1500466469439 Marionette TRACE 378 <- [1,4,null,{}]
05:14:29 INFO - 1500466469441 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:14:29 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500466469443 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:14:29 INFO - 1500466469444 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:14:29 INFO - 1500466469445 Marionette TRACE 378 <- [1,6,null,{}]
05:14:29 INFO - 1500466469446 Marionette DEBUG Closed connection 378
05:14:29 INFO - 1500466469447 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50000
05:14:29 INFO - 1500466469448 Marionette DEBUG Closed connection 379
05:14:29 INFO - 1500466469448 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50001
05:14:29 INFO - 1500466469449 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:29 INFO - 1500466469450 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469451 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469461 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c324c272-e3e7-4f59-9832-72e7d7469118","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469463 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:14:29 INFO - 1500466469464 Marionette TRACE 380 <- [1,2,null,{}]
05:14:29 INFO - 1500466469466 Marionette DEBUG Closed connection 380
05:14:29 INFO - 1500466469467 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50002
05:14:29 INFO - 1500466469467 Marionette DEBUG Closed connection 381
05:14:29 INFO - 1500466469468 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50003
05:14:29 INFO - 1500466469469 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:14:29 INFO - 1500466469470 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469471 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469474 Marionette TRACE 382 <- [1,1,null,{"sessionId":"4b49a4dc-4853-45b7-ad91-a5b0f22f9c9d","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469476 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:14:29 INFO - 1500466469477 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:14:29 INFO - 1500466469479 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:14:29 INFO - 1500466469479 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:14:29 INFO - 1500466469495 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:29 INFO - 1500466469498 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:29 INFO - 1500466469507 Marionette TRACE 382 <- [1,2,null,{}]
05:14:29 INFO - 1500466469508 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:14:29 INFO - 1500466469511 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4fc03b40-b9b6-49a9-9e90-4d61d1496504","ELEMENT":"4fc03b40-b9b6-49a9-9e90-4d61d1496504"}}]
05:14:29 INFO - 1500466469512 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"4fc03b40-b9b6-49a9-9e90-4d61d1496504"}]
05:14:29 INFO - 1500466469751 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:14:29 INFO - 1500466469751 Marionette TRACE 382 <- [1,4,null,{}]
05:14:29 INFO - 1500466469753 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:14:29 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500466469756 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:14:29 INFO - 1500466469756 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:14:29 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 334ms
05:14:29 INFO - TEST-START | test_click.py TestClick.test_input_file
05:14:29 INFO - 1500466469758 Marionette TRACE 382 <- [1,6,null,{}]
05:14:29 INFO - 1500466469759 Marionette DEBUG Closed connection 382
05:14:29 INFO - 1500466469760 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50004
05:14:29 INFO - 1500466469761 Marionette DEBUG Closed connection 383
05:14:29 INFO - 1500466469762 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50005
05:14:29 INFO - 1500466469763 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:29 INFO - 1500466469763 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469765 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469768 Marionette TRACE 384 <- [1,1,null,{"sessionId":"d9ff99b0-a483-45ba-ba90-fb77589f5928","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469770 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:14:29 INFO - 1500466469770 Marionette TRACE 384 <- [1,2,null,{}]
05:14:29 INFO - 1500466469771 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:14:29 INFO - 1500466469771 Marionette TRACE 384 <- [1,3,null,{}]
05:14:29 INFO - 1500466469772 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:14:29 INFO - 1500466469773 Marionette TRACE 384 <- [1,4,null,{}]
05:14:29 INFO - 1500466469774 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:14:29 INFO - TEST-START: test_click.py TestClick.test_input_file1500466469777 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:14:29 INFO - 1500466469778 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:14:29 INFO - 1500466469779 Marionette TRACE 384 <- [1,6,null,{}]
05:14:29 INFO - 1500466469780 Marionette DEBUG Closed connection 384
05:14:29 INFO - 1500466469781 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50006
05:14:29 INFO - 1500466469782 Marionette DEBUG Closed connection 385
05:14:29 INFO - 1500466469782 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50007
05:14:29 INFO - 1500466469783 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:29 INFO - 1500466469784 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469786 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469788 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6ce7ec16-f65c-4fe8-832f-ffeec142ccc8","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469790 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:14:29 INFO - 1500466469791 Marionette TRACE 386 <- [1,2,null,{}]
05:14:29 INFO - 1500466469793 Marionette DEBUG Closed connection 386
05:14:29 INFO - 1500466469793 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50008
05:14:29 INFO - 1500466469794 Marionette DEBUG Closed connection 387
05:14:29 INFO - 1500466469795 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50009
05:14:29 INFO - 1500466469796 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:14:29 INFO - 1500466469796 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469798 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469801 Marionette TRACE 388 <- [1,1,null,{"sessionId":"970afe31-eaee-4045-b1b6-95eadfc52393","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469802 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:14:29 INFO - 1500466469804 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:29 INFO - 1500466469805 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:29 INFO - 1500466469806 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:14:29 INFO - 1500466469822 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:14:29 INFO - 1500466469823 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:14:29 INFO - 1500466469826 Marionette TRACE 388 <- [1,2,null,{}]
05:14:29 INFO - 1500466469828 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:14:29 INFO - 1500466469830 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d37949fb-9e52-4048-9b5b-bc32da8cec33","ELEMENT":"d37949fb-9e52-4048-9b5b-bc32da8cec33"}}]
05:14:29 INFO - 1500466469831 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d37949fb-9e52-4048-9b5b-bc32da8cec33"}]
05:14:29 INFO - 1500466469861 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
05:14:29 INFO - 1500466469863 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:14:29 INFO - TEST-END: test_click.py TestClick.test_input_file1500466469865 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:14:29 INFO - 1500466469866 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:14:29 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 110ms
05:14:29 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:14:29 INFO - 1500466469868 Marionette TRACE 388 <- [1,6,null,{}]
05:14:29 INFO - 1500466469869 Marionette DEBUG Closed connection 388
05:14:29 INFO - 1500466469870 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50010
05:14:29 INFO - 1500466469871 Marionette DEBUG Closed connection 389
05:14:29 INFO - 1500466469872 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50011
05:14:29 INFO - 1500466469873 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:29 INFO - 1500466469873 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469875 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469878 Marionette TRACE 390 <- [1,1,null,{"sessionId":"0ee5bbde-5808-4683-8603-2fa2caa2644f","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469880 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:14:29 INFO - 1500466469880 Marionette TRACE 390 <- [1,2,null,{}]
05:14:29 INFO - 1500466469881 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:14:29 INFO - 1500466469881 Marionette TRACE 390 <- [1,3,null,{}]
05:14:29 INFO - 1500466469882 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:14:29 INFO - 1500466469882 Marionette TRACE 390 <- [1,4,null,{}]
05:14:29 INFO - 1500466469884 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:14:29 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500466469886 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:14:29 INFO - 1500466469887 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:14:29 INFO - 1500466469888 Marionette TRACE 390 <- [1,6,null,{}]
05:14:29 INFO - 1500466469890 Marionette DEBUG Closed connection 390
05:14:29 INFO - 1500466469891 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50012
05:14:29 INFO - 1500466469891 Marionette DEBUG Closed connection 391
05:14:29 INFO - 1500466469892 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50013
05:14:29 INFO - 1500466469893 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:29 INFO - 1500466469894 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469895 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469898 Marionette TRACE 392 <- [1,1,null,{"sessionId":"4d54264a-deaf-4cae-9caf-0b4b99bcc205","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469900 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:14:29 INFO - 1500466469901 Marionette TRACE 392 <- [1,2,null,{}]
05:14:29 INFO - 1500466469903 Marionette DEBUG Closed connection 392
05:14:29 INFO - 1500466469903 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50014
05:14:29 INFO - 1500466469904 Marionette DEBUG Closed connection 393
05:14:29 INFO - 1500466469905 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50015
05:14:29 INFO - 1500466469906 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:14:29 INFO - 1500466469906 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:29 INFO - 1500466469914 Marionette DEBUG Register listener.js for window 15
05:14:29 INFO - 1500466469917 Marionette TRACE 394 <- [1,1,null,{"sessionId":"761e4f7b-fa7c-4c71-81d3-e5bac9d0e1b8","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:29 INFO - 1500466469919 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:14:29 INFO - 1500466469920 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:14:29 INFO - 1500466469922 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:14:29 INFO - 1500466469922 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:14:29 INFO - 1500466469942 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:14:29 INFO - 1500466469943 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:14:29 INFO - 1500466469946 Marionette TRACE 394 <- [1,2,null,{}]
05:14:29 INFO - 1500466469953 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:14:29 INFO - 1500466469956 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ff04d6b-55c8-44cf-90bb-c566690eabf6","ELEMENT":"8ff04d6b-55c8-44cf-90bb-c566690eabf6"}}]
05:14:29 INFO - 1500466469957 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:14:29 INFO - 1500466469958 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d4f710c4-41ca-49c8-9dfd-307ddcf180cd","ELEMENT":"d4f710c4-41ca-49c8-9dfd-307ddcf180cd"}}]
05:14:29 INFO - 1500466469959 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"8ff04d6b-55c8-44cf-90bb-c566690eabf6"}]
05:14:30 INFO - 1500466470194 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:14:30 INFO - 1500466470194 Marionette TRACE 394 <- [1,5,null,{}]
05:14:30 INFO - 1500466470195 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d4f710c4-41ca-49c8-9dfd-307ddcf180cd"}]
05:14:30 INFO - 1500466470209 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
05:14:30 INFO - 1500466470210 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
05:14:30 INFO - 1500466470213 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:14:30 INFO - 1500466470214 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:14:30 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500466470217 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:14:30 INFO - 1500466470217 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:14:30 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 351ms
05:14:30 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:14:30 INFO - 1500466470219 Marionette TRACE 394 <- [1,9,null,{}]
05:14:30 INFO - 1500466470220 Marionette DEBUG Closed connection 394
05:14:30 INFO - 1500466470221 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50016
05:14:30 INFO - 1500466470222 Marionette DEBUG Closed connection 395
05:14:30 INFO - 1500466470223 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50017
05:14:30 INFO - 1500466470224 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:30 INFO - 1500466470224 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:30 INFO - 1500466470226 Marionette DEBUG Register listener.js for window 15
05:14:30 INFO - 1500466470229 Marionette TRACE 396 <- [1,1,null,{"sessionId":"dbec5248-a245-4a05-ab57-68f3a6c29755","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:30 INFO - 1500466470231 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:14:30 INFO - 1500466470231 Marionette TRACE 396 <- [1,2,null,{}]
05:14:30 INFO - 1500466470232 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:14:30 INFO - 1500466470232 Marionette TRACE 396 <- [1,3,null,{}]
05:14:30 INFO - 1500466470233 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:14:30 INFO - 1500466470234 Marionette TRACE 396 <- [1,4,null,{}]
05:14:30 INFO - 1500466470235 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:14:30 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500466470237 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:14:30 INFO - 1500466470238 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:14:30 INFO - 1500466470239 Marionette TRACE 396 <- [1,6,null,{}]
05:14:30 INFO - 1500466470241 Marionette DEBUG Closed connection 396
05:14:30 INFO - 1500466470241 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50018
05:14:30 INFO - 1500466470242 Marionette DEBUG Closed connection 397
05:14:30 INFO - 1500466470243 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50019
05:14:30 INFO - 1500466470244 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:14:30 INFO - 1500466470244 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:30 INFO - 1500466470246 Marionette DEBUG Register listener.js for window 15
05:14:30 INFO - 1500466470249 Marionette TRACE 398 <- [1,1,null,{"sessionId":"9f9b0c7c-98b1-4d96-9b8e-824c72555f42","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:30 INFO - 1500466470250 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:14:30 INFO - 1500466470251 Marionette TRACE 398 <- [1,2,null,{}]
05:14:30 INFO - 1500466470253 Marionette DEBUG Closed connection 398
05:14:30 INFO - 1500466470254 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50020
05:14:30 INFO - 1500466470254 Marionette DEBUG Closed connection 399
05:14:30 INFO - 1500466470255 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50021
05:14:30 INFO - 1500466470256 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:14:30 INFO - 1500466470256 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}
05:14:30 INFO - 1500466470258 Marionette DEBUG Register listener.js for window 15
05:14:30 INFO - 1500466470261 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e560fd9e-c10d-48fc-8344-1bf6cf82ccb1","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0za1x2.mozrunner","moz:accessibilityChecks":false}}]
05:14:30 INFO - 1500466470263 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:14:30 INFO - 1500466470264 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:14:30 INFO - 1500466470266 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:14:30 INFO - 1500466470266 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:14:30 INFO - 1500466470277 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:14:30 INFO - 1500466470280 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:14:30 INFO - 1500466470282 Marionette TRACE 400 <- [1,2,null,{}]
05:14:30 INFO - 1500466470291 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:14:30 INFO - 1500466470294 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ddd6045f-0ca4-4bf7-b859-274e1eb6560d","ELEMENT":"ddd6045f-0ca4-4bf7-b859-274e1eb6560d"}}]
05:14:30 INFO - 1500466470295 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"ddd6045f-0ca4-4bf7-b859-274e1eb6560d"}]
05:14:30 INFO - 1500466470315 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:14:30 INFO - 1500466470316 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"ddd6045f-0ca4-4bf7-b859-274e1eb6560d"}]
05:14:30 INFO - 1500466470329 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element