could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:42:12 INFO - 1500892932658 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
03:42:12 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500892932661 Marionette TRACE 353 <- [1,5,null,{"value":null}]
03:42:12 INFO - 1500892932662 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
03:42:12 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 149ms
03:42:12 INFO - 1500892932663 Marionette TRACE 353 <- [1,6,null,{}]
03:42:12 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:42:12 INFO - 1500892932665 Marionette DEBUG Closed connection 353
03:42:12 INFO - 1500892932665 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49778
03:42:12 INFO - 1500892932666 Marionette DEBUG Closed connection 354
03:42:12 INFO - 1500892932667 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49779
03:42:12 INFO - 1500892932668 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:12 INFO - 1500892932669 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:12 INFO - 1500892932672 Marionette DEBUG Register listener.js for window 4294967297
03:42:12 INFO - 1500892932676 Marionette TRACE 355 <- [1,1,null,{"sessionId":"9bcb998d-96a3-4432-b692-4c8f1b712ab7","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:12 INFO - 1500892932677 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
03:42:12 INFO - 1500892932677 Marionette TRACE 355 <- [1,2,null,{}]
03:42:12 INFO - 1500892932678 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:42:12 INFO - 1500892932679 Marionette TRACE 355 <- [1,3,null,{}]
03:42:12 INFO - 1500892932680 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
03:42:12 INFO - 1500892932680 Marionette TRACE 355 <- [1,4,null,{}]
03:42:12 INFO - 1500892932682 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
03:42:12 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500892932685 Marionette TRACE 355 <- [1,5,null,{"value":null}]
03:42:12 INFO - 1500892932686 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
03:42:12 INFO - 1500892932687 Marionette TRACE 355 <- [1,6,null,{}]
03:42:12 INFO - 1500892932689 Marionette DEBUG Closed connection 355
03:42:12 INFO - 1500892932689 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49780
03:42:12 INFO - 1500892932690 Marionette DEBUG Closed connection 356
03:42:12 INFO - 1500892932691 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49781
03:42:12 INFO - 1500892932692 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:12 INFO - 1500892932692 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:12 INFO - 1500892932695 Marionette DEBUG Register listener.js for window 4294967297
03:42:12 INFO - 1500892932699 Marionette TRACE 357 <- [1,1,null,{"sessionId":"b0820002-867a-45fd-9b6a-89efad635911","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:12 INFO - 1500892932700 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
03:42:12 INFO - 1500892932701 Marionette TRACE 357 <- [1,2,null,{}]
03:42:12 INFO - 1500892932703 Marionette DEBUG Closed connection 357
03:42:12 INFO - 1500892932703 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49782
03:42:12 INFO - 1500892932704 Marionette DEBUG Closed connection 358
03:42:12 INFO - 1500892932705 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49783
03:42:12 INFO - 1500892932706 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:42:12 INFO - 1500892932707 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:12 INFO - 1500892932709 Marionette DEBUG Register listener.js for window 4294967297
03:42:12 INFO - 1500892932713 Marionette TRACE 359 <- [1,1,null,{"sessionId":"7a4a9c26-4a83-4822-b14f-b5622fd3cd53","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:12 INFO - 1500892932714 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
03:42:12 INFO - 1500892932716 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
03:42:12 INFO - 1500892932724 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
03:42:12 INFO - 1500892932724 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
03:42:12 INFO - 1500892932731 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
03:42:12 INFO - 1500892932736 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
03:42:12 INFO - 1500892932737 Marionette TRACE 359 <- [1,2,null,{}]
03:42:12 INFO - 1500892932749 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:42:12 INFO - 1500892932752 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bd01ccf4-894d-4f84-b97f-3864ff11e33d","ELEMENT":"bd01ccf4-894d-4f84-b97f-3864ff11e33d"}}]
03:42:12 INFO - 1500892932753 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"bd01ccf4-894d-4f84-b97f-3864ff11e33d"}]
03:42:12 INFO - 1500892932795 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
03:42:12 INFO - 1500892932804 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
03:42:12 INFO - 1500892932805 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
03:42:12 INFO - 1500892932815 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
03:42:12 INFO - 1500892932821 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
03:42:12 INFO - 1500892932821 Marionette TRACE 359 <- [1,4,null,{}]
03:42:12 INFO - 1500892932829 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:42:12 INFO - 1500892932832 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7779f5c3-b628-4c30-82ed-9ccf6e23a625","ELEMENT":"7779f5c3-b628-4c30-82ed-9ccf6e23a625"}}]
03:42:12 INFO - 1500892932833 Marionette TRACE 359 -> [0,6,"getTitle",{}]
03:42:12 INFO - 1500892932834 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
03:42:12 INFO - 1500892932836 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
03:42:12 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500892932839 Marionette TRACE 359 <- [1,7,null,{"value":null}]
03:42:12 INFO - 1500892932841 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
03:42:12 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 179ms
03:42:12 INFO - TEST-START | test_click.py TestClick.test_container_element
03:42:12 INFO - 1500892932843 Marionette TRACE 359 <- [1,8,null,{}]
03:42:12 INFO - 1500892932844 Marionette DEBUG Closed connection 359
03:42:12 INFO - 1500892932845 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49786
03:42:12 INFO - 1500892932846 Marionette DEBUG Closed connection 360
03:42:12 INFO - 1500892932846 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49787
03:42:12 INFO - 1500892932847 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:12 INFO - 1500892932848 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:12 INFO - 1500892932851 Marionette DEBUG Register listener.js for window 4294967297
03:42:12 INFO - 1500892932854 Marionette TRACE 361 <- [1,1,null,{"sessionId":"d02a2030-c9f1-4cef-86e1-5c3b531057b3","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:12 INFO - 1500892932855 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
03:42:12 INFO - 1500892932856 Marionette TRACE 361 <- [1,2,null,{}]
03:42:12 INFO - 1500892932857 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:42:12 INFO - 1500892932858 Marionette TRACE 361 <- [1,3,null,{}]
03:42:12 INFO - 1500892932859 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
03:42:12 INFO - 1500892932859 Marionette TRACE 361 <- [1,4,null,{}]
03:42:12 INFO - 1500892932861 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
03:42:12 INFO - TEST-START: test_click.py TestClick.test_container_element1500892932863 Marionette TRACE 361 <- [1,5,null,{"value":null}]
03:42:12 INFO - 1500892932864 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
03:42:12 INFO - 1500892932866 Marionette TRACE 361 <- [1,6,null,{}]
03:42:12 INFO - 1500892932868 Marionette DEBUG Closed connection 361
03:42:12 INFO - 1500892932868 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49788
03:42:12 INFO - 1500892932869 Marionette DEBUG Closed connection 362
03:42:12 INFO - 1500892932869 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49789
03:42:12 INFO - 1500892932871 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:12 INFO - 1500892932871 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:12 INFO - 1500892932875 Marionette DEBUG Register listener.js for window 4294967297
03:42:12 INFO - 1500892932878 Marionette TRACE 363 <- [1,1,null,{"sessionId":"bd402b6f-59ee-48d6-a0f0-2dd3693ecc4c","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:12 INFO - 1500892932879 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
03:42:12 INFO - 1500892932881 Marionette TRACE 363 <- [1,2,null,{}]
03:42:12 INFO - 1500892932882 Marionette DEBUG Closed connection 363
03:42:12 INFO - 1500892932883 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49790
03:42:12 INFO - 1500892932883 Marionette DEBUG Closed connection 364
03:42:12 INFO - 1500892932884 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49791
03:42:12 INFO - 1500892932885 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:42:12 INFO - 1500892932886 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:12 INFO - 1500892932888 Marionette DEBUG Register listener.js for window 4294967297
03:42:12 INFO - 1500892932892 Marionette TRACE 365 <- [1,1,null,{"sessionId":"26f0adbd-8fbe-4ae4-81fc-545a8c076d83","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:12 INFO - 1500892932893 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:42:12 INFO - 1500892932895 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
03:42:12 INFO - 1500892932897 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
03:42:12 INFO - 1500892932898 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
03:42:12 INFO - 1500892932904 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"
03:42:12 INFO - 1500892932907 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"
03:42:12 INFO - 1500892932918 Marionette TRACE 365 <- [1,2,null,{}]
03:42:12 INFO - 1500892932922 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:42:12 INFO - 1500892932928 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7c42bb76-bb8e-4266-bf0d-6b10e6972f5c","ELEMENT":"7c42bb76-bb8e-4266-bf0d-6b10e6972f5c"}}]
03:42:12 INFO - 1500892932929 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"7c42bb76-bb8e-4266-bf0d-6b10e6972f5c"}]
03:42:13 INFO - 1500892933176 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:42:13 INFO - 1500892933177 Marionette TRACE 365 <- [1,4,null,{}]
03:42:13 INFO - 1500892933178 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"7c42bb76-bb8e-4266-bf0d-6b10e6972f5c","name":"selected"}]
03:42:13 INFO - 1500892933183 Marionette TRACE 365 <- [1,5,null,{"value":true}]
03:42:13 INFO - 1500892933185 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
03:42:13 INFO - TEST-END: test_click.py TestClick.test_container_element1500892933187 Marionette TRACE 365 <- [1,6,null,{"value":null}]
03:42:13 INFO - 1500892933188 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
03:42:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 346ms
03:42:13 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:42:13 INFO - 1500892933190 Marionette TRACE 365 <- [1,7,null,{}]
03:42:13 INFO - 1500892933192 Marionette DEBUG Closed connection 365
03:42:13 INFO - 1500892933192 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49792
03:42:13 INFO - 1500892933193 Marionette DEBUG Closed connection 366
03:42:13 INFO - 1500892933194 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49793
03:42:13 INFO - 1500892933195 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:13 INFO - 1500892933195 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933198 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933201 Marionette TRACE 367 <- [1,1,null,{"sessionId":"6b8e91be-04ce-4ea3-8fd6-001b09249397","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933203 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
03:42:13 INFO - 1500892933203 Marionette TRACE 367 <- [1,2,null,{}]
03:42:13 INFO - 1500892933204 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:42:13 INFO - 1500892933205 Marionette TRACE 367 <- [1,3,null,{}]
03:42:13 INFO - 1500892933206 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
03:42:13 INFO - 1500892933207 Marionette TRACE 367 <- [1,4,null,{}]
03:42:13 INFO - 1500892933208 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
03:42:13 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500892933211 Marionette TRACE 367 <- [1,5,null,{"value":null}]
03:42:13 INFO - 1500892933212 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
03:42:13 INFO - 1500892933213 Marionette TRACE 367 <- [1,6,null,{}]
03:42:13 INFO - 1500892933215 Marionette DEBUG Closed connection 367
03:42:13 INFO - 1500892933215 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49794
03:42:13 INFO - 1500892933216 Marionette DEBUG Closed connection 368
03:42:13 INFO - 1500892933217 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49795
03:42:13 INFO - 1500892933218 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:13 INFO - 1500892933219 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933221 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933225 Marionette TRACE 369 <- [1,1,null,{"sessionId":"a7660e2a-8718-400c-9e7f-27e596dcfab8","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933226 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
03:42:13 INFO - 1500892933228 Marionette TRACE 369 <- [1,2,null,{}]
03:42:13 INFO - 1500892933229 Marionette DEBUG Closed connection 369
03:42:13 INFO - 1500892933230 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49796
03:42:13 INFO - 1500892933231 Marionette DEBUG Closed connection 370
03:42:13 INFO - 1500892933232 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49797
03:42:13 INFO - 1500892933233 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:42:13 INFO - 1500892933233 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933236 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933240 Marionette TRACE 371 <- [1,1,null,{"sessionId":"9129da52-7882-488c-a1e3-05f8245fdec3","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933241 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:42:13 INFO - 1500892933243 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"
03:42:13 INFO - 1500892933245 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"
03:42:13 INFO - 1500892933245 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"
03:42:13 INFO - 1500892933252 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"
03:42:13 INFO - 1500892933255 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"
03:42:13 INFO - 1500892933262 Marionette TRACE 371 <- [1,2,null,{}]
03:42:13 INFO - 1500892933266 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:42:13 INFO - 1500892933269 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7ec3218e-6820-419f-a5c4-18bc66eb3e58","ELEMENT":"7ec3218e-6820-419f-a5c4-18bc66eb3e58"}}]
03:42:13 INFO - 1500892933270 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"7ec3218e-6820-419f-a5c4-18bc66eb3e58"}]
03:42:13 INFO - 1500892933516 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:42:13 INFO - 1500892933517 Marionette TRACE 371 <- [1,4,null,{}]
03:42:13 INFO - 1500892933518 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"7ec3218e-6820-419f-a5c4-18bc66eb3e58","name":"selected"}]
03:42:13 INFO - 1500892933523 Marionette TRACE 371 <- [1,5,null,{"value":true}]
03:42:13 INFO - 1500892933525 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
03:42:13 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500892933527 Marionette TRACE 371 <- [1,6,null,{"value":null}]
03:42:13 INFO - 1500892933528 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
03:42:13 INFO - 1500892933530 Marionette TRACE 371 <- [1,7,null,{}]
03:42:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 339ms
03:42:13 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:42:13 INFO - 1500892933531 Marionette DEBUG Closed connection 371
03:42:13 INFO - 1500892933532 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49798
03:42:13 INFO - 1500892933533 Marionette DEBUG Closed connection 372
03:42:13 INFO - 1500892933533 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49799
03:42:13 INFO - 1500892933534 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:13 INFO - 1500892933535 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933538 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933541 Marionette TRACE 373 <- [1,1,null,{"sessionId":"2724ede2-511a-4667-940d-ccd6d61202fa","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933542 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
03:42:13 INFO - 1500892933543 Marionette TRACE 373 <- [1,2,null,{}]
03:42:13 INFO - 1500892933544 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:42:13 INFO - 1500892933544 Marionette TRACE 373 <- [1,3,null,{}]
03:42:13 INFO - 1500892933545 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
03:42:13 INFO - 1500892933546 Marionette TRACE 373 <- [1,4,null,{}]
03:42:13 INFO - 1500892933548 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
03:42:13 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500892933551 Marionette TRACE 373 <- [1,5,null,{"value":null}]
03:42:13 INFO - 1500892933552 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
03:42:13 INFO - 1500892933553 Marionette TRACE 373 <- [1,6,null,{}]
03:42:13 INFO - 1500892933555 Marionette DEBUG Closed connection 373
03:42:13 INFO - 1500892933555 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49800
03:42:13 INFO - 1500892933556 Marionette DEBUG Closed connection 374
03:42:13 INFO - 1500892933557 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49801
03:42:13 INFO - 1500892933558 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:13 INFO - 1500892933559 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933561 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933565 Marionette TRACE 375 <- [1,1,null,{"sessionId":"caf35da3-7c96-4b5a-bc7b-29fcf17ce2ba","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933566 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
03:42:13 INFO - 1500892933567 Marionette TRACE 375 <- [1,2,null,{}]
03:42:13 INFO - 1500892933569 Marionette DEBUG Closed connection 375
03:42:13 INFO - 1500892933569 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49802
03:42:13 INFO - 1500892933570 Marionette DEBUG Closed connection 376
03:42:13 INFO - 1500892933571 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49803
03:42:13 INFO - 1500892933572 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:42:13 INFO - 1500892933573 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933575 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933579 Marionette TRACE 377 <- [1,1,null,{"sessionId":"51fa47ff-e533-4831-9d43-b1f2d24cb6c3","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933580 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
03:42:13 INFO - 1500892933582 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"
03:42:13 INFO - 1500892933584 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"
03:42:13 INFO - 1500892933584 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"
03:42:13 INFO - 1500892933593 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"
03:42:13 INFO - 1500892933595 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"
03:42:13 INFO - 1500892933601 Marionette TRACE 377 <- [1,2,null,{}]
03:42:13 INFO - 1500892933604 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:42:13 INFO - 1500892933611 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1e33a8a7-2943-4f97-8051-b920e7da239e","ELEMENT":"1e33a8a7-2943-4f97-8051-b920e7da239e"}}]
03:42:13 INFO - 1500892933613 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"1e33a8a7-2943-4f97-8051-b920e7da239e"}]
03:42:13 INFO - 1500892933859 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:42:13 INFO - 1500892933860 Marionette TRACE 377 <- [1,4,null,{}]
03:42:13 INFO - 1500892933863 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
03:42:13 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500892933865 Marionette TRACE 377 <- [1,5,null,{"value":null}]
03:42:13 INFO - 1500892933866 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
03:42:13 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 338ms
03:42:13 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:42:13 INFO - 1500892933868 Marionette TRACE 377 <- [1,6,null,{}]
03:42:13 INFO - 1500892933869 Marionette DEBUG Closed connection 377
03:42:13 INFO - 1500892933870 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49804
03:42:13 INFO - 1500892933871 Marionette DEBUG Closed connection 378
03:42:13 INFO - 1500892933871 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49805
03:42:13 INFO - 1500892933872 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:13 INFO - 1500892933873 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933876 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933886 Marionette TRACE 379 <- [1,1,null,{"sessionId":"7c749e04-826d-4e73-832e-c32b4a7379a0","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933888 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
03:42:13 INFO - 1500892933888 Marionette TRACE 379 <- [1,2,null,{}]
03:42:13 INFO - 1500892933890 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:42:13 INFO - 1500892933890 Marionette TRACE 379 <- [1,3,null,{}]
03:42:13 INFO - 1500892933891 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
03:42:13 INFO - 1500892933891 Marionette TRACE 379 <- [1,4,null,{}]
03:42:13 INFO - 1500892933893 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
03:42:13 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500892933895 Marionette TRACE 379 <- [1,5,null,{"value":null}]
03:42:13 INFO - 1500892933896 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
03:42:13 INFO - 1500892933898 Marionette TRACE 379 <- [1,6,null,{}]
03:42:13 INFO - 1500892933899 Marionette DEBUG Closed connection 379
03:42:13 INFO - 1500892933900 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49806
03:42:13 INFO - 1500892933901 Marionette DEBUG Closed connection 380
03:42:13 INFO - 1500892933902 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49807
03:42:13 INFO - 1500892933903 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:13 INFO - 1500892933904 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933906 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933910 Marionette TRACE 381 <- [1,1,null,{"sessionId":"2f95b3be-13ea-4b25-9129-b6234bf6d938","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933912 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
03:42:13 INFO - 1500892933913 Marionette TRACE 381 <- [1,2,null,{}]
03:42:13 INFO - 1500892933915 Marionette DEBUG Closed connection 381
03:42:13 INFO - 1500892933915 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49808
03:42:13 INFO - 1500892933916 Marionette DEBUG Closed connection 382
03:42:13 INFO - 1500892933917 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49809
03:42:13 INFO - 1500892933918 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:42:13 INFO - 1500892933918 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:13 INFO - 1500892933921 Marionette DEBUG Register listener.js for window 4294967297
03:42:13 INFO - 1500892933925 Marionette TRACE 383 <- [1,1,null,{"sessionId":"025fbb67-5076-4d6a-869b-2004c965866a","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:13 INFO - 1500892933926 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:42:13 INFO - 1500892933928 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"
03:42:13 INFO - 1500892933930 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"
03:42:13 INFO - 1500892933930 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"
03:42:13 INFO - 1500892933936 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"
03:42:13 INFO - 1500892933939 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"
03:42:13 INFO - 1500892933946 Marionette TRACE 383 <- [1,2,null,{}]
03:42:13 INFO - 1500892933949 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:42:13 INFO - 1500892933952 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"feb5e80c-63d2-4396-b864-2c5b98f4abcc","ELEMENT":"feb5e80c-63d2-4396-b864-2c5b98f4abcc"}}]
03:42:13 INFO - 1500892933953 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"feb5e80c-63d2-4396-b864-2c5b98f4abcc"}]
03:42:14 INFO - 1500892934198 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:42:14 INFO - 1500892934199 Marionette TRACE 383 <- [1,4,null,{}]
03:42:14 INFO - 1500892934201 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
03:42:14 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500892934204 Marionette TRACE 383 <- [1,5,null,{"value":null}]
03:42:14 INFO - 1500892934205 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
03:42:14 INFO - 1500892934206 Marionette TRACE 383 <- [1,6,null,{}]
03:42:14 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 338ms
03:42:14 INFO - TEST-START | test_click.py TestClick.test_input_file
03:42:14 INFO - 1500892934208 Marionette DEBUG Closed connection 383
03:42:14 INFO - 1500892934209 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49810
03:42:14 INFO - 1500892934210 Marionette DEBUG Closed connection 384
03:42:14 INFO - 1500892934211 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49811
03:42:14 INFO - 1500892934212 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:14 INFO - 1500892934213 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934215 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934219 Marionette TRACE 385 <- [1,1,null,{"sessionId":"a5f82ffb-07ea-4170-8875-ff3420808e56","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934220 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
03:42:14 INFO - 1500892934221 Marionette TRACE 385 <- [1,2,null,{}]
03:42:14 INFO - 1500892934222 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:42:14 INFO - 1500892934222 Marionette TRACE 385 <- [1,3,null,{}]
03:42:14 INFO - 1500892934223 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
03:42:14 INFO - 1500892934224 Marionette TRACE 385 <- [1,4,null,{}]
03:42:14 INFO - 1500892934225 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
03:42:14 INFO - TEST-START: test_click.py TestClick.test_input_file1500892934229 Marionette TRACE 385 <- [1,5,null,{"value":null}]
03:42:14 INFO - 1500892934230 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
03:42:14 INFO - 1500892934231 Marionette TRACE 385 <- [1,6,null,{}]
03:42:14 INFO - 1500892934233 Marionette DEBUG Closed connection 385
03:42:14 INFO - 1500892934234 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49812
03:42:14 INFO - 1500892934234 Marionette DEBUG Closed connection 386
03:42:14 INFO - 1500892934235 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49813
03:42:14 INFO - 1500892934236 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:14 INFO - 1500892934237 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934240 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934243 Marionette TRACE 387 <- [1,1,null,{"sessionId":"9e4c6098-155f-46e2-bd0e-4c70f131e08e","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934244 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
03:42:14 INFO - 1500892934246 Marionette TRACE 387 <- [1,2,null,{}]
03:42:14 INFO - 1500892934247 Marionette DEBUG Closed connection 387
03:42:14 INFO - 1500892934248 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49814
03:42:14 INFO - 1500892934249 Marionette DEBUG Closed connection 388
03:42:14 INFO - 1500892934249 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49815
03:42:14 INFO - 1500892934251 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:42:14 INFO - 1500892934251 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934254 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934258 Marionette TRACE 389 <- [1,1,null,{"sessionId":"240d9740-8a1d-4371-b730-988113da39fa","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934259 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:42:14 INFO - 1500892934261 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"
03:42:14 INFO - 1500892934263 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"
03:42:14 INFO - 1500892934264 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"
03:42:14 INFO - 1500892934270 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:42:14 INFO - 1500892934274 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:42:14 INFO - 1500892934279 Marionette TRACE 389 <- [1,2,null,{}]
03:42:14 INFO - 1500892934283 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:42:14 INFO - 1500892934286 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"689a2832-a952-40da-867f-da20e8bd2649","ELEMENT":"689a2832-a952-40da-867f-da20e8bd2649"}}]
03:42:14 INFO - 1500892934287 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"689a2832-a952-40da-867f-da20e8bd2649"}]
03:42:14 INFO - 1500892934324 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:42:14 INFO - 1500892934325 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
03:42:14 INFO - TEST-END: test_click.py TestClick.test_input_file1500892934328 Marionette TRACE 389 <- [1,5,null,{"value":null}]
03:42:14 INFO - 1500892934329 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
03:42:14 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 124ms
03:42:14 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:42:14 INFO - 1500892934331 Marionette TRACE 389 <- [1,6,null,{}]
03:42:14 INFO - 1500892934332 Marionette DEBUG Closed connection 389
03:42:14 INFO - 1500892934333 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49816
03:42:14 INFO - 1500892934334 Marionette DEBUG Closed connection 390
03:42:14 INFO - 1500892934334 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49817
03:42:14 INFO - 1500892934335 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:14 INFO - 1500892934336 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934339 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934343 Marionette TRACE 391 <- [1,1,null,{"sessionId":"6edcea9a-7899-4ce7-a7a2-7542dc1e4d5e","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934344 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
03:42:14 INFO - 1500892934344 Marionette TRACE 391 <- [1,2,null,{}]
03:42:14 INFO - 1500892934345 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:42:14 INFO - 1500892934346 Marionette TRACE 391 <- [1,3,null,{}]
03:42:14 INFO - 1500892934347 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
03:42:14 INFO - 1500892934347 Marionette TRACE 391 <- [1,4,null,{}]
03:42:14 INFO - 1500892934349 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
03:42:14 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500892934351 Marionette TRACE 391 <- [1,5,null,{"value":null}]
03:42:14 INFO - 1500892934352 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
03:42:14 INFO - 1500892934353 Marionette TRACE 391 <- [1,6,null,{}]
03:42:14 INFO - 1500892934355 Marionette DEBUG Closed connection 391
03:42:14 INFO - 1500892934355 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49818
03:42:14 INFO - 1500892934356 Marionette DEBUG Closed connection 392
03:42:14 INFO - 1500892934357 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49819
03:42:14 INFO - 1500892934358 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:14 INFO - 1500892934359 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934362 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934365 Marionette TRACE 393 <- [1,1,null,{"sessionId":"87f70b17-f160-44ca-815d-45fb983fe304","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934366 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
03:42:14 INFO - 1500892934368 Marionette TRACE 393 <- [1,2,null,{}]
03:42:14 INFO - 1500892934369 Marionette DEBUG Closed connection 393
03:42:14 INFO - 1500892934370 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49820
03:42:14 INFO - 1500892934370 Marionette DEBUG Closed connection 394
03:42:14 INFO - 1500892934371 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49821
03:42:14 INFO - 1500892934372 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:42:14 INFO - 1500892934373 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934376 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934379 Marionette TRACE 395 <- [1,1,null,{"sessionId":"f93ac27c-aa02-4dcb-a8a7-58f069d67d58","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934380 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
03:42:14 INFO - 1500892934382 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:42:14 INFO - 1500892934384 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:42:14 INFO - 1500892934384 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:42:14 INFO - 1500892934393 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"
03:42:14 INFO - 1500892934395 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"
03:42:14 INFO - 1500892934401 Marionette TRACE 395 <- [1,2,null,{}]
03:42:14 INFO - 1500892934404 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:42:14 INFO - 1500892934406 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4706337-aafe-4d66-9fda-21e71687ba76","ELEMENT":"a4706337-aafe-4d66-9fda-21e71687ba76"}}]
03:42:14 INFO - 1500892934412 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:42:14 INFO - 1500892934414 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a6c267bd-e759-4e15-8172-c05d3f21430a","ELEMENT":"a6c267bd-e759-4e15-8172-c05d3f21430a"}}]
03:42:14 INFO - 1500892934415 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"a4706337-aafe-4d66-9fda-21e71687ba76"}]
03:42:14 INFO - 1500892934655 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:42:14 INFO - 1500892934656 Marionette TRACE 395 <- [1,5,null,{}]
03:42:14 INFO - 1500892934657 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"a6c267bd-e759-4e15-8172-c05d3f21430a"}]
03:42:14 INFO - 1500892934674 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:42:14 INFO - 1500892934676 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:42:14 INFO - 1500892934679 Marionette TRACE 395 <- [1,7,null,{"value":false}]
03:42:14 INFO - 1500892934680 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
03:42:14 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500892934683 Marionette TRACE 395 <- [1,8,null,{"value":null}]
03:42:14 INFO - 1500892934683 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
03:42:14 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 354ms
03:42:14 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:42:14 INFO - 1500892934685 Marionette TRACE 395 <- [1,9,null,{}]
03:42:14 INFO - 1500892934686 Marionette DEBUG Closed connection 395
03:42:14 INFO - 1500892934688 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49822
03:42:14 INFO - 1500892934689 Marionette DEBUG Closed connection 396
03:42:14 INFO - 1500892934689 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49823
03:42:14 INFO - 1500892934690 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:14 INFO - 1500892934691 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934694 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934697 Marionette TRACE 397 <- [1,1,null,{"sessionId":"dc0d6c42-49f7-4bf7-bf16-5344749f1c97","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934698 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
03:42:14 INFO - 1500892934699 Marionette TRACE 397 <- [1,2,null,{}]
03:42:14 INFO - 1500892934700 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:42:14 INFO - 1500892934701 Marionette TRACE 397 <- [1,3,null,{}]
03:42:14 INFO - 1500892934702 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
03:42:14 INFO - 1500892934702 Marionette TRACE 397 <- [1,4,null,{}]
03:42:14 INFO - 1500892934704 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
03:42:14 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500892934707 Marionette TRACE 397 <- [1,5,null,{"value":null}]
03:42:14 INFO - 1500892934708 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
03:42:14 INFO - 1500892934709 Marionette TRACE 397 <- [1,6,null,{}]
03:42:14 INFO - 1500892934711 Marionette DEBUG Closed connection 397
03:42:14 INFO - 1500892934711 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49824
03:42:14 INFO - 1500892934712 Marionette DEBUG Closed connection 398
03:42:14 INFO - 1500892934713 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49825
03:42:14 INFO - 1500892934714 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:42:14 INFO - 1500892934714 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934717 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934721 Marionette TRACE 399 <- [1,1,null,{"sessionId":"90487ed5-a9c1-47fa-add7-b7727f88fd7b","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934722 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
03:42:14 INFO - 1500892934723 Marionette TRACE 399 <- [1,2,null,{}]
03:42:14 INFO - 1500892934725 Marionette DEBUG Closed connection 399
03:42:14 INFO - 1500892934726 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49826
03:42:14 INFO - 1500892934726 Marionette DEBUG Closed connection 400
03:42:14 INFO - 1500892934727 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49827
03:42:14 INFO - 1500892934728 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:42:14 INFO - 1500892934729 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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}
03:42:14 INFO - 1500892934732 Marionette DEBUG Register listener.js for window 4294967297
03:42:14 INFO - 1500892934735 Marionette TRACE 401 <- [1,1,null,{"sessionId":"24808e15-1ee5-4b0e-8c0a-127bb62c503f","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":2348,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpoi7ggb.mozrunner","moz:accessibilityChecks":false}}]
03:42:14 INFO - 1500892934736 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
03:42:14 INFO - 1500892934739 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"
03:42:14 INFO - 1500892934741 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"
03:42:14 INFO - 1500892934741 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"
03:42:14 INFO - 1500892934748 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"
03:42:14 INFO - 1500892934750 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"
03:42:14 INFO - 1500892934755 Marionette TRACE 401 <- [1,2,null,{}]
03:42:14 INFO - 1500892934763 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:42:14 INFO - 1500892934766 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"afc19a78-ddd6-47c5-84c4-e3ea73c45205","ELEMENT":"afc19a78-ddd6-47c5-84c4-e3ea73c45205"}}]
03:42:14 INFO - 1500892934767 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"afc19a78-ddd6-47c5-84c4-e3ea73c45205"}]
03:42:14 INFO - 1500892934792 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
03:42:14 INFO - 1500892934793 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"afc19a78-ddd6-47c5-84c4-e3ea73c45205"}]
03:42:14 INFO - 1500892934810 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element