could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:59:24 INFO - 1498852763999 Marionette TRACE 358 -> [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}]
12:59:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 257ms
12:59:24 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:59:24 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498852764009 Marionette TRACE 358 <- [1,5,null,{"value":null}]
12:59:24 INFO - 1498852764011 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
12:59:24 INFO - 1498852764012 Marionette TRACE 358 <- [1,6,null,{}]
12:59:24 INFO - 1498852764014 Marionette DEBUG Closed connection 358
12:59:24 INFO - 1498852764015 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50087
12:59:24 INFO - 1498852764016 Marionette DEBUG Closed connection 359
12:59:24 INFO - 1498852764017 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50088
12:59:24 INFO - 1498852764018 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:24 INFO - 1498852764018 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764021 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764026 Marionette TRACE 360 <- [1,1,null,{"sessionId":"63a93bf3-609b-40c2-9d80-2ed91794e49d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764028 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:59:24 INFO - 1498852764028 Marionette TRACE 360 <- [1,2,null,{}]
12:59:24 INFO - 1498852764029 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:59:24 INFO - 1498852764030 Marionette TRACE 360 <- [1,3,null,{}]
12:59:24 INFO - 1498852764031 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:59:24 INFO - 1498852764031 Marionette TRACE 360 <- [1,4,null,{}]
12:59:24 INFO - 1498852764033 Marionette TRACE 360 -> [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}]
12:59:24 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498852764037 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:59:24 INFO - 1498852764038 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:59:24 INFO - 1498852764039 Marionette TRACE 360 <- [1,6,null,{}]
12:59:24 INFO - 1498852764041 Marionette DEBUG Closed connection 360
12:59:24 INFO - 1498852764041 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50089
12:59:24 INFO - 1498852764042 Marionette DEBUG Closed connection 361
12:59:24 INFO - 1498852764043 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50090
12:59:24 INFO - 1498852764044 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:24 INFO - 1498852764045 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764048 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764052 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d703819e-57bf-40ed-9afc-1e4d50a11561","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764053 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:59:24 INFO - 1498852764054 Marionette TRACE 362 <- [1,2,null,{}]
12:59:24 INFO - 1498852764056 Marionette DEBUG Closed connection 362
12:59:24 INFO - 1498852764057 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50091
12:59:24 INFO - 1498852764058 Marionette DEBUG Closed connection 363
12:59:24 INFO - 1498852764058 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50092
12:59:24 INFO - 1498852764059 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:59:24 INFO - 1498852764060 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764063 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764067 Marionette TRACE 364 <- [1,1,null,{"sessionId":"96c2454c-679f-4966-8176-b2c7ef54e1ff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764068 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49686/clicks.html"}]
12:59:24 INFO - 1498852764082 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49686/hidden.html"
12:59:24 INFO - 1498852764091 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49686/hidden.html"
12:59:24 INFO - 1498852764092 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49686/hidden.html"
12:59:24 INFO - 1498852764105 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49686/clicks.html"
12:59:24 INFO - 1498852764107 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49686/clicks.html"
12:59:24 INFO - 1498852764111 Marionette TRACE 364 <- [1,2,null,{}]
12:59:24 INFO - 1498852764121 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:59:24 INFO - 1498852764130 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d187b9f4-703c-425c-b701-ffcaf0e61d5c","ELEMENT":"d187b9f4-703c-425c-b701-ffcaf0e61d5c"}}]
12:59:24 INFO - 1498852764131 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d187b9f4-703c-425c-b701-ffcaf0e61d5c"}]
12:59:24 INFO - 1498852764190 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49686/clicks.html"
12:59:24 INFO - 1498852764205 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49686/clicks.html"
12:59:24 INFO - 1498852764206 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49686/clicks.html"
12:59:24 INFO - 1498852764218 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49686/test.html"
12:59:24 INFO - 1498852764220 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49686/test.html"
12:59:24 INFO - 1498852764224 Marionette TRACE 364 <- [1,4,null,{}]
12:59:24 INFO - 1498852764229 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:59:24 INFO - 1498852764236 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"29d5f0b7-5823-4b8f-a41b-ab7d8bed19da","ELEMENT":"29d5f0b7-5823-4b8f-a41b-ab7d8bed19da"}}]
12:59:24 INFO - 1498852764237 Marionette TRACE 364 -> [0,6,"getTitle",{}]
12:59:24 INFO - 1498852764240 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
12:59:24 INFO - 1498852764242 Marionette TRACE 364 -> [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}]
12:59:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498852764246 Marionette TRACE 364 <- [1,7,null,{"value":null}]
12:59:24 INFO - 1498852764247 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
12:59:24 INFO - 1498852764249 Marionette TRACE 364 <- [1,8,null,{}]
12:59:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 237ms
12:59:24 INFO - TEST-START | test_click.py TestClick.test_container_element
12:59:24 INFO - 1498852764251 Marionette DEBUG Closed connection 364
12:59:24 INFO - 1498852764252 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50095
12:59:24 INFO - 1498852764253 Marionette DEBUG Closed connection 365
12:59:24 INFO - 1498852764254 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50096
12:59:24 INFO - 1498852764255 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:24 INFO - 1498852764255 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764258 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764262 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fe0c15f6-a124-4b84-b4d4-14a636ac495f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764263 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:59:24 INFO - 1498852764264 Marionette TRACE 366 <- [1,2,null,{}]
12:59:24 INFO - 1498852764265 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:59:24 INFO - 1498852764265 Marionette TRACE 366 <- [1,3,null,{}]
12:59:24 INFO - 1498852764266 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:59:24 INFO - 1498852764267 Marionette TRACE 366 <- [1,4,null,{}]
12:59:24 INFO - 1498852764268 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')","sandbox":"simpletest","line":316}]
12:59:24 INFO - TEST-START: test_click.py TestClick.test_container_element1498852764272 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:59:24 INFO - 1498852764273 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:59:24 INFO - 1498852764275 Marionette TRACE 366 <- [1,6,null,{}]
12:59:24 INFO - 1498852764276 Marionette DEBUG Closed connection 366
12:59:24 INFO - 1498852764277 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50097
12:59:24 INFO - 1498852764278 Marionette DEBUG Closed connection 367
12:59:24 INFO - 1498852764279 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50098
12:59:24 INFO - 1498852764280 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:24 INFO - 1498852764281 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764284 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764287 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c2e17fd4-e722-4566-ac81-f542b0595d5d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764288 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:59:24 INFO - 1498852764290 Marionette TRACE 368 <- [1,2,null,{}]
12:59:24 INFO - 1498852764291 Marionette DEBUG Closed connection 368
12:59:24 INFO - 1498852764292 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50099
12:59:24 INFO - 1498852764293 Marionette DEBUG Closed connection 369
12:59:24 INFO - 1498852764294 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50100
12:59:24 INFO - 1498852764295 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:59:24 INFO - 1498852764296 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764298 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764302 Marionette TRACE 370 <- [1,1,null,{"sessionId":"5feb0fde-85c2-40e9-bffa-4664f68d91e8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764303 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%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"}]
12:59:24 INFO - 1498852764305 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49686/test.html"
12:59:24 INFO - 1498852764308 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49686/test.html"
12:59:24 INFO - 1498852764308 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49686/test.html"
12:59:24 INFO - 1498852764320 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"
12:59:24 INFO - 1498852764321 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"
12:59:24 INFO - 1498852764332 Marionette TRACE 370 <- [1,2,null,{}]
12:59:24 INFO - 1498852764337 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:59:24 INFO - 1498852764352 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"35a7801f-7543-4e1f-ab93-e93ef65e5098","ELEMENT":"35a7801f-7543-4e1f-ab93-e93ef65e5098"}}]
12:59:24 INFO - 1498852764361 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"35a7801f-7543-4e1f-ab93-e93ef65e5098"}]
12:59:24 INFO - 1498852764608 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:59:24 INFO - 1498852764610 Marionette TRACE 370 <- [1,4,null,{}]
12:59:24 INFO - 1498852764611 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"35a7801f-7543-4e1f-ab93-e93ef65e5098","name":"selected"}]
12:59:24 INFO - 1498852764617 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:59:24 INFO - 1498852764620 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')","sandbox":"simpletest","line":328}]
12:59:24 INFO - TEST-END: test_click.py TestClick.test_container_element1498852764624 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:59:24 INFO - 1498852764626 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:59:24 INFO - 1498852764630 Marionette TRACE 370 <- [1,7,null,{}]
12:59:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 382ms
12:59:24 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:59:24 INFO - 1498852764634 Marionette DEBUG Closed connection 370
12:59:24 INFO - 1498852764635 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50101
12:59:24 INFO - 1498852764637 Marionette DEBUG Closed connection 371
12:59:24 INFO - 1498852764638 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50102
12:59:24 INFO - 1498852764640 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:24 INFO - 1498852764644 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764647 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764653 Marionette TRACE 372 <- [1,1,null,{"sessionId":"368d2307-c678-4079-a39d-573553163fcc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764654 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:59:24 INFO - 1498852764656 Marionette TRACE 372 <- [1,2,null,{}]
12:59:24 INFO - 1498852764657 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:59:24 INFO - 1498852764658 Marionette TRACE 372 <- [1,3,null,{}]
12:59:24 INFO - 1498852764659 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:59:24 INFO - 1498852764660 Marionette TRACE 372 <- [1,4,null,{}]
12:59:24 INFO - 1498852764662 Marionette TRACE 372 -> [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}]
12:59:24 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498852764667 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:59:24 INFO - 1498852764668 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:59:24 INFO - 1498852764670 Marionette TRACE 372 <- [1,6,null,{}]
12:59:24 INFO - 1498852764672 Marionette DEBUG Closed connection 372
12:59:24 INFO - 1498852764673 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50103
12:59:24 INFO - 1498852764674 Marionette DEBUG Closed connection 373
12:59:24 INFO - 1498852764676 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50104
12:59:24 INFO - 1498852764677 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:24 INFO - 1498852764678 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764681 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764686 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c055d30d-c573-4b7f-a963-fb66b572d70b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764689 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:59:24 INFO - 1498852764692 Marionette TRACE 374 <- [1,2,null,{}]
12:59:24 INFO - 1498852764695 Marionette DEBUG Closed connection 374
12:59:24 INFO - 1498852764696 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50105
12:59:24 INFO - 1498852764697 Marionette DEBUG Closed connection 375
12:59:24 INFO - 1498852764698 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50106
12:59:24 INFO - 1498852764699 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:59:24 INFO - 1498852764700 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:24 INFO - 1498852764703 Marionette DEBUG Register listener.js for window 2147483649
12:59:24 INFO - 1498852764708 Marionette TRACE 376 <- [1,1,null,{"sessionId":"5ec70d2b-47db-4936-84fd-aa5437ccf4b8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:24 INFO - 1498852764709 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%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"}]
12:59:24 INFO - 1498852764711 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"
12:59:24 INFO - 1498852764714 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"
12:59:24 INFO - 1498852764714 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"
12:59:24 INFO - 1498852764721 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"
12:59:24 INFO - 1498852764726 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"
12:59:24 INFO - 1498852764741 Marionette TRACE 376 <- [1,2,null,{}]
12:59:24 INFO - 1498852764753 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:59:24 INFO - 1498852764757 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aeb5daba-d9e2-454f-8c27-2a65f26a9b4c","ELEMENT":"aeb5daba-d9e2-454f-8c27-2a65f26a9b4c"}}]
12:59:24 INFO - 1498852764758 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"aeb5daba-d9e2-454f-8c27-2a65f26a9b4c"}]
12:59:25 INFO - 1498852765005 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:59:25 INFO - 1498852765006 Marionette TRACE 376 <- [1,4,null,{}]
12:59:25 INFO - 1498852765008 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"aeb5daba-d9e2-454f-8c27-2a65f26a9b4c","name":"selected"}]
12:59:25 INFO - 1498852765013 Marionette TRACE 376 <- [1,5,null,{"value":true}]
12:59:25 INFO - 1498852765015 Marionette TRACE 376 -> [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}]
12:59:25 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498852765019 Marionette TRACE 376 <- [1,6,null,{"value":null}]
12:59:25 INFO - 1498852765020 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
12:59:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 390ms
12:59:25 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:59:25 INFO - 1498852765022 Marionette TRACE 376 <- [1,7,null,{}]
12:59:25 INFO - 1498852765024 Marionette DEBUG Closed connection 376
12:59:25 INFO - 1498852765025 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50107
12:59:25 INFO - 1498852765026 Marionette DEBUG Closed connection 377
12:59:25 INFO - 1498852765027 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50108
12:59:25 INFO - 1498852765028 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:25 INFO - 1498852765029 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765032 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765036 Marionette TRACE 378 <- [1,1,null,{"sessionId":"0bbeae1f-d604-4efd-b50f-4d39890ca4fb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765038 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:59:25 INFO - 1498852765038 Marionette TRACE 378 <- [1,2,null,{}]
12:59:25 INFO - 1498852765040 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:59:25 INFO - 1498852765040 Marionette TRACE 378 <- [1,3,null,{}]
12:59:25 INFO - 1498852765041 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:59:25 INFO - 1498852765042 Marionette TRACE 378 <- [1,4,null,{}]
12:59:25 INFO - 1498852765044 Marionette TRACE 378 -> [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}]
12:59:25 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498852765047 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:59:25 INFO - 1498852765048 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:59:25 INFO - 1498852765050 Marionette TRACE 378 <- [1,6,null,{}]
12:59:25 INFO - 1498852765052 Marionette DEBUG Closed connection 378
12:59:25 INFO - 1498852765053 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50109
12:59:25 INFO - 1498852765054 Marionette DEBUG Closed connection 379
12:59:25 INFO - 1498852765054 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50110
12:59:25 INFO - 1498852765056 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:25 INFO - 1498852765057 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765060 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765064 Marionette TRACE 380 <- [1,1,null,{"sessionId":"1b386c2a-a43d-44ef-bdb9-704cb6e0d0d0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765066 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:59:25 INFO - 1498852765068 Marionette TRACE 380 <- [1,2,null,{}]
12:59:25 INFO - 1498852765071 Marionette DEBUG Closed connection 380
12:59:25 INFO - 1498852765073 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50111
12:59:25 INFO - 1498852765075 Marionette DEBUG Closed connection 381
12:59:25 INFO - 1498852765075 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50112
12:59:25 INFO - 1498852765077 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:59:25 INFO - 1498852765078 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765081 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765085 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d4b6365b-1584-4e79-9cfb-264c58fe06cf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765087 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%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"}]
12:59:25 INFO - 1498852765089 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"
12:59:25 INFO - 1498852765092 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"
12:59:25 INFO - 1498852765092 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"
12:59:25 INFO - 1498852765104 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"
12:59:25 INFO - 1498852765107 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"
12:59:25 INFO - 1498852765119 Marionette TRACE 382 <- [1,2,null,{}]
12:59:25 INFO - 1498852765123 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:59:25 INFO - 1498852765128 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"949da4e7-fb48-4212-945e-6eb9650fc20d","ELEMENT":"949da4e7-fb48-4212-945e-6eb9650fc20d"}}]
12:59:25 INFO - 1498852765129 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"949da4e7-fb48-4212-945e-6eb9650fc20d"}]
12:59:25 INFO - 1498852765370 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:59:25 INFO - 1498852765371 Marionette TRACE 382 <- [1,4,null,{}]
12:59:25 INFO - 1498852765373 Marionette TRACE 382 -> [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}]
12:59:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498852765377 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:59:25 INFO - 1498852765378 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:59:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 357ms
12:59:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:59:25 INFO - 1498852765380 Marionette TRACE 382 <- [1,6,null,{}]
12:59:25 INFO - 1498852765382 Marionette DEBUG Closed connection 382
12:59:25 INFO - 1498852765383 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50113
12:59:25 INFO - 1498852765384 Marionette DEBUG Closed connection 383
12:59:25 INFO - 1498852765384 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50114
12:59:25 INFO - 1498852765386 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:25 INFO - 1498852765386 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765389 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765393 Marionette TRACE 384 <- [1,1,null,{"sessionId":"da41143c-3569-44b9-9c7f-fbce0e505840","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765394 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:59:25 INFO - 1498852765395 Marionette TRACE 384 <- [1,2,null,{}]
12:59:25 INFO - 1498852765396 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:59:25 INFO - 1498852765396 Marionette TRACE 384 <- [1,3,null,{}]
12:59:25 INFO - 1498852765397 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:59:25 INFO - 1498852765398 Marionette TRACE 384 <- [1,4,null,{}]
12:59:25 INFO - 1498852765400 Marionette TRACE 384 -> [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}]
12:59:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498852765404 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:59:25 INFO - 1498852765405 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:59:25 INFO - 1498852765406 Marionette TRACE 384 <- [1,6,null,{}]
12:59:25 INFO - 1498852765408 Marionette DEBUG Closed connection 384
12:59:25 INFO - 1498852765409 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50115
12:59:25 INFO - 1498852765410 Marionette DEBUG Closed connection 385
12:59:25 INFO - 1498852765411 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50116
12:59:25 INFO - 1498852765412 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:25 INFO - 1498852765412 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765421 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765425 Marionette TRACE 386 <- [1,1,null,{"sessionId":"659adda5-4a47-4d77-8528-8448a2172dc3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765426 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:59:25 INFO - 1498852765427 Marionette TRACE 386 <- [1,2,null,{}]
12:59:25 INFO - 1498852765429 Marionette DEBUG Closed connection 386
12:59:25 INFO - 1498852765430 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50117
12:59:25 INFO - 1498852765431 Marionette DEBUG Closed connection 387
12:59:25 INFO - 1498852765432 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50118
12:59:25 INFO - 1498852765433 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:59:25 INFO - 1498852765434 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765437 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765440 Marionette TRACE 388 <- [1,1,null,{"sessionId":"47f585c4-56ec-470e-aa9f-09947d148dba","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765441 Marionette TRACE 388 -> [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"}]
12:59:25 INFO - 1498852765443 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"
12:59:25 INFO - 1498852765446 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"
12:59:25 INFO - 1498852765447 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"
12:59:25 INFO - 1498852765454 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"
12:59:25 INFO - 1498852765459 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"
12:59:25 INFO - 1498852765470 Marionette TRACE 388 <- [1,2,null,{}]
12:59:25 INFO - 1498852765475 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:59:25 INFO - 1498852765479 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e2aefd2-3de8-4de1-bf59-a8a30b0a130d","ELEMENT":"9e2aefd2-3de8-4de1-bf59-a8a30b0a130d"}}]
12:59:25 INFO - 1498852765485 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"9e2aefd2-3de8-4de1-bf59-a8a30b0a130d"}]
12:59:25 INFO - 1498852765728 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:59:25 INFO - 1498852765729 Marionette TRACE 388 <- [1,4,null,{}]
12:59:25 INFO - 1498852765731 Marionette TRACE 388 -> [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}]
12:59:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498852765735 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:59:25 INFO - 1498852765736 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:59:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 357ms
12:59:25 INFO - TEST-START | test_click.py TestClick.test_input_file
12:59:25 INFO - 1498852765738 Marionette TRACE 388 <- [1,6,null,{}]
12:59:25 INFO - 1498852765740 Marionette DEBUG Closed connection 388
12:59:25 INFO - 1498852765741 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50119
12:59:25 INFO - 1498852765742 Marionette DEBUG Closed connection 389
12:59:25 INFO - 1498852765742 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50120
12:59:25 INFO - 1498852765744 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:25 INFO - 1498852765744 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765747 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765751 Marionette TRACE 390 <- [1,1,null,{"sessionId":"b4b5c081-9754-4928-b2dd-5cd9a2b5d685","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765752 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:59:25 INFO - 1498852765753 Marionette TRACE 390 <- [1,2,null,{}]
12:59:25 INFO - 1498852765754 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:59:25 INFO - 1498852765754 Marionette TRACE 390 <- [1,3,null,{}]
12:59:25 INFO - 1498852765756 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:59:25 INFO - 1498852765756 Marionette TRACE 390 <- [1,4,null,{}]
12:59:25 INFO - 1498852765758 Marionette TRACE 390 -> [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}]
12:59:25 INFO - TEST-START: test_click.py TestClick.test_input_file1498852765761 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:59:25 INFO - 1498852765763 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:59:25 INFO - 1498852765764 Marionette TRACE 390 <- [1,6,null,{}]
12:59:25 INFO - 1498852765766 Marionette DEBUG Closed connection 390
12:59:25 INFO - 1498852765768 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50121
12:59:25 INFO - 1498852765768 Marionette DEBUG Closed connection 391
12:59:25 INFO - 1498852765769 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50122
12:59:25 INFO - 1498852765770 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:25 INFO - 1498852765772 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765776 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765780 Marionette TRACE 392 <- [1,1,null,{"sessionId":"db94f5b0-43f5-49b2-a2f7-0cc9ca52ad49","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765782 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:59:25 INFO - 1498852765783 Marionette TRACE 392 <- [1,2,null,{}]
12:59:25 INFO - 1498852765785 Marionette DEBUG Closed connection 392
12:59:25 INFO - 1498852765786 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50123
12:59:25 INFO - 1498852765787 Marionette DEBUG Closed connection 393
12:59:25 INFO - 1498852765788 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50124
12:59:25 INFO - 1498852765789 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:59:25 INFO - 1498852765790 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765793 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765796 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e8344f77-261b-4b42-9408-e77153e16b60","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765798 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:59:25 INFO - 1498852765799 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"
12:59:25 INFO - 1498852765802 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"
12:59:25 INFO - 1498852765802 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"
12:59:25 INFO - 1498852765809 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:59:25 INFO - 1498852765815 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:59:25 INFO - 1498852765829 Marionette TRACE 394 <- [1,2,null,{}]
12:59:25 INFO - 1498852765834 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:59:25 INFO - 1498852765837 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4fddaf55-d8ea-4f99-9a37-945d84a12621","ELEMENT":"4fddaf55-d8ea-4f99-9a37-945d84a12621"}}]
12:59:25 INFO - 1498852765838 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"4fddaf55-d8ea-4f99-9a37-945d84a12621"}]
12:59:25 INFO - 1498852765865 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:59:25 INFO - 1498852765867 Marionette TRACE 394 -> [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}]
12:59:25 INFO - TEST-END: test_click.py TestClick.test_input_file1498852765870 Marionette TRACE 394 <- [1,5,null,{"value":null}]
12:59:25 INFO - 1498852765871 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
12:59:25 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 134ms
12:59:25 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:59:25 INFO - 1498852765873 Marionette TRACE 394 <- [1,6,null,{}]
12:59:25 INFO - 1498852765874 Marionette DEBUG Closed connection 394
12:59:25 INFO - 1498852765875 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50125
12:59:25 INFO - 1498852765876 Marionette DEBUG Closed connection 395
12:59:25 INFO - 1498852765877 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50126
12:59:25 INFO - 1498852765878 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:25 INFO - 1498852765879 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765882 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765886 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1e847af2-77d8-4f9a-9d1c-9f32d1c356b7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765887 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:59:25 INFO - 1498852765887 Marionette TRACE 396 <- [1,2,null,{}]
12:59:25 INFO - 1498852765888 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:59:25 INFO - 1498852765889 Marionette TRACE 396 <- [1,3,null,{}]
12:59:25 INFO - 1498852765890 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:59:25 INFO - 1498852765890 Marionette TRACE 396 <- [1,4,null,{}]
12:59:25 INFO - 1498852765892 Marionette TRACE 396 -> [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}]
12:59:25 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498852765895 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:59:25 INFO - 1498852765896 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:59:25 INFO - 1498852765898 Marionette TRACE 396 <- [1,6,null,{}]
12:59:25 INFO - 1498852765900 Marionette DEBUG Closed connection 396
12:59:25 INFO - 1498852765901 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50127
12:59:25 INFO - 1498852765901 Marionette DEBUG Closed connection 397
12:59:25 INFO - 1498852765902 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50128
12:59:25 INFO - 1498852765903 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:25 INFO - 1498852765904 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765907 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765910 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d98046c9-27b8-4d19-a3ed-b58dbb87d8eb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765911 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:59:25 INFO - 1498852765912 Marionette TRACE 398 <- [1,2,null,{}]
12:59:25 INFO - 1498852765914 Marionette DEBUG Closed connection 398
12:59:25 INFO - 1498852765915 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50129
12:59:25 INFO - 1498852765916 Marionette DEBUG Closed connection 399
12:59:25 INFO - 1498852765917 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50130
12:59:25 INFO - 1498852765918 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:59:25 INFO - 1498852765918 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:25 INFO - 1498852765921 Marionette DEBUG Register listener.js for window 2147483649
12:59:25 INFO - 1498852765924 Marionette TRACE 400 <- [1,1,null,{"sessionId":"2e98f493-6275-4f64-b54e-86b5237b70ec","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:25 INFO - 1498852765926 Marionette TRACE 400 -> [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"}]
12:59:25 INFO - 1498852765928 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:59:25 INFO - 1498852765930 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:59:25 INFO - 1498852765930 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:59:25 INFO - 1498852765938 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"
12:59:25 INFO - 1498852765941 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"
12:59:25 INFO - 1498852765944 Marionette TRACE 400 <- [1,2,null,{}]
12:59:25 INFO - 1498852765955 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:59:25 INFO - 1498852765959 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b8a8619c-939b-4eb9-a440-182709ab657d","ELEMENT":"b8a8619c-939b-4eb9-a440-182709ab657d"}}]
12:59:25 INFO - 1498852765960 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:59:25 INFO - 1498852765963 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"497eb071-7307-4b4e-a5da-23884fb3149b","ELEMENT":"497eb071-7307-4b4e-a5da-23884fb3149b"}}]
12:59:25 INFO - 1498852765965 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b8a8619c-939b-4eb9-a440-182709ab657d"}]
12:59:26 INFO - 1498852766207 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:59:26 INFO - 1498852766208 Marionette TRACE 400 <- [1,5,null,{}]
12:59:26 INFO - 1498852766209 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"497eb071-7307-4b4e-a5da-23884fb3149b"}]
12:59:26 INFO - 1498852766228 Marionette TRACE 400 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:59:26 INFO - 1498852766230 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:59:26 INFO - 1498852766234 Marionette TRACE 400 <- [1,7,null,{"value":false}]
12:59:26 INFO - 1498852766236 Marionette TRACE 400 -> [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}]
12:59:26 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498852766239 Marionette TRACE 400 <- [1,8,null,{"value":null}]
12:59:26 INFO - 1498852766240 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
12:59:26 INFO - 1498852766241 Marionette TRACE 400 <- [1,9,null,{}]
12:59:26 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 368ms
12:59:26 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:59:26 INFO - 1498852766243 Marionette DEBUG Closed connection 400
12:59:26 INFO - 1498852766244 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50131
12:59:26 INFO - 1498852766245 Marionette DEBUG Closed connection 401
12:59:26 INFO - 1498852766246 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50132
12:59:26 INFO - 1498852766247 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:26 INFO - 1498852766247 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:26 INFO - 1498852766250 Marionette DEBUG Register listener.js for window 2147483649
12:59:26 INFO - 1498852766254 Marionette TRACE 402 <- [1,1,null,{"sessionId":"a2cb110d-815d-4bb1-915a-32e5174fae7e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:26 INFO - 1498852766255 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
12:59:26 INFO - 1498852766256 Marionette TRACE 402 <- [1,2,null,{}]
12:59:26 INFO - 1498852766257 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:59:26 INFO - 1498852766257 Marionette TRACE 402 <- [1,3,null,{}]
12:59:26 INFO - 1498852766258 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
12:59:26 INFO - 1498852766259 Marionette TRACE 402 <- [1,4,null,{}]
12:59:26 INFO - 1498852766261 Marionette TRACE 402 -> [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}]
12:59:26 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498852766265 Marionette TRACE 402 <- [1,5,null,{"value":null}]
12:59:26 INFO - 1498852766266 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
12:59:26 INFO - 1498852766267 Marionette TRACE 402 <- [1,6,null,{}]
12:59:26 INFO - 1498852766269 Marionette DEBUG Closed connection 402
12:59:26 INFO - 1498852766270 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50133
12:59:26 INFO - 1498852766271 Marionette DEBUG Closed connection 403
12:59:26 INFO - 1498852766272 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50134
12:59:26 INFO - 1498852766273 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:59:26 INFO - 1498852766273 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:26 INFO - 1498852766276 Marionette DEBUG Register listener.js for window 2147483649
12:59:26 INFO - 1498852766279 Marionette TRACE 404 <- [1,1,null,{"sessionId":"cd90fd0f-5ba3-4d76-9a9c-bc82dd4eaeb5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:26 INFO - 1498852766281 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
12:59:26 INFO - 1498852766282 Marionette TRACE 404 <- [1,2,null,{}]
12:59:26 INFO - 1498852766284 Marionette DEBUG Closed connection 404
12:59:26 INFO - 1498852766285 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50135
12:59:26 INFO - 1498852766285 Marionette DEBUG Closed connection 405
12:59:26 INFO - 1498852766286 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50136
12:59:26 INFO - 1498852766288 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:59:26 INFO - 1498852766288 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}
12:59:26 INFO - 1498852766291 Marionette DEBUG Register listener.js for window 2147483649
12:59:26 INFO - 1498852766294 Marionette TRACE 406 <- [1,1,null,{"sessionId":"b2ffcd25-1fda-4eac-9b78-c91fa0d81328","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2176,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsmynin.mozrunner","moz:accessibilityChecks":false}}]
12:59:26 INFO - 1498852766296 Marionette TRACE 406 -> [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"}]
12:59:26 INFO - 1498852766298 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"
12:59:26 INFO - 1498852766300 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"
12:59:26 INFO - 1498852766300 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"
12:59:26 INFO - 1498852766308 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"
12:59:26 INFO - 1498852766311 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"
12:59:26 INFO - 1498852766325 Marionette TRACE 406 <- [1,2,null,{}]
12:59:26 INFO - 1498852766329 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:59:26 INFO - 1498852766336 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5fd4fc6d-9b8a-407b-902d-af23ee183805","ELEMENT":"5fd4fc6d-9b8a-407b-902d-af23ee183805"}}]
12:59:26 INFO - 1498852766338 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5fd4fc6d-9b8a-407b-902d-af23ee183805"}]
12:59:26 INFO - 1498852766367 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
12:59:26 INFO - 1498852766368 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"5fd4fc6d-9b8a-407b-902d-af23ee183805"}]
12:59:26 INFO - 1498852766387 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element