could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
21:27:43 INFO - 1499315263593 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
21:27:43 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499315263596 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:27:43 INFO - 1499315263597 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:27:43 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 109ms
21:27:43 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:27:43 INFO - 1499315263598 Marionette TRACE 352 <- [1,6,null,{}]
21:27:43 INFO - 1499315263600 Marionette DEBUG Closed connection 352
21:27:43 INFO - 1499315263600 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49768
21:27:43 INFO - 1499315263601 Marionette DEBUG Closed connection 353
21:27:43 INFO - 1499315263602 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49769
21:27:43 INFO - 1499315263603 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:43 INFO - 1499315263604 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:43 INFO - 1499315263606 Marionette DEBUG Register listener.js for window 4294967297
21:27:43 INFO - 1499315263609 Marionette TRACE 354 <- [1,1,null,{"sessionId":"98490e5d-2c6b-4be7-8ef1-3c8fe062f6cb","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:43 INFO - 1499315263610 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:27:43 INFO - 1499315263611 Marionette TRACE 354 <- [1,2,null,{}]
21:27:43 INFO - 1499315263612 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:27:43 INFO - 1499315263612 Marionette TRACE 354 <- [1,3,null,{}]
21:27:43 INFO - 1499315263613 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:27:43 INFO - 1499315263613 Marionette TRACE 354 <- [1,4,null,{}]
21:27:43 INFO - 1499315263615 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
21:27:43 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499315263617 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:27:43 INFO - 1499315263618 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:27:43 INFO - 1499315263619 Marionette TRACE 354 <- [1,6,null,{}]
21:27:43 INFO - 1499315263621 Marionette DEBUG Closed connection 354
21:27:43 INFO - 1499315263621 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49770
21:27:43 INFO - 1499315263622 Marionette DEBUG Closed connection 355
21:27:43 INFO - 1499315263623 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49771
21:27:43 INFO - 1499315263623 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:43 INFO - 1499315263624 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:43 INFO - 1499315263627 Marionette DEBUG Register listener.js for window 4294967297
21:27:43 INFO - 1499315263629 Marionette TRACE 356 <- [1,1,null,{"sessionId":"6990a71f-ac59-444d-b243-4fc98a97e9b9","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:43 INFO - 1499315263630 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:27:43 INFO - 1499315263632 Marionette TRACE 356 <- [1,2,null,{}]
21:27:43 INFO - 1499315263633 Marionette DEBUG Closed connection 356
21:27:43 INFO - 1499315263634 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49772
21:27:43 INFO - 1499315263634 Marionette DEBUG Closed connection 357
21:27:43 INFO - 1499315263635 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49773
21:27:43 INFO - 1499315263636 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:27:43 INFO - 1499315263636 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:43 INFO - 1499315263638 Marionette DEBUG Register listener.js for window 4294967297
21:27:43 INFO - 1499315263641 Marionette TRACE 358 <- [1,1,null,{"sessionId":"cf6eda42-e205-4e80-809e-171df45ad6f2","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:43 INFO - 1499315263643 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
21:27:43 INFO - 1499315263644 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
21:27:43 INFO - 1499315263650 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
21:27:43 INFO - 1499315263650 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
21:27:43 INFO - 1499315263659 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
21:27:43 INFO - 1499315263661 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
21:27:43 INFO - 1499315263663 Marionette TRACE 358 <- [1,2,null,{}]
21:27:43 INFO - 1499315263666 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:27:43 INFO - 1499315263668 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2d0c779d-e2df-4b6c-9bb1-faf0f1b86fce","ELEMENT":"2d0c779d-e2df-4b6c-9bb1-faf0f1b86fce"}}]
21:27:43 INFO - 1499315263669 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"2d0c779d-e2df-4b6c-9bb1-faf0f1b86fce"}]
21:27:43 INFO - 1499315263711 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
21:27:43 INFO - 1499315263717 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
21:27:43 INFO - 1499315263717 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
21:27:43 INFO - 1499315263725 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
21:27:43 INFO - 1499315263726 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
21:27:43 INFO - 1499315263730 Marionette TRACE 358 <- [1,4,null,{}]
21:27:43 INFO - 1499315263734 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:27:43 INFO - 1499315263737 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"573b9464-a3ea-44ce-b21f-be6238f5369a","ELEMENT":"573b9464-a3ea-44ce-b21f-be6238f5369a"}}]
21:27:43 INFO - 1499315263738 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:27:43 INFO - 1499315263738 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:27:43 INFO - 1499315263744 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
21:27:43 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 150ms
21:27:43 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499315263747 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:27:43 INFO - 1499315263748 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:27:43 INFO - 1499315263749 Marionette TRACE 358 <- [1,8,null,{}]
21:27:43 INFO - TEST-START | test_click.py TestClick.test_container_element
21:27:43 INFO - 1499315263750 Marionette DEBUG Closed connection 358
21:27:43 INFO - 1499315263751 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49776
21:27:43 INFO - 1499315263752 Marionette DEBUG Closed connection 359
21:27:43 INFO - 1499315263752 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49777
21:27:43 INFO - 1499315263753 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:43 INFO - 1499315263754 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:43 INFO - 1499315263756 Marionette DEBUG Register listener.js for window 4294967297
21:27:43 INFO - 1499315263759 Marionette TRACE 360 <- [1,1,null,{"sessionId":"504f9d4e-3e8b-4b86-884c-10aa86c64802","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:43 INFO - 1499315263769 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:27:43 INFO - 1499315263769 Marionette TRACE 360 <- [1,2,null,{}]
21:27:43 INFO - 1499315263770 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:27:43 INFO - 1499315263770 Marionette TRACE 360 <- [1,3,null,{}]
21:27:43 INFO - 1499315263771 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:27:43 INFO - 1499315263772 Marionette TRACE 360 <- [1,4,null,{}]
21:27:43 INFO - 1499315263774 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
21:27:43 INFO - TEST-START: test_click.py TestClick.test_container_element1499315263776 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:27:43 INFO - 1499315263777 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:27:43 INFO - 1499315263778 Marionette TRACE 360 <- [1,6,null,{}]
21:27:43 INFO - 1499315263779 Marionette DEBUG Closed connection 360
21:27:43 INFO - 1499315263780 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49778
21:27:43 INFO - 1499315263781 Marionette DEBUG Closed connection 361
21:27:43 INFO - 1499315263781 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49779
21:27:43 INFO - 1499315263782 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:43 INFO - 1499315263783 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:43 INFO - 1499315263785 Marionette DEBUG Register listener.js for window 4294967297
21:27:43 INFO - 1499315263788 Marionette TRACE 362 <- [1,1,null,{"sessionId":"cd8466b3-5a40-4a51-a82a-2667c91f3bc7","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:43 INFO - 1499315263789 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:27:43 INFO - 1499315263790 Marionette TRACE 362 <- [1,2,null,{}]
21:27:43 INFO - 1499315263791 Marionette DEBUG Closed connection 362
21:27:43 INFO - 1499315263792 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49780
21:27:43 INFO - 1499315263793 Marionette DEBUG Closed connection 363
21:27:43 INFO - 1499315263793 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49781
21:27:43 INFO - 1499315263794 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:27:43 INFO - 1499315263794 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:43 INFO - 1499315263797 Marionette DEBUG Register listener.js for window 4294967297
21:27:43 INFO - 1499315263799 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d644e447-afaf-4bc3-b47e-3da92d6d2813","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:43 INFO - 1499315263800 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:27:43 INFO - 1499315263801 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
21:27:43 INFO - 1499315263804 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
21:27:43 INFO - 1499315263805 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
21:27:43 INFO - 1499315263812 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:43 INFO - 1499315263816 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:43 INFO - 1499315263819 Marionette TRACE 364 <- [1,2,null,{}]
21:27:43 INFO - 1499315263825 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:27:43 INFO - 1499315263828 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6491b016-d5f6-42c9-a868-a3d827129c44","ELEMENT":"6491b016-d5f6-42c9-a868-a3d827129c44"}}]
21:27:43 INFO - 1499315263829 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6491b016-d5f6-42c9-a868-a3d827129c44"}]
21:27:44 INFO - 1499315264067 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:27:44 INFO - 1499315264068 Marionette TRACE 364 <- [1,4,null,{}]
21:27:44 INFO - 1499315264069 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6491b016-d5f6-42c9-a868-a3d827129c44","name":"selected"}]
21:27:44 INFO - 1499315264073 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:27:44 INFO - 1499315264075 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
21:27:44 INFO - TEST-END: test_click.py TestClick.test_container_element1499315264077 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:27:44 INFO - 1499315264078 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:27:44 INFO - 1499315264079 Marionette TRACE 364 <- [1,7,null,{}]
21:27:44 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 331ms
21:27:44 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:27:44 INFO - 1499315264080 Marionette DEBUG Closed connection 364
21:27:44 INFO - 1499315264083 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49782
21:27:44 INFO - 1499315264084 Marionette DEBUG Closed connection 365
21:27:44 INFO - 1499315264084 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49783
21:27:44 INFO - 1499315264085 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:44 INFO - 1499315264085 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264088 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264090 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ad524ba1-4e47-4465-bde7-1822a0bfef9d","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264092 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:27:44 INFO - 1499315264092 Marionette TRACE 366 <- [1,2,null,{}]
21:27:44 INFO - 1499315264093 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:27:44 INFO - 1499315264094 Marionette TRACE 366 <- [1,3,null,{}]
21:27:44 INFO - 1499315264094 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:27:44 INFO - 1499315264095 Marionette TRACE 366 <- [1,4,null,{}]
21:27:44 INFO - 1499315264096 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
21:27:44 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499315264098 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:27:44 INFO - 1499315264099 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:27:44 INFO - 1499315264100 Marionette TRACE 366 <- [1,6,null,{}]
21:27:44 INFO - 1499315264102 Marionette DEBUG Closed connection 366
21:27:44 INFO - 1499315264102 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49784
21:27:44 INFO - 1499315264103 Marionette DEBUG Closed connection 367
21:27:44 INFO - 1499315264103 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49785
21:27:44 INFO - 1499315264104 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:44 INFO - 1499315264105 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264107 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264109 Marionette TRACE 368 <- [1,1,null,{"sessionId":"5a618961-5557-4990-90e5-f7c9091db20b","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264110 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:27:44 INFO - 1499315264112 Marionette TRACE 368 <- [1,2,null,{}]
21:27:44 INFO - 1499315264113 Marionette DEBUG Closed connection 368
21:27:44 INFO - 1499315264113 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49786
21:27:44 INFO - 1499315264114 Marionette DEBUG Closed connection 369
21:27:44 INFO - 1499315264115 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49787
21:27:44 INFO - 1499315264116 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:27:44 INFO - 1499315264116 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264118 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264121 Marionette TRACE 370 <- [1,1,null,{"sessionId":"bcc585d6-ea23-4b73-8e85-3ac9a99a6302","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264122 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:27:44 INFO - 1499315264123 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264125 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264125 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264131 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264134 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264135 Marionette TRACE 370 <- [1,2,null,{}]
21:27:44 INFO - 1499315264142 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:27:44 INFO - 1499315264146 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8dc92011-0b48-412b-a66a-62f8c922a24e","ELEMENT":"8dc92011-0b48-412b-a66a-62f8c922a24e"}}]
21:27:44 INFO - 1499315264147 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"8dc92011-0b48-412b-a66a-62f8c922a24e"}]
21:27:44 INFO - 1499315264391 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:27:44 INFO - 1499315264392 Marionette TRACE 370 <- [1,4,null,{}]
21:27:44 INFO - 1499315264393 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"8dc92011-0b48-412b-a66a-62f8c922a24e","name":"selected"}]
21:27:44 INFO - 1499315264397 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:27:44 INFO - 1499315264399 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
21:27:44 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 321ms
21:27:44 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499315264401 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:27:44 INFO - 1499315264402 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:27:44 INFO - 1499315264403 Marionette TRACE 370 <- [1,7,null,{}]
21:27:44 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:27:44 INFO - 1499315264404 Marionette DEBUG Closed connection 370
21:27:44 INFO - 1499315264406 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49788
21:27:44 INFO - 1499315264407 Marionette DEBUG Closed connection 371
21:27:44 INFO - 1499315264407 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49789
21:27:44 INFO - 1499315264408 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:44 INFO - 1499315264409 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264411 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264414 Marionette TRACE 372 <- [1,1,null,{"sessionId":"42f63adf-48b7-4421-83a0-ef5cd142c88d","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264415 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:27:44 INFO - 1499315264416 Marionette TRACE 372 <- [1,2,null,{}]
21:27:44 INFO - 1499315264416 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:27:44 INFO - 1499315264417 Marionette TRACE 372 <- [1,3,null,{}]
21:27:44 INFO - 1499315264418 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:27:44 INFO - 1499315264418 Marionette TRACE 372 <- [1,4,null,{}]
21:27:44 INFO - 1499315264420 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
21:27:44 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499315264423 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:27:44 INFO - 1499315264424 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:27:44 INFO - 1499315264425 Marionette TRACE 372 <- [1,6,null,{}]
21:27:44 INFO - 1499315264426 Marionette DEBUG Closed connection 372
21:27:44 INFO - 1499315264427 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49790
21:27:44 INFO - 1499315264428 Marionette DEBUG Closed connection 373
21:27:44 INFO - 1499315264428 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49791
21:27:44 INFO - 1499315264429 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:44 INFO - 1499315264430 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264432 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264435 Marionette TRACE 374 <- [1,1,null,{"sessionId":"8ab19680-ddb1-4f8a-85d3-6d22babef542","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264436 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:27:44 INFO - 1499315264437 Marionette TRACE 374 <- [1,2,null,{}]
21:27:44 INFO - 1499315264438 Marionette DEBUG Closed connection 374
21:27:44 INFO - 1499315264439 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49792
21:27:44 INFO - 1499315264439 Marionette DEBUG Closed connection 375
21:27:44 INFO - 1499315264440 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49793
21:27:44 INFO - 1499315264441 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:27:44 INFO - 1499315264442 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264444 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264446 Marionette TRACE 376 <- [1,1,null,{"sessionId":"93fd2b68-ad52-4868-a71f-209771a08ebb","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264447 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
21:27:44 INFO - 1499315264453 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264455 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264455 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264460 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:27:44 INFO - 1499315264462 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:27:44 INFO - 1499315264467 Marionette TRACE 376 <- [1,2,null,{}]
21:27:44 INFO - 1499315264470 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:27:44 INFO - 1499315264477 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c595d8b3-5ba5-4995-8312-bdc09c261ccb","ELEMENT":"c595d8b3-5ba5-4995-8312-bdc09c261ccb"}}]
21:27:44 INFO - 1499315264478 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"c595d8b3-5ba5-4995-8312-bdc09c261ccb"}]
21:27:44 INFO - 1499315264712 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:27:44 INFO - 1499315264713 Marionette TRACE 376 <- [1,4,null,{}]
21:27:44 INFO - 1499315264715 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
21:27:44 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499315264718 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:27:44 INFO - 1499315264719 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:27:44 INFO - 1499315264720 Marionette TRACE 376 <- [1,6,null,{}]
21:27:44 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 316ms
21:27:44 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:27:44 INFO - 1499315264721 Marionette DEBUG Closed connection 376
21:27:44 INFO - 1499315264722 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49794
21:27:44 INFO - 1499315264723 Marionette DEBUG Closed connection 377
21:27:44 INFO - 1499315264724 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49795
21:27:44 INFO - 1499315264725 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:44 INFO - 1499315264725 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264727 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264730 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e102bfcb-78e9-42e3-bfc0-cbda351135be","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264731 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:27:44 INFO - 1499315264732 Marionette TRACE 378 <- [1,2,null,{}]
21:27:44 INFO - 1499315264733 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:27:44 INFO - 1499315264733 Marionette TRACE 378 <- [1,3,null,{}]
21:27:44 INFO - 1499315264734 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:27:44 INFO - 1499315264734 Marionette TRACE 378 <- [1,4,null,{}]
21:27:44 INFO - 1499315264736 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
21:27:44 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499315264739 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:27:44 INFO - 1499315264740 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:27:44 INFO - 1499315264741 Marionette TRACE 378 <- [1,6,null,{}]
21:27:44 INFO - 1499315264742 Marionette DEBUG Closed connection 378
21:27:44 INFO - 1499315264743 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49796
21:27:44 INFO - 1499315264743 Marionette DEBUG Closed connection 379
21:27:44 INFO - 1499315264744 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49797
21:27:44 INFO - 1499315264745 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:44 INFO - 1499315264745 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264747 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264750 Marionette TRACE 380 <- [1,1,null,{"sessionId":"5b7463ca-1e96-4f5e-9a7e-5d433d83e71b","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264751 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:27:44 INFO - 1499315264752 Marionette TRACE 380 <- [1,2,null,{}]
21:27:44 INFO - 1499315264753 Marionette DEBUG Closed connection 380
21:27:44 INFO - 1499315264754 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49798
21:27:44 INFO - 1499315264754 Marionette DEBUG Closed connection 381
21:27:44 INFO - 1499315264755 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49799
21:27:44 INFO - 1499315264756 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:27:44 INFO - 1499315264757 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:44 INFO - 1499315264759 Marionette DEBUG Register listener.js for window 4294967297
21:27:44 INFO - 1499315264761 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2d06486e-6ab4-44f3-8024-120b8b49ccd4","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:44 INFO - 1499315264763 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:27:44 INFO - 1499315264765 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:27:44 INFO - 1499315264766 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:27:44 INFO - 1499315264766 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:27:44 INFO - 1499315264774 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264775 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:44 INFO - 1499315264780 Marionette TRACE 382 <- [1,2,null,{}]
21:27:44 INFO - 1499315264782 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:27:44 INFO - 1499315264784 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b34b1d63-a7d0-4577-b304-f20667e2f389","ELEMENT":"b34b1d63-a7d0-4577-b304-f20667e2f389"}}]
21:27:44 INFO - 1499315264785 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"b34b1d63-a7d0-4577-b304-f20667e2f389"}]
21:27:45 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 325ms
21:27:45 INFO - 1499315265039 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:27:45 INFO - 1499315265040 Marionette TRACE 382 <- [1,4,null,{}]
21:27:45 INFO - 1499315265042 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
21:27:45 INFO - TEST-START | test_click.py TestClick.test_input_file
21:27:45 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499315265044 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:27:45 INFO - 1499315265045 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:27:45 INFO - 1499315265046 Marionette TRACE 382 <- [1,6,null,{}]
21:27:45 INFO - 1499315265048 Marionette DEBUG Closed connection 382
21:27:45 INFO - 1499315265050 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49800
21:27:45 INFO - 1499315265051 Marionette DEBUG Closed connection 383
21:27:45 INFO - 1499315265051 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49801
21:27:45 INFO - 1499315265052 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:45 INFO - 1499315265052 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265055 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265057 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e36a34c8-7cb9-4fa7-8005-8ee33b592ee1","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265058 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:27:45 INFO - 1499315265059 Marionette TRACE 384 <- [1,2,null,{}]
21:27:45 INFO - 1499315265060 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:27:45 INFO - 1499315265060 Marionette TRACE 384 <- [1,3,null,{}]
21:27:45 INFO - 1499315265061 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:27:45 INFO - 1499315265061 Marionette TRACE 384 <- [1,4,null,{}]
21:27:45 INFO - 1499315265063 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
21:27:45 INFO - TEST-START: test_click.py TestClick.test_input_file1499315265065 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:27:45 INFO - 1499315265066 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:27:45 INFO - 1499315265067 Marionette TRACE 384 <- [1,6,null,{}]
21:27:45 INFO - 1499315265069 Marionette DEBUG Closed connection 384
21:27:45 INFO - 1499315265069 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49802
21:27:45 INFO - 1499315265070 Marionette DEBUG Closed connection 385
21:27:45 INFO - 1499315265071 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49803
21:27:45 INFO - 1499315265072 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:45 INFO - 1499315265072 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265074 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265077 Marionette TRACE 386 <- [1,1,null,{"sessionId":"945f16f9-5f41-4f8e-9cb0-664ff58bc55e","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265078 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:27:45 INFO - 1499315265079 Marionette TRACE 386 <- [1,2,null,{}]
21:27:45 INFO - 1499315265080 Marionette DEBUG Closed connection 386
21:27:45 INFO - 1499315265081 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49804
21:27:45 INFO - 1499315265081 Marionette DEBUG Closed connection 387
21:27:45 INFO - 1499315265082 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49805
21:27:45 INFO - 1499315265083 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:27:45 INFO - 1499315265083 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265085 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265088 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ee24bc33-c06d-457f-bd37-6aada1352d25","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265089 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:27:45 INFO - 1499315265090 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:45 INFO - 1499315265092 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:45 INFO - 1499315265092 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:27:45 INFO - 1499315265098 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:27:45 INFO - 1499315265102 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:27:45 INFO - 1499315265103 Marionette TRACE 388 <- [1,2,null,{}]
21:27:45 INFO - 1499315265109 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:27:45 INFO - 1499315265111 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a822c493-f778-4546-8855-4eb788bdb0da","ELEMENT":"a822c493-f778-4546-8855-4eb788bdb0da"}}]
21:27:45 INFO - 1499315265112 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"a822c493-f778-4546-8855-4eb788bdb0da"}]
21:27:45 INFO - 1499315265138 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
21:27:45 INFO - 1499315265140 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
21:27:45 INFO - TEST-END: test_click.py TestClick.test_input_file1499315265142 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:27:45 INFO - 1499315265143 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:27:45 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 97ms
21:27:45 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:27:45 INFO - 1499315265144 Marionette TRACE 388 <- [1,6,null,{}]
21:27:45 INFO - 1499315265146 Marionette DEBUG Closed connection 388
21:27:45 INFO - 1499315265147 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49806
21:27:45 INFO - 1499315265147 Marionette DEBUG Closed connection 389
21:27:45 INFO - 1499315265148 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49807
21:27:45 INFO - 1499315265149 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:45 INFO - 1499315265150 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265152 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265154 Marionette TRACE 390 <- [1,1,null,{"sessionId":"a3eb1a2a-4a4e-4116-8fb7-1671f9fe6c8d","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265155 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:27:45 INFO - 1499315265156 Marionette TRACE 390 <- [1,2,null,{}]
21:27:45 INFO - 1499315265157 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:27:45 INFO - 1499315265157 Marionette TRACE 390 <- [1,3,null,{}]
21:27:45 INFO - 1499315265158 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:27:45 INFO - 1499315265158 Marionette TRACE 390 <- [1,4,null,{}]
21:27:45 INFO - 1499315265160 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
21:27:45 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499315265162 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:27:45 INFO - 1499315265163 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:27:45 INFO - 1499315265164 Marionette TRACE 390 <- [1,6,null,{}]
21:27:45 INFO - 1499315265165 Marionette DEBUG Closed connection 390
21:27:45 INFO - 1499315265166 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49808
21:27:45 INFO - 1499315265167 Marionette DEBUG Closed connection 391
21:27:45 INFO - 1499315265167 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49809
21:27:45 INFO - 1499315265168 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:45 INFO - 1499315265169 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265171 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265174 Marionette TRACE 392 <- [1,1,null,{"sessionId":"27d2f481-ccdd-4714-8700-66ee1b6dcaf8","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265174 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:27:45 INFO - 1499315265176 Marionette TRACE 392 <- [1,2,null,{}]
21:27:45 INFO - 1499315265177 Marionette DEBUG Closed connection 392
21:27:45 INFO - 1499315265177 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49810
21:27:45 INFO - 1499315265178 Marionette DEBUG Closed connection 393
21:27:45 INFO - 1499315265179 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49811
21:27:45 INFO - 1499315265180 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:27:45 INFO - 1499315265180 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265182 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265192 Marionette TRACE 394 <- [1,1,null,{"sessionId":"da40c260-566d-4f50-8024-2518a34dad6f","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265194 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
21:27:45 INFO - 1499315265195 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:27:45 INFO - 1499315265197 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:27:45 INFO - 1499315265197 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:27:45 INFO - 1499315265203 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:27:45 INFO - 1499315265206 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:27:45 INFO - 1499315265211 Marionette TRACE 394 <- [1,2,null,{}]
21:27:45 INFO - 1499315265213 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:27:45 INFO - 1499315265216 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"552471b3-48dc-49d0-9719-15dc775d3f1f","ELEMENT":"552471b3-48dc-49d0-9719-15dc775d3f1f"}}]
21:27:45 INFO - 1499315265217 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:27:45 INFO - 1499315265218 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83a7bd2b-891f-480b-a5a2-569c817bfe0e","ELEMENT":"83a7bd2b-891f-480b-a5a2-569c817bfe0e"}}]
21:27:45 INFO - 1499315265219 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"552471b3-48dc-49d0-9719-15dc775d3f1f"}]
21:27:45 INFO - 1499315265461 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:27:45 INFO - 1499315265462 Marionette TRACE 394 <- [1,5,null,{}]
21:27:45 INFO - 1499315265464 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"83a7bd2b-891f-480b-a5a2-569c817bfe0e"}]
21:27:45 INFO - 1499315265495 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
21:27:45 INFO - 1499315265503 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:27:45 INFO - 1499315265506 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:27:45 INFO - 1499315265508 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
21:27:45 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499315265515 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:27:45 INFO - 1499315265516 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:27:45 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 374ms
21:27:45 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:27:45 INFO - 1499315265519 Marionette TRACE 394 <- [1,9,null,{}]
21:27:45 INFO - 1499315265521 Marionette DEBUG Closed connection 394
21:27:45 INFO - 1499315265522 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49812
21:27:45 INFO - 1499315265524 Marionette DEBUG Closed connection 395
21:27:45 INFO - 1499315265525 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49813
21:27:45 INFO - 1499315265527 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:45 INFO - 1499315265529 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265532 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265542 Marionette TRACE 396 <- [1,1,null,{"sessionId":"781788dd-a48a-4b90-bd5d-2133f388541d","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265544 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:27:45 INFO - 1499315265544 Marionette TRACE 396 <- [1,2,null,{}]
21:27:45 INFO - 1499315265545 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:27:45 INFO - 1499315265546 Marionette TRACE 396 <- [1,3,null,{}]
21:27:45 INFO - 1499315265548 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:27:45 INFO - 1499315265549 Marionette TRACE 396 <- [1,4,null,{}]
21:27:45 INFO - 1499315265551 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
21:27:45 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499315265554 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:27:45 INFO - 1499315265555 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:27:45 INFO - 1499315265557 Marionette TRACE 396 <- [1,6,null,{}]
21:27:45 INFO - 1499315265559 Marionette DEBUG Closed connection 396
21:27:45 INFO - 1499315265560 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49814
21:27:45 INFO - 1499315265561 Marionette DEBUG Closed connection 397
21:27:45 INFO - 1499315265562 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49815
21:27:45 INFO - 1499315265564 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:27:45 INFO - 1499315265566 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265568 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265572 Marionette TRACE 398 <- [1,1,null,{"sessionId":"f02197c9-d745-4961-8221-e00b458b0574","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265574 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:27:45 INFO - 1499315265575 Marionette TRACE 398 <- [1,2,null,{}]
21:27:45 INFO - 1499315265577 Marionette DEBUG Closed connection 398
21:27:45 INFO - 1499315265577 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49816
21:27:45 INFO - 1499315265578 Marionette DEBUG Closed connection 399
21:27:45 INFO - 1499315265579 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49817
21:27:45 INFO - 1499315265580 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:27:45 INFO - 1499315265581 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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}
21:27:45 INFO - 1499315265583 Marionette DEBUG Register listener.js for window 4294967297
21:27:45 INFO - 1499315265586 Marionette TRACE 400 <- [1,1,null,{"sessionId":"7e1a5964-a2c1-47a3-a509-d6768aafe47d","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":2452,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-333.001\\AppData\\Local\\Temp\\tmp_9xmar.mozrunner","moz:accessibilityChecks":false}}]
21:27:45 INFO - 1499315265588 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
21:27:45 INFO - 1499315265590 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:27:45 INFO - 1499315265591 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:27:45 INFO - 1499315265592 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:27:45 INFO - 1499315265597 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:27:45 INFO - 1499315265599 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:27:45 INFO - 1499315265606 Marionette TRACE 400 <- [1,2,null,{}]
21:27:45 INFO - 1499315265615 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:27:45 INFO - 1499315265617 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f1e6a184-2671-4adf-a6de-a142e0a2ad71","ELEMENT":"f1e6a184-2671-4adf-a6de-a142e0a2ad71"}}]
21:27:45 INFO - 1499315265619 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f1e6a184-2671-4adf-a6de-a142e0a2ad71"}]
21:27:45 INFO - 1499315265641 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:27:45 INFO - 1499315265642 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f1e6a184-2671-4adf-a6de-a142e0a2ad71"}]
21:27:45 INFO - 1499315265657 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element