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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
08:42:20 INFO - 1501083740203 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}]
08:42:20 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501083740206 Marionette TRACE 352 <- [1,5,null,{"value":null}]
08:42:20 INFO - 1501083740207 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
08:42:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 173ms
08:42:20 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
08:42:20 INFO - 1501083740208 Marionette TRACE 352 <- [1,6,null,{}]
08:42:20 INFO - 1501083740210 Marionette DEBUG Closed connection 352
08:42:20 INFO - 1501083740211 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49909
08:42:20 INFO - 1501083740211 Marionette DEBUG Closed connection 353
08:42:20 INFO - 1501083740212 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49910
08:42:20 INFO - 1501083740213 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:20 INFO - 1501083740214 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740216 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740218 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f3eca758-d141-44e9-8d2d-571825d8f2c7","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740226 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
08:42:20 INFO - 1501083740227 Marionette TRACE 354 <- [1,2,null,{}]
08:42:20 INFO - 1501083740227 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:42:20 INFO - 1501083740228 Marionette TRACE 354 <- [1,3,null,{}]
08:42:20 INFO - 1501083740229 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
08:42:20 INFO - 1501083740229 Marionette TRACE 354 <- [1,4,null,{}]
08:42:20 INFO - 1501083740231 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}]
08:42:20 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501083740233 Marionette TRACE 354 <- [1,5,null,{"value":null}]
08:42:20 INFO - 1501083740234 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
08:42:20 INFO - 1501083740236 Marionette TRACE 354 <- [1,6,null,{}]
08:42:20 INFO - 1501083740237 Marionette DEBUG Closed connection 354
08:42:20 INFO - 1501083740238 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49911
08:42:20 INFO - 1501083740239 Marionette DEBUG Closed connection 355
08:42:20 INFO - 1501083740239 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49912
08:42:20 INFO - 1501083740240 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:20 INFO - 1501083740241 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740242 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740245 Marionette TRACE 356 <- [1,1,null,{"sessionId":"949c5bc9-33e1-46f0-9f51-17436e0c4e45","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740247 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
08:42:20 INFO - 1501083740248 Marionette TRACE 356 <- [1,2,null,{}]
08:42:20 INFO - 1501083740250 Marionette DEBUG Closed connection 356
08:42:20 INFO - 1501083740250 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49913
08:42:20 INFO - 1501083740251 Marionette DEBUG Closed connection 357
08:42:20 INFO - 1501083740252 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49914
08:42:20 INFO - 1501083740253 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:42:20 INFO - 1501083740253 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740255 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740257 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a2e7f2a9-5c1b-4599-845b-d2a94938e2a9","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740259 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49477/clicks.html"}]
08:42:20 INFO - 1501083740260 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49477/hidden.html"
08:42:20 INFO - 1501083740265 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49477/hidden.html"
08:42:20 INFO - 1501083740266 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49477/hidden.html"
08:42:20 INFO - 1501083740286 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49477/clicks.html"
08:42:20 INFO - 1501083740292 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49477/clicks.html"
08:42:20 INFO - 1501083740295 Marionette TRACE 358 <- [1,2,null,{}]
08:42:20 INFO - 1501083740310 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
08:42:20 INFO - 1501083740312 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c94765d-9d8e-4564-aee9-0ee6e2c72baa","ELEMENT":"6c94765d-9d8e-4564-aee9-0ee6e2c72baa"}}]
08:42:20 INFO - 1501083740313 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"6c94765d-9d8e-4564-aee9-0ee6e2c72baa"}]
08:42:20 INFO - 1501083740352 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49477/clicks.html"
08:42:20 INFO - 1501083740359 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49477/clicks.html"
08:42:20 INFO - 1501083740360 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49477/clicks.html"
08:42:20 INFO - 1501083740377 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49477/test.html"
08:42:20 INFO - 1501083740379 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49477/test.html"
08:42:20 INFO - 1501083740381 Marionette TRACE 358 <- [1,4,null,{}]
08:42:20 INFO - 1501083740382 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
08:42:20 INFO - 1501083740393 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6cf76719-60d7-4ed6-8b90-963708605a9f","ELEMENT":"6cf76719-60d7-4ed6-8b90-963708605a9f"}}]
08:42:20 INFO - 1501083740395 Marionette TRACE 358 -> [0,6,"getTitle",{}]
08:42:20 INFO - 1501083740395 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
08:42:20 INFO - 1501083740397 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}]
08:42:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501083740399 Marionette TRACE 358 <- [1,7,null,{"value":null}]
08:42:20 INFO - 1501083740400 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
08:42:20 INFO - 1501083740401 Marionette TRACE 358 <- [1,8,null,{}]
08:42:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 193ms
08:42:20 INFO - TEST-START | test_click.py TestClick.test_container_element
08:42:20 INFO - 1501083740403 Marionette DEBUG Closed connection 358
08:42:20 INFO - 1501083740405 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49917
08:42:20 INFO - 1501083740405 Marionette DEBUG Closed connection 359
08:42:20 INFO - 1501083740406 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49918
08:42:20 INFO - 1501083740407 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:20 INFO - 1501083740407 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740409 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740412 Marionette TRACE 360 <- [1,1,null,{"sessionId":"89fffd0e-a281-492d-b888-f710bc26eb0f","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740414 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
08:42:20 INFO - 1501083740415 Marionette TRACE 360 <- [1,2,null,{}]
08:42:20 INFO - 1501083740415 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:42:20 INFO - 1501083740416 Marionette TRACE 360 <- [1,3,null,{}]
08:42:20 INFO - 1501083740417 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
08:42:20 INFO - 1501083740417 Marionette TRACE 360 <- [1,4,null,{}]
08:42:20 INFO - 1501083740419 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}]
08:42:20 INFO - TEST-START: test_click.py TestClick.test_container_element1501083740421 Marionette TRACE 360 <- [1,5,null,{"value":null}]
08:42:20 INFO - 1501083740422 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
08:42:20 INFO - 1501083740423 Marionette TRACE 360 <- [1,6,null,{}]
08:42:20 INFO - 1501083740425 Marionette DEBUG Closed connection 360
08:42:20 INFO - 1501083740425 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49919
08:42:20 INFO - 1501083740426 Marionette DEBUG Closed connection 361
08:42:20 INFO - 1501083740427 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49920
08:42:20 INFO - 1501083740428 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:20 INFO - 1501083740428 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740430 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740432 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a9de026b-9fda-4f74-ac9a-773897a08190","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740434 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
08:42:20 INFO - 1501083740436 Marionette TRACE 362 <- [1,2,null,{}]
08:42:20 INFO - 1501083740439 Marionette DEBUG Closed connection 362
08:42:20 INFO - 1501083740439 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49921
08:42:20 INFO - 1501083740440 Marionette DEBUG Closed connection 363
08:42:20 INFO - 1501083740441 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49922
08:42:20 INFO - 1501083740442 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:42:20 INFO - 1501083740442 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740444 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740446 Marionette TRACE 364 <- [1,1,null,{"sessionId":"599d7a4f-230e-4b77-a2ed-884b06c552f7","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740448 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"}]
08:42:20 INFO - 1501083740449 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49477/test.html"
08:42:20 INFO - 1501083740452 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49477/test.html"
08:42:20 INFO - 1501083740453 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49477/test.html"
08:42:20 INFO - 1501083740482 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"
08:42:20 INFO - 1501083740486 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"
08:42:20 INFO - 1501083740498 Marionette TRACE 364 <- [1,2,null,{}]
08:42:20 INFO - 1501083740499 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:42:20 INFO - 1501083740501 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f9db1905-daa6-46f5-815f-c3c11f64e70c","ELEMENT":"f9db1905-daa6-46f5-815f-c3c11f64e70c"}}]
08:42:20 INFO - 1501083740504 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"f9db1905-daa6-46f5-815f-c3c11f64e70c"}]
08:42:20 INFO - 1501083740740 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:42:20 INFO - 1501083740740 Marionette TRACE 364 <- [1,4,null,{}]
08:42:20 INFO - 1501083740741 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"f9db1905-daa6-46f5-815f-c3c11f64e70c","name":"selected"}]
08:42:20 INFO - 1501083740745 Marionette TRACE 364 <- [1,5,null,{"value":true}]
08:42:20 INFO - 1501083740747 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}]
08:42:20 INFO - TEST-END: test_click.py TestClick.test_container_element1501083740749 Marionette TRACE 364 <- [1,6,null,{"value":null}]
08:42:20 INFO - 1501083740750 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
08:42:20 INFO - 1501083740751 Marionette TRACE 364 <- [1,7,null,{}]
08:42:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 349ms
08:42:20 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
08:42:20 INFO - 1501083740753 Marionette DEBUG Closed connection 364
08:42:20 INFO - 1501083740754 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49923
08:42:20 INFO - 1501083740755 Marionette DEBUG Closed connection 365
08:42:20 INFO - 1501083740756 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49924
08:42:20 INFO - 1501083740757 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:20 INFO - 1501083740757 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740759 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740761 Marionette TRACE 366 <- [1,1,null,{"sessionId":"49c00ae7-da0b-47fe-9594-d1686db88589","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740763 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
08:42:20 INFO - 1501083740764 Marionette TRACE 366 <- [1,2,null,{}]
08:42:20 INFO - 1501083740764 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:42:20 INFO - 1501083740765 Marionette TRACE 366 <- [1,3,null,{}]
08:42:20 INFO - 1501083740766 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
08:42:20 INFO - 1501083740766 Marionette TRACE 366 <- [1,4,null,{}]
08:42:20 INFO - 1501083740768 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}]
08:42:20 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501083740770 Marionette TRACE 366 <- [1,5,null,{"value":null}]
08:42:20 INFO - 1501083740771 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
08:42:20 INFO - 1501083740772 Marionette TRACE 366 <- [1,6,null,{}]
08:42:20 INFO - 1501083740773 Marionette DEBUG Closed connection 366
08:42:20 INFO - 1501083740774 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49925
08:42:20 INFO - 1501083740775 Marionette DEBUG Closed connection 367
08:42:20 INFO - 1501083740775 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49926
08:42:20 INFO - 1501083740776 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:20 INFO - 1501083740777 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740785 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740788 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e4bf914e-7fb8-4447-bc9d-f6b7b8e65152","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740790 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
08:42:20 INFO - 1501083740791 Marionette TRACE 368 <- [1,2,null,{}]
08:42:20 INFO - 1501083740793 Marionette DEBUG Closed connection 368
08:42:20 INFO - 1501083740794 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49927
08:42:20 INFO - 1501083740794 Marionette DEBUG Closed connection 369
08:42:20 INFO - 1501083740795 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49928
08:42:20 INFO - 1501083740796 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:42:20 INFO - 1501083740796 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:20 INFO - 1501083740798 Marionette DEBUG Register listener.js for window 12
08:42:20 INFO - 1501083740800 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7f8da46d-cc23-48f2-82d8-3e6db8f41dcd","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:20 INFO - 1501083740802 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"}]
08:42:20 INFO - 1501083740804 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"
08:42:20 INFO - 1501083740805 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"
08:42:20 INFO - 1501083740806 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"
08:42:20 INFO - 1501083740816 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"
08:42:20 INFO - 1501083740820 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"
08:42:20 INFO - 1501083740828 Marionette TRACE 370 <- [1,2,null,{}]
08:42:20 INFO - 1501083740830 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:42:20 INFO - 1501083740832 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"41a8c509-c61a-4221-b2f7-1652524078bc","ELEMENT":"41a8c509-c61a-4221-b2f7-1652524078bc"}}]
08:42:20 INFO - 1501083740833 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"41a8c509-c61a-4221-b2f7-1652524078bc"}]
08:42:21 INFO - 1501083741071 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:42:21 INFO - 1501083741071 Marionette TRACE 370 <- [1,4,null,{}]
08:42:21 INFO - 1501083741073 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"41a8c509-c61a-4221-b2f7-1652524078bc","name":"selected"}]
08:42:21 INFO - 1501083741076 Marionette TRACE 370 <- [1,5,null,{"value":true}]
08:42:21 INFO - 1501083741078 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}]
08:42:21 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501083741080 Marionette TRACE 370 <- [1,6,null,{"value":null}]
08:42:21 INFO - 1501083741081 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
08:42:21 INFO - 1501083741082 Marionette TRACE 370 <- [1,7,null,{}]
08:42:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 330ms
08:42:21 INFO - TEST-START | test_click.py TestClick.test_css_transforms
08:42:21 INFO - 1501083741084 Marionette DEBUG Closed connection 370
08:42:21 INFO - 1501083741085 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49929
08:42:21 INFO - 1501083741086 Marionette DEBUG Closed connection 371
08:42:21 INFO - 1501083741086 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49930
08:42:21 INFO - 1501083741087 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:21 INFO - 1501083741088 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741089 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741092 Marionette TRACE 372 <- [1,1,null,{"sessionId":"20a297e8-c7e3-41cc-a017-87cd64340527","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741094 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
08:42:21 INFO - 1501083741094 Marionette TRACE 372 <- [1,2,null,{}]
08:42:21 INFO - 1501083741095 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:42:21 INFO - 1501083741096 Marionette TRACE 372 <- [1,3,null,{}]
08:42:21 INFO - 1501083741097 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
08:42:21 INFO - 1501083741097 Marionette TRACE 372 <- [1,4,null,{}]
08:42:21 INFO - 1501083741099 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}]
08:42:21 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501083741102 Marionette TRACE 372 <- [1,5,null,{"value":null}]
08:42:21 INFO - 1501083741103 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
08:42:21 INFO - 1501083741104 Marionette TRACE 372 <- [1,6,null,{}]
08:42:21 INFO - 1501083741105 Marionette DEBUG Closed connection 372
08:42:21 INFO - 1501083741106 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49931
08:42:21 INFO - 1501083741107 Marionette DEBUG Closed connection 373
08:42:21 INFO - 1501083741108 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49932
08:42:21 INFO - 1501083741109 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:21 INFO - 1501083741109 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741111 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741113 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6121bc5f-082a-43d8-96ee-53b9ee7fe125","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741116 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
08:42:21 INFO - 1501083741117 Marionette TRACE 374 <- [1,2,null,{}]
08:42:21 INFO - 1501083741119 Marionette DEBUG Closed connection 374
08:42:21 INFO - 1501083741119 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49933
08:42:21 INFO - 1501083741120 Marionette DEBUG Closed connection 375
08:42:21 INFO - 1501083741121 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49934
08:42:21 INFO - 1501083741122 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:42:21 INFO - 1501083741122 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741124 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741126 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6ac697c9-0bff-44f0-aed3-0023be5de6f6","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741129 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"}]
08:42:21 INFO - 1501083741130 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"
08:42:21 INFO - 1501083741133 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"
08:42:21 INFO - 1501083741134 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"
08:42:21 INFO - 1501083741155 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"
08:42:21 INFO - 1501083741157 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"
08:42:21 INFO - 1501083741160 Marionette TRACE 376 <- [1,2,null,{}]
08:42:21 INFO - 1501083741161 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
08:42:21 INFO - 1501083741164 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d8df3aa-6edd-4838-b8d7-843a6c5d6e47","ELEMENT":"8d8df3aa-6edd-4838-b8d7-843a6c5d6e47"}}]
08:42:21 INFO - 1501083741165 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8d8df3aa-6edd-4838-b8d7-843a6c5d6e47"}]
08:42:21 INFO - 1501083741403 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:42:21 INFO - 1501083741404 Marionette TRACE 376 <- [1,4,null,{}]
08:42:21 INFO - 1501083741405 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}]
08:42:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501083741408 Marionette TRACE 376 <- [1,5,null,{"value":null}]
08:42:21 INFO - 1501083741409 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
08:42:21 INFO - 1501083741410 Marionette TRACE 376 <- [1,6,null,{}]
08:42:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 328ms
08:42:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
08:42:21 INFO - 1501083741412 Marionette DEBUG Closed connection 376
08:42:21 INFO - 1501083741413 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49935
08:42:21 INFO - 1501083741414 Marionette DEBUG Closed connection 377
08:42:21 INFO - 1501083741414 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49936
08:42:21 INFO - 1501083741415 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:21 INFO - 1501083741416 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741418 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741420 Marionette TRACE 378 <- [1,1,null,{"sessionId":"7679ca19-2290-4105-b181-87f8e74356dc","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741422 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
08:42:21 INFO - 1501083741423 Marionette TRACE 378 <- [1,2,null,{}]
08:42:21 INFO - 1501083741423 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:42:21 INFO - 1501083741424 Marionette TRACE 378 <- [1,3,null,{}]
08:42:21 INFO - 1501083741425 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
08:42:21 INFO - 1501083741425 Marionette TRACE 378 <- [1,4,null,{}]
08:42:21 INFO - 1501083741427 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}]
08:42:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501083741429 Marionette TRACE 378 <- [1,5,null,{"value":null}]
08:42:21 INFO - 1501083741430 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
08:42:21 INFO - 1501083741431 Marionette TRACE 378 <- [1,6,null,{}]
08:42:21 INFO - 1501083741433 Marionette DEBUG Closed connection 378
08:42:21 INFO - 1501083741433 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49937
08:42:21 INFO - 1501083741434 Marionette DEBUG Closed connection 379
08:42:21 INFO - 1501083741435 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49938
08:42:21 INFO - 1501083741436 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:21 INFO - 1501083741436 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741438 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741446 Marionette TRACE 380 <- [1,1,null,{"sessionId":"02fcfa5a-f0fb-4064-b182-de3ca313c839","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741448 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
08:42:21 INFO - 1501083741450 Marionette TRACE 380 <- [1,2,null,{}]
08:42:21 INFO - 1501083741451 Marionette DEBUG Closed connection 380
08:42:21 INFO - 1501083741452 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49939
08:42:21 INFO - 1501083741453 Marionette DEBUG Closed connection 381
08:42:21 INFO - 1501083741453 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49940
08:42:21 INFO - 1501083741455 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:42:21 INFO - 1501083741455 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741457 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741459 Marionette TRACE 382 <- [1,1,null,{"sessionId":"9aefb6c3-93b7-436f-b949-0615fcc9c833","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741461 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"}]
08:42:21 INFO - 1501083741462 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"
08:42:21 INFO - 1501083741464 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"
08:42:21 INFO - 1501083741464 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"
08:42:21 INFO - 1501083741481 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"
08:42:21 INFO - 1501083741483 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"
08:42:21 INFO - 1501083741486 Marionette TRACE 382 <- [1,2,null,{}]
08:42:21 INFO - 1501083741494 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
08:42:21 INFO - 1501083741496 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8415be55-2e0f-493e-addd-6ee2b3178cb7","ELEMENT":"8415be55-2e0f-493e-addd-6ee2b3178cb7"}}]
08:42:21 INFO - 1501083741497 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"8415be55-2e0f-493e-addd-6ee2b3178cb7"}]
08:42:21 INFO - 1501083741735 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:42:21 INFO - 1501083741736 Marionette TRACE 382 <- [1,4,null,{}]
08:42:21 INFO - 1501083741738 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}]
08:42:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501083741740 Marionette TRACE 382 <- [1,5,null,{"value":null}]
08:42:21 INFO - 1501083741741 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
08:42:21 INFO - 1501083741742 Marionette TRACE 382 <- [1,6,null,{}]
08:42:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 332ms
08:42:21 INFO - TEST-START | test_click.py TestClick.test_input_file
08:42:21 INFO - 1501083741744 Marionette DEBUG Closed connection 382
08:42:21 INFO - 1501083741745 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49941
08:42:21 INFO - 1501083741746 Marionette DEBUG Closed connection 383
08:42:21 INFO - 1501083741746 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49942
08:42:21 INFO - 1501083741748 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:21 INFO - 1501083741748 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741750 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741752 Marionette TRACE 384 <- [1,1,null,{"sessionId":"0d4ddb7c-d248-421c-8b34-10c0e1483056","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741754 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
08:42:21 INFO - 1501083741755 Marionette TRACE 384 <- [1,2,null,{}]
08:42:21 INFO - 1501083741755 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:42:21 INFO - 1501083741756 Marionette TRACE 384 <- [1,3,null,{}]
08:42:21 INFO - 1501083741757 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
08:42:21 INFO - 1501083741757 Marionette TRACE 384 <- [1,4,null,{}]
08:42:21 INFO - 1501083741759 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}]
08:42:21 INFO - TEST-START: test_click.py TestClick.test_input_file1501083741761 Marionette TRACE 384 <- [1,5,null,{"value":null}]
08:42:21 INFO - 1501083741762 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
08:42:21 INFO - 1501083741763 Marionette TRACE 384 <- [1,6,null,{}]
08:42:21 INFO - 1501083741764 Marionette DEBUG Closed connection 384
08:42:21 INFO - 1501083741765 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49943
08:42:21 INFO - 1501083741766 Marionette DEBUG Closed connection 385
08:42:21 INFO - 1501083741766 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49944
08:42:21 INFO - 1501083741767 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:21 INFO - 1501083741768 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741769 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741772 Marionette TRACE 386 <- [1,1,null,{"sessionId":"76fe7454-9d3d-4ab3-850d-6313662fd9b9","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741774 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
08:42:21 INFO - 1501083741775 Marionette TRACE 386 <- [1,2,null,{}]
08:42:21 INFO - 1501083741777 Marionette DEBUG Closed connection 386
08:42:21 INFO - 1501083741777 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49945
08:42:21 INFO - 1501083741778 Marionette DEBUG Closed connection 387
08:42:21 INFO - 1501083741779 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49946
08:42:21 INFO - 1501083741780 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:42:21 INFO - 1501083741780 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741782 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741784 Marionette TRACE 388 <- [1,1,null,{"sessionId":"24bbc655-2d6b-4446-abff-217444f1130b","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741786 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
08:42:21 INFO - 1501083741787 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"
08:42:21 INFO - 1501083741789 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"
08:42:21 INFO - 1501083741789 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"
08:42:21 INFO - 1501083741799 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:42:21 INFO - 1501083741803 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:42:21 INFO - 1501083741812 Marionette TRACE 388 <- [1,2,null,{}]
08:42:21 INFO - 1501083741816 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
08:42:21 INFO - 1501083741819 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f632aa5-d112-4333-96ed-b8965fc13242","ELEMENT":"2f632aa5-d112-4333-96ed-b8965fc13242"}}]
08:42:21 INFO - 1501083741820 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2f632aa5-d112-4333-96ed-b8965fc13242"}]
08:42:21 INFO - 1501083741843 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
08:42:21 INFO - 1501083741851 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}]
08:42:21 INFO - TEST-END: test_click.py TestClick.test_input_file1501083741853 Marionette TRACE 388 <- [1,5,null,{"value":null}]
08:42:21 INFO - 1501083741854 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
08:42:21 INFO - 1501083741855 Marionette TRACE 388 <- [1,6,null,{}]
08:42:21 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 113ms
08:42:21 INFO - TEST-START | test_click.py TestClick.test_obscured_element
08:42:21 INFO - 1501083741857 Marionette DEBUG Closed connection 388
08:42:21 INFO - 1501083741858 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49947
08:42:21 INFO - 1501083741859 Marionette DEBUG Closed connection 389
08:42:21 INFO - 1501083741859 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49948
08:42:21 INFO - 1501083741861 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:21 INFO - 1501083741861 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741863 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741866 Marionette TRACE 390 <- [1,1,null,{"sessionId":"781dada4-3971-4923-a3f2-3e2129b64b9c","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741867 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
08:42:21 INFO - 1501083741868 Marionette TRACE 390 <- [1,2,null,{}]
08:42:21 INFO - 1501083741869 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:42:21 INFO - 1501083741869 Marionette TRACE 390 <- [1,3,null,{}]
08:42:21 INFO - 1501083741870 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
08:42:21 INFO - 1501083741870 Marionette TRACE 390 <- [1,4,null,{}]
08:42:21 INFO - 1501083741872 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}]
08:42:21 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501083741874 Marionette TRACE 390 <- [1,5,null,{"value":null}]
08:42:21 INFO - 1501083741875 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
08:42:21 INFO - 1501083741876 Marionette TRACE 390 <- [1,6,null,{}]
08:42:21 INFO - 1501083741878 Marionette DEBUG Closed connection 390
08:42:21 INFO - 1501083741879 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49949
08:42:21 INFO - 1501083741879 Marionette DEBUG Closed connection 391
08:42:21 INFO - 1501083741880 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49950
08:42:21 INFO - 1501083741881 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:21 INFO - 1501083741881 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741883 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741885 Marionette TRACE 392 <- [1,1,null,{"sessionId":"1bf0f3de-096a-4ef4-ae5a-0d05e2ffdddb","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741887 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
08:42:21 INFO - 1501083741888 Marionette TRACE 392 <- [1,2,null,{}]
08:42:21 INFO - 1501083741890 Marionette DEBUG Closed connection 392
08:42:21 INFO - 1501083741890 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49951
08:42:21 INFO - 1501083741891 Marionette DEBUG Closed connection 393
08:42:21 INFO - 1501083741892 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49952
08:42:21 INFO - 1501083741893 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:42:21 INFO - 1501083741893 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:21 INFO - 1501083741895 Marionette DEBUG Register listener.js for window 12
08:42:21 INFO - 1501083741904 Marionette TRACE 394 <- [1,1,null,{"sessionId":"50e58d33-4453-4c22-ba4e-2763b0f11e9d","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:21 INFO - 1501083741905 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"}]
08:42:21 INFO - 1501083741907 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:42:21 INFO - 1501083741909 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:42:21 INFO - 1501083741909 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:42:21 INFO - 1501083741932 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"
08:42:21 INFO - 1501083741933 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"
08:42:21 INFO - 1501083741936 Marionette TRACE 394 <- [1,2,null,{}]
08:42:21 INFO - 1501083741942 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
08:42:21 INFO - 1501083741945 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ec8839bc-c708-4b13-8477-02137db55308","ELEMENT":"ec8839bc-c708-4b13-8477-02137db55308"}}]
08:42:21 INFO - 1501083741951 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
08:42:21 INFO - 1501083741953 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"62d7c442-61bf-4f7f-811b-22b3a95b0fe7","ELEMENT":"62d7c442-61bf-4f7f-811b-22b3a95b0fe7"}}]
08:42:21 INFO - 1501083741954 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"ec8839bc-c708-4b13-8477-02137db55308"}]
08:42:22 INFO - 1501083742195 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:42:22 INFO - 1501083742195 Marionette TRACE 394 <- [1,5,null,{}]
08:42:22 INFO - 1501083742196 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"62d7c442-61bf-4f7f-811b-22b3a95b0fe7"}]
08:42:22 INFO - 1501083742210 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
08:42:22 INFO - 1501083742212 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
08:42:22 INFO - 1501083742214 Marionette TRACE 394 <- [1,7,null,{"value":false}]
08:42:22 INFO - 1501083742216 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}]
08:42:22 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501083742218 Marionette TRACE 394 <- [1,8,null,{"value":null}]
08:42:22 INFO - 1501083742219 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
08:42:22 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 365ms
08:42:22 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
08:42:22 INFO - 1501083742220 Marionette TRACE 394 <- [1,9,null,{}]
08:42:22 INFO - 1501083742222 Marionette DEBUG Closed connection 394
08:42:22 INFO - 1501083742223 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49953
08:42:22 INFO - 1501083742223 Marionette DEBUG Closed connection 395
08:42:22 INFO - 1501083742224 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49954
08:42:22 INFO - 1501083742225 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:22 INFO - 1501083742226 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:22 INFO - 1501083742227 Marionette DEBUG Register listener.js for window 12
08:42:22 INFO - 1501083742230 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8b68e7ba-bacc-4c7b-91e1-6e32dbcdecaa","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:22 INFO - 1501083742232 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
08:42:22 INFO - 1501083742232 Marionette TRACE 396 <- [1,2,null,{}]
08:42:22 INFO - 1501083742233 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:42:22 INFO - 1501083742233 Marionette TRACE 396 <- [1,3,null,{}]
08:42:22 INFO - 1501083742234 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
08:42:22 INFO - 1501083742234 Marionette TRACE 396 <- [1,4,null,{}]
08:42:22 INFO - 1501083742236 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}]
08:42:22 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501083742238 Marionette TRACE 396 <- [1,5,null,{"value":null}]
08:42:22 INFO - 1501083742239 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
08:42:22 INFO - 1501083742240 Marionette TRACE 396 <- [1,6,null,{}]
08:42:22 INFO - 1501083742242 Marionette DEBUG Closed connection 396
08:42:22 INFO - 1501083742243 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49955
08:42:22 INFO - 1501083742243 Marionette DEBUG Closed connection 397
08:42:22 INFO - 1501083742244 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49956
08:42:22 INFO - 1501083742245 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:42:22 INFO - 1501083742246 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:22 INFO - 1501083742247 Marionette DEBUG Register listener.js for window 12
08:42:22 INFO - 1501083742250 Marionette TRACE 398 <- [1,1,null,{"sessionId":"3b0108b6-afce-4ddd-bd6c-625bc235678b","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:22 INFO - 1501083742252 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
08:42:22 INFO - 1501083742253 Marionette TRACE 398 <- [1,2,null,{}]
08:42:22 INFO - 1501083742255 Marionette DEBUG Closed connection 398
08:42:22 INFO - 1501083742256 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49957
08:42:22 INFO - 1501083742256 Marionette DEBUG Closed connection 399
08:42:22 INFO - 1501083742257 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49958
08:42:22 INFO - 1501083742258 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:42:22 INFO - 1501083742258 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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}
08:42:22 INFO - 1501083742260 Marionette DEBUG Register listener.js for window 12
08:42:22 INFO - 1501083742262 Marionette TRACE 400 <- [1,1,null,{"sessionId":"67514302-e45e-4ebe-a387-023a84595fe6","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":3856,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsnfw9s.mozrunner","moz:accessibilityChecks":false}}]
08:42:22 INFO - 1501083742264 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"}]
08:42:22 INFO - 1501083742266 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"
08:42:22 INFO - 1501083742268 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"
08:42:22 INFO - 1501083742268 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"
08:42:22 INFO - 1501083742285 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"
08:42:22 INFO - 1501083742286 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"
08:42:22 INFO - 1501083742295 Marionette TRACE 400 <- [1,2,null,{}]
08:42:22 INFO - 1501083742297 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
08:42:22 INFO - 1501083742299 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e3f538c-faf4-4aac-baa7-c51b6365de27","ELEMENT":"9e3f538c-faf4-4aac-baa7-c51b6365de27"}}]
08:42:22 INFO - 1501083742300 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9e3f538c-faf4-4aac-baa7-c51b6365de27"}]
08:42:22 INFO - 1501083742322 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
08:42:22 INFO - 1501083742324 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9e3f538c-faf4-4aac-baa7-c51b6365de27"}]
08:42:22 INFO - 1501083742341 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element