could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
19:05:11 INFO - 1498961111614 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}]
19:05:11 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498961111617 Marionette TRACE 352 <- [1,5,null,{"value":null}]
19:05:11 INFO - 1498961111618 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
19:05:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 132ms
19:05:11 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:05:11 INFO - 1498961111619 Marionette TRACE 352 <- [1,6,null,{}]
19:05:11 INFO - 1498961111621 Marionette DEBUG Closed connection 352
19:05:11 INFO - 1498961111622 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49768
19:05:11 INFO - 1498961111623 Marionette DEBUG Closed connection 353
19:05:11 INFO - 1498961111623 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49769
19:05:11 INFO - 1498961111624 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:11 INFO - 1498961111625 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:11 INFO - 1498961111628 Marionette DEBUG Register listener.js for window 4294967297
19:05:11 INFO - 1498961111631 Marionette TRACE 354 <- [1,1,null,{"sessionId":"a795b545-db97-4324-82b0-c440fbc545ed","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:11 INFO - 1498961111632 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
19:05:11 INFO - 1498961111633 Marionette TRACE 354 <- [1,2,null,{}]
19:05:11 INFO - 1498961111634 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:05:11 INFO - 1498961111635 Marionette TRACE 354 <- [1,3,null,{}]
19:05:11 INFO - 1498961111635 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
19:05:11 INFO - 1498961111636 Marionette TRACE 354 <- [1,4,null,{}]
19:05:11 INFO - 1498961111638 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}]
19:05:11 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498961111641 Marionette TRACE 354 <- [1,5,null,{"value":null}]
19:05:11 INFO - 1498961111641 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
19:05:11 INFO - 1498961111643 Marionette TRACE 354 <- [1,6,null,{}]
19:05:11 INFO - 1498961111645 Marionette DEBUG Closed connection 354
19:05:11 INFO - 1498961111645 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49770
19:05:11 INFO - 1498961111646 Marionette DEBUG Closed connection 355
19:05:11 INFO - 1498961111647 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49771
19:05:11 INFO - 1498961111648 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:11 INFO - 1498961111648 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:11 INFO - 1498961111651 Marionette DEBUG Register listener.js for window 4294967297
19:05:11 INFO - 1498961111655 Marionette TRACE 356 <- [1,1,null,{"sessionId":"57a1ceb3-21e6-4883-a2b3-272c294707cf","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:11 INFO - 1498961111656 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
19:05:11 INFO - 1498961111657 Marionette TRACE 356 <- [1,2,null,{}]
19:05:11 INFO - 1498961111659 Marionette DEBUG Closed connection 356
19:05:11 INFO - 1498961111660 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49772
19:05:11 INFO - 1498961111660 Marionette DEBUG Closed connection 357
19:05:11 INFO - 1498961111661 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49773
19:05:11 INFO - 1498961111662 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:05:11 INFO - 1498961111663 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:11 INFO - 1498961111665 Marionette DEBUG Register listener.js for window 4294967297
19:05:11 INFO - 1498961111669 Marionette TRACE 358 <- [1,1,null,{"sessionId":"04dfbd21-218c-47f1-b1d7-26c27c053b91","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:11 INFO - 1498961111670 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
19:05:11 INFO - 1498961111672 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
19:05:11 INFO - 1498961111680 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
19:05:11 INFO - 1498961111681 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
19:05:11 INFO - 1498961111689 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
19:05:11 INFO - 1498961111694 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
19:05:11 INFO - 1498961111695 Marionette TRACE 358 <- [1,2,null,{}]
19:05:11 INFO - 1498961111698 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:05:11 INFO - 1498961111704 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"580f4487-e607-4c02-b99f-4e9ef1e71ad2","ELEMENT":"580f4487-e607-4c02-b99f-4e9ef1e71ad2"}}]
19:05:11 INFO - 1498961111705 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"580f4487-e607-4c02-b99f-4e9ef1e71ad2"}]
19:05:11 INFO - 1498961111748 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
19:05:11 INFO - 1498961111758 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
19:05:11 INFO - 1498961111758 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
19:05:11 INFO - 1498961111770 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
19:05:11 INFO - 1498961111774 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
19:05:11 INFO - 1498961111775 Marionette TRACE 358 <- [1,4,null,{}]
19:05:11 INFO - 1498961111778 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:05:11 INFO - 1498961111781 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f71f8e6-61b8-4093-88f3-c862eaecd700","ELEMENT":"2f71f8e6-61b8-4093-88f3-c862eaecd700"}}]
19:05:11 INFO - 1498961111786 Marionette TRACE 358 -> [0,6,"getTitle",{}]
19:05:11 INFO - 1498961111788 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
19:05:11 INFO - 1498961111790 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}]
19:05:11 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498961111794 Marionette TRACE 358 <- [1,7,null,{"value":null}]
19:05:11 INFO - 1498961111795 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
19:05:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 176ms
19:05:11 INFO - 1498961111797 Marionette TRACE 358 <- [1,8,null,{}]
19:05:11 INFO - TEST-START | test_click.py TestClick.test_container_element
19:05:11 INFO - 1498961111799 Marionette DEBUG Closed connection 358
19:05:11 INFO - 1498961111800 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49776
19:05:11 INFO - 1498961111801 Marionette DEBUG Closed connection 359
19:05:11 INFO - 1498961111801 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49777
19:05:11 INFO - 1498961111802 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:11 INFO - 1498961111803 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:11 INFO - 1498961111806 Marionette DEBUG Register listener.js for window 4294967297
19:05:11 INFO - 1498961111810 Marionette TRACE 360 <- [1,1,null,{"sessionId":"6a9e7637-7f66-46aa-b22e-b31531403568","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:11 INFO - 1498961111812 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
19:05:11 INFO - 1498961111812 Marionette TRACE 360 <- [1,2,null,{}]
19:05:11 INFO - 1498961111813 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:05:11 INFO - 1498961111814 Marionette TRACE 360 <- [1,3,null,{}]
19:05:11 INFO - 1498961111815 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
19:05:11 INFO - 1498961111815 Marionette TRACE 360 <- [1,4,null,{}]
19:05:11 INFO - 1498961111817 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}]
19:05:11 INFO - TEST-START: test_click.py TestClick.test_container_element1498961111820 Marionette TRACE 360 <- [1,5,null,{"value":null}]
19:05:11 INFO - 1498961111821 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
19:05:11 INFO - 1498961111822 Marionette TRACE 360 <- [1,6,null,{}]
19:05:11 INFO - 1498961111824 Marionette DEBUG Closed connection 360
19:05:11 INFO - 1498961111824 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49778
19:05:11 INFO - 1498961111825 Marionette DEBUG Closed connection 361
19:05:11 INFO - 1498961111826 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49779
19:05:11 INFO - 1498961111827 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:11 INFO - 1498961111828 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:11 INFO - 1498961111830 Marionette DEBUG Register listener.js for window 4294967297
19:05:11 INFO - 1498961111834 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1c6ecb4d-4d87-4d45-b926-e9d69f247e8e","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:11 INFO - 1498961111835 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
19:05:11 INFO - 1498961111836 Marionette TRACE 362 <- [1,2,null,{}]
19:05:11 INFO - 1498961111838 Marionette DEBUG Closed connection 362
19:05:11 INFO - 1498961111839 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49780
19:05:11 INFO - 1498961111840 Marionette DEBUG Closed connection 363
19:05:11 INFO - 1498961111840 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49781
19:05:11 INFO - 1498961111841 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:05:11 INFO - 1498961111842 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:11 INFO - 1498961111845 Marionette DEBUG Register listener.js for window 4294967297
19:05:11 INFO - 1498961111848 Marionette TRACE 364 <- [1,1,null,{"sessionId":"ccc3340a-bdc7-4a9a-9f7c-6b714f878d8a","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:11 INFO - 1498961111849 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"}]
19:05:11 INFO - 1498961111851 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
19:05:11 INFO - 1498961111853 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
19:05:11 INFO - 1498961111854 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
19:05:11 INFO - 1498961111860 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"
19:05:11 INFO - 1498961111864 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"
19:05:11 INFO - 1498961111874 Marionette TRACE 364 <- [1,2,null,{}]
19:05:11 INFO - 1498961111878 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:05:11 INFO - 1498961111885 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fea3aff6-3e62-4ca7-9732-afd48391d105","ELEMENT":"fea3aff6-3e62-4ca7-9732-afd48391d105"}}]
19:05:11 INFO - 1498961111886 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"fea3aff6-3e62-4ca7-9732-afd48391d105"}]
19:05:12 INFO - 1498961112136 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:05:12 INFO - 1498961112137 Marionette TRACE 364 <- [1,4,null,{}]
19:05:12 INFO - 1498961112138 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"fea3aff6-3e62-4ca7-9732-afd48391d105","name":"selected"}]
19:05:12 INFO - 1498961112143 Marionette TRACE 364 <- [1,5,null,{"value":true}]
19:05:12 INFO - 1498961112145 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}]
19:05:12 INFO - TEST-END: test_click.py TestClick.test_container_element1498961112147 Marionette TRACE 364 <- [1,6,null,{"value":null}]
19:05:12 INFO - 1498961112148 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
19:05:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 353ms
19:05:12 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:05:12 INFO - 1498961112150 Marionette TRACE 364 <- [1,7,null,{}]
19:05:12 INFO - 1498961112151 Marionette DEBUG Closed connection 364
19:05:12 INFO - 1498961112152 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49782
19:05:12 INFO - 1498961112153 Marionette DEBUG Closed connection 365
19:05:12 INFO - 1498961112153 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49783
19:05:12 INFO - 1498961112155 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:12 INFO - 1498961112155 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:12 INFO - 1498961112158 Marionette DEBUG Register listener.js for window 4294967297
19:05:12 INFO - 1498961112161 Marionette TRACE 366 <- [1,1,null,{"sessionId":"2b5ac50a-3346-4b34-93b0-2eae1eb0dee1","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:12 INFO - 1498961112163 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
19:05:12 INFO - 1498961112163 Marionette TRACE 366 <- [1,2,null,{}]
19:05:12 INFO - 1498961112164 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:05:12 INFO - 1498961112165 Marionette TRACE 366 <- [1,3,null,{}]
19:05:12 INFO - 1498961112166 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
19:05:12 INFO - 1498961112166 Marionette TRACE 366 <- [1,4,null,{}]
19:05:12 INFO - 1498961112169 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}]
19:05:12 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498961112171 Marionette TRACE 366 <- [1,5,null,{"value":null}]
19:05:12 INFO - 1498961112172 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
19:05:12 INFO - 1498961112174 Marionette TRACE 366 <- [1,6,null,{}]
19:05:12 INFO - 1498961112175 Marionette DEBUG Closed connection 366
19:05:12 INFO - 1498961112176 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49784
19:05:12 INFO - 1498961112177 Marionette DEBUG Closed connection 367
19:05:12 INFO - 1498961112177 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49785
19:05:12 INFO - 1498961112178 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:12 INFO - 1498961112179 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:12 INFO - 1498961112182 Marionette DEBUG Register listener.js for window 4294967297
19:05:12 INFO - 1498961112185 Marionette TRACE 368 <- [1,1,null,{"sessionId":"508dcdfa-a3e8-4fb9-a7fa-6359b66230f5","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:12 INFO - 1498961112186 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
19:05:12 INFO - 1498961112188 Marionette TRACE 368 <- [1,2,null,{}]
19:05:12 INFO - 1498961112189 Marionette DEBUG Closed connection 368
19:05:12 INFO - 1498961112190 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49786
19:05:12 INFO - 1498961112191 Marionette DEBUG Closed connection 369
19:05:12 INFO - 1498961112191 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49787
19:05:12 INFO - 1498961112193 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:05:12 INFO - 1498961112193 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:12 INFO - 1498961112196 Marionette DEBUG Register listener.js for window 4294967297
19:05:12 INFO - 1498961112199 Marionette TRACE 370 <- [1,1,null,{"sessionId":"97ccf240-314c-403b-a842-b9b36d88a159","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:12 INFO - 1498961112200 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"}]
19:05:12 INFO - 1498961112202 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"
19:05:12 INFO - 1498961112204 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"
19:05:12 INFO - 1498961112204 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"
19:05:12 INFO - 1498961112211 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"
19:05:12 INFO - 1498961112214 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"
19:05:12 INFO - 1498961112220 Marionette TRACE 370 <- [1,2,null,{}]
19:05:12 INFO - 1498961112224 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:05:12 INFO - 1498961112227 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"01101cc4-b186-49cd-a020-1578b6a61fe1","ELEMENT":"01101cc4-b186-49cd-a020-1578b6a61fe1"}}]
19:05:12 INFO - 1498961112228 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"01101cc4-b186-49cd-a020-1578b6a61fe1"}]
19:05:12 INFO - 1498961112475 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:05:12 INFO - 1498961112476 Marionette TRACE 370 <- [1,4,null,{}]
19:05:12 INFO - 1498961112477 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"01101cc4-b186-49cd-a020-1578b6a61fe1","name":"selected"}]
19:05:12 INFO - 1498961112482 Marionette TRACE 370 <- [1,5,null,{"value":true}]
19:05:12 INFO - 1498961112484 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}]
19:05:12 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498961112486 Marionette TRACE 370 <- [1,6,null,{"value":null}]
19:05:12 INFO - 1498961112488 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
19:05:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 340ms
19:05:12 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:05:12 INFO - 1498961112489 Marionette TRACE 370 <- [1,7,null,{}]
19:05:12 INFO - 1498961112491 Marionette DEBUG Closed connection 370
19:05:12 INFO - 1498961112492 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49788
19:05:12 INFO - 1498961112493 Marionette DEBUG Closed connection 371
19:05:12 INFO - 1498961112493 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49789
19:05:12 INFO - 1498961112494 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:12 INFO - 1498961112495 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:12 INFO - 1498961112498 Marionette DEBUG Register listener.js for window 4294967297
19:05:12 INFO - 1498961112501 Marionette TRACE 372 <- [1,1,null,{"sessionId":"04e73337-07f0-40ba-bb41-5bd2f0ede835","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:12 INFO - 1498961112502 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
19:05:12 INFO - 1498961112503 Marionette TRACE 372 <- [1,2,null,{}]
19:05:12 INFO - 1498961112504 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:05:12 INFO - 1498961112504 Marionette TRACE 372 <- [1,3,null,{}]
19:05:12 INFO - 1498961112505 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
19:05:12 INFO - 1498961112505 Marionette TRACE 372 <- [1,4,null,{}]
19:05:12 INFO - 1498961112508 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}]
19:05:12 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498961112510 Marionette TRACE 372 <- [1,5,null,{"value":null}]
19:05:12 INFO - 1498961112511 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
19:05:12 INFO - 1498961112513 Marionette TRACE 372 <- [1,6,null,{}]
19:05:12 INFO - 1498961112514 Marionette DEBUG Closed connection 372
19:05:12 INFO - 1498961112515 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49790
19:05:12 INFO - 1498961112516 Marionette DEBUG Closed connection 373
19:05:12 INFO - 1498961112516 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49791
19:05:12 INFO - 1498961112518 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:12 INFO - 1498961112518 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:12 INFO - 1498961112521 Marionette DEBUG Register listener.js for window 4294967297
19:05:12 INFO - 1498961112525 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c8029492-84f8-48a2-a0f2-354e1d3aaf2b","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:12 INFO - 1498961112527 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
19:05:12 INFO - 1498961112528 Marionette TRACE 374 <- [1,2,null,{}]
19:05:12 INFO - 1498961112530 Marionette DEBUG Closed connection 374
19:05:12 INFO - 1498961112530 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49792
19:05:12 INFO - 1498961112531 Marionette DEBUG Closed connection 375
19:05:12 INFO - 1498961112532 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49793
19:05:12 INFO - 1498961112533 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:05:12 INFO - 1498961112534 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:12 INFO - 1498961112537 Marionette DEBUG Register listener.js for window 4294967297
19:05:12 INFO - 1498961112540 Marionette TRACE 376 <- [1,1,null,{"sessionId":"7a651ed8-b4f6-4233-b149-c4112159a62d","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:12 INFO - 1498961112542 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"}]
19:05:12 INFO - 1498961112544 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"
19:05:12 INFO - 1498961112546 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"
19:05:12 INFO - 1498961112546 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"
19:05:12 INFO - 1498961112555 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"
19:05:12 INFO - 1498961112557 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"
19:05:12 INFO - 1498961112561 Marionette TRACE 376 <- [1,2,null,{}]
19:05:12 INFO - 1498961112564 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:05:12 INFO - 1498961112571 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a6e84ce9-ff3d-4d8b-a397-fd0863bca6ff","ELEMENT":"a6e84ce9-ff3d-4d8b-a397-fd0863bca6ff"}}]
19:05:12 INFO - 1498961112572 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"a6e84ce9-ff3d-4d8b-a397-fd0863bca6ff"}]
19:05:12 INFO - 1498961112816 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:05:12 INFO - 1498961112834 Marionette TRACE 376 <- [1,4,null,{}]
19:05:12 INFO - 1498961112842 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}]
19:05:12 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498961112846 Marionette TRACE 376 <- [1,5,null,{"value":null}]
19:05:12 INFO - 1498961112848 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
19:05:12 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 361ms
19:05:12 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:05:12 INFO - 1498961112851 Marionette TRACE 376 <- [1,6,null,{}]
19:05:12 INFO - 1498961112858 Marionette DEBUG Closed connection 376
19:05:12 INFO - 1498961112859 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49794
19:05:12 INFO - 1498961112860 Marionette DEBUG Closed connection 377
19:05:12 INFO - 1498961112861 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49795
19:05:12 INFO - 1498961112863 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:12 INFO - 1498961112867 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:12 INFO - 1498961112870 Marionette DEBUG Register listener.js for window 4294967297
19:05:12 INFO - 1498961112878 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f13b50e0-e317-4f50-99dd-adcd24425a23","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:12 INFO - 1498961112884 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
19:05:12 INFO - 1498961112886 Marionette TRACE 378 <- [1,2,null,{}]
19:05:12 INFO - 1498961112887 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:05:12 INFO - 1498961112888 Marionette TRACE 378 <- [1,3,null,{}]
19:05:12 INFO - 1498961112890 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
19:05:12 INFO - 1498961112891 Marionette TRACE 378 <- [1,4,null,{}]
19:05:12 INFO - 1498961112893 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}]
19:05:12 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498961112939 Marionette TRACE 378 <- [1,5,null,{"value":null}]
19:05:12 INFO - 1498961112941 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
19:05:12 INFO - 1498961112943 Marionette TRACE 378 <- [1,6,null,{}]
19:05:12 INFO - 1498961112946 Marionette DEBUG Closed connection 378
19:05:12 INFO - 1498961112947 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49796
19:05:12 INFO - 1498961112948 Marionette DEBUG Closed connection 379
19:05:12 INFO - 1498961112949 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49797
19:05:12 INFO - 1498961112951 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:12 INFO - 1498961112952 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:12 INFO - 1498961112987 Marionette DEBUG Register listener.js for window 4294967297
19:05:12 INFO - 1498961112992 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d7660ad3-0081-4df5-b4da-465721332706","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:12 INFO - 1498961112994 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
19:05:12 INFO - 1498961112996 Marionette TRACE 380 <- [1,2,null,{}]
19:05:13 INFO - 1498961112999 Marionette DEBUG Closed connection 380
19:05:13 INFO - 1498961112999 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49798
19:05:13 INFO - 1498961113001 Marionette DEBUG Closed connection 381
19:05:13 INFO - 1498961113001 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49799
19:05:13 INFO - 1498961113004 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:05:13 INFO - 1498961113006 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113009 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113013 Marionette TRACE 382 <- [1,1,null,{"sessionId":"1f3b9b63-629d-48b7-9a5e-0cabcfffd7bf","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113015 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"}]
19:05:13 INFO - 1498961113017 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"
19:05:13 INFO - 1498961113019 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"
19:05:13 INFO - 1498961113019 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"
19:05:13 INFO - 1498961113026 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"
19:05:13 INFO - 1498961113029 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"
19:05:13 INFO - 1498961113042 Marionette TRACE 382 <- [1,2,null,{}]
19:05:13 INFO - 1498961113047 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:05:13 INFO - 1498961113052 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa0ccecd-7a9c-4af3-bbbc-9aa7ad4aa307","ELEMENT":"fa0ccecd-7a9c-4af3-bbbc-9aa7ad4aa307"}}]
19:05:13 INFO - 1498961113054 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"fa0ccecd-7a9c-4af3-bbbc-9aa7ad4aa307"}]
19:05:13 INFO - 1498961113302 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:05:13 INFO - 1498961113303 Marionette TRACE 382 <- [1,4,null,{}]
19:05:13 INFO - 1498961113306 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}]
19:05:13 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498961113309 Marionette TRACE 382 <- [1,5,null,{"value":null}]
19:05:13 INFO - 1498961113310 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
19:05:13 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 460ms
19:05:13 INFO - TEST-START | test_click.py TestClick.test_input_file
19:05:13 INFO - 1498961113312 Marionette TRACE 382 <- [1,6,null,{}]
19:05:13 INFO - 1498961113314 Marionette DEBUG Closed connection 382
19:05:13 INFO - 1498961113315 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49800
19:05:13 INFO - 1498961113316 Marionette DEBUG Closed connection 383
19:05:13 INFO - 1498961113316 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49801
19:05:13 INFO - 1498961113317 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:13 INFO - 1498961113318 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113321 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113326 Marionette TRACE 384 <- [1,1,null,{"sessionId":"aed0b6f2-6022-460f-be7f-acc786c7a39f","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113327 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
19:05:13 INFO - 1498961113328 Marionette TRACE 384 <- [1,2,null,{}]
19:05:13 INFO - 1498961113329 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:05:13 INFO - 1498961113330 Marionette TRACE 384 <- [1,3,null,{}]
19:05:13 INFO - 1498961113330 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
19:05:13 INFO - 1498961113331 Marionette TRACE 384 <- [1,4,null,{}]
19:05:13 INFO - 1498961113333 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}]
19:05:13 INFO - TEST-START: test_click.py TestClick.test_input_file1498961113336 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:05:13 INFO - 1498961113337 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:05:13 INFO - 1498961113339 Marionette TRACE 384 <- [1,6,null,{}]
19:05:13 INFO - 1498961113341 Marionette DEBUG Closed connection 384
19:05:13 INFO - 1498961113341 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49802
19:05:13 INFO - 1498961113342 Marionette DEBUG Closed connection 385
19:05:13 INFO - 1498961113343 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49803
19:05:13 INFO - 1498961113344 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:13 INFO - 1498961113345 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113348 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113352 Marionette TRACE 386 <- [1,1,null,{"sessionId":"7bf4ec8a-8d4e-4f1e-9e82-512f9226b220","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113355 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
19:05:13 INFO - 1498961113357 Marionette TRACE 386 <- [1,2,null,{}]
19:05:13 INFO - 1498961113359 Marionette DEBUG Closed connection 386
19:05:13 INFO - 1498961113362 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49804
19:05:13 INFO - 1498961113363 Marionette DEBUG Closed connection 387
19:05:13 INFO - 1498961113364 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49805
19:05:13 INFO - 1498961113365 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:05:13 INFO - 1498961113367 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113369 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113374 Marionette TRACE 388 <- [1,1,null,{"sessionId":"38a0f9fa-7663-4681-9a1e-5c68d9a5801b","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113375 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:05:13 INFO - 1498961113377 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"
19:05:13 INFO - 1498961113379 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"
19:05:13 INFO - 1498961113379 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"
19:05:13 INFO - 1498961113386 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:05:13 INFO - 1498961113391 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:05:13 INFO - 1498961113398 Marionette TRACE 388 <- [1,2,null,{}]
19:05:13 INFO - 1498961113406 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:05:13 INFO - 1498961113409 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"66d5018c-350b-49be-85b9-76ac0dc5f3c0","ELEMENT":"66d5018c-350b-49be-85b9-76ac0dc5f3c0"}}]
19:05:13 INFO - 1498961113410 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"66d5018c-350b-49be-85b9-76ac0dc5f3c0"}]
19:05:13 INFO - 1498961113447 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
19:05:13 INFO - 1498961113449 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}]
19:05:13 INFO - TEST-END: test_click.py TestClick.test_input_file1498961113452 Marionette TRACE 388 <- [1,5,null,{"value":null}]
19:05:13 INFO - 1498961113454 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
19:05:13 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 143ms
19:05:13 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:05:13 INFO - 1498961113455 Marionette TRACE 388 <- [1,6,null,{}]
19:05:13 INFO - 1498961113457 Marionette DEBUG Closed connection 388
19:05:13 INFO - 1498961113458 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49806
19:05:13 INFO - 1498961113459 Marionette DEBUG Closed connection 389
19:05:13 INFO - 1498961113459 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49807
19:05:13 INFO - 1498961113461 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:13 INFO - 1498961113461 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113464 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113468 Marionette TRACE 390 <- [1,1,null,{"sessionId":"08690c7c-22e7-4027-bd2b-af0045ffe0e9","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113469 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
19:05:13 INFO - 1498961113470 Marionette TRACE 390 <- [1,2,null,{}]
19:05:13 INFO - 1498961113472 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:05:13 INFO - 1498961113472 Marionette TRACE 390 <- [1,3,null,{}]
19:05:13 INFO - 1498961113473 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
19:05:13 INFO - 1498961113474 Marionette TRACE 390 <- [1,4,null,{}]
19:05:13 INFO - 1498961113475 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}]
19:05:13 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498961113479 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:05:13 INFO - 1498961113480 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:05:13 INFO - 1498961113482 Marionette TRACE 390 <- [1,6,null,{}]
19:05:13 INFO - 1498961113483 Marionette DEBUG Closed connection 390
19:05:13 INFO - 1498961113484 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49808
19:05:13 INFO - 1498961113485 Marionette DEBUG Closed connection 391
19:05:13 INFO - 1498961113485 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49809
19:05:13 INFO - 1498961113487 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:13 INFO - 1498961113487 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113490 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113494 Marionette TRACE 392 <- [1,1,null,{"sessionId":"c6b4292a-8735-4f03-a699-0f51260cee5f","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113495 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
19:05:13 INFO - 1498961113496 Marionette TRACE 392 <- [1,2,null,{}]
19:05:13 INFO - 1498961113498 Marionette DEBUG Closed connection 392
19:05:13 INFO - 1498961113499 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49810
19:05:13 INFO - 1498961113499 Marionette DEBUG Closed connection 393
19:05:13 INFO - 1498961113500 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49811
19:05:13 INFO - 1498961113501 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:05:13 INFO - 1498961113502 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113505 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113508 Marionette TRACE 394 <- [1,1,null,{"sessionId":"51d478da-5045-4752-afdd-a4a4ca360887","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113510 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"}]
19:05:13 INFO - 1498961113512 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:05:13 INFO - 1498961113514 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:05:13 INFO - 1498961113514 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:05:13 INFO - 1498961113522 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"
19:05:13 INFO - 1498961113525 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"
19:05:13 INFO - 1498961113530 Marionette TRACE 394 <- [1,2,null,{}]
19:05:13 INFO - 1498961113540 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:05:13 INFO - 1498961113543 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c5a1ca0-f225-4506-bdb0-d98274eafa79","ELEMENT":"6c5a1ca0-f225-4506-bdb0-d98274eafa79"}}]
19:05:13 INFO - 1498961113544 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:05:13 INFO - 1498961113546 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8b33a593-a489-4d00-aebc-67cde27d2d9c","ELEMENT":"8b33a593-a489-4d00-aebc-67cde27d2d9c"}}]
19:05:13 INFO - 1498961113547 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"6c5a1ca0-f225-4506-bdb0-d98274eafa79"}]
19:05:13 INFO - 1498961113788 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:05:13 INFO - 1498961113790 Marionette TRACE 394 <- [1,5,null,{}]
19:05:13 INFO - 1498961113791 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"8b33a593-a489-4d00-aebc-67cde27d2d9c"}]
19:05:13 INFO - 1498961113808 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
19:05:13 INFO - 1498961113810 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
19:05:13 INFO - 1498961113813 Marionette TRACE 394 <- [1,7,null,{"value":false}]
19:05:13 INFO - 1498961113814 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}]
19:05:13 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498961113817 Marionette TRACE 394 <- [1,8,null,{"value":null}]
19:05:13 INFO - 1498961113818 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
19:05:13 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 363ms
19:05:13 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:05:13 INFO - 1498961113819 Marionette TRACE 394 <- [1,9,null,{}]
19:05:13 INFO - 1498961113821 Marionette DEBUG Closed connection 394
19:05:13 INFO - 1498961113822 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49812
19:05:13 INFO - 1498961113823 Marionette DEBUG Closed connection 395
19:05:13 INFO - 1498961113823 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49813
19:05:13 INFO - 1498961113825 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:13 INFO - 1498961113825 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113828 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113831 Marionette TRACE 396 <- [1,1,null,{"sessionId":"dd21ce97-0027-4833-bee9-f80e732bc3bf","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113833 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
19:05:13 INFO - 1498961113833 Marionette TRACE 396 <- [1,2,null,{}]
19:05:13 INFO - 1498961113834 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:05:13 INFO - 1498961113835 Marionette TRACE 396 <- [1,3,null,{}]
19:05:13 INFO - 1498961113836 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
19:05:13 INFO - 1498961113836 Marionette TRACE 396 <- [1,4,null,{}]
19:05:13 INFO - 1498961113838 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}]
19:05:13 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498961113841 Marionette TRACE 396 <- [1,5,null,{"value":null}]
19:05:13 INFO - 1498961113842 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
19:05:13 INFO - 1498961113844 Marionette TRACE 396 <- [1,6,null,{}]
19:05:13 INFO - 1498961113845 Marionette DEBUG Closed connection 396
19:05:13 INFO - 1498961113847 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49814
19:05:13 INFO - 1498961113848 Marionette DEBUG Closed connection 397
19:05:13 INFO - 1498961113849 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49815
19:05:13 INFO - 1498961113852 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:05:13 INFO - 1498961113853 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113858 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113862 Marionette TRACE 398 <- [1,1,null,{"sessionId":"28945f8e-767d-447d-98f0-0f5dfb0f6610","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113863 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
19:05:13 INFO - 1498961113865 Marionette TRACE 398 <- [1,2,null,{}]
19:05:13 INFO - 1498961113866 Marionette DEBUG Closed connection 398
19:05:13 INFO - 1498961113868 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49816
19:05:13 INFO - 1498961113869 Marionette DEBUG Closed connection 399
19:05:13 INFO - 1498961113869 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49817
19:05:13 INFO - 1498961113870 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:05:13 INFO - 1498961113871 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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}
19:05:13 INFO - 1498961113874 Marionette DEBUG Register listener.js for window 4294967297
19:05:13 INFO - 1498961113877 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ccf93d3c-32a8-4aac-a765-4e7db82a696f","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":2444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-108.001\\AppData\\Local\\Temp\\tmp6ilmwp.mozrunner","moz:accessibilityChecks":false}}]
19:05:13 INFO - 1498961113879 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"}]
19:05:13 INFO - 1498961113881 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"
19:05:13 INFO - 1498961113883 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"
19:05:13 INFO - 1498961113883 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"
19:05:13 INFO - 1498961113890 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"
19:05:13 INFO - 1498961113893 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"
19:05:13 INFO - 1498961113898 Marionette TRACE 400 <- [1,2,null,{}]
19:05:13 INFO - 1498961113906 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:05:13 INFO - 1498961113909 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e227b3e2-b124-43bd-aaa1-64a484b229a3","ELEMENT":"e227b3e2-b124-43bd-aaa1-64a484b229a3"}}]
19:05:13 INFO - 1498961113911 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e227b3e2-b124-43bd-aaa1-64a484b229a3"}]
19:05:13 INFO - 1498961113938 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
19:05:13 INFO - 1498961113940 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e227b3e2-b124-43bd-aaa1-64a484b229a3"}]
19:05:13 INFO - 1498961113956 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element