could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementNotInteractableError@chrome://marionette/content/error.js:311:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:176: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
05:24:21 INFO - 1498825461551 Marionette TRACE 358 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:24:21 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498825461553 Marionette TRACE 358 <- [1,5,null,{"value":null}]
05:24:21 INFO - 1498825461555 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
05:24:21 INFO - 1498825461556 Marionette TRACE 358 <- [1,6,null,{}]
05:24:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 138ms
05:24:21 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:24:21 INFO - 1498825461558 Marionette DEBUG Closed connection 358
05:24:21 INFO - 1498825461559 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49748
05:24:21 INFO - 1498825461560 Marionette DEBUG Closed connection 359
05:24:21 INFO - 1498825461561 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49749
05:24:21 INFO - 1498825461562 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:21 INFO - 1498825461562 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:21 INFO - 1498825461564 Marionette DEBUG Register listener.js for window 2147483649
05:24:21 INFO - 1498825461567 Marionette TRACE 360 <- [1,1,null,{"sessionId":"858219b6-3d30-4dec-b0f4-f22c1c9d732a","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:21 INFO - 1498825461568 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:24:21 INFO - 1498825461569 Marionette TRACE 360 <- [1,2,null,{}]
05:24:21 INFO - 1498825461570 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:24:21 INFO - 1498825461570 Marionette TRACE 360 <- [1,3,null,{}]
05:24:21 INFO - 1498825461571 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:24:21 INFO - 1498825461572 Marionette TRACE 360 <- [1,4,null,{}]
05:24:21 INFO - 1498825461574 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:24:21 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498825461576 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:24:21 INFO - 1498825461577 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:24:21 INFO - 1498825461579 Marionette TRACE 360 <- [1,6,null,{}]
05:24:21 INFO - 1498825461580 Marionette DEBUG Closed connection 360
05:24:21 INFO - 1498825461581 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49750
05:24:21 INFO - 1498825461581 Marionette DEBUG Closed connection 361
05:24:21 INFO - 1498825461582 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49751
05:24:21 INFO - 1498825461583 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:21 INFO - 1498825461583 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:21 INFO - 1498825461590 Marionette DEBUG Register listener.js for window 2147483649
05:24:21 INFO - 1498825461593 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e356c402-9c2c-43f5-b1f8-0345406c9864","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:21 INFO - 1498825461594 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:24:21 INFO - 1498825461595 Marionette TRACE 362 <- [1,2,null,{}]
05:24:21 INFO - 1498825461596 Marionette DEBUG Closed connection 362
05:24:21 INFO - 1498825461597 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49752
05:24:21 INFO - 1498825461598 Marionette DEBUG Closed connection 363
05:24:21 INFO - 1498825461598 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49753
05:24:21 INFO - 1498825461599 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:24:21 INFO - 1498825461600 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:21 INFO - 1498825461602 Marionette DEBUG Register listener.js for window 2147483649
05:24:21 INFO - 1498825461605 Marionette TRACE 364 <- [1,1,null,{"sessionId":"7aa1b2bb-db91-4e61-8bb3-9c4110d46efd","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:21 INFO - 1498825461606 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49351/clicks.html"}]
05:24:21 INFO - 1498825461608 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49351/hidden.html"
05:24:21 INFO - 1498825461615 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49351/hidden.html"
05:24:21 INFO - 1498825461615 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49351/hidden.html"
05:24:21 INFO - 1498825461625 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49351/clicks.html"
05:24:21 INFO - 1498825461627 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49351/clicks.html"
05:24:21 INFO - 1498825461630 Marionette TRACE 364 <- [1,2,null,{}]
05:24:21 INFO - 1498825461635 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:24:21 INFO - 1498825461637 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c092224b-7b27-4a35-9d0c-8acbe3f39fff","ELEMENT":"c092224b-7b27-4a35-9d0c-8acbe3f39fff"}}]
05:24:21 INFO - 1498825461642 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c092224b-7b27-4a35-9d0c-8acbe3f39fff"}]
05:24:21 INFO - 1498825461692 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49351/clicks.html"
05:24:21 INFO - 1498825461700 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49351/clicks.html"
05:24:21 INFO - 1498825461700 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49351/clicks.html"
05:24:21 INFO - 1498825461709 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49351/test.html"
05:24:21 INFO - 1498825461710 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49351/test.html"
05:24:21 INFO - 1498825461715 Marionette TRACE 364 <- [1,4,null,{}]
05:24:21 INFO - 1498825461719 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:24:21 INFO - 1498825461725 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"69a23315-8dc4-4168-8b2e-2766204750a4","ELEMENT":"69a23315-8dc4-4168-8b2e-2766204750a4"}}]
05:24:21 INFO - 1498825461726 Marionette TRACE 364 -> [0,6,"getTitle",{}]
05:24:21 INFO - 1498825461729 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
05:24:21 INFO - 1498825461731 Marionette TRACE 364 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:24:21 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498825461733 Marionette TRACE 364 <- [1,7,null,{"value":null}]
05:24:21 INFO - 1498825461734 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
05:24:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 180ms
05:24:21 INFO - TEST-START | test_click.py TestClick.test_container_element
05:24:21 INFO - 1498825461736 Marionette TRACE 364 <- [1,8,null,{}]
05:24:21 INFO - 1498825461737 Marionette DEBUG Closed connection 364
05:24:21 INFO - 1498825461738 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49756
05:24:21 INFO - 1498825461739 Marionette DEBUG Closed connection 365
05:24:21 INFO - 1498825461740 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49757
05:24:21 INFO - 1498825461741 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:21 INFO - 1498825461742 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:21 INFO - 1498825461744 Marionette DEBUG Register listener.js for window 2147483649
05:24:21 INFO - 1498825461748 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ba5a5159-1af3-4ea6-839b-100d718ed5b5","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:21 INFO - 1498825461749 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:24:21 INFO - 1498825461749 Marionette TRACE 366 <- [1,2,null,{}]
05:24:21 INFO - 1498825461750 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:24:21 INFO - 1498825461751 Marionette TRACE 366 <- [1,3,null,{}]
05:24:21 INFO - 1498825461752 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:24:21 INFO - 1498825461752 Marionette TRACE 366 <- [1,4,null,{}]
05:24:21 INFO - 1498825461754 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')","sandbox":"simpletest","line":316}]
05:24:21 INFO - TEST-START: test_click.py TestClick.test_container_element1498825461757 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:24:21 INFO - 1498825461758 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:24:21 INFO - 1498825461759 Marionette TRACE 366 <- [1,6,null,{}]
05:24:21 INFO - 1498825461761 Marionette DEBUG Closed connection 366
05:24:21 INFO - 1498825461762 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49758
05:24:21 INFO - 1498825461762 Marionette DEBUG Closed connection 367
05:24:21 INFO - 1498825461763 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49759
05:24:21 INFO - 1498825461764 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:21 INFO - 1498825461765 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:21 INFO - 1498825461767 Marionette DEBUG Register listener.js for window 2147483649
05:24:21 INFO - 1498825461770 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e3ce6351-b042-42b4-a14b-1c4dcc3800ed","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:21 INFO - 1498825461771 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:24:21 INFO - 1498825461772 Marionette TRACE 368 <- [1,2,null,{}]
05:24:21 INFO - 1498825461773 Marionette DEBUG Closed connection 368
05:24:21 INFO - 1498825461774 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49760
05:24:21 INFO - 1498825461775 Marionette DEBUG Closed connection 369
05:24:21 INFO - 1498825461776 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49761
05:24:21 INFO - 1498825461776 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:24:21 INFO - 1498825461777 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:21 INFO - 1498825461779 Marionette DEBUG Register listener.js for window 2147483649
05:24:21 INFO - 1498825461782 Marionette TRACE 370 <- [1,1,null,{"sessionId":"594f697e-69c4-45d8-98cf-86260e9f1e7c","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:21 INFO - 1498825461783 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%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:24:21 INFO - 1498825461785 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49351/test.html"
05:24:21 INFO - 1498825461787 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49351/test.html"
05:24:21 INFO - 1498825461787 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49351/test.html"
05:24:21 INFO - 1498825461794 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:21 INFO - 1498825461797 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:21 INFO - 1498825461803 Marionette TRACE 370 <- [1,2,null,{}]
05:24:21 INFO - 1498825461813 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:24:21 INFO - 1498825461816 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"896b43f1-90ed-4e13-8c08-0030b9b6c74d","ELEMENT":"896b43f1-90ed-4e13-8c08-0030b9b6c74d"}}]
05:24:21 INFO - 1498825461817 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"896b43f1-90ed-4e13-8c08-0030b9b6c74d"}]
05:24:22 INFO - 1498825462062 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:24:22 INFO - 1498825462063 Marionette TRACE 370 <- [1,4,null,{}]
05:24:22 INFO - 1498825462065 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"896b43f1-90ed-4e13-8c08-0030b9b6c74d","name":"selected"}]
05:24:22 INFO - 1498825462069 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:24:22 INFO - 1498825462071 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')","sandbox":"simpletest","line":328}]
05:24:22 INFO - TEST-END: test_click.py TestClick.test_container_element1498825462073 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:24:22 INFO - 1498825462074 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:24:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 339ms
05:24:22 INFO - 1498825462075 Marionette TRACE 370 <- [1,7,null,{}]
05:24:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:24:22 INFO - 1498825462077 Marionette DEBUG Closed connection 370
05:24:22 INFO - 1498825462078 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49762
05:24:22 INFO - 1498825462079 Marionette DEBUG Closed connection 371
05:24:22 INFO - 1498825462079 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49763
05:24:22 INFO - 1498825462080 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:22 INFO - 1498825462081 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462083 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462086 Marionette TRACE 372 <- [1,1,null,{"sessionId":"e47bc1bc-59d2-423f-8717-7658a7761fe0","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462088 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:24:22 INFO - 1498825462088 Marionette TRACE 372 <- [1,2,null,{}]
05:24:22 INFO - 1498825462089 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:24:22 INFO - 1498825462090 Marionette TRACE 372 <- [1,3,null,{}]
05:24:22 INFO - 1498825462090 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:24:22 INFO - 1498825462091 Marionette TRACE 372 <- [1,4,null,{}]
05:24:22 INFO - 1498825462093 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:24:22 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498825462096 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:24:22 INFO - 1498825462097 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:24:22 INFO - 1498825462098 Marionette TRACE 372 <- [1,6,null,{}]
05:24:22 INFO - 1498825462099 Marionette DEBUG Closed connection 372
05:24:22 INFO - 1498825462100 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49764
05:24:22 INFO - 1498825462101 Marionette DEBUG Closed connection 373
05:24:22 INFO - 1498825462102 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49765
05:24:22 INFO - 1498825462103 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:22 INFO - 1498825462103 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462105 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462108 Marionette TRACE 374 <- [1,1,null,{"sessionId":"78bb9056-d8d0-45b9-a36a-740508edfedb","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462110 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:24:22 INFO - 1498825462111 Marionette TRACE 374 <- [1,2,null,{}]
05:24:22 INFO - 1498825462112 Marionette DEBUG Closed connection 374
05:24:22 INFO - 1498825462113 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49766
05:24:22 INFO - 1498825462114 Marionette DEBUG Closed connection 375
05:24:22 INFO - 1498825462115 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49767
05:24:22 INFO - 1498825462116 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:24:22 INFO - 1498825462116 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462118 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462121 Marionette TRACE 376 <- [1,1,null,{"sessionId":"5703c750-4c73-4d9f-a074-9bb8a833b883","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462122 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%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:24:22 INFO - 1498825462124 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462126 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462126 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462132 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462135 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462136 Marionette TRACE 376 <- [1,2,null,{}]
05:24:22 INFO - 1498825462145 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:24:22 INFO - 1498825462149 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f7f4759c-4c18-4db2-b776-c984a96f494f","ELEMENT":"f7f4759c-4c18-4db2-b776-c984a96f494f"}}]
05:24:22 INFO - 1498825462150 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f7f4759c-4c18-4db2-b776-c984a96f494f"}]
05:24:22 INFO - 1498825462389 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:24:22 INFO - 1498825462390 Marionette TRACE 376 <- [1,4,null,{}]
05:24:22 INFO - 1498825462391 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"f7f4759c-4c18-4db2-b776-c984a96f494f","name":"selected"}]
05:24:22 INFO - 1498825462396 Marionette TRACE 376 <- [1,5,null,{"value":true}]
05:24:22 INFO - 1498825462397 Marionette TRACE 376 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:24:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498825462400 Marionette TRACE 376 <- [1,6,null,{"value":null}]
05:24:22 INFO - 1498825462401 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
05:24:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 326ms
05:24:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:24:22 INFO - 1498825462402 Marionette TRACE 376 <- [1,7,null,{}]
05:24:22 INFO - 1498825462403 Marionette DEBUG Closed connection 376
05:24:22 INFO - 1498825462405 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49768
05:24:22 INFO - 1498825462405 Marionette DEBUG Closed connection 377
05:24:22 INFO - 1498825462406 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49769
05:24:22 INFO - 1498825462407 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:22 INFO - 1498825462408 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462410 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462413 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5fbee62f-4ef4-4344-845d-bf6b0b31bc13","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462414 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:24:22 INFO - 1498825462414 Marionette TRACE 378 <- [1,2,null,{}]
05:24:22 INFO - 1498825462415 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:24:22 INFO - 1498825462416 Marionette TRACE 378 <- [1,3,null,{}]
05:24:22 INFO - 1498825462416 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:24:22 INFO - 1498825462417 Marionette TRACE 378 <- [1,4,null,{}]
05:24:22 INFO - 1498825462419 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:24:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498825462422 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:24:22 INFO - 1498825462422 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:24:22 INFO - 1498825462424 Marionette TRACE 378 <- [1,6,null,{}]
05:24:22 INFO - 1498825462425 Marionette DEBUG Closed connection 378
05:24:22 INFO - 1498825462426 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49770
05:24:22 INFO - 1498825462426 Marionette DEBUG Closed connection 379
05:24:22 INFO - 1498825462427 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49771
05:24:22 INFO - 1498825462428 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:22 INFO - 1498825462429 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462431 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462434 Marionette TRACE 380 <- [1,1,null,{"sessionId":"bf71a697-65dc-4c83-a2c9-2061f92fc422","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462435 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:24:22 INFO - 1498825462436 Marionette TRACE 380 <- [1,2,null,{}]
05:24:22 INFO - 1498825462437 Marionette DEBUG Closed connection 380
05:24:22 INFO - 1498825462438 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49772
05:24:22 INFO - 1498825462439 Marionette DEBUG Closed connection 381
05:24:22 INFO - 1498825462439 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49773
05:24:22 INFO - 1498825462440 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:24:22 INFO - 1498825462441 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462443 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462446 Marionette TRACE 382 <- [1,1,null,{"sessionId":"90fc1535-fa90-4dee-86b9-06ad7b95a0ca","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462447 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%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:24:22 INFO - 1498825462449 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462451 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462451 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462457 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:24:22 INFO - 1498825462460 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:24:22 INFO - 1498825462468 Marionette TRACE 382 <- [1,2,null,{}]
05:24:22 INFO - 1498825462476 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:24:22 INFO - 1498825462479 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b33fe15b-7ce8-4d15-a45b-b6f3f65dc7de","ELEMENT":"b33fe15b-7ce8-4d15-a45b-b6f3f65dc7de"}}]
05:24:22 INFO - 1498825462480 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"b33fe15b-7ce8-4d15-a45b-b6f3f65dc7de"}]
05:24:22 INFO - 1498825462716 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:24:22 INFO - 1498825462717 Marionette TRACE 382 <- [1,4,null,{}]
05:24:22 INFO - 1498825462719 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:24:22 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498825462735 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:24:22 INFO - 1498825462736 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:24:22 INFO - 1498825462738 Marionette TRACE 382 <- [1,6,null,{}]
05:24:22 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 336ms
05:24:22 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:24:22 INFO - 1498825462739 Marionette DEBUG Closed connection 382
05:24:22 INFO - 1498825462741 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49774
05:24:22 INFO - 1498825462741 Marionette DEBUG Closed connection 383
05:24:22 INFO - 1498825462743 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49775
05:24:22 INFO - 1498825462744 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:22 INFO - 1498825462744 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462747 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462752 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ee620810-62ad-4a92-a157-24f28ab1c2c7","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462753 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:24:22 INFO - 1498825462754 Marionette TRACE 384 <- [1,2,null,{}]
05:24:22 INFO - 1498825462755 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:24:22 INFO - 1498825462755 Marionette TRACE 384 <- [1,3,null,{}]
05:24:22 INFO - 1498825462756 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:24:22 INFO - 1498825462757 Marionette TRACE 384 <- [1,4,null,{}]
05:24:22 INFO - 1498825462758 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:24:22 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498825462761 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:24:22 INFO - 1498825462762 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:24:22 INFO - 1498825462764 Marionette TRACE 384 <- [1,6,null,{}]
05:24:22 INFO - 1498825462765 Marionette DEBUG Closed connection 384
05:24:22 INFO - 1498825462766 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49776
05:24:22 INFO - 1498825462767 Marionette DEBUG Closed connection 385
05:24:22 INFO - 1498825462767 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49777
05:24:22 INFO - 1498825462768 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:22 INFO - 1498825462769 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462771 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462775 Marionette TRACE 386 <- [1,1,null,{"sessionId":"198506d7-ae94-4098-aced-16da9ce71ae2","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462776 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:24:22 INFO - 1498825462778 Marionette TRACE 386 <- [1,2,null,{}]
05:24:22 INFO - 1498825462779 Marionette DEBUG Closed connection 386
05:24:22 INFO - 1498825462780 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49778
05:24:22 INFO - 1498825462780 Marionette DEBUG Closed connection 387
05:24:22 INFO - 1498825462781 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49779
05:24:22 INFO - 1498825462782 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:24:22 INFO - 1498825462783 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:22 INFO - 1498825462828 Marionette DEBUG Register listener.js for window 2147483649
05:24:22 INFO - 1498825462865 Marionette TRACE 388 <- [1,1,null,{"sessionId":"b8086b52-9ab9-48f3-926d-8e5b9fbafed9","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:22 INFO - 1498825462867 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:24:22 INFO - 1498825462913 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:24:22 INFO - 1498825462916 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:24:22 INFO - 1498825462917 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:24:22 INFO - 1498825462926 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462928 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:22 INFO - 1498825462943 Marionette TRACE 388 <- [1,2,null,{}]
05:24:22 INFO - 1498825462950 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:24:22 INFO - 1498825462955 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"807ecc9b-a95b-4030-b5d4-67bf92f23990","ELEMENT":"807ecc9b-a95b-4030-b5d4-67bf92f23990"}}]
05:24:22 INFO - 1498825462970 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"807ecc9b-a95b-4030-b5d4-67bf92f23990"}]
05:24:23 INFO - 1498825463216 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:24:23 INFO - 1498825463218 Marionette TRACE 388 <- [1,4,null,{}]
05:24:23 INFO - 1498825463221 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:24:23 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498825463225 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:24:23 INFO - 1498825463227 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:24:23 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 490ms
05:24:23 INFO - TEST-START | test_click.py TestClick.test_input_file
05:24:23 INFO - 1498825463229 Marionette TRACE 388 <- [1,6,null,{}]
05:24:23 INFO - 1498825463232 Marionette DEBUG Closed connection 388
05:24:23 INFO - 1498825463233 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49780
05:24:23 INFO - 1498825463235 Marionette DEBUG Closed connection 389
05:24:23 INFO - 1498825463235 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49781
05:24:23 INFO - 1498825463237 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:23 INFO - 1498825463239 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463242 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463247 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8ae81b68-b316-45b5-8cae-fa6b84cbff62","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463248 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:24:23 INFO - 1498825463249 Marionette TRACE 390 <- [1,2,null,{}]
05:24:23 INFO - 1498825463250 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:24:23 INFO - 1498825463251 Marionette TRACE 390 <- [1,3,null,{}]
05:24:23 INFO - 1498825463252 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:24:23 INFO - 1498825463253 Marionette TRACE 390 <- [1,4,null,{}]
05:24:23 INFO - 1498825463255 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:24:23 INFO - TEST-START: test_click.py TestClick.test_input_file1498825463259 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:24:23 INFO - 1498825463260 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:24:23 INFO - 1498825463262 Marionette TRACE 390 <- [1,6,null,{}]
05:24:23 INFO - 1498825463264 Marionette DEBUG Closed connection 390
05:24:23 INFO - 1498825463265 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49782
05:24:23 INFO - 1498825463266 Marionette DEBUG Closed connection 391
05:24:23 INFO - 1498825463267 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49783
05:24:23 INFO - 1498825463270 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:23 INFO - 1498825463271 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463274 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463278 Marionette TRACE 392 <- [1,1,null,{"sessionId":"aed7e3da-3332-4098-bb08-54b16bd51fd8","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463280 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:24:23 INFO - 1498825463282 Marionette TRACE 392 <- [1,2,null,{}]
05:24:23 INFO - 1498825463283 Marionette DEBUG Closed connection 392
05:24:23 INFO - 1498825463284 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49784
05:24:23 INFO - 1498825463285 Marionette DEBUG Closed connection 393
05:24:23 INFO - 1498825463286 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49785
05:24:23 INFO - 1498825463287 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:24:23 INFO - 1498825463288 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463290 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463294 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f693c12e-274b-4e53-a659-635daba4aec4","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463296 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:24:23 INFO - 1498825463298 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:23 INFO - 1498825463300 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:23 INFO - 1498825463301 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:24:23 INFO - 1498825463307 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:24:23 INFO - 1498825463312 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:24:23 INFO - 1498825463327 Marionette TRACE 394 <- [1,2,null,{}]
05:24:23 INFO - 1498825463331 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:24:23 INFO - 1498825463335 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"922f4c99-67fa-48ac-a5de-217a581573bf","ELEMENT":"922f4c99-67fa-48ac-a5de-217a581573bf"}}]
05:24:23 INFO - 1498825463336 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"922f4c99-67fa-48ac-a5de-217a581573bf"}]
05:24:23 INFO - 1498825463364 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nInvalidArgumentError@chrome://marionette/content/error.js:325:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:158: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
05:24:23 INFO - 1498825463366 Marionette TRACE 394 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:24:23 INFO - TEST-END: test_click.py TestClick.test_input_file1498825463370 Marionette TRACE 394 <- [1,5,null,{"value":null}]
05:24:23 INFO - 1498825463371 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
05:24:23 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 143ms
05:24:23 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:24:23 INFO - 1498825463373 Marionette TRACE 394 <- [1,6,null,{}]
05:24:23 INFO - 1498825463374 Marionette DEBUG Closed connection 394
05:24:23 INFO - 1498825463375 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49786
05:24:23 INFO - 1498825463376 Marionette DEBUG Closed connection 395
05:24:23 INFO - 1498825463377 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49787
05:24:23 INFO - 1498825463378 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:23 INFO - 1498825463379 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463381 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463385 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a6b2b5a5-1ae2-46c0-955c-da2fec610b30","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463386 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:24:23 INFO - 1498825463387 Marionette TRACE 396 <- [1,2,null,{}]
05:24:23 INFO - 1498825463388 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:24:23 INFO - 1498825463389 Marionette TRACE 396 <- [1,3,null,{}]
05:24:23 INFO - 1498825463389 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:24:23 INFO - 1498825463390 Marionette TRACE 396 <- [1,4,null,{}]
05:24:23 INFO - 1498825463392 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:24:23 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498825463395 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:24:23 INFO - 1498825463396 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:24:23 INFO - 1498825463397 Marionette TRACE 396 <- [1,6,null,{}]
05:24:23 INFO - 1498825463399 Marionette DEBUG Closed connection 396
05:24:23 INFO - 1498825463400 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49788
05:24:23 INFO - 1498825463401 Marionette DEBUG Closed connection 397
05:24:23 INFO - 1498825463401 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49789
05:24:23 INFO - 1498825463402 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:23 INFO - 1498825463403 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463406 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463410 Marionette TRACE 398 <- [1,1,null,{"sessionId":"84db992c-1537-4e99-b4d1-88617c57b95c","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463412 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:24:23 INFO - 1498825463413 Marionette TRACE 398 <- [1,2,null,{}]
05:24:23 INFO - 1498825463416 Marionette DEBUG Closed connection 398
05:24:23 INFO - 1498825463418 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49790
05:24:23 INFO - 1498825463419 Marionette DEBUG Closed connection 399
05:24:23 INFO - 1498825463420 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49791
05:24:23 INFO - 1498825463421 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:24:23 INFO - 1498825463422 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463425 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463429 Marionette TRACE 400 <- [1,1,null,{"sessionId":"cdb84d1d-e75a-4615-abf7-1d373550f65b","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463430 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:24:23 INFO - 1498825463432 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:24:23 INFO - 1498825463434 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:24:23 INFO - 1498825463434 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:24:23 INFO - 1498825463443 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:24:23 INFO - 1498825463445 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:24:23 INFO - 1498825463452 Marionette TRACE 400 <- [1,2,null,{}]
05:24:23 INFO - 1498825463464 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:24:23 INFO - 1498825463468 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"99c31d0b-1463-4c70-81bc-7b2288e0c267","ELEMENT":"99c31d0b-1463-4c70-81bc-7b2288e0c267"}}]
05:24:23 INFO - 1498825463469 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:24:23 INFO - 1498825463472 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8297e73c-2eee-4554-b593-093a6d041e5c","ELEMENT":"8297e73c-2eee-4554-b593-093a6d041e5c"}}]
05:24:23 INFO - 1498825463474 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"99c31d0b-1463-4c70-81bc-7b2288e0c267"}]
05:24:23 INFO - 1498825463712 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:24:23 INFO - 1498825463713 Marionette TRACE 400 <- [1,5,null,{}]
05:24:23 INFO - 1498825463715 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"8297e73c-2eee-4554-b593-093a6d041e5c"}]
05:24:23 INFO - 1498825463731 Marionette TRACE 400 <- [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:222:5\nElementClickInterceptedError@chrome://marionette/content/error.js:304:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:185: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
05:24:23 INFO - 1498825463733 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:24:23 INFO - 1498825463736 Marionette TRACE 400 <- [1,7,null,{"value":false}]
05:24:23 INFO - 1498825463738 Marionette TRACE 400 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:24:23 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498825463741 Marionette TRACE 400 <- [1,8,null,{"value":null}]
05:24:23 INFO - 1498825463742 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
05:24:23 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 370ms
05:24:23 INFO - 1498825463743 Marionette TRACE 400 <- [1,9,null,{}]
05:24:23 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:24:23 INFO - 1498825463745 Marionette DEBUG Closed connection 400
05:24:23 INFO - 1498825463746 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49792
05:24:23 INFO - 1498825463747 Marionette DEBUG Closed connection 401
05:24:23 INFO - 1498825463748 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49793
05:24:23 INFO - 1498825463749 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:23 INFO - 1498825463749 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463752 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463755 Marionette TRACE 402 <- [1,1,null,{"sessionId":"60af6a1c-3562-408f-ab6a-e02437a28278","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463756 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
05:24:23 INFO - 1498825463757 Marionette TRACE 402 <- [1,2,null,{}]
05:24:23 INFO - 1498825463757 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:24:23 INFO - 1498825463758 Marionette TRACE 402 <- [1,3,null,{}]
05:24:23 INFO - 1498825463759 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
05:24:23 INFO - 1498825463760 Marionette TRACE 402 <- [1,4,null,{}]
05:24:23 INFO - 1498825463761 Marionette TRACE 402 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:24:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498825463765 Marionette TRACE 402 <- [1,5,null,{"value":null}]
05:24:23 INFO - 1498825463766 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
05:24:23 INFO - 1498825463767 Marionette TRACE 402 <- [1,6,null,{}]
05:24:23 INFO - 1498825463768 Marionette DEBUG Closed connection 402
05:24:23 INFO - 1498825463769 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49794
05:24:23 INFO - 1498825463770 Marionette DEBUG Closed connection 403
05:24:23 INFO - 1498825463770 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49795
05:24:23 INFO - 1498825463772 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:24:23 INFO - 1498825463772 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463775 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463778 Marionette TRACE 404 <- [1,1,null,{"sessionId":"fd5ce8c0-1b78-4479-a29a-3b03ae088178","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463779 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
05:24:23 INFO - 1498825463780 Marionette TRACE 404 <- [1,2,null,{}]
05:24:23 INFO - 1498825463782 Marionette DEBUG Closed connection 404
05:24:23 INFO - 1498825463782 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49796
05:24:23 INFO - 1498825463783 Marionette DEBUG Closed connection 405
05:24:23 INFO - 1498825463784 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49797
05:24:23 INFO - 1498825463785 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:24:23 INFO - 1498825463786 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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}
05:24:23 INFO - 1498825463794 Marionette DEBUG Register listener.js for window 2147483649
05:24:23 INFO - 1498825463797 Marionette TRACE 406 <- [1,1,null,{"sessionId":"ca8f440c-ac51-4e70-89c1-9792470af83f","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":3280,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpel4_1f.mozrunner","moz:accessibilityChecks":false}}]
05:24:23 INFO - 1498825463798 Marionette TRACE 406 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:24:23 INFO - 1498825463800 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:24:23 INFO - 1498825463803 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:24:23 INFO - 1498825463803 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:24:23 INFO - 1498825463810 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:24:23 INFO - 1498825463813 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:24:23 INFO - 1498825463818 Marionette TRACE 406 <- [1,2,null,{}]
05:24:23 INFO - 1498825463826 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:24:23 INFO - 1498825463830 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f1ab765-61c6-4843-a248-72a74e23aff5","ELEMENT":"2f1ab765-61c6-4843-a248-72a74e23aff5"}}]
05:24:23 INFO - 1498825463831 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2f1ab765-61c6-4843-a248-72a74e23aff5"}]
05:24:23 INFO - 1498825463854 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
05:24:23 INFO - 1498825463855 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"2f1ab765-61c6-4843-a248-72a74e23aff5"}]
05:24:23 INFO - 1498825463872 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element