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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
19:08:54 INFO - 1500516534558 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}]
19:08:54 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500516534561 Marionette TRACE 353 <- [1,5,null,{"value":null}]
19:08:54 INFO - 1500516534562 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
19:08:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 143ms
19:08:54 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:08:54 INFO - 1500516534564 Marionette TRACE 353 <- [1,6,null,{}]
19:08:54 INFO - 1500516534565 Marionette DEBUG Closed connection 353
19:08:54 INFO - 1500516534566 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49732
19:08:54 INFO - 1500516534567 Marionette DEBUG Closed connection 354
19:08:54 INFO - 1500516534567 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49733
19:08:54 INFO - 1500516534568 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:54 INFO - 1500516534569 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:54 INFO - 1500516534572 Marionette DEBUG Register listener.js for window 6442450945
19:08:54 INFO - 1500516534576 Marionette TRACE 355 <- [1,1,null,{"sessionId":"d98dc946-a6fc-4dee-8908-ac8b14d26d17","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:54 INFO - 1500516534577 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
19:08:54 INFO - 1500516534578 Marionette TRACE 355 <- [1,2,null,{}]
19:08:54 INFO - 1500516534579 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:08:54 INFO - 1500516534579 Marionette TRACE 355 <- [1,3,null,{}]
19:08:54 INFO - 1500516534580 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
19:08:54 INFO - 1500516534580 Marionette TRACE 355 <- [1,4,null,{}]
19:08:54 INFO - 1500516534582 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}]
19:08:54 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500516534585 Marionette TRACE 355 <- [1,5,null,{"value":null}]
19:08:54 INFO - 1500516534586 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
19:08:54 INFO - 1500516534587 Marionette TRACE 355 <- [1,6,null,{}]
19:08:54 INFO - 1500516534589 Marionette DEBUG Closed connection 355
19:08:54 INFO - 1500516534590 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49734
19:08:54 INFO - 1500516534591 Marionette DEBUG Closed connection 356
19:08:54 INFO - 1500516534591 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49735
19:08:54 INFO - 1500516534592 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:54 INFO - 1500516534593 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:54 INFO - 1500516534596 Marionette DEBUG Register listener.js for window 6442450945
19:08:54 INFO - 1500516534599 Marionette TRACE 357 <- [1,1,null,{"sessionId":"b8d3aea4-c4db-4d56-974d-4e45eb77f4e7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:54 INFO - 1500516534600 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
19:08:54 INFO - 1500516534602 Marionette TRACE 357 <- [1,2,null,{}]
19:08:54 INFO - 1500516534604 Marionette DEBUG Closed connection 357
19:08:54 INFO - 1500516534604 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49736
19:08:54 INFO - 1500516534605 Marionette DEBUG Closed connection 358
19:08:54 INFO - 1500516534606 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49737
19:08:54 INFO - 1500516534607 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:08:54 INFO - 1500516534608 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:54 INFO - 1500516534611 Marionette DEBUG Register listener.js for window 6442450945
19:08:54 INFO - 1500516534615 Marionette TRACE 359 <- [1,1,null,{"sessionId":"1e575a5b-4f49-40c2-afec-122f254a75eb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:54 INFO - 1500516534616 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49294/clicks.html"}]
19:08:54 INFO - 1500516534618 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49294/hidden.html"
19:08:54 INFO - 1500516534626 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49294/hidden.html"
19:08:54 INFO - 1500516534626 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49294/hidden.html"
19:08:54 INFO - 1500516534633 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49294/clicks.html"
19:08:54 INFO - 1500516534638 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49294/clicks.html"
19:08:54 INFO - 1500516534652 Marionette TRACE 359 <- [1,2,null,{}]
19:08:54 INFO - 1500516534659 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:08:54 INFO - 1500516534661 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"18973e5f-a779-4904-ad1f-4c93b1514347","ELEMENT":"18973e5f-a779-4904-ad1f-4c93b1514347"}}]
19:08:54 INFO - 1500516534666 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"18973e5f-a779-4904-ad1f-4c93b1514347"}]
19:08:54 INFO - 1500516534708 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49294/clicks.html"
19:08:54 INFO - 1500516534720 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49294/clicks.html"
19:08:54 INFO - 1500516534720 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49294/clicks.html"
19:08:54 INFO - 1500516534729 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49294/test.html"
19:08:54 INFO - 1500516534736 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49294/test.html"
19:08:54 INFO - 1500516534737 Marionette TRACE 359 <- [1,4,null,{}]
19:08:54 INFO - 1500516534741 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:08:54 INFO - 1500516534744 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ffc87b9b-ece4-46a4-ac0c-91465821784e","ELEMENT":"ffc87b9b-ece4-46a4-ac0c-91465821784e"}}]
19:08:54 INFO - 1500516534745 Marionette TRACE 359 -> [0,6,"getTitle",{}]
19:08:54 INFO - 1500516534746 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
19:08:54 INFO - 1500516534752 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}]
19:08:54 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500516534756 Marionette TRACE 359 <- [1,7,null,{"value":null}]
19:08:54 INFO - 1500516534757 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
19:08:54 INFO - 1500516534760 Marionette TRACE 359 <- [1,8,null,{}]
19:08:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 196ms
19:08:54 INFO - TEST-START | test_click.py TestClick.test_container_element
19:08:54 INFO - 1500516534761 Marionette DEBUG Closed connection 359
19:08:54 INFO - 1500516534762 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49740
19:08:54 INFO - 1500516534763 Marionette DEBUG Closed connection 360
19:08:54 INFO - 1500516534764 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49741
19:08:54 INFO - 1500516534765 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:54 INFO - 1500516534765 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:54 INFO - 1500516534775 Marionette DEBUG Register listener.js for window 6442450945
19:08:54 INFO - 1500516534779 Marionette TRACE 361 <- [1,1,null,{"sessionId":"6440688b-b0b3-4f14-bb62-e768834d01d5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:54 INFO - 1500516534780 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
19:08:54 INFO - 1500516534781 Marionette TRACE 361 <- [1,2,null,{}]
19:08:54 INFO - 1500516534782 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:08:54 INFO - 1500516534782 Marionette TRACE 361 <- [1,3,null,{}]
19:08:54 INFO - 1500516534783 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
19:08:54 INFO - 1500516534784 Marionette TRACE 361 <- [1,4,null,{}]
19:08:54 INFO - 1500516534785 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}]
19:08:54 INFO - TEST-START: test_click.py TestClick.test_container_element1500516534788 Marionette TRACE 361 <- [1,5,null,{"value":null}]
19:08:54 INFO - 1500516534789 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
19:08:54 INFO - 1500516534791 Marionette TRACE 361 <- [1,6,null,{}]
19:08:54 INFO - 1500516534792 Marionette DEBUG Closed connection 361
19:08:54 INFO - 1500516534793 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49742
19:08:54 INFO - 1500516534794 Marionette DEBUG Closed connection 362
19:08:54 INFO - 1500516534794 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49743
19:08:54 INFO - 1500516534795 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:54 INFO - 1500516534796 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:54 INFO - 1500516534799 Marionette DEBUG Register listener.js for window 6442450945
19:08:54 INFO - 1500516534802 Marionette TRACE 363 <- [1,1,null,{"sessionId":"32c674c3-4f43-4df2-8b39-482f79e98e50","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:54 INFO - 1500516534804 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
19:08:54 INFO - 1500516534805 Marionette TRACE 363 <- [1,2,null,{}]
19:08:54 INFO - 1500516534806 Marionette DEBUG Closed connection 363
19:08:54 INFO - 1500516534807 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49744
19:08:54 INFO - 1500516534808 Marionette DEBUG Closed connection 364
19:08:54 INFO - 1500516534809 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49745
19:08:54 INFO - 1500516534810 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:08:54 INFO - 1500516534810 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:54 INFO - 1500516534813 Marionette DEBUG Register listener.js for window 6442450945
19:08:54 INFO - 1500516534816 Marionette TRACE 365 <- [1,1,null,{"sessionId":"edd8c000-f255-4a58-bb38-2bdbb0840ccb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:54 INFO - 1500516534818 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"}]
19:08:54 INFO - 1500516534867 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49294/test.html"
19:08:54 INFO - 1500516534869 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49294/test.html"
19:08:54 INFO - 1500516534870 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49294/test.html"
19:08:54 INFO - 1500516534876 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"
19:08:54 INFO - 1500516534879 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"
19:08:54 INFO - 1500516534887 Marionette TRACE 365 <- [1,2,null,{}]
19:08:54 INFO - 1500516534890 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:08:54 INFO - 1500516534899 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"54df5996-7e44-4df4-bd1f-b674b2d64ce8","ELEMENT":"54df5996-7e44-4df4-bd1f-b674b2d64ce8"}}]
19:08:54 INFO - 1500516534900 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"54df5996-7e44-4df4-bd1f-b674b2d64ce8"}]
19:08:55 INFO - 1500516535153 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:08:55 INFO - 1500516535154 Marionette TRACE 365 <- [1,4,null,{}]
19:08:55 INFO - 1500516535155 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"54df5996-7e44-4df4-bd1f-b674b2d64ce8","name":"selected"}]
19:08:55 INFO - 1500516535159 Marionette TRACE 365 <- [1,5,null,{"value":true}]
19:08:55 INFO - 1500516535161 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}]
19:08:55 INFO - TEST-END: test_click.py TestClick.test_container_element1500516535164 Marionette TRACE 365 <- [1,6,null,{"value":null}]
19:08:55 INFO - 1500516535165 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
19:08:55 INFO - 1500516535166 Marionette TRACE 365 <- [1,7,null,{}]
19:08:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 406ms
19:08:55 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:08:55 INFO - 1500516535168 Marionette DEBUG Closed connection 365
19:08:55 INFO - 1500516535169 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49746
19:08:55 INFO - 1500516535170 Marionette DEBUG Closed connection 366
19:08:55 INFO - 1500516535170 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49747
19:08:55 INFO - 1500516535171 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:55 INFO - 1500516535172 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535175 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535178 Marionette TRACE 367 <- [1,1,null,{"sessionId":"cf3cc6db-bc18-465d-b6ba-61a20a6d6c60","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535179 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
19:08:55 INFO - 1500516535180 Marionette TRACE 367 <- [1,2,null,{}]
19:08:55 INFO - 1500516535181 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:08:55 INFO - 1500516535181 Marionette TRACE 367 <- [1,3,null,{}]
19:08:55 INFO - 1500516535182 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
19:08:55 INFO - 1500516535183 Marionette TRACE 367 <- [1,4,null,{}]
19:08:55 INFO - 1500516535186 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}]
19:08:55 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500516535188 Marionette TRACE 367 <- [1,5,null,{"value":null}]
19:08:55 INFO - 1500516535189 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
19:08:55 INFO - 1500516535191 Marionette TRACE 367 <- [1,6,null,{}]
19:08:55 INFO - 1500516535192 Marionette DEBUG Closed connection 367
19:08:55 INFO - 1500516535193 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49748
19:08:55 INFO - 1500516535194 Marionette DEBUG Closed connection 368
19:08:55 INFO - 1500516535194 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49749
19:08:55 INFO - 1500516535195 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:55 INFO - 1500516535196 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535199 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535202 Marionette TRACE 369 <- [1,1,null,{"sessionId":"bcb687a5-5e2e-4882-b774-bb30e48505bd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535203 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
19:08:55 INFO - 1500516535204 Marionette TRACE 369 <- [1,2,null,{}]
19:08:55 INFO - 1500516535206 Marionette DEBUG Closed connection 369
19:08:55 INFO - 1500516535207 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49750
19:08:55 INFO - 1500516535208 Marionette DEBUG Closed connection 370
19:08:55 INFO - 1500516535208 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49751
19:08:55 INFO - 1500516535209 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:08:55 INFO - 1500516535210 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535213 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535216 Marionette TRACE 371 <- [1,1,null,{"sessionId":"bee78e59-d8c2-4bfa-8bdf-4541e7b9f95e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535218 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"}]
19:08:55 INFO - 1500516535221 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"
19:08:55 INFO - 1500516535222 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"
19:08:55 INFO - 1500516535223 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"
19:08:55 INFO - 1500516535234 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"
19:08:55 INFO - 1500516535236 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"
19:08:55 INFO - 1500516535244 Marionette TRACE 371 <- [1,2,null,{}]
19:08:55 INFO - 1500516535251 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:08:55 INFO - 1500516535256 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d214a21c-a5bf-4005-824a-6d13e2325f75","ELEMENT":"d214a21c-a5bf-4005-824a-6d13e2325f75"}}]
19:08:55 INFO - 1500516535257 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"d214a21c-a5bf-4005-824a-6d13e2325f75"}]
19:08:55 INFO - 1500516535501 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:08:55 INFO - 1500516535502 Marionette TRACE 371 <- [1,4,null,{}]
19:08:55 INFO - 1500516535503 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"d214a21c-a5bf-4005-824a-6d13e2325f75","name":"selected"}]
19:08:55 INFO - 1500516535507 Marionette TRACE 371 <- [1,5,null,{"value":true}]
19:08:55 INFO - 1500516535509 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}]
19:08:55 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500516535512 Marionette TRACE 371 <- [1,6,null,{"value":null}]
19:08:55 INFO - 1500516535513 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
19:08:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 347ms
19:08:55 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:08:55 INFO - 1500516535514 Marionette TRACE 371 <- [1,7,null,{}]
19:08:55 INFO - 1500516535516 Marionette DEBUG Closed connection 371
19:08:55 INFO - 1500516535517 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49752
19:08:55 INFO - 1500516535518 Marionette DEBUG Closed connection 372
19:08:55 INFO - 1500516535518 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49753
19:08:55 INFO - 1500516535519 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:55 INFO - 1500516535520 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535523 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535526 Marionette TRACE 373 <- [1,1,null,{"sessionId":"eba8e629-8d39-4b99-9ccd-050ce49b1b73","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535528 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
19:08:55 INFO - 1500516535528 Marionette TRACE 373 <- [1,2,null,{}]
19:08:55 INFO - 1500516535529 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:08:55 INFO - 1500516535530 Marionette TRACE 373 <- [1,3,null,{}]
19:08:55 INFO - 1500516535531 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
19:08:55 INFO - 1500516535531 Marionette TRACE 373 <- [1,4,null,{}]
19:08:55 INFO - 1500516535533 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}]
19:08:55 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500516535536 Marionette TRACE 373 <- [1,5,null,{"value":null}]
19:08:55 INFO - 1500516535537 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
19:08:55 INFO - 1500516535538 Marionette TRACE 373 <- [1,6,null,{}]
19:08:55 INFO - 1500516535540 Marionette DEBUG Closed connection 373
19:08:55 INFO - 1500516535541 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49754
19:08:55 INFO - 1500516535541 Marionette DEBUG Closed connection 374
19:08:55 INFO - 1500516535542 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49755
19:08:55 INFO - 1500516535543 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:55 INFO - 1500516535543 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535546 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535550 Marionette TRACE 375 <- [1,1,null,{"sessionId":"fd414f09-e5ab-4a10-a274-35b470f6a902","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535551 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
19:08:55 INFO - 1500516535552 Marionette TRACE 375 <- [1,2,null,{}]
19:08:55 INFO - 1500516535554 Marionette DEBUG Closed connection 375
19:08:55 INFO - 1500516535555 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49756
19:08:55 INFO - 1500516535556 Marionette DEBUG Closed connection 376
19:08:55 INFO - 1500516535556 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49757
19:08:55 INFO - 1500516535558 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:08:55 INFO - 1500516535559 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535562 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535566 Marionette TRACE 377 <- [1,1,null,{"sessionId":"2d02c1cf-fe73-4020-8b4a-c72062f55446","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535567 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"}]
19:08:55 INFO - 1500516535569 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"
19:08:55 INFO - 1500516535571 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"
19:08:55 INFO - 1500516535571 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"
19:08:55 INFO - 1500516535578 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"
19:08:55 INFO - 1500516535581 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"
19:08:55 INFO - 1500516535589 Marionette TRACE 377 <- [1,2,null,{}]
19:08:55 INFO - 1500516535593 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:08:55 INFO - 1500516535599 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b047c989-c6cd-4852-b797-dc20da58ec54","ELEMENT":"b047c989-c6cd-4852-b797-dc20da58ec54"}}]
19:08:55 INFO - 1500516535601 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"b047c989-c6cd-4852-b797-dc20da58ec54"}]
19:08:55 INFO - 1500516535851 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:08:55 INFO - 1500516535853 Marionette TRACE 377 <- [1,4,null,{}]
19:08:55 INFO - 1500516535855 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}]
19:08:55 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500516535858 Marionette TRACE 377 <- [1,5,null,{"value":null}]
19:08:55 INFO - 1500516535860 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
19:08:55 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
19:08:55 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:08:55 INFO - 1500516535861 Marionette TRACE 377 <- [1,6,null,{}]
19:08:55 INFO - 1500516535863 Marionette DEBUG Closed connection 377
19:08:55 INFO - 1500516535863 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49758
19:08:55 INFO - 1500516535864 Marionette DEBUG Closed connection 378
19:08:55 INFO - 1500516535864 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49759
19:08:55 INFO - 1500516535866 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:55 INFO - 1500516535866 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535869 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535880 Marionette TRACE 379 <- [1,1,null,{"sessionId":"ebb97f30-3715-4ebf-ae72-47b08d86946f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535881 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
19:08:55 INFO - 1500516535882 Marionette TRACE 379 <- [1,2,null,{}]
19:08:55 INFO - 1500516535883 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:08:55 INFO - 1500516535883 Marionette TRACE 379 <- [1,3,null,{}]
19:08:55 INFO - 1500516535884 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
19:08:55 INFO - 1500516535885 Marionette TRACE 379 <- [1,4,null,{}]
19:08:55 INFO - 1500516535887 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}]
19:08:55 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500516535890 Marionette TRACE 379 <- [1,5,null,{"value":null}]
19:08:55 INFO - 1500516535891 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
19:08:55 INFO - 1500516535892 Marionette TRACE 379 <- [1,6,null,{}]
19:08:55 INFO - 1500516535894 Marionette DEBUG Closed connection 379
19:08:55 INFO - 1500516535894 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49760
19:08:55 INFO - 1500516535895 Marionette DEBUG Closed connection 380
19:08:55 INFO - 1500516535896 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49761
19:08:55 INFO - 1500516535897 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:55 INFO - 1500516535898 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535901 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535904 Marionette TRACE 381 <- [1,1,null,{"sessionId":"1407be73-c13c-4874-a5ed-518cb63360b8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535906 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
19:08:55 INFO - 1500516535907 Marionette TRACE 381 <- [1,2,null,{}]
19:08:55 INFO - 1500516535909 Marionette DEBUG Closed connection 381
19:08:55 INFO - 1500516535910 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49762
19:08:55 INFO - 1500516535910 Marionette DEBUG Closed connection 382
19:08:55 INFO - 1500516535911 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49763
19:08:55 INFO - 1500516535912 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:08:55 INFO - 1500516535913 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:55 INFO - 1500516535916 Marionette DEBUG Register listener.js for window 6442450945
19:08:55 INFO - 1500516535919 Marionette TRACE 383 <- [1,1,null,{"sessionId":"021d4af0-1e3e-46b1-85dd-06d1c9c46ea3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:55 INFO - 1500516535921 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"}]
19:08:55 INFO - 1500516535923 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"
19:08:55 INFO - 1500516535925 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"
19:08:55 INFO - 1500516535925 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"
19:08:55 INFO - 1500516535933 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"
19:08:55 INFO - 1500516535937 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"
19:08:55 INFO - 1500516535944 Marionette TRACE 383 <- [1,2,null,{}]
19:08:55 INFO - 1500516535951 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:08:55 INFO - 1500516535954 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1d3dd265-27f5-458d-a299-0306b3001b8c","ELEMENT":"1d3dd265-27f5-458d-a299-0306b3001b8c"}}]
19:08:55 INFO - 1500516535955 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"1d3dd265-27f5-458d-a299-0306b3001b8c"}]
19:08:56 INFO - 1500516536197 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:08:56 INFO - 1500516536198 Marionette TRACE 383 <- [1,4,null,{}]
19:08:56 INFO - 1500516536200 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}]
19:08:56 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500516536202 Marionette TRACE 383 <- [1,5,null,{"value":null}]
19:08:56 INFO - 1500516536204 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
19:08:56 INFO - 1500516536205 Marionette TRACE 383 <- [1,6,null,{}]
19:08:56 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 344ms
19:08:56 INFO - TEST-START | test_click.py TestClick.test_input_file
19:08:56 INFO - 1500516536207 Marionette DEBUG Closed connection 383
19:08:56 INFO - 1500516536208 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49764
19:08:56 INFO - 1500516536208 Marionette DEBUG Closed connection 384
19:08:56 INFO - 1500516536209 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49765
19:08:56 INFO - 1500516536210 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:56 INFO - 1500516536211 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536214 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536217 Marionette TRACE 385 <- [1,1,null,{"sessionId":"8b8e73e2-d171-443a-a15e-d7e633dc0089","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536219 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
19:08:56 INFO - 1500516536219 Marionette TRACE 385 <- [1,2,null,{}]
19:08:56 INFO - 1500516536220 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:08:56 INFO - 1500516536221 Marionette TRACE 385 <- [1,3,null,{}]
19:08:56 INFO - 1500516536222 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
19:08:56 INFO - 1500516536222 Marionette TRACE 385 <- [1,4,null,{}]
19:08:56 INFO - 1500516536224 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}]
19:08:56 INFO - TEST-START: test_click.py TestClick.test_input_file1500516536227 Marionette TRACE 385 <- [1,5,null,{"value":null}]
19:08:56 INFO - 1500516536228 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
19:08:56 INFO - 1500516536230 Marionette TRACE 385 <- [1,6,null,{}]
19:08:56 INFO - 1500516536231 Marionette DEBUG Closed connection 385
19:08:56 INFO - 1500516536232 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49766
19:08:56 INFO - 1500516536233 Marionette DEBUG Closed connection 386
19:08:56 INFO - 1500516536233 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49767
19:08:56 INFO - 1500516536234 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:56 INFO - 1500516536235 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536238 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536241 Marionette TRACE 387 <- [1,1,null,{"sessionId":"7ba6cdcc-b431-460b-8164-eb79dbb579ec","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536243 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
19:08:56 INFO - 1500516536244 Marionette TRACE 387 <- [1,2,null,{}]
19:08:56 INFO - 1500516536246 Marionette DEBUG Closed connection 387
19:08:56 INFO - 1500516536247 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49768
19:08:56 INFO - 1500516536248 Marionette DEBUG Closed connection 388
19:08:56 INFO - 1500516536248 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49769
19:08:56 INFO - 1500516536249 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:08:56 INFO - 1500516536250 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536253 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536256 Marionette TRACE 389 <- [1,1,null,{"sessionId":"0334b4c8-a562-4aea-8321-a66229257541","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536258 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:08:56 INFO - 1500516536259 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"
19:08:56 INFO - 1500516536261 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"
19:08:56 INFO - 1500516536262 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"
19:08:56 INFO - 1500516536269 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:08:56 INFO - 1500516536274 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:08:56 INFO - 1500516536275 Marionette TRACE 389 <- [1,2,null,{}]
19:08:56 INFO - 1500516536278 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:08:56 INFO - 1500516536285 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cdc15f10-206a-4c57-8b7c-98357cc146f7","ELEMENT":"cdc15f10-206a-4c57-8b7c-98357cc146f7"}}]
19:08:56 INFO - 1500516536286 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"cdc15f10-206a-4c57-8b7c-98357cc146f7"}]
19:08:56 INFO - 1500516536320 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
19:08:56 INFO - 1500516536322 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}]
19:08:56 INFO - TEST-END: test_click.py TestClick.test_input_file1500516536325 Marionette TRACE 389 <- [1,5,null,{"value":null}]
19:08:56 INFO - 1500516536326 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
19:08:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 122ms
19:08:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:08:56 INFO - 1500516536327 Marionette TRACE 389 <- [1,6,null,{}]
19:08:56 INFO - 1500516536329 Marionette DEBUG Closed connection 389
19:08:56 INFO - 1500516536329 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49770
19:08:56 INFO - 1500516536330 Marionette DEBUG Closed connection 390
19:08:56 INFO - 1500516536331 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49771
19:08:56 INFO - 1500516536332 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:56 INFO - 1500516536332 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536335 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536339 Marionette TRACE 391 <- [1,1,null,{"sessionId":"2507ae0c-436d-4b42-aa68-94534d23afd1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536340 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
19:08:56 INFO - 1500516536340 Marionette TRACE 391 <- [1,2,null,{}]
19:08:56 INFO - 1500516536342 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:08:56 INFO - 1500516536342 Marionette TRACE 391 <- [1,3,null,{}]
19:08:56 INFO - 1500516536343 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
19:08:56 INFO - 1500516536343 Marionette TRACE 391 <- [1,4,null,{}]
19:08:56 INFO - 1500516536345 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}]
19:08:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500516536348 Marionette TRACE 391 <- [1,5,null,{"value":null}]
19:08:56 INFO - 1500516536349 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
19:08:56 INFO - 1500516536350 Marionette TRACE 391 <- [1,6,null,{}]
19:08:56 INFO - 1500516536352 Marionette DEBUG Closed connection 391
19:08:56 INFO - 1500516536353 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49772
19:08:56 INFO - 1500516536354 Marionette DEBUG Closed connection 392
19:08:56 INFO - 1500516536354 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49773
19:08:56 INFO - 1500516536355 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:56 INFO - 1500516536356 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536359 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536362 Marionette TRACE 393 <- [1,1,null,{"sessionId":"de33150c-7cce-462b-b178-03d6f97a02e1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536364 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
19:08:56 INFO - 1500516536366 Marionette TRACE 393 <- [1,2,null,{}]
19:08:56 INFO - 1500516536367 Marionette DEBUG Closed connection 393
19:08:56 INFO - 1500516536368 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49774
19:08:56 INFO - 1500516536369 Marionette DEBUG Closed connection 394
19:08:56 INFO - 1500516536369 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49775
19:08:56 INFO - 1500516536370 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:08:56 INFO - 1500516536371 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536374 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536377 Marionette TRACE 395 <- [1,1,null,{"sessionId":"f0d3afbf-7fd8-424c-902a-7ce0ddde1eef","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536379 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"}]
19:08:56 INFO - 1500516536381 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:08:56 INFO - 1500516536382 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:08:56 INFO - 1500516536383 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:08:56 INFO - 1500516536390 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"
19:08:56 INFO - 1500516536392 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"
19:08:56 INFO - 1500516536394 Marionette TRACE 395 <- [1,2,null,{}]
19:08:56 INFO - 1500516536403 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:08:56 INFO - 1500516536406 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"98536ea9-445a-4d77-968f-0a71f79382f1","ELEMENT":"98536ea9-445a-4d77-968f-0a71f79382f1"}}]
19:08:56 INFO - 1500516536407 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:08:56 INFO - 1500516536409 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2cec7f4a-ea8e-4378-92b8-c94dbb4d3b89","ELEMENT":"2cec7f4a-ea8e-4378-92b8-c94dbb4d3b89"}}]
19:08:56 INFO - 1500516536410 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"98536ea9-445a-4d77-968f-0a71f79382f1"}]
19:08:56 INFO - 1500516536652 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:08:56 INFO - 1500516536653 Marionette TRACE 395 <- [1,5,null,{}]
19:08:56 INFO - 1500516536654 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"2cec7f4a-ea8e-4378-92b8-c94dbb4d3b89"}]
19:08:56 INFO - 1500516536671 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
19:08:56 INFO - 1500516536673 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
19:08:56 INFO - 1500516536675 Marionette TRACE 395 <- [1,7,null,{"value":false}]
19:08:56 INFO - 1500516536677 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}]
19:08:56 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500516536680 Marionette TRACE 395 <- [1,8,null,{"value":null}]
19:08:56 INFO - 1500516536681 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
19:08:56 INFO - 1500516536682 Marionette TRACE 395 <- [1,9,null,{}]
19:08:56 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 355ms
19:08:56 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:08:56 INFO - 1500516536684 Marionette DEBUG Closed connection 395
19:08:56 INFO - 1500516536685 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49776
19:08:56 INFO - 1500516536686 Marionette DEBUG Closed connection 396
19:08:56 INFO - 1500516536687 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49777
19:08:56 INFO - 1500516536688 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:56 INFO - 1500516536688 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536691 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536695 Marionette TRACE 397 <- [1,1,null,{"sessionId":"d23f75a1-cff9-409d-8c97-0f165e3c8aaa","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536696 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
19:08:56 INFO - 1500516536696 Marionette TRACE 397 <- [1,2,null,{}]
19:08:56 INFO - 1500516536698 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:08:56 INFO - 1500516536698 Marionette TRACE 397 <- [1,3,null,{}]
19:08:56 INFO - 1500516536699 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
19:08:56 INFO - 1500516536700 Marionette TRACE 397 <- [1,4,null,{}]
19:08:56 INFO - 1500516536702 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}]
19:08:56 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500516536705 Marionette TRACE 397 <- [1,5,null,{"value":null}]
19:08:56 INFO - 1500516536706 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
19:08:56 INFO - 1500516536707 Marionette TRACE 397 <- [1,6,null,{}]
19:08:56 INFO - 1500516536709 Marionette DEBUG Closed connection 397
19:08:56 INFO - 1500516536710 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49778
19:08:56 INFO - 1500516536710 Marionette DEBUG Closed connection 398
19:08:56 INFO - 1500516536711 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49779
19:08:56 INFO - 1500516536712 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:08:56 INFO - 1500516536713 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536716 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536719 Marionette TRACE 399 <- [1,1,null,{"sessionId":"9edb96e0-d057-4c21-a888-2d44a27cb783","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536721 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
19:08:56 INFO - 1500516536722 Marionette TRACE 399 <- [1,2,null,{}]
19:08:56 INFO - 1500516536724 Marionette DEBUG Closed connection 399
19:08:56 INFO - 1500516536724 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49780
19:08:56 INFO - 1500516536725 Marionette DEBUG Closed connection 400
19:08:56 INFO - 1500516536726 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49781
19:08:56 INFO - 1500516536727 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:08:56 INFO - 1500516536728 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}
19:08:56 INFO - 1500516536730 Marionette DEBUG Register listener.js for window 6442450945
19:08:56 INFO - 1500516536734 Marionette TRACE 401 <- [1,1,null,{"sessionId":"ec65d8fa-83f4-44c2-b8b0-08fa26664ec7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-161\\AppData\\Local\\Temp\\tmpibvtjn.mozrunner","moz:accessibilityChecks":false}}]
19:08:56 INFO - 1500516536735 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"}]
19:08:56 INFO - 1500516536737 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"
19:08:56 INFO - 1500516536739 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"
19:08:56 INFO - 1500516536739 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"
19:08:56 INFO - 1500516536747 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"
19:08:56 INFO - 1500516536749 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"
19:08:56 INFO - 1500516536756 Marionette TRACE 401 <- [1,2,null,{}]
19:08:56 INFO - 1500516536760 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:08:56 INFO - 1500516536762 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5863d269-d808-459c-b91f-74fdb4c87d9d","ELEMENT":"5863d269-d808-459c-b91f-74fdb4c87d9d"}}]
19:08:56 INFO - 1500516536767 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5863d269-d808-459c-b91f-74fdb4c87d9d"}]
19:08:56 INFO - 1500516536793 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
19:08:56 INFO - 1500516536795 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"5863d269-d808-459c-b91f-74fdb4c87d9d"}]
19:08:56 INFO - 1500516536811 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element