Closed Bug 1637519 Opened 4 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/permissions/set.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1630162

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=302056589&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ftKk4UCCQgaT8JmWpgU-6w/runs/0/artifacts/public/logs/live_backing.log


[task 2020-05-13T05:57:54.134Z] 05:57:54 INFO - STDOUT: FAILED
[task 2020-05-13T05:57:54.143Z] 05:57:54 INFO - PID 2819 | 1589349474125 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/timeouts {"implicit": 0}
[task 2020-05-13T05:57:54.143Z] 05:57:54 INFO - PID 2819 | 1589349474126 Marionette DEBUG 0 -> [0,124,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-05-13T05:57:54.144Z] 05:57:54 INFO - PID 2819 | 1589349474126 Marionette DEBUG 0 <- [1,124,null,{"value":null}]
[task 2020-05-13T05:57:54.144Z] 05:57:54 INFO - PID 2819 | 1589349474126 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.145Z] 05:57:54 INFO - PID 2819 | 1589349474128 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/timeouts {"pageLoad": 300000}
[task 2020-05-13T05:57:54.145Z] 05:57:54 INFO - PID 2819 | 1589349474129 Marionette DEBUG 0 -> [0,125,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-05-13T05:57:54.145Z] 05:57:54 INFO - PID 2819 | 1589349474129 Marionette DEBUG 0 <- [1,125,null,{"value":null}]
[task 2020-05-13T05:57:54.145Z] 05:57:54 INFO - PID 2819 | 1589349474129 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.146Z] 05:57:54 INFO - PID 2819 | 1589349474130 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/timeouts {"script": 30000}
[task 2020-05-13T05:57:54.146Z] 05:57:54 INFO - PID 2819 | 1589349474131 Marionette DEBUG 0 -> [0,126,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-05-13T05:57:54.146Z] 05:57:54 INFO - PID 2819 | 1589349474131 Marionette DEBUG 0 <- [1,126,null,{"value":null}]
[task 2020-05-13T05:57:54.146Z] 05:57:54 INFO - PID 2819 | 1589349474131 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.147Z] 05:57:54 INFO - PID 2819 | 1589349474132 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window
[task 2020-05-13T05:57:54.147Z] 05:57:54 INFO - PID 2819 | 1589349474133 Marionette DEBUG 0 -> [0,127,"WebDriver:GetWindowHandle",{}]
[task 2020-05-13T05:57:54.147Z] 05:57:54 INFO - PID 2819 | 1589349474133 Marionette DEBUG 0 <- [1,127,null,{"value":"19"}]
[task 2020-05-13T05:57:54.147Z] 05:57:54 INFO - PID 2819 | 1589349474133 webdriver::server DEBUG <- 200 OK {"value":"19"}
[task 2020-05-13T05:57:54.150Z] 05:57:54 INFO - PID 2819 | 1589349474136 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window
[task 2020-05-13T05:57:54.150Z] 05:57:54 INFO - PID 2819 | 1589349474137 Marionette DEBUG 0 -> [0,128,"WebDriver:GetWindowHandle",{}]
[task 2020-05-13T05:57:54.151Z] 05:57:54 INFO - PID 2819 | 1589349474137 Marionette DEBUG 0 <- [1,128,null,{"value":"19"}]
[task 2020-05-13T05:57:54.151Z] 05:57:54 INFO - PID 2819 | 1589349474137 webdriver::server DEBUG <- 200 OK {"value":"19"}
[task 2020-05-13T05:57:54.151Z] 05:57:54 INFO - PID 2819 | 1589349474139 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/handles
[task 2020-05-13T05:57:54.151Z] 05:57:54 INFO - PID 2819 | 1589349474139 Marionette DEBUG 0 -> [0,129,"WebDriver:GetWindowHandles",{}]
[task 2020-05-13T05:57:54.156Z] 05:57:54 INFO - PID 2819 | 1589349474139 Marionette DEBUG 0 <- [1,129,null,["19"]]
[task 2020-05-13T05:57:54.156Z] 05:57:54 INFO - PID 2819 | 1589349474139 webdriver::server DEBUG <- 200 OK {"value":["19"]}
[task 2020-05-13T05:57:54.157Z] 05:57:54 INFO - PID 2819 | 1589349474140 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window {"handle": "19"}
[task 2020-05-13T05:57:54.157Z] 05:57:54 INFO - PID 2819 | 1589349474141 Marionette DEBUG 0 -> [0,130,"WebDriver:SwitchToWindow",{"handle":"19","name":"19"}]
[task 2020-05-13T05:57:54.157Z] 05:57:54 INFO - PID 2819 | 1589349474141 Marionette DEBUG 0 <- [1,130,null,{"value":null}]
[task 2020-05-13T05:57:54.157Z] 05:57:54 INFO - PID 2819 | 1589349474141 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.158Z] 05:57:54 INFO - PID 2819 | 1589349474143 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/alert/dismiss {}
[task 2020-05-13T05:57:54.158Z] 05:57:54 INFO - PID 2819 | 1589349474145 Marionette DEBUG 0 -> [0,131,"WebDriver:DismissAlert",{}]
[task 2020-05-13T05:57:54.158Z] 05:57:54 INFO - PID 2819 | 1589349474145 Marionette DEBUG 0 <- [1,131,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchA ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2020-05-13T05:57:54.159Z] 05:57:54 INFO - PID 2819 | 1589349474146 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAlertError@chrome://marionette/content/error.js:376:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3388:11\nGeckoDriver.prototype.dismissDialog@chrome://marionette/content/driver.js:3301:8\ndespatch@chrome://marionette/content/server.js:305:40\nexecute@chrome://marionette/content/server.js:275:16\nonPacket/<@chrome://marionette/content/server.js:248:20\nonPacket@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"}}
[task 2020-05-13T05:57:54.160Z] 05:57:54 INFO - PID 2819 | 1589349474148 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window {"handle": "19"}
[task 2020-05-13T05:57:54.160Z] 05:57:54 INFO - PID 2819 | 1589349474148 Marionette DEBUG 0 -> [0,132,"WebDriver:SwitchToWindow",{"handle":"19","name":"19"}]
[task 2020-05-13T05:57:54.164Z] 05:57:54 INFO - PID 2819 | 1589349474149 Marionette DEBUG 0 <- [1,132,null,{"value":null}]
[task 2020-05-13T05:57:54.164Z] 05:57:54 INFO - PID 2819 | 1589349474149 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.164Z] 05:57:54 INFO - PID 2819 | 1589349474151 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window
[task 2020-05-13T05:57:54.164Z] 05:57:54 INFO - PID 2819 | 1589349474152 Marionette DEBUG 0 -> [0,133,"WebDriver:GetWindowHandle",{}]
[task 2020-05-13T05:57:54.165Z] 05:57:54 INFO - PID 2819 | 1589349474152 Marionette DEBUG 0 <- [1,133,null,{"value":"19"}]
[task 2020-05-13T05:57:54.167Z] 05:57:54 INFO - PID 2819 | 1589349474152 webdriver::server DEBUG <- 200 OK {"value":"19"}
[task 2020-05-13T05:57:54.167Z] 05:57:54 INFO - PID 2819 | 1589349474154 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/handles
[task 2020-05-13T05:57:54.167Z] 05:57:54 INFO - PID 2819 | 1589349474154 Marionette DEBUG 0 -> [0,134,"WebDriver:GetWindowHandles",{}]
[task 2020-05-13T05:57:54.167Z] 05:57:54 INFO - PID 2819 | 1589349474154 Marionette DEBUG 0 <- [1,134,null,["19"]]
[task 2020-05-13T05:57:54.167Z] 05:57:54 INFO - PID 2819 | 1589349474155 webdriver::server DEBUG <- 200 OK {"value":["19"]}
[task 2020-05-13T05:57:54.169Z] 05:57:54 INFO - PID 2819 | 1589349474156 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window {"handle": "19"}
[task 2020-05-13T05:57:54.169Z] 05:57:54 INFO - PID 2819 | 1589349474156 Marionette DEBUG 0 -> [0,135,"WebDriver:SwitchToWindow",{"handle":"19","name":"19"}]
[task 2020-05-13T05:57:54.170Z] 05:57:54 INFO - PID 2819 | 1589349474156 Marionette DEBUG 0 <- [1,135,null,{"value":null}]
[task 2020-05-13T05:57:54.170Z] 05:57:54 INFO - PID 2819 | 1589349474157 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.171Z] 05:57:54 INFO - PID 2819 | 1589349474158 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/rect {"width": 800, "height": 600}
[task 2020-05-13T05:57:54.171Z] 05:57:54 INFO - PID 2819 | 1589349474159 Marionette DEBUG 0 -> [0,136,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-05-13T05:57:54.172Z] 05:57:54 INFO - PID 2819 | 1589349474159 Marionette DEBUG 0 <- [1,136,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-05-13T05:57:54.174Z] 05:57:54 INFO - PID 2819 | 1589349474160 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-05-13T05:57:54.174Z] 05:57:54 INFO - PID 2819 | 1589349474162 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/frame {"id": null}
[task 2020-05-13T05:57:54.174Z] 05:57:54 INFO - PID 2819 | 1589349474163 Marionette DEBUG 0 -> [0,137,"WebDriver:SwitchToFrame",{"id":null}]
[task 2020-05-13T05:57:54.175Z] 05:57:54 INFO - PID 2819 | 1589349474164 Marionette DEBUG 0 <- [1,137,null,{"value":null}]
[task 2020-05-13T05:57:54.175Z] 05:57:54 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/permissions/set.py::test_set_to_state[capabilities0-realmSetting2-prompt]
[task 2020-05-13T05:57:54.175Z] 05:57:54 INFO - PID 2819 | 1589349474165 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.198Z] 05:57:54 INFO - PID 2819 | 1589349474168 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/rect {"width": 800, "height": 600}
[task 2020-05-13T05:57:54.199Z] 05:57:54 INFO - PID 2819 | 1589349474169 Marionette DEBUG 0 -> [0,138,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-05-13T05:57:54.199Z] 05:57:54 INFO - PID 2819 | 1589349474169 Marionette DEBUG 0 <- [1,138,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-05-13T05:57:54.199Z] 05:57:54 INFO - PID 2819 | 1589349474170 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-05-13T05:57:54.200Z] 05:57:54 INFO - PID 2819 | 1589349474171 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/rect {"y": 100, "x": 100}
[task 2020-05-13T05:57:54.200Z] 05:57:54 INFO - PID 2819 | 1589349474171 Marionette DEBUG 0 -> [0,139,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2020-05-13T05:57:54.200Z] 05:57:54 INFO - PID 2819 | 1589349474172 Marionette DEBUG 0 <- [1,139,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-05-13T05:57:54.200Z] 05:57:54 INFO - PID 2819 | 1589349474172 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-05-13T05:57:54.201Z] 05:57:54 INFO - PID 2819 | 1589349474174 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/url {"url": "https://web-platform.test:8443/common/blank.html"}
[task 2020-05-13T05:57:54.201Z] 05:57:54 INFO - PID 2819 | 1589349474175 Marionette DEBUG 0 -> [0,140,"WebDriver:Navigate",{"url":"https://web-platform.test:8443/common/blank.html"}]
[task 2020-05-13T05:57:54.201Z] 05:57:54 INFO - PID 2819 | 1589349474177 Marionette TRACE [19] Received DOM event beforeunload for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.201Z] 05:57:54 INFO - PID 2819 | 1589349474190 Marionette TRACE [19] Received DOM event pagehide for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.204Z] 05:57:54 INFO - PID 2819 | 1589349474194 Marionette TRACE [19] Received DOM event DOMContentLoaded for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.204Z] 05:57:54 INFO - PID 2819 | 1589349474199 Marionette TRACE [19] Received DOM event pageshow for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.267Z] 05:57:54 INFO - PID 2819 | 1589349474204 Marionette DEBUG 0 <- [1,140,null,{"value":null}]
[task 2020-05-13T05:57:54.267Z] 05:57:54 INFO - PID 2819 | 1589349474207 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.267Z] 05:57:54 INFO - STDOUT: FAILED
[task 2020-05-13T05:57:54.268Z] 05:57:54 INFO - PID 2819 | 1589349474225 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/timeouts {"implicit": 0}
[task 2020-05-13T05:57:54.268Z] 05:57:54 INFO - PID 2819 | 1589349474226 Marionette DEBUG 0 -> [0,141,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-05-13T05:57:54.268Z] 05:57:54 INFO - PID 2819 | 1589349474226 Marionette DEBUG 0 <- [1,141,null,{"value":null}]
[task 2020-05-13T05:57:54.268Z] 05:57:54 INFO - PID 2819 | 1589349474226 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.269Z] 05:57:54 INFO - PID 2819 | 1589349474228 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/timeouts {"pageLoad": 300000}
[task 2020-05-13T05:57:54.269Z] 05:57:54 INFO - PID 2819 | 1589349474228 Marionette DEBUG 0 -> [0,142,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-05-13T05:57:54.269Z] 05:57:54 INFO - PID 2819 | 1589349474228 Marionette DEBUG 0 <- [1,142,null,{"value":null}]
[task 2020-05-13T05:57:54.270Z] 05:57:54 INFO - PID 2819 | 1589349474229 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.270Z] 05:57:54 INFO - PID 2819 | 1589349474230 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/timeouts {"script": 30000}
[task 2020-05-13T05:57:54.270Z] 05:57:54 INFO - PID 2819 | 1589349474230 Marionette DEBUG 0 -> [0,143,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-05-13T05:57:54.270Z] 05:57:54 INFO - PID 2819 | 1589349474230 Marionette DEBUG 0 <- [1,143,null,{"value":null}]
[task 2020-05-13T05:57:54.270Z] 05:57:54 INFO - PID 2819 | 1589349474231 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.271Z] 05:57:54 INFO - PID 2819 | 1589349474232 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window
[task 2020-05-13T05:57:54.271Z] 05:57:54 INFO - PID 2819 | 1589349474232 Marionette DEBUG 0 -> [0,144,"WebDriver:GetWindowHandle",{}]
[task 2020-05-13T05:57:54.271Z] 05:57:54 INFO - PID 2819 | 1589349474232 Marionette DEBUG 0 <- [1,144,null,{"value":"19"}]
[task 2020-05-13T05:57:54.271Z] 05:57:54 INFO - PID 2819 | 1589349474232 webdriver::server DEBUG <- 200 OK {"value":"19"}
[task 2020-05-13T05:57:54.272Z] 05:57:54 INFO - PID 2819 | 1589349474234 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window
[task 2020-05-13T05:57:54.272Z] 05:57:54 INFO - PID 2819 | 1589349474236 Marionette DEBUG 0 -> [0,145,"WebDriver:GetWindowHandle",{}]
[task 2020-05-13T05:57:54.272Z] 05:57:54 INFO - PID 2819 | 1589349474236 Marionette DEBUG 0 <- [1,145,null,{"value":"19"}]
[task 2020-05-13T05:57:54.272Z] 05:57:54 INFO - PID 2819 | 1589349474236 webdriver::server DEBUG <- 200 OK {"value":"19"}
[task 2020-05-13T05:57:54.273Z] 05:57:54 INFO - PID 2819 | 1589349474238 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/handles
[task 2020-05-13T05:57:54.273Z] 05:57:54 INFO - PID 2819 | 1589349474238 Marionette DEBUG 0 -> [0,146,"WebDriver:GetWindowHandles",{}]
[task 2020-05-13T05:57:54.273Z] 05:57:54 INFO - PID 2819 | 1589349474238 Marionette DEBUG 0 <- [1,146,null,["19"]]
[task 2020-05-13T05:57:54.273Z] 05:57:54 INFO - PID 2819 | 1589349474239 webdriver::server DEBUG <- 200 OK {"value":["19"]}
[task 2020-05-13T05:57:54.273Z] 05:57:54 INFO - PID 2819 | 1589349474240 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window {"handle": "19"}
[task 2020-05-13T05:57:54.274Z] 05:57:54 INFO - PID 2819 | 1589349474240 Marionette DEBUG 0 -> [0,147,"WebDriver:SwitchToWindow",{"handle":"19","name":"19"}]
[task 2020-05-13T05:57:54.274Z] 05:57:54 INFO - PID 2819 | 1589349474240 Marionette DEBUG 0 <- [1,147,null,{"value":null}]
[task 2020-05-13T05:57:54.274Z] 05:57:54 INFO - PID 2819 | 1589349474241 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.274Z] 05:57:54 INFO - PID 2819 | 1589349474242 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/alert/dismiss {}
[task 2020-05-13T05:57:54.274Z] 05:57:54 INFO - PID 2819 | 1589349474243 Marionette DEBUG 0 -> [0,148,"WebDriver:DismissAlert",{}]
[task 2020-05-13T05:57:54.275Z] 05:57:54 INFO - PID 2819 | 1589349474244 Marionette DEBUG 0 <- [1,148,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchA ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2020-05-13T05:57:54.275Z] 05:57:54 INFO - PID 2819 | 1589349474245 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:175:5\nNoSuchAlertError@chrome://marionette/content/error.js:376:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3388:11\nGeckoDriver.prototype.dismissDialog@chrome://marionette/content/driver.js:3301:8\ndespatch@chrome://marionette/content/server.js:305:40\nexecute@chrome://marionette/content/server.js:275:16\nonPacket/<@chrome://marionette/content/server.js:248:20\nonPacket@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"}}
[task 2020-05-13T05:57:54.275Z] 05:57:54 INFO - PID 2819 | 1589349474247 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window {"handle": "19"}
[task 2020-05-13T05:57:54.275Z] 05:57:54 INFO - PID 2819 | 1589349474247 Marionette DEBUG 0 -> [0,149,"WebDriver:SwitchToWindow",{"handle":"19","name":"19"}]
[task 2020-05-13T05:57:54.276Z] 05:57:54 INFO - PID 2819 | 1589349474248 Marionette DEBUG 0 <- [1,149,null,{"value":null}]
[task 2020-05-13T05:57:54.276Z] 05:57:54 INFO - PID 2819 | 1589349474248 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.276Z] 05:57:54 INFO - PID 2819 | 1589349474249 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window
[task 2020-05-13T05:57:54.277Z] 05:57:54 INFO - PID 2819 | 1589349474251 Marionette DEBUG 0 -> [0,150,"WebDriver:GetWindowHandle",{}]
[task 2020-05-13T05:57:54.277Z] 05:57:54 INFO - PID 2819 | 1589349474251 Marionette DEBUG 0 <- [1,150,null,{"value":"19"}]
[task 2020-05-13T05:57:54.278Z] 05:57:54 INFO - PID 2819 | 1589349474251 webdriver::server DEBUG <- 200 OK {"value":"19"}
[task 2020-05-13T05:57:54.278Z] 05:57:54 INFO - PID 2819 | 1589349474253 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/handles
[task 2020-05-13T05:57:54.278Z] 05:57:54 INFO - PID 2819 | 1589349474253 Marionette DEBUG 0 -> [0,151,"WebDriver:GetWindowHandles",{}]
[task 2020-05-13T05:57:54.279Z] 05:57:54 INFO - PID 2819 | 1589349474253 Marionette DEBUG 0 <- [1,151,null,["19"]]
[task 2020-05-13T05:57:54.279Z] 05:57:54 INFO - PID 2819 | 1589349474254 webdriver::server DEBUG <- 200 OK {"value":["19"]}
[task 2020-05-13T05:57:54.279Z] 05:57:54 INFO - PID 2819 | 1589349474255 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window {"handle": "19"}
[task 2020-05-13T05:57:54.279Z] 05:57:54 INFO - PID 2819 | 1589349474255 Marionette DEBUG 0 -> [0,152,"WebDriver:SwitchToWindow",{"handle":"19","name":"19"}]
[task 2020-05-13T05:57:54.280Z] 05:57:54 INFO - PID 2819 | 1589349474256 Marionette DEBUG 0 <- [1,152,null,{"value":null}]
[task 2020-05-13T05:57:54.280Z] 05:57:54 INFO - PID 2819 | 1589349474256 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.280Z] 05:57:54 INFO - PID 2819 | 1589349474258 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/rect {"width": 800, "height": 600}
[task 2020-05-13T05:57:54.280Z] 05:57:54 INFO - PID 2819 | 1589349474258 Marionette DEBUG 0 -> [0,153,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-05-13T05:57:54.281Z] 05:57:54 INFO - PID 2819 | 1589349474258 Marionette DEBUG 0 <- [1,153,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-05-13T05:57:54.281Z] 05:57:54 INFO - PID 2819 | 1589349474259 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-05-13T05:57:54.281Z] 05:57:54 INFO - PID 2819 | 1589349474260 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/frame {"id": null}
[task 2020-05-13T05:57:54.281Z] 05:57:54 INFO - PID 2819 | 1589349474260 Marionette DEBUG 0 -> [0,154,"WebDriver:SwitchToFrame",{"id":null}]
[task 2020-05-13T05:57:54.281Z] 05:57:54 INFO - PID 2819 | 1589349474261 Marionette DEBUG 0 <- [1,154,null,{"value":null}]
[task 2020-05-13T05:57:54.282Z] 05:57:54 INFO - PID 2819 | 1589349474262 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.282Z] 05:57:54 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/permissions/set.py::test_set_to_state_cross_realm[capabilities0-realmSetting0-granted]
[task 2020-05-13T05:57:54.282Z] 05:57:54 INFO - PID 2819 | 1589349474265 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/rect {"width": 800, "height": 600}
[task 2020-05-13T05:57:54.282Z] 05:57:54 INFO - PID 2819 | 1589349474266 Marionette DEBUG 0 -> [0,155,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-05-13T05:57:54.282Z] 05:57:54 INFO - PID 2819 | 1589349474266 Marionette DEBUG 0 <- [1,155,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-05-13T05:57:54.283Z] 05:57:54 INFO - PID 2819 | 1589349474266 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-05-13T05:57:54.311Z] 05:57:54 INFO - PID 2819 | 1589349474270 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/rect {"y": 100, "x": 100}
[task 2020-05-13T05:57:54.311Z] 05:57:54 INFO - PID 2819 | 1589349474271 Marionette DEBUG 0 -> [0,156,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2020-05-13T05:57:54.311Z] 05:57:54 INFO - PID 2819 | 1589349474271 Marionette DEBUG 0 <- [1,156,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-05-13T05:57:54.312Z] 05:57:54 INFO - PID 2819 | 1589349474272 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-05-13T05:57:54.312Z] 05:57:54 INFO - PID 2819 | 1589349474275 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/url {"url": "https://web-platform.test:8443/common/blank.html"}
[task 2020-05-13T05:57:54.312Z] 05:57:54 INFO - PID 2819 | 1589349474275 Marionette DEBUG 0 -> [0,157,"WebDriver:Navigate",{"url":"https://web-platform.test:8443/common/blank.html"}]
[task 2020-05-13T05:57:54.312Z] 05:57:54 INFO - PID 2819 | 1589349474279 Marionette TRACE [19] Received DOM event beforeunload for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.313Z] 05:57:54 INFO - PID 2819 | 1589349474289 Marionette TRACE [19] Received DOM event pagehide for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.313Z] 05:57:54 INFO - PID 2819 | 1589349474294 Marionette TRACE [19] Received DOM event DOMContentLoaded for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.313Z] 05:57:54 INFO - PID 2819 | 1589349474300 Marionette TRACE [19] Received DOM event pageshow for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.336Z] 05:57:54 INFO - PID 2819 | 1589349474303 Marionette DEBUG 0 <- [1,157,null,{"value":null}]
[task 2020-05-13T05:57:54.336Z] 05:57:54 INFO - PID 2819 | 1589349474307 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.336Z] 05:57:54 INFO - PID 2819 | 1589349474308 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window
[task 2020-05-13T05:57:54.337Z] 05:57:54 INFO - PID 2819 | 1589349474309 Marionette DEBUG 0 -> [0,158,"WebDriver:GetWindowHandle",{}]
[task 2020-05-13T05:57:54.337Z] 05:57:54 INFO - PID 2819 | 1589349474309 Marionette DEBUG 0 <- [1,158,null,{"value":"19"}]
[task 2020-05-13T05:57:54.337Z] 05:57:54 INFO - PID 2819 | 1589349474309 webdriver::server DEBUG <- 200 OK {"value":"19"}
[task 2020-05-13T05:57:54.337Z] 05:57:54 INFO - PID 2819 | 1589349474311 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/handles
[task 2020-05-13T05:57:54.337Z] 05:57:54 INFO - PID 2819 | 1589349474312 Marionette DEBUG 0 -> [0,159,"WebDriver:GetWindowHandles",{}]
[task 2020-05-13T05:57:54.337Z] 05:57:54 INFO - PID 2819 | 1589349474312 Marionette DEBUG 0 <- [1,159,null,["19"]]
[task 2020-05-13T05:57:54.338Z] 05:57:54 INFO - PID 2819 | 1589349474312 webdriver::server DEBUG <- 200 OK {"value":["19"]}
[task 2020-05-13T05:57:54.338Z] 05:57:54 INFO - PID 2819 | 1589349474313 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/execute/sync {"args": [], "script": "window.open()"}
[task 2020-05-13T05:57:54.338Z] 05:57:54 INFO - PID 2819 | 1589349474314 Marionette DEBUG 0 -> [0,160,"WebDriver:ExecuteScript",{"args":[],"script":"window.open()"}]
[task 2020-05-13T05:57:54.359Z] 05:57:54 INFO - PID 2819 | 1589349474339 Marionette TRACE [4294967309] Frame script loaded
[task 2020-05-13T05:57:54.569Z] 05:57:54 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/permissions/set.py | expected OK
[task 2020-05-13T05:57:54.569Z] 05:57:54 INFO - TEST-INFO took 30338ms
[task 2020-05-13T05:57:54.807Z] 05:57:54 INFO - PID 2819 | 1589349474348 Marionette TRACE [4294967309] Frame script registered
[task 2020-05-13T05:57:54.807Z] 05:57:54 INFO - PID 2819 | 1589349474361 Marionette DEBUG 0 <- [1,160,null,{"value":null}]
[task 2020-05-13T05:57:54.807Z] 05:57:54 INFO - PID 2819 | 1589349474363 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.808Z] 05:57:54 INFO - PID 2819 | 1589349474365 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/handles
[task 2020-05-13T05:57:54.808Z] 05:57:54 INFO - PID 2819 | 1589349474366 Marionette DEBUG 0 -> [0,161,"WebDriver:GetWindowHandles",{}]
[task 2020-05-13T05:57:54.808Z] 05:57:54 INFO - PID 2819 | 1589349474366 Marionette DEBUG 0 <- [1,161,null,["19","4294967309"]]
[task 2020-05-13T05:57:54.808Z] 05:57:54 INFO - PID 2819 | 1589349474366 webdriver::server DEBUG <- 200 OK {"value":["19","4294967309"]}
[task 2020-05-13T05:57:54.808Z] 05:57:54 INFO - PID 2819 | 1589349474368 webdriver::server DEBUG -> GET /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window/handles
[task 2020-05-13T05:57:54.809Z] 05:57:54 INFO - PID 2819 | 1589349474372 Marionette DEBUG 0 -> [0,162,"WebDriver:GetWindowHandles",{}]
[task 2020-05-13T05:57:54.809Z] 05:57:54 INFO - PID 2819 | 1589349474372 Marionette DEBUG 0 <- [1,162,null,["19","4294967309"]]
[task 2020-05-13T05:57:54.809Z] 05:57:54 INFO - PID 2819 | 1589349474373 webdriver::server DEBUG <- 200 OK {"value":["19","4294967309"]}
[task 2020-05-13T05:57:54.809Z] 05:57:54 INFO - PID 2819 | 1589349474374 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/window {"handle": "4294967309"}
[task 2020-05-13T05:57:54.810Z] 05:57:54 INFO - PID 2819 | 1589349474375 Marionette DEBUG 0 -> [0,163,"WebDriver:SwitchToWindow",{"handle":"4294967309","name":"4294967309"}]
[task 2020-05-13T05:57:54.810Z] 05:57:54 INFO - PID 2819 | 1589349474375 Marionette DEBUG 0 <- [1,163,null,{"value":null}]
[task 2020-05-13T05:57:54.810Z] 05:57:54 INFO - PID 2819 | 1589349474376 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2020-05-13T05:57:54.810Z] 05:57:54 INFO - PID 2819 | 1589349474378 webdriver::server DEBUG -> POST /session/06545473-1d94-0e4a-aec0-07c226f7df4c/url {"url": "https://web-platform.test:8443/common/blank.html"}
[task 2020-05-13T05:57:54.811Z] 05:57:54 INFO - PID 2819 | 1589349474378 Marionette DEBUG 0 -> [0,164,"WebDriver:Navigate",{"url":"https://web-platform.test:8443/common/blank.html"}]
[task 2020-05-13T05:57:54.811Z] 05:57:54 INFO - PID 2819 | 1589349474381 Marionette TRACE [4294967309] Received DOM event beforeunload for about:blank
[task 2020-05-13T05:57:54.811Z] 05:57:54 INFO - PID 2819 | 1589349474395 Marionette TRACE [4294967309] Received DOM event pagehide for about:blank
[task 2020-05-13T05:57:54.811Z] 05:57:54 INFO - PID 2819 | 1589349474395 Marionette TRACE [4294967309] Received DOM event unload for about:blank
[task 2020-05-13T05:57:54.811Z] 05:57:54 INFO - PID 2819 | 1589349474398 Marionette TRACE [4294967309] Received DOM event DOMContentLoaded for https://web-platform.test:8443/common/blank.html
[task 2020-05-13T05:57:54.811Z] 05:57:54 INFO - STDOUT: FAILED

Long delays after web-workers-shutdown:

[task 2020-05-13T05:57:33.842Z] 05:57:33     INFO - PID 2819 | DEBUG: Finished phase web-workers-shutdown
[task 2020-05-13T05:57:39.901Z] 05:57:39     INFO - PID 2819 | 1589349459889	geckodriver::marionette	DEBUG	Browser process stopped: exit code: 0
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → DUPLICATE
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in before you can comment on or make changes to this bug.