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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
03:23:41 INFO - 1499423021007 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}]
03:23:41 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499423021010 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1499423021011 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:23:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 151ms
03:23:41 INFO - 1499423021012 Marionette TRACE 352 <- [1,6,null,{}]
03:23:41 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:23:41 INFO - 1499423021014 Marionette DEBUG Closed connection 352
03:23:41 INFO - 1499423021015 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49767
03:23:41 INFO - 1499423021016 Marionette DEBUG Closed connection 353
03:23:41 INFO - 1499423021017 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49768
03:23:41 INFO - 1499423021018 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1499423021018 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021021 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021025 Marionette TRACE 354 <- [1,1,null,{"sessionId":"e93fae94-11c3-43f7-b54a-c1b19351e820","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021026 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:23:41 INFO - 1499423021026 Marionette TRACE 354 <- [1,2,null,{}]
03:23:41 INFO - 1499423021027 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:41 INFO - 1499423021028 Marionette TRACE 354 <- [1,3,null,{}]
03:23:41 INFO - 1499423021029 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:41 INFO - 1499423021030 Marionette TRACE 354 <- [1,4,null,{}]
03:23:41 INFO - 1499423021031 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}]
03:23:41 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499423021034 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1499423021035 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:23:41 INFO - 1499423021036 Marionette TRACE 354 <- [1,6,null,{}]
03:23:41 INFO - 1499423021038 Marionette DEBUG Closed connection 354
03:23:41 INFO - 1499423021039 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49769
03:23:41 INFO - 1499423021039 Marionette DEBUG Closed connection 355
03:23:41 INFO - 1499423021040 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49770
03:23:41 INFO - 1499423021041 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1499423021042 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021045 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021048 Marionette TRACE 356 <- [1,1,null,{"sessionId":"b8f12ac2-fbf0-49b5-b8b6-2ffdf2db8d39","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021049 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:23:41 INFO - 1499423021050 Marionette TRACE 356 <- [1,2,null,{}]
03:23:41 INFO - 1499423021052 Marionette DEBUG Closed connection 356
03:23:41 INFO - 1499423021053 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49771
03:23:41 INFO - 1499423021053 Marionette DEBUG Closed connection 357
03:23:41 INFO - 1499423021054 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49772
03:23:41 INFO - 1499423021055 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:41 INFO - 1499423021056 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021059 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021062 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3f6aa26a-affd-41bf-b429-5adfee5153d9","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021063 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
03:23:41 INFO - 1499423021065 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
03:23:41 INFO - 1499423021073 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
03:23:41 INFO - 1499423021073 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
03:23:41 INFO - 1499423021086 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
03:23:41 INFO - 1499423021088 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
03:23:41 INFO - 1499423021091 Marionette TRACE 358 <- [1,2,null,{}]
03:23:41 INFO - 1499423021095 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:23:41 INFO - 1499423021103 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a209d4e-329b-4258-82e9-29cd3df8b485","ELEMENT":"7a209d4e-329b-4258-82e9-29cd3df8b485"}}]
03:23:41 INFO - 1499423021104 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"7a209d4e-329b-4258-82e9-29cd3df8b485"}]
03:23:41 INFO - 1499423021147 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
03:23:41 INFO - 1499423021155 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
03:23:41 INFO - 1499423021155 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
03:23:41 INFO - 1499423021167 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
03:23:41 INFO - 1499423021171 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
03:23:41 INFO - 1499423021172 Marionette TRACE 358 <- [1,4,null,{}]
03:23:41 INFO - 1499423021176 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:23:41 INFO - 1499423021178 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84bb5768-fbdf-41c3-b52e-e29fbfd8c6f2","ELEMENT":"84bb5768-fbdf-41c3-b52e-e29fbfd8c6f2"}}]
03:23:41 INFO - 1499423021183 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:23:41 INFO - 1499423021184 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:23:41 INFO - 1499423021187 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}]
03:23:41 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499423021190 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:23:41 INFO - 1499423021192 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:23:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 181ms
03:23:41 INFO - TEST-START | test_click.py TestClick.test_container_element
03:23:41 INFO - 1499423021194 Marionette TRACE 358 <- [1,8,null,{}]
03:23:41 INFO - 1499423021196 Marionette DEBUG Closed connection 358
03:23:41 INFO - 1499423021197 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49775
03:23:41 INFO - 1499423021198 Marionette DEBUG Closed connection 359
03:23:41 INFO - 1499423021199 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49776
03:23:41 INFO - 1499423021200 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1499423021200 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021203 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021207 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b7f2ff4a-a172-40f5-8c1b-acfd34698912","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021208 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:23:41 INFO - 1499423021208 Marionette TRACE 360 <- [1,2,null,{}]
03:23:41 INFO - 1499423021209 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:41 INFO - 1499423021210 Marionette TRACE 360 <- [1,3,null,{}]
03:23:41 INFO - 1499423021211 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:41 INFO - 1499423021211 Marionette TRACE 360 <- [1,4,null,{}]
03:23:41 INFO - 1499423021213 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}]
03:23:41 INFO - TEST-START: test_click.py TestClick.test_container_element1499423021216 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1499423021216 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:23:41 INFO - 1499423021218 Marionette TRACE 360 <- [1,6,null,{}]
03:23:41 INFO - 1499423021220 Marionette DEBUG Closed connection 360
03:23:41 INFO - 1499423021220 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49777
03:23:41 INFO - 1499423021221 Marionette DEBUG Closed connection 361
03:23:41 INFO - 1499423021222 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49778
03:23:41 INFO - 1499423021223 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1499423021224 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021227 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021230 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5207a220-8fb4-4d56-ab39-95e5b532661e","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021235 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:23:41 INFO - 1499423021236 Marionette TRACE 362 <- [1,2,null,{}]
03:23:41 INFO - 1499423021238 Marionette DEBUG Closed connection 362
03:23:41 INFO - 1499423021238 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49779
03:23:41 INFO - 1499423021239 Marionette DEBUG Closed connection 363
03:23:41 INFO - 1499423021240 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49780
03:23:41 INFO - 1499423021241 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:41 INFO - 1499423021242 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021244 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021248 Marionette TRACE 364 <- [1,1,null,{"sessionId":"547e83c9-ce9b-4a11-ae97-9ed10f3fc6e5","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021249 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"}]
03:23:41 INFO - 1499423021251 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
03:23:41 INFO - 1499423021253 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
03:23:41 INFO - 1499423021253 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
03:23:41 INFO - 1499423021260 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"
03:23:41 INFO - 1499423021264 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"
03:23:41 INFO - 1499423021274 Marionette TRACE 364 <- [1,2,null,{}]
03:23:41 INFO - 1499423021278 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:23:41 INFO - 1499423021283 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1c6cc35c-d232-4272-b4c5-05936554d438","ELEMENT":"1c6cc35c-d232-4272-b4c5-05936554d438"}}]
03:23:41 INFO - 1499423021284 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"1c6cc35c-d232-4272-b4c5-05936554d438"}]
03:23:41 INFO - 1499423021535 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:41 INFO - 1499423021536 Marionette TRACE 364 <- [1,4,null,{}]
03:23:41 INFO - 1499423021537 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"1c6cc35c-d232-4272-b4c5-05936554d438","name":"selected"}]
03:23:41 INFO - 1499423021542 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:23:41 INFO - 1499423021544 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}]
03:23:41 INFO - TEST-END: test_click.py TestClick.test_container_element1499423021547 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:23:41 INFO - 1499423021548 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:23:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 354ms
03:23:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:23:41 INFO - 1499423021549 Marionette TRACE 364 <- [1,7,null,{}]
03:23:41 INFO - 1499423021551 Marionette DEBUG Closed connection 364
03:23:41 INFO - 1499423021551 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49781
03:23:41 INFO - 1499423021552 Marionette DEBUG Closed connection 365
03:23:41 INFO - 1499423021553 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49782
03:23:41 INFO - 1499423021554 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1499423021554 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021557 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021561 Marionette TRACE 366 <- [1,1,null,{"sessionId":"d677acf5-80a0-4bbb-bae3-0b03ae2f4642","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021562 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:23:41 INFO - 1499423021562 Marionette TRACE 366 <- [1,2,null,{}]
03:23:41 INFO - 1499423021563 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:41 INFO - 1499423021564 Marionette TRACE 366 <- [1,3,null,{}]
03:23:41 INFO - 1499423021565 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:41 INFO - 1499423021565 Marionette TRACE 366 <- [1,4,null,{}]
03:23:41 INFO - 1499423021567 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}]
03:23:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499423021570 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1499423021570 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:23:41 INFO - 1499423021572 Marionette TRACE 366 <- [1,6,null,{}]
03:23:41 INFO - 1499423021574 Marionette DEBUG Closed connection 366
03:23:41 INFO - 1499423021574 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49783
03:23:41 INFO - 1499423021575 Marionette DEBUG Closed connection 367
03:23:41 INFO - 1499423021576 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49784
03:23:41 INFO - 1499423021577 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1499423021578 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021581 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021584 Marionette TRACE 368 <- [1,1,null,{"sessionId":"533bd3fc-8ea7-4de7-bfd5-9030bcebb977","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021585 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:23:41 INFO - 1499423021587 Marionette TRACE 368 <- [1,2,null,{}]
03:23:41 INFO - 1499423021589 Marionette DEBUG Closed connection 368
03:23:41 INFO - 1499423021589 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49785
03:23:41 INFO - 1499423021590 Marionette DEBUG Closed connection 369
03:23:41 INFO - 1499423021591 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49786
03:23:41 INFO - 1499423021592 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:41 INFO - 1499423021592 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021595 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021599 Marionette TRACE 370 <- [1,1,null,{"sessionId":"2f452d58-de28-4205-9519-e5b1ac46ce08","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021600 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"}]
03:23:41 INFO - 1499423021602 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"
03:23:41 INFO - 1499423021604 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"
03:23:41 INFO - 1499423021604 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"
03:23:41 INFO - 1499423021611 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"
03:23:41 INFO - 1499423021615 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"
03:23:41 INFO - 1499423021621 Marionette TRACE 370 <- [1,2,null,{}]
03:23:41 INFO - 1499423021624 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:23:41 INFO - 1499423021626 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e9af49b-cdf2-4e38-96fd-4ef7203f7b5a","ELEMENT":"9e9af49b-cdf2-4e38-96fd-4ef7203f7b5a"}}]
03:23:41 INFO - 1499423021627 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9e9af49b-cdf2-4e38-96fd-4ef7203f7b5a"}]
03:23:41 INFO - 1499423021879 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:41 INFO - 1499423021880 Marionette TRACE 370 <- [1,4,null,{}]
03:23:41 INFO - 1499423021881 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9e9af49b-cdf2-4e38-96fd-4ef7203f7b5a","name":"selected"}]
03:23:41 INFO - 1499423021886 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:23:41 INFO - 1499423021887 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}]
03:23:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 342ms
03:23:41 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499423021890 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:23:41 INFO - 1499423021891 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:23:41 INFO - 1499423021892 Marionette TRACE 370 <- [1,7,null,{}]
03:23:41 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:23:41 INFO - 1499423021894 Marionette DEBUG Closed connection 370
03:23:41 INFO - 1499423021895 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49787
03:23:41 INFO - 1499423021896 Marionette DEBUG Closed connection 371
03:23:41 INFO - 1499423021897 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49788
03:23:41 INFO - 1499423021898 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1499423021899 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021901 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021905 Marionette TRACE 372 <- [1,1,null,{"sessionId":"0dd24991-0d44-48c2-9000-0e8f0ef561ca","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021906 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:23:41 INFO - 1499423021907 Marionette TRACE 372 <- [1,2,null,{}]
03:23:41 INFO - 1499423021908 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:41 INFO - 1499423021908 Marionette TRACE 372 <- [1,3,null,{}]
03:23:41 INFO - 1499423021909 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:41 INFO - 1499423021909 Marionette TRACE 372 <- [1,4,null,{}]
03:23:41 INFO - 1499423021911 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}]
03:23:41 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499423021914 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:23:41 INFO - 1499423021915 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:23:41 INFO - 1499423021916 Marionette TRACE 372 <- [1,6,null,{}]
03:23:41 INFO - 1499423021918 Marionette DEBUG Closed connection 372
03:23:41 INFO - 1499423021918 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49789
03:23:41 INFO - 1499423021919 Marionette DEBUG Closed connection 373
03:23:41 INFO - 1499423021920 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49790
03:23:41 INFO - 1499423021921 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:41 INFO - 1499423021921 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021924 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021927 Marionette TRACE 374 <- [1,1,null,{"sessionId":"216ae9d3-1fe2-42dd-b7b9-4a29a16f8054","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021929 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:23:41 INFO - 1499423021930 Marionette TRACE 374 <- [1,2,null,{}]
03:23:41 INFO - 1499423021931 Marionette DEBUG Closed connection 374
03:23:41 INFO - 1499423021932 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49791
03:23:41 INFO - 1499423021933 Marionette DEBUG Closed connection 375
03:23:41 INFO - 1499423021933 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49792
03:23:41 INFO - 1499423021935 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:41 INFO - 1499423021935 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:41 INFO - 1499423021938 Marionette DEBUG Register listener.js for window 4294967297
03:23:41 INFO - 1499423021941 Marionette TRACE 376 <- [1,1,null,{"sessionId":"af09d9ee-0a26-402b-9348-0b5f32143f0f","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:41 INFO - 1499423021942 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"}]
03:23:41 INFO - 1499423021944 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"
03:23:41 INFO - 1499423021946 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"
03:23:41 INFO - 1499423021947 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"
03:23:41 INFO - 1499423021954 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"
03:23:41 INFO - 1499423021956 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"
03:23:41 INFO - 1499423021960 Marionette TRACE 376 <- [1,2,null,{}]
03:23:41 INFO - 1499423021968 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:23:41 INFO - 1499423021971 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"943c8309-af9e-4278-9b44-aec60fca8d1a","ELEMENT":"943c8309-af9e-4278-9b44-aec60fca8d1a"}}]
03:23:41 INFO - 1499423021972 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"943c8309-af9e-4278-9b44-aec60fca8d1a"}]
03:23:42 INFO - 1499423022220 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:42 INFO - 1499423022221 Marionette TRACE 376 <- [1,4,null,{}]
03:23:42 INFO - 1499423022223 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}]
03:23:42 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499423022226 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1499423022227 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1499423022228 Marionette TRACE 376 <- [1,6,null,{}]
03:23:42 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 334ms
03:23:42 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:23:42 INFO - 1499423022230 Marionette DEBUG Closed connection 376
03:23:42 INFO - 1499423022231 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49793
03:23:42 INFO - 1499423022232 Marionette DEBUG Closed connection 377
03:23:42 INFO - 1499423022233 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49794
03:23:42 INFO - 1499423022234 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1499423022234 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022237 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022249 Marionette TRACE 378 <- [1,1,null,{"sessionId":"8a39ee23-7928-4e02-95e0-92f0c344b4eb","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022250 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:23:42 INFO - 1499423022251 Marionette TRACE 378 <- [1,2,null,{}]
03:23:42 INFO - 1499423022252 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:42 INFO - 1499423022252 Marionette TRACE 378 <- [1,3,null,{}]
03:23:42 INFO - 1499423022253 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:42 INFO - 1499423022254 Marionette TRACE 378 <- [1,4,null,{}]
03:23:42 INFO - 1499423022255 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}]
03:23:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499423022258 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1499423022259 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1499423022261 Marionette TRACE 378 <- [1,6,null,{}]
03:23:42 INFO - 1499423022263 Marionette DEBUG Closed connection 378
03:23:42 INFO - 1499423022264 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49795
03:23:42 INFO - 1499423022265 Marionette DEBUG Closed connection 379
03:23:42 INFO - 1499423022266 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49796
03:23:42 INFO - 1499423022267 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1499423022268 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022271 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022275 Marionette TRACE 380 <- [1,1,null,{"sessionId":"eff099e0-ca3e-4c67-b3fb-8699e713c246","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022276 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:23:42 INFO - 1499423022277 Marionette TRACE 380 <- [1,2,null,{}]
03:23:42 INFO - 1499423022279 Marionette DEBUG Closed connection 380
03:23:42 INFO - 1499423022281 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49797
03:23:42 INFO - 1499423022282 Marionette DEBUG Closed connection 381
03:23:42 INFO - 1499423022283 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49798
03:23:42 INFO - 1499423022284 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:42 INFO - 1499423022285 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022288 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022291 Marionette TRACE 382 <- [1,1,null,{"sessionId":"bf361d64-ef55-4599-94a2-decbf276dca7","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022293 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"}]
03:23:42 INFO - 1499423022295 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"
03:23:42 INFO - 1499423022297 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"
03:23:42 INFO - 1499423022297 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"
03:23:42 INFO - 1499423022304 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"
03:23:42 INFO - 1499423022310 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"
03:23:42 INFO - 1499423022312 Marionette TRACE 382 <- [1,2,null,{}]
03:23:42 INFO - 1499423022321 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:23:42 INFO - 1499423022324 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f71186b-5bef-4a18-ba02-4b3ac1e8664d","ELEMENT":"2f71186b-5bef-4a18-ba02-4b3ac1e8664d"}}]
03:23:42 INFO - 1499423022325 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"2f71186b-5bef-4a18-ba02-4b3ac1e8664d"}]
03:23:42 INFO - 1499423022571 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:42 INFO - 1499423022572 Marionette TRACE 382 <- [1,4,null,{}]
03:23:42 INFO - 1499423022574 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}]
03:23:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499423022576 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1499423022577 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1499423022579 Marionette TRACE 382 <- [1,6,null,{}]
03:23:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 350ms
03:23:42 INFO - TEST-START | test_click.py TestClick.test_input_file
03:23:42 INFO - 1499423022581 Marionette DEBUG Closed connection 382
03:23:42 INFO - 1499423022582 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49799
03:23:42 INFO - 1499423022582 Marionette DEBUG Closed connection 383
03:23:42 INFO - 1499423022583 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49800
03:23:42 INFO - 1499423022584 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1499423022585 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022587 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022590 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ae9fe199-3226-41b7-9497-d7547b12b088","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022592 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:23:42 INFO - 1499423022592 Marionette TRACE 384 <- [1,2,null,{}]
03:23:42 INFO - 1499423022593 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:42 INFO - 1499423022594 Marionette TRACE 384 <- [1,3,null,{}]
03:23:42 INFO - 1499423022594 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:42 INFO - 1499423022595 Marionette TRACE 384 <- [1,4,null,{}]
03:23:42 INFO - 1499423022596 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}]
03:23:42 INFO - TEST-START: test_click.py TestClick.test_input_file1499423022599 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1499423022600 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1499423022601 Marionette TRACE 384 <- [1,6,null,{}]
03:23:42 INFO - 1499423022603 Marionette DEBUG Closed connection 384
03:23:42 INFO - 1499423022604 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49801
03:23:42 INFO - 1499423022604 Marionette DEBUG Closed connection 385
03:23:42 INFO - 1499423022605 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49802
03:23:42 INFO - 1499423022606 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1499423022607 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022610 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022613 Marionette TRACE 386 <- [1,1,null,{"sessionId":"7926dd41-9db2-49f5-8c45-08589a622131","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022615 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:23:42 INFO - 1499423022616 Marionette TRACE 386 <- [1,2,null,{}]
03:23:42 INFO - 1499423022618 Marionette DEBUG Closed connection 386
03:23:42 INFO - 1499423022618 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49803
03:23:42 INFO - 1499423022619 Marionette DEBUG Closed connection 387
03:23:42 INFO - 1499423022620 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49804
03:23:42 INFO - 1499423022621 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:42 INFO - 1499423022622 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022624 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022627 Marionette TRACE 388 <- [1,1,null,{"sessionId":"8e270bd1-9e0e-4f30-8575-1f7bdaf2fac6","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022629 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:23:42 INFO - 1499423022630 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"
03:23:42 INFO - 1499423022632 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"
03:23:42 INFO - 1499423022633 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"
03:23:42 INFO - 1499423022640 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1499423022644 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1499423022646 Marionette TRACE 388 <- [1,2,null,{}]
03:23:42 INFO - 1499423022653 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:23:42 INFO - 1499423022656 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4c40d6d9-bace-41df-89b4-f84a3efcac4e","ELEMENT":"4c40d6d9-bace-41df-89b4-f84a3efcac4e"}}]
03:23:42 INFO - 1499423022657 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4c40d6d9-bace-41df-89b4-f84a3efcac4e"}]
03:23:42 INFO - 1499423022694 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
03:23:42 INFO - 1499423022696 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}]
03:23:42 INFO - TEST-END: test_click.py TestClick.test_input_file1499423022699 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1499423022700 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1499423022701 Marionette TRACE 388 <- [1,6,null,{}]
03:23:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 122ms
03:23:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:23:42 INFO - 1499423022702 Marionette DEBUG Closed connection 388
03:23:42 INFO - 1499423022704 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49805
03:23:42 INFO - 1499423022704 Marionette DEBUG Closed connection 389
03:23:42 INFO - 1499423022705 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49806
03:23:42 INFO - 1499423022706 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1499423022707 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022710 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022713 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c329b4ce-3fc4-46e9-a980-f328653de0ca","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022714 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:23:42 INFO - 1499423022714 Marionette TRACE 390 <- [1,2,null,{}]
03:23:42 INFO - 1499423022715 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:42 INFO - 1499423022716 Marionette TRACE 390 <- [1,3,null,{}]
03:23:42 INFO - 1499423022717 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:42 INFO - 1499423022717 Marionette TRACE 390 <- [1,4,null,{}]
03:23:42 INFO - 1499423022719 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}]
03:23:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499423022721 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:23:42 INFO - 1499423022722 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:23:42 INFO - 1499423022724 Marionette TRACE 390 <- [1,6,null,{}]
03:23:42 INFO - 1499423022725 Marionette DEBUG Closed connection 390
03:23:42 INFO - 1499423022726 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49807
03:23:42 INFO - 1499423022727 Marionette DEBUG Closed connection 391
03:23:42 INFO - 1499423022727 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49808
03:23:42 INFO - 1499423022728 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:42 INFO - 1499423022729 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022732 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022735 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0995a54f-46f4-4bf4-88a3-8b7cd9874fd5","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022736 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:23:42 INFO - 1499423022738 Marionette TRACE 392 <- [1,2,null,{}]
03:23:42 INFO - 1499423022740 Marionette DEBUG Closed connection 392
03:23:42 INFO - 1499423022740 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49809
03:23:42 INFO - 1499423022741 Marionette DEBUG Closed connection 393
03:23:42 INFO - 1499423022742 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49810
03:23:42 INFO - 1499423022743 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:42 INFO - 1499423022744 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:42 INFO - 1499423022790 Marionette DEBUG Register listener.js for window 4294967297
03:23:42 INFO - 1499423022794 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2e180623-8159-42fa-ad58-1900c2e4d5e5","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:42 INFO - 1499423022795 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"}]
03:23:42 INFO - 1499423022840 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1499423022842 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1499423022842 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:23:42 INFO - 1499423022851 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"
03:23:42 INFO - 1499423022852 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"
03:23:42 INFO - 1499423022856 Marionette TRACE 394 <- [1,2,null,{}]
03:23:42 INFO - 1499423022859 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:23:42 INFO - 1499423022862 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2d57aa10-04a3-4f25-874a-5585b1d430e4","ELEMENT":"2d57aa10-04a3-4f25-874a-5585b1d430e4"}}]
03:23:42 INFO - 1499423022869 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:23:42 INFO - 1499423022871 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bafbce47-e566-4081-86e1-5deaa0adfa19","ELEMENT":"bafbce47-e566-4081-86e1-5deaa0adfa19"}}]
03:23:42 INFO - 1499423022872 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2d57aa10-04a3-4f25-874a-5585b1d430e4"}]
03:23:43 INFO - 1499423023113 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:23:43 INFO - 1499423023114 Marionette TRACE 394 <- [1,5,null,{}]
03:23:43 INFO - 1499423023115 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"bafbce47-e566-4081-86e1-5deaa0adfa19"}]
03:23:43 INFO - 1499423023131 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
03:23:43 INFO - 1499423023133 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:23:43 INFO - 1499423023136 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:23:43 INFO - 1499423023137 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}]
03:23:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499423023140 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:23:43 INFO - 1499423023141 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:23:43 INFO - 1499423023142 Marionette TRACE 394 <- [1,9,null,{}]
03:23:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 440ms
03:23:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:23:43 INFO - 1499423023144 Marionette DEBUG Closed connection 394
03:23:43 INFO - 1499423023145 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49811
03:23:43 INFO - 1499423023145 Marionette DEBUG Closed connection 395
03:23:43 INFO - 1499423023146 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49812
03:23:43 INFO - 1499423023147 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:43 INFO - 1499423023148 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:43 INFO - 1499423023150 Marionette DEBUG Register listener.js for window 4294967297
03:23:43 INFO - 1499423023154 Marionette TRACE 396 <- [1,1,null,{"sessionId":"c69c3207-b442-4690-9377-6b8c8e883a72","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:43 INFO - 1499423023155 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:23:43 INFO - 1499423023155 Marionette TRACE 396 <- [1,2,null,{}]
03:23:43 INFO - 1499423023156 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:23:43 INFO - 1499423023157 Marionette TRACE 396 <- [1,3,null,{}]
03:23:43 INFO - 1499423023158 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:23:43 INFO - 1499423023158 Marionette TRACE 396 <- [1,4,null,{}]
03:23:43 INFO - 1499423023160 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}]
03:23:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499423023163 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:23:43 INFO - 1499423023164 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:23:43 INFO - 1499423023165 Marionette TRACE 396 <- [1,6,null,{}]
03:23:43 INFO - 1499423023167 Marionette DEBUG Closed connection 396
03:23:43 INFO - 1499423023167 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49813
03:23:43 INFO - 1499423023168 Marionette DEBUG Closed connection 397
03:23:43 INFO - 1499423023169 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49814
03:23:43 INFO - 1499423023170 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:23:43 INFO - 1499423023170 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:43 INFO - 1499423023173 Marionette DEBUG Register listener.js for window 4294967297
03:23:43 INFO - 1499423023177 Marionette TRACE 398 <- [1,1,null,{"sessionId":"11935f09-4f01-4374-b1a4-bb6829f0b7fe","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:43 INFO - 1499423023178 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:23:43 INFO - 1499423023179 Marionette TRACE 398 <- [1,2,null,{}]
03:23:43 INFO - 1499423023181 Marionette DEBUG Closed connection 398
03:23:43 INFO - 1499423023181 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49815
03:23:43 INFO - 1499423023182 Marionette DEBUG Closed connection 399
03:23:43 INFO - 1499423023183 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49816
03:23:43 INFO - 1499423023184 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:23:43 INFO - 1499423023185 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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}
03:23:43 INFO - 1499423023187 Marionette DEBUG Register listener.js for window 4294967297
03:23:43 INFO - 1499423023191 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d1a07c0f-38be-46f2-9cfd-4cc2811c0639","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":3064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-164\\AppData\\Local\\Temp\\tmpltasnv.mozrunner","moz:accessibilityChecks":false}}]
03:23:43 INFO - 1499423023192 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"}]
03:23:43 INFO - 1499423023193 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"
03:23:43 INFO - 1499423023195 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"
03:23:43 INFO - 1499423023196 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"
03:23:43 INFO - 1499423023203 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"
03:23:43 INFO - 1499423023206 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"
03:23:43 INFO - 1499423023209 Marionette TRACE 400 <- [1,2,null,{}]
03:23:43 INFO - 1499423023212 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:23:43 INFO - 1499423023218 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dfa3aa36-d026-4eb8-8a4e-4dfc3dcb3976","ELEMENT":"dfa3aa36-d026-4eb8-8a4e-4dfc3dcb3976"}}]
03:23:43 INFO - 1499423023219 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"dfa3aa36-d026-4eb8-8a4e-4dfc3dcb3976"}]
03:23:43 INFO - 1499423023246 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:23:43 INFO - 1499423023247 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"dfa3aa36-d026-4eb8-8a4e-4dfc3dcb3976"}]
03:23:43 INFO - 1499423023264 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element