could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
20:50:18 INFO - 1499485818484 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}]
20:50:18 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499485818486 Marionette TRACE 352 <- [1,5,null,{"value":null}]
20:50:18 INFO - 1499485818487 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
20:50:18 INFO - 1499485818488 Marionette TRACE 352 <- [1,6,null,{}]
20:50:18 INFO - 1499485818490 Marionette DEBUG Closed connection 352
20:50:18 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
20:50:18 INFO - 1499485818492 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50552
20:50:18 INFO - 1499485818493 Marionette DEBUG Closed connection 353
20:50:18 INFO - 1499485818494 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50553
20:50:18 INFO - 1499485818494 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:18 INFO - 1499485818495 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:18 INFO - 1499485818497 Marionette DEBUG Register listener.js for window 12
20:50:18 INFO - 1499485818500 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ea0c4c1a-61f5-4419-9f79-7f208a4d2837","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:18 INFO - 1499485818508 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
20:50:18 INFO - 1499485818508 Marionette TRACE 354 <- [1,2,null,{}]
20:50:18 INFO - 1499485818509 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:50:18 INFO - 1499485818509 Marionette TRACE 354 <- [1,3,null,{}]
20:50:18 INFO - 1499485818510 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
20:50:18 INFO - 1499485818511 Marionette TRACE 354 <- [1,4,null,{}]
20:50:18 INFO - 1499485818512 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}]
20:50:18 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499485818515 Marionette TRACE 354 <- [1,5,null,{"value":null}]
20:50:18 INFO - 1499485818516 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
20:50:18 INFO - 1499485818517 Marionette TRACE 354 <- [1,6,null,{}]
20:50:18 INFO - 1499485818519 Marionette DEBUG Closed connection 354
20:50:18 INFO - 1499485818520 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50554
20:50:18 INFO - 1499485818520 Marionette DEBUG Closed connection 355
20:50:18 INFO - 1499485818521 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50555
20:50:18 INFO - 1499485818522 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:18 INFO - 1499485818522 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:18 INFO - 1499485818524 Marionette DEBUG Register listener.js for window 12
20:50:18 INFO - 1499485818527 Marionette TRACE 356 <- [1,1,null,{"sessionId":"7fda3074-d2bc-4742-ad45-09c5a6b613ad","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:18 INFO - 1499485818528 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
20:50:18 INFO - 1499485818529 Marionette TRACE 356 <- [1,2,null,{}]
20:50:18 INFO - 1499485818531 Marionette DEBUG Closed connection 356
20:50:18 INFO - 1499485818532 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50556
20:50:18 INFO - 1499485818532 Marionette DEBUG Closed connection 357
20:50:18 INFO - 1499485818533 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50557
20:50:18 INFO - 1499485818534 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:50:18 INFO - 1499485818535 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:18 INFO - 1499485818536 Marionette DEBUG Register listener.js for window 12
20:50:18 INFO - 1499485818539 Marionette TRACE 358 <- [1,1,null,{"sessionId":"8765a2a7-08b5-4fec-aa9e-35ca9c0320f3","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:18 INFO - 1499485818541 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:50125/clicks.html"}]
20:50:18 INFO - 1499485818542 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50125/hidden.html"
20:50:18 INFO - 1499485818550 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50125/hidden.html"
20:50:18 INFO - 1499485818550 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50125/hidden.html"
20:50:18 INFO - 1499485818562 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50125/clicks.html"
20:50:18 INFO - 1499485818569 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50125/clicks.html"
20:50:18 INFO - 1499485818573 Marionette TRACE 358 <- [1,2,null,{}]
20:50:18 INFO - 1499485818574 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
20:50:18 INFO - 1499485818576 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2fbcadac-3123-403d-98ac-f375a99bb48f","ELEMENT":"2fbcadac-3123-403d-98ac-f375a99bb48f"}}]
20:50:18 INFO - 1499485818577 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"2fbcadac-3123-403d-98ac-f375a99bb48f"}]
20:50:18 INFO - 1499485818616 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50125/clicks.html"
20:50:18 INFO - 1499485818625 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50125/clicks.html"
20:50:18 INFO - 1499485818625 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50125/clicks.html"
20:50:18 INFO - 1499485818640 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50125/test.html"
20:50:18 INFO - 1499485818643 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50125/test.html"
20:50:18 INFO - 1499485818646 Marionette TRACE 358 <- [1,4,null,{}]
20:50:18 INFO - 1499485818655 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
20:50:18 INFO - 1499485818658 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"75c81e98-e724-458b-9b0a-60e13c2920e4","ELEMENT":"75c81e98-e724-458b-9b0a-60e13c2920e4"}}]
20:50:18 INFO - 1499485818659 Marionette TRACE 358 -> [0,6,"getTitle",{}]
20:50:18 INFO - 1499485818660 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
20:50:18 INFO - 1499485818661 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}]
20:50:18 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499485818664 Marionette TRACE 358 <- [1,7,null,{"value":null}]
20:50:18 INFO - 1499485818665 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
20:50:18 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 178ms
20:50:18 INFO - TEST-START | test_click.py TestClick.test_container_element
20:50:18 INFO - 1499485818667 Marionette TRACE 358 <- [1,8,null,{}]
20:50:18 INFO - 1499485818668 Marionette DEBUG Closed connection 358
20:50:18 INFO - 1499485818669 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50560
20:50:18 INFO - 1499485818670 Marionette DEBUG Closed connection 359
20:50:18 INFO - 1499485818671 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50561
20:50:18 INFO - 1499485818672 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:18 INFO - 1499485818672 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:18 INFO - 1499485818674 Marionette DEBUG Register listener.js for window 12
20:50:18 INFO - 1499485818677 Marionette TRACE 360 <- [1,1,null,{"sessionId":"9b5a446d-9cf7-4e1c-92ee-8eb3b89f38ee","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:18 INFO - 1499485818679 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
20:50:18 INFO - 1499485818679 Marionette TRACE 360 <- [1,2,null,{}]
20:50:18 INFO - 1499485818680 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:50:18 INFO - 1499485818680 Marionette TRACE 360 <- [1,3,null,{}]
20:50:18 INFO - 1499485818681 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
20:50:18 INFO - 1499485818682 Marionette TRACE 360 <- [1,4,null,{}]
20:50:18 INFO - 1499485818684 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}]
20:50:18 INFO - TEST-START: test_click.py TestClick.test_container_element1499485818686 Marionette TRACE 360 <- [1,5,null,{"value":null}]
20:50:18 INFO - 1499485818687 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
20:50:18 INFO - 1499485818688 Marionette TRACE 360 <- [1,6,null,{}]
20:50:18 INFO - 1499485818690 Marionette DEBUG Closed connection 360
20:50:18 INFO - 1499485818690 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50562
20:50:18 INFO - 1499485818691 Marionette DEBUG Closed connection 361
20:50:18 INFO - 1499485818692 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50563
20:50:18 INFO - 1499485818693 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:18 INFO - 1499485818693 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:18 INFO - 1499485818695 Marionette DEBUG Register listener.js for window 12
20:50:18 INFO - 1499485818697 Marionette TRACE 362 <- [1,1,null,{"sessionId":"6d7d828d-e504-4f63-8bae-286ea4bc8098","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:18 INFO - 1499485818699 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
20:50:18 INFO - 1499485818700 Marionette TRACE 362 <- [1,2,null,{}]
20:50:18 INFO - 1499485818702 Marionette DEBUG Closed connection 362
20:50:18 INFO - 1499485818703 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50564
20:50:18 INFO - 1499485818703 Marionette DEBUG Closed connection 363
20:50:18 INFO - 1499485818704 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50565
20:50:18 INFO - 1499485818705 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:50:18 INFO - 1499485818705 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:18 INFO - 1499485818707 Marionette DEBUG Register listener.js for window 12
20:50:18 INFO - 1499485818710 Marionette TRACE 364 <- [1,1,null,{"sessionId":"c901c55c-ad2f-4d31-9b63-43a5dbde9e75","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:18 INFO - 1499485818711 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"}]
20:50:18 INFO - 1499485818713 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50125/test.html"
20:50:18 INFO - 1499485818716 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50125/test.html"
20:50:18 INFO - 1499485818716 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50125/test.html"
20:50:18 INFO - 1499485818743 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:50:18 INFO - 1499485818749 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:50:18 INFO - 1499485818758 Marionette TRACE 364 <- [1,2,null,{}]
20:50:18 INFO - 1499485818760 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:50:18 INFO - 1499485818762 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bfd350d7-77cb-4e80-864c-695c12735bc7","ELEMENT":"bfd350d7-77cb-4e80-864c-695c12735bc7"}}]
20:50:18 INFO - 1499485818764 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"bfd350d7-77cb-4e80-864c-695c12735bc7"}]
20:50:19 INFO - 1499485819001 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:50:19 INFO - 1499485819002 Marionette TRACE 364 <- [1,4,null,{}]
20:50:19 INFO - 1499485819003 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"bfd350d7-77cb-4e80-864c-695c12735bc7","name":"selected"}]
20:50:19 INFO - 1499485819008 Marionette TRACE 364 <- [1,5,null,{"value":true}]
20:50:19 INFO - 1499485819009 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}]
20:50:19 INFO - TEST-END: test_click.py TestClick.test_container_element1499485819012 Marionette TRACE 364 <- [1,6,null,{"value":null}]
20:50:19 INFO - 1499485819013 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
20:50:19 INFO - 1499485819014 Marionette TRACE 364 <- [1,7,null,{}]
20:50:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 347ms
20:50:19 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
20:50:19 INFO - 1499485819016 Marionette DEBUG Closed connection 364
20:50:19 INFO - 1499485819017 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50568
20:50:19 INFO - 1499485819017 Marionette DEBUG Closed connection 365
20:50:19 INFO - 1499485819018 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50569
20:50:19 INFO - 1499485819019 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:19 INFO - 1499485819020 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819022 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819024 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1d6f7906-8714-4be6-ba16-8dc5d637c1c8","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819026 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
20:50:19 INFO - 1499485819027 Marionette TRACE 366 <- [1,2,null,{}]
20:50:19 INFO - 1499485819027 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:50:19 INFO - 1499485819028 Marionette TRACE 366 <- [1,3,null,{}]
20:50:19 INFO - 1499485819029 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
20:50:19 INFO - 1499485819029 Marionette TRACE 366 <- [1,4,null,{}]
20:50:19 INFO - 1499485819031 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}]
20:50:19 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499485819033 Marionette TRACE 366 <- [1,5,null,{"value":null}]
20:50:19 INFO - 1499485819034 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
20:50:19 INFO - 1499485819035 Marionette TRACE 366 <- [1,6,null,{}]
20:50:19 INFO - 1499485819036 Marionette DEBUG Closed connection 366
20:50:19 INFO - 1499485819037 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50570
20:50:19 INFO - 1499485819038 Marionette DEBUG Closed connection 367
20:50:19 INFO - 1499485819038 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50571
20:50:19 INFO - 1499485819039 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:19 INFO - 1499485819040 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819048 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819051 Marionette TRACE 368 <- [1,1,null,{"sessionId":"11c525e6-5aaa-4c21-92d9-4f23f363f482","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819053 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
20:50:19 INFO - 1499485819054 Marionette TRACE 368 <- [1,2,null,{}]
20:50:19 INFO - 1499485819056 Marionette DEBUG Closed connection 368
20:50:19 INFO - 1499485819057 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50572
20:50:19 INFO - 1499485819057 Marionette DEBUG Closed connection 369
20:50:19 INFO - 1499485819058 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50573
20:50:19 INFO - 1499485819059 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:50:19 INFO - 1499485819060 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819062 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819064 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b5f9dae8-4e85-4e52-8541-3fe27b39661d","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819066 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"}]
20:50:19 INFO - 1499485819067 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:50:19 INFO - 1499485819069 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:50:19 INFO - 1499485819069 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:50:19 INFO - 1499485819080 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:50:19 INFO - 1499485819088 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:50:19 INFO - 1499485819091 Marionette TRACE 370 <- [1,2,null,{}]
20:50:19 INFO - 1499485819092 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:50:19 INFO - 1499485819094 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a092de62-ad32-4ab7-84cf-19b780e18591","ELEMENT":"a092de62-ad32-4ab7-84cf-19b780e18591"}}]
20:50:19 INFO - 1499485819095 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a092de62-ad32-4ab7-84cf-19b780e18591"}]
20:50:19 INFO - 1499485819337 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:50:19 INFO - 1499485819337 Marionette TRACE 370 <- [1,4,null,{}]
20:50:19 INFO - 1499485819338 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a092de62-ad32-4ab7-84cf-19b780e18591","name":"selected"}]
20:50:19 INFO - 1499485819342 Marionette TRACE 370 <- [1,5,null,{"value":true}]
20:50:19 INFO - 1499485819344 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}]
20:50:19 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499485819346 Marionette TRACE 370 <- [1,6,null,{"value":null}]
20:50:19 INFO - 1499485819347 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
20:50:19 INFO - 1499485819348 Marionette TRACE 370 <- [1,7,null,{}]
20:50:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
20:50:19 INFO - TEST-START | test_click.py TestClick.test_css_transforms
20:50:19 INFO - 1499485819350 Marionette DEBUG Closed connection 370
20:50:19 INFO - 1499485819351 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50574
20:50:19 INFO - 1499485819352 Marionette DEBUG Closed connection 371
20:50:19 INFO - 1499485819353 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50575
20:50:19 INFO - 1499485819354 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:19 INFO - 1499485819354 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819356 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819358 Marionette TRACE 372 <- [1,1,null,{"sessionId":"338c063c-0ee9-4795-97b3-b77d8c25eaa7","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819360 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
20:50:19 INFO - 1499485819361 Marionette TRACE 372 <- [1,2,null,{}]
20:50:19 INFO - 1499485819361 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:50:19 INFO - 1499485819362 Marionette TRACE 372 <- [1,3,null,{}]
20:50:19 INFO - 1499485819363 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
20:50:19 INFO - 1499485819363 Marionette TRACE 372 <- [1,4,null,{}]
20:50:19 INFO - 1499485819365 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}]
20:50:19 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499485819367 Marionette TRACE 372 <- [1,5,null,{"value":null}]
20:50:19 INFO - 1499485819368 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
20:50:19 INFO - 1499485819369 Marionette TRACE 372 <- [1,6,null,{}]
20:50:19 INFO - 1499485819370 Marionette DEBUG Closed connection 372
20:50:19 INFO - 1499485819371 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50576
20:50:19 INFO - 1499485819372 Marionette DEBUG Closed connection 373
20:50:19 INFO - 1499485819373 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50577
20:50:19 INFO - 1499485819373 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:19 INFO - 1499485819374 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819376 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819378 Marionette TRACE 374 <- [1,1,null,{"sessionId":"eacdd93f-57b8-4e93-a80a-8b3899042f98","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819380 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
20:50:19 INFO - 1499485819381 Marionette TRACE 374 <- [1,2,null,{}]
20:50:19 INFO - 1499485819383 Marionette DEBUG Closed connection 374
20:50:19 INFO - 1499485819383 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50578
20:50:19 INFO - 1499485819384 Marionette DEBUG Closed connection 375
20:50:19 INFO - 1499485819385 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50579
20:50:19 INFO - 1499485819386 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:50:19 INFO - 1499485819386 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819388 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819390 Marionette TRACE 376 <- [1,1,null,{"sessionId":"d354cc2a-5229-4c17-896a-099df15428c1","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819392 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"}]
20:50:19 INFO - 1499485819394 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:50:19 INFO - 1499485819396 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:50:19 INFO - 1499485819397 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:50:19 INFO - 1499485819416 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:50:19 INFO - 1499485819418 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:50:19 INFO - 1499485819421 Marionette TRACE 376 <- [1,2,null,{}]
20:50:19 INFO - 1499485819423 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
20:50:19 INFO - 1499485819425 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f106ab81-ebc0-4f6b-b2ee-300a6eda77c4","ELEMENT":"f106ab81-ebc0-4f6b-b2ee-300a6eda77c4"}}]
20:50:19 INFO - 1499485819426 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f106ab81-ebc0-4f6b-b2ee-300a6eda77c4"}]
20:50:19 INFO - 1499485819664 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:50:19 INFO - 1499485819664 Marionette TRACE 376 <- [1,4,null,{}]
20:50:19 INFO - 1499485819666 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}]
20:50:19 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499485819669 Marionette TRACE 376 <- [1,5,null,{"value":null}]
20:50:19 INFO - 1499485819669 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
20:50:19 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
20:50:19 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
20:50:19 INFO - 1499485819670 Marionette TRACE 376 <- [1,6,null,{}]
20:50:19 INFO - 1499485819672 Marionette DEBUG Closed connection 376
20:50:19 INFO - 1499485819673 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50580
20:50:19 INFO - 1499485819674 Marionette DEBUG Closed connection 377
20:50:19 INFO - 1499485819675 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50581
20:50:19 INFO - 1499485819676 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:19 INFO - 1499485819676 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819678 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819681 Marionette TRACE 378 <- [1,1,null,{"sessionId":"b1fbe732-0b74-4bcc-848c-374b4148a238","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819683 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
20:50:19 INFO - 1499485819683 Marionette TRACE 378 <- [1,2,null,{}]
20:50:19 INFO - 1499485819684 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:50:19 INFO - 1499485819684 Marionette TRACE 378 <- [1,3,null,{}]
20:50:19 INFO - 1499485819685 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
20:50:19 INFO - 1499485819686 Marionette TRACE 378 <- [1,4,null,{}]
20:50:19 INFO - 1499485819687 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}]
20:50:19 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499485819690 Marionette TRACE 378 <- [1,5,null,{"value":null}]
20:50:19 INFO - 1499485819690 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
20:50:19 INFO - 1499485819691 Marionette TRACE 378 <- [1,6,null,{}]
20:50:19 INFO - 1499485819693 Marionette DEBUG Closed connection 378
20:50:19 INFO - 1499485819694 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50582
20:50:19 INFO - 1499485819694 Marionette DEBUG Closed connection 379
20:50:19 INFO - 1499485819695 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50583
20:50:19 INFO - 1499485819696 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:19 INFO - 1499485819697 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819699 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819707 Marionette TRACE 380 <- [1,1,null,{"sessionId":"237f785e-bbd9-4266-b1db-a7eb1c54498c","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819709 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
20:50:19 INFO - 1499485819710 Marionette TRACE 380 <- [1,2,null,{}]
20:50:19 INFO - 1499485819712 Marionette DEBUG Closed connection 380
20:50:19 INFO - 1499485819713 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50584
20:50:19 INFO - 1499485819713 Marionette DEBUG Closed connection 381
20:50:19 INFO - 1499485819714 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50585
20:50:19 INFO - 1499485819715 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:50:19 INFO - 1499485819715 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:19 INFO - 1499485819717 Marionette DEBUG Register listener.js for window 12
20:50:19 INFO - 1499485819720 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c1de9faf-deaa-4336-a9cc-6b470e6254b7","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:19 INFO - 1499485819722 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"}]
20:50:19 INFO - 1499485819723 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:50:19 INFO - 1499485819725 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:50:19 INFO - 1499485819725 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:50:19 INFO - 1499485819741 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:50:19 INFO - 1499485819745 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:50:19 INFO - 1499485819747 Marionette TRACE 382 <- [1,2,null,{}]
20:50:19 INFO - 1499485819756 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
20:50:19 INFO - 1499485819758 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d5429c7d-f9c4-4988-9cf9-ccc132018492","ELEMENT":"d5429c7d-f9c4-4988-9cf9-ccc132018492"}}]
20:50:19 INFO - 1499485819759 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d5429c7d-f9c4-4988-9cf9-ccc132018492"}]
20:50:20 INFO - 1499485819998 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:50:20 INFO - 1499485819998 Marionette TRACE 382 <- [1,4,null,{}]
20:50:20 INFO - 1499485820000 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}]
20:50:20 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499485820003 Marionette TRACE 382 <- [1,5,null,{"value":null}]
20:50:20 INFO - 1499485820004 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
20:50:20 INFO - 1499485820005 Marionette TRACE 382 <- [1,6,null,{}]
20:50:20 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 334ms
20:50:20 INFO - TEST-START | test_click.py TestClick.test_input_file
20:50:20 INFO - 1499485820007 Marionette DEBUG Closed connection 382
20:50:20 INFO - 1499485820008 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50586
20:50:20 INFO - 1499485820008 Marionette DEBUG Closed connection 383
20:50:20 INFO - 1499485820009 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50587
20:50:20 INFO - 1499485820010 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:20 INFO - 1499485820010 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820012 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820015 Marionette TRACE 384 <- [1,1,null,{"sessionId":"133cd4d8-5f9d-422a-afb1-2acea458eff5","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820017 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
20:50:20 INFO - 1499485820017 Marionette TRACE 384 <- [1,2,null,{}]
20:50:20 INFO - 1499485820018 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:50:20 INFO - 1499485820018 Marionette TRACE 384 <- [1,3,null,{}]
20:50:20 INFO - 1499485820019 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
20:50:20 INFO - 1499485820020 Marionette TRACE 384 <- [1,4,null,{}]
20:50:20 INFO - 1499485820021 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}]
20:50:20 INFO - TEST-START: test_click.py TestClick.test_input_file1499485820024 Marionette TRACE 384 <- [1,5,null,{"value":null}]
20:50:20 INFO - 1499485820024 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
20:50:20 INFO - 1499485820025 Marionette TRACE 384 <- [1,6,null,{}]
20:50:20 INFO - 1499485820027 Marionette DEBUG Closed connection 384
20:50:20 INFO - 1499485820028 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50588
20:50:20 INFO - 1499485820029 Marionette DEBUG Closed connection 385
20:50:20 INFO - 1499485820029 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50589
20:50:20 INFO - 1499485820030 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:20 INFO - 1499485820031 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820033 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820035 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d84c633d-f257-449f-bd7c-b29a6a81f1ec","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820037 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
20:50:20 INFO - 1499485820038 Marionette TRACE 386 <- [1,2,null,{}]
20:50:20 INFO - 1499485820040 Marionette DEBUG Closed connection 386
20:50:20 INFO - 1499485820040 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50590
20:50:20 INFO - 1499485820041 Marionette DEBUG Closed connection 387
20:50:20 INFO - 1499485820042 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50591
20:50:20 INFO - 1499485820043 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:50:20 INFO - 1499485820043 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820045 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820047 Marionette TRACE 388 <- [1,1,null,{"sessionId":"0a6e9bbe-ef68-429f-a43b-6d6a4c1854ca","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820049 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
20:50:20 INFO - 1499485820050 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:50:20 INFO - 1499485820052 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:50:20 INFO - 1499485820053 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:50:20 INFO - 1499485820063 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:50:20 INFO - 1499485820071 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:50:20 INFO - 1499485820073 Marionette TRACE 388 <- [1,2,null,{}]
20:50:20 INFO - 1499485820074 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
20:50:20 INFO - 1499485820077 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a1c2a06-6839-45b4-b749-875b14670f0b","ELEMENT":"8a1c2a06-6839-45b4-b749-875b14670f0b"}}]
20:50:20 INFO - 1499485820077 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"8a1c2a06-6839-45b4-b749-875b14670f0b"}]
20:50:20 INFO - 1499485820108 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
20:50:20 INFO - 1499485820110 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}]
20:50:20 INFO - TEST-END: test_click.py TestClick.test_input_file1499485820113 Marionette TRACE 388 <- [1,5,null,{"value":null}]
20:50:20 INFO - 1499485820114 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
20:50:20 INFO - 1499485820115 Marionette TRACE 388 <- [1,6,null,{}]
20:50:20 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 110ms
20:50:20 INFO - TEST-START | test_click.py TestClick.test_obscured_element
20:50:20 INFO - 1499485820118 Marionette DEBUG Closed connection 388
20:50:20 INFO - 1499485820118 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50592
20:50:20 INFO - 1499485820119 Marionette DEBUG Closed connection 389
20:50:20 INFO - 1499485820120 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50593
20:50:20 INFO - 1499485820121 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:20 INFO - 1499485820121 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820123 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820126 Marionette TRACE 390 <- [1,1,null,{"sessionId":"6cfb6706-0654-4b25-9a05-da41373709cf","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820128 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
20:50:20 INFO - 1499485820129 Marionette TRACE 390 <- [1,2,null,{}]
20:50:20 INFO - 1499485820130 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:50:20 INFO - 1499485820130 Marionette TRACE 390 <- [1,3,null,{}]
20:50:20 INFO - 1499485820131 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
20:50:20 INFO - 1499485820131 Marionette TRACE 390 <- [1,4,null,{}]
20:50:20 INFO - 1499485820133 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}]
20:50:20 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499485820135 Marionette TRACE 390 <- [1,5,null,{"value":null}]
20:50:20 INFO - 1499485820136 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
20:50:20 INFO - 1499485820137 Marionette TRACE 390 <- [1,6,null,{}]
20:50:20 INFO - 1499485820139 Marionette DEBUG Closed connection 390
20:50:20 INFO - 1499485820140 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50594
20:50:20 INFO - 1499485820140 Marionette DEBUG Closed connection 391
20:50:20 INFO - 1499485820141 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50595
20:50:20 INFO - 1499485820142 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:20 INFO - 1499485820142 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820144 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820146 Marionette TRACE 392 <- [1,1,null,{"sessionId":"20e1d346-bb5e-4c82-b898-cfe4b6b06788","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820148 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
20:50:20 INFO - 1499485820149 Marionette TRACE 392 <- [1,2,null,{}]
20:50:20 INFO - 1499485820151 Marionette DEBUG Closed connection 392
20:50:20 INFO - 1499485820151 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50596
20:50:20 INFO - 1499485820152 Marionette DEBUG Closed connection 393
20:50:20 INFO - 1499485820153 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50597
20:50:20 INFO - 1499485820154 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:50:20 INFO - 1499485820154 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820156 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820164 Marionette TRACE 394 <- [1,1,null,{"sessionId":"0b9ba40f-0330-4e75-812a-90a436d0651d","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820166 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"}]
20:50:20 INFO - 1499485820168 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:50:20 INFO - 1499485820170 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:50:20 INFO - 1499485820170 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:50:20 INFO - 1499485820187 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:50:20 INFO - 1499485820189 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:50:20 INFO - 1499485820191 Marionette TRACE 394 <- [1,2,null,{}]
20:50:20 INFO - 1499485820193 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
20:50:20 INFO - 1499485820195 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"414d2e5a-9084-4112-85a4-42cbbfbbaa27","ELEMENT":"414d2e5a-9084-4112-85a4-42cbbfbbaa27"}}]
20:50:20 INFO - 1499485820195 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
20:50:20 INFO - 1499485820203 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c41eb7f2-583f-44fc-9503-138bc0585704","ELEMENT":"c41eb7f2-583f-44fc-9503-138bc0585704"}}]
20:50:20 INFO - 1499485820204 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"414d2e5a-9084-4112-85a4-42cbbfbbaa27"}]
20:50:20 INFO - 1499485820436 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:50:20 INFO - 1499485820436 Marionette TRACE 394 <- [1,5,null,{}]
20:50:20 INFO - 1499485820437 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c41eb7f2-583f-44fc-9503-138bc0585704"}]
20:50:20 INFO - 1499485820451 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
20:50:20 INFO - 1499485820452 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
20:50:20 INFO - 1499485820455 Marionette TRACE 394 <- [1,7,null,{"value":false}]
20:50:20 INFO - 1499485820456 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}]
20:50:20 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499485820459 Marionette TRACE 394 <- [1,8,null,{"value":null}]
20:50:20 INFO - 1499485820459 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
20:50:20 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 344ms
20:50:20 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
20:50:20 INFO - 1499485820460 Marionette TRACE 394 <- [1,9,null,{}]
20:50:20 INFO - 1499485820462 Marionette DEBUG Closed connection 394
20:50:20 INFO - 1499485820463 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50598
20:50:20 INFO - 1499485820464 Marionette DEBUG Closed connection 395
20:50:20 INFO - 1499485820465 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50599
20:50:20 INFO - 1499485820466 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:20 INFO - 1499485820466 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820469 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820471 Marionette TRACE 396 <- [1,1,null,{"sessionId":"5bc774f2-1196-45f4-8cff-03ab3122872c","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820473 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
20:50:20 INFO - 1499485820473 Marionette TRACE 396 <- [1,2,null,{}]
20:50:20 INFO - 1499485820474 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:50:20 INFO - 1499485820475 Marionette TRACE 396 <- [1,3,null,{}]
20:50:20 INFO - 1499485820475 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
20:50:20 INFO - 1499485820476 Marionette TRACE 396 <- [1,4,null,{}]
20:50:20 INFO - 1499485820477 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}]
20:50:20 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499485820480 Marionette TRACE 396 <- [1,5,null,{"value":null}]
20:50:20 INFO - 1499485820481 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
20:50:20 INFO - 1499485820482 Marionette TRACE 396 <- [1,6,null,{}]
20:50:20 INFO - 1499485820483 Marionette DEBUG Closed connection 396
20:50:20 INFO - 1499485820484 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50600
20:50:20 INFO - 1499485820485 Marionette DEBUG Closed connection 397
20:50:20 INFO - 1499485820485 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50601
20:50:20 INFO - 1499485820486 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:50:20 INFO - 1499485820487 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820489 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820491 Marionette TRACE 398 <- [1,1,null,{"sessionId":"60c3d295-9f96-418c-84d4-dbf59ad2a357","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820493 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
20:50:20 INFO - 1499485820494 Marionette TRACE 398 <- [1,2,null,{}]
20:50:20 INFO - 1499485820495 Marionette DEBUG Closed connection 398
20:50:20 INFO - 1499485820496 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50602
20:50:20 INFO - 1499485820497 Marionette DEBUG Closed connection 399
20:50:20 INFO - 1499485820497 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50603
20:50:20 INFO - 1499485820498 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:50:20 INFO - 1499485820499 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}
20:50:20 INFO - 1499485820500 Marionette DEBUG Register listener.js for window 12
20:50:20 INFO - 1499485820503 Marionette TRACE 400 <- [1,1,null,{"sessionId":"30952bd3-9002-4c2a-b0e4-fa32eb77355b","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohynxm.mozrunner","moz:accessibilityChecks":false}}]
20:50:20 INFO - 1499485820505 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"}]
20:50:20 INFO - 1499485820506 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:50:20 INFO - 1499485820508 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:50:20 INFO - 1499485820508 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:50:20 INFO - 1499485820524 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:50:20 INFO - 1499485820527 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:50:20 INFO - 1499485820529 Marionette TRACE 400 <- [1,2,null,{}]
20:50:20 INFO - 1499485820536 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
20:50:20 INFO - 1499485820538 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"54d4179a-d6ff-4aac-b514-3712143f865d","ELEMENT":"54d4179a-d6ff-4aac-b514-3712143f865d"}}]
20:50:20 INFO - 1499485820539 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"54d4179a-d6ff-4aac-b514-3712143f865d"}]
20:50:20 INFO - 1499485820559 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
20:50:20 INFO - 1499485820560 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"54d4179a-d6ff-4aac-b514-3712143f865d"}]
20:50:20 INFO - 1499485820577 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element