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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:12:27 INFO - 1499386347812 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
17:12:27 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499386347816 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:12:27 INFO - 1499386347819 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:12:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 203ms
17:12:27 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:12:27 INFO - 1499386347820 Marionette TRACE 352 <- [1,6,null,{}]
17:12:27 INFO - 1499386347823 Marionette DEBUG Closed connection 352
17:12:27 INFO - 1499386347824 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49910
17:12:27 INFO - 1499386347825 Marionette DEBUG Closed connection 353
17:12:27 INFO - 1499386347825 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49911
17:12:27 INFO - 1499386347827 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:27 INFO - 1499386347827 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:27 INFO - 1499386347830 Marionette DEBUG Register listener.js for window 12
17:12:27 INFO - 1499386347834 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b1d382d3-f119-4aad-81fe-88463aaeefee","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:27 INFO - 1499386347835 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:12:27 INFO - 1499386347836 Marionette TRACE 354 <- [1,2,null,{}]
17:12:27 INFO - 1499386347837 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:12:27 INFO - 1499386347838 Marionette TRACE 354 <- [1,3,null,{}]
17:12:27 INFO - 1499386347839 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:12:27 INFO - 1499386347839 Marionette TRACE 354 <- [1,4,null,{}]
17:12:27 INFO - 1499386347841 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
17:12:27 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499386347844 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:12:27 INFO - 1499386347845 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:12:27 INFO - 1499386347847 Marionette TRACE 354 <- [1,6,null,{}]
17:12:27 INFO - 1499386347849 Marionette DEBUG Closed connection 354
17:12:27 INFO - 1499386347850 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49912
17:12:27 INFO - 1499386347851 Marionette DEBUG Closed connection 355
17:12:27 INFO - 1499386347851 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49913
17:12:27 INFO - 1499386347852 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:27 INFO - 1499386347853 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:27 INFO - 1499386347856 Marionette DEBUG Register listener.js for window 12
17:12:27 INFO - 1499386347860 Marionette TRACE 356 <- [1,1,null,{"sessionId":"e1f6774a-14e8-4ca7-a9a8-17b13e1eb3d8","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:27 INFO - 1499386347861 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:12:27 INFO - 1499386347862 Marionette TRACE 356 <- [1,2,null,{}]
17:12:27 INFO - 1499386347870 Marionette DEBUG Closed connection 356
17:12:27 INFO - 1499386347871 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49914
17:12:27 INFO - 1499386347872 Marionette DEBUG Closed connection 357
17:12:27 INFO - 1499386347873 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49915
17:12:27 INFO - 1499386347874 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:12:27 INFO - 1499386347874 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:27 INFO - 1499386347877 Marionette DEBUG Register listener.js for window 12
17:12:27 INFO - 1499386347881 Marionette TRACE 358 <- [1,1,null,{"sessionId":"e0ac3234-fa91-42e7-b8f3-2207a5e52137","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:27 INFO - 1499386347882 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49476/clicks.html"}]
17:12:27 INFO - 1499386347883 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49476/hidden.html"
17:12:27 INFO - 1499386347890 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49476/hidden.html"
17:12:27 INFO - 1499386347890 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49476/hidden.html"
17:12:27 INFO - 1499386347910 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49476/clicks.html"
17:12:27 INFO - 1499386347919 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49476/clicks.html"
17:12:27 INFO - 1499386347923 Marionette TRACE 358 <- [1,2,null,{}]
17:12:27 INFO - 1499386347925 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:12:27 INFO - 1499386347928 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b97c5fe9-8efe-40e5-a3dc-550a33d10eea","ELEMENT":"b97c5fe9-8efe-40e5-a3dc-550a33d10eea"}}]
17:12:27 INFO - 1499386347936 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"b97c5fe9-8efe-40e5-a3dc-550a33d10eea"}]
17:12:27 INFO - 1499386347990 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49476/clicks.html"
17:12:28 INFO - 1499386348002 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49476/clicks.html"
17:12:28 INFO - 1499386348003 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49476/clicks.html"
17:12:28 INFO - 1499386348036 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49476/test.html"
17:12:28 INFO - 1499386348040 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49476/test.html"
17:12:28 INFO - 1499386348045 Marionette TRACE 358 <- [1,4,null,{}]
17:12:28 INFO - 1499386348054 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:12:28 INFO - 1499386348057 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f211f7ee-8a51-414d-8c82-23213bad7859","ELEMENT":"f211f7ee-8a51-414d-8c82-23213bad7859"}}]
17:12:28 INFO - 1499386348060 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:12:28 INFO - 1499386348060 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:12:28 INFO - 1499386348062 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
17:12:28 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499386348067 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:12:28 INFO - 1499386348068 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:12:28 INFO - 1499386348070 Marionette TRACE 358 <- [1,8,null,{}]
17:12:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 249ms
17:12:28 INFO - TEST-START | test_click.py TestClick.test_container_element
17:12:28 INFO - 1499386348073 Marionette DEBUG Closed connection 358
17:12:28 INFO - 1499386348074 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49918
17:12:28 INFO - 1499386348074 Marionette DEBUG Closed connection 359
17:12:28 INFO - 1499386348075 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49919
17:12:28 INFO - 1499386348076 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:28 INFO - 1499386348077 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348080 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348084 Marionette TRACE 360 <- [1,1,null,{"sessionId":"89782479-59ac-413f-a62d-c8ec485efad6","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348085 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:12:28 INFO - 1499386348085 Marionette TRACE 360 <- [1,2,null,{}]
17:12:28 INFO - 1499386348086 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:12:28 INFO - 1499386348087 Marionette TRACE 360 <- [1,3,null,{}]
17:12:28 INFO - 1499386348088 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:12:28 INFO - 1499386348088 Marionette TRACE 360 <- [1,4,null,{}]
17:12:28 INFO - 1499386348090 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
17:12:28 INFO - TEST-START: test_click.py TestClick.test_container_element1499386348093 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:12:28 INFO - 1499386348094 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:12:28 INFO - 1499386348095 Marionette TRACE 360 <- [1,6,null,{}]
17:12:28 INFO - 1499386348098 Marionette DEBUG Closed connection 360
17:12:28 INFO - 1499386348099 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49920
17:12:28 INFO - 1499386348099 Marionette DEBUG Closed connection 361
17:12:28 INFO - 1499386348100 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49921
17:12:28 INFO - 1499386348101 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:28 INFO - 1499386348102 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348105 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348108 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4ef4cf11-2b28-445c-bc01-1325d55fe52e","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348109 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:12:28 INFO - 1499386348111 Marionette TRACE 362 <- [1,2,null,{}]
17:12:28 INFO - 1499386348113 Marionette DEBUG Closed connection 362
17:12:28 INFO - 1499386348114 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49922
17:12:28 INFO - 1499386348115 Marionette DEBUG Closed connection 363
17:12:28 INFO - 1499386348116 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49923
17:12:28 INFO - 1499386348117 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:12:28 INFO - 1499386348117 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348120 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348123 Marionette TRACE 364 <- [1,1,null,{"sessionId":"aa7eedb0-8609-49d4-bd7a-82ae94fea037","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348125 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:12:28 INFO - 1499386348126 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49476/test.html"
17:12:28 INFO - 1499386348129 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49476/test.html"
17:12:28 INFO - 1499386348129 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49476/test.html"
17:12:28 INFO - 1499386348155 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"
17:12:28 INFO - 1499386348161 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"
17:12:28 INFO - 1499386348165 Marionette TRACE 364 <- [1,2,null,{}]
17:12:28 INFO - 1499386348175 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:12:28 INFO - 1499386348178 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d9b15ac5-6345-4052-9cc2-439b1e292a52","ELEMENT":"d9b15ac5-6345-4052-9cc2-439b1e292a52"}}]
17:12:28 INFO - 1499386348180 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d9b15ac5-6345-4052-9cc2-439b1e292a52"}]
17:12:28 INFO - 1499386348424 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:12:28 INFO - 1499386348424 Marionette TRACE 364 <- [1,4,null,{}]
17:12:28 INFO - 1499386348426 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d9b15ac5-6345-4052-9cc2-439b1e292a52","name":"selected"}]
17:12:28 INFO - 1499386348432 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:12:28 INFO - 1499386348434 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
17:12:28 INFO - TEST-END: test_click.py TestClick.test_container_element1499386348437 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:12:28 INFO - 1499386348438 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:12:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 370ms
17:12:28 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:12:28 INFO - 1499386348440 Marionette TRACE 364 <- [1,7,null,{}]
17:12:28 INFO - 1499386348442 Marionette DEBUG Closed connection 364
17:12:28 INFO - 1499386348443 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49924
17:12:28 INFO - 1499386348444 Marionette DEBUG Closed connection 365
17:12:28 INFO - 1499386348445 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49925
17:12:28 INFO - 1499386348447 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:28 INFO - 1499386348447 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348450 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348454 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3dc9f23c-1366-40b7-8019-f49d7a8b3604","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348456 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:12:28 INFO - 1499386348457 Marionette TRACE 366 <- [1,2,null,{}]
17:12:28 INFO - 1499386348458 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:12:28 INFO - 1499386348458 Marionette TRACE 366 <- [1,3,null,{}]
17:12:28 INFO - 1499386348459 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:12:28 INFO - 1499386348460 Marionette TRACE 366 <- [1,4,null,{}]
17:12:28 INFO - 1499386348462 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
17:12:28 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499386348465 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:12:28 INFO - 1499386348466 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:12:28 INFO - 1499386348467 Marionette TRACE 366 <- [1,6,null,{}]
17:12:28 INFO - 1499386348470 Marionette DEBUG Closed connection 366
17:12:28 INFO - 1499386348470 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49926
17:12:28 INFO - 1499386348471 Marionette DEBUG Closed connection 367
17:12:28 INFO - 1499386348472 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49927
17:12:28 INFO - 1499386348473 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:28 INFO - 1499386348474 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348476 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348480 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ef54b72d-7296-4f37-91d7-a046c62b745e","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348481 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:12:28 INFO - 1499386348482 Marionette TRACE 368 <- [1,2,null,{}]
17:12:28 INFO - 1499386348484 Marionette DEBUG Closed connection 368
17:12:28 INFO - 1499386348485 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49928
17:12:28 INFO - 1499386348486 Marionette DEBUG Closed connection 369
17:12:28 INFO - 1499386348487 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49929
17:12:28 INFO - 1499386348488 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:12:28 INFO - 1499386348488 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348491 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348495 Marionette TRACE 370 <- [1,1,null,{"sessionId":"62097222-f9c7-4820-9867-47c4bf782cc5","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348497 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:12:28 INFO - 1499386348499 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"
17:12:28 INFO - 1499386348502 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"
17:12:28 INFO - 1499386348503 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"
17:12:28 INFO - 1499386348522 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"
17:12:28 INFO - 1499386348526 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"
17:12:28 INFO - 1499386348530 Marionette TRACE 370 <- [1,2,null,{}]
17:12:28 INFO - 1499386348537 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:12:28 INFO - 1499386348540 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"22e96e8b-ceeb-43ec-9a5f-231c057a03c7","ELEMENT":"22e96e8b-ceeb-43ec-9a5f-231c057a03c7"}}]
17:12:28 INFO - 1499386348541 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"22e96e8b-ceeb-43ec-9a5f-231c057a03c7"}]
17:12:28 INFO - 1499386348796 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:12:28 INFO - 1499386348797 Marionette TRACE 370 <- [1,4,null,{}]
17:12:28 INFO - 1499386348798 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"22e96e8b-ceeb-43ec-9a5f-231c057a03c7","name":"selected"}]
17:12:28 INFO - 1499386348803 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:12:28 INFO - 1499386348805 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
17:12:28 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499386348808 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:12:28 INFO - 1499386348809 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:12:28 INFO - 1499386348810 Marionette TRACE 370 <- [1,7,null,{}]
17:12:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 370ms
17:12:28 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:12:28 INFO - 1499386348812 Marionette DEBUG Closed connection 370
17:12:28 INFO - 1499386348813 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49930
17:12:28 INFO - 1499386348814 Marionette DEBUG Closed connection 371
17:12:28 INFO - 1499386348815 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49931
17:12:28 INFO - 1499386348816 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:28 INFO - 1499386348817 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348820 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348823 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8d32796a-5b9e-43bf-8b6e-b853eec2f415","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348824 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:12:28 INFO - 1499386348825 Marionette TRACE 372 <- [1,2,null,{}]
17:12:28 INFO - 1499386348826 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:12:28 INFO - 1499386348826 Marionette TRACE 372 <- [1,3,null,{}]
17:12:28 INFO - 1499386348827 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:12:28 INFO - 1499386348828 Marionette TRACE 372 <- [1,4,null,{}]
17:12:28 INFO - 1499386348830 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
17:12:28 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499386348833 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:12:28 INFO - 1499386348834 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:12:28 INFO - 1499386348835 Marionette TRACE 372 <- [1,6,null,{}]
17:12:28 INFO - 1499386348837 Marionette DEBUG Closed connection 372
17:12:28 INFO - 1499386348838 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49932
17:12:28 INFO - 1499386348839 Marionette DEBUG Closed connection 373
17:12:28 INFO - 1499386348839 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49933
17:12:28 INFO - 1499386348841 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:28 INFO - 1499386348841 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348844 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348848 Marionette TRACE 374 <- [1,1,null,{"sessionId":"5890c013-0a34-4379-af6c-9fdf08c5c1d4","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348849 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:12:28 INFO - 1499386348850 Marionette TRACE 374 <- [1,2,null,{}]
17:12:28 INFO - 1499386348852 Marionette DEBUG Closed connection 374
17:12:28 INFO - 1499386348853 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49934
17:12:28 INFO - 1499386348854 Marionette DEBUG Closed connection 375
17:12:28 INFO - 1499386348855 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49935
17:12:28 INFO - 1499386348856 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:12:28 INFO - 1499386348857 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:28 INFO - 1499386348859 Marionette DEBUG Register listener.js for window 12
17:12:28 INFO - 1499386348863 Marionette TRACE 376 <- [1,1,null,{"sessionId":"92240424-6e4e-4c31-acbb-9abff5c77fdf","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:28 INFO - 1499386348864 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
17:12:28 INFO - 1499386348868 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"
17:12:28 INFO - 1499386348870 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"
17:12:28 INFO - 1499386348871 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"
17:12:28 INFO - 1499386348892 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"
17:12:28 INFO - 1499386348896 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"
17:12:28 INFO - 1499386348907 Marionette TRACE 376 <- [1,2,null,{}]
17:12:28 INFO - 1499386348909 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:12:28 INFO - 1499386348912 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cd58d7a0-70e5-4e9e-9aba-f451cadb510a","ELEMENT":"cd58d7a0-70e5-4e9e-9aba-f451cadb510a"}}]
17:12:28 INFO - 1499386348913 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"cd58d7a0-70e5-4e9e-9aba-f451cadb510a"}]
17:12:29 INFO - 1499386349156 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:12:29 INFO - 1499386349156 Marionette TRACE 376 <- [1,4,null,{}]
17:12:29 INFO - 1499386349159 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
17:12:29 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499386349162 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:12:29 INFO - 1499386349163 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:12:29 INFO - 1499386349164 Marionette TRACE 376 <- [1,6,null,{}]
17:12:29 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 354ms
17:12:29 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:12:29 INFO - 1499386349167 Marionette DEBUG Closed connection 376
17:12:29 INFO - 1499386349168 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49936
17:12:29 INFO - 1499386349168 Marionette DEBUG Closed connection 377
17:12:29 INFO - 1499386349169 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49937
17:12:29 INFO - 1499386349170 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:29 INFO - 1499386349171 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349174 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349177 Marionette TRACE 378 <- [1,1,null,{"sessionId":"12078b25-0431-487d-83a2-450f59566ee8","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349179 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:12:29 INFO - 1499386349179 Marionette TRACE 378 <- [1,2,null,{}]
17:12:29 INFO - 1499386349180 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:12:29 INFO - 1499386349181 Marionette TRACE 378 <- [1,3,null,{}]
17:12:29 INFO - 1499386349182 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:12:29 INFO - 1499386349182 Marionette TRACE 378 <- [1,4,null,{}]
17:12:29 INFO - 1499386349184 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
17:12:29 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499386349187 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:12:29 INFO - 1499386349188 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:12:29 INFO - 1499386349189 Marionette TRACE 378 <- [1,6,null,{}]
17:12:29 INFO - 1499386349191 Marionette DEBUG Closed connection 378
17:12:29 INFO - 1499386349192 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49938
17:12:29 INFO - 1499386349193 Marionette DEBUG Closed connection 379
17:12:29 INFO - 1499386349194 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49939
17:12:29 INFO - 1499386349195 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:29 INFO - 1499386349196 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349198 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349202 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a86ad56e-c538-476a-acaa-b7705e725020","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349203 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:12:29 INFO - 1499386349205 Marionette TRACE 380 <- [1,2,null,{}]
17:12:29 INFO - 1499386349207 Marionette DEBUG Closed connection 380
17:12:29 INFO - 1499386349208 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49940
17:12:29 INFO - 1499386349208 Marionette DEBUG Closed connection 381
17:12:29 INFO - 1499386349209 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49941
17:12:29 INFO - 1499386349210 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:12:29 INFO - 1499386349211 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349214 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349217 Marionette TRACE 382 <- [1,1,null,{"sessionId":"224c22e1-d0c7-4a4b-94a0-1219582c01c0","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349218 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:12:29 INFO - 1499386349220 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"
17:12:29 INFO - 1499386349223 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"
17:12:29 INFO - 1499386349223 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"
17:12:29 INFO - 1499386349244 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"
17:12:29 INFO - 1499386349251 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"
17:12:29 INFO - 1499386349254 Marionette TRACE 382 <- [1,2,null,{}]
17:12:29 INFO - 1499386349255 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:12:29 INFO - 1499386349258 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"09d8ddef-e77f-4299-a830-17d048081b12","ELEMENT":"09d8ddef-e77f-4299-a830-17d048081b12"}}]
17:12:29 INFO - 1499386349259 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"09d8ddef-e77f-4299-a830-17d048081b12"}]
17:12:29 INFO - 1499386349520 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:12:29 INFO - 1499386349520 Marionette TRACE 382 <- [1,4,null,{}]
17:12:29 INFO - 1499386349522 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
17:12:29 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499386349526 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:12:29 INFO - 1499386349527 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:12:29 INFO - 1499386349528 Marionette TRACE 382 <- [1,6,null,{}]
17:12:29 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 364ms
17:12:29 INFO - TEST-START | test_click.py TestClick.test_input_file
17:12:29 INFO - 1499386349531 Marionette DEBUG Closed connection 382
17:12:29 INFO - 1499386349532 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49942
17:12:29 INFO - 1499386349532 Marionette DEBUG Closed connection 383
17:12:29 INFO - 1499386349533 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49943
17:12:29 INFO - 1499386349534 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:29 INFO - 1499386349535 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349538 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349542 Marionette TRACE 384 <- [1,1,null,{"sessionId":"eca6dcba-3702-4fdd-a752-5ae9f23b8d06","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349543 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:12:29 INFO - 1499386349543 Marionette TRACE 384 <- [1,2,null,{}]
17:12:29 INFO - 1499386349544 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:12:29 INFO - 1499386349545 Marionette TRACE 384 <- [1,3,null,{}]
17:12:29 INFO - 1499386349546 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:12:29 INFO - 1499386349546 Marionette TRACE 384 <- [1,4,null,{}]
17:12:29 INFO - 1499386349548 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
17:12:29 INFO - TEST-START: test_click.py TestClick.test_input_file1499386349551 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:12:29 INFO - 1499386349552 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:12:29 INFO - 1499386349553 Marionette TRACE 384 <- [1,6,null,{}]
17:12:29 INFO - 1499386349556 Marionette DEBUG Closed connection 384
17:12:29 INFO - 1499386349556 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49944
17:12:29 INFO - 1499386349557 Marionette DEBUG Closed connection 385
17:12:29 INFO - 1499386349558 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49945
17:12:29 INFO - 1499386349559 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:29 INFO - 1499386349560 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349562 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349566 Marionette TRACE 386 <- [1,1,null,{"sessionId":"ced7e0e8-fec3-4b12-8949-ebd382a312f6","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349567 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:12:29 INFO - 1499386349569 Marionette TRACE 386 <- [1,2,null,{}]
17:12:29 INFO - 1499386349571 Marionette DEBUG Closed connection 386
17:12:29 INFO - 1499386349572 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49946
17:12:29 INFO - 1499386349573 Marionette DEBUG Closed connection 387
17:12:29 INFO - 1499386349573 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49947
17:12:29 INFO - 1499386349574 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:12:29 INFO - 1499386349575 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349578 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349585 Marionette TRACE 388 <- [1,1,null,{"sessionId":"08462dd6-6c6c-4bfa-9bee-3d7ba4129790","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349586 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:12:29 INFO - 1499386349588 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"
17:12:29 INFO - 1499386349590 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"
17:12:29 INFO - 1499386349591 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"
17:12:29 INFO - 1499386349611 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:12:29 INFO - 1499386349618 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:12:29 INFO - 1499386349622 Marionette TRACE 388 <- [1,2,null,{}]
17:12:29 INFO - 1499386349624 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:12:29 INFO - 1499386349627 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eea1d538-32a5-486a-9aaa-3c8eb67cd04f","ELEMENT":"eea1d538-32a5-486a-9aaa-3c8eb67cd04f"}}]
17:12:29 INFO - 1499386349635 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"eea1d538-32a5-486a-9aaa-3c8eb67cd04f"}]
17:12:29 INFO - 1499386349665 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:12:29 INFO - 1499386349667 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
17:12:29 INFO - TEST-END: test_click.py TestClick.test_input_file1499386349670 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:12:29 INFO - 1499386349671 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:12:29 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 144ms
17:12:29 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:12:29 INFO - 1499386349672 Marionette TRACE 388 <- [1,6,null,{}]
17:12:29 INFO - 1499386349675 Marionette DEBUG Closed connection 388
17:12:29 INFO - 1499386349676 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49948
17:12:29 INFO - 1499386349677 Marionette DEBUG Closed connection 389
17:12:29 INFO - 1499386349677 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49949
17:12:29 INFO - 1499386349679 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:29 INFO - 1499386349679 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349682 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349686 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1939a985-690b-4c4d-9b59-a3d14206a019","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349687 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:12:29 INFO - 1499386349687 Marionette TRACE 390 <- [1,2,null,{}]
17:12:29 INFO - 1499386349688 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:12:29 INFO - 1499386349689 Marionette TRACE 390 <- [1,3,null,{}]
17:12:29 INFO - 1499386349690 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:12:29 INFO - 1499386349691 Marionette TRACE 390 <- [1,4,null,{}]
17:12:29 INFO - 1499386349693 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
17:12:29 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499386349696 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:12:29 INFO - 1499386349697 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:12:29 INFO - 1499386349698 Marionette TRACE 390 <- [1,6,null,{}]
17:12:29 INFO - 1499386349700 Marionette DEBUG Closed connection 390
17:12:29 INFO - 1499386349701 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49950
17:12:29 INFO - 1499386349702 Marionette DEBUG Closed connection 391
17:12:29 INFO - 1499386349703 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49951
17:12:29 INFO - 1499386349704 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:29 INFO - 1499386349704 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349707 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349710 Marionette TRACE 392 <- [1,1,null,{"sessionId":"4188c071-c3c6-4f2e-8ae6-936e4b491bec","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349711 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:12:29 INFO - 1499386349713 Marionette TRACE 392 <- [1,2,null,{}]
17:12:29 INFO - 1499386349715 Marionette DEBUG Closed connection 392
17:12:29 INFO - 1499386349716 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49952
17:12:29 INFO - 1499386349717 Marionette DEBUG Closed connection 393
17:12:29 INFO - 1499386349717 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49953
17:12:29 INFO - 1499386349718 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:12:29 INFO - 1499386349719 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:29 INFO - 1499386349722 Marionette DEBUG Register listener.js for window 12
17:12:29 INFO - 1499386349725 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e83c92eb-eb25-4db7-ac3d-2b6919ed747c","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:29 INFO - 1499386349726 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
17:12:29 INFO - 1499386349728 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:12:29 INFO - 1499386349731 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:12:29 INFO - 1499386349731 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:12:29 INFO - 1499386349744 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"
17:12:29 INFO - 1499386349749 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"
17:12:29 INFO - 1499386349760 Marionette TRACE 394 <- [1,2,null,{}]
17:12:29 INFO - 1499386349761 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:12:29 INFO - 1499386349767 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9624e6cd-602b-4712-8bed-458152d60315","ELEMENT":"9624e6cd-602b-4712-8bed-458152d60315"}}]
17:12:29 INFO - 1499386349768 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:12:29 INFO - 1499386349771 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b79a077-f137-4d85-a3fc-dae3ef28e40c","ELEMENT":"0b79a077-f137-4d85-a3fc-dae3ef28e40c"}}]
17:12:29 INFO - 1499386349772 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9624e6cd-602b-4712-8bed-458152d60315"}]
17:12:30 INFO - 1499386350012 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:12:30 INFO - 1499386350013 Marionette TRACE 394 <- [1,5,null,{}]
17:12:30 INFO - 1499386350014 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"0b79a077-f137-4d85-a3fc-dae3ef28e40c"}]
17:12:30 INFO - 1499386350032 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:12:30 INFO - 1499386350034 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:12:30 INFO - 1499386350037 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:12:30 INFO - 1499386350039 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
17:12:30 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499386350042 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:12:30 INFO - 1499386350043 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:12:30 INFO - 1499386350044 Marionette TRACE 394 <- [1,9,null,{}]
17:12:30 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 372ms
17:12:30 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:12:30 INFO - 1499386350047 Marionette DEBUG Closed connection 394
17:12:30 INFO - 1499386350047 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49954
17:12:30 INFO - 1499386350048 Marionette DEBUG Closed connection 395
17:12:30 INFO - 1499386350049 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49955
17:12:30 INFO - 1499386350050 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:30 INFO - 1499386350051 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:30 INFO - 1499386350061 Marionette DEBUG Register listener.js for window 12
17:12:30 INFO - 1499386350066 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a180acc6-5627-4ff9-92c7-7c381626f718","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:30 INFO - 1499386350067 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:12:30 INFO - 1499386350068 Marionette TRACE 396 <- [1,2,null,{}]
17:12:30 INFO - 1499386350069 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:12:30 INFO - 1499386350069 Marionette TRACE 396 <- [1,3,null,{}]
17:12:30 INFO - 1499386350070 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:12:30 INFO - 1499386350071 Marionette TRACE 396 <- [1,4,null,{}]
17:12:30 INFO - 1499386350073 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
17:12:30 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499386350076 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:12:30 INFO - 1499386350077 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:12:30 INFO - 1499386350079 Marionette TRACE 396 <- [1,6,null,{}]
17:12:30 INFO - 1499386350082 Marionette DEBUG Closed connection 396
17:12:30 INFO - 1499386350083 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49956
17:12:30 INFO - 1499386350084 Marionette DEBUG Closed connection 397
17:12:30 INFO - 1499386350085 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49957
17:12:30 INFO - 1499386350086 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:12:30 INFO - 1499386350087 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:30 INFO - 1499386350089 Marionette DEBUG Register listener.js for window 12
17:12:30 INFO - 1499386350093 Marionette TRACE 398 <- [1,1,null,{"sessionId":"af6f2014-7401-433e-a3bf-77df18754e4a","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:30 INFO - 1499386350094 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:12:30 INFO - 1499386350096 Marionette TRACE 398 <- [1,2,null,{}]
17:12:30 INFO - 1499386350098 Marionette DEBUG Closed connection 398
17:12:30 INFO - 1499386350099 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49958
17:12:30 INFO - 1499386350100 Marionette DEBUG Closed connection 399
17:12:30 INFO - 1499386350101 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49959
17:12:30 INFO - 1499386350102 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:12:30 INFO - 1499386350103 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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}
17:12:30 INFO - 1499386350105 Marionette DEBUG Register listener.js for window 12
17:12:30 INFO - 1499386350109 Marionette TRACE 400 <- [1,1,null,{"sessionId":"73bb2f39-aec5-4cc9-b16b-fb57d0ae6002","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":3144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjxv8_t.mozrunner","moz:accessibilityChecks":false}}]
17:12:30 INFO - 1499386350110 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
17:12:30 INFO - 1499386350111 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"
17:12:30 INFO - 1499386350114 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"
17:12:30 INFO - 1499386350114 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"
17:12:30 INFO - 1499386350129 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"
17:12:30 INFO - 1499386350133 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"
17:12:30 INFO - 1499386350144 Marionette TRACE 400 <- [1,2,null,{}]
17:12:30 INFO - 1499386350147 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:12:30 INFO - 1499386350150 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f130b2c3-305e-4e1d-9a05-4a169e6b8ac2","ELEMENT":"f130b2c3-305e-4e1d-9a05-4a169e6b8ac2"}}]
17:12:30 INFO - 1499386350151 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f130b2c3-305e-4e1d-9a05-4a169e6b8ac2"}]
17:12:30 INFO - 1499386350175 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:12:30 INFO - 1499386350176 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f130b2c3-305e-4e1d-9a05-4a169e6b8ac2"}]
17:12:30 INFO - 1499386350194 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element