could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
22:05:56 INFO - 1499835956500 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}]
22:05:56 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499835956503 Marionette TRACE 353 <- [1,5,null,{"value":null}]
22:05:56 INFO - 1499835956503 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
22:05:56 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 147ms
22:05:56 INFO - 1499835956505 Marionette TRACE 353 <- [1,6,null,{}]
22:05:56 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
22:05:56 INFO - 1499835956507 Marionette DEBUG Closed connection 353
22:05:56 INFO - 1499835956508 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49759
22:05:56 INFO - 1499835956509 Marionette DEBUG Closed connection 354
22:05:56 INFO - 1499835956510 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49760
22:05:56 INFO - 1499835956511 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:56 INFO - 1499835956512 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:56 INFO - 1499835956514 Marionette DEBUG Register listener.js for window 12
22:05:56 INFO - 1499835956518 Marionette TRACE 355 <- [1,1,null,{"sessionId":"1cd31c0b-adae-45f2-82bd-d1aff713d9e4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:56 INFO - 1499835956520 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
22:05:56 INFO - 1499835956520 Marionette TRACE 355 <- [1,2,null,{}]
22:05:56 INFO - 1499835956521 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:56 INFO - 1499835956521 Marionette TRACE 355 <- [1,3,null,{}]
22:05:56 INFO - 1499835956522 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:56 INFO - 1499835956523 Marionette TRACE 355 <- [1,4,null,{}]
22:05:56 INFO - 1499835956525 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}]
22:05:56 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499835956527 Marionette TRACE 355 <- [1,5,null,{"value":null}]
22:05:56 INFO - 1499835956528 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
22:05:56 INFO - 1499835956530 Marionette TRACE 355 <- [1,6,null,{}]
22:05:56 INFO - 1499835956532 Marionette DEBUG Closed connection 355
22:05:56 INFO - 1499835956533 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49761
22:05:56 INFO - 1499835956534 Marionette DEBUG Closed connection 356
22:05:56 INFO - 1499835956534 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49762
22:05:56 INFO - 1499835956535 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:56 INFO - 1499835956536 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:56 INFO - 1499835956538 Marionette DEBUG Register listener.js for window 12
22:05:56 INFO - 1499835956541 Marionette TRACE 357 <- [1,1,null,{"sessionId":"113b72ef-aec0-4978-b2df-2fd23f7d189b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:56 INFO - 1499835956543 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
22:05:56 INFO - 1499835956545 Marionette TRACE 357 <- [1,2,null,{}]
22:05:56 INFO - 1499835956547 Marionette DEBUG Closed connection 357
22:05:56 INFO - 1499835956547 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49763
22:05:56 INFO - 1499835956548 Marionette DEBUG Closed connection 358
22:05:56 INFO - 1499835956549 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49764
22:05:56 INFO - 1499835956550 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:56 INFO - 1499835956550 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:56 INFO - 1499835956553 Marionette DEBUG Register listener.js for window 12
22:05:56 INFO - 1499835956556 Marionette TRACE 359 <- [1,1,null,{"sessionId":"469c272e-bf2f-4e06-af8f-e629bafd951d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:56 INFO - 1499835956558 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
22:05:56 INFO - 1499835956560 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
22:05:56 INFO - 1499835956565 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
22:05:56 INFO - 1499835956566 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
22:05:56 INFO - 1499835956591 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
22:05:56 INFO - 1499835956593 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
22:05:56 INFO - 1499835956597 Marionette TRACE 359 <- [1,2,null,{}]
22:05:56 INFO - 1499835956598 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
22:05:56 INFO - 1499835956606 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"685c1fc0-19d9-4e58-aa00-93e57e85ee8d","ELEMENT":"685c1fc0-19d9-4e58-aa00-93e57e85ee8d"}}]
22:05:56 INFO - 1499835956608 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"685c1fc0-19d9-4e58-aa00-93e57e85ee8d"}]
22:05:56 INFO - 1499835956662 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
22:05:56 INFO - 1499835956672 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
22:05:56 INFO - 1499835956673 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
22:05:56 INFO - 1499835956694 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
22:05:56 INFO - 1499835956696 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
22:05:56 INFO - 1499835956698 Marionette TRACE 359 <- [1,4,null,{}]
22:05:56 INFO - 1499835956699 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
22:05:56 INFO - 1499835956709 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"65fda39b-fe12-4e09-9fba-4c0479a89e4b","ELEMENT":"65fda39b-fe12-4e09-9fba-4c0479a89e4b"}}]
22:05:56 INFO - 1499835956710 Marionette TRACE 359 -> [0,6,"getTitle",{}]
22:05:56 INFO - 1499835956710 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
22:05:56 INFO - 1499835956712 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}]
22:05:56 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499835956714 Marionette TRACE 359 <- [1,7,null,{"value":null}]
22:05:56 INFO - 1499835956715 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
22:05:56 INFO - 1499835956717 Marionette TRACE 359 <- [1,8,null,{}]
22:05:56 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 212ms
22:05:56 INFO - TEST-START | test_click.py TestClick.test_container_element
22:05:56 INFO - 1499835956720 Marionette DEBUG Closed connection 359
22:05:56 INFO - 1499835956721 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49767
22:05:56 INFO - 1499835956721 Marionette DEBUG Closed connection 360
22:05:56 INFO - 1499835956722 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49768
22:05:56 INFO - 1499835956723 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:56 INFO - 1499835956724 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:56 INFO - 1499835956726 Marionette DEBUG Register listener.js for window 12
22:05:56 INFO - 1499835956730 Marionette TRACE 361 <- [1,1,null,{"sessionId":"76e54065-9add-45d7-8699-c64c0a85c429","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:56 INFO - 1499835956732 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
22:05:56 INFO - 1499835956732 Marionette TRACE 361 <- [1,2,null,{}]
22:05:56 INFO - 1499835956733 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:56 INFO - 1499835956734 Marionette TRACE 361 <- [1,3,null,{}]
22:05:56 INFO - 1499835956734 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:56 INFO - 1499835956735 Marionette TRACE 361 <- [1,4,null,{}]
22:05:56 INFO - 1499835956736 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}]
22:05:56 INFO - TEST-START: test_click.py TestClick.test_container_element1499835956739 Marionette TRACE 361 <- [1,5,null,{"value":null}]
22:05:56 INFO - 1499835956740 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
22:05:56 INFO - 1499835956741 Marionette TRACE 361 <- [1,6,null,{}]
22:05:56 INFO - 1499835956743 Marionette DEBUG Closed connection 361
22:05:56 INFO - 1499835956744 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49769
22:05:56 INFO - 1499835956745 Marionette DEBUG Closed connection 362
22:05:56 INFO - 1499835956745 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49770
22:05:56 INFO - 1499835956746 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:56 INFO - 1499835956747 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:56 INFO - 1499835956750 Marionette DEBUG Register listener.js for window 12
22:05:56 INFO - 1499835956753 Marionette TRACE 363 <- [1,1,null,{"sessionId":"e120eddc-3fad-48a9-8d57-ae9ddc9f4f7e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:56 INFO - 1499835956755 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
22:05:56 INFO - 1499835956756 Marionette TRACE 363 <- [1,2,null,{}]
22:05:56 INFO - 1499835956759 Marionette DEBUG Closed connection 363
22:05:56 INFO - 1499835956759 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49771
22:05:56 INFO - 1499835956760 Marionette DEBUG Closed connection 364
22:05:56 INFO - 1499835956761 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49772
22:05:56 INFO - 1499835956761 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:56 INFO - 1499835956762 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:56 INFO - 1499835956765 Marionette DEBUG Register listener.js for window 12
22:05:56 INFO - 1499835956768 Marionette TRACE 365 <- [1,1,null,{"sessionId":"3d583143-974c-452e-bf69-25b679d65c97","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:56 INFO - 1499835956770 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"}]
22:05:56 INFO - 1499835956772 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
22:05:56 INFO - 1499835956774 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
22:05:56 INFO - 1499835956774 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
22:05:56 INFO - 1499835956797 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"
22:05:56 INFO - 1499835956801 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"
22:05:56 INFO - 1499835956810 Marionette TRACE 365 <- [1,2,null,{}]
22:05:56 INFO - 1499835956811 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:05:56 INFO - 1499835956813 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a5e80d40-6921-4ab6-bdd4-aadf46e34629","ELEMENT":"a5e80d40-6921-4ab6-bdd4-aadf46e34629"}}]
22:05:56 INFO - 1499835956814 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"a5e80d40-6921-4ab6-bdd4-aadf46e34629"}]
22:05:57 INFO - 1499835957057 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:57 INFO - 1499835957058 Marionette TRACE 365 <- [1,4,null,{}]
22:05:57 INFO - 1499835957059 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"a5e80d40-6921-4ab6-bdd4-aadf46e34629","name":"selected"}]
22:05:57 INFO - 1499835957064 Marionette TRACE 365 <- [1,5,null,{"value":true}]
22:05:57 INFO - 1499835957067 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}]
22:05:57 INFO - TEST-END: test_click.py TestClick.test_container_element1499835957070 Marionette TRACE 365 <- [1,6,null,{"value":null}]
22:05:57 INFO - 1499835957071 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
22:05:57 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 354ms
22:05:57 INFO - 1499835957073 Marionette TRACE 365 <- [1,7,null,{}]
22:05:57 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
22:05:57 INFO - 1499835957075 Marionette DEBUG Closed connection 365
22:05:57 INFO - 1499835957076 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49773
22:05:57 INFO - 1499835957076 Marionette DEBUG Closed connection 366
22:05:57 INFO - 1499835957077 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49774
22:05:57 INFO - 1499835957078 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:57 INFO - 1499835957079 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957082 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957085 Marionette TRACE 367 <- [1,1,null,{"sessionId":"f6e27374-d447-49ca-a589-6bb383d83b5f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957088 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
22:05:57 INFO - 1499835957089 Marionette TRACE 367 <- [1,2,null,{}]
22:05:57 INFO - 1499835957090 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:57 INFO - 1499835957091 Marionette TRACE 367 <- [1,3,null,{}]
22:05:57 INFO - 1499835957092 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:57 INFO - 1499835957092 Marionette TRACE 367 <- [1,4,null,{}]
22:05:57 INFO - 1499835957094 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}]
22:05:57 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499835957096 Marionette TRACE 367 <- [1,5,null,{"value":null}]
22:05:57 INFO - 1499835957097 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
22:05:57 INFO - 1499835957099 Marionette TRACE 367 <- [1,6,null,{}]
22:05:57 INFO - 1499835957101 Marionette DEBUG Closed connection 367
22:05:57 INFO - 1499835957102 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49775
22:05:57 INFO - 1499835957102 Marionette DEBUG Closed connection 368
22:05:57 INFO - 1499835957103 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49776
22:05:57 INFO - 1499835957104 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:57 INFO - 1499835957105 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957107 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957110 Marionette TRACE 369 <- [1,1,null,{"sessionId":"ea8f9727-aabc-4345-9291-538d80bb77df","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957112 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
22:05:57 INFO - 1499835957114 Marionette TRACE 369 <- [1,2,null,{}]
22:05:57 INFO - 1499835957116 Marionette DEBUG Closed connection 369
22:05:57 INFO - 1499835957116 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49777
22:05:57 INFO - 1499835957117 Marionette DEBUG Closed connection 370
22:05:57 INFO - 1499835957118 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49778
22:05:57 INFO - 1499835957119 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:57 INFO - 1499835957120 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957122 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957125 Marionette TRACE 371 <- [1,1,null,{"sessionId":"e68f604d-727b-48b4-a727-5c2a64045289","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957127 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"}]
22:05:57 INFO - 1499835957128 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"
22:05:57 INFO - 1499835957130 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"
22:05:57 INFO - 1499835957131 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"
22:05:57 INFO - 1499835957148 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"
22:05:57 INFO - 1499835957155 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"
22:05:57 INFO - 1499835957159 Marionette TRACE 371 <- [1,2,null,{}]
22:05:57 INFO - 1499835957160 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:05:57 INFO - 1499835957162 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3cadc76e-c0a3-4620-84aa-ee8d65c14a6f","ELEMENT":"3cadc76e-c0a3-4620-84aa-ee8d65c14a6f"}}]
22:05:57 INFO - 1499835957163 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"3cadc76e-c0a3-4620-84aa-ee8d65c14a6f"}]
22:05:57 INFO - 1499835957427 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:57 INFO - 1499835957428 Marionette TRACE 371 <- [1,4,null,{}]
22:05:57 INFO - 1499835957429 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"3cadc76e-c0a3-4620-84aa-ee8d65c14a6f","name":"selected"}]
22:05:57 INFO - 1499835957434 Marionette TRACE 371 <- [1,5,null,{"value":true}]
22:05:57 INFO - 1499835957435 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}]
22:05:57 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499835957439 Marionette TRACE 371 <- [1,6,null,{"value":null}]
22:05:57 INFO - 1499835957440 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
22:05:57 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
22:05:57 INFO - TEST-START | test_click.py TestClick.test_css_transforms
22:05:57 INFO - 1499835957441 Marionette TRACE 371 <- [1,7,null,{}]
22:05:57 INFO - 1499835957443 Marionette DEBUG Closed connection 371
22:05:57 INFO - 1499835957444 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49779
22:05:57 INFO - 1499835957445 Marionette DEBUG Closed connection 372
22:05:57 INFO - 1499835957445 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49780
22:05:57 INFO - 1499835957446 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:57 INFO - 1499835957447 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957449 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957452 Marionette TRACE 373 <- [1,1,null,{"sessionId":"660389bb-5064-4908-9d1d-cdfa577e645c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957455 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
22:05:57 INFO - 1499835957455 Marionette TRACE 373 <- [1,2,null,{}]
22:05:57 INFO - 1499835957456 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:57 INFO - 1499835957456 Marionette TRACE 373 <- [1,3,null,{}]
22:05:57 INFO - 1499835957457 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:57 INFO - 1499835957458 Marionette TRACE 373 <- [1,4,null,{}]
22:05:57 INFO - 1499835957460 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}]
22:05:57 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499835957463 Marionette TRACE 373 <- [1,5,null,{"value":null}]
22:05:57 INFO - 1499835957464 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
22:05:57 INFO - 1499835957465 Marionette TRACE 373 <- [1,6,null,{}]
22:05:57 INFO - 1499835957467 Marionette DEBUG Closed connection 373
22:05:57 INFO - 1499835957467 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49781
22:05:57 INFO - 1499835957468 Marionette DEBUG Closed connection 374
22:05:57 INFO - 1499835957469 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49782
22:05:57 INFO - 1499835957470 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:57 INFO - 1499835957471 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957473 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957476 Marionette TRACE 375 <- [1,1,null,{"sessionId":"6a87e97a-f1e1-4404-b44e-fe9c812a6ed0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957478 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
22:05:57 INFO - 1499835957479 Marionette TRACE 375 <- [1,2,null,{}]
22:05:57 INFO - 1499835957481 Marionette DEBUG Closed connection 375
22:05:57 INFO - 1499835957482 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49783
22:05:57 INFO - 1499835957483 Marionette DEBUG Closed connection 376
22:05:57 INFO - 1499835957483 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49784
22:05:57 INFO - 1499835957485 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:57 INFO - 1499835957485 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957488 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957491 Marionette TRACE 377 <- [1,1,null,{"sessionId":"ab59e0aa-a576-40d3-9bfe-10ba39bcb2aa","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957493 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"}]
22:05:57 INFO - 1499835957495 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"
22:05:57 INFO - 1499835957497 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"
22:05:57 INFO - 1499835957497 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"
22:05:57 INFO - 1499835957520 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"
22:05:57 INFO - 1499835957522 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"
22:05:57 INFO - 1499835957526 Marionette TRACE 377 <- [1,2,null,{}]
22:05:57 INFO - 1499835957527 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
22:05:57 INFO - 1499835957529 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ed4cc279-a351-4f98-bbf0-66d2bfdcbc2b","ELEMENT":"ed4cc279-a351-4f98-bbf0-66d2bfdcbc2b"}}]
22:05:57 INFO - 1499835957530 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"ed4cc279-a351-4f98-bbf0-66d2bfdcbc2b"}]
22:05:57 INFO - 1499835957783 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:57 INFO - 1499835957784 Marionette TRACE 377 <- [1,4,null,{}]
22:05:57 INFO - 1499835957786 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}]
22:05:57 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499835957789 Marionette TRACE 377 <- [1,5,null,{"value":null}]
22:05:57 INFO - 1499835957790 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
22:05:57 INFO - 1499835957791 Marionette TRACE 377 <- [1,6,null,{}]
22:05:57 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 350ms
22:05:57 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
22:05:57 INFO - 1499835957793 Marionette DEBUG Closed connection 377
22:05:57 INFO - 1499835957794 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49785
22:05:57 INFO - 1499835957795 Marionette DEBUG Closed connection 378
22:05:57 INFO - 1499835957795 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49786
22:05:57 INFO - 1499835957796 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:57 INFO - 1499835957797 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957800 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957803 Marionette TRACE 379 <- [1,1,null,{"sessionId":"a915ae51-72d3-4167-b228-39a6fab23141","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957805 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
22:05:57 INFO - 1499835957805 Marionette TRACE 379 <- [1,2,null,{}]
22:05:57 INFO - 1499835957806 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:57 INFO - 1499835957807 Marionette TRACE 379 <- [1,3,null,{}]
22:05:57 INFO - 1499835957808 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:57 INFO - 1499835957808 Marionette TRACE 379 <- [1,4,null,{}]
22:05:57 INFO - 1499835957809 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}]
22:05:57 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499835957812 Marionette TRACE 379 <- [1,5,null,{"value":null}]
22:05:57 INFO - 1499835957813 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
22:05:57 INFO - 1499835957814 Marionette TRACE 379 <- [1,6,null,{}]
22:05:57 INFO - 1499835957816 Marionette DEBUG Closed connection 379
22:05:57 INFO - 1499835957817 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49787
22:05:57 INFO - 1499835957817 Marionette DEBUG Closed connection 380
22:05:57 INFO - 1499835957818 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49788
22:05:57 INFO - 1499835957824 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:57 INFO - 1499835957825 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957827 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957831 Marionette TRACE 381 <- [1,1,null,{"sessionId":"1c0402d3-9fc3-4750-9bf6-23c887f0db84","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957833 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
22:05:57 INFO - 1499835957834 Marionette TRACE 381 <- [1,2,null,{}]
22:05:57 INFO - 1499835957836 Marionette DEBUG Closed connection 381
22:05:57 INFO - 1499835957837 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49789
22:05:57 INFO - 1499835957837 Marionette DEBUG Closed connection 382
22:05:57 INFO - 1499835957838 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49790
22:05:57 INFO - 1499835957839 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:57 INFO - 1499835957840 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:57 INFO - 1499835957842 Marionette DEBUG Register listener.js for window 12
22:05:57 INFO - 1499835957845 Marionette TRACE 383 <- [1,1,null,{"sessionId":"d8747dee-30e3-48ad-80da-ae89ff002270","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:57 INFO - 1499835957847 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"}]
22:05:57 INFO - 1499835957848 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"
22:05:57 INFO - 1499835957851 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"
22:05:57 INFO - 1499835957851 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"
22:05:57 INFO - 1499835957862 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"
22:05:57 INFO - 1499835957866 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"
22:05:57 INFO - 1499835957876 Marionette TRACE 383 <- [1,2,null,{}]
22:05:57 INFO - 1499835957878 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
22:05:57 INFO - 1499835957881 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ef3a00b9-6ee5-461d-b841-823e051fbc98","ELEMENT":"ef3a00b9-6ee5-461d-b841-823e051fbc98"}}]
22:05:57 INFO - 1499835957881 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"ef3a00b9-6ee5-461d-b841-823e051fbc98"}]
22:05:58 INFO - 1499835958126 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:58 INFO - 1499835958127 Marionette TRACE 383 <- [1,4,null,{}]
22:05:58 INFO - 1499835958129 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}]
22:05:58 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499835958132 Marionette TRACE 383 <- [1,5,null,{"value":null}]
22:05:58 INFO - 1499835958133 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
22:05:58 INFO - 1499835958134 Marionette TRACE 383 <- [1,6,null,{}]
22:05:58 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 343ms
22:05:58 INFO - TEST-START | test_click.py TestClick.test_input_file
22:05:58 INFO - 1499835958137 Marionette DEBUG Closed connection 383
22:05:58 INFO - 1499835958137 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49791
22:05:58 INFO - 1499835958138 Marionette DEBUG Closed connection 384
22:05:58 INFO - 1499835958139 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49792
22:05:58 INFO - 1499835958140 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:58 INFO - 1499835958140 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958143 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958146 Marionette TRACE 385 <- [1,1,null,{"sessionId":"b738230d-13e8-4e62-9c7e-f48cb08d6007","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958149 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
22:05:58 INFO - 1499835958149 Marionette TRACE 385 <- [1,2,null,{}]
22:05:58 INFO - 1499835958150 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:58 INFO - 1499835958150 Marionette TRACE 385 <- [1,3,null,{}]
22:05:58 INFO - 1499835958151 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:58 INFO - 1499835958152 Marionette TRACE 385 <- [1,4,null,{}]
22:05:58 INFO - 1499835958154 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}]
22:05:58 INFO - TEST-START: test_click.py TestClick.test_input_file1499835958156 Marionette TRACE 385 <- [1,5,null,{"value":null}]
22:05:58 INFO - 1499835958157 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
22:05:58 INFO - 1499835958158 Marionette TRACE 385 <- [1,6,null,{}]
22:05:58 INFO - 1499835958161 Marionette DEBUG Closed connection 385
22:05:58 INFO - 1499835958161 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49793
22:05:58 INFO - 1499835958162 Marionette DEBUG Closed connection 386
22:05:58 INFO - 1499835958163 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49794
22:05:58 INFO - 1499835958164 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:58 INFO - 1499835958164 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958167 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958170 Marionette TRACE 387 <- [1,1,null,{"sessionId":"da4b48c6-8e76-4f62-bf64-6f14700c337f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958172 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
22:05:58 INFO - 1499835958173 Marionette TRACE 387 <- [1,2,null,{}]
22:05:58 INFO - 1499835958176 Marionette DEBUG Closed connection 387
22:05:58 INFO - 1499835958176 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49795
22:05:58 INFO - 1499835958177 Marionette DEBUG Closed connection 388
22:05:58 INFO - 1499835958177 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49796
22:05:58 INFO - 1499835958179 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:58 INFO - 1499835958180 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958182 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958186 Marionette TRACE 389 <- [1,1,null,{"sessionId":"16deb7a6-5021-4d8c-9ba9-e580c9babf8f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958188 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
22:05:58 INFO - 1499835958189 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"
22:05:58 INFO - 1499835958192 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"
22:05:58 INFO - 1499835958192 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"
22:05:58 INFO - 1499835958212 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:58 INFO - 1499835958215 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:58 INFO - 1499835958219 Marionette TRACE 389 <- [1,2,null,{}]
22:05:58 INFO - 1499835958225 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
22:05:58 INFO - 1499835958227 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c179acf2-a92c-4e84-be61-581609101766","ELEMENT":"c179acf2-a92c-4e84-be61-581609101766"}}]
22:05:58 INFO - 1499835958228 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"c179acf2-a92c-4e84-be61-581609101766"}]
22:05:58 INFO - 1499835958265 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
22:05:58 INFO - 1499835958281 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}]
22:05:58 INFO - TEST-END: test_click.py TestClick.test_input_file1499835958285 Marionette TRACE 389 <- [1,5,null,{"value":null}]
22:05:58 INFO - 1499835958287 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
22:05:58 INFO - 1499835958290 Marionette TRACE 389 <- [1,6,null,{}]
22:05:58 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 155ms
22:05:58 INFO - TEST-START | test_click.py TestClick.test_obscured_element
22:05:58 INFO - 1499835958293 Marionette DEBUG Closed connection 389
22:05:58 INFO - 1499835958294 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49797
22:05:58 INFO - 1499835958295 Marionette DEBUG Closed connection 390
22:05:58 INFO - 1499835958296 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49798
22:05:58 INFO - 1499835958297 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:58 INFO - 1499835958300 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958303 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958310 Marionette TRACE 391 <- [1,1,null,{"sessionId":"aa819255-9822-4bb1-9057-c483b1c0afd5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958315 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
22:05:58 INFO - 1499835958316 Marionette TRACE 391 <- [1,2,null,{}]
22:05:58 INFO - 1499835958322 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:58 INFO - 1499835958323 Marionette TRACE 391 <- [1,3,null,{}]
22:05:58 INFO - 1499835958325 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:58 INFO - 1499835958326 Marionette TRACE 391 <- [1,4,null,{}]
22:05:58 INFO - 1499835958328 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}]
22:05:58 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499835958332 Marionette TRACE 391 <- [1,5,null,{"value":null}]
22:05:58 INFO - 1499835958334 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
22:05:58 INFO - 1499835958336 Marionette TRACE 391 <- [1,6,null,{}]
22:05:58 INFO - 1499835958339 Marionette DEBUG Closed connection 391
22:05:58 INFO - 1499835958340 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49799
22:05:58 INFO - 1499835958342 Marionette DEBUG Closed connection 392
22:05:58 INFO - 1499835958342 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49800
22:05:58 INFO - 1499835958344 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:58 INFO - 1499835958345 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958348 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958353 Marionette TRACE 393 <- [1,1,null,{"sessionId":"b8fe2939-9169-40c9-a2b7-cb596a75290f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958355 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
22:05:58 INFO - 1499835958357 Marionette TRACE 393 <- [1,2,null,{}]
22:05:58 INFO - 1499835958360 Marionette DEBUG Closed connection 393
22:05:58 INFO - 1499835958361 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49801
22:05:58 INFO - 1499835958362 Marionette DEBUG Closed connection 394
22:05:58 INFO - 1499835958363 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49802
22:05:58 INFO - 1499835958365 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:58 INFO - 1499835958367 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958370 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958375 Marionette TRACE 395 <- [1,1,null,{"sessionId":"0ab1974f-f1dd-44f6-bcc6-00c62c0097d0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958378 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"}]
22:05:58 INFO - 1499835958380 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:58 INFO - 1499835958383 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:58 INFO - 1499835958384 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:58 INFO - 1499835958419 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"
22:05:58 INFO - 1499835958422 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"
22:05:58 INFO - 1499835958431 Marionette TRACE 395 <- [1,2,null,{}]
22:05:58 INFO - 1499835958432 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
22:05:58 INFO - 1499835958436 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d8018626-4665-4868-9503-745b3de48f96","ELEMENT":"d8018626-4665-4868-9503-745b3de48f96"}}]
22:05:58 INFO - 1499835958438 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
22:05:58 INFO - 1499835958442 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bde4b9ef-94e2-47e4-b4a5-fb02dbefb87b","ELEMENT":"bde4b9ef-94e2-47e4-b4a5-fb02dbefb87b"}}]
22:05:58 INFO - 1499835958448 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"d8018626-4665-4868-9503-745b3de48f96"}]
22:05:58 INFO - 1499835958746 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:58 INFO - 1499835958747 Marionette TRACE 395 <- [1,5,null,{}]
22:05:58 INFO - 1499835958749 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"bde4b9ef-94e2-47e4-b4a5-fb02dbefb87b"}]
22:05:58 INFO - 1499835958770 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
22:05:58 INFO - 1499835958773 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
22:05:58 INFO - 1499835958777 Marionette TRACE 395 <- [1,7,null,{"value":false}]
22:05:58 INFO - 1499835958779 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}]
22:05:58 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499835958783 Marionette TRACE 395 <- [1,8,null,{"value":null}]
22:05:58 INFO - 1499835958785 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
22:05:58 INFO - 1499835958788 Marionette TRACE 395 <- [1,9,null,{}]
22:05:58 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 497ms
22:05:58 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
22:05:58 INFO - 1499835958799 Marionette DEBUG Closed connection 395
22:05:58 INFO - 1499835958801 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49803
22:05:58 INFO - 1499835958802 Marionette DEBUG Closed connection 396
22:05:58 INFO - 1499835958803 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49804
22:05:58 INFO - 1499835958804 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:58 INFO - 1499835958807 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958810 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958816 Marionette TRACE 397 <- [1,1,null,{"sessionId":"e9dddfb2-4852-486b-b0fc-d837d9d9dda3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958820 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
22:05:58 INFO - 1499835958821 Marionette TRACE 397 <- [1,2,null,{}]
22:05:58 INFO - 1499835958822 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:58 INFO - 1499835958823 Marionette TRACE 397 <- [1,3,null,{}]
22:05:58 INFO - 1499835958824 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:58 INFO - 1499835958825 Marionette TRACE 397 <- [1,4,null,{}]
22:05:58 INFO - 1499835958827 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}]
22:05:58 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499835958831 Marionette TRACE 397 <- [1,5,null,{"value":null}]
22:05:58 INFO - 1499835958832 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
22:05:58 INFO - 1499835958834 Marionette TRACE 397 <- [1,6,null,{}]
22:05:58 INFO - 1499835958839 Marionette DEBUG Closed connection 397
22:05:58 INFO - 1499835958840 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49805
22:05:58 INFO - 1499835958841 Marionette DEBUG Closed connection 398
22:05:58 INFO - 1499835958842 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49806
22:05:58 INFO - 1499835958845 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:58 INFO - 1499835958847 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958850 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958855 Marionette TRACE 399 <- [1,1,null,{"sessionId":"80242c51-4009-4b0a-8d48-cdf1416ceaf7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958858 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
22:05:58 INFO - 1499835958860 Marionette TRACE 399 <- [1,2,null,{}]
22:05:58 INFO - 1499835958862 Marionette DEBUG Closed connection 399
22:05:58 INFO - 1499835958863 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49807
22:05:58 INFO - 1499835958864 Marionette DEBUG Closed connection 400
22:05:58 INFO - 1499835958865 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49808
22:05:58 INFO - 1499835958866 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:58 INFO - 1499835958867 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}
22:05:58 INFO - 1499835958870 Marionette DEBUG Register listener.js for window 12
22:05:58 INFO - 1499835958874 Marionette TRACE 401 <- [1,1,null,{"sessionId":"a86d1834-fb03-450b-9a8a-1a72c510359c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3096,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-031.001\\AppData\\Local\\Temp\\tmp_vgpbf.mozrunner","moz:accessibilityChecks":false}}]
22:05:58 INFO - 1499835958877 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"}]
22:05:58 INFO - 1499835958879 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"
22:05:58 INFO - 1499835958883 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"
22:05:58 INFO - 1499835958884 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"
22:05:58 INFO - 1499835958908 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"
22:05:58 INFO - 1499835958911 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"
22:05:58 INFO - 1499835958916 Marionette TRACE 401 <- [1,2,null,{}]
22:05:58 INFO - 1499835958933 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
22:05:58 INFO - 1499835958936 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"db548cf7-c2b9-41cd-8e41-212ef931c2d5","ELEMENT":"db548cf7-c2b9-41cd-8e41-212ef931c2d5"}}]
22:05:58 INFO - 1499835958938 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"db548cf7-c2b9-41cd-8e41-212ef931c2d5"}]
22:05:58 INFO - 1499835958963 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
22:05:58 INFO - 1499835958965 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"db548cf7-c2b9-41cd-8e41-212ef931c2d5"}]
22:05:58 INFO - 1499835958986 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element