Closed Bug 1739658 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)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=357147071&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/er-u4eK6S_69CzmmtVQKTQ/runs/0/artifacts/public/logs/live_backing.log


[task 2021-11-05T16:26:28.467Z] 16:26:28     INFO - PID 7564 | 1636129588461	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/timeouts {"implicit": 0}
[task 2021-11-05T16:26:28.471Z] 16:26:28     INFO - PID 7564 | 1636129588463	Marionette	DEBUG	0 -> [0,240,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2021-11-05T16:26:28.475Z] 16:26:28     INFO - PID 7564 | 1636129588464	Marionette	DEBUG	0 <- [1,240,null,{"value":null}]
[task 2021-11-05T16:26:28.480Z] 16:26:28     INFO - PID 7564 | 1636129588464	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.484Z] 16:26:28     INFO - PID 7564 | 1636129588469	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/timeouts {"pageLoad": 300000}
[task 2021-11-05T16:26:28.490Z] 16:26:28     INFO - PID 7564 | 1636129588471	Marionette	DEBUG	0 -> [0,241,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2021-11-05T16:26:28.495Z] 16:26:28     INFO - PID 7564 | 1636129588472	Marionette	DEBUG	0 <- [1,241,null,{"value":null}]
[task 2021-11-05T16:26:28.499Z] 16:26:28     INFO - PID 7564 | 1636129588473	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.503Z] 16:26:28     INFO - PID 7564 | 1636129588478	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/timeouts {"script": 30000}
[task 2021-11-05T16:26:28.508Z] 16:26:28     INFO - PID 7564 | 1636129588480	Marionette	DEBUG	0 -> [0,242,"WebDriver:SetTimeouts",{"script":30000}]
[task 2021-11-05T16:26:28.512Z] 16:26:28     INFO - PID 7564 | 1636129588481	Marionette	DEBUG	0 <- [1,242,null,{"value":null}]
[task 2021-11-05T16:26:28.517Z] 16:26:28     INFO - PID 7564 | 1636129588482	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.522Z] 16:26:28     INFO - PID 7564 | 1636129588487	webdriver::server	DEBUG	-> GET /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window
[task 2021-11-05T16:26:28.528Z] 16:26:28     INFO - PID 7564 | 1636129588488	Marionette	DEBUG	0 -> [0,243,"WebDriver:GetWindowHandle",{}]
[task 2021-11-05T16:26:28.533Z] 16:26:28     INFO - PID 7564 | 1636129588489	Marionette	DEBUG	0 <- [1,243,null,{"value":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}]
[task 2021-11-05T16:26:28.537Z] 16:26:28     INFO - PID 7564 | 1636129588490	webdriver::server	DEBUG	<- 200 OK {"value":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}
[task 2021-11-05T16:26:28.542Z] 16:26:28     INFO - PID 7564 | 1636129588493	webdriver::server	DEBUG	-> GET /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window
[task 2021-11-05T16:26:28.546Z] 16:26:28     INFO - PID 7564 | 1636129588495	Marionette	DEBUG	0 -> [0,244,"WebDriver:GetWindowHandle",{}]
[task 2021-11-05T16:26:28.550Z] 16:26:28     INFO - PID 7564 | 1636129588495	Marionette	DEBUG	0 <- [1,244,null,{"value":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}]
[task 2021-11-05T16:26:28.554Z] 16:26:28     INFO - PID 7564 | 1636129588497	webdriver::server	DEBUG	<- 200 OK {"value":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}
[task 2021-11-05T16:26:28.559Z] 16:26:28     INFO - PID 7564 | 1636129588500	webdriver::server	DEBUG	-> GET /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window/handles
[task 2021-11-05T16:26:28.564Z] 16:26:28     INFO - PID 7564 | 1636129588501	Marionette	DEBUG	0 -> [0,245,"WebDriver:GetWindowHandles",{}]
[task 2021-11-05T16:26:28.569Z] 16:26:28     INFO - PID 7564 | 1636129588502	Marionette	DEBUG	0 <- [1,245,null,["7559bce6-ddfb-4f99-9173-49637785ed3b","52e3785c-0f78-4b7c-9cf3-dcda703db3c9"]]
[task 2021-11-05T16:26:28.574Z] 16:26:28     INFO - PID 7564 | 1636129588502	webdriver::server	DEBUG	<- 200 OK {"value":["7559bce6-ddfb-4f99-9173-49637785ed3b","52e3785c-0f78-4b7c-9cf3-dcda703db3c9"]}
[task 2021-11-05T16:26:28.580Z] 16:26:28     INFO - PID 7564 | 1636129588506	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window {"handle": "52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}
[task 2021-11-05T16:26:28.583Z] 16:26:28     INFO - PID 7564 | 1636129588508	Marionette	DEBUG	0 -> [0,246,"WebDriver:SwitchToWindow",{"handle":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}]
[task 2021-11-05T16:26:28.589Z] 16:26:28     INFO - PID 7564 | 1636129588509	Marionette	DEBUG	0 <- [1,246,null,{"value":null}]
[task 2021-11-05T16:26:28.593Z] 16:26:28     INFO - PID 7564 | 1636129588510	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.598Z] 16:26:28     INFO - PID 7564 | 1636129588514	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/alert/dismiss {}
[task 2021-11-05T16:26:28.602Z] 16:26:28     INFO - PID 7564 | 1636129588516	Marionette	DEBUG	0 -> [0,247,"WebDriver:DismissAlert",{}]
[task 2021-11-05T16:26:28.609Z] 16:26:28     INFO - PID 7564 | 1636129588517	Marionette	DEBUG	0 <- [1,247,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm ... ote/content/marionette/server.js:253:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20\n"},null]
[task 2021-11-05T16:26:28.620Z] 16:26:28     INFO - PID 7564 | 1636129588518	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:181:5\nNoSuchAlertError@chrome://remote/content/shared/webdriver/Errors.jsm:382:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://remote/content/marionette/driver.js:2506:11\nGeckoDriver.prototype.dismissDialog@chrome://remote/content/marionette/driver.js:2407:8\ndespatch@chrome://remote/content/marionette/server.js:306:40\nexecute@chrome://remote/content/marionette/server.js:279:16\nonPacket/<@chrome://remote/content/marionette/server.js:252:20\nonPacket@chrome://remote/content/marionette/server.js:253:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20\n"}}
[task 2021-11-05T16:26:28.625Z] 16:26:28     INFO - PID 7564 | 1636129588524	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window {"handle": "7559bce6-ddfb-4f99-9173-49637785ed3b"}
[task 2021-11-05T16:26:28.630Z] 16:26:28     INFO - PID 7564 | 1636129588526	Marionette	DEBUG	0 -> [0,248,"WebDriver:SwitchToWindow",{"handle":"7559bce6-ddfb-4f99-9173-49637785ed3b"}]
[task 2021-11-05T16:26:28.635Z] 16:26:28     INFO - PID 7564 | 1636129588540	Marionette	TRACE	Received DOM event TabSelect for [object XULElement]
[task 2021-11-05T16:26:28.639Z] 16:26:28     INFO - PID 7564 | 1636129588552	Marionette	DEBUG	0 <- [1,248,null,{"value":null}]
[task 2021-11-05T16:26:28.644Z] 16:26:28     INFO - PID 7564 | 1636129588554	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.650Z] 16:26:28     INFO - PID 7564 | 1636129588560	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/alert/dismiss {}
[task 2021-11-05T16:26:28.655Z] 16:26:28     INFO - PID 7564 | 1636129588564	Marionette	DEBUG	0 -> [0,249,"WebDriver:DismissAlert",{}]
[task 2021-11-05T16:26:28.660Z] 16:26:28     INFO - PID 7564 | 1636129588565	Marionette	DEBUG	0 <- [1,249,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm ... ote/content/marionette/server.js:253:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20\n"},null]
[task 2021-11-05T16:26:28.669Z] 16:26:28     INFO - PID 7564 | 1636129588566	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:181:5\nNoSuchAlertError@chrome://remote/content/shared/webdriver/Errors.jsm:382:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://remote/content/marionette/driver.js:2506:11\nGeckoDriver.prototype.dismissDialog@chrome://remote/content/marionette/driver.js:2407:8\ndespatch@chrome://remote/content/marionette/server.js:306:40\nexecute@chrome://remote/content/marionette/server.js:279:16\nonPacket/<@chrome://remote/content/marionette/server.js:252:20\nonPacket@chrome://remote/content/marionette/server.js:253:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.js:500:20\n"}}
[task 2021-11-05T16:26:28.675Z] 16:26:28     INFO - PID 7564 | 1636129588570	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window {"handle": "52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}
[task 2021-11-05T16:26:28.680Z] 16:26:28     INFO - PID 7564 | 1636129588571	Marionette	DEBUG	0 -> [0,250,"WebDriver:SwitchToWindow",{"handle":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}]
[task 2021-11-05T16:26:28.686Z] 16:26:28     INFO - PID 7564 | 1636129588590	Marionette	TRACE	Received DOM event TabSelect for [object XULElement]
[task 2021-11-05T16:26:28.690Z] 16:26:28     INFO - PID 7564 | 1636129588602	Marionette	DEBUG	0 <- [1,250,null,{"value":null}]
[task 2021-11-05T16:26:28.694Z] 16:26:28     INFO - PID 7564 | 1636129588605	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.698Z] 16:26:28     INFO - PID 7564 | 1636129588610	webdriver::server	DEBUG	-> GET /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window
[task 2021-11-05T16:26:28.702Z] 16:26:28     INFO - PID 7564 | 1636129588611	Marionette	DEBUG	0 -> [0,251,"WebDriver:GetWindowHandle",{}]
[task 2021-11-05T16:26:28.707Z] 16:26:28     INFO - PID 7564 | 1636129588612	Marionette	DEBUG	0 <- [1,251,null,{"value":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}]
[task 2021-11-05T16:26:28.711Z] 16:26:28     INFO - PID 7564 | 1636129588613	webdriver::server	DEBUG	<- 200 OK {"value":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}
[task 2021-11-05T16:26:28.715Z] 16:26:28     INFO - PID 7564 | 1636129588619	webdriver::server	DEBUG	-> GET /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window/handles
[task 2021-11-05T16:26:28.721Z] 16:26:28     INFO - PID 7564 | 1636129588621	Marionette	DEBUG	0 -> [0,252,"WebDriver:GetWindowHandles",{}]
[task 2021-11-05T16:26:28.727Z] 16:26:28     INFO - PID 7564 | 1636129588623	Marionette	DEBUG	0 <- [1,252,null,["7559bce6-ddfb-4f99-9173-49637785ed3b","52e3785c-0f78-4b7c-9cf3-dcda703db3c9"]]
[task 2021-11-05T16:26:28.733Z] 16:26:28     INFO - PID 7564 | 1636129588624	webdriver::server	DEBUG	<- 200 OK {"value":["7559bce6-ddfb-4f99-9173-49637785ed3b","52e3785c-0f78-4b7c-9cf3-dcda703db3c9"]}
[task 2021-11-05T16:26:28.738Z] 16:26:28     INFO - PID 7564 | 1636129588629	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window {"handle": "7559bce6-ddfb-4f99-9173-49637785ed3b"}
[task 2021-11-05T16:26:28.743Z] 16:26:28     INFO - PID 7564 | 1636129588631	Marionette	DEBUG	0 -> [0,253,"WebDriver:SwitchToWindow",{"handle":"7559bce6-ddfb-4f99-9173-49637785ed3b"}]
[task 2021-11-05T16:26:28.748Z] 16:26:28     INFO - PID 7564 | 1636129588646	Marionette	TRACE	Received DOM event TabSelect for [object XULElement]
[task 2021-11-05T16:26:28.752Z] 16:26:28     INFO - PID 7564 | 1636129588659	Marionette	DEBUG	0 <- [1,253,null,{"value":null}]
[task 2021-11-05T16:26:28.756Z] 16:26:28     INFO - PID 7564 | 1636129588660	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.759Z] 16:26:28     INFO - PID 7564 | 1636129588665	webdriver::server	DEBUG	-> GET /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window/handles
[task 2021-11-05T16:26:28.763Z] 16:26:28     INFO - PID 7564 | 1636129588666	Marionette	DEBUG	0 -> [0,254,"WebDriver:GetWindowHandles",{}]
[task 2021-11-05T16:26:28.766Z] 16:26:28     INFO - PID 7564 | 1636129588667	Marionette	DEBUG	0 <- [1,254,null,["7559bce6-ddfb-4f99-9173-49637785ed3b","52e3785c-0f78-4b7c-9cf3-dcda703db3c9"]]
[task 2021-11-05T16:26:28.770Z] 16:26:28     INFO - PID 7564 | 1636129588668	webdriver::server	DEBUG	<- 200 OK {"value":["7559bce6-ddfb-4f99-9173-49637785ed3b","52e3785c-0f78-4b7c-9cf3-dcda703db3c9"]}
[task 2021-11-05T16:26:28.773Z] 16:26:28     INFO - PID 7564 | 1636129588673	webdriver::server	DEBUG	-> DELETE /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window
[task 2021-11-05T16:26:28.777Z] 16:26:28     INFO - PID 7564 | 1636129588675	Marionette	DEBUG	0 -> [0,255,"WebDriver:CloseWindow",{}]
[task 2021-11-05T16:26:28.780Z] 16:26:28     INFO - PID 7564 | 1636129588696	Marionette	TRACE	Received DOM event TabClose for [object XULElement]
[task 2021-11-05T16:26:28.784Z] 16:26:28     INFO - PID 7564 | 1636129588716	Marionette	TRACE	Received observer notification message-manager-disconnect
[task 2021-11-05T16:26:28.787Z] 16:26:28     INFO - PID 7564 | 1636129588717	Marionette	TRACE	Received observer notification message-manager-disconnect
[task 2021-11-05T16:26:28.791Z] 16:26:28     INFO - PID 7564 | 1636129588718	Marionette	DEBUG	0 <- [1,255,null,["52e3785c-0f78-4b7c-9cf3-dcda703db3c9"]]
[task 2021-11-05T16:26:28.794Z] 16:26:28     INFO - PID 7564 | 1636129588724	webdriver::server	DEBUG	<- 200 OK {"value":["52e3785c-0f78-4b7c-9cf3-dcda703db3c9"]}
[task 2021-11-05T16:26:28.798Z] 16:26:28     INFO - PID 7564 | 1636129588729	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window {"handle": "52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}
[task 2021-11-05T16:26:28.802Z] 16:26:28     INFO - PID 7564 | 1636129588732	Marionette	DEBUG	0 -> [0,256,"WebDriver:SwitchToWindow",{"handle":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}]
[task 2021-11-05T16:26:28.805Z] 16:26:28     INFO - PID 7564 | 1636129588734	Marionette	DEBUG	0 <- [1,256,null,{"value":null}]
[task 2021-11-05T16:26:28.809Z] 16:26:28     INFO - PID 7564 | 1636129588735	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.812Z] 16:26:28     INFO - PID 7564 | 1636129588740	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window/rect {"width": 800, "height": 600}
[task 2021-11-05T16:26:28.816Z] 16:26:28     INFO - PID 7564 | 1636129588741	Marionette	DEBUG	0 -> [0,257,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2021-11-05T16:26:28.821Z] 16:26:28     INFO - PID 7564 | 1636129588742	Marionette	DEBUG	0 <- [1,257,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2021-11-05T16:26:28.827Z] 16:26:28     INFO - PID 7564 | 1636129588743	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2021-11-05T16:26:28.832Z] 16:26:28     INFO - PID 7564 | 1636129588748	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/frame {"id": null}
[task 2021-11-05T16:26:28.837Z] 16:26:28     INFO - PID 7564 | 1636129588749	Marionette	DEBUG	0 -> [0,258,"WebDriver:SwitchToFrame",{"id":null}]
[task 2021-11-05T16:26:28.842Z] 16:26:28     INFO - PID 7564 | 1636129588758	Marionette	TRACE	[81] MarionetteCommands actor created for window id 17179869185
[task 2021-11-05T16:26:28.847Z] 16:26:28     INFO - PID 7564 | 1636129588774	Marionette	DEBUG	0 <- [1,258,null,{"value":null}]
[task 2021-11-05T16:26:28.851Z] 16:26:28     INFO - PID 7564 | 1636129588775	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.859Z] 16:26:28     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/permissions/set.py::test_set_to_state_cross_realm[capabilities0-realmSetting1-denied] 
[task 2021-11-05T16:26:28.862Z] 16:26:28     INFO - PID 7564 | 1636129588784	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window/rect {"width": 800, "height": 600}
[task 2021-11-05T16:26:28.866Z] 16:26:28     INFO - PID 7564 | 1636129588785	Marionette	DEBUG	0 -> [0,259,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2021-11-05T16:26:28.870Z] 16:26:28     INFO - PID 7564 | 1636129588786	Marionette	DEBUG	0 <- [1,259,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2021-11-05T16:26:28.875Z] 16:26:28     INFO - PID 7564 | 1636129588787	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2021-11-05T16:26:28.879Z] 16:26:28     INFO - PID 7564 | 1636129588791	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window/rect {"x": 100, "y": 100}
[task 2021-11-05T16:26:28.883Z] 16:26:28     INFO - PID 7564 | 1636129588792	Marionette	DEBUG	0 -> [0,260,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2021-11-05T16:26:28.886Z] 16:26:28     INFO - PID 7564 | 1636129588793	Marionette	DEBUG	0 <- [1,260,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2021-11-05T16:26:28.891Z] 16:26:28     INFO - PID 7564 | 1636129588794	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2021-11-05T16:26:28.896Z] 16:26:28     INFO - PID 7564 | 1636129588803	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/url {"url": "https://web-platform.test:8443/common/blank.html"}
[task 2021-11-05T16:26:28.900Z] 16:26:28     INFO - PID 7564 | 1636129588804	Marionette	DEBUG	0 -> [0,261,"WebDriver:Navigate",{"url":"https://web-platform.test:8443/common/blank.html"}]
[task 2021-11-05T16:26:28.905Z] 16:26:28     INFO - PID 7564 | 1636129588809	Marionette	TRACE	[81] Received event beforeunload for https://web-platform.test:8443/common/blank.html
[task 2021-11-05T16:26:28.910Z] 16:26:28     INFO - PID 7564 | 1636129588842	Marionette	TRACE	Remoteness change detected. Set new top-level browsing context to 83
[task 2021-11-05T16:26:28.914Z] 16:26:28     INFO - PID 7564 | 1636129588857	Marionette	TRACE	[83] Received event beforeunload for about:blank
[task 2021-11-05T16:26:28.919Z] 16:26:28     INFO - PID 7564 | 1636129588864	Marionette	TRACE	[83] Received event pagehide for about:blank
[task 2021-11-05T16:26:28.927Z] 16:26:28     INFO - PID 7564 | 1636129588889	Marionette	TRACE	[83] Received event DOMContentLoaded for https://web-platform.test:8443/common/blank.html
[task 2021-11-05T16:26:28.933Z] 16:26:28     INFO - PID 7564 | 1636129588891	Marionette	TRACE	[83] Received event pageshow for https://web-platform.test:8443/common/blank.html
[task 2021-11-05T16:26:28.939Z] 16:26:28     INFO - PID 7564 | 1636129588891	Marionette	DEBUG	0 <- [1,261,null,{"value":null}]
[task 2021-11-05T16:26:28.944Z] 16:26:28     INFO - PID 7564 | 1636129588899	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:28.949Z] 16:26:28     INFO - PID 7564 | 1636129588903	webdriver::server	DEBUG	-> GET /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window
[task 2021-11-05T16:26:28.953Z] 16:26:28     INFO - PID 7564 | 1636129588904	Marionette	DEBUG	0 -> [0,262,"WebDriver:GetWindowHandle",{}]
[task 2021-11-05T16:26:28.957Z] 16:26:28     INFO - PID 7564 | 1636129588905	Marionette	DEBUG	0 <- [1,262,null,{"value":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}]
[task 2021-11-05T16:26:28.961Z] 16:26:28     INFO - PID 7564 | 1636129588913	webdriver::server	DEBUG	<- 200 OK {"value":"52e3785c-0f78-4b7c-9cf3-dcda703db3c9"}
[task 2021-11-05T16:26:28.965Z] 16:26:28     INFO - PID 7564 | 1636129588917	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window/new {"type": "tab"}
[task 2021-11-05T16:26:28.968Z] 16:26:28     INFO - PID 7564 | 1636129588919	Marionette	DEBUG	0 -> [0,263,"WebDriver:NewWindow",{"type":"tab"}]
[task 2021-11-05T16:26:28.972Z] 16:26:28     INFO - PID 7564 | 1636129588939	Marionette	TRACE	Received DOM event TabOpen for [object XULElement]
[task 2021-11-05T16:26:29.014Z] 16:26:29     INFO - PID 7564 | 1636129589013	Marionette	TRACE	[85] Received event beforeunload for about:blank
[task 2021-11-05T16:26:29.033Z] 16:26:29     INFO - PID 7564 | 1636129589028	Marionette	TRACE	[85] Received event pagehide for about:blank
[task 2021-11-05T16:26:29.047Z] 16:26:29     INFO - PID 7564 | 1636129589041	Marionette	TRACE	[85] Received event DOMContentLoaded for about:blank
[task 2021-11-05T16:26:29.051Z] 16:26:29     INFO - PID 7564 | 1636129589042	Marionette	TRACE	[85] Received event pageshow for about:blank
[task 2021-11-05T16:26:29.057Z] 16:26:29     INFO - PID 7564 | 1636129589043	Marionette	DEBUG	0 <- [1,263,null,{"handle":"7bbd7c43-0d86-405c-99e3-f5d08b1302cb","type":"tab"}]
[task 2021-11-05T16:26:29.061Z] 16:26:29     INFO - PID 7564 | 1636129589046	webdriver::server	DEBUG	<- 200 OK {"value":{"handle":"7bbd7c43-0d86-405c-99e3-f5d08b1302cb","type":"tab"}}
[task 2021-11-05T16:26:29.066Z] 16:26:29     INFO - PID 7564 | 1636129589051	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/window {"handle": "7bbd7c43-0d86-405c-99e3-f5d08b1302cb"}
[task 2021-11-05T16:26:29.070Z] 16:26:29     INFO - PID 7564 | 1636129589053	Marionette	DEBUG	0 -> [0,264,"WebDriver:SwitchToWindow",{"handle":"7bbd7c43-0d86-405c-99e3-f5d08b1302cb"}]
[task 2021-11-05T16:26:29.084Z] 16:26:29     INFO - PID 7564 | 1636129589081	Marionette	TRACE	Received DOM event TabSelect for [object XULElement]
[task 2021-11-05T16:26:29.101Z] 16:26:29     INFO - PID 7564 | 1636129589098	Marionette	DEBUG	0 <- [1,264,null,{"value":null}]
[task 2021-11-05T16:26:29.112Z] 16:26:29     INFO - PID 7564 | 1636129589102	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:29.118Z] 16:26:29     INFO - PID 7564 | 1636129589107	webdriver::server	DEBUG	-> POST /session/69fe52af-7d52-4670-a3bf-2ebf3a26c45c/url {"url": "https://web-platform.test:8443/common/blank.html"}
[task 2021-11-05T16:26:29.124Z] 16:26:29     INFO - PID 7564 | 1636129589110	Marionette	DEBUG	0 -> [0,265,"WebDriver:Navigate",{"url":"https://web-platform.test:8443/common/blank.html"}]
[task 2021-11-05T16:26:29.128Z] 16:26:29     INFO - PID 7564 | 1636129589118	Marionette	TRACE	[85] Received event beforeunload for about:blank
[task 2021-11-05T16:26:29.156Z] 16:26:29     INFO - PID 7564 | 1636129589153	Marionette	TRACE	Remoteness change detected. Set new top-level browsing context to 87
[task 2021-11-05T16:26:29.177Z] 16:26:29     INFO - PID 7564 | 1636129589177	Marionette	TRACE	[87] Received event beforeunload for about:blank
[task 2021-11-05T16:26:29.196Z] 16:26:29     INFO - PID 7564 | 1636129589191	Marionette	TRACE	[87] Received event pagehide for about:blank
[task 2021-11-05T16:26:29.245Z] 16:26:29     INFO - PID 7564 | DEBUG: Adding blocker ContentParent: id=2a252622800 for phase xpcom-will-shutdown
[task 2021-11-05T16:26:29.254Z] 16:26:29     INFO - PID 7564 | DEBUG: Adding blocker ContentParent: id=2a252622800 for phase profile-before-change
[task 2021-11-05T16:26:29.258Z] 16:26:29     INFO - PID 7564 | 1636129589252	Marionette	TRACE	[87] Received event DOMContentLoaded for https://web-platform.test:8443/common/blank.html
[task 2021-11-05T16:26:29.263Z] 16:26:29     INFO - PID 7564 | 1636129589254	Marionette	TRACE	[87] Received event pageshow for https://web-platform.test:8443/common/blank.html
[task 2021-11-05T16:26:29.269Z] 16:26:29     INFO - PID 7564 | 1636129589263	Marionette	DEBUG	0 <- [1,265,null,{"value":null}]
[task 2021-11-05T16:26:29.282Z] 16:26:29     INFO - PID 7564 | 1636129589276	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2021-11-05T16:26:29.314Z] 16:26:29     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/permissions/set.py | expected OK
[task 2021-11-05T16:26:29.314Z] 16:26:29     INFO - TEST-INFO took 30019ms
[task 2021-11-05T16:26:29.323Z] 16:26:29     INFO - Restarting browser for new test group
[task 2021-11-05T16:26:32.103Z] 16:26:32     INFO - STDOUT: FAILED
[task 2021-11-05T16:26:32.107Z] 16:26:32     INFO - Closing logging queue
[task 2021-11-05T16:26:32.108Z] 16:26:32     INFO - queue closed
[task 2021-11-05T16:26:32.114Z] 16:26:32     INFO - Starting runner
[task 2021-11-05T16:26:35.194Z] 16:26:35     INFO - PID 7364 | 1636129594699	geckodriver	INFO	Listening on 127.0.0.1:64552
[task 2021-11-05T16:26:35.204Z] 16:26:35     INFO - WebDriver HTTP server listening at http://127.0.0.1:64552/
[task 2021-11-05T16:26:35.209Z] 16:26:35     INFO - TEST-START | /webdriver/tests/release_actions/release.py
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
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.