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:1330:5\n"},null]
20:27:04 INFO - 1498879624182 Marionette TRACE 359 -> [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}]
20:27:04 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498879624184 Marionette TRACE 359 <- [1,5,null,{"value":null}]
20:27:04 INFO - 1498879624185 Marionette TRACE 359 -> [0,6,"deleteSession",{}]
20:27:04 INFO - 1498879624187 Marionette TRACE 359 <- [1,6,null,{}]
20:27:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 139ms
20:27:04 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
20:27:04 INFO - 1498879624188 Marionette DEBUG Closed connection 359
20:27:04 INFO - 1498879624189 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50247
20:27:04 INFO - 1498879624190 Marionette DEBUG Closed connection 360
20:27:04 INFO - 1498879624191 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50248
20:27:04 INFO - 1498879624192 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:04 INFO - 1498879624192 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624195 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624197 Marionette TRACE 361 <- [1,1,null,{"sessionId":"dc400494-6fb3-4984-9689-d940829f74a6","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624199 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
20:27:04 INFO - 1498879624199 Marionette TRACE 361 <- [1,2,null,{}]
20:27:04 INFO - 1498879624200 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:27:04 INFO - 1498879624200 Marionette TRACE 361 <- [1,3,null,{}]
20:27:04 INFO - 1498879624201 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
20:27:04 INFO - 1498879624202 Marionette TRACE 361 <- [1,4,null,{}]
20:27:04 INFO - 1498879624204 Marionette TRACE 361 -> [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}]
20:27:04 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498879624206 Marionette TRACE 361 <- [1,5,null,{"value":null}]
20:27:04 INFO - 1498879624207 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
20:27:04 INFO - 1498879624209 Marionette TRACE 361 <- [1,6,null,{}]
20:27:04 INFO - 1498879624211 Marionette DEBUG Closed connection 361
20:27:04 INFO - 1498879624211 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50249
20:27:04 INFO - 1498879624214 Marionette DEBUG Closed connection 362
20:27:04 INFO - 1498879624214 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50250
20:27:04 INFO - 1498879624216 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:04 INFO - 1498879624216 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624218 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624221 Marionette TRACE 363 <- [1,1,null,{"sessionId":"8f153e61-11e8-4aab-9aea-65f9d47f7172","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624223 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
20:27:04 INFO - 1498879624224 Marionette TRACE 363 <- [1,2,null,{}]
20:27:04 INFO - 1498879624225 Marionette DEBUG Closed connection 363
20:27:04 INFO - 1498879624226 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50251
20:27:04 INFO - 1498879624227 Marionette DEBUG Closed connection 364
20:27:04 INFO - 1498879624228 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50252
20:27:04 INFO - 1498879624229 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:27:04 INFO - 1498879624229 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624231 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624234 Marionette TRACE 365 <- [1,1,null,{"sessionId":"489a4bf8-4314-4950-ac8c-c19e4adf6973","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624236 Marionette TRACE 365 -> [0,2,"get",{"url":"http://127.0.0.1:49849/clicks.html"}]
20:27:04 INFO - 1498879624238 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49849/hidden.html"
20:27:04 INFO - 1498879624243 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49849/hidden.html"
20:27:04 INFO - 1498879624244 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49849/hidden.html"
20:27:04 INFO - 1498879624263 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49849/clicks.html"
20:27:04 INFO - 1498879624265 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49849/clicks.html"
20:27:04 INFO - 1498879624269 Marionette TRACE 365 <- [1,2,null,{}]
20:27:04 INFO - 1498879624277 Marionette TRACE 365 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
20:27:04 INFO - 1498879624279 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a1bcad92-aa0a-4d66-9cd3-f2ce68332065","ELEMENT":"a1bcad92-aa0a-4d66-9cd3-f2ce68332065"}}]
20:27:04 INFO - 1498879624280 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"a1bcad92-aa0a-4d66-9cd3-f2ce68332065"}]
20:27:04 INFO - 1498879624326 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49849/clicks.html"
20:27:04 INFO - 1498879624331 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49849/clicks.html"
20:27:04 INFO - 1498879624331 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49849/clicks.html"
20:27:04 INFO - 1498879624348 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49849/test.html"
20:27:04 INFO - 1498879624351 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49849/test.html"
20:27:04 INFO - 1498879624354 Marionette TRACE 365 <- [1,4,null,{}]
20:27:04 INFO - 1498879624362 Marionette TRACE 365 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
20:27:04 INFO - 1498879624364 Marionette TRACE 365 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fabe4485-4c25-447a-abdb-15f1026ce189","ELEMENT":"fabe4485-4c25-447a-abdb-15f1026ce189"}}]
20:27:04 INFO - 1498879624365 Marionette TRACE 365 -> [0,6,"getTitle",{}]
20:27:04 INFO - 1498879624367 Marionette TRACE 365 <- [1,6,null,{"value":"Marionette Test"}]
20:27:04 INFO - 1498879624368 Marionette TRACE 365 -> [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}]
20:27:04 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498879624371 Marionette TRACE 365 <- [1,7,null,{"value":null}]
20:27:04 INFO - 1498879624373 Marionette TRACE 365 -> [0,8,"deleteSession",{}]
20:27:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 187ms
20:27:04 INFO - 1498879624374 Marionette TRACE 365 <- [1,8,null,{}]
20:27:04 INFO - TEST-START | test_click.py TestClick.test_container_element
20:27:04 INFO - 1498879624377 Marionette DEBUG Closed connection 365
20:27:04 INFO - 1498879624378 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50255
20:27:04 INFO - 1498879624379 Marionette DEBUG Closed connection 366
20:27:04 INFO - 1498879624379 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50256
20:27:04 INFO - 1498879624380 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:04 INFO - 1498879624381 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624383 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624386 Marionette TRACE 367 <- [1,1,null,{"sessionId":"9de2f2f8-a857-4a9a-916c-284d38685c02","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624390 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
20:27:04 INFO - 1498879624391 Marionette TRACE 367 <- [1,2,null,{}]
20:27:04 INFO - 1498879624392 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:27:04 INFO - 1498879624392 Marionette TRACE 367 <- [1,3,null,{}]
20:27:04 INFO - 1498879624393 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
20:27:04 INFO - 1498879624393 Marionette TRACE 367 <- [1,4,null,{}]
20:27:04 INFO - 1498879624397 Marionette TRACE 367 -> [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}]
20:27:04 INFO - TEST-START: test_click.py TestClick.test_container_element1498879624399 Marionette TRACE 367 <- [1,5,null,{"value":null}]
20:27:04 INFO - 1498879624400 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
20:27:04 INFO - 1498879624401 Marionette TRACE 367 <- [1,6,null,{}]
20:27:04 INFO - 1498879624404 Marionette DEBUG Closed connection 367
20:27:04 INFO - 1498879624405 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50257
20:27:04 INFO - 1498879624419 Marionette DEBUG Closed connection 368
20:27:04 INFO - 1498879624420 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50258
20:27:04 INFO - 1498879624421 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:04 INFO - 1498879624421 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624424 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624427 Marionette TRACE 369 <- [1,1,null,{"sessionId":"cd3a5265-dab6-4cbd-9f59-2be21c60ea0b","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624437 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
20:27:04 INFO - 1498879624438 Marionette TRACE 369 <- [1,2,null,{}]
20:27:04 INFO - 1498879624451 Marionette DEBUG Closed connection 369
20:27:04 INFO - 1498879624452 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50259
20:27:04 INFO - 1498879624459 Marionette DEBUG Closed connection 370
20:27:04 INFO - 1498879624459 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50260
20:27:04 INFO - 1498879624465 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:27:04 INFO - 1498879624466 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624468 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624473 Marionette TRACE 371 <- [1,1,null,{"sessionId":"b9fabe3c-cbb6-426c-9b05-c332a12f5982","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624474 Marionette TRACE 371 -> [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"}]
20:27:04 INFO - 1498879624475 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49849/test.html"
20:27:04 INFO - 1498879624477 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49849/test.html"
20:27:04 INFO - 1498879624478 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49849/test.html"
20:27:04 INFO - 1498879624497 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"
20:27:04 INFO - 1498879624501 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"
20:27:04 INFO - 1498879624505 Marionette TRACE 371 <- [1,2,null,{}]
20:27:04 INFO - 1498879624513 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:27:04 INFO - 1498879624516 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7035f0f6-69ec-474a-a6fd-af5f8749b3e3","ELEMENT":"7035f0f6-69ec-474a-a6fd-af5f8749b3e3"}}]
20:27:04 INFO - 1498879624517 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"7035f0f6-69ec-474a-a6fd-af5f8749b3e3"}]
20:27:04 INFO - 1498879624755 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:27:04 INFO - 1498879624756 Marionette TRACE 371 <- [1,4,null,{}]
20:27:04 INFO - 1498879624757 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"7035f0f6-69ec-474a-a6fd-af5f8749b3e3","name":"selected"}]
20:27:04 INFO - 1498879624761 Marionette TRACE 371 <- [1,5,null,{"value":true}]
20:27:04 INFO - 1498879624763 Marionette TRACE 371 -> [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}]
20:27:04 INFO - TEST-END: test_click.py TestClick.test_container_element1498879624766 Marionette TRACE 371 <- [1,6,null,{"value":null}]
20:27:04 INFO - 1498879624767 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
20:27:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 393ms
20:27:04 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
20:27:04 INFO - 1498879624768 Marionette TRACE 371 <- [1,7,null,{}]
20:27:04 INFO - 1498879624770 Marionette DEBUG Closed connection 371
20:27:04 INFO - 1498879624771 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50261
20:27:04 INFO - 1498879624771 Marionette DEBUG Closed connection 372
20:27:04 INFO - 1498879624772 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50262
20:27:04 INFO - 1498879624773 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:04 INFO - 1498879624774 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624776 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624779 Marionette TRACE 373 <- [1,1,null,{"sessionId":"32d0b7e6-3a77-4e59-8d40-06a074fa41db","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624780 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
20:27:04 INFO - 1498879624780 Marionette TRACE 373 <- [1,2,null,{}]
20:27:04 INFO - 1498879624781 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:27:04 INFO - 1498879624781 Marionette TRACE 373 <- [1,3,null,{}]
20:27:04 INFO - 1498879624782 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
20:27:04 INFO - 1498879624783 Marionette TRACE 373 <- [1,4,null,{}]
20:27:04 INFO - 1498879624785 Marionette TRACE 373 -> [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}]
20:27:04 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498879624787 Marionette TRACE 373 <- [1,5,null,{"value":null}]
20:27:04 INFO - 1498879624788 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
20:27:04 INFO - 1498879624790 Marionette TRACE 373 <- [1,6,null,{}]
20:27:04 INFO - 1498879624791 Marionette DEBUG Closed connection 373
20:27:04 INFO - 1498879624792 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50263
20:27:04 INFO - 1498879624793 Marionette DEBUG Closed connection 374
20:27:04 INFO - 1498879624793 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50264
20:27:04 INFO - 1498879624795 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:04 INFO - 1498879624795 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624797 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624800 Marionette TRACE 375 <- [1,1,null,{"sessionId":"5c431c3c-c5fc-4fd9-8850-f3e0fadec4b7","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624801 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
20:27:04 INFO - 1498879624802 Marionette TRACE 375 <- [1,2,null,{}]
20:27:04 INFO - 1498879624804 Marionette DEBUG Closed connection 375
20:27:04 INFO - 1498879624805 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50265
20:27:04 INFO - 1498879624806 Marionette DEBUG Closed connection 376
20:27:04 INFO - 1498879624806 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50266
20:27:04 INFO - 1498879624807 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:27:04 INFO - 1498879624808 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:04 INFO - 1498879624810 Marionette DEBUG Register listener.js for window 7
20:27:04 INFO - 1498879624813 Marionette TRACE 377 <- [1,1,null,{"sessionId":"25eb2f46-eafa-4977-98f1-390fc2e7df8d","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:04 INFO - 1498879624814 Marionette TRACE 377 -> [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"}]
20:27:04 INFO - 1498879624815 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"
20:27:04 INFO - 1498879624817 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"
20:27:04 INFO - 1498879624818 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"
20:27:04 INFO - 1498879624833 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"
20:27:04 INFO - 1498879624836 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"
20:27:04 INFO - 1498879624844 Marionette TRACE 377 <- [1,2,null,{}]
20:27:04 INFO - 1498879624846 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:27:04 INFO - 1498879624848 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce2568d2-a1e8-4bec-86f5-224c1896ac38","ELEMENT":"ce2568d2-a1e8-4bec-86f5-224c1896ac38"}}]
20:27:04 INFO - 1498879624849 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"ce2568d2-a1e8-4bec-86f5-224c1896ac38"}]
20:27:05 INFO - 1498879625095 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:27:05 INFO - 1498879625096 Marionette TRACE 377 <- [1,4,null,{}]
20:27:05 INFO - 1498879625097 Marionette TRACE 377 -> [0,5,"getElementProperty",{"id":"ce2568d2-a1e8-4bec-86f5-224c1896ac38","name":"selected"}]
20:27:05 INFO - 1498879625101 Marionette TRACE 377 <- [1,5,null,{"value":true}]
20:27:05 INFO - 1498879625105 Marionette TRACE 377 -> [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}]
20:27:05 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498879625108 Marionette TRACE 377 <- [1,6,null,{"value":null}]
20:27:05 INFO - 1498879625109 Marionette TRACE 377 -> [0,7,"deleteSession",{}]
20:27:05 INFO - 1498879625110 Marionette TRACE 377 <- [1,7,null,{}]
20:27:05 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 342ms
20:27:05 INFO - TEST-START | test_click.py TestClick.test_css_transforms
20:27:05 INFO - 1498879625112 Marionette DEBUG Closed connection 377
20:27:05 INFO - 1498879625113 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50267
20:27:05 INFO - 1498879625114 Marionette DEBUG Closed connection 378
20:27:05 INFO - 1498879625115 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50268
20:27:05 INFO - 1498879625116 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:05 INFO - 1498879625116 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625118 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625121 Marionette TRACE 379 <- [1,1,null,{"sessionId":"10f23c3f-b455-4220-ae93-93ca68bd2b8f","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625122 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
20:27:05 INFO - 1498879625123 Marionette TRACE 379 <- [1,2,null,{}]
20:27:05 INFO - 1498879625124 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:27:05 INFO - 1498879625124 Marionette TRACE 379 <- [1,3,null,{}]
20:27:05 INFO - 1498879625125 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
20:27:05 INFO - 1498879625125 Marionette TRACE 379 <- [1,4,null,{}]
20:27:05 INFO - 1498879625127 Marionette TRACE 379 -> [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}]
20:27:05 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498879625130 Marionette TRACE 379 <- [1,5,null,{"value":null}]
20:27:05 INFO - 1498879625131 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
20:27:05 INFO - 1498879625132 Marionette TRACE 379 <- [1,6,null,{}]
20:27:05 INFO - 1498879625133 Marionette DEBUG Closed connection 379
20:27:05 INFO - 1498879625134 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50269
20:27:05 INFO - 1498879625135 Marionette DEBUG Closed connection 380
20:27:05 INFO - 1498879625136 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50270
20:27:05 INFO - 1498879625136 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:05 INFO - 1498879625137 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625139 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625142 Marionette TRACE 381 <- [1,1,null,{"sessionId":"2a804509-8e24-4b4a-8395-e2f878955ef8","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625143 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
20:27:05 INFO - 1498879625144 Marionette TRACE 381 <- [1,2,null,{}]
20:27:05 INFO - 1498879625146 Marionette DEBUG Closed connection 381
20:27:05 INFO - 1498879625146 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50271
20:27:05 INFO - 1498879625147 Marionette DEBUG Closed connection 382
20:27:05 INFO - 1498879625148 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50272
20:27:05 INFO - 1498879625149 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:27:05 INFO - 1498879625150 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625152 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625155 Marionette TRACE 383 <- [1,1,null,{"sessionId":"5a8469d4-ee40-4630-982d-8470b80967df","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625156 Marionette TRACE 383 -> [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"}]
20:27:05 INFO - 1498879625157 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"
20:27:05 INFO - 1498879625159 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"
20:27:05 INFO - 1498879625160 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"
20:27:05 INFO - 1498879625176 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"
20:27:05 INFO - 1498879625178 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"
20:27:05 INFO - 1498879625181 Marionette TRACE 383 <- [1,2,null,{}]
20:27:05 INFO - 1498879625182 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
20:27:05 INFO - 1498879625185 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4abf34bf-e70d-4654-819a-e6283fa267a3","ELEMENT":"4abf34bf-e70d-4654-819a-e6283fa267a3"}}]
20:27:05 INFO - 1498879625193 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"4abf34bf-e70d-4654-819a-e6283fa267a3"}]
20:27:05 INFO - 1498879625429 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:27:05 INFO - 1498879625430 Marionette TRACE 383 <- [1,4,null,{}]
20:27:05 INFO - 1498879625432 Marionette TRACE 383 -> [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}]
20:27:05 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498879625434 Marionette TRACE 383 <- [1,5,null,{"value":null}]
20:27:05 INFO - 1498879625435 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
20:27:05 INFO - 1498879625436 Marionette TRACE 383 <- [1,6,null,{}]
20:27:05 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 326ms
20:27:05 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
20:27:05 INFO - 1498879625438 Marionette DEBUG Closed connection 383
20:27:05 INFO - 1498879625439 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50273
20:27:05 INFO - 1498879625440 Marionette DEBUG Closed connection 384
20:27:05 INFO - 1498879625441 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50274
20:27:05 INFO - 1498879625442 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:05 INFO - 1498879625442 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625444 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625447 Marionette TRACE 385 <- [1,1,null,{"sessionId":"1b4ce24e-1e8e-4f07-a3d2-245d7c6f0d25","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625449 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
20:27:05 INFO - 1498879625449 Marionette TRACE 385 <- [1,2,null,{}]
20:27:05 INFO - 1498879625450 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:27:05 INFO - 1498879625450 Marionette TRACE 385 <- [1,3,null,{}]
20:27:05 INFO - 1498879625451 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
20:27:05 INFO - 1498879625451 Marionette TRACE 385 <- [1,4,null,{}]
20:27:05 INFO - 1498879625453 Marionette TRACE 385 -> [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}]
20:27:05 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498879625456 Marionette TRACE 385 <- [1,5,null,{"value":null}]
20:27:05 INFO - 1498879625456 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
20:27:05 INFO - 1498879625458 Marionette TRACE 385 <- [1,6,null,{}]
20:27:05 INFO - 1498879625459 Marionette DEBUG Closed connection 385
20:27:05 INFO - 1498879625460 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50275
20:27:05 INFO - 1498879625461 Marionette DEBUG Closed connection 386
20:27:05 INFO - 1498879625461 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50276
20:27:05 INFO - 1498879625462 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:05 INFO - 1498879625463 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625465 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625468 Marionette TRACE 387 <- [1,1,null,{"sessionId":"18f99f98-19a7-4e43-8197-f79cd3ddab41","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625469 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
20:27:05 INFO - 1498879625470 Marionette TRACE 387 <- [1,2,null,{}]
20:27:05 INFO - 1498879625472 Marionette DEBUG Closed connection 387
20:27:05 INFO - 1498879625473 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50277
20:27:05 INFO - 1498879625473 Marionette DEBUG Closed connection 388
20:27:05 INFO - 1498879625474 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50278
20:27:05 INFO - 1498879625475 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:27:05 INFO - 1498879625476 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625478 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625481 Marionette TRACE 389 <- [1,1,null,{"sessionId":"74c5bf3b-31d7-4a34-81ab-08a5fa0d1469","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625482 Marionette TRACE 389 -> [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"}]
20:27:05 INFO - 1498879625483 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"
20:27:05 INFO - 1498879625485 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"
20:27:05 INFO - 1498879625485 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"
20:27:05 INFO - 1498879625500 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"
20:27:05 INFO - 1498879625504 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"
20:27:05 INFO - 1498879625513 Marionette TRACE 389 <- [1,2,null,{}]
20:27:05 INFO - 1498879625515 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
20:27:05 INFO - 1498879625518 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2715732c-877f-4939-b5e5-ccdb605d8c47","ELEMENT":"2715732c-877f-4939-b5e5-ccdb605d8c47"}}]
20:27:05 INFO - 1498879625519 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"2715732c-877f-4939-b5e5-ccdb605d8c47"}]
20:27:05 INFO - 1498879625814 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:27:05 INFO - 1498879625815 Marionette TRACE 389 <- [1,4,null,{}]
20:27:05 INFO - 1498879625818 Marionette TRACE 389 -> [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}]
20:27:05 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498879625823 Marionette TRACE 389 <- [1,5,null,{"value":null}]
20:27:05 INFO - 1498879625825 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
20:27:05 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 391ms
20:27:05 INFO - 1498879625828 Marionette TRACE 389 <- [1,6,null,{}]
20:27:05 INFO - TEST-START | test_click.py TestClick.test_input_file
20:27:05 INFO - 1498879625831 Marionette DEBUG Closed connection 389
20:27:05 INFO - 1498879625832 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50279
20:27:05 INFO - 1498879625842 Marionette DEBUG Closed connection 390
20:27:05 INFO - 1498879625842 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50280
20:27:05 INFO - 1498879625847 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:05 INFO - 1498879625850 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625852 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625859 Marionette TRACE 391 <- [1,1,null,{"sessionId":"89de444d-c07a-4826-b919-551e88e90df3","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625899 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
20:27:05 INFO - 1498879625901 Marionette TRACE 391 <- [1,2,null,{}]
20:27:05 INFO - 1498879625903 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:27:05 INFO - 1498879625905 Marionette TRACE 391 <- [1,3,null,{}]
20:27:05 INFO - 1498879625906 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
20:27:05 INFO - 1498879625907 Marionette TRACE 391 <- [1,4,null,{}]
20:27:05 INFO - 1498879625909 Marionette TRACE 391 -> [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}]
20:27:05 INFO - TEST-START: test_click.py TestClick.test_input_file1498879625915 Marionette TRACE 391 <- [1,5,null,{"value":null}]
20:27:05 INFO - 1498879625916 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
20:27:05 INFO - 1498879625919 Marionette TRACE 391 <- [1,6,null,{}]
20:27:05 INFO - 1498879625923 Marionette DEBUG Closed connection 391
20:27:05 INFO - 1498879625924 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50281
20:27:05 INFO - 1498879625925 Marionette DEBUG Closed connection 392
20:27:05 INFO - 1498879625926 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50282
20:27:05 INFO - 1498879625927 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:05 INFO - 1498879625928 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625931 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625935 Marionette TRACE 393 <- [1,1,null,{"sessionId":"1b3568b2-4505-4fb4-9c4d-bd1250091147","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625937 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
20:27:05 INFO - 1498879625939 Marionette TRACE 393 <- [1,2,null,{}]
20:27:05 INFO - 1498879625941 Marionette DEBUG Closed connection 393
20:27:05 INFO - 1498879625942 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50283
20:27:05 INFO - 1498879625943 Marionette DEBUG Closed connection 394
20:27:05 INFO - 1498879625943 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50284
20:27:05 INFO - 1498879625946 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:27:05 INFO - 1498879625947 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:05 INFO - 1498879625949 Marionette DEBUG Register listener.js for window 7
20:27:05 INFO - 1498879625953 Marionette TRACE 395 <- [1,1,null,{"sessionId":"ba470f33-2674-46f3-8dcd-2a5b50331ca4","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:05 INFO - 1498879625955 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
20:27:05 INFO - 1498879625957 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"
20:27:05 INFO - 1498879625960 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"
20:27:05 INFO - 1498879625962 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"
20:27:05 INFO - 1498879625982 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:27:05 INFO - 1498879625988 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:27:06 INFO - 1498879626000 Marionette TRACE 395 <- [1,2,null,{}]
20:27:06 INFO - 1498879626001 Marionette TRACE 395 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
20:27:06 INFO - 1498879626006 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bebac759-520e-490f-9c2f-b435ea1d7e81","ELEMENT":"bebac759-520e-490f-9c2f-b435ea1d7e81"}}]
20:27:06 INFO - 1498879626011 Marionette TRACE 395 -> [0,4,"clickElement",{"id":"bebac759-520e-490f-9c2f-b435ea1d7e81"}]
20:27:06 INFO - 1498879626060 Marionette TRACE 395 <- [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:1330:5\n"},null]
20:27:06 INFO - 1498879626062 Marionette TRACE 395 -> [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}]
20:27:06 INFO - TEST-END: test_click.py TestClick.test_input_file1498879626065 Marionette TRACE 395 <- [1,5,null,{"value":null}]
20:27:06 INFO - 1498879626066 Marionette TRACE 395 -> [0,6,"deleteSession",{}]
20:27:06 INFO - 1498879626068 Marionette TRACE 395 <- [1,6,null,{}]
20:27:06 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 238ms
20:27:06 INFO - TEST-START | test_click.py TestClick.test_obscured_element
20:27:06 INFO - 1498879626070 Marionette DEBUG Closed connection 395
20:27:06 INFO - 1498879626071 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50285
20:27:06 INFO - 1498879626072 Marionette DEBUG Closed connection 396
20:27:06 INFO - 1498879626073 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50286
20:27:06 INFO - 1498879626082 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:06 INFO - 1498879626083 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:06 INFO - 1498879626086 Marionette DEBUG Register listener.js for window 7
20:27:06 INFO - 1498879626095 Marionette TRACE 397 <- [1,1,null,{"sessionId":"77e8a588-f5e2-401c-9f58-24d3e58b18d8","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:06 INFO - 1498879626099 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
20:27:06 INFO - 1498879626100 Marionette TRACE 397 <- [1,2,null,{}]
20:27:06 INFO - 1498879626117 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:27:06 INFO - 1498879626118 Marionette TRACE 397 <- [1,3,null,{}]
20:27:06 INFO - 1498879626119 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
20:27:06 INFO - 1498879626120 Marionette TRACE 397 <- [1,4,null,{}]
20:27:06 INFO - 1498879626123 Marionette TRACE 397 -> [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}]
20:27:06 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498879626126 Marionette TRACE 397 <- [1,5,null,{"value":null}]
20:27:06 INFO - 1498879626127 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
20:27:06 INFO - 1498879626128 Marionette TRACE 397 <- [1,6,null,{}]
20:27:06 INFO - 1498879626159 Marionette DEBUG Closed connection 397
20:27:06 INFO - 1498879626160 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50287
20:27:06 INFO - 1498879626169 Marionette DEBUG Closed connection 398
20:27:06 INFO - 1498879626170 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50288
20:27:06 INFO - 1498879626171 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:06 INFO - 1498879626172 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:06 INFO - 1498879626174 Marionette DEBUG Register listener.js for window 7
20:27:06 INFO - 1498879626177 Marionette TRACE 399 <- [1,1,null,{"sessionId":"8be1b39e-ef6b-4960-bc71-878e48e1a255","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:06 INFO - 1498879626180 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
20:27:06 INFO - 1498879626181 Marionette TRACE 399 <- [1,2,null,{}]
20:27:06 INFO - 1498879626184 Marionette DEBUG Closed connection 399
20:27:06 INFO - 1498879626186 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50289
20:27:06 INFO - 1498879626191 Marionette DEBUG Closed connection 400
20:27:06 INFO - 1498879626192 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50290
20:27:06 INFO - 1498879626193 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:27:06 INFO - 1498879626195 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:06 INFO - 1498879626198 Marionette DEBUG Register listener.js for window 7
20:27:06 INFO - 1498879626202 Marionette TRACE 401 <- [1,1,null,{"sessionId":"c3ecd7b9-706d-4928-9e76-3525505fe615","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:06 INFO - 1498879626203 Marionette TRACE 401 -> [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"}]
20:27:06 INFO - 1498879626204 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:27:06 INFO - 1498879626207 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:27:06 INFO - 1498879626208 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:27:06 INFO - 1498879626231 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"
20:27:06 INFO - 1498879626234 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"
20:27:06 INFO - 1498879626237 Marionette TRACE 401 <- [1,2,null,{}]
20:27:06 INFO - 1498879626245 Marionette TRACE 401 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
20:27:06 INFO - 1498879626247 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f9d460e3-36ba-4eec-97dc-52b3d83eada0","ELEMENT":"f9d460e3-36ba-4eec-97dc-52b3d83eada0"}}]
20:27:06 INFO - 1498879626248 Marionette TRACE 401 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
20:27:06 INFO - 1498879626251 Marionette TRACE 401 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c78467fd-4295-415d-8beb-1976c254162c","ELEMENT":"c78467fd-4295-415d-8beb-1976c254162c"}}]
20:27:06 INFO - 1498879626253 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"f9d460e3-36ba-4eec-97dc-52b3d83eada0"}]
20:27:06 INFO - 1498879626491 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:27:06 INFO - 1498879626492 Marionette TRACE 401 <- [1,5,null,{}]
20:27:06 INFO - 1498879626493 Marionette TRACE 401 -> [0,6,"clickElement",{"id":"c78467fd-4295-415d-8beb-1976c254162c"}]
20:27:06 INFO - 1498879626508 Marionette TRACE 401 <- [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:1330:5\n"},null]
20:27:06 INFO - 1498879626510 Marionette TRACE 401 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
20:27:06 INFO - 1498879626513 Marionette TRACE 401 <- [1,7,null,{"value":false}]
20:27:06 INFO - 1498879626515 Marionette TRACE 401 -> [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}]
20:27:06 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498879626518 Marionette TRACE 401 <- [1,8,null,{"value":null}]
20:27:06 INFO - 1498879626519 Marionette TRACE 401 -> [0,9,"deleteSession",{}]
20:27:06 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 452ms
20:27:06 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
20:27:06 INFO - 1498879626520 Marionette TRACE 401 <- [1,9,null,{}]
20:27:06 INFO - 1498879626522 Marionette DEBUG Closed connection 401
20:27:06 INFO - 1498879626523 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50291
20:27:06 INFO - 1498879626524 Marionette DEBUG Closed connection 402
20:27:06 INFO - 1498879626524 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50292
20:27:06 INFO - 1498879626526 Marionette TRACE 403 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:06 INFO - 1498879626526 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:06 INFO - 1498879626528 Marionette DEBUG Register listener.js for window 7
20:27:06 INFO - 1498879626532 Marionette TRACE 403 <- [1,1,null,{"sessionId":"fa17a563-f1bb-415b-a7f0-607b1cc5dddf","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:06 INFO - 1498879626533 Marionette TRACE 403 -> [0,2,"setTimeouts",{"script":30000}]
20:27:06 INFO - 1498879626533 Marionette TRACE 403 <- [1,2,null,{}]
20:27:06 INFO - 1498879626534 Marionette TRACE 403 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:27:06 INFO - 1498879626535 Marionette TRACE 403 <- [1,3,null,{}]
20:27:06 INFO - 1498879626535 Marionette TRACE 403 -> [0,4,"setTimeouts",{"implicit":0}]
20:27:06 INFO - 1498879626536 Marionette TRACE 403 <- [1,4,null,{}]
20:27:06 INFO - 1498879626538 Marionette TRACE 403 -> [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}]
20:27:06 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498879626540 Marionette TRACE 403 <- [1,5,null,{"value":null}]
20:27:06 INFO - 1498879626541 Marionette TRACE 403 -> [0,6,"deleteSession",{}]
20:27:06 INFO - 1498879626543 Marionette TRACE 403 <- [1,6,null,{}]
20:27:06 INFO - 1498879626544 Marionette DEBUG Closed connection 403
20:27:06 INFO - 1498879626545 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50293
20:27:06 INFO - 1498879626546 Marionette DEBUG Closed connection 404
20:27:06 INFO - 1498879626547 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50294
20:27:06 INFO - 1498879626547 Marionette TRACE 405 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:27:06 INFO - 1498879626548 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:06 INFO - 1498879626550 Marionette DEBUG Register listener.js for window 7
20:27:06 INFO - 1498879626554 Marionette TRACE 405 <- [1,1,null,{"sessionId":"cf77c382-e0e5-4622-8aa8-6a2b8380874b","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:06 INFO - 1498879626555 Marionette TRACE 405 -> [0,2,"deleteSession",{}]
20:27:06 INFO - 1498879626556 Marionette TRACE 405 <- [1,2,null,{}]
20:27:06 INFO - 1498879626558 Marionette DEBUG Closed connection 405
20:27:06 INFO - 1498879626558 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50295
20:27:06 INFO - 1498879626559 Marionette DEBUG Closed connection 406
20:27:06 INFO - 1498879626560 Marionette DEBUG Accepted connection 407 from 127.0.0.1:50296
20:27:06 INFO - 1498879626561 Marionette TRACE 407 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:27:06 INFO - 1498879626562 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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}
20:27:06 INFO - 1498879626564 Marionette DEBUG Register listener.js for window 7
20:27:06 INFO - 1498879626567 Marionette TRACE 407 <- [1,1,null,{"sessionId":"43af36a0-5d25-4c2e-a071-5d0a182680f3","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":2780,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpydoku0.mozrunner","moz:accessibilityChecks":false}}]
20:27:06 INFO - 1498879626569 Marionette TRACE 407 -> [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"}]
20:27:06 INFO - 1498879626570 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"
20:27:06 INFO - 1498879626573 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"
20:27:06 INFO - 1498879626573 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"
20:27:06 INFO - 1498879626592 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"
20:27:06 INFO - 1498879626594 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"
20:27:06 INFO - 1498879626598 Marionette TRACE 407 <- [1,2,null,{}]
20:27:06 INFO - 1498879626599 Marionette TRACE 407 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
20:27:06 INFO - 1498879626602 Marionette TRACE 407 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c5d4d3d7-80ef-4070-8b3d-5d77b5022cea","ELEMENT":"c5d4d3d7-80ef-4070-8b3d-5d77b5022cea"}}]
20:27:06 INFO - 1498879626610 Marionette TRACE 407 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c5d4d3d7-80ef-4070-8b3d-5d77b5022cea"}]
20:27:06 INFO - 1498879626632 Marionette TRACE 407 <- [1,4,null,{"value":"none"}]
20:27:06 INFO - 1498879626633 Marionette TRACE 407 -> [0,5,"clickElement",{"id":"c5d4d3d7-80ef-4070-8b3d-5d77b5022cea"}]
20:27:06 INFO - 1498879626649 Marionette TRACE 407 <- [1,5,{"error":"element click intercepted","message":"Element