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]
09:45:49 INFO - 1501001149556 Marionette TRACE 353 -> [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}]
09:45:49 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501001149560 Marionette TRACE 353 <- [1,5,null,{"value":null}]
09:45:49 INFO - 1501001149561 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
09:45:49 INFO - 1501001149562 Marionette TRACE 353 <- [1,6,null,{}]
09:45:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 146ms
09:45:49 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:45:49 INFO - 1501001149563 Marionette DEBUG Closed connection 353
09:45:49 INFO - 1501001149565 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49814
09:45:49 INFO - 1501001149566 Marionette DEBUG Closed connection 354
09:45:49 INFO - 1501001149567 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49815
09:45:49 INFO - 1501001149568 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:49 INFO - 1501001149569 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:49 INFO - 1501001149571 Marionette DEBUG Register listener.js for window 4294967297
09:45:49 INFO - 1501001149574 Marionette TRACE 355 <- [1,1,null,{"sessionId":"22fb48c3-b40f-4e2a-9baa-53991450e309","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:49 INFO - 1501001149576 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
09:45:49 INFO - 1501001149576 Marionette TRACE 355 <- [1,2,null,{}]
09:45:49 INFO - 1501001149577 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:49 INFO - 1501001149578 Marionette TRACE 355 <- [1,3,null,{}]
09:45:49 INFO - 1501001149579 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:49 INFO - 1501001149579 Marionette TRACE 355 <- [1,4,null,{}]
09:45:49 INFO - 1501001149581 Marionette TRACE 355 -> [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}]
09:45:49 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501001149584 Marionette TRACE 355 <- [1,5,null,{"value":null}]
09:45:49 INFO - 1501001149585 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
09:45:49 INFO - 1501001149587 Marionette TRACE 355 <- [1,6,null,{}]
09:45:49 INFO - 1501001149588 Marionette DEBUG Closed connection 355
09:45:49 INFO - 1501001149589 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49816
09:45:49 INFO - 1501001149589 Marionette DEBUG Closed connection 356
09:45:49 INFO - 1501001149590 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49817
09:45:49 INFO - 1501001149591 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:49 INFO - 1501001149593 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:49 INFO - 1501001149595 Marionette DEBUG Register listener.js for window 4294967297
09:45:49 INFO - 1501001149598 Marionette TRACE 357 <- [1,1,null,{"sessionId":"5796da1c-4303-414b-bcfe-0e011fddd8ee","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:49 INFO - 1501001149599 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
09:45:49 INFO - 1501001149601 Marionette TRACE 357 <- [1,2,null,{}]
09:45:49 INFO - 1501001149602 Marionette DEBUG Closed connection 357
09:45:49 INFO - 1501001149603 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49818
09:45:49 INFO - 1501001149603 Marionette DEBUG Closed connection 358
09:45:49 INFO - 1501001149604 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49819
09:45:49 INFO - 1501001149605 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:49 INFO - 1501001149606 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:49 INFO - 1501001149608 Marionette DEBUG Register listener.js for window 4294967297
09:45:49 INFO - 1501001149611 Marionette TRACE 359 <- [1,1,null,{"sessionId":"2d95457e-7a58-44f1-8dc2-905161b58b1d","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:49 INFO - 1501001149612 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49376/clicks.html"}]
09:45:49 INFO - 1501001149614 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49376/hidden.html"
09:45:49 INFO - 1501001149623 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49376/hidden.html"
09:45:49 INFO - 1501001149624 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49376/hidden.html"
09:45:49 INFO - 1501001149630 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49376/clicks.html"
09:45:49 INFO - 1501001149635 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49376/clicks.html"
09:45:49 INFO - 1501001149638 Marionette TRACE 359 <- [1,2,null,{}]
09:45:49 INFO - 1501001149642 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:45:49 INFO - 1501001149646 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"77d9af14-1a8c-443c-85f8-3c2d427bd55a","ELEMENT":"77d9af14-1a8c-443c-85f8-3c2d427bd55a"}}]
09:45:49 INFO - 1501001149647 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"77d9af14-1a8c-443c-85f8-3c2d427bd55a"}]
09:45:49 INFO - 1501001149687 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49376/clicks.html"
09:45:49 INFO - 1501001149695 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49376/clicks.html"
09:45:49 INFO - 1501001149695 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49376/clicks.html"
09:45:49 INFO - 1501001149705 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49376/test.html"
09:45:49 INFO - 1501001149706 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49376/test.html"
09:45:49 INFO - 1501001149712 Marionette TRACE 359 <- [1,4,null,{}]
09:45:49 INFO - 1501001149717 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:45:49 INFO - 1501001149723 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9b81003f-3942-4046-b47c-c55d72a49344","ELEMENT":"9b81003f-3942-4046-b47c-c55d72a49344"}}]
09:45:49 INFO - 1501001149724 Marionette TRACE 359 -> [0,6,"getTitle",{}]
09:45:49 INFO - 1501001149725 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
09:45:49 INFO - 1501001149727 Marionette TRACE 359 -> [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}]
09:45:49 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501001149730 Marionette TRACE 359 <- [1,7,null,{"value":null}]
09:45:49 INFO - 1501001149731 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
09:45:49 INFO - 1501001149732 Marionette TRACE 359 <- [1,8,null,{}]
09:45:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 169ms
09:45:49 INFO - TEST-START | test_click.py TestClick.test_container_element
09:45:49 INFO - 1501001149734 Marionette DEBUG Closed connection 359
09:45:49 INFO - 1501001149736 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49822
09:45:49 INFO - 1501001149737 Marionette DEBUG Closed connection 360
09:45:49 INFO - 1501001149737 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49823
09:45:49 INFO - 1501001149739 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:49 INFO - 1501001149739 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:49 INFO - 1501001149741 Marionette DEBUG Register listener.js for window 4294967297
09:45:49 INFO - 1501001149755 Marionette TRACE 361 <- [1,1,null,{"sessionId":"8ec9068a-31ce-4f59-b002-ddd4126237cd","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:49 INFO - 1501001149756 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
09:45:49 INFO - 1501001149756 Marionette TRACE 361 <- [1,2,null,{}]
09:45:49 INFO - 1501001149757 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:49 INFO - 1501001149758 Marionette TRACE 361 <- [1,3,null,{}]
09:45:49 INFO - 1501001149758 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:49 INFO - 1501001149759 Marionette TRACE 361 <- [1,4,null,{}]
09:45:49 INFO - 1501001149761 Marionette TRACE 361 -> [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}]
09:45:49 INFO - TEST-START: test_click.py TestClick.test_container_element1501001149764 Marionette TRACE 361 <- [1,5,null,{"value":null}]
09:45:49 INFO - 1501001149765 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
09:45:49 INFO - 1501001149766 Marionette TRACE 361 <- [1,6,null,{}]
09:45:49 INFO - 1501001149767 Marionette DEBUG Closed connection 361
09:45:49 INFO - 1501001149768 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49824
09:45:49 INFO - 1501001149769 Marionette DEBUG Closed connection 362
09:45:49 INFO - 1501001149770 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49825
09:45:49 INFO - 1501001149771 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:49 INFO - 1501001149772 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:49 INFO - 1501001149774 Marionette DEBUG Register listener.js for window 4294967297
09:45:49 INFO - 1501001149778 Marionette TRACE 363 <- [1,1,null,{"sessionId":"a9a5997b-4197-41ec-8ef2-2fe6f9e70052","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:49 INFO - 1501001149779 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
09:45:49 INFO - 1501001149780 Marionette TRACE 363 <- [1,2,null,{}]
09:45:49 INFO - 1501001149781 Marionette DEBUG Closed connection 363
09:45:49 INFO - 1501001149782 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49826
09:45:49 INFO - 1501001149783 Marionette DEBUG Closed connection 364
09:45:49 INFO - 1501001149784 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49827
09:45:49 INFO - 1501001149785 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:49 INFO - 1501001149786 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:49 INFO - 1501001149788 Marionette DEBUG Register listener.js for window 4294967297
09:45:49 INFO - 1501001149791 Marionette TRACE 365 <- [1,1,null,{"sessionId":"aa0707f1-884e-4176-868f-23829ddc0cf7","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:49 INFO - 1501001149792 Marionette TRACE 365 -> [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"}]
09:45:49 INFO - 1501001149794 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49376/test.html"
09:45:49 INFO - 1501001149796 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49376/test.html"
09:45:49 INFO - 1501001149796 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49376/test.html"
09:45:49 INFO - 1501001149803 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"
09:45:49 INFO - 1501001149806 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"
09:45:49 INFO - 1501001149815 Marionette TRACE 365 <- [1,2,null,{}]
09:45:49 INFO - 1501001149823 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:45:49 INFO - 1501001149826 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da5a5fce-e183-43a3-a96a-d146d418128e","ELEMENT":"da5a5fce-e183-43a3-a96a-d146d418128e"}}]
09:45:49 INFO - 1501001149828 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"da5a5fce-e183-43a3-a96a-d146d418128e"}]
09:45:50 INFO - 1501001150067 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:50 INFO - 1501001150068 Marionette TRACE 365 <- [1,4,null,{}]
09:45:50 INFO - 1501001150069 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"da5a5fce-e183-43a3-a96a-d146d418128e","name":"selected"}]
09:45:50 INFO - 1501001150073 Marionette TRACE 365 <- [1,5,null,{"value":true}]
09:45:50 INFO - 1501001150075 Marionette TRACE 365 -> [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}]
09:45:50 INFO - TEST-END: test_click.py TestClick.test_container_element1501001150078 Marionette TRACE 365 <- [1,6,null,{"value":null}]
09:45:50 INFO - 1501001150079 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
09:45:50 INFO - 1501001150080 Marionette TRACE 365 <- [1,7,null,{}]
09:45:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 347ms
09:45:50 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:45:50 INFO - 1501001150082 Marionette DEBUG Closed connection 365
09:45:50 INFO - 1501001150083 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49828
09:45:50 INFO - 1501001150084 Marionette DEBUG Closed connection 366
09:45:50 INFO - 1501001150084 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49829
09:45:50 INFO - 1501001150085 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:50 INFO - 1501001150086 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150088 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150091 Marionette TRACE 367 <- [1,1,null,{"sessionId":"fd91bbf6-2585-4d71-b132-79ad5034f440","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150092 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
09:45:50 INFO - 1501001150092 Marionette TRACE 367 <- [1,2,null,{}]
09:45:50 INFO - 1501001150093 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:50 INFO - 1501001150094 Marionette TRACE 367 <- [1,3,null,{}]
09:45:50 INFO - 1501001150094 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:50 INFO - 1501001150095 Marionette TRACE 367 <- [1,4,null,{}]
09:45:50 INFO - 1501001150097 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
09:45:50 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501001150099 Marionette TRACE 367 <- [1,5,null,{"value":null}]
09:45:50 INFO - 1501001150101 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
09:45:50 INFO - 1501001150102 Marionette TRACE 367 <- [1,6,null,{}]
09:45:50 INFO - 1501001150104 Marionette DEBUG Closed connection 367
09:45:50 INFO - 1501001150104 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49830
09:45:50 INFO - 1501001150105 Marionette DEBUG Closed connection 368
09:45:50 INFO - 1501001150106 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49831
09:45:50 INFO - 1501001150107 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:50 INFO - 1501001150107 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150109 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150112 Marionette TRACE 369 <- [1,1,null,{"sessionId":"ee67828e-adef-41ea-8052-f3399721b21e","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150113 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
09:45:50 INFO - 1501001150114 Marionette TRACE 369 <- [1,2,null,{}]
09:45:50 INFO - 1501001150116 Marionette DEBUG Closed connection 369
09:45:50 INFO - 1501001150116 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49832
09:45:50 INFO - 1501001150117 Marionette DEBUG Closed connection 370
09:45:50 INFO - 1501001150118 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49833
09:45:50 INFO - 1501001150119 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:50 INFO - 1501001150120 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150122 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150125 Marionette TRACE 371 <- [1,1,null,{"sessionId":"874f965b-43ac-4be9-8b3f-28ec738ce8f5","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150126 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%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"}]
09:45:50 INFO - 1501001150128 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"
09:45:50 INFO - 1501001150130 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"
09:45:50 INFO - 1501001150130 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"
09:45:50 INFO - 1501001150136 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"
09:45:50 INFO - 1501001150139 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"
09:45:50 INFO - 1501001150153 Marionette TRACE 371 <- [1,2,null,{}]
09:45:50 INFO - 1501001150166 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:45:50 INFO - 1501001150170 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4ba5d49c-19f2-43da-8ad0-73f31850a53d","ELEMENT":"4ba5d49c-19f2-43da-8ad0-73f31850a53d"}}]
09:45:50 INFO - 1501001150172 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"4ba5d49c-19f2-43da-8ad0-73f31850a53d"}]
09:45:50 INFO - 1501001150423 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:50 INFO - 1501001150424 Marionette TRACE 371 <- [1,4,null,{}]
09:45:50 INFO - 1501001150425 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"4ba5d49c-19f2-43da-8ad0-73f31850a53d","name":"selected"}]
09:45:50 INFO - 1501001150430 Marionette TRACE 371 <- [1,5,null,{"value":true}]
09:45:50 INFO - 1501001150431 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
09:45:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501001150434 Marionette TRACE 371 <- [1,6,null,{"value":null}]
09:45:50 INFO - 1501001150435 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
09:45:50 INFO - 1501001150436 Marionette TRACE 371 <- [1,7,null,{}]
09:45:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 355ms
09:45:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:45:50 INFO - 1501001150438 Marionette DEBUG Closed connection 371
09:45:50 INFO - 1501001150439 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49834
09:45:50 INFO - 1501001150440 Marionette DEBUG Closed connection 372
09:45:50 INFO - 1501001150440 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49835
09:45:50 INFO - 1501001150441 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:50 INFO - 1501001150442 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150444 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150447 Marionette TRACE 373 <- [1,1,null,{"sessionId":"5b7f6918-164a-48d1-a603-28dc18693364","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150448 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
09:45:50 INFO - 1501001150449 Marionette TRACE 373 <- [1,2,null,{}]
09:45:50 INFO - 1501001150449 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:50 INFO - 1501001150450 Marionette TRACE 373 <- [1,3,null,{}]
09:45:50 INFO - 1501001150451 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:50 INFO - 1501001150451 Marionette TRACE 373 <- [1,4,null,{}]
09:45:50 INFO - 1501001150454 Marionette TRACE 373 -> [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}]
09:45:50 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501001150463 Marionette TRACE 373 <- [1,5,null,{"value":null}]
09:45:50 INFO - 1501001150465 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
09:45:50 INFO - 1501001150466 Marionette TRACE 373 <- [1,6,null,{}]
09:45:50 INFO - 1501001150467 Marionette DEBUG Closed connection 373
09:45:50 INFO - 1501001150468 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49836
09:45:50 INFO - 1501001150469 Marionette DEBUG Closed connection 374
09:45:50 INFO - 1501001150470 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49837
09:45:50 INFO - 1501001150471 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:50 INFO - 1501001150471 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150473 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150476 Marionette TRACE 375 <- [1,1,null,{"sessionId":"e79c8e40-6b14-447a-8e11-7514425c5908","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150477 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
09:45:50 INFO - 1501001150479 Marionette TRACE 375 <- [1,2,null,{}]
09:45:50 INFO - 1501001150480 Marionette DEBUG Closed connection 375
09:45:50 INFO - 1501001150481 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49838
09:45:50 INFO - 1501001150482 Marionette DEBUG Closed connection 376
09:45:50 INFO - 1501001150483 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49839
09:45:50 INFO - 1501001150484 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:50 INFO - 1501001150484 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150486 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150490 Marionette TRACE 377 <- [1,1,null,{"sessionId":"6a734983-db56-49fc-a810-1d036fcb94d0","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150491 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%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"}]
09:45:50 INFO - 1501001150493 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"
09:45:50 INFO - 1501001150495 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"
09:45:50 INFO - 1501001150495 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"
09:45:50 INFO - 1501001150503 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"
09:45:50 INFO - 1501001150505 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"
09:45:50 INFO - 1501001150512 Marionette TRACE 377 <- [1,2,null,{}]
09:45:50 INFO - 1501001150515 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:45:50 INFO - 1501001150521 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2dc5e650-84d2-404d-99e0-5efdce7fcd16","ELEMENT":"2dc5e650-84d2-404d-99e0-5efdce7fcd16"}}]
09:45:50 INFO - 1501001150523 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"2dc5e650-84d2-404d-99e0-5efdce7fcd16"}]
09:45:50 INFO - 1501001150764 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:50 INFO - 1501001150765 Marionette TRACE 377 <- [1,4,null,{}]
09:45:50 INFO - 1501001150767 Marionette TRACE 377 -> [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}]
09:45:50 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501001150770 Marionette TRACE 377 <- [1,5,null,{"value":null}]
09:45:50 INFO - 1501001150771 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
09:45:50 INFO - 1501001150772 Marionette TRACE 377 <- [1,6,null,{}]
09:45:50 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 336ms
09:45:50 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:45:50 INFO - 1501001150773 Marionette DEBUG Closed connection 377
09:45:50 INFO - 1501001150775 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49840
09:45:50 INFO - 1501001150776 Marionette DEBUG Closed connection 378
09:45:50 INFO - 1501001150777 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49841
09:45:50 INFO - 1501001150778 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:50 INFO - 1501001150778 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150780 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150783 Marionette TRACE 379 <- [1,1,null,{"sessionId":"3e009721-8675-4b55-b948-e5cac47b4dcf","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150785 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
09:45:50 INFO - 1501001150785 Marionette TRACE 379 <- [1,2,null,{}]
09:45:50 INFO - 1501001150786 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:50 INFO - 1501001150786 Marionette TRACE 379 <- [1,3,null,{}]
09:45:50 INFO - 1501001150787 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:50 INFO - 1501001150788 Marionette TRACE 379 <- [1,4,null,{}]
09:45:50 INFO - 1501001150790 Marionette TRACE 379 -> [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}]
09:45:50 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501001150792 Marionette TRACE 379 <- [1,5,null,{"value":null}]
09:45:50 INFO - 1501001150793 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
09:45:50 INFO - 1501001150795 Marionette TRACE 379 <- [1,6,null,{}]
09:45:50 INFO - 1501001150796 Marionette DEBUG Closed connection 379
09:45:50 INFO - 1501001150796 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49842
09:45:50 INFO - 1501001150797 Marionette DEBUG Closed connection 380
09:45:50 INFO - 1501001150798 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49843
09:45:50 INFO - 1501001150799 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:50 INFO - 1501001150799 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150801 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150804 Marionette TRACE 381 <- [1,1,null,{"sessionId":"0d888b4f-6b14-4197-9a06-300bfd2115a4","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150805 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
09:45:50 INFO - 1501001150806 Marionette TRACE 381 <- [1,2,null,{}]
09:45:50 INFO - 1501001150808 Marionette DEBUG Closed connection 381
09:45:50 INFO - 1501001150808 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49844
09:45:50 INFO - 1501001150809 Marionette DEBUG Closed connection 382
09:45:50 INFO - 1501001150810 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49845
09:45:50 INFO - 1501001150811 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:50 INFO - 1501001150811 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:50 INFO - 1501001150813 Marionette DEBUG Register listener.js for window 4294967297
09:45:50 INFO - 1501001150816 Marionette TRACE 383 <- [1,1,null,{"sessionId":"5de11df8-d18f-4444-955f-004f3b3c6c42","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:50 INFO - 1501001150818 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%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"}]
09:45:50 INFO - 1501001150820 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"
09:45:50 INFO - 1501001150823 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"
09:45:50 INFO - 1501001150824 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"
09:45:50 INFO - 1501001150831 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"
09:45:50 INFO - 1501001150835 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"
09:45:50 INFO - 1501001150840 Marionette TRACE 383 <- [1,2,null,{}]
09:45:50 INFO - 1501001150844 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:45:50 INFO - 1501001150847 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f34f3937-f545-45da-9cd3-2100fe643f84","ELEMENT":"f34f3937-f545-45da-9cd3-2100fe643f84"}}]
09:45:50 INFO - 1501001150848 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"f34f3937-f545-45da-9cd3-2100fe643f84"}]
09:45:51 INFO - 1501001151087 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:51 INFO - 1501001151088 Marionette TRACE 383 <- [1,4,null,{}]
09:45:51 INFO - 1501001151090 Marionette TRACE 383 -> [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}]
09:45:51 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501001151092 Marionette TRACE 383 <- [1,5,null,{"value":null}]
09:45:51 INFO - 1501001151093 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
09:45:51 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 321ms
09:45:51 INFO - TEST-START | test_click.py TestClick.test_input_file
09:45:51 INFO - 1501001151095 Marionette TRACE 383 <- [1,6,null,{}]
09:45:51 INFO - 1501001151096 Marionette DEBUG Closed connection 383
09:45:51 INFO - 1501001151097 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49846
09:45:51 INFO - 1501001151098 Marionette DEBUG Closed connection 384
09:45:51 INFO - 1501001151099 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49847
09:45:51 INFO - 1501001151100 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:51 INFO - 1501001151100 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151102 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151105 Marionette TRACE 385 <- [1,1,null,{"sessionId":"1edd64df-971b-4734-bc30-e64df5675107","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151107 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
09:45:51 INFO - 1501001151108 Marionette TRACE 385 <- [1,2,null,{}]
09:45:51 INFO - 1501001151109 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:51 INFO - 1501001151109 Marionette TRACE 385 <- [1,3,null,{}]
09:45:51 INFO - 1501001151110 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:51 INFO - 1501001151111 Marionette TRACE 385 <- [1,4,null,{}]
09:45:51 INFO - 1501001151112 Marionette TRACE 385 -> [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}]
09:45:51 INFO - TEST-START: test_click.py TestClick.test_input_file1501001151116 Marionette TRACE 385 <- [1,5,null,{"value":null}]
09:45:51 INFO - 1501001151117 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
09:45:51 INFO - 1501001151118 Marionette TRACE 385 <- [1,6,null,{}]
09:45:51 INFO - 1501001151119 Marionette DEBUG Closed connection 385
09:45:51 INFO - 1501001151120 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49848
09:45:51 INFO - 1501001151121 Marionette DEBUG Closed connection 386
09:45:51 INFO - 1501001151122 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49849
09:45:51 INFO - 1501001151123 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:51 INFO - 1501001151123 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151125 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151129 Marionette TRACE 387 <- [1,1,null,{"sessionId":"152e0a83-e64e-470a-96b6-6dc37c8267ef","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151130 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
09:45:51 INFO - 1501001151131 Marionette TRACE 387 <- [1,2,null,{}]
09:45:51 INFO - 1501001151133 Marionette DEBUG Closed connection 387
09:45:51 INFO - 1501001151133 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49850
09:45:51 INFO - 1501001151134 Marionette DEBUG Closed connection 388
09:45:51 INFO - 1501001151135 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49851
09:45:51 INFO - 1501001151136 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:51 INFO - 1501001151137 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151139 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151142 Marionette TRACE 389 <- [1,1,null,{"sessionId":"d4b36994-e7b8-40ce-98b6-ddd7d890f8c6","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151143 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:45:51 INFO - 1501001151145 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"
09:45:51 INFO - 1501001151147 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"
09:45:51 INFO - 1501001151147 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"
09:45:51 INFO - 1501001151154 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:51 INFO - 1501001151157 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:51 INFO - 1501001151161 Marionette TRACE 389 <- [1,2,null,{}]
09:45:51 INFO - 1501001151164 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:45:51 INFO - 1501001151172 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a920ac05-9a04-42fc-9a82-d740d2587aff","ELEMENT":"a920ac05-9a04-42fc-9a82-d740d2587aff"}}]
09:45:51 INFO - 1501001151173 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"a920ac05-9a04-42fc-9a82-d740d2587aff"}]
09:45:51 INFO - 1501001151204 Marionette TRACE 389 <- [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]
09:45:51 INFO - 1501001151206 Marionette TRACE 389 -> [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}]
09:45:51 INFO - TEST-END: test_click.py TestClick.test_input_file1501001151209 Marionette TRACE 389 <- [1,5,null,{"value":null}]
09:45:51 INFO - 1501001151210 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
09:45:51 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 116ms
09:45:51 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:45:51 INFO - 1501001151211 Marionette TRACE 389 <- [1,6,null,{}]
09:45:51 INFO - 1501001151212 Marionette DEBUG Closed connection 389
09:45:51 INFO - 1501001151213 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49852
09:45:51 INFO - 1501001151214 Marionette DEBUG Closed connection 390
09:45:51 INFO - 1501001151215 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49853
09:45:51 INFO - 1501001151216 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:51 INFO - 1501001151216 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151218 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151221 Marionette TRACE 391 <- [1,1,null,{"sessionId":"d6c409d7-a6e4-479e-95c5-01ec205021e6","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151222 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
09:45:51 INFO - 1501001151223 Marionette TRACE 391 <- [1,2,null,{}]
09:45:51 INFO - 1501001151223 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:51 INFO - 1501001151224 Marionette TRACE 391 <- [1,3,null,{}]
09:45:51 INFO - 1501001151225 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:51 INFO - 1501001151225 Marionette TRACE 391 <- [1,4,null,{}]
09:45:51 INFO - 1501001151227 Marionette TRACE 391 -> [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}]
09:45:51 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501001151230 Marionette TRACE 391 <- [1,5,null,{"value":null}]
09:45:51 INFO - 1501001151230 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
09:45:51 INFO - 1501001151232 Marionette TRACE 391 <- [1,6,null,{}]
09:45:51 INFO - 1501001151233 Marionette DEBUG Closed connection 391
09:45:51 INFO - 1501001151233 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49854
09:45:51 INFO - 1501001151234 Marionette DEBUG Closed connection 392
09:45:51 INFO - 1501001151235 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49855
09:45:51 INFO - 1501001151236 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:51 INFO - 1501001151236 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151238 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151242 Marionette TRACE 393 <- [1,1,null,{"sessionId":"3a64baaf-3b3f-4a6b-b3f2-4d415cc2709a","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151243 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
09:45:51 INFO - 1501001151244 Marionette TRACE 393 <- [1,2,null,{}]
09:45:51 INFO - 1501001151246 Marionette DEBUG Closed connection 393
09:45:51 INFO - 1501001151246 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49856
09:45:51 INFO - 1501001151247 Marionette DEBUG Closed connection 394
09:45:51 INFO - 1501001151248 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49857
09:45:51 INFO - 1501001151249 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:51 INFO - 1501001151249 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151251 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151255 Marionette TRACE 395 <- [1,1,null,{"sessionId":"4e82889a-96f9-478d-a4ca-33a4421cbf0b","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151256 Marionette TRACE 395 -> [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"}]
09:45:51 INFO - 1501001151258 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:51 INFO - 1501001151259 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:51 INFO - 1501001151260 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:45:51 INFO - 1501001151266 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"
09:45:51 INFO - 1501001151268 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"
09:45:51 INFO - 1501001151276 Marionette TRACE 395 <- [1,2,null,{}]
09:45:51 INFO - 1501001151280 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:45:51 INFO - 1501001151283 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e6ae8037-1030-42aa-86de-5311b2272638","ELEMENT":"e6ae8037-1030-42aa-86de-5311b2272638"}}]
09:45:51 INFO - 1501001151289 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:45:51 INFO - 1501001151292 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ec26eaf-c234-47c8-9624-0fc01fbf2587","ELEMENT":"8ec26eaf-c234-47c8-9624-0fc01fbf2587"}}]
09:45:51 INFO - 1501001151293 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"e6ae8037-1030-42aa-86de-5311b2272638"}]
09:45:51 INFO - 1501001151529 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:45:51 INFO - 1501001151530 Marionette TRACE 395 <- [1,5,null,{}]
09:45:51 INFO - 1501001151531 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"8ec26eaf-c234-47c8-9624-0fc01fbf2587"}]
09:45:51 INFO - 1501001151545 Marionette TRACE 395 <- [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]
09:45:51 INFO - 1501001151547 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:45:51 INFO - 1501001151550 Marionette TRACE 395 <- [1,7,null,{"value":false}]
09:45:51 INFO - 1501001151551 Marionette TRACE 395 -> [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}]
09:45:51 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501001151554 Marionette TRACE 395 <- [1,8,null,{"value":null}]
09:45:51 INFO - 1501001151555 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
09:45:51 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 344ms
09:45:51 INFO - 1501001151556 Marionette TRACE 395 <- [1,9,null,{}]
09:45:51 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:45:51 INFO - 1501001151557 Marionette DEBUG Closed connection 395
09:45:51 INFO - 1501001151558 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49858
09:45:51 INFO - 1501001151559 Marionette DEBUG Closed connection 396
09:45:51 INFO - 1501001151560 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49859
09:45:51 INFO - 1501001151561 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:51 INFO - 1501001151561 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151563 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151574 Marionette TRACE 397 <- [1,1,null,{"sessionId":"9f566cc3-d257-45da-9f69-d5987688f681","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151576 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
09:45:51 INFO - 1501001151577 Marionette TRACE 397 <- [1,2,null,{}]
09:45:51 INFO - 1501001151577 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:45:51 INFO - 1501001151578 Marionette TRACE 397 <- [1,3,null,{}]
09:45:51 INFO - 1501001151579 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
09:45:51 INFO - 1501001151579 Marionette TRACE 397 <- [1,4,null,{}]
09:45:51 INFO - 1501001151581 Marionette TRACE 397 -> [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}]
09:45:51 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501001151584 Marionette TRACE 397 <- [1,5,null,{"value":null}]
09:45:51 INFO - 1501001151585 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
09:45:51 INFO - 1501001151586 Marionette TRACE 397 <- [1,6,null,{}]
09:45:51 INFO - 1501001151588 Marionette DEBUG Closed connection 397
09:45:51 INFO - 1501001151588 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49860
09:45:51 INFO - 1501001151589 Marionette DEBUG Closed connection 398
09:45:51 INFO - 1501001151590 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49861
09:45:51 INFO - 1501001151591 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:45:51 INFO - 1501001151591 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151593 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151596 Marionette TRACE 399 <- [1,1,null,{"sessionId":"5401eab8-22cc-4acf-bb13-95b5d4b1a7ae","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151597 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
09:45:51 INFO - 1501001151599 Marionette TRACE 399 <- [1,2,null,{}]
09:45:51 INFO - 1501001151600 Marionette DEBUG Closed connection 399
09:45:51 INFO - 1501001151601 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49862
09:45:51 INFO - 1501001151601 Marionette DEBUG Closed connection 400
09:45:51 INFO - 1501001151602 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49863
09:45:51 INFO - 1501001151603 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:45:51 INFO - 1501001151604 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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}
09:45:51 INFO - 1501001151606 Marionette DEBUG Register listener.js for window 4294967297
09:45:51 INFO - 1501001151609 Marionette TRACE 401 <- [1,1,null,{"sessionId":"fd7b48df-472d-4178-9043-13ae9bb67db2","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":760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuwvsk6.mozrunner","moz:accessibilityChecks":false}}]
09:45:51 INFO - 1501001151610 Marionette TRACE 401 -> [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"}]
09:45:51 INFO - 1501001151612 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"
09:45:51 INFO - 1501001151614 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"
09:45:51 INFO - 1501001151614 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"
09:45:51 INFO - 1501001151620 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"
09:45:51 INFO - 1501001151622 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"
09:45:51 INFO - 1501001151630 Marionette TRACE 401 <- [1,2,null,{}]
09:45:51 INFO - 1501001151637 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:45:51 INFO - 1501001151641 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8b160de8-d884-408b-b360-afe180a848ec","ELEMENT":"8b160de8-d884-408b-b360-afe180a848ec"}}]
09:45:51 INFO - 1501001151642 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8b160de8-d884-408b-b360-afe180a848ec"}]
09:45:51 INFO - 1501001151664 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
09:45:51 INFO - 1501001151665 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"8b160de8-d884-408b-b360-afe180a848ec"}]
09:45:51 INFO - 1501001151680 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element