could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
00:21:24 INFO - 1499239284012 Marionette TRACE 353 -> [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}]
00:21:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 115ms
00:21:24 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499239284016 Marionette TRACE 353 <- [1,5,null,{"value":null}]
00:21:24 INFO - 1499239284017 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
00:21:24 INFO - 1499239284019 Marionette TRACE 353 <- [1,6,null,{}]
00:21:24 INFO - 1499239284020 Marionette DEBUG Closed connection 353
00:21:24 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
00:21:24 INFO - 1499239284023 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49793
00:21:24 INFO - 1499239284035 Marionette DEBUG Closed connection 354
00:21:24 INFO - 1499239284035 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49794
00:21:24 INFO - 1499239284036 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:24 INFO - 1499239284037 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284039 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284042 Marionette TRACE 355 <- [1,1,null,{"sessionId":"4e9bfe8c-0444-4463-81d7-e9e96e0faca8","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284043 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
00:21:24 INFO - 1499239284043 Marionette TRACE 355 <- [1,2,null,{}]
00:21:24 INFO - 1499239284044 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:21:24 INFO - 1499239284044 Marionette TRACE 355 <- [1,3,null,{}]
00:21:24 INFO - 1499239284046 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
00:21:24 INFO - 1499239284046 Marionette TRACE 355 <- [1,4,null,{}]
00:21:24 INFO - 1499239284048 Marionette TRACE 355 -> [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}]
00:21:24 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499239284051 Marionette TRACE 355 <- [1,5,null,{"value":null}]
00:21:24 INFO - 1499239284052 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
00:21:24 INFO - 1499239284053 Marionette TRACE 355 <- [1,6,null,{}]
00:21:24 INFO - 1499239284054 Marionette DEBUG Closed connection 355
00:21:24 INFO - 1499239284055 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49795
00:21:24 INFO - 1499239284056 Marionette DEBUG Closed connection 356
00:21:24 INFO - 1499239284057 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49796
00:21:24 INFO - 1499239284058 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:24 INFO - 1499239284058 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284060 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284063 Marionette TRACE 357 <- [1,1,null,{"sessionId":"7f2e4340-b6e5-4ebf-bced-f1e3e5ffdb81","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284064 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
00:21:24 INFO - 1499239284065 Marionette TRACE 357 <- [1,2,null,{}]
00:21:24 INFO - 1499239284066 Marionette DEBUG Closed connection 357
00:21:24 INFO - 1499239284067 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49797
00:21:24 INFO - 1499239284068 Marionette DEBUG Closed connection 358
00:21:24 INFO - 1499239284069 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49798
00:21:24 INFO - 1499239284070 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:21:24 INFO - 1499239284070 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284072 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284075 Marionette TRACE 359 <- [1,1,null,{"sessionId":"eb209229-06e0-40ee-8234-8fba1ee730b8","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284076 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49353/clicks.html"}]
00:21:24 INFO - 1499239284077 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49353/hidden.html"
00:21:24 INFO - 1499239284085 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49353/hidden.html"
00:21:24 INFO - 1499239284085 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49353/hidden.html"
00:21:24 INFO - 1499239284092 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49353/clicks.html"
00:21:24 INFO - 1499239284097 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49353/clicks.html"
00:21:24 INFO - 1499239284098 Marionette TRACE 359 <- [1,2,null,{}]
00:21:24 INFO - 1499239284101 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
00:21:24 INFO - 1499239284106 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"50ddbd12-66fb-454e-9374-889a97dc9348","ELEMENT":"50ddbd12-66fb-454e-9374-889a97dc9348"}}]
00:21:24 INFO - 1499239284112 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"50ddbd12-66fb-454e-9374-889a97dc9348"}]
00:21:24 INFO - 1499239284149 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49353/clicks.html"
00:21:24 INFO - 1499239284157 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49353/clicks.html"
00:21:24 INFO - 1499239284157 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49353/clicks.html"
00:21:24 INFO - 1499239284164 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49353/test.html"
00:21:24 INFO - 1499239284167 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49353/test.html"
00:21:24 INFO - 1499239284177 Marionette TRACE 359 <- [1,4,null,{}]
00:21:24 INFO - 1499239284184 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
00:21:24 INFO - 1499239284189 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f51fda1-4362-48a6-b888-119a82c23dd3","ELEMENT":"9f51fda1-4362-48a6-b888-119a82c23dd3"}}]
00:21:24 INFO - 1499239284190 Marionette TRACE 359 -> [0,6,"getTitle",{}]
00:21:24 INFO - 1499239284190 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
00:21:24 INFO - 1499239284192 Marionette TRACE 359 -> [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}]
00:21:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499239284195 Marionette TRACE 359 <- [1,7,null,{"value":null}]
00:21:24 INFO - 1499239284196 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
00:21:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 177ms
00:21:24 INFO - TEST-START | test_click.py TestClick.test_container_element
00:21:24 INFO - 1499239284197 Marionette TRACE 359 <- [1,8,null,{}]
00:21:24 INFO - 1499239284198 Marionette DEBUG Closed connection 359
00:21:24 INFO - 1499239284199 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49801
00:21:24 INFO - 1499239284200 Marionette DEBUG Closed connection 360
00:21:24 INFO - 1499239284201 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49802
00:21:24 INFO - 1499239284202 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:24 INFO - 1499239284202 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284205 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284208 Marionette TRACE 361 <- [1,1,null,{"sessionId":"ced76cb7-5b05-4589-957d-55108011a890","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284209 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
00:21:24 INFO - 1499239284209 Marionette TRACE 361 <- [1,2,null,{}]
00:21:24 INFO - 1499239284210 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:21:24 INFO - 1499239284210 Marionette TRACE 361 <- [1,3,null,{}]
00:21:24 INFO - 1499239284211 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
00:21:24 INFO - 1499239284212 Marionette TRACE 361 <- [1,4,null,{}]
00:21:24 INFO - 1499239284213 Marionette TRACE 361 -> [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}]
00:21:24 INFO - TEST-START: test_click.py TestClick.test_container_element1499239284216 Marionette TRACE 361 <- [1,5,null,{"value":null}]
00:21:24 INFO - 1499239284217 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
00:21:24 INFO - 1499239284218 Marionette TRACE 361 <- [1,6,null,{}]
00:21:24 INFO - 1499239284219 Marionette DEBUG Closed connection 361
00:21:24 INFO - 1499239284220 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49803
00:21:24 INFO - 1499239284220 Marionette DEBUG Closed connection 362
00:21:24 INFO - 1499239284221 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49804
00:21:24 INFO - 1499239284222 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:24 INFO - 1499239284222 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284224 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284227 Marionette TRACE 363 <- [1,1,null,{"sessionId":"af5b9541-e491-471d-81f7-af0544197ff4","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284228 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
00:21:24 INFO - 1499239284229 Marionette TRACE 363 <- [1,2,null,{}]
00:21:24 INFO - 1499239284230 Marionette DEBUG Closed connection 363
00:21:24 INFO - 1499239284231 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49805
00:21:24 INFO - 1499239284232 Marionette DEBUG Closed connection 364
00:21:24 INFO - 1499239284232 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49806
00:21:24 INFO - 1499239284234 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:21:24 INFO - 1499239284234 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284236 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284239 Marionette TRACE 365 <- [1,1,null,{"sessionId":"5c07502a-91ae-4675-bdb6-e4ab967f1904","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284240 Marionette TRACE 365 -> [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"}]
00:21:24 INFO - 1499239284241 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49353/test.html"
00:21:24 INFO - 1499239284243 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49353/test.html"
00:21:24 INFO - 1499239284244 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49353/test.html"
00:21:24 INFO - 1499239284249 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"
00:21:24 INFO - 1499239284253 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"
00:21:24 INFO - 1499239284262 Marionette TRACE 365 <- [1,2,null,{}]
00:21:24 INFO - 1499239284266 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
00:21:24 INFO - 1499239284269 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f1ad92cb-1461-47db-8c38-8113e2db588a","ELEMENT":"f1ad92cb-1461-47db-8c38-8113e2db588a"}}]
00:21:24 INFO - 1499239284274 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"f1ad92cb-1461-47db-8c38-8113e2db588a"}]
00:21:24 INFO - 1499239284512 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:21:24 INFO - 1499239284513 Marionette TRACE 365 <- [1,4,null,{}]
00:21:24 INFO - 1499239284514 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"f1ad92cb-1461-47db-8c38-8113e2db588a","name":"selected"}]
00:21:24 INFO - 1499239284518 Marionette TRACE 365 <- [1,5,null,{"value":true}]
00:21:24 INFO - 1499239284519 Marionette TRACE 365 -> [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}]
00:21:24 INFO - TEST-END: test_click.py TestClick.test_container_element1499239284522 Marionette TRACE 365 <- [1,6,null,{"value":null}]
00:21:24 INFO - 1499239284523 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
00:21:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 326ms
00:21:24 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
00:21:24 INFO - 1499239284524 Marionette TRACE 365 <- [1,7,null,{}]
00:21:24 INFO - 1499239284525 Marionette DEBUG Closed connection 365
00:21:24 INFO - 1499239284526 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49807
00:21:24 INFO - 1499239284527 Marionette DEBUG Closed connection 366
00:21:24 INFO - 1499239284528 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49808
00:21:24 INFO - 1499239284529 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:24 INFO - 1499239284529 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284531 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284534 Marionette TRACE 367 <- [1,1,null,{"sessionId":"e44dd01a-6017-4034-a388-b58656094ddf","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284535 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
00:21:24 INFO - 1499239284536 Marionette TRACE 367 <- [1,2,null,{}]
00:21:24 INFO - 1499239284537 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:21:24 INFO - 1499239284537 Marionette TRACE 367 <- [1,3,null,{}]
00:21:24 INFO - 1499239284538 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
00:21:24 INFO - 1499239284538 Marionette TRACE 367 <- [1,4,null,{}]
00:21:24 INFO - 1499239284540 Marionette TRACE 367 -> [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}]
00:21:24 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499239284542 Marionette TRACE 367 <- [1,5,null,{"value":null}]
00:21:24 INFO - 1499239284543 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
00:21:24 INFO - 1499239284544 Marionette TRACE 367 <- [1,6,null,{}]
00:21:24 INFO - 1499239284545 Marionette DEBUG Closed connection 367
00:21:24 INFO - 1499239284546 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49809
00:21:24 INFO - 1499239284547 Marionette DEBUG Closed connection 368
00:21:24 INFO - 1499239284547 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49810
00:21:24 INFO - 1499239284548 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:24 INFO - 1499239284549 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284551 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284554 Marionette TRACE 369 <- [1,1,null,{"sessionId":"251399fb-621a-4804-b140-5e3d015071f1","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284555 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
00:21:24 INFO - 1499239284556 Marionette TRACE 369 <- [1,2,null,{}]
00:21:24 INFO - 1499239284557 Marionette DEBUG Closed connection 369
00:21:24 INFO - 1499239284558 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49811
00:21:24 INFO - 1499239284558 Marionette DEBUG Closed connection 370
00:21:24 INFO - 1499239284559 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49812
00:21:24 INFO - 1499239284560 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:21:24 INFO - 1499239284561 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284563 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284565 Marionette TRACE 371 <- [1,1,null,{"sessionId":"da8e2532-b97f-4bd4-8a15-ba3800eec788","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284566 Marionette TRACE 371 -> [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"}]
00:21:24 INFO - 1499239284568 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"
00:21:24 INFO - 1499239284570 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"
00:21:24 INFO - 1499239284570 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"
00:21:24 INFO - 1499239284576 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"
00:21:24 INFO - 1499239284579 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"
00:21:24 INFO - 1499239284581 Marionette TRACE 371 <- [1,2,null,{}]
00:21:24 INFO - 1499239284583 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
00:21:24 INFO - 1499239284590 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f500481d-056b-474e-90d1-e25e0687f17c","ELEMENT":"f500481d-056b-474e-90d1-e25e0687f17c"}}]
00:21:24 INFO - 1499239284592 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"f500481d-056b-474e-90d1-e25e0687f17c"}]
00:21:24 INFO - 1499239284837 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:21:24 INFO - 1499239284838 Marionette TRACE 371 <- [1,4,null,{}]
00:21:24 INFO - 1499239284839 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"f500481d-056b-474e-90d1-e25e0687f17c","name":"selected"}]
00:21:24 INFO - 1499239284843 Marionette TRACE 371 <- [1,5,null,{"value":true}]
00:21:24 INFO - 1499239284844 Marionette TRACE 371 -> [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}]
00:21:24 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499239284847 Marionette TRACE 371 <- [1,6,null,{"value":null}]
00:21:24 INFO - 1499239284848 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
00:21:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 324ms
00:21:24 INFO - 1499239284849 Marionette TRACE 371 <- [1,7,null,{}]
00:21:24 INFO - TEST-START | test_click.py TestClick.test_css_transforms
00:21:24 INFO - 1499239284850 Marionette DEBUG Closed connection 371
00:21:24 INFO - 1499239284852 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49813
00:21:24 INFO - 1499239284853 Marionette DEBUG Closed connection 372
00:21:24 INFO - 1499239284854 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49814
00:21:24 INFO - 1499239284855 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:24 INFO - 1499239284855 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284857 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284860 Marionette TRACE 373 <- [1,1,null,{"sessionId":"656ff93a-18bc-4947-9885-007f29101dd1","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284861 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
00:21:24 INFO - 1499239284861 Marionette TRACE 373 <- [1,2,null,{}]
00:21:24 INFO - 1499239284862 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:21:24 INFO - 1499239284863 Marionette TRACE 373 <- [1,3,null,{}]
00:21:24 INFO - 1499239284863 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
00:21:24 INFO - 1499239284864 Marionette TRACE 373 <- [1,4,null,{}]
00:21:24 INFO - 1499239284865 Marionette TRACE 373 -> [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}]
00:21:24 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499239284868 Marionette TRACE 373 <- [1,5,null,{"value":null}]
00:21:24 INFO - 1499239284869 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
00:21:24 INFO - 1499239284870 Marionette TRACE 373 <- [1,6,null,{}]
00:21:24 INFO - 1499239284871 Marionette DEBUG Closed connection 373
00:21:24 INFO - 1499239284872 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49815
00:21:24 INFO - 1499239284872 Marionette DEBUG Closed connection 374
00:21:24 INFO - 1499239284873 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49816
00:21:24 INFO - 1499239284874 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:24 INFO - 1499239284875 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284876 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284879 Marionette TRACE 375 <- [1,1,null,{"sessionId":"d4c28ce4-0b26-49c0-874b-1170369c12b9","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284880 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
00:21:24 INFO - 1499239284881 Marionette TRACE 375 <- [1,2,null,{}]
00:21:24 INFO - 1499239284883 Marionette DEBUG Closed connection 375
00:21:24 INFO - 1499239284883 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49817
00:21:24 INFO - 1499239284884 Marionette DEBUG Closed connection 376
00:21:24 INFO - 1499239284885 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49818
00:21:24 INFO - 1499239284886 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:21:24 INFO - 1499239284886 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:24 INFO - 1499239284888 Marionette DEBUG Register listener.js for window 4294967297
00:21:24 INFO - 1499239284891 Marionette TRACE 377 <- [1,1,null,{"sessionId":"b967c7aa-ed79-482b-9e09-be2d3226e9da","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:24 INFO - 1499239284892 Marionette TRACE 377 -> [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"}]
00:21:24 INFO - 1499239284894 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"
00:21:24 INFO - 1499239284896 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"
00:21:24 INFO - 1499239284896 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"
00:21:24 INFO - 1499239284901 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"
00:21:24 INFO - 1499239284904 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"
00:21:24 INFO - 1499239284912 Marionette TRACE 377 <- [1,2,null,{}]
00:21:24 INFO - 1499239284916 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
00:21:24 INFO - 1499239284924 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0794b856-a4bd-4617-acfd-0dada8ba9f26","ELEMENT":"0794b856-a4bd-4617-acfd-0dada8ba9f26"}}]
00:21:24 INFO - 1499239284925 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"0794b856-a4bd-4617-acfd-0dada8ba9f26"}]
00:21:25 INFO - 1499239285162 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:21:25 INFO - 1499239285163 Marionette TRACE 377 <- [1,4,null,{}]
00:21:25 INFO - 1499239285164 Marionette TRACE 377 -> [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}]
00:21:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499239285167 Marionette TRACE 377 <- [1,5,null,{"value":null}]
00:21:25 INFO - 1499239285168 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
00:21:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 320ms
00:21:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
00:21:25 INFO - 1499239285169 Marionette TRACE 377 <- [1,6,null,{}]
00:21:25 INFO - 1499239285170 Marionette DEBUG Closed connection 377
00:21:25 INFO - 1499239285172 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49819
00:21:25 INFO - 1499239285172 Marionette DEBUG Closed connection 378
00:21:25 INFO - 1499239285173 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49820
00:21:25 INFO - 1499239285174 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:25 INFO - 1499239285175 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285177 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285180 Marionette TRACE 379 <- [1,1,null,{"sessionId":"e11dc4c5-fe8c-45b7-ac83-d7d4dfc09906","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285181 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
00:21:25 INFO - 1499239285181 Marionette TRACE 379 <- [1,2,null,{}]
00:21:25 INFO - 1499239285182 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:21:25 INFO - 1499239285182 Marionette TRACE 379 <- [1,3,null,{}]
00:21:25 INFO - 1499239285183 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
00:21:25 INFO - 1499239285184 Marionette TRACE 379 <- [1,4,null,{}]
00:21:25 INFO - 1499239285185 Marionette TRACE 379 -> [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}]
00:21:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499239285188 Marionette TRACE 379 <- [1,5,null,{"value":null}]
00:21:25 INFO - 1499239285188 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
00:21:25 INFO - 1499239285190 Marionette TRACE 379 <- [1,6,null,{}]
00:21:25 INFO - 1499239285191 Marionette DEBUG Closed connection 379
00:21:25 INFO - 1499239285192 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49821
00:21:25 INFO - 1499239285192 Marionette DEBUG Closed connection 380
00:21:25 INFO - 1499239285193 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49822
00:21:25 INFO - 1499239285194 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:25 INFO - 1499239285194 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285196 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285199 Marionette TRACE 381 <- [1,1,null,{"sessionId":"e32c754b-5135-42aa-9a28-1a01395b553c","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285200 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
00:21:25 INFO - 1499239285201 Marionette TRACE 381 <- [1,2,null,{}]
00:21:25 INFO - 1499239285202 Marionette DEBUG Closed connection 381
00:21:25 INFO - 1499239285203 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49823
00:21:25 INFO - 1499239285204 Marionette DEBUG Closed connection 382
00:21:25 INFO - 1499239285204 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49824
00:21:25 INFO - 1499239285206 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:21:25 INFO - 1499239285206 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285208 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285211 Marionette TRACE 383 <- [1,1,null,{"sessionId":"22d58927-1a2e-43ec-ac5f-122fb75e9a98","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285212 Marionette TRACE 383 -> [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"}]
00:21:25 INFO - 1499239285213 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"
00:21:25 INFO - 1499239285216 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"
00:21:25 INFO - 1499239285216 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"
00:21:25 INFO - 1499239285224 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"
00:21:25 INFO - 1499239285229 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"
00:21:25 INFO - 1499239285229 Marionette TRACE 383 <- [1,2,null,{}]
00:21:25 INFO - 1499239285232 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
00:21:25 INFO - 1499239285234 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"50801535-9c13-47e6-86f9-c0dde14d4c35","ELEMENT":"50801535-9c13-47e6-86f9-c0dde14d4c35"}}]
00:21:25 INFO - 1499239285235 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"50801535-9c13-47e6-86f9-c0dde14d4c35"}]
00:21:25 INFO - 1499239285474 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:21:25 INFO - 1499239285475 Marionette TRACE 383 <- [1,4,null,{}]
00:21:25 INFO - 1499239285477 Marionette TRACE 383 -> [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}]
00:21:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499239285479 Marionette TRACE 383 <- [1,5,null,{"value":null}]
00:21:25 INFO - 1499239285480 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
00:21:25 INFO - 1499239285482 Marionette TRACE 383 <- [1,6,null,{}]
00:21:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 312ms
00:21:25 INFO - TEST-START | test_click.py TestClick.test_input_file
00:21:25 INFO - 1499239285483 Marionette DEBUG Closed connection 383
00:21:25 INFO - 1499239285484 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49825
00:21:25 INFO - 1499239285485 Marionette DEBUG Closed connection 384
00:21:25 INFO - 1499239285486 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49826
00:21:25 INFO - 1499239285487 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:25 INFO - 1499239285487 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285489 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285492 Marionette TRACE 385 <- [1,1,null,{"sessionId":"180390e3-e897-4a55-9b88-f84ebe140b59","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285493 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
00:21:25 INFO - 1499239285494 Marionette TRACE 385 <- [1,2,null,{}]
00:21:25 INFO - 1499239285495 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:21:25 INFO - 1499239285495 Marionette TRACE 385 <- [1,3,null,{}]
00:21:25 INFO - 1499239285496 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
00:21:25 INFO - 1499239285496 Marionette TRACE 385 <- [1,4,null,{}]
00:21:25 INFO - 1499239285498 Marionette TRACE 385 -> [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}]
00:21:25 INFO - TEST-START: test_click.py TestClick.test_input_file1499239285500 Marionette TRACE 385 <- [1,5,null,{"value":null}]
00:21:25 INFO - 1499239285501 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
00:21:25 INFO - 1499239285502 Marionette TRACE 385 <- [1,6,null,{}]
00:21:25 INFO - 1499239285503 Marionette DEBUG Closed connection 385
00:21:25 INFO - 1499239285504 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49827
00:21:25 INFO - 1499239285505 Marionette DEBUG Closed connection 386
00:21:25 INFO - 1499239285506 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49828
00:21:25 INFO - 1499239285506 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:25 INFO - 1499239285507 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285509 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285511 Marionette TRACE 387 <- [1,1,null,{"sessionId":"b6a990a8-9a89-4d0b-a617-a1b016105f92","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285512 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
00:21:25 INFO - 1499239285513 Marionette TRACE 387 <- [1,2,null,{}]
00:21:25 INFO - 1499239285515 Marionette DEBUG Closed connection 387
00:21:25 INFO - 1499239285516 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49829
00:21:25 INFO - 1499239285516 Marionette DEBUG Closed connection 388
00:21:25 INFO - 1499239285517 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49830
00:21:25 INFO - 1499239285518 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:21:25 INFO - 1499239285518 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285520 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285530 Marionette TRACE 389 <- [1,1,null,{"sessionId":"bdf9b183-f69f-4bbc-8291-921025a526f7","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285531 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
00:21:25 INFO - 1499239285533 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"
00:21:25 INFO - 1499239285535 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"
00:21:25 INFO - 1499239285535 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"
00:21:25 INFO - 1499239285542 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:21:25 INFO - 1499239285546 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:21:25 INFO - 1499239285547 Marionette TRACE 389 <- [1,2,null,{}]
00:21:25 INFO - 1499239285550 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
00:21:25 INFO - 1499239285552 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"218baafd-0820-46f1-8342-0b05b694bfd0","ELEMENT":"218baafd-0820-46f1-8342-0b05b694bfd0"}}]
00:21:25 INFO - 1499239285562 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"218baafd-0820-46f1-8342-0b05b694bfd0"}]
00:21:25 INFO - 1499239285593 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
00:21:25 INFO - 1499239285595 Marionette TRACE 389 -> [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}]
00:21:25 INFO - TEST-END: test_click.py TestClick.test_input_file1499239285597 Marionette TRACE 389 <- [1,5,null,{"value":null}]
00:21:25 INFO - 1499239285598 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
00:21:25 INFO - 1499239285599 Marionette TRACE 389 <- [1,6,null,{}]
00:21:25 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 118ms
00:21:25 INFO - TEST-START | test_click.py TestClick.test_obscured_element
00:21:25 INFO - 1499239285601 Marionette DEBUG Closed connection 389
00:21:25 INFO - 1499239285602 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49831
00:21:25 INFO - 1499239285603 Marionette DEBUG Closed connection 390
00:21:25 INFO - 1499239285603 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49832
00:21:25 INFO - 1499239285604 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:25 INFO - 1499239285605 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285607 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285610 Marionette TRACE 391 <- [1,1,null,{"sessionId":"e31011ba-8357-4b4c-abae-f6295eaa97dc","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285611 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
00:21:25 INFO - 1499239285611 Marionette TRACE 391 <- [1,2,null,{}]
00:21:25 INFO - 1499239285613 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:21:25 INFO - 1499239285613 Marionette TRACE 391 <- [1,3,null,{}]
00:21:25 INFO - 1499239285614 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
00:21:25 INFO - 1499239285614 Marionette TRACE 391 <- [1,4,null,{}]
00:21:25 INFO - 1499239285616 Marionette TRACE 391 -> [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}]
00:21:25 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499239285618 Marionette TRACE 391 <- [1,5,null,{"value":null}]
00:21:25 INFO - 1499239285619 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
00:21:25 INFO - 1499239285620 Marionette TRACE 391 <- [1,6,null,{}]
00:21:25 INFO - 1499239285621 Marionette DEBUG Closed connection 391
00:21:25 INFO - 1499239285622 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49833
00:21:25 INFO - 1499239285623 Marionette DEBUG Closed connection 392
00:21:25 INFO - 1499239285624 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49834
00:21:25 INFO - 1499239285624 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:25 INFO - 1499239285625 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285627 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285629 Marionette TRACE 393 <- [1,1,null,{"sessionId":"f129cd35-9544-47e8-8905-450d419fa340","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285630 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
00:21:25 INFO - 1499239285631 Marionette TRACE 393 <- [1,2,null,{}]
00:21:25 INFO - 1499239285633 Marionette DEBUG Closed connection 393
00:21:25 INFO - 1499239285634 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49835
00:21:25 INFO - 1499239285634 Marionette DEBUG Closed connection 394
00:21:25 INFO - 1499239285635 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49836
00:21:25 INFO - 1499239285636 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:21:25 INFO - 1499239285636 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285638 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285641 Marionette TRACE 395 <- [1,1,null,{"sessionId":"1cda1f55-eabc-42ab-9d2f-fa55fab96e85","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:25 INFO - 1499239285642 Marionette TRACE 395 -> [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"}]
00:21:25 INFO - 1499239285644 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:21:25 INFO - 1499239285646 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:21:25 INFO - 1499239285646 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
00:21:25 INFO - 1499239285652 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"
00:21:25 INFO - 1499239285655 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"
00:21:25 INFO - 1499239285660 Marionette TRACE 395 <- [1,2,null,{}]
00:21:25 INFO - 1499239285664 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
00:21:25 INFO - 1499239285666 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7450739e-564a-4dbc-a5e0-1b86f87ccb3a","ELEMENT":"7450739e-564a-4dbc-a5e0-1b86f87ccb3a"}}]
00:21:25 INFO - 1499239285667 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
00:21:25 INFO - 1499239285673 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"60316a1b-1101-4c6e-9cd2-eab881dc5359","ELEMENT":"60316a1b-1101-4c6e-9cd2-eab881dc5359"}}]
00:21:25 INFO - 1499239285675 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"7450739e-564a-4dbc-a5e0-1b86f87ccb3a"}]
00:21:25 INFO - 1499239285909 Marionette DEBUG Canceled page load listener because no navigation has been detected
00:21:25 INFO - 1499239285910 Marionette TRACE 395 <- [1,5,null,{}]
00:21:25 INFO - 1499239285911 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"60316a1b-1101-4c6e-9cd2-eab881dc5359"}]
00:21:25 INFO - 1499239285924 Marionette TRACE 395 <- [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:1318:5\n"},null]
00:21:25 INFO - 1499239285926 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
00:21:25 INFO - 1499239285928 Marionette TRACE 395 <- [1,7,null,{"value":false}]
00:21:25 INFO - 1499239285930 Marionette TRACE 395 -> [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}]
00:21:25 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499239285932 Marionette TRACE 395 <- [1,8,null,{"value":null}]
00:21:25 INFO - 1499239285933 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
00:21:25 INFO - 1499239285935 Marionette TRACE 395 <- [1,9,null,{}]
00:21:25 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 335ms
00:21:25 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
00:21:25 INFO - 1499239285936 Marionette DEBUG Closed connection 395
00:21:25 INFO - 1499239285937 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49837
00:21:25 INFO - 1499239285938 Marionette DEBUG Closed connection 396
00:21:25 INFO - 1499239285938 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49838
00:21:25 INFO - 1499239285939 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:25 INFO - 1499239285940 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:25 INFO - 1499239285942 Marionette DEBUG Register listener.js for window 4294967297
00:21:25 INFO - 1499239285945 Marionette TRACE 397 <- [1,1,null,{"sessionId":"3f2e3b49-1b95-4e7b-8144-a65e34b96c0e","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:26 INFO - 1499239285946 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
00:21:26 INFO - 1499239285946 Marionette TRACE 397 <- [1,2,null,{}]
00:21:26 INFO - 1499239285947 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
00:21:26 INFO - 1499239285947 Marionette TRACE 397 <- [1,3,null,{}]
00:21:26 INFO - 1499239285948 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
00:21:26 INFO - 1499239285949 Marionette TRACE 397 <- [1,4,null,{}]
00:21:26 INFO - 1499239285950 Marionette TRACE 397 -> [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}]
00:21:26 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499239285953 Marionette TRACE 397 <- [1,5,null,{"value":null}]
00:21:26 INFO - 1499239285953 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
00:21:26 INFO - 1499239285954 Marionette TRACE 397 <- [1,6,null,{}]
00:21:26 INFO - 1499239285956 Marionette DEBUG Closed connection 397
00:21:26 INFO - 1499239285956 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49839
00:21:26 INFO - 1499239285957 Marionette DEBUG Closed connection 398
00:21:26 INFO - 1499239285958 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49840
00:21:26 INFO - 1499239285959 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
00:21:26 INFO - 1499239285959 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:26 INFO - 1499239285961 Marionette DEBUG Register listener.js for window 4294967297
00:21:26 INFO - 1499239285964 Marionette TRACE 399 <- [1,1,null,{"sessionId":"ab33e880-ebe0-481e-a184-1a57425b2393","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:26 INFO - 1499239285965 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
00:21:26 INFO - 1499239285966 Marionette TRACE 399 <- [1,2,null,{}]
00:21:26 INFO - 1499239285967 Marionette DEBUG Closed connection 399
00:21:26 INFO - 1499239285968 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49841
00:21:26 INFO - 1499239285969 Marionette DEBUG Closed connection 400
00:21:26 INFO - 1499239285970 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49842
00:21:26 INFO - 1499239285971 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
00:21:26 INFO - 1499239285971 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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}
00:21:26 INFO - 1499239285973 Marionette DEBUG Register listener.js for window 4294967297
00:21:26 INFO - 1499239285976 Marionette TRACE 401 <- [1,1,null,{"sessionId":"32197697-0945-4ad6-a6ef-95cbd162e48d","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":3900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuk1dlt.mozrunner","moz:accessibilityChecks":false}}]
00:21:26 INFO - 1499239285977 Marionette TRACE 401 -> [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"}]
00:21:26 INFO - 1499239285978 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"
00:21:26 INFO - 1499239285980 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"
00:21:26 INFO - 1499239285980 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"
00:21:26 INFO - 1499239285988 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"
00:21:26 INFO - 1499239285989 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"
00:21:26 INFO - 1499239285996 Marionette TRACE 401 <- [1,2,null,{}]
00:21:26 INFO - 1499239285998 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
00:21:26 INFO - 1499239286001 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a27fe7d2-ee5f-4598-80a2-1a501ff8bef0","ELEMENT":"a27fe7d2-ee5f-4598-80a2-1a501ff8bef0"}}]
00:21:26 INFO - 1499239286002 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a27fe7d2-ee5f-4598-80a2-1a501ff8bef0"}]
00:21:26 INFO - 1499239286025 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
00:21:26 INFO - 1499239286027 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"a27fe7d2-ee5f-4598-80a2-1a501ff8bef0"}]
00:21:26 INFO - 1499239286041 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element