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]
18:53:18 INFO - 1499133198800 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}]
18:53:18 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499133198803 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:53:18 INFO - 1499133198804 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:53:18 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 151ms
18:53:18 INFO - 1499133198806 Marionette TRACE 352 <- [1,6,null,{}]
18:53:18 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:53:18 INFO - 1499133198808 Marionette DEBUG Closed connection 352
18:53:18 INFO - 1499133198809 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49753
18:53:18 INFO - 1499133198809 Marionette DEBUG Closed connection 353
18:53:18 INFO - 1499133198810 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49754
18:53:18 INFO - 1499133198811 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:18 INFO - 1499133198812 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:18 INFO - 1499133198814 Marionette DEBUG Register listener.js for window 12
18:53:18 INFO - 1499133198818 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ebd0dac4-57d7-4bf7-a6be-61c68037105e","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:18 INFO - 1499133198819 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:53:18 INFO - 1499133198820 Marionette TRACE 354 <- [1,2,null,{}]
18:53:18 INFO - 1499133198821 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:18 INFO - 1499133198822 Marionette TRACE 354 <- [1,3,null,{}]
18:53:18 INFO - 1499133198823 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:18 INFO - 1499133198823 Marionette TRACE 354 <- [1,4,null,{}]
18:53:18 INFO - 1499133198825 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}]
18:53:18 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499133198827 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:53:18 INFO - 1499133198828 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:53:18 INFO - 1499133198830 Marionette TRACE 354 <- [1,6,null,{}]
18:53:18 INFO - 1499133198832 Marionette DEBUG Closed connection 354
18:53:18 INFO - 1499133198833 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49755
18:53:18 INFO - 1499133198833 Marionette DEBUG Closed connection 355
18:53:18 INFO - 1499133198834 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49756
18:53:18 INFO - 1499133198835 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:18 INFO - 1499133198835 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:18 INFO - 1499133198838 Marionette DEBUG Register listener.js for window 12
18:53:18 INFO - 1499133198842 Marionette TRACE 356 <- [1,1,null,{"sessionId":"93e343fc-2700-414c-8690-591e7428a454","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:18 INFO - 1499133198843 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:53:18 INFO - 1499133198844 Marionette TRACE 356 <- [1,2,null,{}]
18:53:18 INFO - 1499133198846 Marionette DEBUG Closed connection 356
18:53:18 INFO - 1499133198846 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49757
18:53:18 INFO - 1499133198847 Marionette DEBUG Closed connection 357
18:53:18 INFO - 1499133198848 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49758
18:53:18 INFO - 1499133198849 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:18 INFO - 1499133198849 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:18 INFO - 1499133198852 Marionette DEBUG Register listener.js for window 12
18:53:18 INFO - 1499133198857 Marionette TRACE 358 <- [1,1,null,{"sessionId":"5680d945-3b76-4e4c-b8bc-cb35e927f442","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:18 INFO - 1499133198858 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
18:53:18 INFO - 1499133198860 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
18:53:18 INFO - 1499133198872 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
18:53:18 INFO - 1499133198872 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
18:53:18 INFO - 1499133198897 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
18:53:18 INFO - 1499133198900 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
18:53:18 INFO - 1499133198909 Marionette TRACE 358 <- [1,2,null,{}]
18:53:18 INFO - 1499133198919 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:53:18 INFO - 1499133198922 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9c39e71c-dc99-4502-823e-de60ec369826","ELEMENT":"9c39e71c-dc99-4502-823e-de60ec369826"}}]
18:53:18 INFO - 1499133198923 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"9c39e71c-dc99-4502-823e-de60ec369826"}]
18:53:18 INFO - 1499133198961 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
18:53:18 INFO - 1499133198966 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
18:53:18 INFO - 1499133198966 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
18:53:18 INFO - 1499133198997 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
18:53:18 INFO - 1499133198998 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
18:53:19 INFO - 1499133199001 Marionette TRACE 358 <- [1,4,null,{}]
18:53:19 INFO - 1499133199007 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:53:19 INFO - 1499133199010 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1e807912-ee75-4e41-81e8-e6613ee15294","ELEMENT":"1e807912-ee75-4e41-81e8-e6613ee15294"}}]
18:53:19 INFO - 1499133199014 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:53:19 INFO - 1499133199015 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:53:19 INFO - 1499133199018 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}]
18:53:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499133199020 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:53:19 INFO - 1499133199021 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:53:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 216ms
18:53:19 INFO - 1499133199023 Marionette TRACE 358 <- [1,8,null,{}]
18:53:19 INFO - TEST-START | test_click.py TestClick.test_container_element
18:53:19 INFO - 1499133199025 Marionette DEBUG Closed connection 358
18:53:19 INFO - 1499133199026 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49761
18:53:19 INFO - 1499133199026 Marionette DEBUG Closed connection 359
18:53:19 INFO - 1499133199027 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49762
18:53:19 INFO - 1499133199028 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:19 INFO - 1499133199028 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199031 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199036 Marionette TRACE 360 <- [1,1,null,{"sessionId":"249a483f-c7f6-467f-8b80-9e1640d5e94e","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199038 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:53:19 INFO - 1499133199038 Marionette TRACE 360 <- [1,2,null,{}]
18:53:19 INFO - 1499133199039 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:19 INFO - 1499133199039 Marionette TRACE 360 <- [1,3,null,{}]
18:53:19 INFO - 1499133199040 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:19 INFO - 1499133199041 Marionette TRACE 360 <- [1,4,null,{}]
18:53:19 INFO - 1499133199043 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}]
18:53:19 INFO - TEST-START: test_click.py TestClick.test_container_element1499133199046 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:53:19 INFO - 1499133199047 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:53:19 INFO - 1499133199048 Marionette TRACE 360 <- [1,6,null,{}]
18:53:19 INFO - 1499133199051 Marionette DEBUG Closed connection 360
18:53:19 INFO - 1499133199052 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49763
18:53:19 INFO - 1499133199053 Marionette DEBUG Closed connection 361
18:53:19 INFO - 1499133199053 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49764
18:53:19 INFO - 1499133199054 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:19 INFO - 1499133199055 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199058 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199061 Marionette TRACE 362 <- [1,1,null,{"sessionId":"849661d6-f238-4b35-96b8-6b2e6bdc3bee","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199062 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:53:19 INFO - 1499133199063 Marionette TRACE 362 <- [1,2,null,{}]
18:53:19 INFO - 1499133199065 Marionette DEBUG Closed connection 362
18:53:19 INFO - 1499133199066 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49765
18:53:19 INFO - 1499133199068 Marionette DEBUG Closed connection 363
18:53:19 INFO - 1499133199068 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49766
18:53:19 INFO - 1499133199070 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:19 INFO - 1499133199070 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199073 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199077 Marionette TRACE 364 <- [1,1,null,{"sessionId":"c4828cc2-bc62-48df-8701-235e737f30b0","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199078 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"}]
18:53:19 INFO - 1499133199079 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
18:53:19 INFO - 1499133199081 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
18:53:19 INFO - 1499133199081 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
18:53:19 INFO - 1499133199106 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"
18:53:19 INFO - 1499133199110 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"
18:53:19 INFO - 1499133199113 Marionette TRACE 364 <- [1,2,null,{}]
18:53:19 INFO - 1499133199114 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:53:19 INFO - 1499133199122 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e3a22c77-253c-4ae8-8216-7ef0b671ac90","ELEMENT":"e3a22c77-253c-4ae8-8216-7ef0b671ac90"}}]
18:53:19 INFO - 1499133199124 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e3a22c77-253c-4ae8-8216-7ef0b671ac90"}]
18:53:19 INFO - 1499133199372 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:19 INFO - 1499133199373 Marionette TRACE 364 <- [1,4,null,{}]
18:53:19 INFO - 1499133199374 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e3a22c77-253c-4ae8-8216-7ef0b671ac90","name":"selected"}]
18:53:19 INFO - 1499133199379 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:53:19 INFO - 1499133199381 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}]
18:53:19 INFO - TEST-END: test_click.py TestClick.test_container_element1499133199384 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:53:19 INFO - 1499133199385 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:53:19 INFO - 1499133199386 Marionette TRACE 364 <- [1,7,null,{}]
18:53:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 362ms
18:53:19 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:53:19 INFO - 1499133199388 Marionette DEBUG Closed connection 364
18:53:19 INFO - 1499133199389 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49767
18:53:19 INFO - 1499133199390 Marionette DEBUG Closed connection 365
18:53:19 INFO - 1499133199390 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49768
18:53:19 INFO - 1499133199392 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:19 INFO - 1499133199392 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199395 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199398 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e51c265e-5bac-4f72-9bae-f62300a94413","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199399 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:53:19 INFO - 1499133199399 Marionette TRACE 366 <- [1,2,null,{}]
18:53:19 INFO - 1499133199400 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:19 INFO - 1499133199401 Marionette TRACE 366 <- [1,3,null,{}]
18:53:19 INFO - 1499133199402 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:19 INFO - 1499133199402 Marionette TRACE 366 <- [1,4,null,{}]
18:53:19 INFO - 1499133199404 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}]
18:53:19 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499133199406 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:53:19 INFO - 1499133199407 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:53:19 INFO - 1499133199409 Marionette TRACE 366 <- [1,6,null,{}]
18:53:19 INFO - 1499133199411 Marionette DEBUG Closed connection 366
18:53:19 INFO - 1499133199411 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49769
18:53:19 INFO - 1499133199412 Marionette DEBUG Closed connection 367
18:53:19 INFO - 1499133199413 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49770
18:53:19 INFO - 1499133199414 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:19 INFO - 1499133199414 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199417 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199420 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ab77e772-f0d9-4a5e-bc87-f35ca98e6ff6","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199421 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:53:19 INFO - 1499133199422 Marionette TRACE 368 <- [1,2,null,{}]
18:53:19 INFO - 1499133199425 Marionette DEBUG Closed connection 368
18:53:19 INFO - 1499133199425 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49771
18:53:19 INFO - 1499133199426 Marionette DEBUG Closed connection 369
18:53:19 INFO - 1499133199426 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49772
18:53:19 INFO - 1499133199427 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:19 INFO - 1499133199428 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199431 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199434 Marionette TRACE 370 <- [1,1,null,{"sessionId":"1323026f-e31d-468f-9f25-2871eacc8003","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199435 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"}]
18:53:19 INFO - 1499133199436 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"
18:53:19 INFO - 1499133199439 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"
18:53:19 INFO - 1499133199439 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"
18:53:19 INFO - 1499133199459 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"
18:53:19 INFO - 1499133199461 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"
18:53:19 INFO - 1499133199465 Marionette TRACE 370 <- [1,2,null,{}]
18:53:19 INFO - 1499133199473 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:53:19 INFO - 1499133199475 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d2d2fce2-c448-40f7-a4bf-0df6482f212e","ELEMENT":"d2d2fce2-c448-40f7-a4bf-0df6482f212e"}}]
18:53:19 INFO - 1499133199476 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d2d2fce2-c448-40f7-a4bf-0df6482f212e"}]
18:53:19 INFO - 1499133199721 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:19 INFO - 1499133199722 Marionette TRACE 370 <- [1,4,null,{}]
18:53:19 INFO - 1499133199723 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d2d2fce2-c448-40f7-a4bf-0df6482f212e","name":"selected"}]
18:53:19 INFO - 1499133199728 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:53:19 INFO - 1499133199729 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}]
18:53:19 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499133199732 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:53:19 INFO - 1499133199734 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:53:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 349ms
18:53:19 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:53:19 INFO - 1499133199735 Marionette TRACE 370 <- [1,7,null,{}]
18:53:19 INFO - 1499133199737 Marionette DEBUG Closed connection 370
18:53:19 INFO - 1499133199738 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49773
18:53:19 INFO - 1499133199739 Marionette DEBUG Closed connection 371
18:53:19 INFO - 1499133199739 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49774
18:53:19 INFO - 1499133199740 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:19 INFO - 1499133199741 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199744 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199747 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d873619f-4901-4eaf-9c37-d71b3a21c39c","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199754 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:53:19 INFO - 1499133199755 Marionette TRACE 372 <- [1,2,null,{}]
18:53:19 INFO - 1499133199756 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:19 INFO - 1499133199756 Marionette TRACE 372 <- [1,3,null,{}]
18:53:19 INFO - 1499133199757 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:19 INFO - 1499133199758 Marionette TRACE 372 <- [1,4,null,{}]
18:53:19 INFO - 1499133199759 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}]
18:53:19 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499133199762 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:53:19 INFO - 1499133199763 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:53:19 INFO - 1499133199764 Marionette TRACE 372 <- [1,6,null,{}]
18:53:19 INFO - 1499133199766 Marionette DEBUG Closed connection 372
18:53:19 INFO - 1499133199767 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49775
18:53:19 INFO - 1499133199768 Marionette DEBUG Closed connection 373
18:53:19 INFO - 1499133199768 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49776
18:53:19 INFO - 1499133199769 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:19 INFO - 1499133199770 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199772 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199776 Marionette TRACE 374 <- [1,1,null,{"sessionId":"296d379b-2c79-4b2b-a519-f3540b7170ae","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199777 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:53:19 INFO - 1499133199778 Marionette TRACE 374 <- [1,2,null,{}]
18:53:19 INFO - 1499133199780 Marionette DEBUG Closed connection 374
18:53:19 INFO - 1499133199781 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49777
18:53:19 INFO - 1499133199781 Marionette DEBUG Closed connection 375
18:53:19 INFO - 1499133199782 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49778
18:53:19 INFO - 1499133199784 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:19 INFO - 1499133199785 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:19 INFO - 1499133199788 Marionette DEBUG Register listener.js for window 12
18:53:19 INFO - 1499133199791 Marionette TRACE 376 <- [1,1,null,{"sessionId":"d8ecc787-a110-45ea-9def-9c8f9e2de3e0","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:19 INFO - 1499133199792 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"}]
18:53:19 INFO - 1499133199794 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"
18:53:19 INFO - 1499133199796 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"
18:53:19 INFO - 1499133199796 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"
18:53:19 INFO - 1499133199818 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"
18:53:19 INFO - 1499133199820 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"
18:53:19 INFO - 1499133199824 Marionette TRACE 376 <- [1,2,null,{}]
18:53:19 INFO - 1499133199825 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:53:19 INFO - 1499133199828 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"830709d7-913a-4ead-8e7c-3aacf7c14e45","ELEMENT":"830709d7-913a-4ead-8e7c-3aacf7c14e45"}}]
18:53:19 INFO - 1499133199828 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"830709d7-913a-4ead-8e7c-3aacf7c14e45"}]
18:53:20 INFO - 1499133200083 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:20 INFO - 1499133200084 Marionette TRACE 376 <- [1,4,null,{}]
18:53:20 INFO - 1499133200086 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}]
18:53:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499133200088 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:53:20 INFO - 1499133200089 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:53:20 INFO - 1499133200091 Marionette TRACE 376 <- [1,6,null,{}]
18:53:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 356ms
18:53:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:53:20 INFO - 1499133200093 Marionette DEBUG Closed connection 376
18:53:20 INFO - 1499133200094 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49779
18:53:20 INFO - 1499133200094 Marionette DEBUG Closed connection 377
18:53:20 INFO - 1499133200095 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49780
18:53:20 INFO - 1499133200096 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:20 INFO - 1499133200096 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200099 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200102 Marionette TRACE 378 <- [1,1,null,{"sessionId":"32160b60-0b89-41a3-b55a-e841ce828691","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200104 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:53:20 INFO - 1499133200104 Marionette TRACE 378 <- [1,2,null,{}]
18:53:20 INFO - 1499133200105 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:20 INFO - 1499133200105 Marionette TRACE 378 <- [1,3,null,{}]
18:53:20 INFO - 1499133200106 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:20 INFO - 1499133200107 Marionette TRACE 378 <- [1,4,null,{}]
18:53:20 INFO - 1499133200109 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}]
18:53:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499133200112 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:53:20 INFO - 1499133200113 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:53:20 INFO - 1499133200114 Marionette TRACE 378 <- [1,6,null,{}]
18:53:20 INFO - 1499133200116 Marionette DEBUG Closed connection 378
18:53:20 INFO - 1499133200117 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49781
18:53:20 INFO - 1499133200118 Marionette DEBUG Closed connection 379
18:53:20 INFO - 1499133200118 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49782
18:53:20 INFO - 1499133200119 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:20 INFO - 1499133200120 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200122 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200126 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3e345059-884d-4a42-a04e-016cd0cd670f","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200127 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:53:20 INFO - 1499133200128 Marionette TRACE 380 <- [1,2,null,{}]
18:53:20 INFO - 1499133200130 Marionette DEBUG Closed connection 380
18:53:20 INFO - 1499133200131 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49783
18:53:20 INFO - 1499133200131 Marionette DEBUG Closed connection 381
18:53:20 INFO - 1499133200132 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49784
18:53:20 INFO - 1499133200133 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:20 INFO - 1499133200134 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200136 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200139 Marionette TRACE 382 <- [1,1,null,{"sessionId":"bca6bf8b-1530-4b7b-a08e-9eb44bd77f6e","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200140 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"}]
18:53:20 INFO - 1499133200142 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"
18:53:20 INFO - 1499133200144 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"
18:53:20 INFO - 1499133200144 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"
18:53:20 INFO - 1499133200163 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"
18:53:20 INFO - 1499133200167 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"
18:53:20 INFO - 1499133200176 Marionette TRACE 382 <- [1,2,null,{}]
18:53:20 INFO - 1499133200178 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:53:20 INFO - 1499133200180 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8b884033-fa5c-46f0-9756-958de3fd910a","ELEMENT":"8b884033-fa5c-46f0-9756-958de3fd910a"}}]
18:53:20 INFO - 1499133200181 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"8b884033-fa5c-46f0-9756-958de3fd910a"}]
18:53:20 INFO - 1499133200432 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:20 INFO - 1499133200433 Marionette TRACE 382 <- [1,4,null,{}]
18:53:20 INFO - 1499133200434 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}]
18:53:20 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499133200437 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:53:20 INFO - 1499133200438 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:53:20 INFO - 1499133200439 Marionette TRACE 382 <- [1,6,null,{}]
18:53:20 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 348ms
18:53:20 INFO - TEST-START | test_click.py TestClick.test_input_file
18:53:20 INFO - 1499133200441 Marionette DEBUG Closed connection 382
18:53:20 INFO - 1499133200442 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49785
18:53:20 INFO - 1499133200443 Marionette DEBUG Closed connection 383
18:53:20 INFO - 1499133200443 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49786
18:53:20 INFO - 1499133200444 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:20 INFO - 1499133200445 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200448 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200451 Marionette TRACE 384 <- [1,1,null,{"sessionId":"204aa4d7-2f82-495e-a3fd-e1c3e96d17df","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200452 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:53:20 INFO - 1499133200453 Marionette TRACE 384 <- [1,2,null,{}]
18:53:20 INFO - 1499133200454 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:20 INFO - 1499133200455 Marionette TRACE 384 <- [1,3,null,{}]
18:53:20 INFO - 1499133200456 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:20 INFO - 1499133200456 Marionette TRACE 384 <- [1,4,null,{}]
18:53:20 INFO - 1499133200458 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}]
18:53:20 INFO - TEST-START: test_click.py TestClick.test_input_file1499133200460 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:53:20 INFO - 1499133200461 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:53:20 INFO - 1499133200462 Marionette TRACE 384 <- [1,6,null,{}]
18:53:20 INFO - 1499133200465 Marionette DEBUG Closed connection 384
18:53:20 INFO - 1499133200465 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49787
18:53:20 INFO - 1499133200466 Marionette DEBUG Closed connection 385
18:53:20 INFO - 1499133200467 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49788
18:53:20 INFO - 1499133200468 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:20 INFO - 1499133200468 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200471 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200474 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f5538d68-137b-4d19-a598-b295e60c44fa","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200475 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:53:20 INFO - 1499133200477 Marionette TRACE 386 <- [1,2,null,{}]
18:53:20 INFO - 1499133200479 Marionette DEBUG Closed connection 386
18:53:20 INFO - 1499133200479 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49789
18:53:20 INFO - 1499133200480 Marionette DEBUG Closed connection 387
18:53:20 INFO - 1499133200481 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49790
18:53:20 INFO - 1499133200482 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:20 INFO - 1499133200483 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200485 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200489 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2461760c-8351-4b89-902b-b419134e530c","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200490 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:53:20 INFO - 1499133200491 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"
18:53:20 INFO - 1499133200494 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"
18:53:20 INFO - 1499133200494 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"
18:53:20 INFO - 1499133200514 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:20 INFO - 1499133200522 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:20 INFO - 1499133200525 Marionette TRACE 388 <- [1,2,null,{}]
18:53:20 INFO - 1499133200526 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:53:20 INFO - 1499133200533 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bc8ebd9d-406c-4d05-902b-a81d7c5d6960","ELEMENT":"bc8ebd9d-406c-4d05-902b-a81d7c5d6960"}}]
18:53:20 INFO - 1499133200540 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"bc8ebd9d-406c-4d05-902b-a81d7c5d6960"}]
18:53:20 INFO - 1499133200569 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]
18:53:20 INFO - 1499133200571 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}]
18:53:20 INFO - TEST-END: test_click.py TestClick.test_input_file1499133200574 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:53:20 INFO - 1499133200575 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:53:20 INFO - 1499133200576 Marionette TRACE 388 <- [1,6,null,{}]
18:53:20 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 137ms
18:53:20 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:53:20 INFO - 1499133200578 Marionette DEBUG Closed connection 388
18:53:20 INFO - 1499133200579 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49791
18:53:20 INFO - 1499133200581 Marionette DEBUG Closed connection 389
18:53:20 INFO - 1499133200581 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49792
18:53:20 INFO - 1499133200582 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:20 INFO - 1499133200583 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200586 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200589 Marionette TRACE 390 <- [1,1,null,{"sessionId":"01c39df9-750f-4c0c-888b-053273ace123","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200590 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:53:20 INFO - 1499133200591 Marionette TRACE 390 <- [1,2,null,{}]
18:53:20 INFO - 1499133200592 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:20 INFO - 1499133200592 Marionette TRACE 390 <- [1,3,null,{}]
18:53:20 INFO - 1499133200593 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:20 INFO - 1499133200594 Marionette TRACE 390 <- [1,4,null,{}]
18:53:20 INFO - 1499133200595 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}]
18:53:20 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499133200598 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:53:20 INFO - 1499133200598 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:53:20 INFO - 1499133200600 Marionette TRACE 390 <- [1,6,null,{}]
18:53:20 INFO - 1499133200602 Marionette DEBUG Closed connection 390
18:53:20 INFO - 1499133200602 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49793
18:53:20 INFO - 1499133200603 Marionette DEBUG Closed connection 391
18:53:20 INFO - 1499133200604 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49794
18:53:20 INFO - 1499133200605 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:20 INFO - 1499133200605 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200608 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200611 Marionette TRACE 392 <- [1,1,null,{"sessionId":"4d5c375b-62ed-48c2-97ee-de515f40abfc","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200612 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:53:20 INFO - 1499133200613 Marionette TRACE 392 <- [1,2,null,{}]
18:53:20 INFO - 1499133200615 Marionette DEBUG Closed connection 392
18:53:20 INFO - 1499133200616 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49795
18:53:20 INFO - 1499133200617 Marionette DEBUG Closed connection 393
18:53:20 INFO - 1499133200617 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49796
18:53:20 INFO - 1499133200619 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:20 INFO - 1499133200619 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200622 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200625 Marionette TRACE 394 <- [1,1,null,{"sessionId":"37469260-cd3f-42ce-815c-acfb69579f16","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200626 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"}]
18:53:20 INFO - 1499133200627 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:20 INFO - 1499133200630 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:20 INFO - 1499133200630 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:53:20 INFO - 1499133200656 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"
18:53:20 INFO - 1499133200659 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"
18:53:20 INFO - 1499133200668 Marionette TRACE 394 <- [1,2,null,{}]
18:53:20 INFO - 1499133200670 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:53:20 INFO - 1499133200672 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"baa1ba63-9934-46d1-bd9e-662afa9fdeba","ELEMENT":"baa1ba63-9934-46d1-bd9e-662afa9fdeba"}}]
18:53:20 INFO - 1499133200673 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:53:20 INFO - 1499133200675 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a519b16-382d-456f-bc21-e7fa878f19ed","ELEMENT":"7a519b16-382d-456f-bc21-e7fa878f19ed"}}]
18:53:20 INFO - 1499133200676 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"baa1ba63-9934-46d1-bd9e-662afa9fdeba"}]
18:53:20 INFO - 1499133200916 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:53:20 INFO - 1499133200917 Marionette TRACE 394 <- [1,5,null,{}]
18:53:20 INFO - 1499133200919 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"7a519b16-382d-456f-bc21-e7fa878f19ed"}]
18:53:20 INFO - 1499133200940 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]
18:53:20 INFO - 1499133200943 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:53:20 INFO - 1499133200947 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:53:20 INFO - 1499133200949 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}]
18:53:20 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499133200953 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:53:20 INFO - 1499133200954 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:53:20 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 380ms
18:53:20 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:53:20 INFO - 1499133200957 Marionette TRACE 394 <- [1,9,null,{}]
18:53:20 INFO - 1499133200960 Marionette DEBUG Closed connection 394
18:53:20 INFO - 1499133200961 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49797
18:53:20 INFO - 1499133200962 Marionette DEBUG Closed connection 395
18:53:20 INFO - 1499133200963 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49798
18:53:20 INFO - 1499133200965 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:20 INFO - 1499133200968 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:20 INFO - 1499133200971 Marionette DEBUG Register listener.js for window 12
18:53:20 INFO - 1499133200977 Marionette TRACE 396 <- [1,1,null,{"sessionId":"0652283f-ba29-484c-9e89-cc8bff1e8763","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:20 INFO - 1499133200979 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:53:20 INFO - 1499133200980 Marionette TRACE 396 <- [1,2,null,{}]
18:53:20 INFO - 1499133200982 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:53:20 INFO - 1499133200982 Marionette TRACE 396 <- [1,3,null,{}]
18:53:20 INFO - 1499133200983 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:53:20 INFO - 1499133200984 Marionette TRACE 396 <- [1,4,null,{}]
18:53:20 INFO - 1499133200986 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}]
18:53:20 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499133200990 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:53:20 INFO - 1499133200991 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:53:20 INFO - 1499133200993 Marionette TRACE 396 <- [1,6,null,{}]
18:53:20 INFO - 1499133200995 Marionette DEBUG Closed connection 396
18:53:20 INFO - 1499133200996 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49799
18:53:21 INFO - 1499133200997 Marionette DEBUG Closed connection 397
18:53:21 INFO - 1499133200998 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49800
18:53:21 INFO - 1499133201074 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:53:21 INFO - 1499133201077 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:21 INFO - 1499133201080 Marionette DEBUG Register listener.js for window 12
18:53:21 INFO - 1499133201088 Marionette TRACE 398 <- [1,1,null,{"sessionId":"2816dbd2-9b0c-4cec-9e1f-8a51b6d7b73d","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:21 INFO - 1499133201090 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:53:21 INFO - 1499133201092 Marionette TRACE 398 <- [1,2,null,{}]
18:53:21 INFO - 1499133201095 Marionette DEBUG Closed connection 398
18:53:21 INFO - 1499133201096 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49801
18:53:21 INFO - 1499133201097 Marionette DEBUG Closed connection 399
18:53:21 INFO - 1499133201098 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49802
18:53:21 INFO - 1499133201114 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:53:21 INFO - 1499133201116 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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}
18:53:21 INFO - 1499133201119 Marionette DEBUG Register listener.js for window 12
18:53:21 INFO - 1499133201126 Marionette TRACE 400 <- [1,1,null,{"sessionId":"95795937-a519-4827-a0ee-d2799cb2eba8","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":2504,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-128.000\\AppData\\Local\\Temp\\tmpvhv44n.mozrunner","moz:accessibilityChecks":false}}]
18:53:21 INFO - 1499133201128 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"}]
18:53:21 INFO - 1499133201131 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"
18:53:21 INFO - 1499133201135 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"
18:53:21 INFO - 1499133201135 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"
18:53:21 INFO - 1499133201161 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"
18:53:21 INFO - 1499133201164 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"
18:53:21 INFO - 1499133201174 Marionette TRACE 400 <- [1,2,null,{}]
18:53:21 INFO - 1499133201181 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:53:21 INFO - 1499133201185 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"75218f64-86cb-44b2-ac44-ccaf902728ed","ELEMENT":"75218f64-86cb-44b2-ac44-ccaf902728ed"}}]
18:53:21 INFO - 1499133201187 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"75218f64-86cb-44b2-ac44-ccaf902728ed"}]
18:53:21 INFO - 1499133201214 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:53:21 INFO - 1499133201216 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"75218f64-86cb-44b2-ac44-ccaf902728ed"}]
18:53:21 INFO - 1499133201247 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element