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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
12:45:26 INFO - 1500925526324 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}]
12:45:26 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500925526327 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:45:26 INFO - 1500925526328 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:45:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 147ms
12:45:26 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:45:26 INFO - 1500925526329 Marionette TRACE 352 <- [1,6,null,{}]
12:45:26 INFO - 1500925526331 Marionette DEBUG Closed connection 352
12:45:26 INFO - 1500925526332 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50009
12:45:26 INFO - 1500925526333 Marionette DEBUG Closed connection 353
12:45:26 INFO - 1500925526334 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50010
12:45:26 INFO - 1500925526335 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:26 INFO - 1500925526335 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526338 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526341 Marionette TRACE 354 <- [1,1,null,{"sessionId":"d75ba5b4-7569-4fb8-928b-abde3ee4849d","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526343 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:45:26 INFO - 1500925526343 Marionette TRACE 354 <- [1,2,null,{}]
12:45:26 INFO - 1500925526344 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:26 INFO - 1500925526344 Marionette TRACE 354 <- [1,3,null,{}]
12:45:26 INFO - 1500925526346 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:26 INFO - 1500925526347 Marionette TRACE 354 <- [1,4,null,{}]
12:45:26 INFO - 1500925526348 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}]
12:45:26 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500925526351 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:45:26 INFO - 1500925526352 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:45:26 INFO - 1500925526353 Marionette TRACE 354 <- [1,6,null,{}]
12:45:26 INFO - 1500925526355 Marionette DEBUG Closed connection 354
12:45:26 INFO - 1500925526355 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50011
12:45:26 INFO - 1500925526356 Marionette DEBUG Closed connection 355
12:45:26 INFO - 1500925526357 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50012
12:45:26 INFO - 1500925526358 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:26 INFO - 1500925526358 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526360 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526362 Marionette TRACE 356 <- [1,1,null,{"sessionId":"3f9f44de-0168-476a-b52f-f99c94e2bd21","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526364 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:45:26 INFO - 1500925526366 Marionette TRACE 356 <- [1,2,null,{}]
12:45:26 INFO - 1500925526367 Marionette DEBUG Closed connection 356
12:45:26 INFO - 1500925526368 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50013
12:45:26 INFO - 1500925526369 Marionette DEBUG Closed connection 357
12:45:26 INFO - 1500925526370 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50014
12:45:26 INFO - 1500925526370 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:26 INFO - 1500925526371 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526373 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526375 Marionette TRACE 358 <- [1,1,null,{"sessionId":"55734161-d5eb-411a-8137-c60c3a5474b2","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526377 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49575/clicks.html"}]
12:45:26 INFO - 1500925526378 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49575/hidden.html"
12:45:26 INFO - 1500925526384 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49575/hidden.html"
12:45:26 INFO - 1500925526384 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49575/hidden.html"
12:45:26 INFO - 1500925526401 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49575/clicks.html"
12:45:26 INFO - 1500925526406 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49575/clicks.html"
12:45:26 INFO - 1500925526410 Marionette TRACE 358 <- [1,2,null,{}]
12:45:26 INFO - 1500925526411 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:45:26 INFO - 1500925526414 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5473fe81-38bd-467b-82db-f06b6cb3430a","ELEMENT":"5473fe81-38bd-467b-82db-f06b6cb3430a"}}]
12:45:26 INFO - 1500925526414 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5473fe81-38bd-467b-82db-f06b6cb3430a"}]
12:45:26 INFO - 1500925526455 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49575/clicks.html"
12:45:26 INFO - 1500925526463 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49575/clicks.html"
12:45:26 INFO - 1500925526464 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49575/clicks.html"
12:45:26 INFO - 1500925526481 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49575/test.html"
12:45:26 INFO - 1500925526483 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49575/test.html"
12:45:26 INFO - 1500925526486 Marionette TRACE 358 <- [1,4,null,{}]
12:45:26 INFO - 1500925526496 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:45:26 INFO - 1500925526500 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4519c9b-3490-4048-9a90-7d3336b05927","ELEMENT":"a4519c9b-3490-4048-9a90-7d3336b05927"}}]
12:45:26 INFO - 1500925526501 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:45:26 INFO - 1500925526502 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:45:26 INFO - 1500925526504 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}]
12:45:26 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500925526507 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:45:26 INFO - 1500925526508 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:45:26 INFO - 1500925526509 Marionette TRACE 358 <- [1,8,null,{}]
12:45:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 179ms
12:45:26 INFO - TEST-START | test_click.py TestClick.test_container_element
12:45:26 INFO - 1500925526511 Marionette DEBUG Closed connection 358
12:45:26 INFO - 1500925526512 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50017
12:45:26 INFO - 1500925526512 Marionette DEBUG Closed connection 359
12:45:26 INFO - 1500925526513 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50018
12:45:26 INFO - 1500925526514 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:26 INFO - 1500925526514 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526516 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526519 Marionette TRACE 360 <- [1,1,null,{"sessionId":"ed5a1111-7e6f-45d6-8476-5a37b0d5bbbd","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526522 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:45:26 INFO - 1500925526523 Marionette TRACE 360 <- [1,2,null,{}]
12:45:26 INFO - 1500925526523 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:26 INFO - 1500925526524 Marionette TRACE 360 <- [1,3,null,{}]
12:45:26 INFO - 1500925526525 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:26 INFO - 1500925526525 Marionette TRACE 360 <- [1,4,null,{}]
12:45:26 INFO - 1500925526527 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}]
12:45:26 INFO - TEST-START: test_click.py TestClick.test_container_element1500925526529 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:45:26 INFO - 1500925526530 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:45:26 INFO - 1500925526531 Marionette TRACE 360 <- [1,6,null,{}]
12:45:26 INFO - 1500925526533 Marionette DEBUG Closed connection 360
12:45:26 INFO - 1500925526533 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50019
12:45:26 INFO - 1500925526534 Marionette DEBUG Closed connection 361
12:45:26 INFO - 1500925526535 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50020
12:45:26 INFO - 1500925526536 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:26 INFO - 1500925526536 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526538 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526541 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f1c92271-bb4c-4f4c-8c22-79b626037a60","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526543 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:45:26 INFO - 1500925526544 Marionette TRACE 362 <- [1,2,null,{}]
12:45:26 INFO - 1500925526546 Marionette DEBUG Closed connection 362
12:45:26 INFO - 1500925526546 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50021
12:45:26 INFO - 1500925526547 Marionette DEBUG Closed connection 363
12:45:26 INFO - 1500925526548 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50022
12:45:26 INFO - 1500925526549 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:26 INFO - 1500925526549 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526551 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526554 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a09e9659-9075-4279-9375-816f41525258","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526556 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"}]
12:45:26 INFO - 1500925526557 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49575/test.html"
12:45:26 INFO - 1500925526560 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49575/test.html"
12:45:26 INFO - 1500925526560 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49575/test.html"
12:45:26 INFO - 1500925526590 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"
12:45:26 INFO - 1500925526594 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"
12:45:26 INFO - 1500925526599 Marionette TRACE 364 <- [1,2,null,{}]
12:45:26 INFO - 1500925526600 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:45:26 INFO - 1500925526609 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8e2c9c1-6b80-490e-8710-4bc11bb27c31","ELEMENT":"e8e2c9c1-6b80-490e-8710-4bc11bb27c31"}}]
12:45:26 INFO - 1500925526611 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e8e2c9c1-6b80-490e-8710-4bc11bb27c31"}]
12:45:26 INFO - 1500925526856 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:26 INFO - 1500925526858 Marionette TRACE 364 <- [1,4,null,{}]
12:45:26 INFO - 1500925526859 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e8e2c9c1-6b80-490e-8710-4bc11bb27c31","name":"selected"}]
12:45:26 INFO - 1500925526863 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:45:26 INFO - 1500925526864 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}]
12:45:26 INFO - TEST-END: test_click.py TestClick.test_container_element1500925526867 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:45:26 INFO - 1500925526868 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:45:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 360ms
12:45:26 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:45:26 INFO - 1500925526869 Marionette TRACE 364 <- [1,7,null,{}]
12:45:26 INFO - 1500925526871 Marionette DEBUG Closed connection 364
12:45:26 INFO - 1500925526872 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50023
12:45:26 INFO - 1500925526873 Marionette DEBUG Closed connection 365
12:45:26 INFO - 1500925526873 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50024
12:45:26 INFO - 1500925526874 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:26 INFO - 1500925526875 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526877 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526879 Marionette TRACE 366 <- [1,1,null,{"sessionId":"5300f8a1-fff8-4853-84c3-500047bc9f02","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526881 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:45:26 INFO - 1500925526881 Marionette TRACE 366 <- [1,2,null,{}]
12:45:26 INFO - 1500925526882 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:26 INFO - 1500925526883 Marionette TRACE 366 <- [1,3,null,{}]
12:45:26 INFO - 1500925526884 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:26 INFO - 1500925526884 Marionette TRACE 366 <- [1,4,null,{}]
12:45:26 INFO - 1500925526886 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}]
12:45:26 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500925526888 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:45:26 INFO - 1500925526889 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:45:26 INFO - 1500925526890 Marionette TRACE 366 <- [1,6,null,{}]
12:45:26 INFO - 1500925526892 Marionette DEBUG Closed connection 366
12:45:26 INFO - 1500925526892 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50025
12:45:26 INFO - 1500925526893 Marionette DEBUG Closed connection 367
12:45:26 INFO - 1500925526894 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50026
12:45:26 INFO - 1500925526895 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:26 INFO - 1500925526895 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526897 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526899 Marionette TRACE 368 <- [1,1,null,{"sessionId":"5775d4c9-cac8-4521-9067-bad77c27ffd7","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526902 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:45:26 INFO - 1500925526903 Marionette TRACE 368 <- [1,2,null,{}]
12:45:26 INFO - 1500925526905 Marionette DEBUG Closed connection 368
12:45:26 INFO - 1500925526906 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50027
12:45:26 INFO - 1500925526906 Marionette DEBUG Closed connection 369
12:45:26 INFO - 1500925526907 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50028
12:45:26 INFO - 1500925526908 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:26 INFO - 1500925526908 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:26 INFO - 1500925526910 Marionette DEBUG Register listener.js for window 12
12:45:26 INFO - 1500925526913 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c937a7fa-db43-4c9e-b811-889623456ad9","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:26 INFO - 1500925526915 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"}]
12:45:26 INFO - 1500925526916 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"
12:45:26 INFO - 1500925526918 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"
12:45:26 INFO - 1500925526918 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"
12:45:26 INFO - 1500925526934 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"
12:45:26 INFO - 1500925526936 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"
12:45:26 INFO - 1500925526944 Marionette TRACE 370 <- [1,2,null,{}]
12:45:26 INFO - 1500925526947 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:45:26 INFO - 1500925526949 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1b18f5b8-5e26-4417-a3e0-e2c2f6db0f2d","ELEMENT":"1b18f5b8-5e26-4417-a3e0-e2c2f6db0f2d"}}]
12:45:26 INFO - 1500925526950 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1b18f5b8-5e26-4417-a3e0-e2c2f6db0f2d"}]
12:45:27 INFO - 1500925527188 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:27 INFO - 1500925527189 Marionette TRACE 370 <- [1,4,null,{}]
12:45:27 INFO - 1500925527202 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1b18f5b8-5e26-4417-a3e0-e2c2f6db0f2d","name":"selected"}]
12:45:27 INFO - 1500925527206 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:45:27 INFO - 1500925527217 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}]
12:45:27 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500925527220 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:45:27 INFO - 1500925527221 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:45:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 353ms
12:45:27 INFO - 1500925527222 Marionette TRACE 370 <- [1,7,null,{}]
12:45:27 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:45:27 INFO - 1500925527226 Marionette DEBUG Closed connection 370
12:45:27 INFO - 1500925527227 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50029
12:45:27 INFO - 1500925527228 Marionette DEBUG Closed connection 371
12:45:27 INFO - 1500925527230 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50030
12:45:27 INFO - 1500925527231 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:27 INFO - 1500925527231 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527233 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527236 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2dc8ac6d-683c-4ce7-8f46-c166dc471386","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527239 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:45:27 INFO - 1500925527239 Marionette TRACE 372 <- [1,2,null,{}]
12:45:27 INFO - 1500925527240 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:27 INFO - 1500925527240 Marionette TRACE 372 <- [1,3,null,{}]
12:45:27 INFO - 1500925527241 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:27 INFO - 1500925527242 Marionette TRACE 372 <- [1,4,null,{}]
12:45:27 INFO - 1500925527244 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}]
12:45:27 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500925527246 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:45:27 INFO - 1500925527247 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:45:27 INFO - 1500925527248 Marionette TRACE 372 <- [1,6,null,{}]
12:45:27 INFO - 1500925527250 Marionette DEBUG Closed connection 372
12:45:27 INFO - 1500925527251 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50031
12:45:27 INFO - 1500925527251 Marionette DEBUG Closed connection 373
12:45:27 INFO - 1500925527253 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50032
12:45:27 INFO - 1500925527254 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:27 INFO - 1500925527254 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527256 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527259 Marionette TRACE 374 <- [1,1,null,{"sessionId":"8bcdb22e-7918-4a28-bb64-01e155a29235","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527261 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:45:27 INFO - 1500925527263 Marionette TRACE 374 <- [1,2,null,{}]
12:45:27 INFO - 1500925527264 Marionette DEBUG Closed connection 374
12:45:27 INFO - 1500925527265 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50033
12:45:27 INFO - 1500925527266 Marionette DEBUG Closed connection 375
12:45:27 INFO - 1500925527267 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50034
12:45:27 INFO - 1500925527268 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:27 INFO - 1500925527268 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527270 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527273 Marionette TRACE 376 <- [1,1,null,{"sessionId":"bc6b048a-972a-4660-919d-a510e533f8b6","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527275 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"}]
12:45:27 INFO - 1500925527277 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"
12:45:27 INFO - 1500925527279 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"
12:45:27 INFO - 1500925527280 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"
12:45:27 INFO - 1500925527300 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"
12:45:27 INFO - 1500925527301 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"
12:45:27 INFO - 1500925527310 Marionette TRACE 376 <- [1,2,null,{}]
12:45:27 INFO - 1500925527312 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:45:27 INFO - 1500925527314 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"605e2c63-caaa-4a3e-a67e-5a6e2de24fbe","ELEMENT":"605e2c63-caaa-4a3e-a67e-5a6e2de24fbe"}}]
12:45:27 INFO - 1500925527315 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"605e2c63-caaa-4a3e-a67e-5a6e2de24fbe"}]
12:45:27 INFO - 1500925527556 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:27 INFO - 1500925527557 Marionette TRACE 376 <- [1,4,null,{}]
12:45:27 INFO - 1500925527559 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}]
12:45:27 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500925527561 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:45:27 INFO - 1500925527562 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:45:27 INFO - 1500925527563 Marionette TRACE 376 <- [1,6,null,{}]
12:45:27 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 340ms
12:45:27 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:45:27 INFO - 1500925527565 Marionette DEBUG Closed connection 376
12:45:27 INFO - 1500925527567 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50035
12:45:27 INFO - 1500925527567 Marionette DEBUG Closed connection 377
12:45:27 INFO - 1500925527568 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50036
12:45:27 INFO - 1500925527569 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:27 INFO - 1500925527569 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527571 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527574 Marionette TRACE 378 <- [1,1,null,{"sessionId":"fbae98fd-be19-4c6b-b7b6-a43f0da6e033","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527576 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:45:27 INFO - 1500925527576 Marionette TRACE 378 <- [1,2,null,{}]
12:45:27 INFO - 1500925527577 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:27 INFO - 1500925527578 Marionette TRACE 378 <- [1,3,null,{}]
12:45:27 INFO - 1500925527578 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:27 INFO - 1500925527579 Marionette TRACE 378 <- [1,4,null,{}]
12:45:27 INFO - 1500925527580 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}]
12:45:27 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500925527583 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:45:27 INFO - 1500925527583 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:45:27 INFO - 1500925527584 Marionette TRACE 378 <- [1,6,null,{}]
12:45:27 INFO - 1500925527586 Marionette DEBUG Closed connection 378
12:45:27 INFO - 1500925527587 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50037
12:45:27 INFO - 1500925527587 Marionette DEBUG Closed connection 379
12:45:27 INFO - 1500925527588 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50038
12:45:27 INFO - 1500925527589 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:27 INFO - 1500925527590 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527591 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527594 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a6756e43-8dda-4fec-b28b-b8f15a7e4b98","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527596 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:45:27 INFO - 1500925527597 Marionette TRACE 380 <- [1,2,null,{}]
12:45:27 INFO - 1500925527598 Marionette DEBUG Closed connection 380
12:45:27 INFO - 1500925527599 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50039
12:45:27 INFO - 1500925527600 Marionette DEBUG Closed connection 381
12:45:27 INFO - 1500925527601 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50040
12:45:27 INFO - 1500925527601 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:27 INFO - 1500925527602 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527604 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527606 Marionette TRACE 382 <- [1,1,null,{"sessionId":"424a9d59-cd9e-425f-9da1-66b5b4d2b6ec","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527608 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"}]
12:45:27 INFO - 1500925527609 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"
12:45:27 INFO - 1500925527611 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"
12:45:27 INFO - 1500925527611 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"
12:45:27 INFO - 1500925527627 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"
12:45:27 INFO - 1500925527629 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"
12:45:27 INFO - 1500925527632 Marionette TRACE 382 <- [1,2,null,{}]
12:45:27 INFO - 1500925527633 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:45:27 INFO - 1500925527641 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4bebb8e8-8ab0-434f-bcec-33723c4ca77c","ELEMENT":"4bebb8e8-8ab0-434f-bcec-33723c4ca77c"}}]
12:45:27 INFO - 1500925527643 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"4bebb8e8-8ab0-434f-bcec-33723c4ca77c"}]
12:45:27 INFO - 1500925527885 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:27 INFO - 1500925527886 Marionette TRACE 382 <- [1,4,null,{}]
12:45:27 INFO - 1500925527888 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}]
12:45:27 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500925527890 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:45:27 INFO - 1500925527891 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:45:27 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 329ms
12:45:27 INFO - TEST-START | test_click.py TestClick.test_input_file
12:45:27 INFO - 1500925527892 Marionette TRACE 382 <- [1,6,null,{}]
12:45:27 INFO - 1500925527894 Marionette DEBUG Closed connection 382
12:45:27 INFO - 1500925527895 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50041
12:45:27 INFO - 1500925527896 Marionette DEBUG Closed connection 383
12:45:27 INFO - 1500925527896 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50042
12:45:27 INFO - 1500925527897 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:27 INFO - 1500925527898 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527900 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527902 Marionette TRACE 384 <- [1,1,null,{"sessionId":"2f4e97f2-eb30-4836-8b08-e43c876f5310","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527904 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:45:27 INFO - 1500925527905 Marionette TRACE 384 <- [1,2,null,{}]
12:45:27 INFO - 1500925527906 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:27 INFO - 1500925527906 Marionette TRACE 384 <- [1,3,null,{}]
12:45:27 INFO - 1500925527907 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:27 INFO - 1500925527907 Marionette TRACE 384 <- [1,4,null,{}]
12:45:27 INFO - 1500925527909 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}]
12:45:27 INFO - TEST-START: test_click.py TestClick.test_input_file1500925527911 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:45:27 INFO - 1500925527912 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:45:27 INFO - 1500925527913 Marionette TRACE 384 <- [1,6,null,{}]
12:45:27 INFO - 1500925527915 Marionette DEBUG Closed connection 384
12:45:27 INFO - 1500925527915 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50043
12:45:27 INFO - 1500925527916 Marionette DEBUG Closed connection 385
12:45:27 INFO - 1500925527917 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50044
12:45:27 INFO - 1500925527918 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:27 INFO - 1500925527918 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527920 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527922 Marionette TRACE 386 <- [1,1,null,{"sessionId":"4a150a08-2c51-46ae-92ae-8f645429716c","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527924 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:45:27 INFO - 1500925527925 Marionette TRACE 386 <- [1,2,null,{}]
12:45:27 INFO - 1500925527927 Marionette DEBUG Closed connection 386
12:45:27 INFO - 1500925527928 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50045
12:45:27 INFO - 1500925527928 Marionette DEBUG Closed connection 387
12:45:27 INFO - 1500925527929 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50046
12:45:27 INFO - 1500925527930 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:27 INFO - 1500925527931 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:27 INFO - 1500925527932 Marionette DEBUG Register listener.js for window 12
12:45:27 INFO - 1500925527935 Marionette TRACE 388 <- [1,1,null,{"sessionId":"31b55e0f-7ed8-4aa2-8819-5fd7c7843c20","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:27 INFO - 1500925527937 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:45:27 INFO - 1500925527938 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"
12:45:27 INFO - 1500925527940 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"
12:45:27 INFO - 1500925527940 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"
12:45:27 INFO - 1500925527950 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:27 INFO - 1500925527958 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:27 INFO - 1500925527962 Marionette TRACE 388 <- [1,2,null,{}]
12:45:27 INFO - 1500925527963 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:45:27 INFO - 1500925527965 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f6a55a4-2174-4d11-adf9-2889d7857c71","ELEMENT":"3f6a55a4-2174-4d11-adf9-2889d7857c71"}}]
12:45:27 INFO - 1500925527966 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3f6a55a4-2174-4d11-adf9-2889d7857c71"}]
12:45:28 INFO - 1500925528002 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
12:45:28 INFO - 1500925528004 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}]
12:45:28 INFO - TEST-END: test_click.py TestClick.test_input_file1500925528007 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:45:28 INFO - 1500925528008 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:45:28 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 117ms
12:45:28 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:45:28 INFO - 1500925528009 Marionette TRACE 388 <- [1,6,null,{}]
12:45:28 INFO - 1500925528011 Marionette DEBUG Closed connection 388
12:45:28 INFO - 1500925528012 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50047
12:45:28 INFO - 1500925528013 Marionette DEBUG Closed connection 389
12:45:28 INFO - 1500925528013 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50048
12:45:28 INFO - 1500925528014 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:28 INFO - 1500925528015 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:28 INFO - 1500925528017 Marionette DEBUG Register listener.js for window 12
12:45:28 INFO - 1500925528020 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d0d8ad66-5d40-460e-b441-57ef60740c6d","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:28 INFO - 1500925528022 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:45:28 INFO - 1500925528023 Marionette TRACE 390 <- [1,2,null,{}]
12:45:28 INFO - 1500925528023 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:28 INFO - 1500925528024 Marionette TRACE 390 <- [1,3,null,{}]
12:45:28 INFO - 1500925528025 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:28 INFO - 1500925528025 Marionette TRACE 390 <- [1,4,null,{}]
12:45:28 INFO - 1500925528027 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}]
12:45:28 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500925528029 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:45:28 INFO - 1500925528030 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:45:28 INFO - 1500925528031 Marionette TRACE 390 <- [1,6,null,{}]
12:45:28 INFO - 1500925528033 Marionette DEBUG Closed connection 390
12:45:28 INFO - 1500925528033 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50049
12:45:28 INFO - 1500925528034 Marionette DEBUG Closed connection 391
12:45:28 INFO - 1500925528035 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50050
12:45:28 INFO - 1500925528036 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:28 INFO - 1500925528036 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:28 INFO - 1500925528038 Marionette DEBUG Register listener.js for window 12
12:45:28 INFO - 1500925528041 Marionette TRACE 392 <- [1,1,null,{"sessionId":"75e4f963-db7a-410c-9ff8-37a09b568512","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:28 INFO - 1500925528042 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:45:28 INFO - 1500925528043 Marionette TRACE 392 <- [1,2,null,{}]
12:45:28 INFO - 1500925528045 Marionette DEBUG Closed connection 392
12:45:28 INFO - 1500925528046 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50051
12:45:28 INFO - 1500925528047 Marionette DEBUG Closed connection 393
12:45:28 INFO - 1500925528047 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50052
12:45:28 INFO - 1500925528048 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:28 INFO - 1500925528049 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:28 INFO - 1500925528051 Marionette DEBUG Register listener.js for window 12
12:45:28 INFO - 1500925528053 Marionette TRACE 394 <- [1,1,null,{"sessionId":"6dae675e-7630-4317-b894-d89793059677","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:28 INFO - 1500925528055 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"}]
12:45:28 INFO - 1500925528056 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:28 INFO - 1500925528058 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:28 INFO - 1500925528058 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:45:28 INFO - 1500925528078 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"
12:45:28 INFO - 1500925528079 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"
12:45:28 INFO - 1500925528082 Marionette TRACE 394 <- [1,2,null,{}]
12:45:28 INFO - 1500925528083 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:45:28 INFO - 1500925528090 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"991242d3-52a1-44b9-a36d-7411821185e8","ELEMENT":"991242d3-52a1-44b9-a36d-7411821185e8"}}]
12:45:28 INFO - 1500925528092 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:45:28 INFO - 1500925528094 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c82b8517-cd07-4eed-afb7-cbbcb9f4c153","ELEMENT":"c82b8517-cd07-4eed-afb7-cbbcb9f4c153"}}]
12:45:28 INFO - 1500925528095 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"991242d3-52a1-44b9-a36d-7411821185e8"}]
12:45:28 INFO - 1500925528331 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:45:28 INFO - 1500925528332 Marionette TRACE 394 <- [1,5,null,{}]
12:45:28 INFO - 1500925528333 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c82b8517-cd07-4eed-afb7-cbbcb9f4c153"}]
12:45:28 INFO - 1500925528346 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
12:45:28 INFO - 1500925528348 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:45:28 INFO - 1500925528350 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:45:28 INFO - 1500925528352 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}]
12:45:28 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500925528354 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:45:28 INFO - 1500925528355 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:45:28 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 347ms
12:45:28 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:45:28 INFO - 1500925528356 Marionette TRACE 394 <- [1,9,null,{}]
12:45:28 INFO - 1500925528358 Marionette DEBUG Closed connection 394
12:45:28 INFO - 1500925528359 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50053
12:45:28 INFO - 1500925528360 Marionette DEBUG Closed connection 395
12:45:28 INFO - 1500925528361 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50054
12:45:28 INFO - 1500925528361 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:28 INFO - 1500925528362 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:28 INFO - 1500925528364 Marionette DEBUG Register listener.js for window 12
12:45:28 INFO - 1500925528367 Marionette TRACE 396 <- [1,1,null,{"sessionId":"b5611d00-4704-4de2-b03a-a936df5af223","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:28 INFO - 1500925528368 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:45:28 INFO - 1500925528369 Marionette TRACE 396 <- [1,2,null,{}]
12:45:28 INFO - 1500925528370 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:45:28 INFO - 1500925528370 Marionette TRACE 396 <- [1,3,null,{}]
12:45:28 INFO - 1500925528371 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:45:28 INFO - 1500925528371 Marionette TRACE 396 <- [1,4,null,{}]
12:45:28 INFO - 1500925528373 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}]
12:45:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500925528375 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:45:28 INFO - 1500925528376 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:45:28 INFO - 1500925528377 Marionette TRACE 396 <- [1,6,null,{}]
12:45:28 INFO - 1500925528379 Marionette DEBUG Closed connection 396
12:45:28 INFO - 1500925528379 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50055
12:45:28 INFO - 1500925528380 Marionette DEBUG Closed connection 397
12:45:28 INFO - 1500925528381 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50056
12:45:28 INFO - 1500925528382 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:45:28 INFO - 1500925528382 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:28 INFO - 1500925528384 Marionette DEBUG Register listener.js for window 12
12:45:28 INFO - 1500925528387 Marionette TRACE 398 <- [1,1,null,{"sessionId":"da788a5b-4c77-463e-a370-40e00a3443ea","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:28 INFO - 1500925528388 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:45:28 INFO - 1500925528389 Marionette TRACE 398 <- [1,2,null,{}]
12:45:28 INFO - 1500925528391 Marionette DEBUG Closed connection 398
12:45:28 INFO - 1500925528392 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50057
12:45:28 INFO - 1500925528392 Marionette DEBUG Closed connection 399
12:45:28 INFO - 1500925528393 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50058
12:45:28 INFO - 1500925528394 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:45:28 INFO - 1500925528394 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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}
12:45:28 INFO - 1500925528396 Marionette DEBUG Register listener.js for window 12
12:45:28 INFO - 1500925528399 Marionette TRACE 400 <- [1,1,null,{"sessionId":"f12ab803-059d-49fc-a1ad-0725d278f396","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":3660,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvczixg.mozrunner","moz:accessibilityChecks":false}}]
12:45:28 INFO - 1500925528401 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"}]
12:45:28 INFO - 1500925528402 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"
12:45:28 INFO - 1500925528405 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"
12:45:28 INFO - 1500925528405 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"
12:45:28 INFO - 1500925528417 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"
12:45:28 INFO - 1500925528420 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"
12:45:28 INFO - 1500925528429 Marionette TRACE 400 <- [1,2,null,{}]
12:45:28 INFO - 1500925528432 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:45:28 INFO - 1500925528435 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"feb72f2e-3212-4519-aa21-72615b009be0","ELEMENT":"feb72f2e-3212-4519-aa21-72615b009be0"}}]
12:45:28 INFO - 1500925528436 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"feb72f2e-3212-4519-aa21-72615b009be0"}]
12:45:28 INFO - 1500925528464 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:45:28 INFO - 1500925528465 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"feb72f2e-3212-4519-aa21-72615b009be0"}]
12:45:28 INFO - 1500925528479 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element