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:1318:5\n"},null]
04:55:13 INFO - 1499169313848 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}]
04:55:13 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499169313850 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:55:13 INFO - 1499169313851 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:55:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 118ms
04:55:13 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:55:13 INFO - 1499169313853 Marionette TRACE 352 <- [1,6,null,{}]
04:55:13 INFO - 1499169313854 Marionette DEBUG Closed connection 352
04:55:13 INFO - 1499169313855 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49800
04:55:13 INFO - 1499169313856 Marionette DEBUG Closed connection 353
04:55:13 INFO - 1499169313857 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49801
04:55:13 INFO - 1499169313858 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:13 INFO - 1499169313858 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:13 INFO - 1499169313861 Marionette DEBUG Register listener.js for window 4294967297
04:55:13 INFO - 1499169313863 Marionette TRACE 354 <- [1,1,null,{"sessionId":"9bab448f-d253-42c3-bff0-07ec3e346e12","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:13 INFO - 1499169313864 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:55:13 INFO - 1499169313865 Marionette TRACE 354 <- [1,2,null,{}]
04:55:13 INFO - 1499169313866 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:55:13 INFO - 1499169313866 Marionette TRACE 354 <- [1,3,null,{}]
04:55:13 INFO - 1499169313867 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:55:13 INFO - 1499169313868 Marionette TRACE 354 <- [1,4,null,{}]
04:55:13 INFO - 1499169313869 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}]
04:55:13 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499169313872 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:55:13 INFO - 1499169313873 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:55:13 INFO - 1499169313874 Marionette TRACE 354 <- [1,6,null,{}]
04:55:13 INFO - 1499169313875 Marionette DEBUG Closed connection 354
04:55:13 INFO - 1499169313876 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49802
04:55:13 INFO - 1499169313877 Marionette DEBUG Closed connection 355
04:55:13 INFO - 1499169313878 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49803
04:55:13 INFO - 1499169313879 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:13 INFO - 1499169313879 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:13 INFO - 1499169313881 Marionette DEBUG Register listener.js for window 4294967297
04:55:13 INFO - 1499169313885 Marionette TRACE 356 <- [1,1,null,{"sessionId":"107db6bc-2db7-4662-869e-1a99fc129752","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:13 INFO - 1499169313886 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:55:13 INFO - 1499169313888 Marionette TRACE 356 <- [1,2,null,{}]
04:55:13 INFO - 1499169313889 Marionette DEBUG Closed connection 356
04:55:13 INFO - 1499169313890 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49804
04:55:13 INFO - 1499169313890 Marionette DEBUG Closed connection 357
04:55:13 INFO - 1499169313891 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49805
04:55:13 INFO - 1499169313892 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:55:13 INFO - 1499169313893 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:13 INFO - 1499169313895 Marionette DEBUG Register listener.js for window 4294967297
04:55:13 INFO - 1499169313898 Marionette TRACE 358 <- [1,1,null,{"sessionId":"167cc7fa-9a94-46d1-9875-60a140cd2ea1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:13 INFO - 1499169313899 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49363/clicks.html"}]
04:55:13 INFO - 1499169313901 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49363/hidden.html"
04:55:13 INFO - 1499169313908 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49363/hidden.html"
04:55:13 INFO - 1499169313908 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49363/hidden.html"
04:55:13 INFO - 1499169313916 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49363/clicks.html"
04:55:13 INFO - 1499169313920 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49363/clicks.html"
04:55:13 INFO - 1499169313922 Marionette TRACE 358 <- [1,2,null,{}]
04:55:13 INFO - 1499169313930 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:55:13 INFO - 1499169313934 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bef1e1ff-7b42-4019-8f35-4132ac852b3a","ELEMENT":"bef1e1ff-7b42-4019-8f35-4132ac852b3a"}}]
04:55:13 INFO - 1499169313935 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"bef1e1ff-7b42-4019-8f35-4132ac852b3a"}]
04:55:13 INFO - 1499169313979 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49363/clicks.html"
04:55:13 INFO - 1499169313987 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49363/clicks.html"
04:55:13 INFO - 1499169313987 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49363/clicks.html"
04:55:14 INFO - 1499169313995 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49363/test.html"
04:55:14 INFO - 1499169313997 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49363/test.html"
04:55:14 INFO - 1499169314011 Marionette TRACE 358 <- [1,4,null,{}]
04:55:14 INFO - 1499169314019 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:55:14 INFO - 1499169314023 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6cd0ab2b-fd09-442b-a22e-a55933e1bea5","ELEMENT":"6cd0ab2b-fd09-442b-a22e-a55933e1bea5"}}]
04:55:14 INFO - 1499169314024 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:55:14 INFO - 1499169314024 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:55:14 INFO - 1499169314031 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}]
04:55:14 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499169314034 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:55:14 INFO - 1499169314035 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:55:14 INFO - 1499169314036 Marionette TRACE 358 <- [1,8,null,{}]
04:55:14 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 183ms
04:55:14 INFO - TEST-START | test_click.py TestClick.test_container_element
04:55:14 INFO - 1499169314038 Marionette DEBUG Closed connection 358
04:55:14 INFO - 1499169314039 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49808
04:55:14 INFO - 1499169314039 Marionette DEBUG Closed connection 359
04:55:14 INFO - 1499169314040 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49809
04:55:14 INFO - 1499169314041 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:14 INFO - 1499169314042 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314044 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314047 Marionette TRACE 360 <- [1,1,null,{"sessionId":"1b7e9e2f-5c34-4984-bdc3-8b8cd9b4c4ff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314048 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:55:14 INFO - 1499169314049 Marionette TRACE 360 <- [1,2,null,{}]
04:55:14 INFO - 1499169314050 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:55:14 INFO - 1499169314050 Marionette TRACE 360 <- [1,3,null,{}]
04:55:14 INFO - 1499169314051 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:55:14 INFO - 1499169314051 Marionette TRACE 360 <- [1,4,null,{}]
04:55:14 INFO - 1499169314054 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}]
04:55:14 INFO - TEST-START: test_click.py TestClick.test_container_element1499169314057 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:55:14 INFO - 1499169314058 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:55:14 INFO - 1499169314059 Marionette TRACE 360 <- [1,6,null,{}]
04:55:14 INFO - 1499169314060 Marionette DEBUG Closed connection 360
04:55:14 INFO - 1499169314061 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49810
04:55:14 INFO - 1499169314062 Marionette DEBUG Closed connection 361
04:55:14 INFO - 1499169314063 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49811
04:55:14 INFO - 1499169314063 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:14 INFO - 1499169314064 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314066 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314069 Marionette TRACE 362 <- [1,1,null,{"sessionId":"100cd347-401e-4c17-ad89-f162f7b03025","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314070 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:55:14 INFO - 1499169314071 Marionette TRACE 362 <- [1,2,null,{}]
04:55:14 INFO - 1499169314073 Marionette DEBUG Closed connection 362
04:55:14 INFO - 1499169314074 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49812
04:55:14 INFO - 1499169314074 Marionette DEBUG Closed connection 363
04:55:14 INFO - 1499169314075 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49813
04:55:14 INFO - 1499169314076 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:55:14 INFO - 1499169314077 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314079 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314082 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a4d6bb8c-dbd9-49d3-990c-19e6d07a85fa","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314083 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"}]
04:55:14 INFO - 1499169314085 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49363/test.html"
04:55:14 INFO - 1499169314088 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49363/test.html"
04:55:14 INFO - 1499169314088 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49363/test.html"
04:55:14 INFO - 1499169314096 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"
04:55:14 INFO - 1499169314101 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"
04:55:14 INFO - 1499169314105 Marionette TRACE 364 <- [1,2,null,{}]
04:55:14 INFO - 1499169314115 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:55:14 INFO - 1499169314118 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bed99bb7-bdc5-46af-a187-ceb1962f8734","ELEMENT":"bed99bb7-bdc5-46af-a187-ceb1962f8734"}}]
04:55:14 INFO - 1499169314119 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"bed99bb7-bdc5-46af-a187-ceb1962f8734"}]
04:55:14 INFO - 1499169314358 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:55:14 INFO - 1499169314359 Marionette TRACE 364 <- [1,4,null,{}]
04:55:14 INFO - 1499169314360 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"bed99bb7-bdc5-46af-a187-ceb1962f8734","name":"selected"}]
04:55:14 INFO - 1499169314364 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:55:14 INFO - 1499169314366 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}]
04:55:14 INFO - TEST-END: test_click.py TestClick.test_container_element1499169314368 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:55:14 INFO - 1499169314369 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:55:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 334ms
04:55:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:55:14 INFO - 1499169314370 Marionette TRACE 364 <- [1,7,null,{}]
04:55:14 INFO - 1499169314372 Marionette DEBUG Closed connection 364
04:55:14 INFO - 1499169314373 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49814
04:55:14 INFO - 1499169314374 Marionette DEBUG Closed connection 365
04:55:14 INFO - 1499169314374 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49815
04:55:14 INFO - 1499169314375 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:14 INFO - 1499169314376 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314378 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314381 Marionette TRACE 366 <- [1,1,null,{"sessionId":"a1f7374e-1138-4422-912f-e47377ce9177","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314382 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:55:14 INFO - 1499169314382 Marionette TRACE 366 <- [1,2,null,{}]
04:55:14 INFO - 1499169314383 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:55:14 INFO - 1499169314384 Marionette TRACE 366 <- [1,3,null,{}]
04:55:14 INFO - 1499169314385 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:55:14 INFO - 1499169314385 Marionette TRACE 366 <- [1,4,null,{}]
04:55:14 INFO - 1499169314387 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}]
04:55:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499169314390 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:55:14 INFO - 1499169314391 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:55:14 INFO - 1499169314392 Marionette TRACE 366 <- [1,6,null,{}]
04:55:14 INFO - 1499169314393 Marionette DEBUG Closed connection 366
04:55:14 INFO - 1499169314394 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49816
04:55:14 INFO - 1499169314395 Marionette DEBUG Closed connection 367
04:55:14 INFO - 1499169314395 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49817
04:55:14 INFO - 1499169314396 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:14 INFO - 1499169314397 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314399 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314402 Marionette TRACE 368 <- [1,1,null,{"sessionId":"a751c232-c817-4c1f-83c9-5c718353183b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314403 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:55:14 INFO - 1499169314404 Marionette TRACE 368 <- [1,2,null,{}]
04:55:14 INFO - 1499169314405 Marionette DEBUG Closed connection 368
04:55:14 INFO - 1499169314406 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49818
04:55:14 INFO - 1499169314407 Marionette DEBUG Closed connection 369
04:55:14 INFO - 1499169314409 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49819
04:55:14 INFO - 1499169314410 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:55:14 INFO - 1499169314410 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314412 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314415 Marionette TRACE 370 <- [1,1,null,{"sessionId":"5166d675-dd83-411a-9aaa-3c9113a3329c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314416 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"}]
04:55:14 INFO - 1499169314418 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"
04:55:14 INFO - 1499169314420 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"
04:55:14 INFO - 1499169314420 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"
04:55:14 INFO - 1499169314428 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"
04:55:14 INFO - 1499169314429 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"
04:55:14 INFO - 1499169314436 Marionette TRACE 370 <- [1,2,null,{}]
04:55:14 INFO - 1499169314439 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:55:14 INFO - 1499169314445 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fce6acdd-6819-45c3-b014-d2ce45bd02e6","ELEMENT":"fce6acdd-6819-45c3-b014-d2ce45bd02e6"}}]
04:55:14 INFO - 1499169314446 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fce6acdd-6819-45c3-b014-d2ce45bd02e6"}]
04:55:14 INFO - 1499169314687 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:55:14 INFO - 1499169314687 Marionette TRACE 370 <- [1,4,null,{}]
04:55:14 INFO - 1499169314689 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fce6acdd-6819-45c3-b014-d2ce45bd02e6","name":"selected"}]
04:55:14 INFO - 1499169314693 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:55:14 INFO - 1499169314694 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}]
04:55:14 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499169314697 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:55:14 INFO - 1499169314698 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:55:14 INFO - 1499169314699 Marionette TRACE 370 <- [1,7,null,{}]
04:55:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 329ms
04:55:14 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:55:14 INFO - 1499169314701 Marionette DEBUG Closed connection 370
04:55:14 INFO - 1499169314702 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49820
04:55:14 INFO - 1499169314703 Marionette DEBUG Closed connection 371
04:55:14 INFO - 1499169314703 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49821
04:55:14 INFO - 1499169314704 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:14 INFO - 1499169314705 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314707 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314710 Marionette TRACE 372 <- [1,1,null,{"sessionId":"a310f6bf-502f-46ed-8f7a-a2b9a16dba19","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314711 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:55:14 INFO - 1499169314712 Marionette TRACE 372 <- [1,2,null,{}]
04:55:14 INFO - 1499169314712 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:55:14 INFO - 1499169314713 Marionette TRACE 372 <- [1,3,null,{}]
04:55:14 INFO - 1499169314714 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:55:14 INFO - 1499169314714 Marionette TRACE 372 <- [1,4,null,{}]
04:55:14 INFO - 1499169314716 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}]
04:55:14 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499169314719 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:55:14 INFO - 1499169314719 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:55:14 INFO - 1499169314721 Marionette TRACE 372 <- [1,6,null,{}]
04:55:14 INFO - 1499169314722 Marionette DEBUG Closed connection 372
04:55:14 INFO - 1499169314723 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49822
04:55:14 INFO - 1499169314723 Marionette DEBUG Closed connection 373
04:55:14 INFO - 1499169314724 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49823
04:55:14 INFO - 1499169314725 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:14 INFO - 1499169314726 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314728 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314731 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4e39a003-297f-4960-856e-6468ff2ac5ff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314732 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:55:14 INFO - 1499169314733 Marionette TRACE 374 <- [1,2,null,{}]
04:55:14 INFO - 1499169314734 Marionette DEBUG Closed connection 374
04:55:14 INFO - 1499169314735 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49824
04:55:14 INFO - 1499169314736 Marionette DEBUG Closed connection 375
04:55:14 INFO - 1499169314736 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49825
04:55:14 INFO - 1499169314737 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:55:14 INFO - 1499169314738 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:14 INFO - 1499169314740 Marionette DEBUG Register listener.js for window 4294967297
04:55:14 INFO - 1499169314743 Marionette TRACE 376 <- [1,1,null,{"sessionId":"2434ac60-2259-4727-80a5-1c12772b4eeb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:14 INFO - 1499169314744 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"}]
04:55:14 INFO - 1499169314746 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"
04:55:14 INFO - 1499169314748 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"
04:55:14 INFO - 1499169314748 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"
04:55:14 INFO - 1499169314754 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"
04:55:14 INFO - 1499169314756 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"
04:55:14 INFO - 1499169314765 Marionette TRACE 376 <- [1,2,null,{}]
04:55:14 INFO - 1499169314770 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:55:14 INFO - 1499169314773 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f9984dc4-a4d4-4311-9ee9-4dd30c73c5aa","ELEMENT":"f9984dc4-a4d4-4311-9ee9-4dd30c73c5aa"}}]
04:55:14 INFO - 1499169314774 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f9984dc4-a4d4-4311-9ee9-4dd30c73c5aa"}]
04:55:15 INFO - 1499169315014 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:55:15 INFO - 1499169315015 Marionette TRACE 376 <- [1,4,null,{}]
04:55:15 INFO - 1499169315016 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}]
04:55:15 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499169315019 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:55:15 INFO - 1499169315020 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:55:15 INFO - 1499169315021 Marionette TRACE 376 <- [1,6,null,{}]
04:55:15 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
04:55:15 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:55:15 INFO - 1499169315023 Marionette DEBUG Closed connection 376
04:55:15 INFO - 1499169315024 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49826
04:55:15 INFO - 1499169315024 Marionette DEBUG Closed connection 377
04:55:15 INFO - 1499169315025 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49827
04:55:15 INFO - 1499169315026 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:15 INFO - 1499169315027 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315029 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315032 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e445dde2-54ce-49b9-8310-49e3c12f37e4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315033 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:55:15 INFO - 1499169315034 Marionette TRACE 378 <- [1,2,null,{}]
04:55:15 INFO - 1499169315034 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:55:15 INFO - 1499169315035 Marionette TRACE 378 <- [1,3,null,{}]
04:55:15 INFO - 1499169315036 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:55:15 INFO - 1499169315036 Marionette TRACE 378 <- [1,4,null,{}]
04:55:15 INFO - 1499169315038 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}]
04:55:15 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499169315041 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:55:15 INFO - 1499169315042 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:55:15 INFO - 1499169315043 Marionette TRACE 378 <- [1,6,null,{}]
04:55:15 INFO - 1499169315044 Marionette DEBUG Closed connection 378
04:55:15 INFO - 1499169315045 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49828
04:55:15 INFO - 1499169315045 Marionette DEBUG Closed connection 379
04:55:15 INFO - 1499169315046 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49829
04:55:15 INFO - 1499169315047 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:15 INFO - 1499169315048 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315050 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315053 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d996f830-53e2-466d-95da-6e0ebcd01081","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315054 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:55:15 INFO - 1499169315055 Marionette TRACE 380 <- [1,2,null,{}]
04:55:15 INFO - 1499169315057 Marionette DEBUG Closed connection 380
04:55:15 INFO - 1499169315058 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49830
04:55:15 INFO - 1499169315058 Marionette DEBUG Closed connection 381
04:55:15 INFO - 1499169315059 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49831
04:55:15 INFO - 1499169315060 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:55:15 INFO - 1499169315061 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315063 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315066 Marionette TRACE 382 <- [1,1,null,{"sessionId":"efca265e-8990-4712-b72d-62332fa723a2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315067 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"}]
04:55:15 INFO - 1499169315069 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"
04:55:15 INFO - 1499169315070 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"
04:55:15 INFO - 1499169315071 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"
04:55:15 INFO - 1499169315077 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"
04:55:15 INFO - 1499169315080 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"
04:55:15 INFO - 1499169315086 Marionette TRACE 382 <- [1,2,null,{}]
04:55:15 INFO - 1499169315089 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:55:15 INFO - 1499169315094 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cff499d6-1989-4880-a0b2-e54ef49ce89a","ELEMENT":"cff499d6-1989-4880-a0b2-e54ef49ce89a"}}]
04:55:15 INFO - 1499169315096 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"cff499d6-1989-4880-a0b2-e54ef49ce89a"}]
04:55:15 INFO - 1499169315337 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:55:15 INFO - 1499169315338 Marionette TRACE 382 <- [1,4,null,{}]
04:55:15 INFO - 1499169315340 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}]
04:55:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499169315343 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:55:15 INFO - 1499169315343 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:55:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 323ms
04:55:15 INFO - TEST-START | test_click.py TestClick.test_input_file
04:55:15 INFO - 1499169315345 Marionette TRACE 382 <- [1,6,null,{}]
04:55:15 INFO - 1499169315346 Marionette DEBUG Closed connection 382
04:55:15 INFO - 1499169315347 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49832
04:55:15 INFO - 1499169315348 Marionette DEBUG Closed connection 383
04:55:15 INFO - 1499169315349 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49833
04:55:15 INFO - 1499169315350 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:15 INFO - 1499169315350 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315353 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315355 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4a82d967-e46d-4162-a469-c31369d60b44","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315356 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:55:15 INFO - 1499169315357 Marionette TRACE 384 <- [1,2,null,{}]
04:55:15 INFO - 1499169315358 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:55:15 INFO - 1499169315358 Marionette TRACE 384 <- [1,3,null,{}]
04:55:15 INFO - 1499169315359 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:55:15 INFO - 1499169315360 Marionette TRACE 384 <- [1,4,null,{}]
04:55:15 INFO - 1499169315361 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}]
04:55:15 INFO - TEST-START: test_click.py TestClick.test_input_file1499169315364 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:55:15 INFO - 1499169315365 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:55:15 INFO - 1499169315366 Marionette TRACE 384 <- [1,6,null,{}]
04:55:15 INFO - 1499169315367 Marionette DEBUG Closed connection 384
04:55:15 INFO - 1499169315368 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49834
04:55:15 INFO - 1499169315369 Marionette DEBUG Closed connection 385
04:55:15 INFO - 1499169315369 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49835
04:55:15 INFO - 1499169315370 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:15 INFO - 1499169315371 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315373 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315376 Marionette TRACE 386 <- [1,1,null,{"sessionId":"ed9cf04c-599c-4efe-8e52-d400e6a0f6b1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315377 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:55:15 INFO - 1499169315378 Marionette TRACE 386 <- [1,2,null,{}]
04:55:15 INFO - 1499169315379 Marionette DEBUG Closed connection 386
04:55:15 INFO - 1499169315380 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49836
04:55:15 INFO - 1499169315381 Marionette DEBUG Closed connection 387
04:55:15 INFO - 1499169315382 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49837
04:55:15 INFO - 1499169315383 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:55:15 INFO - 1499169315383 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315385 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315396 Marionette TRACE 388 <- [1,1,null,{"sessionId":"dab15e47-1e1a-4bff-8903-c9e16b330921","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315398 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:55:15 INFO - 1499169315399 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"
04:55:15 INFO - 1499169315401 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"
04:55:15 INFO - 1499169315402 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"
04:55:15 INFO - 1499169315411 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:55:15 INFO - 1499169315412 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:55:15 INFO - 1499169315421 Marionette TRACE 388 <- [1,2,null,{}]
04:55:15 INFO - 1499169315428 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:55:15 INFO - 1499169315432 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dbc8958a-facc-4aa1-9728-972545b293e3","ELEMENT":"dbc8958a-facc-4aa1-9728-972545b293e3"}}]
04:55:15 INFO - 1499169315433 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"dbc8958a-facc-4aa1-9728-972545b293e3"}]
04:55:15 INFO - 1499169315459 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:1318:5\n"},null]
04:55:15 INFO - 1499169315461 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}]
04:55:15 INFO - TEST-END: test_click.py TestClick.test_input_file1499169315463 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:55:15 INFO - 1499169315464 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:55:15 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 120ms
04:55:15 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:55:15 INFO - 1499169315465 Marionette TRACE 388 <- [1,6,null,{}]
04:55:15 INFO - 1499169315467 Marionette DEBUG Closed connection 388
04:55:15 INFO - 1499169315468 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49838
04:55:15 INFO - 1499169315469 Marionette DEBUG Closed connection 389
04:55:15 INFO - 1499169315469 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49839
04:55:15 INFO - 1499169315470 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:15 INFO - 1499169315471 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315473 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315476 Marionette TRACE 390 <- [1,1,null,{"sessionId":"48d11096-22c7-47e0-8c3e-8c2745f7c33b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315477 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:55:15 INFO - 1499169315477 Marionette TRACE 390 <- [1,2,null,{}]
04:55:15 INFO - 1499169315478 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:55:15 INFO - 1499169315479 Marionette TRACE 390 <- [1,3,null,{}]
04:55:15 INFO - 1499169315480 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:55:15 INFO - 1499169315480 Marionette TRACE 390 <- [1,4,null,{}]
04:55:15 INFO - 1499169315482 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}]
04:55:15 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499169315484 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:55:15 INFO - 1499169315485 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:55:15 INFO - 1499169315487 Marionette TRACE 390 <- [1,6,null,{}]
04:55:15 INFO - 1499169315488 Marionette DEBUG Closed connection 390
04:55:15 INFO - 1499169315489 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49840
04:55:15 INFO - 1499169315489 Marionette DEBUG Closed connection 391
04:55:15 INFO - 1499169315490 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49841
04:55:15 INFO - 1499169315491 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:15 INFO - 1499169315492 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315494 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315496 Marionette TRACE 392 <- [1,1,null,{"sessionId":"65e05876-2aa1-46f1-af23-2230519fb91a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315498 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:55:15 INFO - 1499169315499 Marionette TRACE 392 <- [1,2,null,{}]
04:55:15 INFO - 1499169315500 Marionette DEBUG Closed connection 392
04:55:15 INFO - 1499169315501 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49842
04:55:15 INFO - 1499169315501 Marionette DEBUG Closed connection 393
04:55:15 INFO - 1499169315502 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49843
04:55:15 INFO - 1499169315503 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:55:15 INFO - 1499169315504 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315506 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315509 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e1ac8a65-9c69-4657-9793-00e11b338349","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315510 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"}]
04:55:15 INFO - 1499169315511 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:55:15 INFO - 1499169315514 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:55:15 INFO - 1499169315514 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:55:15 INFO - 1499169315520 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"
04:55:15 INFO - 1499169315523 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"
04:55:15 INFO - 1499169315524 Marionette TRACE 394 <- [1,2,null,{}]
04:55:15 INFO - 1499169315533 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:55:15 INFO - 1499169315536 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c6ea809e-eea2-4581-b3f9-7f474db5b2d0","ELEMENT":"c6ea809e-eea2-4581-b3f9-7f474db5b2d0"}}]
04:55:15 INFO - 1499169315537 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:55:15 INFO - 1499169315539 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4cb91f4-513b-466a-81bb-daef4bce9ddc","ELEMENT":"a4cb91f4-513b-466a-81bb-daef4bce9ddc"}}]
04:55:15 INFO - 1499169315545 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"c6ea809e-eea2-4581-b3f9-7f474db5b2d0"}]
04:55:15 INFO - 1499169315784 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:55:15 INFO - 1499169315784 Marionette TRACE 394 <- [1,5,null,{}]
04:55:15 INFO - 1499169315785 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a4cb91f4-513b-466a-81bb-daef4bce9ddc"}]
04:55:15 INFO - 1499169315799 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,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:1318:5\n"},null]
04:55:15 INFO - 1499169315801 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:55:15 INFO - 1499169315804 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:55:15 INFO - 1499169315806 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}]
04:55:15 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499169315808 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:55:15 INFO - 1499169315809 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:55:15 INFO - 1499169315810 Marionette TRACE 394 <- [1,9,null,{}]
04:55:15 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 345ms
04:55:15 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:55:15 INFO - 1499169315812 Marionette DEBUG Closed connection 394
04:55:15 INFO - 1499169315813 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49844
04:55:15 INFO - 1499169315814 Marionette DEBUG Closed connection 395
04:55:15 INFO - 1499169315815 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49845
04:55:15 INFO - 1499169315816 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:15 INFO - 1499169315816 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315818 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315821 Marionette TRACE 396 <- [1,1,null,{"sessionId":"58320b2d-bd09-4cf5-9ec2-c50daf52cc94","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315822 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:55:15 INFO - 1499169315823 Marionette TRACE 396 <- [1,2,null,{}]
04:55:15 INFO - 1499169315823 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:55:15 INFO - 1499169315824 Marionette TRACE 396 <- [1,3,null,{}]
04:55:15 INFO - 1499169315825 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:55:15 INFO - 1499169315825 Marionette TRACE 396 <- [1,4,null,{}]
04:55:15 INFO - 1499169315827 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}]
04:55:15 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499169315830 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:55:15 INFO - 1499169315830 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:55:15 INFO - 1499169315832 Marionette TRACE 396 <- [1,6,null,{}]
04:55:15 INFO - 1499169315833 Marionette DEBUG Closed connection 396
04:55:15 INFO - 1499169315834 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49846
04:55:15 INFO - 1499169315835 Marionette DEBUG Closed connection 397
04:55:15 INFO - 1499169315835 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49847
04:55:15 INFO - 1499169315836 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:55:15 INFO - 1499169315837 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315839 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315842 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1d9c3554-b81b-434c-9bf4-201caf2fce4b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315843 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:55:15 INFO - 1499169315844 Marionette TRACE 398 <- [1,2,null,{}]
04:55:15 INFO - 1499169315845 Marionette DEBUG Closed connection 398
04:55:15 INFO - 1499169315846 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49848
04:55:15 INFO - 1499169315847 Marionette DEBUG Closed connection 399
04:55:15 INFO - 1499169315847 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49849
04:55:15 INFO - 1499169315848 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:55:15 INFO - 1499169315849 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}
04:55:15 INFO - 1499169315851 Marionette DEBUG Register listener.js for window 4294967297
04:55:15 INFO - 1499169315854 Marionette TRACE 400 <- [1,1,null,{"sessionId":"de83d25f-9f64-48cf-abe4-7d39199e3dbf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":216,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrqcdu.mozrunner","moz:accessibilityChecks":false}}]
04:55:15 INFO - 1499169315855 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"}]
04:55:15 INFO - 1499169315856 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"
04:55:15 INFO - 1499169315858 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"
04:55:15 INFO - 1499169315859 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"
04:55:15 INFO - 1499169315865 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"
04:55:15 INFO - 1499169315867 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"
04:55:15 INFO - 1499169315870 Marionette TRACE 400 <- [1,2,null,{}]
04:55:15 INFO - 1499169315873 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:55:15 INFO - 1499169315879 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1fcbe76f-772a-4ca1-ad1e-89ec36a3e979","ELEMENT":"1fcbe76f-772a-4ca1-ad1e-89ec36a3e979"}}]
04:55:15 INFO - 1499169315881 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1fcbe76f-772a-4ca1-ad1e-89ec36a3e979"}]
04:55:15 INFO - 1499169315904 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:55:15 INFO - 1499169315905 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"1fcbe76f-772a-4ca1-ad1e-89ec36a3e979"}]
04:55:15 INFO - 1499169315919 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element