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]
06:53:52 INFO - 1499867632639 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}]
06:53:52 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499867632641 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:53:52 INFO - 1499867632643 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:53:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 136ms
06:53:52 INFO - 1499867632644 Marionette TRACE 352 <- [1,6,null,{}]
06:53:52 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:53:52 INFO - 1499867632649 Marionette DEBUG Closed connection 352
06:53:52 INFO - 1499867632650 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49769
06:53:52 INFO - 1499867632652 Marionette DEBUG Closed connection 353
06:53:52 INFO - 1499867632653 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49770
06:53:52 INFO - 1499867632654 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1499867632654 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1499867632656 Marionette DEBUG Register listener.js for window 15
06:53:52 INFO - 1499867632659 Marionette TRACE 354 <- [1,1,null,{"sessionId":"567ac6ad-0bd8-4af5-96de-b8abbcd41300","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1499867632661 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:53:52 INFO - 1499867632662 Marionette TRACE 354 <- [1,2,null,{}]
06:53:52 INFO - 1499867632663 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:52 INFO - 1499867632663 Marionette TRACE 354 <- [1,3,null,{}]
06:53:52 INFO - 1499867632664 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:52 INFO - 1499867632665 Marionette TRACE 354 <- [1,4,null,{}]
06:53:52 INFO - 1499867632666 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}]
06:53:52 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499867632668 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:53:52 INFO - 1499867632669 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:53:52 INFO - 1499867632670 Marionette TRACE 354 <- [1,6,null,{}]
06:53:52 INFO - 1499867632672 Marionette DEBUG Closed connection 354
06:53:52 INFO - 1499867632673 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49771
06:53:52 INFO - 1499867632673 Marionette DEBUG Closed connection 355
06:53:52 INFO - 1499867632674 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49772
06:53:52 INFO - 1499867632675 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1499867632675 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1499867632678 Marionette DEBUG Register listener.js for window 15
06:53:52 INFO - 1499867632680 Marionette TRACE 356 <- [1,1,null,{"sessionId":"8840c47e-4b55-4a4a-b2f1-a1b0df4270f1","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1499867632682 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:53:52 INFO - 1499867632683 Marionette TRACE 356 <- [1,2,null,{}]
06:53:52 INFO - 1499867632684 Marionette DEBUG Closed connection 356
06:53:52 INFO - 1499867632685 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49773
06:53:52 INFO - 1499867632685 Marionette DEBUG Closed connection 357
06:53:52 INFO - 1499867632686 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49774
06:53:52 INFO - 1499867632687 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:52 INFO - 1499867632687 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1499867632689 Marionette DEBUG Register listener.js for window 15
06:53:52 INFO - 1499867632692 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b457d93e-731a-4d2a-a5ee-9f6eb1d710e0","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1499867632695 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49335/clicks.html"}]
06:53:52 INFO - 1499867632696 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/hidden.html"
06:53:52 INFO - 1499867632701 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/hidden.html"
06:53:52 INFO - 1499867632702 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/hidden.html"
06:53:52 INFO - 1499867632718 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/clicks.html"
06:53:52 INFO - 1499867632720 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/clicks.html"
06:53:52 INFO - 1499867632723 Marionette TRACE 358 <- [1,2,null,{}]
06:53:52 INFO - 1499867632724 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:53:52 INFO - 1499867632726 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"25e85f09-938d-41ba-97af-7b4bf0fd24ff","ELEMENT":"25e85f09-938d-41ba-97af-7b4bf0fd24ff"}}]
06:53:52 INFO - 1499867632732 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"25e85f09-938d-41ba-97af-7b4bf0fd24ff"}]
06:53:52 INFO - 1499867632778 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/clicks.html"
06:53:52 INFO - 1499867632786 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/clicks.html"
06:53:52 INFO - 1499867632787 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/clicks.html"
06:53:52 INFO - 1499867632804 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/test.html"
06:53:52 INFO - 1499867632805 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/test.html"
06:53:52 INFO - 1499867632807 Marionette TRACE 358 <- [1,4,null,{}]
06:53:52 INFO - 1499867632808 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:53:52 INFO - 1499867632816 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8fcbf718-78fa-44e9-9c21-e7b951b00837","ELEMENT":"8fcbf718-78fa-44e9-9c21-e7b951b00837"}}]
06:53:52 INFO - 1499867632819 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:53:52 INFO - 1499867632820 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:53:52 INFO - 1499867632821 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}]
06:53:52 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499867632824 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:53:52 INFO - 1499867632824 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:53:52 INFO - 1499867632826 Marionette TRACE 358 <- [1,8,null,{}]
06:53:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 181ms
06:53:52 INFO - TEST-START | test_click.py TestClick.test_container_element
06:53:52 INFO - 1499867632828 Marionette DEBUG Closed connection 358
06:53:52 INFO - 1499867632829 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49777
06:53:52 INFO - 1499867632830 Marionette DEBUG Closed connection 359
06:53:52 INFO - 1499867632831 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49778
06:53:52 INFO - 1499867632832 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1499867632832 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1499867632835 Marionette DEBUG Register listener.js for window 15
06:53:52 INFO - 1499867632837 Marionette TRACE 360 <- [1,1,null,{"sessionId":"2a38e5fc-d0d9-4cf5-add9-afa8eea8749d","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1499867632839 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:53:52 INFO - 1499867632839 Marionette TRACE 360 <- [1,2,null,{}]
06:53:52 INFO - 1499867632840 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:52 INFO - 1499867632840 Marionette TRACE 360 <- [1,3,null,{}]
06:53:52 INFO - 1499867632841 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:52 INFO - 1499867632841 Marionette TRACE 360 <- [1,4,null,{}]
06:53:52 INFO - 1499867632843 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}]
06:53:52 INFO - TEST-START: test_click.py TestClick.test_container_element1499867632845 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:53:52 INFO - 1499867632846 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:53:52 INFO - 1499867632848 Marionette TRACE 360 <- [1,6,null,{}]
06:53:52 INFO - 1499867632850 Marionette DEBUG Closed connection 360
06:53:52 INFO - 1499867632850 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49779
06:53:52 INFO - 1499867632851 Marionette DEBUG Closed connection 361
06:53:52 INFO - 1499867632851 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49780
06:53:52 INFO - 1499867632852 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1499867632853 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1499867632855 Marionette DEBUG Register listener.js for window 15
06:53:52 INFO - 1499867632857 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3011f6b9-7a18-4766-a5f8-00cd093c7d5e","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1499867632859 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:53:52 INFO - 1499867632860 Marionette TRACE 362 <- [1,2,null,{}]
06:53:52 INFO - 1499867632862 Marionette DEBUG Closed connection 362
06:53:52 INFO - 1499867632862 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49781
06:53:52 INFO - 1499867632863 Marionette DEBUG Closed connection 363
06:53:52 INFO - 1499867632863 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49782
06:53:52 INFO - 1499867632864 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:52 INFO - 1499867632864 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1499867632867 Marionette DEBUG Register listener.js for window 15
06:53:52 INFO - 1499867632869 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d96c26bb-3404-49d9-b0b9-e001d42b5fa1","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1499867632871 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"}]
06:53:52 INFO - 1499867632872 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/test.html"
06:53:52 INFO - 1499867632873 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/test.html"
06:53:52 INFO - 1499867632874 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/test.html"
06:53:52 INFO - 1499867632892 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"
06:53:52 INFO - 1499867632895 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"
06:53:52 INFO - 1499867632902 Marionette TRACE 364 <- [1,2,null,{}]
06:53:52 INFO - 1499867632904 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:52 INFO - 1499867632906 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d5f900e3-21a3-49ef-a077-9479b7e813a3","ELEMENT":"d5f900e3-21a3-49ef-a077-9479b7e813a3"}}]
06:53:52 INFO - 1499867632906 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d5f900e3-21a3-49ef-a077-9479b7e813a3"}]
06:53:53 INFO - 1499867633143 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:53 INFO - 1499867633144 Marionette TRACE 364 <- [1,4,null,{}]
06:53:53 INFO - 1499867633145 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d5f900e3-21a3-49ef-a077-9479b7e813a3","name":"selected"}]
06:53:53 INFO - 1499867633149 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:53:53 INFO - 1499867633150 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}]
06:53:53 INFO - TEST-END: test_click.py TestClick.test_container_element1499867633152 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:53:53 INFO - 1499867633153 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:53:53 INFO - 1499867633154 Marionette TRACE 364 <- [1,7,null,{}]
06:53:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 328ms
06:53:53 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:53:53 INFO - 1499867633156 Marionette DEBUG Closed connection 364
06:53:53 INFO - 1499867633158 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49783
06:53:53 INFO - 1499867633159 Marionette DEBUG Closed connection 365
06:53:53 INFO - 1499867633160 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49784
06:53:53 INFO - 1499867633160 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1499867633161 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633163 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633166 Marionette TRACE 366 <- [1,1,null,{"sessionId":"998a93f1-9419-4112-af84-56509c70364f","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633167 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:53:53 INFO - 1499867633168 Marionette TRACE 366 <- [1,2,null,{}]
06:53:53 INFO - 1499867633169 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:53 INFO - 1499867633169 Marionette TRACE 366 <- [1,3,null,{}]
06:53:53 INFO - 1499867633170 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:53 INFO - 1499867633171 Marionette TRACE 366 <- [1,4,null,{}]
06:53:53 INFO - 1499867633172 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}]
06:53:53 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499867633174 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:53:53 INFO - 1499867633176 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:53:53 INFO - 1499867633177 Marionette TRACE 366 <- [1,6,null,{}]
06:53:53 INFO - 1499867633178 Marionette DEBUG Closed connection 366
06:53:53 INFO - 1499867633179 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49785
06:53:53 INFO - 1499867633179 Marionette DEBUG Closed connection 367
06:53:53 INFO - 1499867633180 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49786
06:53:53 INFO - 1499867633181 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1499867633182 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633184 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633187 Marionette TRACE 368 <- [1,1,null,{"sessionId":"5bf56e07-bc5e-43c6-91a3-3226169653de","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633188 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:53:53 INFO - 1499867633189 Marionette TRACE 368 <- [1,2,null,{}]
06:53:53 INFO - 1499867633191 Marionette DEBUG Closed connection 368
06:53:53 INFO - 1499867633191 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49787
06:53:53 INFO - 1499867633192 Marionette DEBUG Closed connection 369
06:53:53 INFO - 1499867633193 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49788
06:53:53 INFO - 1499867633195 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:53 INFO - 1499867633195 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633197 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633199 Marionette TRACE 370 <- [1,1,null,{"sessionId":"aba7cbda-9df5-4f6a-a1f0-0db6aa13beb0","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633201 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"}]
06:53:53 INFO - 1499867633202 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"
06:53:53 INFO - 1499867633204 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"
06:53:53 INFO - 1499867633204 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"
06:53:53 INFO - 1499867633219 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"
06:53:53 INFO - 1499867633221 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"
06:53:53 INFO - 1499867633224 Marionette TRACE 370 <- [1,2,null,{}]
06:53:53 INFO - 1499867633225 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:53 INFO - 1499867633234 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"689c1074-8143-4425-b19c-091332af5194","ELEMENT":"689c1074-8143-4425-b19c-091332af5194"}}]
06:53:53 INFO - 1499867633236 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"689c1074-8143-4425-b19c-091332af5194"}]
06:53:53 INFO - 1499867633481 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:53 INFO - 1499867633482 Marionette TRACE 370 <- [1,4,null,{}]
06:53:53 INFO - 1499867633483 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"689c1074-8143-4425-b19c-091332af5194","name":"selected"}]
06:53:53 INFO - 1499867633487 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:53:53 INFO - 1499867633488 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}]
06:53:53 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499867633490 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:53:53 INFO - 1499867633491 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:53:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 336ms
06:53:53 INFO - 1499867633493 Marionette TRACE 370 <- [1,7,null,{}]
06:53:53 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:53:53 INFO - 1499867633494 Marionette DEBUG Closed connection 370
06:53:53 INFO - 1499867633496 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49789
06:53:53 INFO - 1499867633497 Marionette DEBUG Closed connection 371
06:53:53 INFO - 1499867633497 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49790
06:53:53 INFO - 1499867633498 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1499867633499 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633501 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633503 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ad1a1721-08d3-4134-b0e5-3fbdfda81a24","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633505 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:53:53 INFO - 1499867633505 Marionette TRACE 372 <- [1,2,null,{}]
06:53:53 INFO - 1499867633506 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:53 INFO - 1499867633506 Marionette TRACE 372 <- [1,3,null,{}]
06:53:53 INFO - 1499867633507 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:53 INFO - 1499867633508 Marionette TRACE 372 <- [1,4,null,{}]
06:53:53 INFO - 1499867633509 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}]
06:53:53 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499867633511 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:53:53 INFO - 1499867633512 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:53:53 INFO - 1499867633513 Marionette TRACE 372 <- [1,6,null,{}]
06:53:53 INFO - 1499867633515 Marionette DEBUG Closed connection 372
06:53:53 INFO - 1499867633515 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49791
06:53:53 INFO - 1499867633516 Marionette DEBUG Closed connection 373
06:53:53 INFO - 1499867633516 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49792
06:53:53 INFO - 1499867633517 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1499867633518 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633520 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633522 Marionette TRACE 374 <- [1,1,null,{"sessionId":"0e41bcb0-7dbb-4795-9d06-ce08a57963e1","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633524 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:53:53 INFO - 1499867633525 Marionette TRACE 374 <- [1,2,null,{}]
06:53:53 INFO - 1499867633526 Marionette DEBUG Closed connection 374
06:53:53 INFO - 1499867633527 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49793
06:53:53 INFO - 1499867633527 Marionette DEBUG Closed connection 375
06:53:53 INFO - 1499867633528 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49794
06:53:53 INFO - 1499867633529 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:53 INFO - 1499867633530 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633532 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633534 Marionette TRACE 376 <- [1,1,null,{"sessionId":"62cbf346-47ab-4434-b2bf-9b06145e6f98","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633536 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"}]
06:53:53 INFO - 1499867633537 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"
06:53:53 INFO - 1499867633539 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"
06:53:53 INFO - 1499867633539 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"
06:53:53 INFO - 1499867633555 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"
06:53:53 INFO - 1499867633558 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"
06:53:53 INFO - 1499867633560 Marionette TRACE 376 <- [1,2,null,{}]
06:53:53 INFO - 1499867633566 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:53:53 INFO - 1499867633569 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fafde0ed-9ad0-4a1c-a19d-c1ace6907549","ELEMENT":"fafde0ed-9ad0-4a1c-a19d-c1ace6907549"}}]
06:53:53 INFO - 1499867633571 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"fafde0ed-9ad0-4a1c-a19d-c1ace6907549"}]
06:53:53 INFO - 1499867633812 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:53 INFO - 1499867633813 Marionette TRACE 376 <- [1,4,null,{}]
06:53:53 INFO - 1499867633815 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}]
06:53:53 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499867633817 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:53:53 INFO - 1499867633818 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:53:53 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 325ms
06:53:53 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:53:53 INFO - 1499867633819 Marionette TRACE 376 <- [1,6,null,{}]
06:53:53 INFO - 1499867633820 Marionette DEBUG Closed connection 376
06:53:53 INFO - 1499867633821 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49795
06:53:53 INFO - 1499867633822 Marionette DEBUG Closed connection 377
06:53:53 INFO - 1499867633822 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49796
06:53:53 INFO - 1499867633823 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1499867633824 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633826 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633829 Marionette TRACE 378 <- [1,1,null,{"sessionId":"66d95792-c452-45f1-8128-7a9ce7c0000b","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633831 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:53:53 INFO - 1499867633831 Marionette TRACE 378 <- [1,2,null,{}]
06:53:53 INFO - 1499867633832 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:53 INFO - 1499867633833 Marionette TRACE 378 <- [1,3,null,{}]
06:53:53 INFO - 1499867633833 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:53 INFO - 1499867633834 Marionette TRACE 378 <- [1,4,null,{}]
06:53:53 INFO - 1499867633835 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}]
06:53:53 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499867633837 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:53:53 INFO - 1499867633838 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:53:53 INFO - 1499867633839 Marionette TRACE 378 <- [1,6,null,{}]
06:53:53 INFO - 1499867633841 Marionette DEBUG Closed connection 378
06:53:53 INFO - 1499867633841 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49797
06:53:53 INFO - 1499867633842 Marionette DEBUG Closed connection 379
06:53:53 INFO - 1499867633842 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49798
06:53:53 INFO - 1499867633843 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1499867633844 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633846 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633848 Marionette TRACE 380 <- [1,1,null,{"sessionId":"58ce2b0d-e70d-42c5-9e42-e68434dfd382","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633850 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:53:53 INFO - 1499867633851 Marionette TRACE 380 <- [1,2,null,{}]
06:53:53 INFO - 1499867633853 Marionette DEBUG Closed connection 380
06:53:53 INFO - 1499867633853 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49799
06:53:53 INFO - 1499867633854 Marionette DEBUG Closed connection 381
06:53:53 INFO - 1499867633855 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49800
06:53:53 INFO - 1499867633856 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:53 INFO - 1499867633856 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1499867633858 Marionette DEBUG Register listener.js for window 15
06:53:53 INFO - 1499867633860 Marionette TRACE 382 <- [1,1,null,{"sessionId":"54ca1685-12e5-40f1-bf27-c9e4fdc80567","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1499867633862 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"}]
06:53:53 INFO - 1499867633863 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"
06:53:53 INFO - 1499867633865 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"
06:53:53 INFO - 1499867633865 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"
06:53:53 INFO - 1499867633874 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"
06:53:53 INFO - 1499867633877 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"
06:53:53 INFO - 1499867633886 Marionette TRACE 382 <- [1,2,null,{}]
06:53:53 INFO - 1499867633889 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:53:53 INFO - 1499867633891 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c1d4aef7-3d46-4275-9b1c-509d99df9072","ELEMENT":"c1d4aef7-3d46-4275-9b1c-509d99df9072"}}]
06:53:53 INFO - 1499867633892 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c1d4aef7-3d46-4275-9b1c-509d99df9072"}]
06:53:54 INFO - 1499867634130 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:54 INFO - 1499867634131 Marionette TRACE 382 <- [1,4,null,{}]
06:53:54 INFO - 1499867634133 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}]
06:53:54 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499867634135 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:53:54 INFO - 1499867634136 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:53:54 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 318ms
06:53:54 INFO - TEST-START | test_click.py TestClick.test_input_file
06:53:54 INFO - 1499867634137 Marionette TRACE 382 <- [1,6,null,{}]
06:53:54 INFO - 1499867634139 Marionette DEBUG Closed connection 382
06:53:54 INFO - 1499867634140 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49801
06:53:54 INFO - 1499867634141 Marionette DEBUG Closed connection 383
06:53:54 INFO - 1499867634141 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49802
06:53:54 INFO - 1499867634142 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:54 INFO - 1499867634143 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634145 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634147 Marionette TRACE 384 <- [1,1,null,{"sessionId":"1d90731d-72c8-475e-9d32-e2556a7add15","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634149 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:53:54 INFO - 1499867634149 Marionette TRACE 384 <- [1,2,null,{}]
06:53:54 INFO - 1499867634150 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:54 INFO - 1499867634150 Marionette TRACE 384 <- [1,3,null,{}]
06:53:54 INFO - 1499867634151 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:54 INFO - 1499867634152 Marionette TRACE 384 <- [1,4,null,{}]
06:53:54 INFO - 1499867634153 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}]
06:53:54 INFO - TEST-START: test_click.py TestClick.test_input_file1499867634155 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:53:54 INFO - 1499867634156 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:53:54 INFO - 1499867634157 Marionette TRACE 384 <- [1,6,null,{}]
06:53:54 INFO - 1499867634159 Marionette DEBUG Closed connection 384
06:53:54 INFO - 1499867634159 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49803
06:53:54 INFO - 1499867634160 Marionette DEBUG Closed connection 385
06:53:54 INFO - 1499867634161 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49804
06:53:54 INFO - 1499867634162 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:54 INFO - 1499867634162 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634164 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634166 Marionette TRACE 386 <- [1,1,null,{"sessionId":"fa13d729-9609-41e8-9ebe-1ecb4971ec2f","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634168 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:53:54 INFO - 1499867634169 Marionette TRACE 386 <- [1,2,null,{}]
06:53:54 INFO - 1499867634171 Marionette DEBUG Closed connection 386
06:53:54 INFO - 1499867634171 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49805
06:53:54 INFO - 1499867634172 Marionette DEBUG Closed connection 387
06:53:54 INFO - 1499867634172 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49806
06:53:54 INFO - 1499867634173 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:54 INFO - 1499867634174 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634176 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634178 Marionette TRACE 388 <- [1,1,null,{"sessionId":"57d88bfb-fb28-4fb3-b37e-0159be962100","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634180 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:53:54 INFO - 1499867634181 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"
06:53:54 INFO - 1499867634183 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"
06:53:54 INFO - 1499867634183 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"
06:53:54 INFO - 1499867634192 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:54 INFO - 1499867634200 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:54 INFO - 1499867634203 Marionette TRACE 388 <- [1,2,null,{}]
06:53:54 INFO - 1499867634205 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:53:54 INFO - 1499867634207 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ec8797a9-5338-46b7-b5e1-68244c01b1dd","ELEMENT":"ec8797a9-5338-46b7-b5e1-68244c01b1dd"}}]
06:53:54 INFO - 1499867634208 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"ec8797a9-5338-46b7-b5e1-68244c01b1dd"}]
06:53:54 INFO - 1499867634249 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:53:54 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 128ms
06:53:54 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:53:54 INFO - 1499867634259 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}]
06:53:54 INFO - TEST-END: test_click.py TestClick.test_input_file1499867634262 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:53:54 INFO - 1499867634263 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:53:54 INFO - 1499867634265 Marionette TRACE 388 <- [1,6,null,{}]
06:53:54 INFO - 1499867634266 Marionette DEBUG Closed connection 388
06:53:54 INFO - 1499867634267 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49807
06:53:54 INFO - 1499867634269 Marionette DEBUG Closed connection 389
06:53:54 INFO - 1499867634269 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49808
06:53:54 INFO - 1499867634270 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:54 INFO - 1499867634270 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634273 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634275 Marionette TRACE 390 <- [1,1,null,{"sessionId":"ef52d7c0-b8ad-4f99-bdc0-612cb671e8c8","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634277 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:53:54 INFO - 1499867634278 Marionette TRACE 390 <- [1,2,null,{}]
06:53:54 INFO - 1499867634279 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:54 INFO - 1499867634279 Marionette TRACE 390 <- [1,3,null,{}]
06:53:54 INFO - 1499867634280 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:54 INFO - 1499867634280 Marionette TRACE 390 <- [1,4,null,{}]
06:53:54 INFO - 1499867634282 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}]
06:53:54 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499867634284 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:53:54 INFO - 1499867634285 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:53:54 INFO - 1499867634286 Marionette TRACE 390 <- [1,6,null,{}]
06:53:54 INFO - 1499867634288 Marionette DEBUG Closed connection 390
06:53:54 INFO - 1499867634288 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49809
06:53:54 INFO - 1499867634289 Marionette DEBUG Closed connection 391
06:53:54 INFO - 1499867634290 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49810
06:53:54 INFO - 1499867634290 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:54 INFO - 1499867634291 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634293 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634296 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fc91705f-d4b9-4abc-b0eb-337f0bcb027c","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634297 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:53:54 INFO - 1499867634298 Marionette TRACE 392 <- [1,2,null,{}]
06:53:54 INFO - 1499867634300 Marionette DEBUG Closed connection 392
06:53:54 INFO - 1499867634300 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49811
06:53:54 INFO - 1499867634301 Marionette DEBUG Closed connection 393
06:53:54 INFO - 1499867634302 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49812
06:53:54 INFO - 1499867634303 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:54 INFO - 1499867634303 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634305 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634308 Marionette TRACE 394 <- [1,1,null,{"sessionId":"8e301af0-a025-4aba-b289-c9aa8a7d212c","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634310 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"}]
06:53:54 INFO - 1499867634311 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:54 INFO - 1499867634313 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:54 INFO - 1499867634313 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:54 INFO - 1499867634322 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"
06:53:54 INFO - 1499867634325 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"
06:53:54 INFO - 1499867634328 Marionette TRACE 394 <- [1,2,null,{}]
06:53:54 INFO - 1499867634338 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:53:54 INFO - 1499867634340 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2317e9d8-689d-4188-bbca-d2a179e97d8c","ELEMENT":"2317e9d8-689d-4188-bbca-d2a179e97d8c"}}]
06:53:54 INFO - 1499867634341 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:53:54 INFO - 1499867634343 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a88e4645-2303-413e-87b9-2b50a5bcfa26","ELEMENT":"a88e4645-2303-413e-87b9-2b50a5bcfa26"}}]
06:53:54 INFO - 1499867634344 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2317e9d8-689d-4188-bbca-d2a179e97d8c"}]
06:53:54 INFO - 1499867634579 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:54 INFO - 1499867634580 Marionette TRACE 394 <- [1,5,null,{}]
06:53:54 INFO - 1499867634581 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a88e4645-2303-413e-87b9-2b50a5bcfa26"}]
06:53:54 INFO - 1499867634594 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:53:54 INFO - 1499867634596 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:53:54 INFO - 1499867634598 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:53:54 INFO - 1499867634599 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}]
06:53:54 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499867634601 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:53:54 INFO - 1499867634602 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:53:54 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 338ms
06:53:54 INFO - 1499867634603 Marionette TRACE 394 <- [1,9,null,{}]
06:53:54 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:53:54 INFO - 1499867634605 Marionette DEBUG Closed connection 394
06:53:54 INFO - 1499867634606 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49813
06:53:54 INFO - 1499867634606 Marionette DEBUG Closed connection 395
06:53:54 INFO - 1499867634607 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49814
06:53:54 INFO - 1499867634608 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:54 INFO - 1499867634608 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634610 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634619 Marionette TRACE 396 <- [1,1,null,{"sessionId":"faac469d-4e34-439d-bca4-8e44a7551f07","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634621 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:53:54 INFO - 1499867634621 Marionette TRACE 396 <- [1,2,null,{}]
06:53:54 INFO - 1499867634622 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:54 INFO - 1499867634622 Marionette TRACE 396 <- [1,3,null,{}]
06:53:54 INFO - 1499867634623 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:54 INFO - 1499867634623 Marionette TRACE 396 <- [1,4,null,{}]
06:53:54 INFO - 1499867634625 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}]
06:53:54 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499867634627 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:53:54 INFO - 1499867634628 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:53:54 INFO - 1499867634629 Marionette TRACE 396 <- [1,6,null,{}]
06:53:54 INFO - 1499867634631 Marionette DEBUG Closed connection 396
06:53:54 INFO - 1499867634631 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49815
06:53:54 INFO - 1499867634632 Marionette DEBUG Closed connection 397
06:53:54 INFO - 1499867634633 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49816
06:53:54 INFO - 1499867634634 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:54 INFO - 1499867634634 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634636 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634639 Marionette TRACE 398 <- [1,1,null,{"sessionId":"eaadaeaf-63e2-476a-bb50-78faa77a8d12","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634640 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:53:54 INFO - 1499867634642 Marionette TRACE 398 <- [1,2,null,{}]
06:53:54 INFO - 1499867634643 Marionette DEBUG Closed connection 398
06:53:54 INFO - 1499867634644 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49817
06:53:54 INFO - 1499867634645 Marionette DEBUG Closed connection 399
06:53:54 INFO - 1499867634645 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49818
06:53:54 INFO - 1499867634646 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:54 INFO - 1499867634647 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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}
06:53:54 INFO - 1499867634649 Marionette DEBUG Register listener.js for window 15
06:53:54 INFO - 1499867634651 Marionette TRACE 400 <- [1,1,null,{"sessionId":"5b8426f8-472f-4ff5-974b-368e90a1de66","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":2344,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-028.001\\AppData\\Local\\Temp\\tmpjcb5g4.mozrunner","moz:accessibilityChecks":false}}]
06:53:54 INFO - 1499867634653 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"}]
06:53:54 INFO - 1499867634654 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"
06:53:54 INFO - 1499867634656 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"
06:53:54 INFO - 1499867634656 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"
06:53:54 INFO - 1499867634673 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"
06:53:54 INFO - 1499867634675 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"
06:53:54 INFO - 1499867634678 Marionette TRACE 400 <- [1,2,null,{}]
06:53:54 INFO - 1499867634684 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:53:54 INFO - 1499867634686 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9b581eee-43f2-475a-8434-2e6d229bc9b9","ELEMENT":"9b581eee-43f2-475a-8434-2e6d229bc9b9"}}]
06:53:54 INFO - 1499867634687 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9b581eee-43f2-475a-8434-2e6d229bc9b9"}]
06:53:54 INFO - 1499867634708 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:53:54 INFO - 1499867634710 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9b581eee-43f2-475a-8434-2e6d229bc9b9"}]
06:53:54 INFO - 1499867634723 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element