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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
11:11:51 INFO - 1499710311568 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}]
11:11:51 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499710311571 Marionette TRACE 352 <- [1,5,null,{"value":null}]
11:11:51 INFO - 1499710311572 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
11:11:51 INFO - 1499710311573 Marionette TRACE 352 <- [1,6,null,{}]
11:11:51 INFO - 1499710311575 Marionette DEBUG Closed connection 352
11:11:51 INFO - 1499710311576 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49758
11:11:51 INFO - 1499710311577 Marionette DEBUG Closed connection 353
11:11:51 INFO - 1499710311577 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49759
11:11:51 INFO - 1499710311578 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:51 INFO - 1499710311579 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:51 INFO - 1499710311582 Marionette DEBUG Register listener.js for window 12
11:11:51 INFO - 1499710311585 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f30fdccf-6fba-4274-88e1-7016397176e2","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:51 INFO - 1499710311587 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
11:11:51 INFO - 1499710311588 Marionette TRACE 354 <- [1,2,null,{}]
11:11:51 INFO - 1499710311589 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:11:51 INFO - 1499710311590 Marionette TRACE 354 <- [1,3,null,{}]
11:11:51 INFO - 1499710311591 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
11:11:51 INFO - 1499710311592 Marionette TRACE 354 <- [1,4,null,{}]
11:11:51 INFO - 1499710311593 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}]
11:11:51 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499710311596 Marionette TRACE 354 <- [1,5,null,{"value":null}]
11:11:51 INFO - 1499710311597 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
11:11:51 INFO - 1499710311598 Marionette TRACE 354 <- [1,6,null,{}]
11:11:51 INFO - 1499710311601 Marionette DEBUG Closed connection 354
11:11:51 INFO - 1499710311601 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49760
11:11:51 INFO - 1499710311602 Marionette DEBUG Closed connection 355
11:11:51 INFO - 1499710311603 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49761
11:11:51 INFO - 1499710311604 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:51 INFO - 1499710311604 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:51 INFO - 1499710311607 Marionette DEBUG Register listener.js for window 12
11:11:51 INFO - 1499710311610 Marionette TRACE 356 <- [1,1,null,{"sessionId":"c4fd1085-f451-4ab4-9847-02c1d25bb3cc","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:51 INFO - 1499710311612 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
11:11:51 INFO - 1499710311613 Marionette TRACE 356 <- [1,2,null,{}]
11:11:51 INFO - 1499710311615 Marionette DEBUG Closed connection 356
11:11:51 INFO - 1499710311616 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49762
11:11:51 INFO - 1499710311617 Marionette DEBUG Closed connection 357
11:11:51 INFO - 1499710311617 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49763
11:11:51 INFO - 1499710311618 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:11:51 INFO - 1499710311619 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:51 INFO - 1499710311622 Marionette DEBUG Register listener.js for window 12
11:11:51 INFO - 1499710311626 Marionette TRACE 358 <- [1,1,null,{"sessionId":"8b1fc734-02ce-46c5-8fd3-47522160f004","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:51 INFO - 1499710311628 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49325/clicks.html"}]
11:11:51 INFO - 1499710311629 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/hidden.html"
11:11:51 INFO - 1499710311635 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/hidden.html"
11:11:51 INFO - 1499710311635 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/hidden.html"
11:11:51 INFO - 1499710311652 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49325/clicks.html"
11:11:51 INFO - 1499710311661 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49325/clicks.html"
11:11:51 INFO - 1499710311665 Marionette TRACE 358 <- [1,2,null,{}]
11:11:51 INFO - 1499710311666 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
11:11:51 INFO - 1499710311668 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5955d8fb-df74-42a4-a3a8-1e13346193d4","ELEMENT":"5955d8fb-df74-42a4-a3a8-1e13346193d4"}}]
11:11:51 INFO - 1499710311669 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5955d8fb-df74-42a4-a3a8-1e13346193d4"}]
11:11:51 INFO - 1499710311726 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/clicks.html"
11:11:51 INFO - 1499710311731 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/clicks.html"
11:11:51 INFO - 1499710311732 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/clicks.html"
11:11:51 INFO - 1499710311750 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49325/test.html"
11:11:51 INFO - 1499710311754 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49325/test.html"
11:11:51 INFO - 1499710311762 Marionette TRACE 358 <- [1,4,null,{}]
11:11:51 INFO - 1499710311764 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
11:11:51 INFO - 1499710311766 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5449f28f-bc4c-4103-bb18-788a788f0eb8","ELEMENT":"5449f28f-bc4c-4103-bb18-788a788f0eb8"}}]
11:11:51 INFO - 1499710311767 Marionette TRACE 358 -> [0,6,"getTitle",{}]
11:11:51 INFO - 1499710311768 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
11:11:51 INFO - 1499710311769 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}]
11:11:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 205ms
11:11:51 INFO - TEST-START | test_click.py TestClick.test_container_element
11:11:51 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499710311776 Marionette TRACE 358 <- [1,7,null,{"value":null}]
11:11:51 INFO - 1499710311777 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
11:11:51 INFO - 1499710311778 Marionette TRACE 358 <- [1,8,null,{}]
11:11:51 INFO - 1499710311781 Marionette DEBUG Closed connection 358
11:11:51 INFO - 1499710311781 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49766
11:11:51 INFO - 1499710311782 Marionette DEBUG Closed connection 359
11:11:51 INFO - 1499710311783 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49767
11:11:51 INFO - 1499710311783 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:51 INFO - 1499710311784 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:51 INFO - 1499710311787 Marionette DEBUG Register listener.js for window 12
11:11:51 INFO - 1499710311792 Marionette TRACE 360 <- [1,1,null,{"sessionId":"a44b69f6-3663-4dce-bca8-467ff85bdca8","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:51 INFO - 1499710311794 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
11:11:51 INFO - 1499710311794 Marionette TRACE 360 <- [1,2,null,{}]
11:11:51 INFO - 1499710311795 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:11:51 INFO - 1499710311796 Marionette TRACE 360 <- [1,3,null,{}]
11:11:51 INFO - 1499710311796 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
11:11:51 INFO - 1499710311797 Marionette TRACE 360 <- [1,4,null,{}]
11:11:51 INFO - 1499710311798 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}]
11:11:51 INFO - TEST-START: test_click.py TestClick.test_container_element1499710311801 Marionette TRACE 360 <- [1,5,null,{"value":null}]
11:11:51 INFO - 1499710311802 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
11:11:51 INFO - 1499710311803 Marionette TRACE 360 <- [1,6,null,{}]
11:11:51 INFO - 1499710311807 Marionette DEBUG Closed connection 360
11:11:51 INFO - 1499710311807 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49768
11:11:51 INFO - 1499710311808 Marionette DEBUG Closed connection 361
11:11:51 INFO - 1499710311808 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49769
11:11:51 INFO - 1499710311809 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:51 INFO - 1499710311810 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:51 INFO - 1499710311813 Marionette DEBUG Register listener.js for window 12
11:11:51 INFO - 1499710311816 Marionette TRACE 362 <- [1,1,null,{"sessionId":"be3ee661-8be6-4f9e-b9b2-5a235c13f97a","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:51 INFO - 1499710311818 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
11:11:51 INFO - 1499710311819 Marionette TRACE 362 <- [1,2,null,{}]
11:11:51 INFO - 1499710311822 Marionette DEBUG Closed connection 362
11:11:51 INFO - 1499710311823 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49770
11:11:51 INFO - 1499710311824 Marionette DEBUG Closed connection 363
11:11:51 INFO - 1499710311824 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49771
11:11:51 INFO - 1499710311825 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:11:51 INFO - 1499710311826 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:51 INFO - 1499710311829 Marionette DEBUG Register listener.js for window 12
11:11:51 INFO - 1499710311833 Marionette TRACE 364 <- [1,1,null,{"sessionId":"4648316b-4106-4312-bb57-b2c204811222","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:51 INFO - 1499710311835 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"}]
11:11:51 INFO - 1499710311837 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/test.html"
11:11:51 INFO - 1499710311844 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/test.html"
11:11:51 INFO - 1499710311844 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/test.html"
11:11:51 INFO - 1499710311866 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"
11:11:51 INFO - 1499710311871 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"
11:11:51 INFO - 1499710311880 Marionette TRACE 364 <- [1,2,null,{}]
11:11:51 INFO - 1499710311881 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:11:51 INFO - 1499710311883 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40249fbb-dfb1-404e-92a2-b389b78d2b49","ELEMENT":"40249fbb-dfb1-404e-92a2-b389b78d2b49"}}]
11:11:51 INFO - 1499710311884 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"40249fbb-dfb1-404e-92a2-b389b78d2b49"}]
11:11:52 INFO - 1499710312127 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:11:52 INFO - 1499710312128 Marionette TRACE 364 <- [1,4,null,{}]
11:11:52 INFO - 1499710312129 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"40249fbb-dfb1-404e-92a2-b389b78d2b49","name":"selected"}]
11:11:52 INFO - 1499710312134 Marionette TRACE 364 <- [1,5,null,{"value":true}]
11:11:52 INFO - 1499710312136 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}]
11:11:52 INFO - TEST-END: test_click.py TestClick.test_container_element1499710312138 Marionette TRACE 364 <- [1,6,null,{"value":null}]
11:11:52 INFO - 1499710312139 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
11:11:52 INFO - 1499710312141 Marionette TRACE 364 <- [1,7,null,{}]
11:11:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 362ms
11:11:52 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
11:11:52 INFO - 1499710312143 Marionette DEBUG Closed connection 364
11:11:52 INFO - 1499710312143 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49772
11:11:52 INFO - 1499710312144 Marionette DEBUG Closed connection 365
11:11:52 INFO - 1499710312145 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49773
11:11:52 INFO - 1499710312146 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:52 INFO - 1499710312146 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312149 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312152 Marionette TRACE 366 <- [1,1,null,{"sessionId":"abbab34f-b098-45c2-abe9-8302c77d2f08","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312154 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
11:11:52 INFO - 1499710312155 Marionette TRACE 366 <- [1,2,null,{}]
11:11:52 INFO - 1499710312156 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:11:52 INFO - 1499710312156 Marionette TRACE 366 <- [1,3,null,{}]
11:11:52 INFO - 1499710312157 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
11:11:52 INFO - 1499710312159 Marionette TRACE 366 <- [1,4,null,{}]
11:11:52 INFO - 1499710312160 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}]
11:11:52 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499710312163 Marionette TRACE 366 <- [1,5,null,{"value":null}]
11:11:52 INFO - 1499710312164 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
11:11:52 INFO - 1499710312165 Marionette TRACE 366 <- [1,6,null,{}]
11:11:52 INFO - 1499710312168 Marionette DEBUG Closed connection 366
11:11:52 INFO - 1499710312168 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49774
11:11:52 INFO - 1499710312169 Marionette DEBUG Closed connection 367
11:11:52 INFO - 1499710312170 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49775
11:11:52 INFO - 1499710312171 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:52 INFO - 1499710312178 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312181 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312185 Marionette TRACE 368 <- [1,1,null,{"sessionId":"79c8b4fe-28ab-4891-8f5e-5b42850526a5","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312187 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
11:11:52 INFO - 1499710312189 Marionette TRACE 368 <- [1,2,null,{}]
11:11:52 INFO - 1499710312191 Marionette DEBUG Closed connection 368
11:11:52 INFO - 1499710312191 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49776
11:11:52 INFO - 1499710312192 Marionette DEBUG Closed connection 369
11:11:52 INFO - 1499710312193 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49777
11:11:52 INFO - 1499710312194 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:11:52 INFO - 1499710312194 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312197 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312200 Marionette TRACE 370 <- [1,1,null,{"sessionId":"79a6c59c-2ce4-4f2d-84a2-57bfe156bdee","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312202 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"}]
11:11:52 INFO - 1499710312204 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"
11:11:52 INFO - 1499710312206 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"
11:11:52 INFO - 1499710312206 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"
11:11:52 INFO - 1499710312217 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"
11:11:52 INFO - 1499710312223 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"
11:11:52 INFO - 1499710312234 Marionette TRACE 370 <- [1,2,null,{}]
11:11:52 INFO - 1499710312236 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:11:52 INFO - 1499710312239 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"10beb62a-15e3-4f57-a04f-a9fbea5050b8","ELEMENT":"10beb62a-15e3-4f57-a04f-a9fbea5050b8"}}]
11:11:52 INFO - 1499710312240 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"10beb62a-15e3-4f57-a04f-a9fbea5050b8"}]
11:11:52 INFO - 1499710312483 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:11:52 INFO - 1499710312483 Marionette TRACE 370 <- [1,4,null,{}]
11:11:52 INFO - 1499710312485 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"10beb62a-15e3-4f57-a04f-a9fbea5050b8","name":"selected"}]
11:11:52 INFO - 1499710312489 Marionette TRACE 370 <- [1,5,null,{"value":true}]
11:11:52 INFO - 1499710312490 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}]
11:11:52 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499710312493 Marionette TRACE 370 <- [1,6,null,{"value":null}]
11:11:52 INFO - 1499710312494 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
11:11:52 INFO - 1499710312495 Marionette TRACE 370 <- [1,7,null,{}]
11:11:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 354ms
11:11:52 INFO - TEST-START | test_click.py TestClick.test_css_transforms
11:11:52 INFO - 1499710312497 Marionette DEBUG Closed connection 370
11:11:52 INFO - 1499710312498 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49778
11:11:52 INFO - 1499710312499 Marionette DEBUG Closed connection 371
11:11:52 INFO - 1499710312499 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49779
11:11:52 INFO - 1499710312500 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:52 INFO - 1499710312501 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312504 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312507 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ec4983a4-b43e-4953-8302-df49a7f997e7","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312509 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
11:11:52 INFO - 1499710312509 Marionette TRACE 372 <- [1,2,null,{}]
11:11:52 INFO - 1499710312510 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:11:52 INFO - 1499710312511 Marionette TRACE 372 <- [1,3,null,{}]
11:11:52 INFO - 1499710312512 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
11:11:52 INFO - 1499710312512 Marionette TRACE 372 <- [1,4,null,{}]
11:11:52 INFO - 1499710312514 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}]
11:11:52 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499710312517 Marionette TRACE 372 <- [1,5,null,{"value":null}]
11:11:52 INFO - 1499710312518 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
11:11:52 INFO - 1499710312519 Marionette TRACE 372 <- [1,6,null,{}]
11:11:52 INFO - 1499710312521 Marionette DEBUG Closed connection 372
11:11:52 INFO - 1499710312522 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49780
11:11:52 INFO - 1499710312522 Marionette DEBUG Closed connection 373
11:11:52 INFO - 1499710312523 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49781
11:11:52 INFO - 1499710312524 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:52 INFO - 1499710312525 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312528 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312530 Marionette TRACE 374 <- [1,1,null,{"sessionId":"5da87a7c-05a2-47da-93ea-79da33e9f275","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312533 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
11:11:52 INFO - 1499710312534 Marionette TRACE 374 <- [1,2,null,{}]
11:11:52 INFO - 1499710312537 Marionette DEBUG Closed connection 374
11:11:52 INFO - 1499710312537 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49782
11:11:52 INFO - 1499710312539 Marionette DEBUG Closed connection 375
11:11:52 INFO - 1499710312540 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49783
11:11:52 INFO - 1499710312541 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:11:52 INFO - 1499710312542 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312544 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312547 Marionette TRACE 376 <- [1,1,null,{"sessionId":"61dcc7b3-b722-4ecd-9d3d-0b48d12b18fe","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312549 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"}]
11:11:52 INFO - 1499710312551 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"
11:11:52 INFO - 1499710312553 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"
11:11:52 INFO - 1499710312553 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"
11:11:52 INFO - 1499710312575 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"
11:11:52 INFO - 1499710312578 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"
11:11:52 INFO - 1499710312581 Marionette TRACE 376 <- [1,2,null,{}]
11:11:52 INFO - 1499710312583 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
11:11:52 INFO - 1499710312585 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"233b8e38-7ff2-4e1a-922c-e7003c0fdb59","ELEMENT":"233b8e38-7ff2-4e1a-922c-e7003c0fdb59"}}]
11:11:52 INFO - 1499710312586 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"233b8e38-7ff2-4e1a-922c-e7003c0fdb59"}]
11:11:52 INFO - 1499710312843 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:11:52 INFO - 1499710312843 Marionette TRACE 376 <- [1,4,null,{}]
11:11:52 INFO - 1499710312845 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}]
11:11:52 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499710312848 Marionette TRACE 376 <- [1,5,null,{"value":null}]
11:11:52 INFO - 1499710312849 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
11:11:52 INFO - 1499710312850 Marionette TRACE 376 <- [1,6,null,{}]
11:11:52 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 354ms
11:11:52 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
11:11:52 INFO - 1499710312852 Marionette DEBUG Closed connection 376
11:11:52 INFO - 1499710312853 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49784
11:11:52 INFO - 1499710312854 Marionette DEBUG Closed connection 377
11:11:52 INFO - 1499710312854 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49785
11:11:52 INFO - 1499710312855 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:52 INFO - 1499710312856 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312859 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312861 Marionette TRACE 378 <- [1,1,null,{"sessionId":"24c793e8-fda2-494b-b1cc-31287143295a","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312864 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
11:11:52 INFO - 1499710312864 Marionette TRACE 378 <- [1,2,null,{}]
11:11:52 INFO - 1499710312865 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:11:52 INFO - 1499710312866 Marionette TRACE 378 <- [1,3,null,{}]
11:11:52 INFO - 1499710312867 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
11:11:52 INFO - 1499710312867 Marionette TRACE 378 <- [1,4,null,{}]
11:11:52 INFO - 1499710312869 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}]
11:11:52 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499710312871 Marionette TRACE 378 <- [1,5,null,{"value":null}]
11:11:52 INFO - 1499710312872 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
11:11:52 INFO - 1499710312874 Marionette TRACE 378 <- [1,6,null,{}]
11:11:52 INFO - 1499710312876 Marionette DEBUG Closed connection 378
11:11:52 INFO - 1499710312876 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49786
11:11:52 INFO - 1499710312877 Marionette DEBUG Closed connection 379
11:11:52 INFO - 1499710312878 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49787
11:11:52 INFO - 1499710312879 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:52 INFO - 1499710312879 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312882 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312885 Marionette TRACE 380 <- [1,1,null,{"sessionId":"4676fd47-59b4-4fb8-a8e7-fe8ec2f7b4bf","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312887 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
11:11:52 INFO - 1499710312889 Marionette TRACE 380 <- [1,2,null,{}]
11:11:52 INFO - 1499710312891 Marionette DEBUG Closed connection 380
11:11:52 INFO - 1499710312891 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49788
11:11:52 INFO - 1499710312892 Marionette DEBUG Closed connection 381
11:11:52 INFO - 1499710312893 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49789
11:11:52 INFO - 1499710312894 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:11:52 INFO - 1499710312895 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:52 INFO - 1499710312897 Marionette DEBUG Register listener.js for window 12
11:11:52 INFO - 1499710312900 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d895662e-832f-4724-a2f0-085e107536ad","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:52 INFO - 1499710312902 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"}]
11:11:52 INFO - 1499710312904 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"
11:11:52 INFO - 1499710312906 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"
11:11:52 INFO - 1499710312907 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"
11:11:52 INFO - 1499710312918 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"
11:11:52 INFO - 1499710312930 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"
11:11:52 INFO - 1499710312933 Marionette TRACE 382 <- [1,2,null,{}]
11:11:52 INFO - 1499710312934 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
11:11:52 INFO - 1499710312937 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5b0bd4e4-344a-4c81-9fef-b321585135ce","ELEMENT":"5b0bd4e4-344a-4c81-9fef-b321585135ce"}}]
11:11:52 INFO - 1499710312944 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"5b0bd4e4-344a-4c81-9fef-b321585135ce"}]
11:11:53 INFO - 1499710313190 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:11:53 INFO - 1499710313190 Marionette TRACE 382 <- [1,4,null,{}]
11:11:53 INFO - 1499710313193 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}]
11:11:53 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499710313195 Marionette TRACE 382 <- [1,5,null,{"value":null}]
11:11:53 INFO - 1499710313196 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
11:11:53 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 347ms
11:11:53 INFO - TEST-START | test_click.py TestClick.test_input_file
11:11:53 INFO - 1499710313198 Marionette TRACE 382 <- [1,6,null,{}]
11:11:53 INFO - 1499710313200 Marionette DEBUG Closed connection 382
11:11:53 INFO - 1499710313200 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49790
11:11:53 INFO - 1499710313201 Marionette DEBUG Closed connection 383
11:11:53 INFO - 1499710313202 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49791
11:11:53 INFO - 1499710313203 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:53 INFO - 1499710313203 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313206 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313209 Marionette TRACE 384 <- [1,1,null,{"sessionId":"3a100f35-1bbe-4cf0-8227-73fdd46e1af8","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313211 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
11:11:53 INFO - 1499710313211 Marionette TRACE 384 <- [1,2,null,{}]
11:11:53 INFO - 1499710313212 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:11:53 INFO - 1499710313212 Marionette TRACE 384 <- [1,3,null,{}]
11:11:53 INFO - 1499710313213 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
11:11:53 INFO - 1499710313214 Marionette TRACE 384 <- [1,4,null,{}]
11:11:53 INFO - 1499710313216 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}]
11:11:53 INFO - TEST-START: test_click.py TestClick.test_input_file1499710313218 Marionette TRACE 384 <- [1,5,null,{"value":null}]
11:11:53 INFO - 1499710313219 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
11:11:53 INFO - 1499710313221 Marionette TRACE 384 <- [1,6,null,{}]
11:11:53 INFO - 1499710313223 Marionette DEBUG Closed connection 384
11:11:53 INFO - 1499710313223 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49792
11:11:53 INFO - 1499710313224 Marionette DEBUG Closed connection 385
11:11:53 INFO - 1499710313225 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49793
11:11:53 INFO - 1499710313226 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:53 INFO - 1499710313227 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313229 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313232 Marionette TRACE 386 <- [1,1,null,{"sessionId":"22f1610d-e4b3-47e5-919b-bbb7e335b569","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313286 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
11:11:53 INFO - 1499710313289 Marionette TRACE 386 <- [1,2,null,{}]
11:11:53 INFO - 1499710313297 Marionette DEBUG Closed connection 386
11:11:53 INFO - 1499710313298 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49794
11:11:53 INFO - 1499710313299 Marionette DEBUG Closed connection 387
11:11:53 INFO - 1499710313300 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49795
11:11:53 INFO - 1499710313302 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:11:53 INFO - 1499710313305 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313308 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313315 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1eaf88ff-0f49-4f87-a0e8-01c11a93147d","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313320 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
11:11:53 INFO - 1499710313322 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"
11:11:53 INFO - 1499710313326 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"
11:11:53 INFO - 1499710313327 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"
11:11:53 INFO - 1499710313349 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:11:53 INFO - 1499710313354 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:11:53 INFO - 1499710313365 Marionette TRACE 388 <- [1,2,null,{}]
11:11:53 INFO - 1499710313367 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
11:11:53 INFO - 1499710313372 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a423689-f87f-4724-a1b7-5582b1ff4558","ELEMENT":"8a423689-f87f-4724-a1b7-5582b1ff4558"}}]
11:11:53 INFO - 1499710313375 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"8a423689-f87f-4724-a1b7-5582b1ff4558"}]
11:11:53 INFO - 1499710313411 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
11:11:53 INFO - 1499710313424 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}]
11:11:53 INFO - TEST-END: test_click.py TestClick.test_input_file1499710313427 Marionette TRACE 388 <- [1,5,null,{"value":null}]
11:11:53 INFO - 1499710313429 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
11:11:53 INFO - 1499710313431 Marionette TRACE 388 <- [1,6,null,{}]
11:11:53 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 233ms
11:11:53 INFO - TEST-START | test_click.py TestClick.test_obscured_element
11:11:53 INFO - 1499710313433 Marionette DEBUG Closed connection 388
11:11:53 INFO - 1499710313435 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49796
11:11:53 INFO - 1499710313436 Marionette DEBUG Closed connection 389
11:11:53 INFO - 1499710313437 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49797
11:11:53 INFO - 1499710313438 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:53 INFO - 1499710313439 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313443 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313447 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1e045af4-4bd7-46e1-b7da-70363a6d844a","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313450 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
11:11:53 INFO - 1499710313451 Marionette TRACE 390 <- [1,2,null,{}]
11:11:53 INFO - 1499710313452 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:11:53 INFO - 1499710313453 Marionette TRACE 390 <- [1,3,null,{}]
11:11:53 INFO - 1499710313455 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
11:11:53 INFO - 1499710313457 Marionette TRACE 390 <- [1,4,null,{}]
11:11:53 INFO - 1499710313459 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}]
11:11:53 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499710313463 Marionette TRACE 390 <- [1,5,null,{"value":null}]
11:11:53 INFO - 1499710313464 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
11:11:53 INFO - 1499710313466 Marionette TRACE 390 <- [1,6,null,{}]
11:11:53 INFO - 1499710313468 Marionette DEBUG Closed connection 390
11:11:53 INFO - 1499710313469 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49798
11:11:53 INFO - 1499710313471 Marionette DEBUG Closed connection 391
11:11:53 INFO - 1499710313472 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49799
11:11:53 INFO - 1499710313473 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:53 INFO - 1499710313474 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313477 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313482 Marionette TRACE 392 <- [1,1,null,{"sessionId":"99a6970c-04c8-4f40-bbc1-6c12ce600a32","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313485 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
11:11:53 INFO - 1499710313486 Marionette TRACE 392 <- [1,2,null,{}]
11:11:53 INFO - 1499710313488 Marionette DEBUG Closed connection 392
11:11:53 INFO - 1499710313489 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49800
11:11:53 INFO - 1499710313490 Marionette DEBUG Closed connection 393
11:11:53 INFO - 1499710313491 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49801
11:11:53 INFO - 1499710313492 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:11:53 INFO - 1499710313493 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313496 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313501 Marionette TRACE 394 <- [1,1,null,{"sessionId":"146c56d6-55c4-4ef5-af82-e5758961c165","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313504 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"}]
11:11:53 INFO - 1499710313505 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:11:53 INFO - 1499710313509 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:11:53 INFO - 1499710313509 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:11:53 INFO - 1499710313547 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"
11:11:53 INFO - 1499710313549 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"
11:11:53 INFO - 1499710313554 Marionette TRACE 394 <- [1,2,null,{}]
11:11:53 INFO - 1499710313568 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
11:11:53 INFO - 1499710313572 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"90944668-7115-4228-8c14-0d7c80503d7b","ELEMENT":"90944668-7115-4228-8c14-0d7c80503d7b"}}]
11:11:53 INFO - 1499710313574 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
11:11:53 INFO - 1499710313577 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bdadcd50-1139-4061-a382-625a9f9a373c","ELEMENT":"bdadcd50-1139-4061-a382-625a9f9a373c"}}]
11:11:53 INFO - 1499710313582 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"90944668-7115-4228-8c14-0d7c80503d7b"}]
11:11:53 INFO - 1499710313833 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:11:53 INFO - 1499710313834 Marionette TRACE 394 <- [1,5,null,{}]
11:11:53 INFO - 1499710313840 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"bdadcd50-1139-4061-a382-625a9f9a373c"}]
11:11:53 INFO - 1499710313860 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
11:11:53 INFO - 1499710313863 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
11:11:53 INFO - 1499710313867 Marionette TRACE 394 <- [1,7,null,{"value":false}]
11:11:53 INFO - 1499710313868 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}]
11:11:53 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499710313872 Marionette TRACE 394 <- [1,8,null,{"value":null}]
11:11:53 INFO - 1499710313874 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
11:11:53 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 445ms
11:11:53 INFO - 1499710313876 Marionette TRACE 394 <- [1,9,null,{}]
11:11:53 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
11:11:53 INFO - 1499710313886 Marionette DEBUG Closed connection 394
11:11:53 INFO - 1499710313888 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49802
11:11:53 INFO - 1499710313889 Marionette DEBUG Closed connection 395
11:11:53 INFO - 1499710313890 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49803
11:11:53 INFO - 1499710313892 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:53 INFO - 1499710313894 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313897 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313903 Marionette TRACE 396 <- [1,1,null,{"sessionId":"988acfb2-102b-4983-b943-c1f0e986cef4","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313907 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
11:11:53 INFO - 1499710313908 Marionette TRACE 396 <- [1,2,null,{}]
11:11:53 INFO - 1499710313909 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:11:53 INFO - 1499710313909 Marionette TRACE 396 <- [1,3,null,{}]
11:11:53 INFO - 1499710313911 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
11:11:53 INFO - 1499710313911 Marionette TRACE 396 <- [1,4,null,{}]
11:11:53 INFO - 1499710313914 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}]
11:11:53 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499710313917 Marionette TRACE 396 <- [1,5,null,{"value":null}]
11:11:53 INFO - 1499710313918 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
11:11:53 INFO - 1499710313920 Marionette TRACE 396 <- [1,6,null,{}]
11:11:53 INFO - 1499710313923 Marionette DEBUG Closed connection 396
11:11:53 INFO - 1499710313924 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49804
11:11:53 INFO - 1499710313926 Marionette DEBUG Closed connection 397
11:11:53 INFO - 1499710313926 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49805
11:11:53 INFO - 1499710313929 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:11:53 INFO - 1499710313931 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313933 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313938 Marionette TRACE 398 <- [1,1,null,{"sessionId":"e25b8702-3d79-47cb-b342-ac2bbd7df0a1","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313941 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
11:11:53 INFO - 1499710313943 Marionette TRACE 398 <- [1,2,null,{}]
11:11:53 INFO - 1499710313945 Marionette DEBUG Closed connection 398
11:11:53 INFO - 1499710313946 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49806
11:11:53 INFO - 1499710313947 Marionette DEBUG Closed connection 399
11:11:53 INFO - 1499710313948 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49807
11:11:53 INFO - 1499710313949 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:11:53 INFO - 1499710313950 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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}
11:11:53 INFO - 1499710313953 Marionette DEBUG Register listener.js for window 12
11:11:53 INFO - 1499710313957 Marionette TRACE 400 <- [1,1,null,{"sessionId":"3f2bf696-69dd-4e67-8d57-9155139ab7e6","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":576,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-123.001\\AppData\\Local\\Temp\\tmp4zwcph.mozrunner","moz:accessibilityChecks":false}}]
11:11:53 INFO - 1499710313960 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"}]
11:11:53 INFO - 1499710313962 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"
11:11:53 INFO - 1499710313965 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"
11:11:53 INFO - 1499710313966 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"
11:11:53 INFO - 1499710313988 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"
11:11:53 INFO - 1499710313992 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"
11:11:54 INFO - 1499710314002 Marionette TRACE 400 <- [1,2,null,{}]
11:11:54 INFO - 1499710314004 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
11:11:54 INFO - 1499710314018 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5fd3633e-0dc2-484a-801b-6f5e3b492e32","ELEMENT":"5fd3633e-0dc2-484a-801b-6f5e3b492e32"}}]
11:11:54 INFO - 1499710314020 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5fd3633e-0dc2-484a-801b-6f5e3b492e32"}]
11:11:54 INFO - 1499710314044 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
11:11:54 INFO - 1499710314045 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5fd3633e-0dc2-484a-801b-6f5e3b492e32"}]
11:11:54 INFO - 1499710314063 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element