could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:05:24 INFO - 1499339124237 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}]
04:05:24 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499339124242 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:05:24 INFO - 1499339124243 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:05:24 INFO - 1499339124245 Marionette TRACE 352 <- [1,6,null,{}]
04:05:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 162ms
04:05:24 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:05:24 INFO - 1499339124247 Marionette DEBUG Closed connection 352
04:05:24 INFO - 1499339124248 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49923
04:05:24 INFO - 1499339124249 Marionette DEBUG Closed connection 353
04:05:24 INFO - 1499339124250 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49924
04:05:24 INFO - 1499339124251 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:24 INFO - 1499339124252 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124255 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124258 Marionette TRACE 354 <- [1,1,null,{"sessionId":"e499b345-1285-44c7-b830-c3d39bdd32cc","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124260 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:05:24 INFO - 1499339124260 Marionette TRACE 354 <- [1,2,null,{}]
04:05:24 INFO - 1499339124262 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:05:24 INFO - 1499339124262 Marionette TRACE 354 <- [1,3,null,{}]
04:05:24 INFO - 1499339124263 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:05:24 INFO - 1499339124264 Marionette TRACE 354 <- [1,4,null,{}]
04:05:24 INFO - 1499339124266 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}]
04:05:24 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499339124269 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:05:24 INFO - 1499339124270 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:05:24 INFO - 1499339124271 Marionette TRACE 354 <- [1,6,null,{}]
04:05:24 INFO - 1499339124274 Marionette DEBUG Closed connection 354
04:05:24 INFO - 1499339124275 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49925
04:05:24 INFO - 1499339124276 Marionette DEBUG Closed connection 355
04:05:24 INFO - 1499339124276 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49926
04:05:24 INFO - 1499339124277 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:24 INFO - 1499339124278 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124281 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124285 Marionette TRACE 356 <- [1,1,null,{"sessionId":"95a51764-75fb-499b-990a-fae21301c118","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124286 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:05:24 INFO - 1499339124287 Marionette TRACE 356 <- [1,2,null,{}]
04:05:24 INFO - 1499339124290 Marionette DEBUG Closed connection 356
04:05:24 INFO - 1499339124291 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49927
04:05:24 INFO - 1499339124291 Marionette DEBUG Closed connection 357
04:05:24 INFO - 1499339124292 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49928
04:05:24 INFO - 1499339124294 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:05:24 INFO - 1499339124294 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124297 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124301 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a00cc666-85f4-4eee-906c-4e98c8bfc282","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124302 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49494/clicks.html"}]
04:05:24 INFO - 1499339124304 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49494/hidden.html"
04:05:24 INFO - 1499339124315 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49494/hidden.html"
04:05:24 INFO - 1499339124315 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49494/hidden.html"
04:05:24 INFO - 1499339124335 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49494/clicks.html"
04:05:24 INFO - 1499339124343 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49494/clicks.html"
04:05:24 INFO - 1499339124356 Marionette TRACE 358 <- [1,2,null,{}]
04:05:24 INFO - 1499339124357 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:05:24 INFO - 1499339124362 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"027099d8-b137-4d2b-b365-77f70eb42f85","ELEMENT":"027099d8-b137-4d2b-b365-77f70eb42f85"}}]
04:05:24 INFO - 1499339124364 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"027099d8-b137-4d2b-b365-77f70eb42f85"}]
04:05:24 INFO - 1499339124417 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49494/clicks.html"
04:05:24 INFO - 1499339124425 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49494/clicks.html"
04:05:24 INFO - 1499339124426 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49494/clicks.html"
04:05:24 INFO - 1499339124460 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49494/test.html"
04:05:24 INFO - 1499339124471 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49494/test.html"
04:05:24 INFO - 1499339124476 Marionette TRACE 358 <- [1,4,null,{}]
04:05:24 INFO - 1499339124486 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:05:24 INFO - 1499339124489 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"85e24173-43d2-4f6e-9bd8-46e5c98c3ff6","ELEMENT":"85e24173-43d2-4f6e-9bd8-46e5c98c3ff6"}}]
04:05:24 INFO - 1499339124491 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:05:24 INFO - 1499339124491 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:05:24 INFO - 1499339124494 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}]
04:05:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499339124498 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:05:24 INFO - 1499339124500 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:05:24 INFO - 1499339124501 Marionette TRACE 358 <- [1,8,null,{}]
04:05:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 257ms
04:05:24 INFO - TEST-START | test_click.py TestClick.test_container_element
04:05:24 INFO - 1499339124504 Marionette DEBUG Closed connection 358
04:05:24 INFO - 1499339124505 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49931
04:05:24 INFO - 1499339124506 Marionette DEBUG Closed connection 359
04:05:24 INFO - 1499339124507 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49932
04:05:24 INFO - 1499339124508 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:24 INFO - 1499339124508 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124511 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124519 Marionette TRACE 360 <- [1,1,null,{"sessionId":"58cf7a0a-5b8d-42a5-a0ac-8a47049267e8","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124521 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:05:24 INFO - 1499339124521 Marionette TRACE 360 <- [1,2,null,{}]
04:05:24 INFO - 1499339124522 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:05:24 INFO - 1499339124523 Marionette TRACE 360 <- [1,3,null,{}]
04:05:24 INFO - 1499339124524 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:05:24 INFO - 1499339124525 Marionette TRACE 360 <- [1,4,null,{}]
04:05:24 INFO - 1499339124528 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}]
04:05:24 INFO - TEST-START: test_click.py TestClick.test_container_element1499339124531 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:05:24 INFO - 1499339124532 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:05:24 INFO - 1499339124534 Marionette TRACE 360 <- [1,6,null,{}]
04:05:24 INFO - 1499339124536 Marionette DEBUG Closed connection 360
04:05:24 INFO - 1499339124537 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49933
04:05:24 INFO - 1499339124538 Marionette DEBUG Closed connection 361
04:05:24 INFO - 1499339124539 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49934
04:05:24 INFO - 1499339124540 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:24 INFO - 1499339124541 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124544 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124549 Marionette TRACE 362 <- [1,1,null,{"sessionId":"26d1eda4-54d7-4598-a4a4-e07614a3ef92","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124550 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:05:24 INFO - 1499339124552 Marionette TRACE 362 <- [1,2,null,{}]
04:05:24 INFO - 1499339124554 Marionette DEBUG Closed connection 362
04:05:24 INFO - 1499339124555 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49935
04:05:24 INFO - 1499339124556 Marionette DEBUG Closed connection 363
04:05:24 INFO - 1499339124557 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49936
04:05:24 INFO - 1499339124559 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:05:24 INFO - 1499339124560 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124563 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124567 Marionette TRACE 364 <- [1,1,null,{"sessionId":"307424f2-ca69-4326-a6e7-b38e5d394809","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124568 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"}]
04:05:24 INFO - 1499339124570 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49494/test.html"
04:05:24 INFO - 1499339124573 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49494/test.html"
04:05:24 INFO - 1499339124573 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49494/test.html"
04:05:24 INFO - 1499339124599 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"
04:05:24 INFO - 1499339124605 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"
04:05:24 INFO - 1499339124609 Marionette TRACE 364 <- [1,2,null,{}]
04:05:24 INFO - 1499339124618 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:05:24 INFO - 1499339124622 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b799dc9f-f89c-48b1-ba99-9e02b7f3ec9b","ELEMENT":"b799dc9f-f89c-48b1-ba99-9e02b7f3ec9b"}}]
04:05:24 INFO - 1499339124623 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b799dc9f-f89c-48b1-ba99-9e02b7f3ec9b"}]
04:05:24 INFO - 1499339124869 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:05:24 INFO - 1499339124870 Marionette TRACE 364 <- [1,4,null,{}]
04:05:24 INFO - 1499339124871 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b799dc9f-f89c-48b1-ba99-9e02b7f3ec9b","name":"selected"}]
04:05:24 INFO - 1499339124884 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:05:24 INFO - 1499339124886 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}]
04:05:24 INFO - TEST-END: test_click.py TestClick.test_container_element1499339124889 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:05:24 INFO - 1499339124890 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:05:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 390ms
04:05:24 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:05:24 INFO - 1499339124892 Marionette TRACE 364 <- [1,7,null,{}]
04:05:24 INFO - 1499339124894 Marionette DEBUG Closed connection 364
04:05:24 INFO - 1499339124895 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49937
04:05:24 INFO - 1499339124896 Marionette DEBUG Closed connection 365
04:05:24 INFO - 1499339124897 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49938
04:05:24 INFO - 1499339124898 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:24 INFO - 1499339124899 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124901 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124905 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b20cb622-8e14-4289-b947-51040b720764","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124907 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:05:24 INFO - 1499339124907 Marionette TRACE 366 <- [1,2,null,{}]
04:05:24 INFO - 1499339124908 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:05:24 INFO - 1499339124909 Marionette TRACE 366 <- [1,3,null,{}]
04:05:24 INFO - 1499339124910 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:05:24 INFO - 1499339124910 Marionette TRACE 366 <- [1,4,null,{}]
04:05:24 INFO - 1499339124912 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}]
04:05:24 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499339124915 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:05:24 INFO - 1499339124916 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:05:24 INFO - 1499339124918 Marionette TRACE 366 <- [1,6,null,{}]
04:05:24 INFO - 1499339124920 Marionette DEBUG Closed connection 366
04:05:24 INFO - 1499339124921 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49939
04:05:24 INFO - 1499339124922 Marionette DEBUG Closed connection 367
04:05:24 INFO - 1499339124923 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49940
04:05:24 INFO - 1499339124924 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:24 INFO - 1499339124925 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124927 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124931 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cc5d639c-27a5-4320-9465-bef34f7871ef","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124933 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:05:24 INFO - 1499339124934 Marionette TRACE 368 <- [1,2,null,{}]
04:05:24 INFO - 1499339124936 Marionette DEBUG Closed connection 368
04:05:24 INFO - 1499339124937 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49941
04:05:24 INFO - 1499339124938 Marionette DEBUG Closed connection 369
04:05:24 INFO - 1499339124939 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49942
04:05:24 INFO - 1499339124940 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:05:24 INFO - 1499339124941 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:24 INFO - 1499339124943 Marionette DEBUG Register listener.js for window 12
04:05:24 INFO - 1499339124947 Marionette TRACE 370 <- [1,1,null,{"sessionId":"cc4ad73b-9cfb-4f79-917f-525f3cb3f4b4","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:24 INFO - 1499339124949 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"}]
04:05:24 INFO - 1499339124951 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"
04:05:24 INFO - 1499339124954 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"
04:05:24 INFO - 1499339124955 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"
04:05:24 INFO - 1499339124975 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"
04:05:24 INFO - 1499339124983 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"
04:05:24 INFO - 1499339124987 Marionette TRACE 370 <- [1,2,null,{}]
04:05:24 INFO - 1499339124988 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:05:24 INFO - 1499339124997 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fcde18a8-171b-4742-8068-74ed946dd53a","ELEMENT":"fcde18a8-171b-4742-8068-74ed946dd53a"}}]
04:05:25 INFO - 1499339124999 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fcde18a8-171b-4742-8068-74ed946dd53a"}]
04:05:25 INFO - 1499339125254 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:05:25 INFO - 1499339125255 Marionette TRACE 370 <- [1,4,null,{}]
04:05:25 INFO - 1499339125256 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fcde18a8-171b-4742-8068-74ed946dd53a","name":"selected"}]
04:05:25 INFO - 1499339125261 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:05:25 INFO - 1499339125263 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}]
04:05:25 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499339125266 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:05:25 INFO - 1499339125267 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:05:25 INFO - 1499339125269 Marionette TRACE 370 <- [1,7,null,{}]
04:05:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 377ms
04:05:25 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:05:25 INFO - 1499339125271 Marionette DEBUG Closed connection 370
04:05:25 INFO - 1499339125272 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49943
04:05:25 INFO - 1499339125273 Marionette DEBUG Closed connection 371
04:05:25 INFO - 1499339125274 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49944
04:05:25 INFO - 1499339125275 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:25 INFO - 1499339125275 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:25 INFO - 1499339125278 Marionette DEBUG Register listener.js for window 12
04:05:25 INFO - 1499339125282 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9e9af30f-2de1-42ee-8482-e0b20c4ed9db","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:25 INFO - 1499339125283 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:05:25 INFO - 1499339125284 Marionette TRACE 372 <- [1,2,null,{}]
04:05:25 INFO - 1499339125285 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:05:25 INFO - 1499339125285 Marionette TRACE 372 <- [1,3,null,{}]
04:05:25 INFO - 1499339125286 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:05:25 INFO - 1499339125287 Marionette TRACE 372 <- [1,4,null,{}]
04:05:25 INFO - 1499339125289 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}]
04:05:25 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499339125292 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:05:25 INFO - 1499339125293 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:05:25 INFO - 1499339125294 Marionette TRACE 372 <- [1,6,null,{}]
04:05:25 INFO - 1499339125297 Marionette DEBUG Closed connection 372
04:05:25 INFO - 1499339125298 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49945
04:05:25 INFO - 1499339125298 Marionette DEBUG Closed connection 373
04:05:25 INFO - 1499339125299 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49946
04:05:25 INFO - 1499339125300 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:25 INFO - 1499339125301 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:25 INFO - 1499339125304 Marionette DEBUG Register listener.js for window 12
04:05:25 INFO - 1499339125308 Marionette TRACE 374 <- [1,1,null,{"sessionId":"9228f419-9b1d-48bc-bcf7-a132ed99f501","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:25 INFO - 1499339125309 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:05:25 INFO - 1499339125310 Marionette TRACE 374 <- [1,2,null,{}]
04:05:25 INFO - 1499339125313 Marionette DEBUG Closed connection 374
04:05:25 INFO - 1499339125314 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49947
04:05:25 INFO - 1499339125314 Marionette DEBUG Closed connection 375
04:05:25 INFO - 1499339125315 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49948
04:05:25 INFO - 1499339125316 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:05:25 INFO - 1499339125317 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:25 INFO - 1499339125320 Marionette DEBUG Register listener.js for window 12
04:05:25 INFO - 1499339125324 Marionette TRACE 376 <- [1,1,null,{"sessionId":"661874aa-0d83-4a3d-b2ee-b27cd3bad20e","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:25 INFO - 1499339125325 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"}]
04:05:25 INFO - 1499339125327 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"
04:05:25 INFO - 1499339125330 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"
04:05:25 INFO - 1499339125330 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"
04:05:25 INFO - 1499339125352 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"
04:05:25 INFO - 1499339125355 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"
04:05:25 INFO - 1499339125359 Marionette TRACE 376 <- [1,2,null,{}]
04:05:25 INFO - 1499339125368 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:05:25 INFO - 1499339125372 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"46c09b3c-4c26-48c2-9547-946b66ef20cb","ELEMENT":"46c09b3c-4c26-48c2-9547-946b66ef20cb"}}]
04:05:25 INFO - 1499339125373 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"46c09b3c-4c26-48c2-9547-946b66ef20cb"}]
04:05:25 INFO - 1499339125615 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:05:25 INFO - 1499339125616 Marionette TRACE 376 <- [1,4,null,{}]
04:05:25 INFO - 1499339125618 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}]
04:05:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499339125621 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:05:25 INFO - 1499339125622 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:05:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 355ms
04:05:25 INFO - 1499339125624 Marionette TRACE 376 <- [1,6,null,{}]
04:05:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:05:25 INFO - 1499339125626 Marionette DEBUG Closed connection 376
04:05:25 INFO - 1499339125628 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49949
04:05:25 INFO - 1499339125629 Marionette DEBUG Closed connection 377
04:05:25 INFO - 1499339125629 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49950
04:05:25 INFO - 1499339125631 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:25 INFO - 1499339125631 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:25 INFO - 1499339125634 Marionette DEBUG Register listener.js for window 12
04:05:25 INFO - 1499339125645 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5e71819a-3b85-464e-b026-ac17c13720d0","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:25 INFO - 1499339125646 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:05:25 INFO - 1499339125647 Marionette TRACE 378 <- [1,2,null,{}]
04:05:25 INFO - 1499339125648 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:05:25 INFO - 1499339125648 Marionette TRACE 378 <- [1,3,null,{}]
04:05:25 INFO - 1499339125650 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:05:25 INFO - 1499339125651 Marionette TRACE 378 <- [1,4,null,{}]
04:05:25 INFO - 1499339125652 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}]
04:05:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499339125656 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:05:25 INFO - 1499339125657 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:05:25 INFO - 1499339125658 Marionette TRACE 378 <- [1,6,null,{}]
04:05:25 INFO - 1499339125661 Marionette DEBUG Closed connection 378
04:05:25 INFO - 1499339125662 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49951
04:05:25 INFO - 1499339125662 Marionette DEBUG Closed connection 379
04:05:25 INFO - 1499339125663 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49952
04:05:25 INFO - 1499339125665 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:25 INFO - 1499339125665 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:25 INFO - 1499339125668 Marionette DEBUG Register listener.js for window 12
04:05:25 INFO - 1499339125672 Marionette TRACE 380 <- [1,1,null,{"sessionId":"52455f1b-dc6a-4cbf-b1f5-18b2265f68ac","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:25 INFO - 1499339125673 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:05:25 INFO - 1499339125674 Marionette TRACE 380 <- [1,2,null,{}]
04:05:25 INFO - 1499339125677 Marionette DEBUG Closed connection 380
04:05:25 INFO - 1499339125678 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49953
04:05:25 INFO - 1499339125679 Marionette DEBUG Closed connection 381
04:05:25 INFO - 1499339125680 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49954
04:05:25 INFO - 1499339125681 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:05:25 INFO - 1499339125682 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:25 INFO - 1499339125684 Marionette DEBUG Register listener.js for window 12
04:05:25 INFO - 1499339125688 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ec4b72ff-8db1-4bc8-b873-57e7539b834d","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:25 INFO - 1499339125690 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"}]
04:05:25 INFO - 1499339125691 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"
04:05:25 INFO - 1499339125700 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"
04:05:25 INFO - 1499339125700 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"
04:05:25 INFO - 1499339125717 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"
04:05:25 INFO - 1499339125722 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"
04:05:25 INFO - 1499339125726 Marionette TRACE 382 <- [1,2,null,{}]
04:05:25 INFO - 1499339125736 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:05:25 INFO - 1499339125739 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"895ab9c3-6e4a-4340-868b-00e58c6f7a6f","ELEMENT":"895ab9c3-6e4a-4340-868b-00e58c6f7a6f"}}]
04:05:25 INFO - 1499339125740 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"895ab9c3-6e4a-4340-868b-00e58c6f7a6f"}]
04:05:26 INFO - 1499339125997 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:05:26 INFO - 1499339125998 Marionette TRACE 382 <- [1,4,null,{}]
04:05:26 INFO - 1499339126000 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}]
04:05:26 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499339126003 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:05:26 INFO - 1499339126004 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:05:26 INFO - 1499339126006 Marionette TRACE 382 <- [1,6,null,{}]
04:05:26 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 382ms
04:05:26 INFO - TEST-START | test_click.py TestClick.test_input_file
04:05:26 INFO - 1499339126008 Marionette DEBUG Closed connection 382
04:05:26 INFO - 1499339126010 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49955
04:05:26 INFO - 1499339126010 Marionette DEBUG Closed connection 383
04:05:26 INFO - 1499339126011 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49956
04:05:26 INFO - 1499339126012 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:26 INFO - 1499339126013 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126016 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126020 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4e8bb1bc-9044-445a-b5e9-e27bf2047c36","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126021 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:05:26 INFO - 1499339126022 Marionette TRACE 384 <- [1,2,null,{}]
04:05:26 INFO - 1499339126023 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:05:26 INFO - 1499339126023 Marionette TRACE 384 <- [1,3,null,{}]
04:05:26 INFO - 1499339126024 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:05:26 INFO - 1499339126025 Marionette TRACE 384 <- [1,4,null,{}]
04:05:26 INFO - 1499339126026 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}]
04:05:26 INFO - TEST-START: test_click.py TestClick.test_input_file1499339126029 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:05:26 INFO - 1499339126031 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:05:26 INFO - 1499339126032 Marionette TRACE 384 <- [1,6,null,{}]
04:05:26 INFO - 1499339126034 Marionette DEBUG Closed connection 384
04:05:26 INFO - 1499339126035 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49957
04:05:26 INFO - 1499339126036 Marionette DEBUG Closed connection 385
04:05:26 INFO - 1499339126037 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49958
04:05:26 INFO - 1499339126038 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:26 INFO - 1499339126039 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126042 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126045 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5d9d51b5-3e54-499f-ad46-6c22f9cdd9d6","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126047 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:05:26 INFO - 1499339126048 Marionette TRACE 386 <- [1,2,null,{}]
04:05:26 INFO - 1499339126050 Marionette DEBUG Closed connection 386
04:05:26 INFO - 1499339126051 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49959
04:05:26 INFO - 1499339126052 Marionette DEBUG Closed connection 387
04:05:26 INFO - 1499339126053 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49960
04:05:26 INFO - 1499339126054 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:05:26 INFO - 1499339126054 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126057 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126061 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9aabe80b-8352-43e4-a026-af98966e814d","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126062 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:05:26 INFO - 1499339126064 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"
04:05:26 INFO - 1499339126067 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"
04:05:26 INFO - 1499339126067 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"
04:05:26 INFO - 1499339126088 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:05:26 INFO - 1499339126093 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:05:26 INFO - 1499339126105 Marionette TRACE 388 <- [1,2,null,{}]
04:05:26 INFO - 1499339126107 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:05:26 INFO - 1499339126110 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f57e0b5-af92-4922-9880-7d303c8ee83c","ELEMENT":"3f57e0b5-af92-4922-9880-7d303c8ee83c"}}]
04:05:26 INFO - 1499339126112 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3f57e0b5-af92-4922-9880-7d303c8ee83c"}]
04:05:26 INFO - 1499339126141 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:05:26 INFO - 1499339126143 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}]
04:05:26 INFO - TEST-END: test_click.py TestClick.test_input_file1499339126146 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:05:26 INFO - 1499339126147 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:05:26 INFO - 1499339126149 Marionette TRACE 388 <- [1,6,null,{}]
04:05:26 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 142ms
04:05:26 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:05:26 INFO - 1499339126151 Marionette DEBUG Closed connection 388
04:05:26 INFO - 1499339126152 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49961
04:05:26 INFO - 1499339126153 Marionette DEBUG Closed connection 389
04:05:26 INFO - 1499339126154 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49962
04:05:26 INFO - 1499339126155 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:26 INFO - 1499339126156 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126159 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126162 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8d484c23-5134-487f-bc73-d040c07f8e87","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126164 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:05:26 INFO - 1499339126164 Marionette TRACE 390 <- [1,2,null,{}]
04:05:26 INFO - 1499339126165 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:05:26 INFO - 1499339126166 Marionette TRACE 390 <- [1,3,null,{}]
04:05:26 INFO - 1499339126167 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:05:26 INFO - 1499339126167 Marionette TRACE 390 <- [1,4,null,{}]
04:05:26 INFO - 1499339126170 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}]
04:05:26 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499339126173 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:05:26 INFO - 1499339126174 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:05:26 INFO - 1499339126176 Marionette TRACE 390 <- [1,6,null,{}]
04:05:26 INFO - 1499339126178 Marionette DEBUG Closed connection 390
04:05:26 INFO - 1499339126179 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49963
04:05:26 INFO - 1499339126180 Marionette DEBUG Closed connection 391
04:05:26 INFO - 1499339126181 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49964
04:05:26 INFO - 1499339126182 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:26 INFO - 1499339126183 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126186 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126190 Marionette TRACE 392 <- [1,1,null,{"sessionId":"80e126b3-2466-4ce0-87d4-99d4557a02c6","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126191 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:05:26 INFO - 1499339126192 Marionette TRACE 392 <- [1,2,null,{}]
04:05:26 INFO - 1499339126195 Marionette DEBUG Closed connection 392
04:05:26 INFO - 1499339126196 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49965
04:05:26 INFO - 1499339126197 Marionette DEBUG Closed connection 393
04:05:26 INFO - 1499339126197 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49966
04:05:26 INFO - 1499339126199 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:05:26 INFO - 1499339126199 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126202 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126206 Marionette TRACE 394 <- [1,1,null,{"sessionId":"8aa913dc-36e3-4df5-b6ae-a812def0cbbd","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126207 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"}]
04:05:26 INFO - 1499339126209 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:05:26 INFO - 1499339126212 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:05:26 INFO - 1499339126212 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:05:26 INFO - 1499339126238 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"
04:05:26 INFO - 1499339126240 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"
04:05:26 INFO - 1499339126244 Marionette TRACE 394 <- [1,2,null,{}]
04:05:26 INFO - 1499339126253 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:05:26 INFO - 1499339126256 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f7a51dd5-7626-44fd-9689-dbf76848dbb4","ELEMENT":"f7a51dd5-7626-44fd-9689-dbf76848dbb4"}}]
04:05:26 INFO - 1499339126257 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:05:26 INFO - 1499339126260 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"01baaf8e-9e76-4111-8151-9bb5b8430c07","ELEMENT":"01baaf8e-9e76-4111-8151-9bb5b8430c07"}}]
04:05:26 INFO - 1499339126261 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"f7a51dd5-7626-44fd-9689-dbf76848dbb4"}]
04:05:26 INFO - 1499339126518 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:05:26 INFO - 1499339126519 Marionette TRACE 394 <- [1,5,null,{}]
04:05:26 INFO - 1499339126520 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"01baaf8e-9e76-4111-8151-9bb5b8430c07"}]
04:05:26 INFO - 1499339126538 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:05:26 INFO - 1499339126540 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:05:26 INFO - 1499339126543 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:05:26 INFO - 1499339126545 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}]
04:05:26 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499339126548 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:05:26 INFO - 1499339126549 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:05:26 INFO - 1499339126550 Marionette TRACE 394 <- [1,9,null,{}]
04:05:26 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 402ms
04:05:26 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:05:26 INFO - 1499339126553 Marionette DEBUG Closed connection 394
04:05:26 INFO - 1499339126554 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49969
04:05:26 INFO - 1499339126555 Marionette DEBUG Closed connection 395
04:05:26 INFO - 1499339126556 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49970
04:05:26 INFO - 1499339126557 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:26 INFO - 1499339126557 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126560 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126565 Marionette TRACE 396 <- [1,1,null,{"sessionId":"3b25145b-5040-4ead-8d1f-fc73363cba8e","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126566 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:05:26 INFO - 1499339126567 Marionette TRACE 396 <- [1,2,null,{}]
04:05:26 INFO - 1499339126567 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:05:26 INFO - 1499339126568 Marionette TRACE 396 <- [1,3,null,{}]
04:05:26 INFO - 1499339126569 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:05:26 INFO - 1499339126569 Marionette TRACE 396 <- [1,4,null,{}]
04:05:26 INFO - 1499339126571 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}]
04:05:26 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499339126575 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:05:26 INFO - 1499339126576 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:05:26 INFO - 1499339126577 Marionette TRACE 396 <- [1,6,null,{}]
04:05:26 INFO - 1499339126580 Marionette DEBUG Closed connection 396
04:05:26 INFO - 1499339126580 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49971
04:05:26 INFO - 1499339126581 Marionette DEBUG Closed connection 397
04:05:26 INFO - 1499339126582 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49972
04:05:26 INFO - 1499339126583 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:05:26 INFO - 1499339126584 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126587 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126593 Marionette TRACE 398 <- [1,1,null,{"sessionId":"7e5f79c7-f7dc-4e0c-b221-9407fef776e0","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126594 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:05:26 INFO - 1499339126596 Marionette TRACE 398 <- [1,2,null,{}]
04:05:26 INFO - 1499339126598 Marionette DEBUG Closed connection 398
04:05:26 INFO - 1499339126599 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49973
04:05:26 INFO - 1499339126600 Marionette DEBUG Closed connection 399
04:05:26 INFO - 1499339126600 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49974
04:05:26 INFO - 1499339126602 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:05:26 INFO - 1499339126602 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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}
04:05:26 INFO - 1499339126605 Marionette DEBUG Register listener.js for window 12
04:05:26 INFO - 1499339126609 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4ffe1bc4-87bd-4579-9495-440a51fb9bac","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":196,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeo1ate.mozrunner","moz:accessibilityChecks":false}}]
04:05:26 INFO - 1499339126611 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"}]
04:05:26 INFO - 1499339126613 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"
04:05:26 INFO - 1499339126616 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"
04:05:26 INFO - 1499339126616 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"
04:05:26 INFO - 1499339126639 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"
04:05:26 INFO - 1499339126643 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"
04:05:26 INFO - 1499339126655 Marionette TRACE 400 <- [1,2,null,{}]
04:05:26 INFO - 1499339126656 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:05:26 INFO - 1499339126660 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e7ea28e-dea8-4da8-9da6-1360602e6293","ELEMENT":"6e7ea28e-dea8-4da8-9da6-1360602e6293"}}]
04:05:26 INFO - 1499339126661 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6e7ea28e-dea8-4da8-9da6-1360602e6293"}]
04:05:26 INFO - 1499339126685 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:05:26 INFO - 1499339126687 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"6e7ea28e-dea8-4da8-9da6-1360602e6293"}]
04:05:26 INFO - 1499339126706 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element