Closed Bug 1850776 Opened 10 months ago Closed 10 months ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/network/response_completed/response_completed.py | expected OK

Categories

(Remote Protocol :: WebDriver BiDi, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1630162

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2023-08-30T15:06:02.530Z] 15:06:02     INFO - STDOUT: PASSED
[task 2023-08-30T15:06:02.531Z] 15:06:02     INFO - PID 6464 | 1693407962530	RemoteAgent	DEBUG	WebDriverBiDiConnection 49d06cf9-6e98-4e81-96b2-7a7af80e3429 -> {"id":336,"method":"session.unsubscribe","params":{"events":["network.responseCompleted"]}}
[task 2023-08-30T15:06:02.532Z] 15:06:02     INFO - PID 6464 | 1693407962530	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2023-08-30T15:06:02.533Z] 15:06:02     INFO - PID 6464 | 1693407962530	RemoteAgent	TRACE	Received command session.unsubscribe for destination ROOT
[task 2023-08-30T15:06:02.534Z] 15:06:02     INFO - PID 6464 | 1693407962530	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2023-08-30T15:06:02.534Z] 15:06:02     INFO - PID 6464 | 1693407962531	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2023-08-30T15:06:02.535Z] 15:06:02     INFO - PID 6464 | 1693407962532	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-30T15:06:02.536Z] 15:06:02     INFO - PID 6464 | 1693407962532	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-30T15:06:02.537Z] 15:06:02     INFO - PID 6464 | 1693407962533	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2023-08-30T15:06:02.538Z] 15:06:02     INFO - PID 6464 | 1693407962533	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2023-08-30T15:06:02.538Z] 15:06:02     INFO - PID 6464 | 1693407962533	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2023-08-30T15:06:02.539Z] 15:06:02     INFO - PID 6464 | 1693407962533	RemoteAgent	DEBUG	WebDriverBiDiConnection 49d06cf9-6e98-4e81-96b2-7a7af80e3429 <- {"type":"success","id":336,"result":{}}
[task 2023-08-30T15:06:02.540Z] 15:06:02     INFO - PID 6464 | 1693407962535	RemoteAgent	DEBUG	WebDriverBiDiConnection 49d06cf9-6e98-4e81-96b2-7a7af80e3429 -> {"id":337,"method":"browsingContext.close","params":{"context":"39229766-a9dd-4b75-b40d-b9e34a33b9b9"}}
[task 2023-08-30T15:06:02.541Z] 15:06:02     INFO - PID 6464 | 1693407962535	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-30T15:06:02.541Z] 15:06:02     INFO - PID 6464 | 1693407962535	RemoteAgent	TRACE	Received command browsingContext.close for destination ROOT
[task 2023-08-30T15:06:02.542Z] 15:06:02     INFO - PID 6464 | 1693407962535	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-30T15:06:02.563Z] 15:06:02     INFO - PID 6464 | 1693407962564	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 3912b398-4736-45df-8c85-aa1a175d6664 is being destroyed
[task 2023-08-30T15:06:02.566Z] 15:06:02     INFO - PID 6464 | 1693407962564	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 3912b398-4736-45df-8c85-aa1a175d6664
[task 2023-08-30T15:06:02.567Z] 15:06:02     INFO - PID 6464 | 1693407962564	RemoteAgent	DEBUG	WebDriverBiDiConnection 49d06cf9-6e98-4e81-96b2-7a7af80e3429 <- {"type":"success","id":337,"result":{}}
[task 2023-08-30T15:06:02.583Z] 15:06:02     INFO - PID 6464 | 1693407962586	RemoteAgent	DEBUG	WebDriverBiDiConnection 49d06cf9-6e98-4e81-96b2-7a7af80e3429 closed
[task 2023-08-30T15:06:02.589Z] 15:06:02     INFO - PID 6464 | 1693407962588	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/timeouts {"implicit": 0}
[task 2023-08-30T15:06:02.590Z] 15:06:02     INFO - PID 6464 | 1693407962589	Marionette	DEBUG	0 -> [0,740,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2023-08-30T15:06:02.591Z] 15:06:02     INFO - PID 6464 | 1693407962590	Marionette	DEBUG	0 <- [1,740,null,{"value":null}]
[task 2023-08-30T15:06:02.591Z] 15:06:02     INFO - PID 6464 | 1693407962590	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-08-30T15:06:02.593Z] 15:06:02     INFO - PID 6464 | 1693407962592	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/timeouts {"pageLoad": 300000}
[task 2023-08-30T15:06:02.593Z] 15:06:02     INFO - PID 6464 | 1693407962592	Marionette	DEBUG	0 -> [0,741,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2023-08-30T15:06:02.594Z] 15:06:02     INFO - PID 6464 | 1693407962593	Marionette	DEBUG	0 <- [1,741,null,{"value":null}]
[task 2023-08-30T15:06:02.595Z] 15:06:02     INFO - PID 6464 | 1693407962593	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-08-30T15:06:02.596Z] 15:06:02     INFO - PID 6464 | 1693407962595	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/timeouts {"script": 30000}
[task 2023-08-30T15:06:02.597Z] 15:06:02     INFO - PID 6464 | 1693407962596	Marionette	DEBUG	0 -> [0,742,"WebDriver:SetTimeouts",{"script":30000}]
[task 2023-08-30T15:06:02.597Z] 15:06:02     INFO - PID 6464 | 1693407962596	Marionette	DEBUG	0 <- [1,742,null,{"value":null}]
[task 2023-08-30T15:06:02.598Z] 15:06:02     INFO - PID 6464 | 1693407962596	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-08-30T15:06:02.599Z] 15:06:02     INFO - PID 6464 | 1693407962598	webdriver::server	DEBUG	-> GET /session/3912b398-4736-45df-8c85-aa1a175d6664/window
[task 2023-08-30T15:06:02.600Z] 15:06:02     INFO - PID 6464 | 1693407962598	Marionette	DEBUG	0 -> [0,743,"WebDriver:GetWindowHandle",{}]
[task 2023-08-30T15:06:02.600Z] 15:06:02     INFO - PID 6464 | 1693407962599	Marionette	DEBUG	0 <- [1,743,null,{"value":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}]
[task 2023-08-30T15:06:02.601Z] 15:06:02     INFO - PID 6464 | 1693407962599	webdriver::server	DEBUG	<- 200 OK {"value":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}
[task 2023-08-30T15:06:02.602Z] 15:06:02     INFO - PID 6464 | 1693407962600	webdriver::server	DEBUG	-> GET /session/3912b398-4736-45df-8c85-aa1a175d6664/window
[task 2023-08-30T15:06:02.602Z] 15:06:02     INFO - PID 6464 | 1693407962601	Marionette	DEBUG	0 -> [0,744,"WebDriver:GetWindowHandle",{}]
[task 2023-08-30T15:06:02.603Z] 15:06:02     INFO - PID 6464 | 1693407962601	Marionette	DEBUG	0 <- [1,744,null,{"value":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}]
[task 2023-08-30T15:06:02.603Z] 15:06:02     INFO - PID 6464 | 1693407962602	webdriver::server	DEBUG	<- 200 OK {"value":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}
[task 2023-08-30T15:06:02.604Z] 15:06:02     INFO - PID 6464 | 1693407962602	webdriver::server	DEBUG	-> GET /session/3912b398-4736-45df-8c85-aa1a175d6664/window/handles
[task 2023-08-30T15:06:02.605Z] 15:06:02     INFO - PID 6464 | 1693407962603	Marionette	DEBUG	0 -> [0,745,"WebDriver:GetWindowHandles",{}]
[task 2023-08-30T15:06:02.606Z] 15:06:02     INFO - PID 6464 | 1693407962603	Marionette	DEBUG	0 <- [1,745,null,["63a3621c-4f4f-4c9b-b514-4ea6b378ca25"]]
[task 2023-08-30T15:06:02.606Z] 15:06:02     INFO - PID 6464 | 1693407962604	webdriver::server	DEBUG	<- 200 OK {"value":["63a3621c-4f4f-4c9b-b514-4ea6b378ca25"]}
[task 2023-08-30T15:06:02.607Z] 15:06:02     INFO - PID 6464 | 1693407962605	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/window {"handle": "63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}
[task 2023-08-30T15:06:02.608Z] 15:06:02     INFO - PID 6464 | 1693407962605	Marionette	DEBUG	0 -> [0,746,"WebDriver:SwitchToWindow",{"handle":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}]
[task 2023-08-30T15:06:02.609Z] 15:06:02     INFO - PID 6464 | 1693407962606	Marionette	DEBUG	0 <- [1,746,null,{"value":null}]
[task 2023-08-30T15:06:02.609Z] 15:06:02     INFO - PID 6464 | 1693407962607	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-08-30T15:06:02.610Z] 15:06:02     INFO - PID 6464 | 1693407962607	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/alert/dismiss {}
[task 2023-08-30T15:06:02.611Z] 15:06:02     INFO - PID 6464 | 1693407962608	Marionette	DEBUG	0 -> [0,747,"WebDriver:DismissAlert",{}]
[task 2023-08-30T15:06:02.612Z] 15:06:02     INFO - PID 6464 | 1693407962609	Marionette	DEBUG	0 <- [1,747,{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... ote/content/marionette/server.sys.mjs:263:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:494:20\n"},null]
[task 2023-08-30T15:06:02.613Z] 15:06:02     INFO - PID 6464 | 1693407962609	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:263:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:494:20\n"}}
[task 2023-08-30T15:06:02.614Z] 15:06:02     INFO - PID 6464 | [Child 4140, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:517
[task 2023-08-30T15:06:02.615Z] 15:06:02     INFO - PID 6464 | 1693407962610	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/window {"handle": "63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}
[task 2023-08-30T15:06:02.616Z] 15:06:02     INFO - PID 6464 | 1693407962611	Marionette	DEBUG	0 -> [0,748,"WebDriver:SwitchToWindow",{"handle":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}]
[task 2023-08-30T15:06:02.616Z] 15:06:02     INFO - PID 6464 | 1693407962612	Marionette	DEBUG	0 <- [1,748,null,{"value":null}]
[task 2023-08-30T15:06:02.617Z] 15:06:02     INFO - PID 6464 | 1693407962613	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-08-30T15:06:02.617Z] 15:06:02     INFO - PID 6464 | 1693407962613	webdriver::server	DEBUG	-> GET /session/3912b398-4736-45df-8c85-aa1a175d6664/window
[task 2023-08-30T15:06:02.618Z] 15:06:02     INFO - PID 6464 | 1693407962614	Marionette	DEBUG	0 -> [0,749,"WebDriver:GetWindowHandle",{}]
[task 2023-08-30T15:06:02.618Z] 15:06:02     INFO - PID 6464 | 1693407962614	Marionette	DEBUG	0 <- [1,749,null,{"value":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}]
[task 2023-08-30T15:06:02.620Z] 15:06:02     INFO - PID 6464 | 1693407962615	webdriver::server	DEBUG	<- 200 OK {"value":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}
[task 2023-08-30T15:06:02.620Z] 15:06:02     INFO - PID 6464 | 1693407962616	webdriver::server	DEBUG	-> GET /session/3912b398-4736-45df-8c85-aa1a175d6664/window/handles
[task 2023-08-30T15:06:02.620Z] 15:06:02     INFO - PID 6464 | 1693407962616	Marionette	DEBUG	0 -> [0,750,"WebDriver:GetWindowHandles",{}]
[task 2023-08-30T15:06:02.620Z] 15:06:02     INFO - PID 6464 | 1693407962617	Marionette	DEBUG	0 <- [1,750,null,["63a3621c-4f4f-4c9b-b514-4ea6b378ca25"]]
[task 2023-08-30T15:06:02.623Z] 15:06:02     INFO - PID 6464 | 1693407962617	webdriver::server	DEBUG	<- 200 OK {"value":["63a3621c-4f4f-4c9b-b514-4ea6b378ca25"]}
[task 2023-08-30T15:06:02.623Z] 15:06:02     INFO - PID 6464 | 1693407962618	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/window {"handle": "63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}
[task 2023-08-30T15:06:02.623Z] 15:06:02     INFO - PID 6464 | 1693407962619	Marionette	DEBUG	0 -> [0,751,"WebDriver:SwitchToWindow",{"handle":"63a3621c-4f4f-4c9b-b514-4ea6b378ca25"}]
[task 2023-08-30T15:06:02.623Z] 15:06:02     INFO - PID 6464 | 1693407962620	Marionette	DEBUG	0 <- [1,751,null,{"value":null}]
[task 2023-08-30T15:06:02.623Z] 15:06:02     INFO - PID 6464 | 1693407962620	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-08-30T15:06:02.623Z] 15:06:02     INFO - PID 6464 | 1693407962621	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/window/rect {"width": 800, "height": 600}
[task 2023-08-30T15:06:02.623Z] 15:06:02     INFO - PID 6464 | 1693407962622	Marionette	DEBUG	0 -> [0,752,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2023-08-30T15:06:02.623Z] 15:06:02     INFO - PID 6464 | 1693407962622	Marionette	TRACE	Requested window geometry matches
[task 2023-08-30T15:06:02.628Z] 15:06:02     INFO - PID 6464 | 1693407962622	Marionette	DEBUG	0 <- [1,752,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2023-08-30T15:06:02.628Z] 15:06:02     INFO - PID 6464 | 1693407962623	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2023-08-30T15:06:02.630Z] 15:06:02     INFO - PID 6464 | 1693407962624	webdriver::server	DEBUG	-> POST /session/3912b398-4736-45df-8c85-aa1a175d6664/frame {"id": null}
[task 2023-08-30T15:06:02.630Z] 15:06:02     INFO - PID 6464 | 1693407962624	Marionette	DEBUG	0 -> [0,753,"WebDriver:SwitchToFrame",{"id":null}]
[task 2023-08-30T15:06:02.630Z] 15:06:02     INFO - PID 6464 | 1693407962625	Marionette	TRACE	[11] MarionetteCommands actor created for window id 8589934640
[task 2023-08-30T15:06:02.630Z] 15:06:02     INFO - PID 6464 | 1693407962627	Marionette	DEBUG	0 <- [1,753,null,{"value":null}]
[task 2023-08-30T15:06:02.630Z] 15:06:02     INFO - PID 6464 | 1693407962627	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-08-30T15:06:02.630Z] 15:06:02     INFO - PID 6464 | 1693407962636	webdriver::server	DEBUG	-> DELETE /session/3912b398-4736-45df-8c85-aa1a175d6664
[task 2023-08-30T15:06:02.638Z] 15:06:02     INFO - PID 6464 | 1693407962637	Marionette	DEBUG	0 -> [0,754,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2023-08-30T15:06:02.638Z] 15:06:02     INFO - PID 6464 | 1693407962639	Marionette	INFO	Stopped listening on port 53210
[task 2023-08-30T15:06:02.646Z] 15:06:02     INFO - PID 6464 | 1693407962646	Marionette	TRACE	[11] MarionetteCommands actor destroyed for window id 8589934640
[task 2023-08-30T15:06:02.648Z] 15:06:02     INFO - PID 6464 | 1693407962648	WebDriver BiDi	DEBUG	Unregistered session handler: /session/3912b398-4736-45df-8c85-aa1a175d6664
[task 2023-08-30T15:06:02.648Z] 15:06:02     INFO - PID 6464 | 1693407962651	RemoteAgent	TRACE	MessageHandler ROOT for session 3912b398-4736-45df-8c85-aa1a175d6664 is being destroyed
[task 2023-08-30T15:06:02.648Z] 15:06:02     INFO - PID 6464 | 1693407962652	RemoteAgent	TRACE	Unregistered MessageHandler ROOT for session 3912b398-4736-45df-8c85-aa1a175d6664
[task 2023-08-30T15:06:02.678Z] 15:06:02     INFO - PID 6464 | [Child 7876, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-08-30T15:06:02.691Z] 15:06:02     INFO - PID 6464 | [Child 7876, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-08-30T15:06:02.702Z] 15:06:02     INFO - PID 6464 | [Child 7876, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-08-30T15:06:02.703Z] 15:06:02     INFO - PID 6464 | [Child 7876, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-08-30T15:06:02.704Z] 15:06:02     INFO - PID 6464 | [Child 7876, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-08-30T15:06:02.705Z] 15:06:02     INFO - PID 6464 | [Child 7876, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-08-30T15:06:02.708Z] 15:06:02     INFO - PID 6464 | [Child 7876, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-08-30T15:06:02.725Z] 15:06:02     INFO - PID 6464 | [Parent 5724, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-08-30T15:06:02.741Z] 15:06:02     INFO - PID 6464 | 1693407962751	Marionette	TRACE	Received observer notification quit-application
[task 2023-08-30T15:06:02.756Z] 15:06:02     INFO - PID 6464 | 1693407962755	Marionette	TRACE	Received observer notification quit-application
[task 2023-08-30T15:06:02.757Z] 15:06:02     INFO - PID 6464 | 1693407962755	RemoteAgent	TRACE	Received observer notification quit-application
[task 2023-08-30T15:06:02.758Z] 15:06:02     INFO - PID 6464 | 1693407962757	Marionette	DEBUG	Marionette stopped listening
[task 2023-08-30T15:06:02.766Z] 15:06:02     INFO - PID 6464 | 1693407962765	Marionette	DEBUG	0 <- [1,754,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-08-30T15:06:02.787Z] 15:06:02     INFO - PID 6464 | 1693407962801	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 3912b398-4736-45df-8c85-aa1a175d6664 is being destroyed
[task 2023-08-30T15:06:02.802Z] 15:06:02     INFO - PID 6464 | 1693407962801	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 3912b398-4736-45df-8c85-aa1a175d6664
[task 2023-08-30T15:06:02.807Z] 15:06:02     INFO - PID 6464 | [Parent 5724, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-08-30T15:06:02.810Z] 15:06:02     INFO - PID 6464 | 1693407962809	webdriver::server	DEBUG	Teardown session
[task 2023-08-30T15:06:02.817Z] 15:06:02     INFO - PID 6464 | 1693407962816	Marionette	DEBUG	Closed connection 0
[task 2023-08-30T15:06:02.825Z] 15:06:02     INFO - PID 6464 | [Parent 5724, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-08-30T15:06:02.826Z] 15:06:02     INFO - PID 6464 | [Parent 5724, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-08-30T15:06:02.827Z] 15:06:02     INFO - PID 6464 | [Parent 5724, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-08-30T15:06:02.828Z] 15:06:02     INFO - PID 6464 | !!! error running onStopped callback: TypeError: callback is not a function
[task 2023-08-30T15:06:02.837Z] 15:06:02     INFO - PID 6464 | [Parent 5724, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x8046001E (NS_ERROR_ILLEGAL_DURING_SHUTDOWN): file /builds/worker/checkouts/gecko/toolkit/components/credentialmanagement/IdentityCredentialStorageService.cpp:55
[task 2023-08-30T15:06:02.843Z] 15:06:02     INFO - PID 6464 | [Parent 5724, Main Thread] WARNING: NS_ENSURE_TRUE(inst) failed: file StaticComponents.cpp:10841
[task 2023-08-30T15:06:02.843Z] 15:06:02     INFO - PID 6464 | console.error: ({})
[task 2023-08-30T15:06:02.853Z] 15:06:02     INFO - PID 6464 | [Child 3508, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:517
[task 2023-08-30T15:06:02.853Z] 15:06:02     INFO - PID 6464 | [Child 3508, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:517
[task 2023-08-30T15:06:02.853Z] 15:06:02     INFO - PID 6464 | [Child 3508, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:517
[task 2023-08-30T15:06:02.853Z] 15:06:02     INFO - PID 6464 | [Child 3508, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:517
[task 2023-08-30T15:06:02.853Z] 15:06:02     INFO - PID 6464 | [Child 3508, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:517
[task 2023-08-30T15:07:12.329Z] 15:07:12     INFO - PID 6464 | XPCOM_MEM_BLOAT_LOG: C:\Users\task_169340691337180\AppData\Local\Temp\tmpttwskk2f.mozrunner\runtests_leaks_2576.log
[task 2023-08-30T15:07:12.331Z] 15:07:12     INFO - PID 6464 | Writing to log: C:\Users\task_169340691337180\AppData\Local\Temp\tmpttwskk2f.mozrunner\runtests_leaks_2576.log
[task 2023-08-30T15:07:12.818Z] 15:07:12     INFO - PID 6464 | 1693408032818	mozrunner::runner	DEBUG	Killing process 4112
[task 2023-08-30T15:07:12.823Z] 15:07:12     INFO - PID 6464 | 1693408032822	geckodriver::browser	DEBUG	Browser process stopped: exit code: 1
[task 2023-08-30T15:07:46.473Z] 15:07:46     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/network/response_completed/response_completed.py | expected OK
[task 2023-08-30T15:07:46.473Z] 15:07:46     INFO - TEST-INFO took 127663ms

That's a hang during shutdown of Firefox.

Status: NEW → RESOLVED
Closed: 10 months ago
Duplicate of bug: 1630162
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.