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]
10:55:13 INFO - 1499363713918 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}]
10:55:13 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499363713920 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:55:13 INFO - 1499363713921 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:55:13 INFO - 1499363713923 Marionette TRACE 352 <- [1,6,null,{}]
10:55:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 141ms
10:55:13 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:55:13 INFO - 1499363713924 Marionette DEBUG Closed connection 352
10:55:13 INFO - 1499363713925 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49770
10:55:13 INFO - 1499363713926 Marionette DEBUG Closed connection 353
10:55:13 INFO - 1499363713927 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49771
10:55:13 INFO - 1499363713928 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:13 INFO - 1499363713929 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:13 INFO - 1499363713932 Marionette DEBUG Register listener.js for window 4294967297
10:55:13 INFO - 1499363713935 Marionette TRACE 354 <- [1,1,null,{"sessionId":"adde4dd6-24e1-4236-92b9-a033e61860b3","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:13 INFO - 1499363713936 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:55:13 INFO - 1499363713937 Marionette TRACE 354 <- [1,2,null,{}]
10:55:13 INFO - 1499363713938 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:55:13 INFO - 1499363713938 Marionette TRACE 354 <- [1,3,null,{}]
10:55:13 INFO - 1499363713939 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:55:13 INFO - 1499363713939 Marionette TRACE 354 <- [1,4,null,{}]
10:55:13 INFO - 1499363713941 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}]
10:55:13 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499363713944 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:55:13 INFO - 1499363713945 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:55:13 INFO - 1499363713946 Marionette TRACE 354 <- [1,6,null,{}]
10:55:13 INFO - 1499363713948 Marionette DEBUG Closed connection 354
10:55:13 INFO - 1499363713949 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49772
10:55:13 INFO - 1499363713950 Marionette DEBUG Closed connection 355
10:55:13 INFO - 1499363713950 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49773
10:55:13 INFO - 1499363713951 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:13 INFO - 1499363713952 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:13 INFO - 1499363713955 Marionette DEBUG Register listener.js for window 4294967297
10:55:13 INFO - 1499363713958 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9fa3b358-dc9f-4f24-a148-202ffaa4b92a","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:13 INFO - 1499363713959 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:55:13 INFO - 1499363713961 Marionette TRACE 356 <- [1,2,null,{}]
10:55:13 INFO - 1499363713963 Marionette DEBUG Closed connection 356
10:55:13 INFO - 1499363713963 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49774
10:55:13 INFO - 1499363713964 Marionette DEBUG Closed connection 357
10:55:13 INFO - 1499363713965 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49775
10:55:13 INFO - 1499363713966 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:55:13 INFO - 1499363713966 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:13 INFO - 1499363713969 Marionette DEBUG Register listener.js for window 4294967297
10:55:13 INFO - 1499363713972 Marionette TRACE 358 <- [1,1,null,{"sessionId":"4b23e1d7-6863-4cd4-b248-bf387add6c2b","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:13 INFO - 1499363713974 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49334/clicks.html"}]
10:55:13 INFO - 1499363713976 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/hidden.html"
10:55:13 INFO - 1499363713987 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/hidden.html"
10:55:13 INFO - 1499363713987 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/hidden.html"
10:55:13 INFO - 1499363713994 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/clicks.html"
10:55:14 INFO - 1499363714000 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/clicks.html"
10:55:14 INFO - 1499363714002 Marionette TRACE 358 <- [1,2,null,{}]
10:55:14 INFO - 1499363714006 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:55:14 INFO - 1499363714008 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f9bd3da9-9c3b-4899-9653-9369082d3097","ELEMENT":"f9bd3da9-9c3b-4899-9653-9369082d3097"}}]
10:55:14 INFO - 1499363714009 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f9bd3da9-9c3b-4899-9653-9369082d3097"}]
10:55:14 INFO - 1499363714052 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/clicks.html"
10:55:14 INFO - 1499363714062 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/clicks.html"
10:55:14 INFO - 1499363714062 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/clicks.html"
10:55:14 INFO - 1499363714072 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/test.html"
10:55:14 INFO - 1499363714079 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/test.html"
10:55:14 INFO - 1499363714080 Marionette TRACE 358 <- [1,4,null,{}]
10:55:14 INFO - 1499363714087 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:55:14 INFO - 1499363714091 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"369ad355-3692-4b55-9a4f-32945bd2edd2","ELEMENT":"369ad355-3692-4b55-9a4f-32945bd2edd2"}}]
10:55:14 INFO - 1499363714092 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:55:14 INFO - 1499363714092 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:55:14 INFO - 1499363714095 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}]
10:55:14 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499363714099 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:55:14 INFO - 1499363714100 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:55:14 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 178ms
10:55:14 INFO - 1499363714101 Marionette TRACE 358 <- [1,8,null,{}]
10:55:14 INFO - TEST-START | test_click.py TestClick.test_container_element
10:55:14 INFO - 1499363714103 Marionette DEBUG Closed connection 358
10:55:14 INFO - 1499363714104 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49778
10:55:14 INFO - 1499363714104 Marionette DEBUG Closed connection 359
10:55:14 INFO - 1499363714105 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49779
10:55:14 INFO - 1499363714106 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:14 INFO - 1499363714107 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714110 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714114 Marionette TRACE 360 <- [1,1,null,{"sessionId":"19ee070f-78fc-4b3a-847c-7563df320d43","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714115 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:55:14 INFO - 1499363714115 Marionette TRACE 360 <- [1,2,null,{}]
10:55:14 INFO - 1499363714116 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:55:14 INFO - 1499363714117 Marionette TRACE 360 <- [1,3,null,{}]
10:55:14 INFO - 1499363714118 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:55:14 INFO - 1499363714118 Marionette TRACE 360 <- [1,4,null,{}]
10:55:14 INFO - 1499363714120 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}]
10:55:14 INFO - TEST-START: test_click.py TestClick.test_container_element1499363714123 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:55:14 INFO - 1499363714124 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:55:14 INFO - 1499363714125 Marionette TRACE 360 <- [1,6,null,{}]
10:55:14 INFO - 1499363714127 Marionette DEBUG Closed connection 360
10:55:14 INFO - 1499363714128 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49780
10:55:14 INFO - 1499363714128 Marionette DEBUG Closed connection 361
10:55:14 INFO - 1499363714129 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49781
10:55:14 INFO - 1499363714130 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:14 INFO - 1499363714131 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714134 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714137 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ea44b14f-ed7f-475c-9315-be303fe3c900","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714138 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:55:14 INFO - 1499363714139 Marionette TRACE 362 <- [1,2,null,{}]
10:55:14 INFO - 1499363714141 Marionette DEBUG Closed connection 362
10:55:14 INFO - 1499363714142 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49782
10:55:14 INFO - 1499363714143 Marionette DEBUG Closed connection 363
10:55:14 INFO - 1499363714143 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49783
10:55:14 INFO - 1499363714144 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:55:14 INFO - 1499363714145 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714148 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714151 Marionette TRACE 364 <- [1,1,null,{"sessionId":"4d75a770-6afa-4c77-b111-cfe8f0b087a2","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714153 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"}]
10:55:14 INFO - 1499363714155 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/test.html"
10:55:14 INFO - 1499363714157 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/test.html"
10:55:14 INFO - 1499363714157 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/test.html"
10:55:14 INFO - 1499363714163 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"
10:55:14 INFO - 1499363714167 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"
10:55:14 INFO - 1499363714177 Marionette TRACE 364 <- [1,2,null,{}]
10:55:14 INFO - 1499363714181 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:55:14 INFO - 1499363714186 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"20f0e29c-3c9a-42df-bf94-bdcbd9ac26f4","ELEMENT":"20f0e29c-3c9a-42df-bf94-bdcbd9ac26f4"}}]
10:55:14 INFO - 1499363714187 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"20f0e29c-3c9a-42df-bf94-bdcbd9ac26f4"}]
10:55:14 INFO - 1499363714435 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:55:14 INFO - 1499363714436 Marionette TRACE 364 <- [1,4,null,{}]
10:55:14 INFO - 1499363714437 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"20f0e29c-3c9a-42df-bf94-bdcbd9ac26f4","name":"selected"}]
10:55:14 INFO - 1499363714442 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:55:14 INFO - 1499363714443 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}]
10:55:14 INFO - TEST-END: test_click.py TestClick.test_container_element1499363714446 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:55:14 INFO - 1499363714447 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:55:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 346ms
10:55:14 INFO - 1499363714448 Marionette TRACE 364 <- [1,7,null,{}]
10:55:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:55:14 INFO - 1499363714450 Marionette DEBUG Closed connection 364
10:55:14 INFO - 1499363714451 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49784
10:55:14 INFO - 1499363714452 Marionette DEBUG Closed connection 365
10:55:14 INFO - 1499363714453 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49785
10:55:14 INFO - 1499363714454 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:14 INFO - 1499363714454 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714457 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714460 Marionette TRACE 366 <- [1,1,null,{"sessionId":"7c0c169f-75fa-44cc-a123-35c7027d0463","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714461 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:55:14 INFO - 1499363714462 Marionette TRACE 366 <- [1,2,null,{}]
10:55:14 INFO - 1499363714463 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:55:14 INFO - 1499363714463 Marionette TRACE 366 <- [1,3,null,{}]
10:55:14 INFO - 1499363714464 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:55:14 INFO - 1499363714465 Marionette TRACE 366 <- [1,4,null,{}]
10:55:14 INFO - 1499363714466 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}]
10:55:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499363714469 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:55:14 INFO - 1499363714470 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:55:14 INFO - 1499363714471 Marionette TRACE 366 <- [1,6,null,{}]
10:55:14 INFO - 1499363714473 Marionette DEBUG Closed connection 366
10:55:14 INFO - 1499363714473 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49786
10:55:14 INFO - 1499363714474 Marionette DEBUG Closed connection 367
10:55:14 INFO - 1499363714475 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49787
10:55:14 INFO - 1499363714476 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:14 INFO - 1499363714477 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714479 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714483 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cb1afb92-5a1c-46d6-8b6a-e74a305af59b","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714484 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:55:14 INFO - 1499363714486 Marionette TRACE 368 <- [1,2,null,{}]
10:55:14 INFO - 1499363714488 Marionette DEBUG Closed connection 368
10:55:14 INFO - 1499363714488 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49788
10:55:14 INFO - 1499363714489 Marionette DEBUG Closed connection 369
10:55:14 INFO - 1499363714489 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49789
10:55:14 INFO - 1499363714491 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:55:14 INFO - 1499363714491 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714494 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714497 Marionette TRACE 370 <- [1,1,null,{"sessionId":"953762cf-1479-46a4-bf10-a0b3b7925852","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714499 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"}]
10:55:14 INFO - 1499363714501 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"
10:55:14 INFO - 1499363714503 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"
10:55:14 INFO - 1499363714503 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"
10:55:14 INFO - 1499363714509 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"
10:55:14 INFO - 1499363714513 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"
10:55:14 INFO - 1499363714515 Marionette TRACE 370 <- [1,2,null,{}]
10:55:14 INFO - 1499363714523 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:55:14 INFO - 1499363714526 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"34aa9da7-4def-4b29-b7de-67bb44a034da","ELEMENT":"34aa9da7-4def-4b29-b7de-67bb44a034da"}}]
10:55:14 INFO - 1499363714527 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"34aa9da7-4def-4b29-b7de-67bb44a034da"}]
10:55:14 INFO - 1499363714770 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:55:14 INFO - 1499363714771 Marionette TRACE 370 <- [1,4,null,{}]
10:55:14 INFO - 1499363714773 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"34aa9da7-4def-4b29-b7de-67bb44a034da","name":"selected"}]
10:55:14 INFO - 1499363714777 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:55:14 INFO - 1499363714778 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}]
10:55:14 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499363714781 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:55:14 INFO - 1499363714782 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:55:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
10:55:14 INFO - 1499363714784 Marionette TRACE 370 <- [1,7,null,{}]
10:55:14 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:55:14 INFO - 1499363714786 Marionette DEBUG Closed connection 370
10:55:14 INFO - 1499363714787 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49790
10:55:14 INFO - 1499363714787 Marionette DEBUG Closed connection 371
10:55:14 INFO - 1499363714788 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49791
10:55:14 INFO - 1499363714789 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:14 INFO - 1499363714790 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714792 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714796 Marionette TRACE 372 <- [1,1,null,{"sessionId":"a4b206a3-ff18-42c5-a899-62d0b19b2325","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714797 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:55:14 INFO - 1499363714797 Marionette TRACE 372 <- [1,2,null,{}]
10:55:14 INFO - 1499363714798 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:55:14 INFO - 1499363714798 Marionette TRACE 372 <- [1,3,null,{}]
10:55:14 INFO - 1499363714799 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:55:14 INFO - 1499363714800 Marionette TRACE 372 <- [1,4,null,{}]
10:55:14 INFO - 1499363714801 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}]
10:55:14 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499363714804 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:55:14 INFO - 1499363714805 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:55:14 INFO - 1499363714806 Marionette TRACE 372 <- [1,6,null,{}]
10:55:14 INFO - 1499363714808 Marionette DEBUG Closed connection 372
10:55:14 INFO - 1499363714809 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49792
10:55:14 INFO - 1499363714809 Marionette DEBUG Closed connection 373
10:55:14 INFO - 1499363714810 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49793
10:55:14 INFO - 1499363714811 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:14 INFO - 1499363714812 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714815 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714819 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a3966805-18f1-4b1b-b70e-919bb6ed0350","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714820 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:55:14 INFO - 1499363714821 Marionette TRACE 374 <- [1,2,null,{}]
10:55:14 INFO - 1499363714823 Marionette DEBUG Closed connection 374
10:55:14 INFO - 1499363714823 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49794
10:55:14 INFO - 1499363714824 Marionette DEBUG Closed connection 375
10:55:14 INFO - 1499363714825 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49795
10:55:14 INFO - 1499363714826 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:55:14 INFO - 1499363714827 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:14 INFO - 1499363714830 Marionette DEBUG Register listener.js for window 4294967297
10:55:14 INFO - 1499363714833 Marionette TRACE 376 <- [1,1,null,{"sessionId":"9d5a6617-d9fd-4962-b866-70c0b92f8933","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:14 INFO - 1499363714834 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"}]
10:55:14 INFO - 1499363714836 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"
10:55:14 INFO - 1499363714838 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"
10:55:14 INFO - 1499363714838 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"
10:55:14 INFO - 1499363714845 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"
10:55:14 INFO - 1499363714847 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"
10:55:14 INFO - 1499363714856 Marionette TRACE 376 <- [1,2,null,{}]
10:55:14 INFO - 1499363714859 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:55:14 INFO - 1499363714862 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"78325a5a-3d04-4c8c-960c-b5bc6a7064f8","ELEMENT":"78325a5a-3d04-4c8c-960c-b5bc6a7064f8"}}]
10:55:14 INFO - 1499363714863 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"78325a5a-3d04-4c8c-960c-b5bc6a7064f8"}]
10:55:15 INFO - 1499363715110 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:55:15 INFO - 1499363715110 Marionette TRACE 376 <- [1,4,null,{}]
10:55:15 INFO - 1499363715112 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}]
10:55:15 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499363715115 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:55:15 INFO - 1499363715116 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:55:15 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 333ms
10:55:15 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:55:15 INFO - 1499363715118 Marionette TRACE 376 <- [1,6,null,{}]
10:55:15 INFO - 1499363715119 Marionette DEBUG Closed connection 376
10:55:15 INFO - 1499363715120 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49796
10:55:15 INFO - 1499363715121 Marionette DEBUG Closed connection 377
10:55:15 INFO - 1499363715121 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49797
10:55:15 INFO - 1499363715123 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:15 INFO - 1499363715123 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715126 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715129 Marionette TRACE 378 <- [1,1,null,{"sessionId":"85a41c7b-19c7-41ed-b718-b6012dfa1df2","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715130 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:55:15 INFO - 1499363715131 Marionette TRACE 378 <- [1,2,null,{}]
10:55:15 INFO - 1499363715132 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:55:15 INFO - 1499363715132 Marionette TRACE 378 <- [1,3,null,{}]
10:55:15 INFO - 1499363715133 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:55:15 INFO - 1499363715134 Marionette TRACE 378 <- [1,4,null,{}]
10:55:15 INFO - 1499363715136 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}]
10:55:15 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499363715139 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:55:15 INFO - 1499363715140 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:55:15 INFO - 1499363715141 Marionette TRACE 378 <- [1,6,null,{}]
10:55:15 INFO - 1499363715142 Marionette DEBUG Closed connection 378
10:55:15 INFO - 1499363715143 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49798
10:55:15 INFO - 1499363715144 Marionette DEBUG Closed connection 379
10:55:15 INFO - 1499363715145 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49799
10:55:15 INFO - 1499363715146 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:15 INFO - 1499363715146 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715149 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715160 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a57a0df4-9588-461e-880e-d86244df2569","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715161 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:55:15 INFO - 1499363715163 Marionette TRACE 380 <- [1,2,null,{}]
10:55:15 INFO - 1499363715165 Marionette DEBUG Closed connection 380
10:55:15 INFO - 1499363715165 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49800
10:55:15 INFO - 1499363715166 Marionette DEBUG Closed connection 381
10:55:15 INFO - 1499363715167 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49801
10:55:15 INFO - 1499363715168 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:55:15 INFO - 1499363715169 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715172 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715175 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d7831252-b3a6-41d5-9ecc-b1194c0621d5","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715176 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"}]
10:55:15 INFO - 1499363715178 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"
10:55:15 INFO - 1499363715180 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"
10:55:15 INFO - 1499363715180 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"
10:55:15 INFO - 1499363715187 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"
10:55:15 INFO - 1499363715190 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"
10:55:15 INFO - 1499363715194 Marionette TRACE 382 <- [1,2,null,{}]
10:55:15 INFO - 1499363715197 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:55:15 INFO - 1499363715204 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f666eaa0-30c0-41ea-93b9-4d123a2cd065","ELEMENT":"f666eaa0-30c0-41ea-93b9-4d123a2cd065"}}]
10:55:15 INFO - 1499363715205 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f666eaa0-30c0-41ea-93b9-4d123a2cd065"}]
10:55:15 INFO - 1499363715447 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:55:15 INFO - 1499363715448 Marionette TRACE 382 <- [1,4,null,{}]
10:55:15 INFO - 1499363715450 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}]
10:55:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499363715453 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:55:15 INFO - 1499363715454 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:55:15 INFO - 1499363715455 Marionette TRACE 382 <- [1,6,null,{}]
10:55:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 337ms
10:55:15 INFO - TEST-START | test_click.py TestClick.test_input_file
10:55:15 INFO - 1499363715457 Marionette DEBUG Closed connection 382
10:55:15 INFO - 1499363715458 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49802
10:55:15 INFO - 1499363715459 Marionette DEBUG Closed connection 383
10:55:15 INFO - 1499363715459 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49803
10:55:15 INFO - 1499363715460 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:15 INFO - 1499363715461 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715464 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715467 Marionette TRACE 384 <- [1,1,null,{"sessionId":"74f3fb31-1a2b-4b64-aa16-222e248b81b7","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715468 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:55:15 INFO - 1499363715469 Marionette TRACE 384 <- [1,2,null,{}]
10:55:15 INFO - 1499363715470 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:55:15 INFO - 1499363715470 Marionette TRACE 384 <- [1,3,null,{}]
10:55:15 INFO - 1499363715471 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:55:15 INFO - 1499363715472 Marionette TRACE 384 <- [1,4,null,{}]
10:55:15 INFO - 1499363715473 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}]
10:55:15 INFO - TEST-START: test_click.py TestClick.test_input_file1499363715476 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:55:15 INFO - 1499363715478 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:55:15 INFO - 1499363715479 Marionette TRACE 384 <- [1,6,null,{}]
10:55:15 INFO - 1499363715481 Marionette DEBUG Closed connection 384
10:55:15 INFO - 1499363715481 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49804
10:55:15 INFO - 1499363715482 Marionette DEBUG Closed connection 385
10:55:15 INFO - 1499363715483 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49805
10:55:15 INFO - 1499363715484 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:15 INFO - 1499363715484 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715487 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715490 Marionette TRACE 386 <- [1,1,null,{"sessionId":"67ce24da-b5ed-482a-8de2-6ce732da1c64","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715491 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:55:15 INFO - 1499363715492 Marionette TRACE 386 <- [1,2,null,{}]
10:55:15 INFO - 1499363715494 Marionette DEBUG Closed connection 386
10:55:15 INFO - 1499363715495 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49806
10:55:15 INFO - 1499363715496 Marionette DEBUG Closed connection 387
10:55:15 INFO - 1499363715496 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49807
10:55:15 INFO - 1499363715497 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:55:15 INFO - 1499363715498 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715500 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715504 Marionette TRACE 388 <- [1,1,null,{"sessionId":"28b29f74-7694-40ea-827a-d9a3a94406fe","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715505 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:55:15 INFO - 1499363715507 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"
10:55:15 INFO - 1499363715509 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"
10:55:15 INFO - 1499363715509 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"
10:55:15 INFO - 1499363715519 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:55:15 INFO - 1499363715520 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:55:15 INFO - 1499363715524 Marionette TRACE 388 <- [1,2,null,{}]
10:55:15 INFO - 1499363715527 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:55:15 INFO - 1499363715530 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"022fbf92-1ec9-4c0b-8c6f-e2d82fa3e789","ELEMENT":"022fbf92-1ec9-4c0b-8c6f-e2d82fa3e789"}}]
10:55:15 INFO - 1499363715531 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"022fbf92-1ec9-4c0b-8c6f-e2d82fa3e789"}]
10:55:15 INFO - 1499363715568 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]
10:55:15 INFO - 1499363715570 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}]
10:55:15 INFO - TEST-END: test_click.py TestClick.test_input_file1499363715573 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:55:15 INFO - 1499363715574 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:55:15 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 119ms
10:55:15 INFO - 1499363715576 Marionette TRACE 388 <- [1,6,null,{}]
10:55:15 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:55:15 INFO - 1499363715577 Marionette DEBUG Closed connection 388
10:55:15 INFO - 1499363715578 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49808
10:55:15 INFO - 1499363715579 Marionette DEBUG Closed connection 389
10:55:15 INFO - 1499363715579 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49809
10:55:15 INFO - 1499363715580 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:15 INFO - 1499363715582 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715585 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715588 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8ba08c83-4b3c-41c9-a1d5-8863c88c9ab4","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715590 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:55:15 INFO - 1499363715591 Marionette TRACE 390 <- [1,2,null,{}]
10:55:15 INFO - 1499363715591 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:55:15 INFO - 1499363715592 Marionette TRACE 390 <- [1,3,null,{}]
10:55:15 INFO - 1499363715593 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:55:15 INFO - 1499363715593 Marionette TRACE 390 <- [1,4,null,{}]
10:55:15 INFO - 1499363715595 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}]
10:55:15 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499363715598 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:55:15 INFO - 1499363715599 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:55:15 INFO - 1499363715600 Marionette TRACE 390 <- [1,6,null,{}]
10:55:15 INFO - 1499363715602 Marionette DEBUG Closed connection 390
10:55:15 INFO - 1499363715602 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49810
10:55:15 INFO - 1499363715603 Marionette DEBUG Closed connection 391
10:55:15 INFO - 1499363715604 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49811
10:55:15 INFO - 1499363715605 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:15 INFO - 1499363715605 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715608 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715611 Marionette TRACE 392 <- [1,1,null,{"sessionId":"6a366511-5a32-4268-b470-df892769e17c","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715613 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:55:15 INFO - 1499363715614 Marionette TRACE 392 <- [1,2,null,{}]
10:55:15 INFO - 1499363715615 Marionette DEBUG Closed connection 392
10:55:15 INFO - 1499363715616 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49812
10:55:15 INFO - 1499363715617 Marionette DEBUG Closed connection 393
10:55:15 INFO - 1499363715618 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49813
10:55:15 INFO - 1499363715619 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:55:15 INFO - 1499363715619 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715622 Marionette DEBUG Register listener.js for window 4294967297
10:55:15 INFO - 1499363715625 Marionette TRACE 394 <- [1,1,null,{"sessionId":"431259c6-43f6-42ab-9e89-13db77b80237","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:15 INFO - 1499363715626 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"}]
10:55:15 INFO - 1499363715628 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:55:15 INFO - 1499363715630 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:55:15 INFO - 1499363715631 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:55:15 INFO - 1499363715638 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"
10:55:15 INFO - 1499363715641 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"
10:55:15 INFO - 1499363715643 Marionette TRACE 394 <- [1,2,null,{}]
10:55:15 INFO - 1499363715646 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:55:15 INFO - 1499363715653 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"96a86cad-8783-4a31-bda5-47e957d2e533","ELEMENT":"96a86cad-8783-4a31-bda5-47e957d2e533"}}]
10:55:15 INFO - 1499363715654 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:55:15 INFO - 1499363715657 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"931b826c-a737-448e-bb02-72b4f0c1b02c","ELEMENT":"931b826c-a737-448e-bb02-72b4f0c1b02c"}}]
10:55:15 INFO - 1499363715658 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"96a86cad-8783-4a31-bda5-47e957d2e533"}]
10:55:15 INFO - 1499363715898 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:55:15 INFO - 1499363715909 Marionette TRACE 394 <- [1,5,null,{}]
10:55:15 INFO - 1499363715913 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"931b826c-a737-448e-bb02-72b4f0c1b02c"}]
10:55:15 INFO - 1499363715951 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
10:55:15 INFO - 1499363715959 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:55:15 INFO - 1499363715963 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:55:15 INFO - 1499363715966 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}]
10:55:15 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499363715973 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:55:15 INFO - 1499363715975 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:55:15 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 402ms
10:55:15 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:55:15 INFO - 1499363715979 Marionette TRACE 394 <- [1,9,null,{}]
10:55:15 INFO - 1499363715981 Marionette DEBUG Closed connection 394
10:55:15 INFO - 1499363715983 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49814
10:55:15 INFO - 1499363715985 Marionette DEBUG Closed connection 395
10:55:15 INFO - 1499363715986 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49815
10:55:15 INFO - 1499363715987 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:15 INFO - 1499363715990 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:15 INFO - 1499363715994 Marionette DEBUG Register listener.js for window 4294967297
10:55:16 INFO - 1499363716005 Marionette TRACE 396 <- [1,1,null,{"sessionId":"27f7a8cc-acfe-4e2c-a56d-cc83c8f5fe11","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:16 INFO - 1499363716007 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:55:16 INFO - 1499363716008 Marionette TRACE 396 <- [1,2,null,{}]
10:55:16 INFO - 1499363716009 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:55:16 INFO - 1499363716010 Marionette TRACE 396 <- [1,3,null,{}]
10:55:16 INFO - 1499363716012 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:55:16 INFO - 1499363716013 Marionette TRACE 396 <- [1,4,null,{}]
10:55:16 INFO - 1499363716016 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}]
10:55:16 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499363716020 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:55:16 INFO - 1499363716022 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:55:16 INFO - 1499363716024 Marionette TRACE 396 <- [1,6,null,{}]
10:55:16 INFO - 1499363716026 Marionette DEBUG Closed connection 396
10:55:16 INFO - 1499363716027 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49816
10:55:16 INFO - 1499363716028 Marionette DEBUG Closed connection 397
10:55:16 INFO - 1499363716029 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49817
10:55:16 INFO - 1499363716032 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:55:16 INFO - 1499363716034 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:16 INFO - 1499363716037 Marionette DEBUG Register listener.js for window 4294967297
10:55:16 INFO - 1499363716042 Marionette TRACE 398 <- [1,1,null,{"sessionId":"178318c6-fc7a-4b16-bd6d-351814b09598","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:16 INFO - 1499363716043 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:55:16 INFO - 1499363716046 Marionette TRACE 398 <- [1,2,null,{}]
10:55:16 INFO - 1499363716048 Marionette DEBUG Closed connection 398
10:55:16 INFO - 1499363716049 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49818
10:55:16 INFO - 1499363716051 Marionette DEBUG Closed connection 399
10:55:16 INFO - 1499363716052 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49819
10:55:16 INFO - 1499363716053 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:55:16 INFO - 1499363716054 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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}
10:55:16 INFO - 1499363716057 Marionette DEBUG Register listener.js for window 4294967297
10:55:16 INFO - 1499363716061 Marionette TRACE 400 <- [1,1,null,{"sessionId":"5859727a-72d0-4367-bc45-32468c6aaf10","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":3168,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-263.000\\AppData\\Local\\Temp\\tmpwefk9f.mozrunner","moz:accessibilityChecks":false}}]
10:55:16 INFO - 1499363716062 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"}]
10:55:16 INFO - 1499363716065 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"
10:55:16 INFO - 1499363716067 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"
10:55:16 INFO - 1499363716068 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"
10:55:16 INFO - 1499363716074 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"
10:55:16 INFO - 1499363716077 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"
10:55:16 INFO - 1499363716089 Marionette TRACE 400 <- [1,2,null,{}]
10:55:16 INFO - 1499363716095 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:55:16 INFO - 1499363716098 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"07df848f-f94f-44cd-99e3-4caed3b4523c","ELEMENT":"07df848f-f94f-44cd-99e3-4caed3b4523c"}}]
10:55:16 INFO - 1499363716103 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"07df848f-f94f-44cd-99e3-4caed3b4523c"}]
10:55:16 INFO - 1499363716129 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:55:16 INFO - 1499363716130 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"07df848f-f94f-44cd-99e3-4caed3b4523c"}]
10:55:16 INFO - 1499363716147 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element