could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
17:33:30 INFO - 1500597210983 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
17:33:30 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500597210985 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:33:30 INFO - 1500597210986 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:33:30 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 153ms
17:33:30 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:33:30 INFO - 1500597210988 Marionette TRACE 352 <- [1,6,null,{}]
17:33:30 INFO - 1500597210991 Marionette DEBUG Closed connection 352
17:33:30 INFO - 1500597210991 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49754
17:33:30 INFO - 1500597210992 Marionette DEBUG Closed connection 353
17:33:30 INFO - 1500597210993 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49755
17:33:30 INFO - 1500597210994 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:30 INFO - 1500597210994 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:30 INFO - 1500597210997 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211001 Marionette TRACE 354 <- [1,1,null,{"sessionId":"d7a307dc-12be-4d16-8369-c1eadb4db851","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211004 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:33:31 INFO - 1500597211004 Marionette TRACE 354 <- [1,2,null,{}]
17:33:31 INFO - 1500597211005 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:31 INFO - 1500597211006 Marionette TRACE 354 <- [1,3,null,{}]
17:33:31 INFO - 1500597211007 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:31 INFO - 1500597211007 Marionette TRACE 354 <- [1,4,null,{}]
17:33:31 INFO - 1500597211009 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
17:33:31 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500597211011 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:33:31 INFO - 1500597211012 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:33:31 INFO - 1500597211014 Marionette TRACE 354 <- [1,6,null,{}]
17:33:31 INFO - 1500597211016 Marionette DEBUG Closed connection 354
17:33:31 INFO - 1500597211017 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49756
17:33:31 INFO - 1500597211018 Marionette DEBUG Closed connection 355
17:33:31 INFO - 1500597211018 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49757
17:33:31 INFO - 1500597211019 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:31 INFO - 1500597211020 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211022 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211026 Marionette TRACE 356 <- [1,1,null,{"sessionId":"1620bf83-1f30-4df0-aa3b-138db27b0020","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211028 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:33:31 INFO - 1500597211029 Marionette TRACE 356 <- [1,2,null,{}]
17:33:31 INFO - 1500597211032 Marionette DEBUG Closed connection 356
17:33:31 INFO - 1500597211033 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49758
17:33:31 INFO - 1500597211034 Marionette DEBUG Closed connection 357
17:33:31 INFO - 1500597211035 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49759
17:33:31 INFO - 1500597211036 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:31 INFO - 1500597211037 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211040 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211043 Marionette TRACE 358 <- [1,1,null,{"sessionId":"2876aa67-68ed-42c3-8120-8a8308bab551","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211045 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49326/clicks.html"}]
17:33:31 INFO - 1500597211047 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/hidden.html"
17:33:31 INFO - 1500597211056 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/hidden.html"
17:33:31 INFO - 1500597211056 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/hidden.html"
17:33:31 INFO - 1500597211078 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/clicks.html"
17:33:31 INFO - 1500597211079 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/clicks.html"
17:33:31 INFO - 1500597211083 Marionette TRACE 358 <- [1,2,null,{}]
17:33:31 INFO - 1500597211091 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:33:31 INFO - 1500597211093 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05b97145-ed25-4d90-a147-967d7b74a7b7","ELEMENT":"05b97145-ed25-4d90-a147-967d7b74a7b7"}}]
17:33:31 INFO - 1500597211094 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"05b97145-ed25-4d90-a147-967d7b74a7b7"}]
17:33:31 INFO - 1500597211146 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/clicks.html"
17:33:31 INFO - 1500597211156 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/clicks.html"
17:33:31 INFO - 1500597211156 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/clicks.html"
17:33:31 INFO - 1500597211178 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/test.html"
17:33:31 INFO - 1500597211179 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/test.html"
17:33:31 INFO - 1500597211182 Marionette TRACE 358 <- [1,4,null,{}]
17:33:31 INFO - 1500597211190 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:33:31 INFO - 1500597211192 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3e16dde0-4bc7-459b-9040-014762d63794","ELEMENT":"3e16dde0-4bc7-459b-9040-014762d63794"}}]
17:33:31 INFO - 1500597211193 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:33:31 INFO - 1500597211194 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:33:31 INFO - 1500597211195 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
17:33:31 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500597211198 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:33:31 INFO - 1500597211198 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:33:31 INFO - 1500597211200 Marionette TRACE 358 <- [1,8,null,{}]
17:33:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 212ms
17:33:31 INFO - TEST-START | test_click.py TestClick.test_container_element
17:33:31 INFO - 1500597211203 Marionette DEBUG Closed connection 358
17:33:31 INFO - 1500597211204 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49762
17:33:31 INFO - 1500597211204 Marionette DEBUG Closed connection 359
17:33:31 INFO - 1500597211205 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49763
17:33:31 INFO - 1500597211206 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:31 INFO - 1500597211207 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211209 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211213 Marionette TRACE 360 <- [1,1,null,{"sessionId":"701f4d12-e540-4be6-bd0e-4e15a1be64db","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211215 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:33:31 INFO - 1500597211216 Marionette TRACE 360 <- [1,2,null,{}]
17:33:31 INFO - 1500597211217 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:31 INFO - 1500597211217 Marionette TRACE 360 <- [1,3,null,{}]
17:33:31 INFO - 1500597211218 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:31 INFO - 1500597211218 Marionette TRACE 360 <- [1,4,null,{}]
17:33:31 INFO - 1500597211220 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
17:33:31 INFO - TEST-START: test_click.py TestClick.test_container_element1500597211223 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:33:31 INFO - 1500597211224 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:33:31 INFO - 1500597211225 Marionette TRACE 360 <- [1,6,null,{}]
17:33:31 INFO - 1500597211227 Marionette DEBUG Closed connection 360
17:33:31 INFO - 1500597211228 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49764
17:33:31 INFO - 1500597211229 Marionette DEBUG Closed connection 361
17:33:31 INFO - 1500597211229 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49765
17:33:31 INFO - 1500597211230 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:31 INFO - 1500597211231 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211234 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211237 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1d9451ed-187f-41a7-bd46-ddbafbace498","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211239 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:33:31 INFO - 1500597211241 Marionette TRACE 362 <- [1,2,null,{}]
17:33:31 INFO - 1500597211243 Marionette DEBUG Closed connection 362
17:33:31 INFO - 1500597211243 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49766
17:33:31 INFO - 1500597211244 Marionette DEBUG Closed connection 363
17:33:31 INFO - 1500597211245 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49767
17:33:31 INFO - 1500597211246 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:31 INFO - 1500597211246 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211249 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211252 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d7004204-bb1b-4aa7-8fc1-02140e7a2edb","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211254 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:33:31 INFO - 1500597211256 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/test.html"
17:33:31 INFO - 1500597211257 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/test.html"
17:33:31 INFO - 1500597211258 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/test.html"
17:33:31 INFO - 1500597211280 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211284 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211292 Marionette TRACE 364 <- [1,2,null,{}]
17:33:31 INFO - 1500597211293 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:33:31 INFO - 1500597211296 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6d506d46-ef32-4362-a457-214b7fb1b904","ELEMENT":"6d506d46-ef32-4362-a457-214b7fb1b904"}}]
17:33:31 INFO - 1500597211297 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6d506d46-ef32-4362-a457-214b7fb1b904"}]
17:33:31 INFO - 1500597211541 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:31 INFO - 1500597211542 Marionette TRACE 364 <- [1,4,null,{}]
17:33:31 INFO - 1500597211544 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6d506d46-ef32-4362-a457-214b7fb1b904","name":"selected"}]
17:33:31 INFO - 1500597211548 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:33:31 INFO - 1500597211550 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
17:33:31 INFO - TEST-END: test_click.py TestClick.test_container_element1500597211552 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:33:31 INFO - 1500597211553 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:33:31 INFO - 1500597211555 Marionette TRACE 364 <- [1,7,null,{}]
17:33:31 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 354ms
17:33:31 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:33:31 INFO - 1500597211557 Marionette DEBUG Closed connection 364
17:33:31 INFO - 1500597211558 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49768
17:33:31 INFO - 1500597211558 Marionette DEBUG Closed connection 365
17:33:31 INFO - 1500597211559 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49769
17:33:31 INFO - 1500597211560 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:31 INFO - 1500597211561 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211564 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211573 Marionette TRACE 366 <- [1,1,null,{"sessionId":"da4800e2-1658-4168-8a79-edd5137d7efc","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211577 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:33:31 INFO - 1500597211578 Marionette TRACE 366 <- [1,2,null,{}]
17:33:31 INFO - 1500597211579 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:31 INFO - 1500597211579 Marionette TRACE 366 <- [1,3,null,{}]
17:33:31 INFO - 1500597211580 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:31 INFO - 1500597211581 Marionette TRACE 366 <- [1,4,null,{}]
17:33:31 INFO - 1500597211584 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
17:33:31 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500597211587 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:33:31 INFO - 1500597211588 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:33:31 INFO - 1500597211590 Marionette TRACE 366 <- [1,6,null,{}]
17:33:31 INFO - 1500597211592 Marionette DEBUG Closed connection 366
17:33:31 INFO - 1500597211592 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49770
17:33:31 INFO - 1500597211593 Marionette DEBUG Closed connection 367
17:33:31 INFO - 1500597211594 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49771
17:33:31 INFO - 1500597211595 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:31 INFO - 1500597211595 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211598 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211602 Marionette TRACE 368 <- [1,1,null,{"sessionId":"0e9c101a-1d2b-421a-a083-fc32a2d477ff","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211604 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:33:31 INFO - 1500597211605 Marionette TRACE 368 <- [1,2,null,{}]
17:33:31 INFO - 1500597211607 Marionette DEBUG Closed connection 368
17:33:31 INFO - 1500597211608 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49772
17:33:31 INFO - 1500597211609 Marionette DEBUG Closed connection 369
17:33:31 INFO - 1500597211609 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49773
17:33:31 INFO - 1500597211610 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:31 INFO - 1500597211611 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211614 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211617 Marionette TRACE 370 <- [1,1,null,{"sessionId":"0214a93a-24ca-48d4-9930-515b582ab6d7","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211619 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:33:31 INFO - 1500597211620 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211622 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211622 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211642 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211644 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211647 Marionette TRACE 370 <- [1,2,null,{}]
17:33:31 INFO - 1500597211648 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:33:31 INFO - 1500597211658 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f15fbb5b-aae6-4f0a-92da-afd7a711b148","ELEMENT":"f15fbb5b-aae6-4f0a-92da-afd7a711b148"}}]
17:33:31 INFO - 1500597211659 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"f15fbb5b-aae6-4f0a-92da-afd7a711b148"}]
17:33:31 INFO - 1500597211904 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:31 INFO - 1500597211905 Marionette TRACE 370 <- [1,4,null,{}]
17:33:31 INFO - 1500597211906 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"f15fbb5b-aae6-4f0a-92da-afd7a711b148","name":"selected"}]
17:33:31 INFO - 1500597211910 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:33:31 INFO - 1500597211912 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
17:33:31 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500597211914 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:33:31 INFO - 1500597211916 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:33:31 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 362ms
17:33:31 INFO - 1500597211917 Marionette TRACE 370 <- [1,7,null,{}]
17:33:31 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:33:31 INFO - 1500597211920 Marionette DEBUG Closed connection 370
17:33:31 INFO - 1500597211920 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49774
17:33:31 INFO - 1500597211921 Marionette DEBUG Closed connection 371
17:33:31 INFO - 1500597211922 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49775
17:33:31 INFO - 1500597211924 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:31 INFO - 1500597211924 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211927 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211930 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3d8dfdcc-fbf1-40a6-bfa7-0ea6c07e5601","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211933 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:33:31 INFO - 1500597211933 Marionette TRACE 372 <- [1,2,null,{}]
17:33:31 INFO - 1500597211934 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:31 INFO - 1500597211935 Marionette TRACE 372 <- [1,3,null,{}]
17:33:31 INFO - 1500597211935 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:31 INFO - 1500597211936 Marionette TRACE 372 <- [1,4,null,{}]
17:33:31 INFO - 1500597211937 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
17:33:31 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500597211940 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:33:31 INFO - 1500597211941 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:33:31 INFO - 1500597211942 Marionette TRACE 372 <- [1,6,null,{}]
17:33:31 INFO - 1500597211944 Marionette DEBUG Closed connection 372
17:33:31 INFO - 1500597211945 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49776
17:33:31 INFO - 1500597211946 Marionette DEBUG Closed connection 373
17:33:31 INFO - 1500597211946 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49777
17:33:31 INFO - 1500597211947 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:31 INFO - 1500597211948 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211950 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211953 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d1288d8f-33b6-4ab0-9e66-eef66bb7bced","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211955 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:33:31 INFO - 1500597211957 Marionette TRACE 374 <- [1,2,null,{}]
17:33:31 INFO - 1500597211959 Marionette DEBUG Closed connection 374
17:33:31 INFO - 1500597211959 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49778
17:33:31 INFO - 1500597211960 Marionette DEBUG Closed connection 375
17:33:31 INFO - 1500597211961 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49779
17:33:31 INFO - 1500597211962 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:31 INFO - 1500597211962 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:31 INFO - 1500597211965 Marionette DEBUG Register listener.js for window 15
17:33:31 INFO - 1500597211969 Marionette TRACE 376 <- [1,1,null,{"sessionId":"24dc6155-9368-470e-b26c-81db792dca59","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:31 INFO - 1500597211972 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
17:33:31 INFO - 1500597211973 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211975 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:31 INFO - 1500597211975 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:32 INFO - 1500597211995 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:33:32 INFO - 1500597211998 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:33:32 INFO - 1500597212001 Marionette TRACE 376 <- [1,2,null,{}]
17:33:32 INFO - 1500597212007 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:33:32 INFO - 1500597212010 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f7411a25-a54d-4493-9894-fddf04bad4bf","ELEMENT":"f7411a25-a54d-4493-9894-fddf04bad4bf"}}]
17:33:32 INFO - 1500597212011 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f7411a25-a54d-4493-9894-fddf04bad4bf"}]
17:33:32 INFO - 1500597212259 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:32 INFO - 1500597212260 Marionette TRACE 376 <- [1,4,null,{}]
17:33:32 INFO - 1500597212262 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
17:33:32 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500597212265 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:33:32 INFO - 1500597212266 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:33:32 INFO - 1500597212267 Marionette TRACE 376 <- [1,6,null,{}]
17:33:32 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 349ms
17:33:32 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:33:32 INFO - 1500597212270 Marionette DEBUG Closed connection 376
17:33:32 INFO - 1500597212270 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49780
17:33:32 INFO - 1500597212271 Marionette DEBUG Closed connection 377
17:33:32 INFO - 1500597212272 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49781
17:33:32 INFO - 1500597212273 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:32 INFO - 1500597212273 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:32 INFO - 1500597212276 Marionette DEBUG Register listener.js for window 15
17:33:32 INFO - 1500597212279 Marionette TRACE 378 <- [1,1,null,{"sessionId":"22d7c58f-b259-41ef-bb59-ea3b839993c9","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:32 INFO - 1500597212281 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:33:32 INFO - 1500597212282 Marionette TRACE 378 <- [1,2,null,{}]
17:33:32 INFO - 1500597212283 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:32 INFO - 1500597212283 Marionette TRACE 378 <- [1,3,null,{}]
17:33:32 INFO - 1500597212284 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:32 INFO - 1500597212285 Marionette TRACE 378 <- [1,4,null,{}]
17:33:32 INFO - 1500597212286 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
17:33:32 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500597212289 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:33:32 INFO - 1500597212290 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:33:32 INFO - 1500597212291 Marionette TRACE 378 <- [1,6,null,{}]
17:33:32 INFO - 1500597212294 Marionette DEBUG Closed connection 378
17:33:32 INFO - 1500597212294 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49782
17:33:32 INFO - 1500597212295 Marionette DEBUG Closed connection 379
17:33:32 INFO - 1500597212296 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49783
17:33:32 INFO - 1500597212297 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:32 INFO - 1500597212297 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:32 INFO - 1500597212300 Marionette DEBUG Register listener.js for window 15
17:33:32 INFO - 1500597212303 Marionette TRACE 380 <- [1,1,null,{"sessionId":"2015db7e-ef83-4392-8ff3-76e8b5400608","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:32 INFO - 1500597212305 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:33:32 INFO - 1500597212306 Marionette TRACE 380 <- [1,2,null,{}]
17:33:32 INFO - 1500597212308 Marionette DEBUG Closed connection 380
17:33:32 INFO - 1500597212309 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49784
17:33:32 INFO - 1500597212310 Marionette DEBUG Closed connection 381
17:33:32 INFO - 1500597212311 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49785
17:33:32 INFO - 1500597212312 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:32 INFO - 1500597212312 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:32 INFO - 1500597212315 Marionette DEBUG Register listener.js for window 15
17:33:32 INFO - 1500597212318 Marionette TRACE 382 <- [1,1,null,{"sessionId":"4714c844-5716-4150-b9c1-fba2f6d55761","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:32 INFO - 1500597212320 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:33:32 INFO - 1500597212322 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:33:32 INFO - 1500597212324 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:33:32 INFO - 1500597212324 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:33:32 INFO - 1500597212344 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:32 INFO - 1500597212346 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:32 INFO - 1500597212350 Marionette TRACE 382 <- [1,2,null,{}]
17:33:32 INFO - 1500597212351 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:33:32 INFO - 1500597212360 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9fe4bd2f-13fc-48f5-81b5-3b96e2502116","ELEMENT":"9fe4bd2f-13fc-48f5-81b5-3b96e2502116"}}]
17:33:32 INFO - 1500597212361 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"9fe4bd2f-13fc-48f5-81b5-3b96e2502116"}]
17:33:32 INFO - 1500597212606 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:32 INFO - 1500597212607 Marionette TRACE 382 <- [1,4,null,{}]
17:33:32 INFO - 1500597212608 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
17:33:32 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500597212611 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:33:32 INFO - 1500597212612 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:33:32 INFO - 1500597212613 Marionette TRACE 382 <- [1,6,null,{}]
17:33:32 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 346ms
17:33:32 INFO - TEST-START | test_click.py TestClick.test_input_file
17:33:32 INFO - 1500597212616 Marionette DEBUG Closed connection 382
17:33:32 INFO - 1500597212616 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49786
17:33:32 INFO - 1500597212617 Marionette DEBUG Closed connection 383
17:33:32 INFO - 1500597212618 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49787
17:33:32 INFO - 1500597212619 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:32 INFO - 1500597212619 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:32 INFO - 1500597212622 Marionette DEBUG Register listener.js for window 15
17:33:32 INFO - 1500597212625 Marionette TRACE 384 <- [1,1,null,{"sessionId":"94675cc4-4fa1-40a2-be89-35deb1b95175","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:32 INFO - 1500597212627 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:33:32 INFO - 1500597212627 Marionette TRACE 384 <- [1,2,null,{}]
17:33:32 INFO - 1500597212628 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:32 INFO - 1500597212629 Marionette TRACE 384 <- [1,3,null,{}]
17:33:32 INFO - 1500597212630 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:32 INFO - 1500597212630 Marionette TRACE 384 <- [1,4,null,{}]
17:33:32 INFO - 1500597212632 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
17:33:32 INFO - TEST-START: test_click.py TestClick.test_input_file1500597212635 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:33:32 INFO - 1500597212636 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:33:32 INFO - 1500597212637 Marionette TRACE 384 <- [1,6,null,{}]
17:33:32 INFO - 1500597212639 Marionette DEBUG Closed connection 384
17:33:32 INFO - 1500597212640 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49788
17:33:32 INFO - 1500597212641 Marionette DEBUG Closed connection 385
17:33:32 INFO - 1500597212641 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49789
17:33:32 INFO - 1500597212643 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:32 INFO - 1500597212643 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:32 INFO - 1500597212646 Marionette DEBUG Register listener.js for window 15
17:33:32 INFO - 1500597212656 Marionette TRACE 386 <- [1,1,null,{"sessionId":"86dcea11-2eba-4bd5-8f24-b67cc7d3b114","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:32 INFO - 1500597212776 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:33:32 INFO - 1500597212780 Marionette TRACE 386 <- [1,2,null,{}]
17:33:32 INFO - 1500597212788 Marionette DEBUG Closed connection 386
17:33:32 INFO - 1500597212789 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49790
17:33:32 INFO - 1500597212793 Marionette DEBUG Closed connection 387
17:33:32 INFO - 1500597212794 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49791
17:33:32 INFO - 1500597212805 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:32 INFO - 1500597212808 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:32 INFO - 1500597212811 Marionette DEBUG Register listener.js for window 15
17:33:32 INFO - 1500597212818 Marionette TRACE 388 <- [1,1,null,{"sessionId":"353e7668-09c4-46ee-8e5a-a02571ae5a08","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:32 INFO - 1500597212823 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:33:32 INFO - 1500597212826 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:32 INFO - 1500597212829 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:32 INFO - 1500597212831 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:33:32 INFO - 1500597212860 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:32 INFO - 1500597212862 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:32 INFO - 1500597212868 Marionette TRACE 388 <- [1,2,null,{}]
17:33:32 INFO - 1500597212876 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:33:32 INFO - 1500597212881 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2e38cc82-6935-41c4-861b-f1ef69d6c929","ELEMENT":"2e38cc82-6935-41c4-861b-f1ef69d6c929"}}]
17:33:32 INFO - 1500597212882 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2e38cc82-6935-41c4-861b-f1ef69d6c929"}]
17:33:32 INFO - 1500597212928 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
17:33:32 INFO - 1500597212930 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
17:33:32 INFO - TEST-END: test_click.py TestClick.test_input_file1500597212934 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:33:32 INFO - 1500597212935 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:33:32 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 323ms
17:33:32 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:33:32 INFO - 1500597212938 Marionette TRACE 388 <- [1,6,null,{}]
17:33:32 INFO - 1500597212941 Marionette DEBUG Closed connection 388
17:33:32 INFO - 1500597212942 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49792
17:33:32 INFO - 1500597212943 Marionette DEBUG Closed connection 389
17:33:32 INFO - 1500597212944 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49793
17:33:32 INFO - 1500597212946 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:32 INFO - 1500597212947 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:32 INFO - 1500597212950 Marionette DEBUG Register listener.js for window 15
17:33:32 INFO - 1500597212954 Marionette TRACE 390 <- [1,1,null,{"sessionId":"81b6e1e0-939f-496e-ae58-f7eebf62ba90","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:32 INFO - 1500597212959 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:33:32 INFO - 1500597212960 Marionette TRACE 390 <- [1,2,null,{}]
17:33:32 INFO - 1500597212961 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:32 INFO - 1500597212962 Marionette TRACE 390 <- [1,3,null,{}]
17:33:32 INFO - 1500597212965 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:32 INFO - 1500597212966 Marionette TRACE 390 <- [1,4,null,{}]
17:33:32 INFO - 1500597212968 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
17:33:32 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500597212972 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:33:32 INFO - 1500597212973 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:33:32 INFO - 1500597212976 Marionette TRACE 390 <- [1,6,null,{}]
17:33:32 INFO - 1500597212988 Marionette DEBUG Closed connection 390
17:33:32 INFO - 1500597212989 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49794
17:33:32 INFO - 1500597212990 Marionette DEBUG Closed connection 391
17:33:32 INFO - 1500597212990 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49795
17:33:32 INFO - 1500597212991 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:32 INFO - 1500597212992 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:32 INFO - 1500597212995 Marionette DEBUG Register listener.js for window 15
17:33:33 INFO - 1500597213000 Marionette TRACE 392 <- [1,1,null,{"sessionId":"b4417b41-da50-4558-985f-b73cbb2a31c0","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:33 INFO - 1500597213002 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:33:33 INFO - 1500597213004 Marionette TRACE 392 <- [1,2,null,{}]
17:33:33 INFO - 1500597213006 Marionette DEBUG Closed connection 392
17:33:33 INFO - 1500597213007 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49796
17:33:33 INFO - 1500597213008 Marionette DEBUG Closed connection 393
17:33:33 INFO - 1500597213009 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49797
17:33:33 INFO - 1500597213010 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:33 INFO - 1500597213011 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:33 INFO - 1500597213014 Marionette DEBUG Register listener.js for window 15
17:33:33 INFO - 1500597213019 Marionette TRACE 394 <- [1,1,null,{"sessionId":"1a7bd8a3-220e-407a-96ac-88debb5d513e","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:33 INFO - 1500597213022 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
17:33:33 INFO - 1500597213023 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:33 INFO - 1500597213026 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:33 INFO - 1500597213027 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:33 INFO - 1500597213064 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:33:33 INFO - 1500597213065 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:33:33 INFO - 1500597213071 Marionette TRACE 394 <- [1,2,null,{}]
17:33:33 INFO - 1500597213081 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:33:33 INFO - 1500597213084 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a2f0832-f843-4fa7-a974-14c9622c1999","ELEMENT":"7a2f0832-f843-4fa7-a974-14c9622c1999"}}]
17:33:33 INFO - 1500597213085 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:33:33 INFO - 1500597213089 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2a51ff9d-7e6a-4a66-93bc-5f328c1637cc","ELEMENT":"2a51ff9d-7e6a-4a66-93bc-5f328c1637cc"}}]
17:33:33 INFO - 1500597213100 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7a2f0832-f843-4fa7-a974-14c9622c1999"}]
17:33:33 INFO - 1500597213345 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:33 INFO - 1500597213346 Marionette TRACE 394 <- [1,5,null,{}]
17:33:33 INFO - 1500597213347 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"2a51ff9d-7e6a-4a66-93bc-5f328c1637cc"}]
17:33:33 INFO - 1500597213366 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
17:33:33 INFO - 1500597213368 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
17:33:33 INFO - 1500597213372 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:33:33 INFO - 1500597213374 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
17:33:33 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500597213377 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:33:33 INFO - 1500597213378 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:33:33 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 442ms
17:33:33 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:33:33 INFO - 1500597213379 Marionette TRACE 394 <- [1,9,null,{}]
17:33:33 INFO - 1500597213382 Marionette DEBUG Closed connection 394
17:33:33 INFO - 1500597213383 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49798
17:33:33 INFO - 1500597213384 Marionette DEBUG Closed connection 395
17:33:33 INFO - 1500597213384 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49799
17:33:33 INFO - 1500597213386 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:33 INFO - 1500597213386 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:33 INFO - 1500597213389 Marionette DEBUG Register listener.js for window 15
17:33:33 INFO - 1500597213396 Marionette TRACE 396 <- [1,1,null,{"sessionId":"72d2b606-97cb-48b0-a94f-4866d2c501f3","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:33 INFO - 1500597213398 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:33:33 INFO - 1500597213399 Marionette TRACE 396 <- [1,2,null,{}]
17:33:33 INFO - 1500597213400 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:33 INFO - 1500597213400 Marionette TRACE 396 <- [1,3,null,{}]
17:33:33 INFO - 1500597213401 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:33 INFO - 1500597213402 Marionette TRACE 396 <- [1,4,null,{}]
17:33:33 INFO - 1500597213403 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
17:33:33 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500597213407 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:33:33 INFO - 1500597213408 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:33:33 INFO - 1500597213409 Marionette TRACE 396 <- [1,6,null,{}]
17:33:33 INFO - 1500597213412 Marionette DEBUG Closed connection 396
17:33:33 INFO - 1500597213414 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49800
17:33:33 INFO - 1500597213415 Marionette DEBUG Closed connection 397
17:33:33 INFO - 1500597213416 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49801
17:33:33 INFO - 1500597213417 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:33 INFO - 1500597213418 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:33 INFO - 1500597213421 Marionette DEBUG Register listener.js for window 15
17:33:33 INFO - 1500597213425 Marionette TRACE 398 <- [1,1,null,{"sessionId":"afe854fa-a0f6-4737-a200-0b9d64a419b0","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:33 INFO - 1500597213427 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:33:33 INFO - 1500597213429 Marionette TRACE 398 <- [1,2,null,{}]
17:33:33 INFO - 1500597213431 Marionette DEBUG Closed connection 398
17:33:33 INFO - 1500597213431 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49802
17:33:33 INFO - 1500597213432 Marionette DEBUG Closed connection 399
17:33:33 INFO - 1500597213433 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49803
17:33:33 INFO - 1500597213434 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:33 INFO - 1500597213435 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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}
17:33:33 INFO - 1500597213438 Marionette DEBUG Register listener.js for window 15
17:33:33 INFO - 1500597213441 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d19570af-7119-4f9e-b1e6-f7f455a2ba83","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-211.001\\AppData\\Local\\Temp\\tmpfzwmec.mozrunner","moz:accessibilityChecks":false}}]
17:33:33 INFO - 1500597213443 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
17:33:33 INFO - 1500597213445 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:33:33 INFO - 1500597213448 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:33:33 INFO - 1500597213448 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:33:33 INFO - 1500597213473 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
17:33:33 INFO - 1500597213475 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
17:33:33 INFO - 1500597213479 Marionette TRACE 400 <- [1,2,null,{}]
17:33:33 INFO - 1500597213480 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:33:33 INFO - 1500597213483 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e58b31bb-de8d-471c-9769-76a81ee1eadf","ELEMENT":"e58b31bb-de8d-471c-9769-76a81ee1eadf"}}]
17:33:33 INFO - 1500597213484 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e58b31bb-de8d-471c-9769-76a81ee1eadf"}]
17:33:33 INFO - 1500597213517 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:33:33 INFO - 1500597213519 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e58b31bb-de8d-471c-9769-76a81ee1eadf"}]
17:33:33 INFO - 1500597213540 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element