could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:53:28 INFO - 1499608408781 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
06:53:28 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499608408783 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:53:28 INFO - 1499608408784 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:53:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 114ms
06:53:28 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:53:28 INFO - 1499608408786 Marionette TRACE 352 <- [1,6,null,{}]
06:53:28 INFO - 1499608408787 Marionette DEBUG Closed connection 352
06:53:28 INFO - 1499608408788 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49791
06:53:28 INFO - 1499608408788 Marionette DEBUG Closed connection 353
06:53:28 INFO - 1499608408789 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49792
06:53:28 INFO - 1499608408790 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:28 INFO - 1499608408790 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:28 INFO - 1499608408792 Marionette DEBUG Register listener.js for window 4294967297
06:53:28 INFO - 1499608408795 Marionette TRACE 354 <- [1,1,null,{"sessionId":"95dd4772-c336-4fbb-91fa-9e9e11783085","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:28 INFO - 1499608408796 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:53:28 INFO - 1499608408797 Marionette TRACE 354 <- [1,2,null,{}]
06:53:28 INFO - 1499608408798 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:28 INFO - 1499608408798 Marionette TRACE 354 <- [1,3,null,{}]
06:53:28 INFO - 1499608408799 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:28 INFO - 1499608408799 Marionette TRACE 354 <- [1,4,null,{}]
06:53:28 INFO - 1499608408800 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
06:53:28 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499608408803 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:53:28 INFO - 1499608408803 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:53:28 INFO - 1499608408805 Marionette TRACE 354 <- [1,6,null,{}]
06:53:28 INFO - 1499608408806 Marionette DEBUG Closed connection 354
06:53:28 INFO - 1499608408806 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49793
06:53:28 INFO - 1499608408807 Marionette DEBUG Closed connection 355
06:53:28 INFO - 1499608408808 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49794
06:53:28 INFO - 1499608408809 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:28 INFO - 1499608408809 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:28 INFO - 1499608408811 Marionette DEBUG Register listener.js for window 4294967297
06:53:28 INFO - 1499608408814 Marionette TRACE 356 <- [1,1,null,{"sessionId":"06bed177-01c3-4959-83eb-2cdab32277aa","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:28 INFO - 1499608408815 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:53:28 INFO - 1499608408816 Marionette TRACE 356 <- [1,2,null,{}]
06:53:28 INFO - 1499608408818 Marionette DEBUG Closed connection 356
06:53:28 INFO - 1499608408818 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49795
06:53:28 INFO - 1499608408819 Marionette DEBUG Closed connection 357
06:53:28 INFO - 1499608408819 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49796
06:53:28 INFO - 1499608408820 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:28 INFO - 1499608408821 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:28 INFO - 1499608408823 Marionette DEBUG Register listener.js for window 4294967297
06:53:28 INFO - 1499608408832 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b18247e1-a44e-4068-9a61-963570458626","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:28 INFO - 1499608408833 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49361/clicks.html"}]
06:53:28 INFO - 1499608408835 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/hidden.html"
06:53:28 INFO - 1499608408841 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/hidden.html"
06:53:28 INFO - 1499608408842 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/hidden.html"
06:53:28 INFO - 1499608408851 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49361/clicks.html"
06:53:28 INFO - 1499608408852 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49361/clicks.html"
06:53:28 INFO - 1499608408857 Marionette TRACE 358 <- [1,2,null,{}]
06:53:28 INFO - 1499608408861 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:53:28 INFO - 1499608408866 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"378eca20-2d54-4a17-8eba-0deaee1f951d","ELEMENT":"378eca20-2d54-4a17-8eba-0deaee1f951d"}}]
06:53:28 INFO - 1499608408867 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"378eca20-2d54-4a17-8eba-0deaee1f951d"}]
06:53:28 INFO - 1499608408904 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/clicks.html"
06:53:28 INFO - 1499608408911 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/clicks.html"
06:53:28 INFO - 1499608408912 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/clicks.html"
06:53:28 INFO - 1499608408919 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49361/test.html"
06:53:28 INFO - 1499608408924 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49361/test.html"
06:53:28 INFO - 1499608408932 Marionette TRACE 358 <- [1,4,null,{}]
06:53:28 INFO - 1499608408938 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:53:28 INFO - 1499608408940 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7eddfc98-a9ff-422c-a8b4-239e9bdd93ad","ELEMENT":"7eddfc98-a9ff-422c-a8b4-239e9bdd93ad"}}]
06:53:28 INFO - 1499608408941 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:53:28 INFO - 1499608408941 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:53:28 INFO - 1499608408943 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
06:53:28 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499608408945 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:53:28 INFO - 1499608408946 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:53:28 INFO - 1499608408947 Marionette TRACE 358 <- [1,8,null,{}]
06:53:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 164ms
06:53:28 INFO - TEST-START | test_click.py TestClick.test_container_element
06:53:28 INFO - 1499608408952 Marionette DEBUG Closed connection 358
06:53:28 INFO - 1499608408953 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49799
06:53:28 INFO - 1499608408953 Marionette DEBUG Closed connection 359
06:53:28 INFO - 1499608408954 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49800
06:53:28 INFO - 1499608408955 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:28 INFO - 1499608408955 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:28 INFO - 1499608408957 Marionette DEBUG Register listener.js for window 4294967297
06:53:28 INFO - 1499608408960 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4c900cad-d001-49a3-aacc-4becdef996e6","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:28 INFO - 1499608408961 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:53:28 INFO - 1499608408961 Marionette TRACE 360 <- [1,2,null,{}]
06:53:28 INFO - 1499608408962 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:28 INFO - 1499608408963 Marionette TRACE 360 <- [1,3,null,{}]
06:53:28 INFO - 1499608408963 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:28 INFO - 1499608408964 Marionette TRACE 360 <- [1,4,null,{}]
06:53:28 INFO - 1499608408966 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
06:53:28 INFO - TEST-START: test_click.py TestClick.test_container_element1499608408968 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:53:28 INFO - 1499608408969 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:53:28 INFO - 1499608408970 Marionette TRACE 360 <- [1,6,null,{}]
06:53:28 INFO - 1499608408972 Marionette DEBUG Closed connection 360
06:53:28 INFO - 1499608408972 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49801
06:53:28 INFO - 1499608408973 Marionette DEBUG Closed connection 361
06:53:28 INFO - 1499608408973 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49802
06:53:28 INFO - 1499608408974 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:28 INFO - 1499608408975 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:28 INFO - 1499608408977 Marionette DEBUG Register listener.js for window 4294967297
06:53:28 INFO - 1499608408980 Marionette TRACE 362 <- [1,1,null,{"sessionId":"05bc573e-d321-40a9-a5e4-302c83f69dd2","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:28 INFO - 1499608408981 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:53:28 INFO - 1499608408982 Marionette TRACE 362 <- [1,2,null,{}]
06:53:28 INFO - 1499608408983 Marionette DEBUG Closed connection 362
06:53:28 INFO - 1499608408984 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49803
06:53:28 INFO - 1499608408985 Marionette DEBUG Closed connection 363
06:53:28 INFO - 1499608408985 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49804
06:53:28 INFO - 1499608408986 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:28 INFO - 1499608408987 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:28 INFO - 1499608408989 Marionette DEBUG Register listener.js for window 4294967297
06:53:28 INFO - 1499608408991 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d966e629-8eeb-4c5f-9153-9225c30f45c3","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:28 INFO - 1499608408992 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:53:28 INFO - 1499608408994 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49361/test.html"
06:53:28 INFO - 1499608408995 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49361/test.html"
06:53:28 INFO - 1499608408996 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49361/test.html"
06:53:29 INFO - 1499608409001 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409004 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409009 Marionette TRACE 364 <- [1,2,null,{}]
06:53:29 INFO - 1499608409012 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:29 INFO - 1499608409018 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b830234f-949c-4bdb-aba7-da42960a5159","ELEMENT":"b830234f-949c-4bdb-aba7-da42960a5159"}}]
06:53:29 INFO - 1499608409019 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b830234f-949c-4bdb-aba7-da42960a5159"}]
06:53:29 INFO - 1499608409255 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:29 INFO - 1499608409256 Marionette TRACE 364 <- [1,4,null,{}]
06:53:29 INFO - 1499608409257 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b830234f-949c-4bdb-aba7-da42960a5159","name":"selected"}]
06:53:29 INFO - 1499608409261 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:53:29 INFO - 1499608409262 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
06:53:29 INFO - TEST-END: test_click.py TestClick.test_container_element1499608409265 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:53:29 INFO - 1499608409266 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:53:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 317ms
06:53:29 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:53:29 INFO - 1499608409267 Marionette TRACE 364 <- [1,7,null,{}]
06:53:29 INFO - 1499608409268 Marionette DEBUG Closed connection 364
06:53:29 INFO - 1499608409269 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49805
06:53:29 INFO - 1499608409270 Marionette DEBUG Closed connection 365
06:53:29 INFO - 1499608409270 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49806
06:53:29 INFO - 1499608409271 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:29 INFO - 1499608409272 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409274 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409277 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e946774d-9bd9-4643-994d-46b8eeff2a1c","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409278 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:53:29 INFO - 1499608409279 Marionette TRACE 366 <- [1,2,null,{}]
06:53:29 INFO - 1499608409279 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:29 INFO - 1499608409280 Marionette TRACE 366 <- [1,3,null,{}]
06:53:29 INFO - 1499608409281 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:29 INFO - 1499608409281 Marionette TRACE 366 <- [1,4,null,{}]
06:53:29 INFO - 1499608409283 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
06:53:29 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499608409285 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:53:29 INFO - 1499608409286 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:53:29 INFO - 1499608409287 Marionette TRACE 366 <- [1,6,null,{}]
06:53:29 INFO - 1499608409288 Marionette DEBUG Closed connection 366
06:53:29 INFO - 1499608409289 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49807
06:53:29 INFO - 1499608409289 Marionette DEBUG Closed connection 367
06:53:29 INFO - 1499608409290 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49808
06:53:29 INFO - 1499608409291 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:29 INFO - 1499608409291 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409294 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409296 Marionette TRACE 368 <- [1,1,null,{"sessionId":"73e20ebd-5194-49d7-bfcf-a7bf7683e45e","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409299 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:53:29 INFO - 1499608409300 Marionette TRACE 368 <- [1,2,null,{}]
06:53:29 INFO - 1499608409301 Marionette DEBUG Closed connection 368
06:53:29 INFO - 1499608409302 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49809
06:53:29 INFO - 1499608409302 Marionette DEBUG Closed connection 369
06:53:29 INFO - 1499608409303 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49810
06:53:29 INFO - 1499608409304 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:29 INFO - 1499608409305 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409307 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409309 Marionette TRACE 370 <- [1,1,null,{"sessionId":"254fbaac-d6b0-499a-94ef-98cf59458304","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409310 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:53:29 INFO - 1499608409312 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409314 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409314 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409320 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409323 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409324 Marionette TRACE 370 <- [1,2,null,{}]
06:53:29 INFO - 1499608409327 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:29 INFO - 1499608409329 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b5fcbceb-b5d2-4420-9429-32ee6e8b3a98","ELEMENT":"b5fcbceb-b5d2-4420-9429-32ee6e8b3a98"}}]
06:53:29 INFO - 1499608409333 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"b5fcbceb-b5d2-4420-9429-32ee6e8b3a98"}]
06:53:29 INFO - 1499608409575 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:29 INFO - 1499608409575 Marionette TRACE 370 <- [1,4,null,{}]
06:53:29 INFO - 1499608409577 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"b5fcbceb-b5d2-4420-9429-32ee6e8b3a98","name":"selected"}]
06:53:29 INFO - 1499608409580 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:53:29 INFO - 1499608409582 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
06:53:29 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499608409584 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:53:29 INFO - 1499608409585 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:53:29 INFO - 1499608409586 Marionette TRACE 370 <- [1,7,null,{}]
06:53:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 319ms
06:53:29 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:53:29 INFO - 1499608409587 Marionette DEBUG Closed connection 370
06:53:29 INFO - 1499608409589 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49811
06:53:29 INFO - 1499608409589 Marionette DEBUG Closed connection 371
06:53:29 INFO - 1499608409590 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49812
06:53:29 INFO - 1499608409591 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:29 INFO - 1499608409591 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409593 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409596 Marionette TRACE 372 <- [1,1,null,{"sessionId":"1ef83f7d-2041-436c-aaf0-6058f1b41d8a","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409597 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:53:29 INFO - 1499608409598 Marionette TRACE 372 <- [1,2,null,{}]
06:53:29 INFO - 1499608409598 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:29 INFO - 1499608409599 Marionette TRACE 372 <- [1,3,null,{}]
06:53:29 INFO - 1499608409600 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:29 INFO - 1499608409600 Marionette TRACE 372 <- [1,4,null,{}]
06:53:29 INFO - 1499608409602 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
06:53:29 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499608409604 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:53:29 INFO - 1499608409605 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:53:29 INFO - 1499608409606 Marionette TRACE 372 <- [1,6,null,{}]
06:53:29 INFO - 1499608409607 Marionette DEBUG Closed connection 372
06:53:29 INFO - 1499608409608 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49813
06:53:29 INFO - 1499608409608 Marionette DEBUG Closed connection 373
06:53:29 INFO - 1499608409609 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49814
06:53:29 INFO - 1499608409610 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:29 INFO - 1499608409610 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409612 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409615 Marionette TRACE 374 <- [1,1,null,{"sessionId":"da9e90a6-3e21-452b-b2f6-2c8c12a822bb","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409616 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:53:29 INFO - 1499608409617 Marionette TRACE 374 <- [1,2,null,{}]
06:53:29 INFO - 1499608409619 Marionette DEBUG Closed connection 374
06:53:29 INFO - 1499608409619 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49815
06:53:29 INFO - 1499608409620 Marionette DEBUG Closed connection 375
06:53:29 INFO - 1499608409621 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49816
06:53:29 INFO - 1499608409622 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:29 INFO - 1499608409622 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409624 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409627 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6d76646d-95d2-4c9a-bd87-5d4d7f5dead5","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409628 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
06:53:29 INFO - 1499608409630 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409631 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409631 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409637 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:53:29 INFO - 1499608409639 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:53:29 INFO - 1499608409642 Marionette TRACE 376 <- [1,2,null,{}]
06:53:29 INFO - 1499608409645 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:53:29 INFO - 1499608409653 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e29ab045-7885-445c-b621-0a4fe210979d","ELEMENT":"e29ab045-7885-445c-b621-0a4fe210979d"}}]
06:53:29 INFO - 1499608409655 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e29ab045-7885-445c-b621-0a4fe210979d"}]
06:53:29 INFO - 1499608409888 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:29 INFO - 1499608409889 Marionette TRACE 376 <- [1,4,null,{}]
06:53:29 INFO - 1499608409890 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
06:53:29 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 307ms
06:53:29 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499608409893 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:53:29 INFO - 1499608409894 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:53:29 INFO - 1499608409895 Marionette TRACE 376 <- [1,6,null,{}]
06:53:29 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:53:29 INFO - 1499608409896 Marionette DEBUG Closed connection 376
06:53:29 INFO - 1499608409897 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49817
06:53:29 INFO - 1499608409898 Marionette DEBUG Closed connection 377
06:53:29 INFO - 1499608409899 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49818
06:53:29 INFO - 1499608409899 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:29 INFO - 1499608409900 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409902 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409905 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5ab972c7-9c60-437c-bfbf-be42c511b3d9","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409906 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:53:29 INFO - 1499608409906 Marionette TRACE 378 <- [1,2,null,{}]
06:53:29 INFO - 1499608409907 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:29 INFO - 1499608409907 Marionette TRACE 378 <- [1,3,null,{}]
06:53:29 INFO - 1499608409909 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:29 INFO - 1499608409909 Marionette TRACE 378 <- [1,4,null,{}]
06:53:29 INFO - 1499608409911 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
06:53:29 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499608409913 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:53:29 INFO - 1499608409914 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:53:29 INFO - 1499608409915 Marionette TRACE 378 <- [1,6,null,{}]
06:53:29 INFO - 1499608409916 Marionette DEBUG Closed connection 378
06:53:29 INFO - 1499608409917 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49819
06:53:29 INFO - 1499608409918 Marionette DEBUG Closed connection 379
06:53:29 INFO - 1499608409918 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49820
06:53:29 INFO - 1499608409919 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:29 INFO - 1499608409920 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409922 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409925 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a62c530d-a643-47f7-a6e3-8a51bf41b8ef","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409926 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:53:29 INFO - 1499608409927 Marionette TRACE 380 <- [1,2,null,{}]
06:53:29 INFO - 1499608409928 Marionette DEBUG Closed connection 380
06:53:29 INFO - 1499608409928 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49821
06:53:29 INFO - 1499608409929 Marionette DEBUG Closed connection 381
06:53:29 INFO - 1499608409929 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49822
06:53:29 INFO - 1499608409930 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:29 INFO - 1499608409931 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:29 INFO - 1499608409933 Marionette DEBUG Register listener.js for window 4294967297
06:53:29 INFO - 1499608409936 Marionette TRACE 382 <- [1,1,null,{"sessionId":"33183082-aa68-46f0-bee8-2af3364feef2","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:29 INFO - 1499608409937 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:53:29 INFO - 1499608409938 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:53:29 INFO - 1499608409940 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:53:29 INFO - 1499608409940 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:53:29 INFO - 1499608409945 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409948 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:29 INFO - 1499608409953 Marionette TRACE 382 <- [1,2,null,{}]
06:53:29 INFO - 1499608409956 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:53:29 INFO - 1499608409958 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9dab460f-6e42-4651-b5d2-ab57a58ab49a","ELEMENT":"9dab460f-6e42-4651-b5d2-ab57a58ab49a"}}]
06:53:29 INFO - 1499608409959 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"9dab460f-6e42-4651-b5d2-ab57a58ab49a"}]
06:53:30 INFO - 1499608410198 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:30 INFO - 1499608410199 Marionette TRACE 382 <- [1,4,null,{}]
06:53:30 INFO - 1499608410200 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
06:53:30 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499608410203 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:53:30 INFO - 1499608410204 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:53:30 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 309ms
06:53:30 INFO - TEST-START | test_click.py TestClick.test_input_file
06:53:30 INFO - 1499608410205 Marionette TRACE 382 <- [1,6,null,{}]
06:53:30 INFO - 1499608410206 Marionette DEBUG Closed connection 382
06:53:30 INFO - 1499608410207 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49823
06:53:30 INFO - 1499608410208 Marionette DEBUG Closed connection 383
06:53:30 INFO - 1499608410208 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49824
06:53:30 INFO - 1499608410209 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:30 INFO - 1499608410210 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410212 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410215 Marionette TRACE 384 <- [1,1,null,{"sessionId":"d1614a87-0755-4479-98a4-e62c8aac809c","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410216 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:53:30 INFO - 1499608410216 Marionette TRACE 384 <- [1,2,null,{}]
06:53:30 INFO - 1499608410217 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:30 INFO - 1499608410217 Marionette TRACE 384 <- [1,3,null,{}]
06:53:30 INFO - 1499608410219 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:30 INFO - 1499608410219 Marionette TRACE 384 <- [1,4,null,{}]
06:53:30 INFO - 1499608410220 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
06:53:30 INFO - TEST-START: test_click.py TestClick.test_input_file1499608410223 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:53:30 INFO - 1499608410223 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:53:30 INFO - 1499608410225 Marionette TRACE 384 <- [1,6,null,{}]
06:53:30 INFO - 1499608410226 Marionette DEBUG Closed connection 384
06:53:30 INFO - 1499608410226 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49825
06:53:30 INFO - 1499608410227 Marionette DEBUG Closed connection 385
06:53:30 INFO - 1499608410228 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49826
06:53:30 INFO - 1499608410228 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:30 INFO - 1499608410229 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410231 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410241 Marionette TRACE 386 <- [1,1,null,{"sessionId":"ad90b0f8-8e57-4e4f-a58e-d1ee0d164db0","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410242 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:53:30 INFO - 1499608410244 Marionette TRACE 386 <- [1,2,null,{}]
06:53:30 INFO - 1499608410245 Marionette DEBUG Closed connection 386
06:53:30 INFO - 1499608410245 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49827
06:53:30 INFO - 1499608410246 Marionette DEBUG Closed connection 387
06:53:30 INFO - 1499608410247 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49828
06:53:30 INFO - 1499608410248 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:30 INFO - 1499608410248 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410251 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410253 Marionette TRACE 388 <- [1,1,null,{"sessionId":"3d9a3caf-ae9a-487f-b846-084d26e2e1ec","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410254 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:53:30 INFO - 1499608410256 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:30 INFO - 1499608410257 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:30 INFO - 1499608410258 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:53:30 INFO - 1499608410266 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:30 INFO - 1499608410267 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:30 INFO - 1499608410271 Marionette TRACE 388 <- [1,2,null,{}]
06:53:30 INFO - 1499608410274 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:53:30 INFO - 1499608410276 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9341d93c-7a3f-4242-b006-84308fcb87d8","ELEMENT":"9341d93c-7a3f-4242-b006-84308fcb87d8"}}]
06:53:30 INFO - 1499608410277 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"9341d93c-7a3f-4242-b006-84308fcb87d8"}]
06:53:30 INFO - 1499608410301 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:53:30 INFO - 1499608410303 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
06:53:30 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 102ms
06:53:30 INFO - TEST-END: test_click.py TestClick.test_input_file1499608410306 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:53:30 INFO - 1499608410307 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:53:30 INFO - 1499608410308 Marionette TRACE 388 <- [1,6,null,{}]
06:53:30 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:53:30 INFO - 1499608410309 Marionette DEBUG Closed connection 388
06:53:30 INFO - 1499608410310 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49829
06:53:30 INFO - 1499608410311 Marionette DEBUG Closed connection 389
06:53:30 INFO - 1499608410312 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49830
06:53:30 INFO - 1499608410313 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:30 INFO - 1499608410314 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410316 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410319 Marionette TRACE 390 <- [1,1,null,{"sessionId":"e4b375f9-1a7a-4693-9d35-4b06589534a3","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410320 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:53:30 INFO - 1499608410320 Marionette TRACE 390 <- [1,2,null,{}]
06:53:30 INFO - 1499608410321 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:30 INFO - 1499608410321 Marionette TRACE 390 <- [1,3,null,{}]
06:53:30 INFO - 1499608410322 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:30 INFO - 1499608410323 Marionette TRACE 390 <- [1,4,null,{}]
06:53:30 INFO - 1499608410324 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
06:53:30 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499608410332 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:53:30 INFO - 1499608410333 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:53:30 INFO - 1499608410334 Marionette TRACE 390 <- [1,6,null,{}]
06:53:30 INFO - 1499608410335 Marionette DEBUG Closed connection 390
06:53:30 INFO - 1499608410335 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49831
06:53:30 INFO - 1499608410336 Marionette DEBUG Closed connection 391
06:53:30 INFO - 1499608410337 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49832
06:53:30 INFO - 1499608410338 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:30 INFO - 1499608410338 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410341 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410343 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a9276fd0-cab2-4a36-9c07-71ed064cb4b6","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410344 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:53:30 INFO - 1499608410346 Marionette TRACE 392 <- [1,2,null,{}]
06:53:30 INFO - 1499608410347 Marionette DEBUG Closed connection 392
06:53:30 INFO - 1499608410348 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49833
06:53:30 INFO - 1499608410349 Marionette DEBUG Closed connection 393
06:53:30 INFO - 1499608410349 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49834
06:53:30 INFO - 1499608410350 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:30 INFO - 1499608410351 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410353 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410355 Marionette TRACE 394 <- [1,1,null,{"sessionId":"687b3320-917f-423a-89fa-42e3ffb37968","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410356 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
06:53:30 INFO - 1499608410357 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:30 INFO - 1499608410359 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:30 INFO - 1499608410359 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:30 INFO - 1499608410366 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:53:30 INFO - 1499608410368 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:53:30 INFO - 1499608410372 Marionette TRACE 394 <- [1,2,null,{}]
06:53:30 INFO - 1499608410375 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:53:30 INFO - 1499608410383 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3e3ed434-edbc-4fc2-bba0-87d83a232701","ELEMENT":"3e3ed434-edbc-4fc2-bba0-87d83a232701"}}]
06:53:30 INFO - 1499608410384 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:53:30 INFO - 1499608410396 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"08612c2f-5772-4d29-bf44-c0deb4a222e2","ELEMENT":"08612c2f-5772-4d29-bf44-c0deb4a222e2"}}]
06:53:30 INFO - 1499608410397 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"3e3ed434-edbc-4fc2-bba0-87d83a232701"}]
06:53:30 INFO - 1499608410634 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:30 INFO - 1499608410635 Marionette TRACE 394 <- [1,5,null,{}]
06:53:30 INFO - 1499608410636 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"08612c2f-5772-4d29-bf44-c0deb4a222e2"}]
06:53:30 INFO - 1499608410649 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:53:30 INFO - 1499608410651 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:53:30 INFO - 1499608410654 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:53:30 INFO - 1499608410655 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
06:53:30 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499608410657 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:53:30 INFO - 1499608410658 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:53:30 INFO - 1499608410659 Marionette TRACE 394 <- [1,9,null,{}]
06:53:30 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 351ms
06:53:30 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:53:30 INFO - 1499608410661 Marionette DEBUG Closed connection 394
06:53:30 INFO - 1499608410662 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49835
06:53:30 INFO - 1499608410663 Marionette DEBUG Closed connection 395
06:53:30 INFO - 1499608410664 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49836
06:53:30 INFO - 1499608410665 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:30 INFO - 1499608410665 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410667 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410670 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a533aec0-9b62-4fbf-ae14-b95c1ae9b5ec","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410672 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:53:30 INFO - 1499608410672 Marionette TRACE 396 <- [1,2,null,{}]
06:53:30 INFO - 1499608410673 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:30 INFO - 1499608410673 Marionette TRACE 396 <- [1,3,null,{}]
06:53:30 INFO - 1499608410674 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:30 INFO - 1499608410674 Marionette TRACE 396 <- [1,4,null,{}]
06:53:30 INFO - 1499608410677 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
06:53:30 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499608410679 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:53:30 INFO - 1499608410680 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:53:30 INFO - 1499608410681 Marionette TRACE 396 <- [1,6,null,{}]
06:53:30 INFO - 1499608410682 Marionette DEBUG Closed connection 396
06:53:30 INFO - 1499608410682 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49837
06:53:30 INFO - 1499608410683 Marionette DEBUG Closed connection 397
06:53:30 INFO - 1499608410684 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49838
06:53:30 INFO - 1499608410685 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:30 INFO - 1499608410685 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410687 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410690 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d18e988f-73b0-49f1-95aa-22980a5f8d04","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410692 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:53:30 INFO - 1499608410693 Marionette TRACE 398 <- [1,2,null,{}]
06:53:30 INFO - 1499608410694 Marionette DEBUG Closed connection 398
06:53:30 INFO - 1499608410694 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49839
06:53:30 INFO - 1499608410695 Marionette DEBUG Closed connection 399
06:53:30 INFO - 1499608410696 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49840
06:53:30 INFO - 1499608410697 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:30 INFO - 1499608410697 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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}
06:53:30 INFO - 1499608410699 Marionette DEBUG Register listener.js for window 4294967297
06:53:30 INFO - 1499608410702 Marionette TRACE 400 <- [1,1,null,{"sessionId":"52465ba4-5020-41f6-a743-05007f7ec860","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":812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-197.001\\AppData\\Local\\Temp\\tmpszewid.mozrunner","moz:accessibilityChecks":false}}]
06:53:30 INFO - 1499608410704 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
06:53:30 INFO - 1499608410706 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:53:30 INFO - 1499608410707 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:53:30 INFO - 1499608410708 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:53:30 INFO - 1499608410714 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
06:53:30 INFO - 1499608410716 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
06:53:30 INFO - 1499608410722 Marionette TRACE 400 <- [1,2,null,{}]
06:53:30 INFO - 1499608410725 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:53:30 INFO - 1499608410727 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8aeceae0-662a-4eda-8620-291f54e88c71","ELEMENT":"8aeceae0-662a-4eda-8620-291f54e88c71"}}]
06:53:30 INFO - 1499608410728 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8aeceae0-662a-4eda-8620-291f54e88c71"}]
06:53:30 INFO - 1499608410750 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:53:30 INFO - 1499608410751 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"8aeceae0-662a-4eda-8620-291f54e88c71"}]
06:53:30 INFO - 1499608410765 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element