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]
21:37:48 INFO - 1498970268011 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}]
21:37:48 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498970268013 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:37:48 INFO - 1498970268014 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:37:48 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 123ms
21:37:48 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:37:48 INFO - 1498970268015 Marionette TRACE 352 <- [1,6,null,{}]
21:37:48 INFO - 1498970268017 Marionette DEBUG Closed connection 352
21:37:48 INFO - 1498970268018 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49749
21:37:48 INFO - 1498970268018 Marionette DEBUG Closed connection 353
21:37:48 INFO - 1498970268019 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49750
21:37:48 INFO - 1498970268020 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:48 INFO - 1498970268020 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268022 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268025 Marionette TRACE 354 <- [1,1,null,{"sessionId":"4a651f6f-af31-4dd9-89f0-0f7a7e815783","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268026 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:37:48 INFO - 1498970268026 Marionette TRACE 354 <- [1,2,null,{}]
21:37:48 INFO - 1498970268027 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:48 INFO - 1498970268028 Marionette TRACE 354 <- [1,3,null,{}]
21:37:48 INFO - 1498970268028 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:48 INFO - 1498970268029 Marionette TRACE 354 <- [1,4,null,{}]
21:37:48 INFO - 1498970268030 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}]
21:37:48 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498970268032 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:37:48 INFO - 1498970268033 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:37:48 INFO - 1498970268034 Marionette TRACE 354 <- [1,6,null,{}]
21:37:48 INFO - 1498970268036 Marionette DEBUG Closed connection 354
21:37:48 INFO - 1498970268037 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49751
21:37:48 INFO - 1498970268037 Marionette DEBUG Closed connection 355
21:37:48 INFO - 1498970268038 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49752
21:37:48 INFO - 1498970268039 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:48 INFO - 1498970268039 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268041 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268044 Marionette TRACE 356 <- [1,1,null,{"sessionId":"3e26b23e-583d-4347-b187-c60b104dfbd6","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268045 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:37:48 INFO - 1498970268046 Marionette TRACE 356 <- [1,2,null,{}]
21:37:48 INFO - 1498970268047 Marionette DEBUG Closed connection 356
21:37:48 INFO - 1498970268047 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49753
21:37:48 INFO - 1498970268048 Marionette DEBUG Closed connection 357
21:37:48 INFO - 1498970268049 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49754
21:37:48 INFO - 1498970268050 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:48 INFO - 1498970268050 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268052 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268055 Marionette TRACE 358 <- [1,1,null,{"sessionId":"83137355-410a-4422-9add-b50284ca04f9","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268055 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49324/clicks.html"}]
21:37:48 INFO - 1498970268057 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/hidden.html"
21:37:48 INFO - 1498970268061 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/hidden.html"
21:37:48 INFO - 1498970268061 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/hidden.html"
21:37:48 INFO - 1498970268077 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49324/clicks.html"
21:37:48 INFO - 1498970268082 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49324/clicks.html"
21:37:48 INFO - 1498970268090 Marionette TRACE 358 <- [1,2,null,{}]
21:37:48 INFO - 1498970268092 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:37:48 INFO - 1498970268093 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aeaec61c-827c-44b0-aecf-d365588ec1e0","ELEMENT":"aeaec61c-827c-44b0-aecf-d365588ec1e0"}}]
21:37:48 INFO - 1498970268094 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"aeaec61c-827c-44b0-aecf-d365588ec1e0"}]
21:37:48 INFO - 1498970268132 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/clicks.html"
21:37:48 INFO - 1498970268139 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/clicks.html"
21:37:48 INFO - 1498970268139 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/clicks.html"
21:37:48 INFO - 1498970268156 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49324/test.html"
21:37:48 INFO - 1498970268157 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49324/test.html"
21:37:48 INFO - 1498970268159 Marionette TRACE 358 <- [1,4,null,{}]
21:37:48 INFO - 1498970268160 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:37:48 INFO - 1498970268161 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cbb38833-7ca9-44d3-9d98-9e83b8d930e8","ELEMENT":"cbb38833-7ca9-44d3-9d98-9e83b8d930e8"}}]
21:37:48 INFO - 1498970268162 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:37:48 INFO - 1498970268163 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:37:48 INFO - 1498970268170 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}]
21:37:48 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498970268172 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:37:48 INFO - 1498970268173 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:37:48 INFO - 1498970268174 Marionette TRACE 358 <- [1,8,null,{}]
21:37:48 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 159ms
21:37:48 INFO - TEST-START | test_click.py TestClick.test_container_element
21:37:48 INFO - 1498970268176 Marionette DEBUG Closed connection 358
21:37:48 INFO - 1498970268177 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49757
21:37:48 INFO - 1498970268178 Marionette DEBUG Closed connection 359
21:37:48 INFO - 1498970268178 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49758
21:37:48 INFO - 1498970268179 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:48 INFO - 1498970268179 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268188 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268192 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d0406297-38b6-4e21-8808-c13f4aa3d185","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268194 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:37:48 INFO - 1498970268194 Marionette TRACE 360 <- [1,2,null,{}]
21:37:48 INFO - 1498970268195 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:48 INFO - 1498970268195 Marionette TRACE 360 <- [1,3,null,{}]
21:37:48 INFO - 1498970268198 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:48 INFO - 1498970268198 Marionette TRACE 360 <- [1,4,null,{}]
21:37:48 INFO - 1498970268200 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}]
21:37:48 INFO - TEST-START: test_click.py TestClick.test_container_element1498970268202 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:37:48 INFO - 1498970268202 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:37:48 INFO - 1498970268204 Marionette TRACE 360 <- [1,6,null,{}]
21:37:48 INFO - 1498970268205 Marionette DEBUG Closed connection 360
21:37:48 INFO - 1498970268205 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49759
21:37:48 INFO - 1498970268206 Marionette DEBUG Closed connection 361
21:37:48 INFO - 1498970268207 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49760
21:37:48 INFO - 1498970268208 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:48 INFO - 1498970268208 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268210 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268213 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4ff6ac0c-a89e-43af-b317-15cf8ec04216","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268214 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:37:48 INFO - 1498970268215 Marionette TRACE 362 <- [1,2,null,{}]
21:37:48 INFO - 1498970268216 Marionette DEBUG Closed connection 362
21:37:48 INFO - 1498970268217 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49761
21:37:48 INFO - 1498970268218 Marionette DEBUG Closed connection 363
21:37:48 INFO - 1498970268218 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49762
21:37:48 INFO - 1498970268219 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:48 INFO - 1498970268219 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268221 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268224 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d34ccf04-c2f4-4682-8982-0357ed160428","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268225 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"}]
21:37:48 INFO - 1498970268226 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/test.html"
21:37:48 INFO - 1498970268227 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/test.html"
21:37:48 INFO - 1498970268228 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/test.html"
21:37:48 INFO - 1498970268246 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"
21:37:48 INFO - 1498970268253 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"
21:37:48 INFO - 1498970268255 Marionette TRACE 364 <- [1,2,null,{}]
21:37:48 INFO - 1498970268258 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:37:48 INFO - 1498970268260 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5281a9c0-d03d-48bd-ab76-08cdea2d37d3","ELEMENT":"5281a9c0-d03d-48bd-ab76-08cdea2d37d3"}}]
21:37:48 INFO - 1498970268261 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5281a9c0-d03d-48bd-ab76-08cdea2d37d3"}]
21:37:48 INFO - 1498970268500 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:48 INFO - 1498970268501 Marionette TRACE 364 <- [1,4,null,{}]
21:37:48 INFO - 1498970268502 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5281a9c0-d03d-48bd-ab76-08cdea2d37d3","name":"selected"}]
21:37:48 INFO - 1498970268506 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:37:48 INFO - 1498970268507 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}]
21:37:48 INFO - TEST-END: test_click.py TestClick.test_container_element1498970268509 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:37:48 INFO - 1498970268511 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:37:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 338ms
21:37:48 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:37:48 INFO - 1498970268512 Marionette TRACE 364 <- [1,7,null,{}]
21:37:48 INFO - 1498970268514 Marionette DEBUG Closed connection 364
21:37:48 INFO - 1498970268515 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49763
21:37:48 INFO - 1498970268516 Marionette DEBUG Closed connection 365
21:37:48 INFO - 1498970268516 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49764
21:37:48 INFO - 1498970268517 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:48 INFO - 1498970268518 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268520 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268522 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1bc26268-2eab-4743-a846-c83e33a9daa1","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268523 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:37:48 INFO - 1498970268524 Marionette TRACE 366 <- [1,2,null,{}]
21:37:48 INFO - 1498970268525 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:48 INFO - 1498970268526 Marionette TRACE 366 <- [1,3,null,{}]
21:37:48 INFO - 1498970268527 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:48 INFO - 1498970268527 Marionette TRACE 366 <- [1,4,null,{}]
21:37:48 INFO - 1498970268529 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}]
21:37:48 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498970268531 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:37:48 INFO - 1498970268532 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:37:48 INFO - 1498970268533 Marionette TRACE 366 <- [1,6,null,{}]
21:37:48 INFO - 1498970268535 Marionette DEBUG Closed connection 366
21:37:48 INFO - 1498970268535 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49765
21:37:48 INFO - 1498970268536 Marionette DEBUG Closed connection 367
21:37:48 INFO - 1498970268536 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49766
21:37:48 INFO - 1498970268537 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:48 INFO - 1498970268538 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268540 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268542 Marionette TRACE 368 <- [1,1,null,{"sessionId":"2a3e506e-1ff5-4851-a3fa-730fc982c6d7","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268543 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:37:48 INFO - 1498970268544 Marionette TRACE 368 <- [1,2,null,{}]
21:37:48 INFO - 1498970268546 Marionette DEBUG Closed connection 368
21:37:48 INFO - 1498970268546 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49767
21:37:48 INFO - 1498970268548 Marionette DEBUG Closed connection 369
21:37:48 INFO - 1498970268548 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49768
21:37:48 INFO - 1498970268550 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:48 INFO - 1498970268550 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268552 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268554 Marionette TRACE 370 <- [1,1,null,{"sessionId":"820d5241-2b28-431a-bc1a-fa5fe945d5d3","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268555 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"}]
21:37:48 INFO - 1498970268556 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"
21:37:48 INFO - 1498970268558 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"
21:37:48 INFO - 1498970268558 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"
21:37:48 INFO - 1498970268573 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"
21:37:48 INFO - 1498970268576 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"
21:37:48 INFO - 1498970268579 Marionette TRACE 370 <- [1,2,null,{}]
21:37:48 INFO - 1498970268586 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:37:48 INFO - 1498970268588 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f765f509-a79d-405c-9e90-0d289fccb109","ELEMENT":"f765f509-a79d-405c-9e90-0d289fccb109"}}]
21:37:48 INFO - 1498970268589 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"f765f509-a79d-405c-9e90-0d289fccb109"}]
21:37:48 INFO - 1498970268834 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:48 INFO - 1498970268834 Marionette TRACE 370 <- [1,4,null,{}]
21:37:48 INFO - 1498970268835 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"f765f509-a79d-405c-9e90-0d289fccb109","name":"selected"}]
21:37:48 INFO - 1498970268839 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:37:48 INFO - 1498970268840 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}]
21:37:48 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498970268842 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:37:48 INFO - 1498970268843 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:37:48 INFO - 1498970268844 Marionette TRACE 370 <- [1,7,null,{}]
21:37:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 332ms
21:37:48 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:37:48 INFO - 1498970268846 Marionette DEBUG Closed connection 370
21:37:48 INFO - 1498970268847 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49769
21:37:48 INFO - 1498970268848 Marionette DEBUG Closed connection 371
21:37:48 INFO - 1498970268848 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49770
21:37:48 INFO - 1498970268849 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:48 INFO - 1498970268850 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268852 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268855 Marionette TRACE 372 <- [1,1,null,{"sessionId":"94fed26c-ac4f-4c18-aad0-c6a019236586","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268856 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:37:48 INFO - 1498970268856 Marionette TRACE 372 <- [1,2,null,{}]
21:37:48 INFO - 1498970268857 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:48 INFO - 1498970268858 Marionette TRACE 372 <- [1,3,null,{}]
21:37:48 INFO - 1498970268858 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:48 INFO - 1498970268859 Marionette TRACE 372 <- [1,4,null,{}]
21:37:48 INFO - 1498970268861 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}]
21:37:48 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498970268863 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:37:48 INFO - 1498970268864 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:37:48 INFO - 1498970268865 Marionette TRACE 372 <- [1,6,null,{}]
21:37:48 INFO - 1498970268867 Marionette DEBUG Closed connection 372
21:37:48 INFO - 1498970268868 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49771
21:37:48 INFO - 1498970268868 Marionette DEBUG Closed connection 373
21:37:48 INFO - 1498970268869 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49772
21:37:48 INFO - 1498970268870 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:48 INFO - 1498970268870 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268872 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268875 Marionette TRACE 374 <- [1,1,null,{"sessionId":"039f7b9d-9a47-44c6-aa45-0c36a6e63678","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268876 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:37:48 INFO - 1498970268877 Marionette TRACE 374 <- [1,2,null,{}]
21:37:48 INFO - 1498970268879 Marionette DEBUG Closed connection 374
21:37:48 INFO - 1498970268879 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49773
21:37:48 INFO - 1498970268880 Marionette DEBUG Closed connection 375
21:37:48 INFO - 1498970268881 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49774
21:37:48 INFO - 1498970268882 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:48 INFO - 1498970268882 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:48 INFO - 1498970268884 Marionette DEBUG Register listener.js for window 12
21:37:48 INFO - 1498970268887 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6cf9c66d-e71c-4999-aa11-ef766d3c6f6c","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:48 INFO - 1498970268888 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"}]
21:37:48 INFO - 1498970268889 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"
21:37:48 INFO - 1498970268891 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"
21:37:48 INFO - 1498970268891 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"
21:37:48 INFO - 1498970268908 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"
21:37:48 INFO - 1498970268910 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"
21:37:48 INFO - 1498970268913 Marionette TRACE 376 <- [1,2,null,{}]
21:37:48 INFO - 1498970268914 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:37:48 INFO - 1498970268921 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"38443d6b-98a6-45ee-8585-5fc49017b17c","ELEMENT":"38443d6b-98a6-45ee-8585-5fc49017b17c"}}]
21:37:48 INFO - 1498970268922 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"38443d6b-98a6-45ee-8585-5fc49017b17c"}]
21:37:49 INFO - 1498970269157 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:49 INFO - 1498970269157 Marionette TRACE 376 <- [1,4,null,{}]
21:37:49 INFO - 1498970269159 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}]
21:37:49 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498970269161 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:37:49 INFO - 1498970269162 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:37:49 INFO - 1498970269163 Marionette TRACE 376 <- [1,6,null,{}]
21:37:49 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 319ms
21:37:49 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:37:49 INFO - 1498970269165 Marionette DEBUG Closed connection 376
21:37:49 INFO - 1498970269166 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49775
21:37:49 INFO - 1498970269167 Marionette DEBUG Closed connection 377
21:37:49 INFO - 1498970269167 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49776
21:37:49 INFO - 1498970269168 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:49 INFO - 1498970269168 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269170 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269173 Marionette TRACE 378 <- [1,1,null,{"sessionId":"7a534493-355a-45f3-8f89-0697aca4a5f3","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269174 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:37:49 INFO - 1498970269174 Marionette TRACE 378 <- [1,2,null,{}]
21:37:49 INFO - 1498970269175 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:49 INFO - 1498970269175 Marionette TRACE 378 <- [1,3,null,{}]
21:37:49 INFO - 1498970269176 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:49 INFO - 1498970269176 Marionette TRACE 378 <- [1,4,null,{}]
21:37:49 INFO - 1498970269178 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}]
21:37:49 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498970269180 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:37:49 INFO - 1498970269181 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:37:49 INFO - 1498970269182 Marionette TRACE 378 <- [1,6,null,{}]
21:37:49 INFO - 1498970269183 Marionette DEBUG Closed connection 378
21:37:49 INFO - 1498970269184 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49777
21:37:49 INFO - 1498970269185 Marionette DEBUG Closed connection 379
21:37:49 INFO - 1498970269185 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49778
21:37:49 INFO - 1498970269186 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:49 INFO - 1498970269187 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269189 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269191 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c152d78e-053d-47ff-afd9-257358e17a09","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269192 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:37:49 INFO - 1498970269193 Marionette TRACE 380 <- [1,2,null,{}]
21:37:49 INFO - 1498970269195 Marionette DEBUG Closed connection 380
21:37:49 INFO - 1498970269195 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49779
21:37:49 INFO - 1498970269196 Marionette DEBUG Closed connection 381
21:37:49 INFO - 1498970269197 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49780
21:37:49 INFO - 1498970269198 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:49 INFO - 1498970269198 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269200 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269210 Marionette TRACE 382 <- [1,1,null,{"sessionId":"43ac4242-a36c-4329-a328-4dfffce81113","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269211 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"}]
21:37:49 INFO - 1498970269212 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"
21:37:49 INFO - 1498970269214 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"
21:37:49 INFO - 1498970269214 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"
21:37:49 INFO - 1498970269230 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"
21:37:49 INFO - 1498970269236 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"
21:37:49 INFO - 1498970269238 Marionette TRACE 382 <- [1,2,null,{}]
21:37:49 INFO - 1498970269240 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:37:49 INFO - 1498970269242 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"52a0675b-81b6-4fb6-b4d9-a2433b39889e","ELEMENT":"52a0675b-81b6-4fb6-b4d9-a2433b39889e"}}]
21:37:49 INFO - 1498970269243 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"52a0675b-81b6-4fb6-b4d9-a2433b39889e"}]
21:37:49 INFO - 1498970269485 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:49 INFO - 1498970269486 Marionette TRACE 382 <- [1,4,null,{}]
21:37:49 INFO - 1498970269487 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}]
21:37:49 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498970269490 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:37:49 INFO - 1498970269491 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:37:49 INFO - 1498970269492 Marionette TRACE 382 <- [1,6,null,{}]
21:37:49 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 327ms
21:37:49 INFO - TEST-START | test_click.py TestClick.test_input_file
21:37:49 INFO - 1498970269493 Marionette DEBUG Closed connection 382
21:37:49 INFO - 1498970269494 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49781
21:37:49 INFO - 1498970269495 Marionette DEBUG Closed connection 383
21:37:49 INFO - 1498970269495 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49782
21:37:49 INFO - 1498970269496 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:49 INFO - 1498970269496 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269499 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269501 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ec52835a-e808-47dd-8e44-874e7a9830cc","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269502 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:37:49 INFO - 1498970269502 Marionette TRACE 384 <- [1,2,null,{}]
21:37:49 INFO - 1498970269503 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:49 INFO - 1498970269503 Marionette TRACE 384 <- [1,3,null,{}]
21:37:49 INFO - 1498970269504 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:49 INFO - 1498970269504 Marionette TRACE 384 <- [1,4,null,{}]
21:37:49 INFO - 1498970269506 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}]
21:37:49 INFO - TEST-START: test_click.py TestClick.test_input_file1498970269508 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:37:49 INFO - 1498970269509 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:37:49 INFO - 1498970269510 Marionette TRACE 384 <- [1,6,null,{}]
21:37:49 INFO - 1498970269511 Marionette DEBUG Closed connection 384
21:37:49 INFO - 1498970269512 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49783
21:37:49 INFO - 1498970269513 Marionette DEBUG Closed connection 385
21:37:49 INFO - 1498970269513 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49784
21:37:49 INFO - 1498970269514 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:49 INFO - 1498970269514 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269516 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269519 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5834f7df-6448-4183-b7eb-42759834b795","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269520 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:37:49 INFO - 1498970269521 Marionette TRACE 386 <- [1,2,null,{}]
21:37:49 INFO - 1498970269522 Marionette DEBUG Closed connection 386
21:37:49 INFO - 1498970269523 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49785
21:37:49 INFO - 1498970269523 Marionette DEBUG Closed connection 387
21:37:49 INFO - 1498970269524 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49786
21:37:49 INFO - 1498970269525 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:49 INFO - 1498970269525 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269527 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269530 Marionette TRACE 388 <- [1,1,null,{"sessionId":"429f6299-4635-472a-821c-50d123711a4b","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269531 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:37:49 INFO - 1498970269532 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"
21:37:49 INFO - 1498970269533 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"
21:37:49 INFO - 1498970269534 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"
21:37:49 INFO - 1498970269543 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:49 INFO - 1498970269547 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:49 INFO - 1498970269549 Marionette TRACE 388 <- [1,2,null,{}]
21:37:49 INFO - 1498970269556 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:37:49 INFO - 1498970269558 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"31df77d9-8531-45f9-a117-cb2574b77afe","ELEMENT":"31df77d9-8531-45f9-a117-cb2574b77afe"}}]
21:37:49 INFO - 1498970269559 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"31df77d9-8531-45f9-a117-cb2574b77afe"}]
21:37:49 INFO - 1498970269590 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
21:37:49 INFO - 1498970269592 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}]
21:37:49 INFO - TEST-END: test_click.py TestClick.test_input_file1498970269594 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:37:49 INFO - 1498970269595 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:37:49 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 104ms
21:37:49 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:37:49 INFO - 1498970269596 Marionette TRACE 388 <- [1,6,null,{}]
21:37:49 INFO - 1498970269598 Marionette DEBUG Closed connection 388
21:37:49 INFO - 1498970269599 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49787
21:37:49 INFO - 1498970269599 Marionette DEBUG Closed connection 389
21:37:49 INFO - 1498970269600 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49788
21:37:49 INFO - 1498970269601 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:49 INFO - 1498970269601 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269603 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269606 Marionette TRACE 390 <- [1,1,null,{"sessionId":"24091e99-1b87-4e8a-a8e7-4848669035cf","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269607 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:37:49 INFO - 1498970269607 Marionette TRACE 390 <- [1,2,null,{}]
21:37:49 INFO - 1498970269608 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:49 INFO - 1498970269608 Marionette TRACE 390 <- [1,3,null,{}]
21:37:49 INFO - 1498970269609 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:49 INFO - 1498970269609 Marionette TRACE 390 <- [1,4,null,{}]
21:37:49 INFO - 1498970269611 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}]
21:37:49 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498970269613 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:37:49 INFO - 1498970269614 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:37:49 INFO - 1498970269615 Marionette TRACE 390 <- [1,6,null,{}]
21:37:49 INFO - 1498970269617 Marionette DEBUG Closed connection 390
21:37:49 INFO - 1498970269617 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49789
21:37:49 INFO - 1498970269618 Marionette DEBUG Closed connection 391
21:37:49 INFO - 1498970269618 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49790
21:37:49 INFO - 1498970269619 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:49 INFO - 1498970269619 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269621 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269624 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a778da05-a3ea-4dbb-96b4-de81d3d68a88","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269625 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:37:49 INFO - 1498970269626 Marionette TRACE 392 <- [1,2,null,{}]
21:37:49 INFO - 1498970269628 Marionette DEBUG Closed connection 392
21:37:49 INFO - 1498970269628 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49791
21:37:49 INFO - 1498970269629 Marionette DEBUG Closed connection 393
21:37:49 INFO - 1498970269630 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49792
21:37:49 INFO - 1498970269631 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:49 INFO - 1498970269631 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269633 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269636 Marionette TRACE 394 <- [1,1,null,{"sessionId":"dfc737b9-34a4-4567-ac68-42858bab0025","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269637 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"}]
21:37:49 INFO - 1498970269638 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:49 INFO - 1498970269640 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:49 INFO - 1498970269640 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:49 INFO - 1498970269657 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"
21:37:49 INFO - 1498970269659 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"
21:37:49 INFO - 1498970269661 Marionette TRACE 394 <- [1,2,null,{}]
21:37:49 INFO - 1498970269662 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:37:49 INFO - 1498970269664 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"926508a5-8a32-490a-b4cf-c9b1ff42c5a8","ELEMENT":"926508a5-8a32-490a-b4cf-c9b1ff42c5a8"}}]
21:37:49 INFO - 1498970269669 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:37:49 INFO - 1498970269671 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cce83b4a-6e6b-49b3-b14a-30fe2a537b00","ELEMENT":"cce83b4a-6e6b-49b3-b14a-30fe2a537b00"}}]
21:37:49 INFO - 1498970269672 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"926508a5-8a32-490a-b4cf-c9b1ff42c5a8"}]
21:37:49 INFO - 1498970269906 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:49 INFO - 1498970269907 Marionette TRACE 394 <- [1,5,null,{}]
21:37:49 INFO - 1498970269908 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"cce83b4a-6e6b-49b3-b14a-30fe2a537b00"}]
21:37:49 INFO - 1498970269921 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
21:37:49 INFO - 1498970269923 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:37:49 INFO - 1498970269924 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:37:49 INFO - 1498970269926 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}]
21:37:49 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498970269928 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:37:49 INFO - 1498970269929 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:37:49 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 334ms
21:37:49 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:37:49 INFO - 1498970269930 Marionette TRACE 394 <- [1,9,null,{}]
21:37:49 INFO - 1498970269932 Marionette DEBUG Closed connection 394
21:37:49 INFO - 1498970269933 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49793
21:37:49 INFO - 1498970269933 Marionette DEBUG Closed connection 395
21:37:49 INFO - 1498970269934 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49794
21:37:49 INFO - 1498970269935 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:49 INFO - 1498970269935 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269937 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269940 Marionette TRACE 396 <- [1,1,null,{"sessionId":"fd246cea-601c-409e-a998-ac2bc3748642","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269941 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:37:49 INFO - 1498970269941 Marionette TRACE 396 <- [1,2,null,{}]
21:37:49 INFO - 1498970269942 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:49 INFO - 1498970269942 Marionette TRACE 396 <- [1,3,null,{}]
21:37:49 INFO - 1498970269943 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:49 INFO - 1498970269944 Marionette TRACE 396 <- [1,4,null,{}]
21:37:49 INFO - 1498970269945 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}]
21:37:49 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498970269947 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:37:49 INFO - 1498970269948 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:37:49 INFO - 1498970269949 Marionette TRACE 396 <- [1,6,null,{}]
21:37:49 INFO - 1498970269951 Marionette DEBUG Closed connection 396
21:37:49 INFO - 1498970269951 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49795
21:37:49 INFO - 1498970269952 Marionette DEBUG Closed connection 397
21:37:49 INFO - 1498970269952 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49796
21:37:49 INFO - 1498970269953 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:49 INFO - 1498970269954 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269956 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269958 Marionette TRACE 398 <- [1,1,null,{"sessionId":"143ae425-16d0-4e13-9189-e57e46380663","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269959 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:37:49 INFO - 1498970269960 Marionette TRACE 398 <- [1,2,null,{}]
21:37:49 INFO - 1498970269962 Marionette DEBUG Closed connection 398
21:37:49 INFO - 1498970269962 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49797
21:37:49 INFO - 1498970269963 Marionette DEBUG Closed connection 399
21:37:49 INFO - 1498970269963 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49798
21:37:49 INFO - 1498970269964 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:49 INFO - 1498970269965 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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}
21:37:49 INFO - 1498970269967 Marionette DEBUG Register listener.js for window 12
21:37:49 INFO - 1498970269970 Marionette TRACE 400 <- [1,1,null,{"sessionId":"247969fb-33cc-4f30-82b5-1682cd0c5922","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":3416,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpuyepsu.mozrunner","moz:accessibilityChecks":false}}]
21:37:49 INFO - 1498970269971 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"}]
21:37:49 INFO - 1498970269972 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"
21:37:49 INFO - 1498970269974 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"
21:37:49 INFO - 1498970269974 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"
21:37:49 INFO - 1498970269993 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"
21:37:49 INFO - 1498970269995 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"
21:37:49 INFO - 1498970269997 Marionette TRACE 400 <- [1,2,null,{}]
21:37:50 INFO - 1498970270003 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:37:50 INFO - 1498970270005 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c9522efc-9dab-49a7-ac4c-54ca9d8323db","ELEMENT":"c9522efc-9dab-49a7-ac4c-54ca9d8323db"}}]
21:37:50 INFO - 1498970270006 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c9522efc-9dab-49a7-ac4c-54ca9d8323db"}]
21:37:50 INFO - 1498970270035 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:37:50 INFO - 1498970270036 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c9522efc-9dab-49a7-ac4c-54ca9d8323db"}]
21:37:50 INFO - 1498970270052 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element