could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:48:54 INFO - 1501069734607 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
04:48:54 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501069734610 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:48:54 INFO - 1501069734611 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:48:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 118ms
04:48:54 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:48:54 INFO - 1501069734612 Marionette TRACE 352 <- [1,6,null,{}]
04:48:54 INFO - 1501069734613 Marionette DEBUG Closed connection 352
04:48:54 INFO - 1501069734614 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49924
04:48:54 INFO - 1501069734615 Marionette DEBUG Closed connection 353
04:48:54 INFO - 1501069734616 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49925
04:48:54 INFO - 1501069734617 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:54 INFO - 1501069734617 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:54 INFO - 1501069734620 Marionette DEBUG Register listener.js for window 4294967297
04:48:54 INFO - 1501069734623 Marionette TRACE 354 <- [1,1,null,{"sessionId":"a19ca68f-3e46-455e-b7aa-b6ad838c221f","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:54 INFO - 1501069734624 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:48:54 INFO - 1501069734624 Marionette TRACE 354 <- [1,2,null,{}]
04:48:54 INFO - 1501069734625 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:48:54 INFO - 1501069734626 Marionette TRACE 354 <- [1,3,null,{}]
04:48:54 INFO - 1501069734627 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:48:54 INFO - 1501069734627 Marionette TRACE 354 <- [1,4,null,{}]
04:48:54 INFO - 1501069734629 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
04:48:54 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501069734631 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:48:54 INFO - 1501069734632 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:48:54 INFO - 1501069734633 Marionette TRACE 354 <- [1,6,null,{}]
04:48:54 INFO - 1501069734635 Marionette DEBUG Closed connection 354
04:48:54 INFO - 1501069734635 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49926
04:48:54 INFO - 1501069734636 Marionette DEBUG Closed connection 355
04:48:54 INFO - 1501069734637 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49927
04:48:54 INFO - 1501069734638 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:54 INFO - 1501069734638 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:54 INFO - 1501069734646 Marionette DEBUG Register listener.js for window 4294967297
04:48:54 INFO - 1501069734649 Marionette TRACE 356 <- [1,1,null,{"sessionId":"66e22817-3ad5-48de-a8c1-5f8aee8f062d","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:54 INFO - 1501069734650 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:48:54 INFO - 1501069734651 Marionette TRACE 356 <- [1,2,null,{}]
04:48:54 INFO - 1501069734653 Marionette DEBUG Closed connection 356
04:48:54 INFO - 1501069734653 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49928
04:48:54 INFO - 1501069734654 Marionette DEBUG Closed connection 357
04:48:54 INFO - 1501069734655 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49929
04:48:54 INFO - 1501069734656 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:48:54 INFO - 1501069734656 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:54 INFO - 1501069734658 Marionette DEBUG Register listener.js for window 4294967297
04:48:54 INFO - 1501069734670 Marionette TRACE 358 <- [1,1,null,{"sessionId":"d7085eb5-f7da-43cf-a035-3e7491fd9e87","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:54 INFO - 1501069734672 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49486/clicks.html"}]
04:48:54 INFO - 1501069734674 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49486/hidden.html"
04:48:54 INFO - 1501069734682 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49486/hidden.html"
04:48:54 INFO - 1501069734682 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49486/hidden.html"
04:48:54 INFO - 1501069734689 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49486/clicks.html"
04:48:54 INFO - 1501069734693 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49486/clicks.html"
04:48:54 INFO - 1501069734696 Marionette TRACE 358 <- [1,2,null,{}]
04:48:54 INFO - 1501069734700 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:48:54 INFO - 1501069734706 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f65a4a8-78e2-42ba-a3af-07e5a7f75a74","ELEMENT":"3f65a4a8-78e2-42ba-a3af-07e5a7f75a74"}}]
04:48:54 INFO - 1501069734707 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"3f65a4a8-78e2-42ba-a3af-07e5a7f75a74"}]
04:48:54 INFO - 1501069734745 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49486/clicks.html"
04:48:54 INFO - 1501069734752 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49486/clicks.html"
04:48:54 INFO - 1501069734752 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49486/clicks.html"
04:48:54 INFO - 1501069734759 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49486/test.html"
04:48:54 INFO - 1501069734761 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49486/test.html"
04:48:54 INFO - 1501069734766 Marionette TRACE 358 <- [1,4,null,{}]
04:48:54 INFO - 1501069734775 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:48:54 INFO - 1501069734778 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"01b04371-ec96-431e-9c93-fa4a79204285","ELEMENT":"01b04371-ec96-431e-9c93-fa4a79204285"}}]
04:48:54 INFO - 1501069734779 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:48:54 INFO - 1501069734779 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:48:54 INFO - 1501069734781 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
04:48:54 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501069734784 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:48:54 INFO - 1501069734785 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:48:54 INFO - 1501069734786 Marionette TRACE 358 <- [1,8,null,{}]
04:48:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 174ms
04:48:54 INFO - TEST-START | test_click.py TestClick.test_container_element
04:48:54 INFO - 1501069734788 Marionette DEBUG Closed connection 358
04:48:54 INFO - 1501069734789 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49932
04:48:54 INFO - 1501069734790 Marionette DEBUG Closed connection 359
04:48:54 INFO - 1501069734790 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49933
04:48:54 INFO - 1501069734791 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:54 INFO - 1501069734793 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:54 INFO - 1501069734795 Marionette DEBUG Register listener.js for window 4294967297
04:48:54 INFO - 1501069734798 Marionette TRACE 360 <- [1,1,null,{"sessionId":"9ac282bd-cf54-4e99-a5b0-6d7e6e772eb7","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:54 INFO - 1501069734799 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:48:54 INFO - 1501069734799 Marionette TRACE 360 <- [1,2,null,{}]
04:48:54 INFO - 1501069734800 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:48:54 INFO - 1501069734801 Marionette TRACE 360 <- [1,3,null,{}]
04:48:54 INFO - 1501069734802 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:48:54 INFO - 1501069734802 Marionette TRACE 360 <- [1,4,null,{}]
04:48:54 INFO - 1501069734804 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
04:48:54 INFO - TEST-START: test_click.py TestClick.test_container_element1501069734807 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:48:54 INFO - 1501069734808 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:48:54 INFO - 1501069734809 Marionette TRACE 360 <- [1,6,null,{}]
04:48:54 INFO - 1501069734811 Marionette DEBUG Closed connection 360
04:48:54 INFO - 1501069734811 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49934
04:48:54 INFO - 1501069734812 Marionette DEBUG Closed connection 361
04:48:54 INFO - 1501069734813 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49935
04:48:54 INFO - 1501069734814 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:54 INFO - 1501069734814 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:54 INFO - 1501069734816 Marionette DEBUG Register listener.js for window 4294967297
04:48:54 INFO - 1501069734819 Marionette TRACE 362 <- [1,1,null,{"sessionId":"6cf2fecc-97f0-412d-a78b-8381bce0312b","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:54 INFO - 1501069734820 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:48:54 INFO - 1501069734821 Marionette TRACE 362 <- [1,2,null,{}]
04:48:54 INFO - 1501069734822 Marionette DEBUG Closed connection 362
04:48:54 INFO - 1501069734823 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49936
04:48:54 INFO - 1501069734823 Marionette DEBUG Closed connection 363
04:48:54 INFO - 1501069734824 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49937
04:48:54 INFO - 1501069734825 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:48:54 INFO - 1501069734826 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:54 INFO - 1501069734828 Marionette DEBUG Register listener.js for window 4294967297
04:48:54 INFO - 1501069734831 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0eae33a9-d231-4f86-a91e-d38c85e5a3b0","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:54 INFO - 1501069734832 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:48:54 INFO - 1501069734833 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49486/test.html"
04:48:54 INFO - 1501069734835 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49486/test.html"
04:48:54 INFO - 1501069734835 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49486/test.html"
04:48:54 INFO - 1501069734841 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:54 INFO - 1501069734844 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:54 INFO - 1501069734847 Marionette TRACE 364 <- [1,2,null,{}]
04:48:54 INFO - 1501069734850 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:48:54 INFO - 1501069734857 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"24e877af-d1d5-4b18-a818-8ebb29d1c51b","ELEMENT":"24e877af-d1d5-4b18-a818-8ebb29d1c51b"}}]
04:48:54 INFO - 1501069734859 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"24e877af-d1d5-4b18-a818-8ebb29d1c51b"}]
04:48:55 INFO - 1501069735098 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:48:55 INFO - 1501069735099 Marionette TRACE 364 <- [1,4,null,{}]
04:48:55 INFO - 1501069735100 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"24e877af-d1d5-4b18-a818-8ebb29d1c51b","name":"selected"}]
04:48:55 INFO - 1501069735104 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:48:55 INFO - 1501069735106 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
04:48:55 INFO - TEST-END: test_click.py TestClick.test_container_element1501069735109 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:48:55 INFO - 1501069735110 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:48:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 325ms
04:48:55 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:48:55 INFO - 1501069735111 Marionette TRACE 364 <- [1,7,null,{}]
04:48:55 INFO - 1501069735112 Marionette DEBUG Closed connection 364
04:48:55 INFO - 1501069735114 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49938
04:48:55 INFO - 1501069735114 Marionette DEBUG Closed connection 365
04:48:55 INFO - 1501069735115 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49939
04:48:55 INFO - 1501069735117 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:55 INFO - 1501069735117 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735119 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735123 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ea459d6c-6729-4490-8ced-99df110c6479","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735124 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:48:55 INFO - 1501069735124 Marionette TRACE 366 <- [1,2,null,{}]
04:48:55 INFO - 1501069735125 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:48:55 INFO - 1501069735125 Marionette TRACE 366 <- [1,3,null,{}]
04:48:55 INFO - 1501069735126 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:48:55 INFO - 1501069735126 Marionette TRACE 366 <- [1,4,null,{}]
04:48:55 INFO - 1501069735128 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
04:48:55 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501069735131 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:48:55 INFO - 1501069735131 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:48:55 INFO - 1501069735133 Marionette TRACE 366 <- [1,6,null,{}]
04:48:55 INFO - 1501069735134 Marionette DEBUG Closed connection 366
04:48:55 INFO - 1501069735134 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49940
04:48:55 INFO - 1501069735135 Marionette DEBUG Closed connection 367
04:48:55 INFO - 1501069735136 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49941
04:48:55 INFO - 1501069735137 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:55 INFO - 1501069735137 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735139 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735142 Marionette TRACE 368 <- [1,1,null,{"sessionId":"3eab8d19-38ba-4f7b-a5e4-a0fb60d5ea97","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735143 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:48:55 INFO - 1501069735144 Marionette TRACE 368 <- [1,2,null,{}]
04:48:55 INFO - 1501069735145 Marionette DEBUG Closed connection 368
04:48:55 INFO - 1501069735146 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49942
04:48:55 INFO - 1501069735147 Marionette DEBUG Closed connection 369
04:48:55 INFO - 1501069735147 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49943
04:48:55 INFO - 1501069735148 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:48:55 INFO - 1501069735149 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735150 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735153 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b1439c79-373c-4e03-9cab-839cb630dc80","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735154 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:48:55 INFO - 1501069735156 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735158 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735158 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735164 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735166 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735169 Marionette TRACE 370 <- [1,2,null,{}]
04:48:55 INFO - 1501069735177 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:48:55 INFO - 1501069735180 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"69cd771e-2900-44b1-9d36-3ddcff9c9f21","ELEMENT":"69cd771e-2900-44b1-9d36-3ddcff9c9f21"}}]
04:48:55 INFO - 1501069735181 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"69cd771e-2900-44b1-9d36-3ddcff9c9f21"}]
04:48:55 INFO - 1501069735420 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:48:55 INFO - 1501069735420 Marionette TRACE 370 <- [1,4,null,{}]
04:48:55 INFO - 1501069735422 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"69cd771e-2900-44b1-9d36-3ddcff9c9f21","name":"selected"}]
04:48:55 INFO - 1501069735425 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:48:55 INFO - 1501069735427 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
04:48:55 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501069735430 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:48:55 INFO - 1501069735431 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:48:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 320ms
04:48:55 INFO - 1501069735432 Marionette TRACE 370 <- [1,7,null,{}]
04:48:55 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:48:55 INFO - 1501069735433 Marionette DEBUG Closed connection 370
04:48:55 INFO - 1501069735434 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49944
04:48:55 INFO - 1501069735435 Marionette DEBUG Closed connection 371
04:48:55 INFO - 1501069735436 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49945
04:48:55 INFO - 1501069735437 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:55 INFO - 1501069735437 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735439 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735442 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c485b674-1dd0-424f-a420-2e2030108d0e","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735443 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:48:55 INFO - 1501069735443 Marionette TRACE 372 <- [1,2,null,{}]
04:48:55 INFO - 1501069735444 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:48:55 INFO - 1501069735445 Marionette TRACE 372 <- [1,3,null,{}]
04:48:55 INFO - 1501069735446 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:48:55 INFO - 1501069735446 Marionette TRACE 372 <- [1,4,null,{}]
04:48:55 INFO - 1501069735448 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
04:48:55 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501069735450 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:48:55 INFO - 1501069735451 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:48:55 INFO - 1501069735452 Marionette TRACE 372 <- [1,6,null,{}]
04:48:55 INFO - 1501069735453 Marionette DEBUG Closed connection 372
04:48:55 INFO - 1501069735454 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49946
04:48:55 INFO - 1501069735455 Marionette DEBUG Closed connection 373
04:48:55 INFO - 1501069735456 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49947
04:48:55 INFO - 1501069735457 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:55 INFO - 1501069735457 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735459 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735462 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f709dcf6-53bb-4b1f-b4b3-f74089cafc45","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735464 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:48:55 INFO - 1501069735466 Marionette TRACE 374 <- [1,2,null,{}]
04:48:55 INFO - 1501069735467 Marionette DEBUG Closed connection 374
04:48:55 INFO - 1501069735468 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49948
04:48:55 INFO - 1501069735468 Marionette DEBUG Closed connection 375
04:48:55 INFO - 1501069735469 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49949
04:48:55 INFO - 1501069735470 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:48:55 INFO - 1501069735471 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735472 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735475 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6e380574-3d22-4029-b308-2792152349ba","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735476 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
04:48:55 INFO - 1501069735478 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735480 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735480 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735494 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:48:55 INFO - 1501069735496 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:48:55 INFO - 1501069735506 Marionette TRACE 376 <- [1,2,null,{}]
04:48:55 INFO - 1501069735515 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:48:55 INFO - 1501069735526 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"130441db-e410-4e5a-92e1-5c3e1df8c55a","ELEMENT":"130441db-e410-4e5a-92e1-5c3e1df8c55a"}}]
04:48:55 INFO - 1501069735527 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"130441db-e410-4e5a-92e1-5c3e1df8c55a"}]
04:48:55 INFO - 1501069735772 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:48:55 INFO - 1501069735773 Marionette TRACE 376 <- [1,4,null,{}]
04:48:55 INFO - 1501069735775 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
04:48:55 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501069735777 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:48:55 INFO - 1501069735778 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:48:55 INFO - 1501069735779 Marionette TRACE 376 <- [1,6,null,{}]
04:48:55 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 347ms
04:48:55 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:48:55 INFO - 1501069735781 Marionette DEBUG Closed connection 376
04:48:55 INFO - 1501069735782 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49950
04:48:55 INFO - 1501069735782 Marionette DEBUG Closed connection 377
04:48:55 INFO - 1501069735783 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49951
04:48:55 INFO - 1501069735784 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:55 INFO - 1501069735785 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735787 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735790 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4be5b030-16c9-4f0d-98c9-ca81ad1d2997","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735791 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:48:55 INFO - 1501069735791 Marionette TRACE 378 <- [1,2,null,{}]
04:48:55 INFO - 1501069735792 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:48:55 INFO - 1501069735793 Marionette TRACE 378 <- [1,3,null,{}]
04:48:55 INFO - 1501069735794 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:48:55 INFO - 1501069735794 Marionette TRACE 378 <- [1,4,null,{}]
04:48:55 INFO - 1501069735796 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
04:48:55 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501069735798 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:48:55 INFO - 1501069735799 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:48:55 INFO - 1501069735800 Marionette TRACE 378 <- [1,6,null,{}]
04:48:55 INFO - 1501069735802 Marionette DEBUG Closed connection 378
04:48:55 INFO - 1501069735802 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49952
04:48:55 INFO - 1501069735803 Marionette DEBUG Closed connection 379
04:48:55 INFO - 1501069735804 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49953
04:48:55 INFO - 1501069735805 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:55 INFO - 1501069735805 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735807 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735817 Marionette TRACE 380 <- [1,1,null,{"sessionId":"fc78c3ed-6c7b-4f91-99b0-47334f3756b7","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735818 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:48:55 INFO - 1501069735820 Marionette TRACE 380 <- [1,2,null,{}]
04:48:55 INFO - 1501069735821 Marionette DEBUG Closed connection 380
04:48:55 INFO - 1501069735822 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49954
04:48:55 INFO - 1501069735822 Marionette DEBUG Closed connection 381
04:48:55 INFO - 1501069735823 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49955
04:48:55 INFO - 1501069735824 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:48:55 INFO - 1501069735825 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:55 INFO - 1501069735826 Marionette DEBUG Register listener.js for window 4294967297
04:48:55 INFO - 1501069735829 Marionette TRACE 382 <- [1,1,null,{"sessionId":"11b7bfd6-b052-437f-bda0-5b3affa56abe","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:55 INFO - 1501069735830 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:48:55 INFO - 1501069735832 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:48:55 INFO - 1501069735835 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:48:55 INFO - 1501069735835 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:48:55 INFO - 1501069735842 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735846 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:55 INFO - 1501069735848 Marionette TRACE 382 <- [1,2,null,{}]
04:48:55 INFO - 1501069735857 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:48:55 INFO - 1501069735860 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f52f46ce-dcc7-44e7-bda9-819293ed1623","ELEMENT":"f52f46ce-dcc7-44e7-bda9-819293ed1623"}}]
04:48:55 INFO - 1501069735861 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f52f46ce-dcc7-44e7-bda9-819293ed1623"}]
04:48:56 INFO - 1501069736099 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:48:56 INFO - 1501069736100 Marionette TRACE 382 <- [1,4,null,{}]
04:48:56 INFO - 1501069736101 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
04:48:56 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501069736104 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:48:56 INFO - 1501069736105 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:48:56 INFO - 1501069736106 Marionette TRACE 382 <- [1,6,null,{}]
04:48:56 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 327ms
04:48:56 INFO - TEST-START | test_click.py TestClick.test_input_file
04:48:56 INFO - 1501069736108 Marionette DEBUG Closed connection 382
04:48:56 INFO - 1501069736109 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49956
04:48:56 INFO - 1501069736109 Marionette DEBUG Closed connection 383
04:48:56 INFO - 1501069736110 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49957
04:48:56 INFO - 1501069736111 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:56 INFO - 1501069736112 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736114 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736116 Marionette TRACE 384 <- [1,1,null,{"sessionId":"76cfb9ab-2ff4-4e3d-bcff-baddd0f72c74","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736117 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:48:56 INFO - 1501069736118 Marionette TRACE 384 <- [1,2,null,{}]
04:48:56 INFO - 1501069736119 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:48:56 INFO - 1501069736119 Marionette TRACE 384 <- [1,3,null,{}]
04:48:56 INFO - 1501069736120 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:48:56 INFO - 1501069736120 Marionette TRACE 384 <- [1,4,null,{}]
04:48:56 INFO - 1501069736122 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
04:48:56 INFO - TEST-START: test_click.py TestClick.test_input_file1501069736124 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:48:56 INFO - 1501069736125 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:48:56 INFO - 1501069736126 Marionette TRACE 384 <- [1,6,null,{}]
04:48:56 INFO - 1501069736128 Marionette DEBUG Closed connection 384
04:48:56 INFO - 1501069736128 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49958
04:48:56 INFO - 1501069736129 Marionette DEBUG Closed connection 385
04:48:56 INFO - 1501069736130 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49959
04:48:56 INFO - 1501069736131 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:56 INFO - 1501069736131 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736133 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736135 Marionette TRACE 386 <- [1,1,null,{"sessionId":"3ad23820-cc05-4fb7-a575-d484a5c0449a","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736136 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:48:56 INFO - 1501069736137 Marionette TRACE 386 <- [1,2,null,{}]
04:48:56 INFO - 1501069736139 Marionette DEBUG Closed connection 386
04:48:56 INFO - 1501069736139 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49960
04:48:56 INFO - 1501069736140 Marionette DEBUG Closed connection 387
04:48:56 INFO - 1501069736141 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49961
04:48:56 INFO - 1501069736142 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:48:56 INFO - 1501069736142 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736144 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736146 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e10cfbd0-4dd4-4783-b5dc-ee45c679f199","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736147 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:48:56 INFO - 1501069736149 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:56 INFO - 1501069736151 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:56 INFO - 1501069736152 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:48:56 INFO - 1501069736157 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:48:56 INFO - 1501069736161 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:48:56 INFO - 1501069736164 Marionette TRACE 388 <- [1,2,null,{}]
04:48:56 INFO - 1501069736167 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:48:56 INFO - 1501069736173 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"32143b13-0206-497f-a6dd-9564a61ffd92","ELEMENT":"32143b13-0206-497f-a6dd-9564a61ffd92"}}]
04:48:56 INFO - 1501069736174 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"32143b13-0206-497f-a6dd-9564a61ffd92"}]
04:48:56 INFO - 1501069736199 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:48:56 INFO - 1501069736201 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
04:48:56 INFO - TEST-END: test_click.py TestClick.test_input_file1501069736204 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:48:56 INFO - 1501069736205 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:48:56 INFO - 1501069736206 Marionette TRACE 388 <- [1,6,null,{}]
04:48:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 100ms
04:48:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:48:56 INFO - 1501069736208 Marionette DEBUG Closed connection 388
04:48:56 INFO - 1501069736209 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49962
04:48:56 INFO - 1501069736210 Marionette DEBUG Closed connection 389
04:48:56 INFO - 1501069736210 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49963
04:48:56 INFO - 1501069736211 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:56 INFO - 1501069736212 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736213 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736216 Marionette TRACE 390 <- [1,1,null,{"sessionId":"bfccc118-23a3-4ba5-8906-75e0adbd6102","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736217 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:48:56 INFO - 1501069736218 Marionette TRACE 390 <- [1,2,null,{}]
04:48:56 INFO - 1501069736219 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:48:56 INFO - 1501069736219 Marionette TRACE 390 <- [1,3,null,{}]
04:48:56 INFO - 1501069736220 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:48:56 INFO - 1501069736220 Marionette TRACE 390 <- [1,4,null,{}]
04:48:56 INFO - 1501069736222 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
04:48:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501069736224 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:48:56 INFO - 1501069736225 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:48:56 INFO - 1501069736226 Marionette TRACE 390 <- [1,6,null,{}]
04:48:56 INFO - 1501069736228 Marionette DEBUG Closed connection 390
04:48:56 INFO - 1501069736228 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49964
04:48:56 INFO - 1501069736229 Marionette DEBUG Closed connection 391
04:48:56 INFO - 1501069736230 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49965
04:48:56 INFO - 1501069736231 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:56 INFO - 1501069736231 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736233 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736235 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f3761bc3-bb58-415a-962a-6d0a6d8d2bcb","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736236 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:48:56 INFO - 1501069736238 Marionette TRACE 392 <- [1,2,null,{}]
04:48:56 INFO - 1501069736239 Marionette DEBUG Closed connection 392
04:48:56 INFO - 1501069736240 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49966
04:48:56 INFO - 1501069736240 Marionette DEBUG Closed connection 393
04:48:56 INFO - 1501069736241 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49967
04:48:56 INFO - 1501069736242 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:48:56 INFO - 1501069736242 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736244 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736254 Marionette TRACE 394 <- [1,1,null,{"sessionId":"1eac67a3-b162-40cb-a6a3-cda1b2e3fd73","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736255 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
04:48:56 INFO - 1501069736257 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:48:56 INFO - 1501069736259 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:48:56 INFO - 1501069736260 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:48:56 INFO - 1501069736266 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:48:56 INFO - 1501069736268 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:48:56 INFO - 1501069736275 Marionette TRACE 394 <- [1,2,null,{}]
04:48:56 INFO - 1501069736279 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:48:56 INFO - 1501069736281 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d3328847-a2fb-4a1c-8ca2-aa5afd3d887a","ELEMENT":"d3328847-a2fb-4a1c-8ca2-aa5afd3d887a"}}]
04:48:56 INFO - 1501069736282 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:48:56 INFO - 1501069736284 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ae7a440f-0279-4f9c-a165-9ec6cb5686a0","ELEMENT":"ae7a440f-0279-4f9c-a165-9ec6cb5686a0"}}]
04:48:56 INFO - 1501069736289 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"d3328847-a2fb-4a1c-8ca2-aa5afd3d887a"}]
04:48:56 INFO - 1501069736530 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:48:56 INFO - 1501069736531 Marionette TRACE 394 <- [1,5,null,{}]
04:48:56 INFO - 1501069736532 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"ae7a440f-0279-4f9c-a165-9ec6cb5686a0"}]
04:48:56 INFO - 1501069736545 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:48:56 INFO - 1501069736547 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:48:56 INFO - 1501069736550 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:48:56 INFO - 1501069736552 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
04:48:56 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501069736554 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:48:56 INFO - 1501069736555 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:48:56 INFO - 1501069736556 Marionette TRACE 394 <- [1,9,null,{}]
04:48:56 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 350ms
04:48:56 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:48:56 INFO - 1501069736557 Marionette DEBUG Closed connection 394
04:48:56 INFO - 1501069736559 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49968
04:48:56 INFO - 1501069736560 Marionette DEBUG Closed connection 395
04:48:56 INFO - 1501069736560 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49969
04:48:56 INFO - 1501069736561 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:56 INFO - 1501069736562 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736564 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736566 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a16f80b9-78d7-4f06-8e9f-66f0090dc72d","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736567 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:48:56 INFO - 1501069736568 Marionette TRACE 396 <- [1,2,null,{}]
04:48:56 INFO - 1501069736569 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:48:56 INFO - 1501069736569 Marionette TRACE 396 <- [1,3,null,{}]
04:48:56 INFO - 1501069736570 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:48:56 INFO - 1501069736571 Marionette TRACE 396 <- [1,4,null,{}]
04:48:56 INFO - 1501069736572 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
04:48:56 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501069736575 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:48:56 INFO - 1501069736576 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:48:56 INFO - 1501069736577 Marionette TRACE 396 <- [1,6,null,{}]
04:48:56 INFO - 1501069736578 Marionette DEBUG Closed connection 396
04:48:56 INFO - 1501069736579 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49970
04:48:56 INFO - 1501069736579 Marionette DEBUG Closed connection 397
04:48:56 INFO - 1501069736580 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49971
04:48:56 INFO - 1501069736581 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:48:56 INFO - 1501069736582 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736583 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736586 Marionette TRACE 398 <- [1,1,null,{"sessionId":"334cb140-8537-4ac0-b70b-1da0275b6aba","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736587 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:48:56 INFO - 1501069736589 Marionette TRACE 398 <- [1,2,null,{}]
04:48:56 INFO - 1501069736590 Marionette DEBUG Closed connection 398
04:48:56 INFO - 1501069736590 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49972
04:48:56 INFO - 1501069736591 Marionette DEBUG Closed connection 399
04:48:56 INFO - 1501069736592 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49973
04:48:56 INFO - 1501069736593 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:48:56 INFO - 1501069736594 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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}
04:48:56 INFO - 1501069736595 Marionette DEBUG Register listener.js for window 4294967297
04:48:56 INFO - 1501069736598 Marionette TRACE 400 <- [1,1,null,{"sessionId":"469e673b-8353-40f7-b85e-c28ee334d395","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":3468,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpfvplof.mozrunner","moz:accessibilityChecks":false}}]
04:48:56 INFO - 1501069736599 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
04:48:56 INFO - 1501069736601 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:48:56 INFO - 1501069736603 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:48:56 INFO - 1501069736603 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:48:56 INFO - 1501069736610 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
04:48:56 INFO - 1501069736612 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
04:48:56 INFO - 1501069736615 Marionette TRACE 400 <- [1,2,null,{}]
04:48:56 INFO - 1501069736617 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:48:56 INFO - 1501069736624 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8356e8ad-8c58-4bd8-94d7-2e15ababb2be","ELEMENT":"8356e8ad-8c58-4bd8-94d7-2e15ababb2be"}}]
04:48:56 INFO - 1501069736625 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8356e8ad-8c58-4bd8-94d7-2e15ababb2be"}]
04:48:56 INFO - 1501069736647 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:48:56 INFO - 1501069736648 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"8356e8ad-8c58-4bd8-94d7-2e15ababb2be"}]
04:48:56 INFO - 1501069736661 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element