1875679 - Intermittent [tier 2] webdriver/tests/bidi/network/continue_with_auth/invalid.py | single tracking bug (Hang at RemoteAgent DEBUG WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":78,"result":{"realm":"5038ac4d-1<...>)

Reporter

Description

•

5 months ago

treeherder

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

[task 2024-01-21T11:15:43.583Z] 11:15:43     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/network/continue_with_auth/invalid.py::test_params_action_invalid_type[False] 
[task 2024-01-21T11:15:43.583Z] 11:15:43     INFO - PID 1304 | 1705835743563	Marionette	DEBUG	0 -> [0,207,"WebDriver:SwitchToWindow",{"handle":"12e7136e-6e76-497a-8691-c311244dc310"}]
[task 2024-01-21T11:15:43.584Z] 11:15:43     INFO - PID 1304 | 1705835743563	Marionette	DEBUG	0 <- [1,207,null,{"value":null}]
[task 2024-01-21T11:15:43.584Z] 11:15:43     INFO - PID 1304 | 1705835743564	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-01-21T11:15:43.585Z] 11:15:43     INFO - PID 1304 | 1705835743565	webdriver::server	DEBUG	-> POST /session/ca69b8b1-c4b7-4ec0-8501-0022571693ec/window/rect {"width": 800, "height": 600}
[task 2024-01-21T11:15:43.586Z] 11:15:43     INFO - PID 1304 | 1705835743565	Marionette	DEBUG	0 -> [0,208,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2024-01-21T11:15:43.586Z] 11:15:43     INFO - PID 1304 | 1705835743565	Marionette	TRACE	Requested window geometry matches
[task 2024-01-21T11:15:43.587Z] 11:15:43     INFO - PID 1304 | 1705835743566	Marionette	DEBUG	0 <- [1,208,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2024-01-21T11:15:43.588Z] 11:15:43     INFO - PID 1304 | 1705835743566	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2024-01-21T11:15:43.589Z] 11:15:43     INFO - PID 1304 | 1705835743567	webdriver::server	DEBUG	-> POST /session/ca69b8b1-c4b7-4ec0-8501-0022571693ec/frame {"id": null}
[task 2024-01-21T11:15:43.589Z] 11:15:43     INFO - PID 1304 | 1705835743568	Marionette	DEBUG	0 -> [0,209,"WebDriver:SwitchToFrame",{"id":null}]
[task 2024-01-21T11:15:43.590Z] 11:15:43     INFO - PID 1304 | 1705835743569	Marionette	TRACE	[11] MarionetteCommands actor created for window id 8589934598
[task 2024-01-21T11:15:43.591Z] 11:15:43     INFO - PID 1304 | 1705835743570	Marionette	DEBUG	0 <- [1,209,null,{"value":null}]
[task 2024-01-21T11:15:43.592Z] 11:15:43     INFO - PID 1304 | 1705835743571	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-01-21T11:15:43.592Z] 11:15:43     INFO - PID 1304 | 1705835743577	RemoteAgent	INFO	Perform WebSocket upgrade for incoming connection from 127.0.0.1:55323
[task 2024-01-21T11:15:43.594Z] 11:15:43     INFO - PID 1304 | 1705835743580	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 accepted
[task 2024-01-21T11:15:43.595Z] 11:15:43     INFO - PID 1304 | 1705835743580	WebDriver BiDi	DEBUG	Connection cc458062-f48b-48d0-90a8-659662d03e77 attached to session ca69b8b1-c4b7-4ec0-8501-0022571693ec
[task 2024-01-21T11:15:43.596Z] 11:15:43     INFO - PID 1304 | 1705835743580	webdriver::server	DEBUG	-> GET /session/ca69b8b1-c4b7-4ec0-8501-0022571693ec/window/rect
[task 2024-01-21T11:15:43.596Z] 11:15:43     INFO - PID 1304 | 1705835743580	Marionette	DEBUG	0 -> [0,210,"WebDriver:GetWindowRect",{}]
[task 2024-01-21T11:15:43.597Z] 11:15:43     INFO - PID 1304 | 1705835743581	Marionette	DEBUG	0 <- [1,210,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2024-01-21T11:15:43.598Z] 11:15:43     INFO - PID 1304 | 1705835743581	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2024-01-21T11:15:43.598Z] 11:15:43     INFO - PID 1304 | 1705835743582	webdriver::server	DEBUG	-> GET /session/ca69b8b1-c4b7-4ec0-8501-0022571693ec/window/rect
[task 2024-01-21T11:15:43.599Z] 11:15:43     INFO - PID 1304 | 1705835743583	Marionette	DEBUG	0 -> [0,211,"WebDriver:GetWindowRect",{}]
[task 2024-01-21T11:15:43.600Z] 11:15:43     INFO - PID 1304 | 1705835743583	Marionette	DEBUG	0 <- [1,211,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2024-01-21T11:15:43.601Z] 11:15:43     INFO - PID 1304 | 1705835743584	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2024-01-21T11:15:43.602Z] 11:15:43     INFO - PID 1304 | 1705835743585	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 -> {"id":72,"method":"browsingContext.getTree","params":{}}
[task 2024-01-21T11:15:43.602Z] 11:15:43     INFO - PID 1304 | 1705835743586	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.603Z] 11:15:43     INFO - PID 1304 | 1705835743586	RemoteAgent	TRACE	Received command browsingContext.getTree for destination ROOT
[task 2024-01-21T11:15:43.604Z] 11:15:43     INFO - PID 1304 | 1705835743586	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.606Z] 11:15:43     INFO - PID 1304 | 1705835743586	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":72,"result":{"contexts":[{"context":"12e7136e-6e76-497a-8691-c311244dc310","url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html","children":[],"parent":null}]}}
[task 2024-01-21T11:15:43.607Z] 11:15:43     INFO - PID 1304 | 1705835743589	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 -> {"id":73,"method":"session.subscribe","params":{"events":["network.responseCompleted"],"contexts":["12e7136e-6e76-497a-8691-c311244dc310"]}}
[task 2024-01-21T11:15:43.608Z] 11:15:43     INFO - PID 1304 | 1705835743589	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.608Z] 11:15:43     INFO - PID 1304 | 1705835743589	RemoteAgent	TRACE	Received command session.subscribe for destination ROOT
[task 2024-01-21T11:15:43.609Z] 11:15:43     INFO - PID 1304 | 1705835743589	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.610Z] 11:15:43     INFO - PID 1304 | 1705835743590	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.610Z] 11:15:43     INFO - PID 1304 | 1705835743592	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.611Z] 11:15:43     INFO - PID 1304 | 1705835743592	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.612Z] 11:15:43     INFO - PID 1304 | 1705835743592	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.613Z] 11:15:43     INFO - PID 1304 | 1705835743592	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.614Z] 11:15:43     INFO - PID 1304 | 1705835743592	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.614Z] 11:15:43     INFO - PID 1304 | 1705835743593	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":73,"result":{}}
[task 2024-01-21T11:15:43.615Z] 11:15:43     INFO - PID 1304 | 1705835743594	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 -> {"id":74,"method":"browsingContext.navigate","params":{"context":"12e7136e-6e76-497a-8691-c311244dc310","url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html","wait":"complete"}}
[task 2024-01-21T11:15:43.615Z] 11:15:43     INFO - PID 1304 | 1705835743594	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.616Z] 11:15:43     INFO - PID 1304 | 1705835743594	RemoteAgent	TRACE	Received command browsingContext.navigate for destination ROOT
[task 2024-01-21T11:15:43.617Z] 11:15:43     INFO - PID 1304 | 1705835743594	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.617Z] 11:15:43     INFO - PID 1304 | 1705835743594	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2024-01-21T11:15:43.618Z] 11:15:43     INFO - PID 1304 | 1705835743594	RemoteAgent	TRACE	Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.619Z] 11:15:43     INFO - PID 1304 | 1705835743594	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.620Z] 11:15:43     INFO - PID 1304 | 1705835743595	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2024-01-21T11:15:43.620Z] 11:15:43     INFO - PID 1304 | 1705835743595	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.621Z] 11:15:43     INFO - PID 1304 | 1705835743595	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.622Z] 11:15:43     INFO - PID 1304 | 1705835743598	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.623Z] 11:15:43     INFO - PID 1304 | 1705835743598	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.623Z] 11:15:43     INFO - PID 1304 | 1705835743598	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.624Z] 11:15:43     INFO - PID 1304 | 1705835743598	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.624Z] 11:15:43     INFO - PID 1304 | 1705835743598	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.626Z] 11:15:43     INFO - PID 1304 | 1705835743600	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.627Z] 11:15:43     INFO - PID 1304 | 1705835743600	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.627Z] 11:15:43     INFO - PID 1304 | 1705835743600	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.628Z] 11:15:43     INFO - PID 1304 | 1705835743600	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.629Z] 11:15:43     INFO - PID 1304 | 1705835743600	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.630Z] 11:15:43     INFO - PID 1304 | 1705835743602	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.630Z] 11:15:43     INFO - PID 1304 | 1705835743602	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.631Z] 11:15:43     INFO - PID 1304 | 1705835743602	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.632Z] 11:15:43     INFO - PID 1304 | 1705835743602	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.633Z] 11:15:43     INFO - PID 1304 | 1705835743602	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.633Z] 11:15:43     INFO - PID 1304 | 1705835743603	RemoteAgent	TRACE	[11] ProgressListener Start: expectNavigation=true resolveWhenStarted=false unloadTimeout=1600 waitForExplicitStart=true
[task 2024-01-21T11:15:43.634Z] 11:15:43     INFO - PID 1304 | 1705835743603	RemoteAgent	TRACE	[11] ProgressListener Skip setting the unload timer
[task 2024-01-21T11:15:43.635Z] 11:15:43     INFO - PID 1304 | 1705835743605	RemoteAgent	TRACE	[11] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-21T11:15:43.635Z] 11:15:43     INFO - PID 1304 | 1705835743608	RemoteAgent	TRACE	[12e7136e-6e76-497a-8691-c311244dc310] Navigation started for url: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html (e74e2615-dd37-47a4-bb17-60268d4dd9e4)
[task 2024-01-21T11:15:43.636Z] 11:15:43     INFO - PID 1304 | 1705835743609	RemoteAgent	TRACE	[12e7136e-6e76-497a-8691-c311244dc310] Skipping already tracked navigation, navigationId: e74e2615-dd37-47a4-bb17-60268d4dd9e4
[task 2024-01-21T11:15:43.636Z] 11:15:43     INFO - PID 1304 | 1705835743611	RemoteAgent	TRACE	[11] ProgressListener Check loading state: isStart=1 isStop=0
[task 2024-01-21T11:15:43.637Z] 11:15:43     INFO - PID 1304 | 1705835743611	RemoteAgent	TRACE	[11] ProgressListener state=start: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-21T11:15:43.695Z] 11:15:43     INFO - PID 1304 | 1705835743693	RemoteAgent	TRACE	[11] NavigationListener onStateChange, stateFlags: 196610, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-21T11:15:43.713Z] 11:15:43     INFO - PID 1304 | 1705835743706	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"event","method":"network.responseCompleted","params":{"context":"12e7136e-6e76-497a-8691-c311244dc310","isBlocked":false,"navigation":"e74e2615-dd37-47a4-bb17-60268d4dd9e4","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"web-platform.test:8443"}},{"name":"Accept","value":{"type":"string","value":"text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,*/*;q=0.8"}},{"name":"Accept-Language","value":{"type":"string","value":"en-US,en;q=0.5"}},{"name":"Accept-Encoding","value":{"type":"string","value":"gzip, deflate, br"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:123.0) Gecko/20100101 Firefox/123.0"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Upgrade-Insecure-Requests","value":{"type":"string","value":"1"}},{"name":"Sec-Fetch-Dest","value":{"type":"string","value":"document"}},{"name":"Sec-Fetch-Mode","value":{"type":"string","value":"navigate"}},{"name":"Sec-Fetch-Site","value":{"type":"string","value":"none"}},{"name":"Sec-Fetch-User","value":{"type":"string","value":"?1"}}],"headersSize":501,"method":"GET","request":"16","timings":{"timeOrigin":0,"requestTime":1705835743603000,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1705835743605444,"responseStart":1705835743606769,"responseEnd":1705835743607291},"url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html"},"timestamp":1705835743705,"response":{"bodySize":30,"content":{"size":30},"headersSize":117,"url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html","bytesReceived":147,"fromCache":false,"headers":[{"name":"Content-Type","value":{"type":"string","value":"text/html"}},{"name":"Server","value":{"type":"string","value":"BaseHTTP/0.6 Python/3.9.10"}},{"name":"Date","value":{"type":"string","value":"Sun, 21 Jan 2024 11:15:43 GMT"}}],"mimeType":"text/html","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2024-01-21T11:15:43.714Z] 11:15:43     INFO - PID 1304 | 1705835743708	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.715Z] 11:15:43     INFO - PID 1304 | 1705835743708	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.715Z] 11:15:43     INFO - PID 1304 | 1705835743708	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.716Z] 11:15:43     INFO - PID 1304 | 1705835743708	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.717Z] 11:15:43     INFO - PID 1304 | 1705835743708	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session ca69b8b1-c4b7-4ec0-8501-0022571693ec
[task 2024-01-21T11:15:43.718Z] 11:15:43     INFO - PID 1304 | 1705835743708	RemoteAgent	DEBUG	WebDriverBiDiConnection e8d8e875-8ab8-456b-a0be-643a5af92cc9 <- {"type":"error","id":68,"error":"unknown error","message":"AbortError: Actor 'MessageHandlerFrame' destroyed before query 'MessageHandlerFrameParent:sendCommand' was resolved","stacktrace":""}
[task 2024-01-21T11:15:43.719Z] 11:15:43     INFO - PID 1304 | 1705835743711	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session ca69b8b1-c4b7-4ec0-8501-0022571693ec is being destroyed
[task 2024-01-21T11:15:43.719Z] 11:15:43     INFO - PID 1304 | 1705835743711	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session ca69b8b1-c4b7-4ec0-8501-0022571693ec
[task 2024-01-21T11:15:43.720Z] 11:15:43     INFO - PID 1304 | 1705835743711	Marionette	TRACE	[11] MarionetteCommands actor destroyed for window id 8589934598
[task 2024-01-21T11:15:43.721Z] 11:15:43     INFO - PID 1304 | 1705835743712	RemoteAgent	TRACE	[11] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-21T11:15:43.722Z] 11:15:43     INFO - PID 1304 | 1705835743719	RemoteAgent	TRACE	[11] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-21T11:15:43.730Z] 11:15:43     INFO - PID 1304 | 1705835743729	RemoteAgent	TRACE	[12e7136e-6e76-497a-8691-c311244dc310] Navigation finished for url: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html (e74e2615-dd37-47a4-bb17-60268d4dd9e4)
[task 2024-01-21T11:15:43.733Z] 11:15:43     INFO - PID 1304 | 1705835743732	RemoteAgent	TRACE	[11] ProgressListener Check loading state: isStart=0 isStop=16
[task 2024-01-21T11:15:43.734Z] 11:15:43     INFO - PID 1304 | 1705835743732	RemoteAgent	TRACE	[11] ProgressListener state=stop: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-21T11:15:43.735Z] 11:15:43     INFO - PID 1304 | 1705835743732	RemoteAgent	TRACE	[11] ProgressListener Stop: has error=false
[task 2024-01-21T11:15:43.736Z] 11:15:43     INFO - PID 1304 | 1705835743735	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.737Z] 11:15:43     INFO - PID 1304 | 1705835743735	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.738Z] 11:15:43     INFO - PID 1304 | 1705835743735	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.738Z] 11:15:43     INFO - PID 1304 | 1705835743735	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.739Z] 11:15:43     INFO - PID 1304 | 1705835743735	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.741Z] 11:15:43     INFO - PID 1304 | 1705835743738	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.741Z] 11:15:43     INFO - PID 1304 | 1705835743738	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.742Z] 11:15:43     INFO - PID 1304 | 1705835743738	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.743Z] 11:15:43     INFO - PID 1304 | 1705835743738	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.743Z] 11:15:43     INFO - PID 1304 | 1705835743738	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.744Z] 11:15:43     INFO - PID 1304 | 1705835743740	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.744Z] 11:15:43     INFO - PID 1304 | 1705835743740	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.745Z] 11:15:43     INFO - PID 1304 | 1705835743740	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.745Z] 11:15:43     INFO - PID 1304 | 1705835743740	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.746Z] 11:15:43     INFO - PID 1304 | 1705835743740	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.747Z] 11:15:43     INFO - PID 1304 | 1705835743741	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":74,"result":{"navigation":"e74e2615-dd37-47a4-bb17-60268d4dd9e4","url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html"}}
[task 2024-01-21T11:15:43.751Z] 11:15:43     INFO - PID 1304 | 1705835743749	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 -> {"id":75,"method":"session.unsubscribe","params":{"events":["network.responseCompleted"],"contexts":["12e7136e-6e76-497a-8691-c311244dc310"]}}
[task 2024-01-21T11:15:43.751Z] 11:15:43     INFO - PID 1304 | 1705835743749	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.752Z] 11:15:43     INFO - PID 1304 | 1705835743749	RemoteAgent	TRACE	Received command session.unsubscribe for destination ROOT
[task 2024-01-21T11:15:43.753Z] 11:15:43     INFO - PID 1304 | 1705835743749	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.754Z] 11:15:43     INFO - PID 1304 | 1705835743749	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.754Z] 11:15:43     INFO - PID 1304 | 1705835743751	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.755Z] 11:15:43     INFO - PID 1304 | 1705835743751	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.756Z] 11:15:43     INFO - PID 1304 | 1705835743751	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.757Z] 11:15:43     INFO - PID 1304 | 1705835743752	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.758Z] 11:15:43     INFO - PID 1304 | 1705835743752	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.758Z] 11:15:43     INFO - PID 1304 | 1705835743752	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":75,"result":{}}
[task 2024-01-21T11:15:43.759Z] 11:15:43     INFO - PID 1304 | 1705835743754	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 -> {"id":76,"method":"session.subscribe","params":{"events":["network.authRequired"]}}
[task 2024-01-21T11:15:43.760Z] 11:15:43     INFO - PID 1304 | 1705835743754	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.760Z] 11:15:43     INFO - PID 1304 | 1705835743754	RemoteAgent	TRACE	Received command session.subscribe for destination ROOT
[task 2024-01-21T11:15:43.761Z] 11:15:43     INFO - PID 1304 | 1705835743754	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.762Z] 11:15:43     INFO - PID 1304 | 1705835743754	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.762Z] 11:15:43     INFO - PID 1304 | 1705835743757	RemoteAgent	TRACE	Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.763Z] 11:15:43     INFO - PID 1304 | 1705835743757	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.764Z] 11:15:43     INFO - PID 1304 | 1705835743757	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.765Z] 11:15:43     INFO - PID 1304 | 1705835743757	RemoteAgent	TRACE	Received command network._applySessionData for destination ROOT
[task 2024-01-21T11:15:43.766Z] 11:15:43     INFO - PID 1304 | 1705835743757	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.767Z] 11:15:43     INFO - PID 1304 | 1705835743758	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":76,"result":{}}
[task 2024-01-21T11:15:43.768Z] 11:15:43     INFO - PID 1304 | 1705835743761	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 -> {"id":77,"method":"network.addIntercept","params":{"phases":["authRequired"],"urlPatterns":[{"type":"string","pattern":"https://web-platform.test:8443/webdriver/tests/support/http_handlers/authentication.py?username=user&password=password&realm=test"}]}}
[task 2024-01-21T11:15:43.768Z] 11:15:43     INFO - PID 1304 | 1705835743762	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.769Z] 11:15:43     INFO - PID 1304 | 1705835743762	RemoteAgent	TRACE	Received command network.addIntercept for destination ROOT
[task 2024-01-21T11:15:43.770Z] 11:15:43     INFO - PID 1304 | 1705835743762	RemoteAgent	TRACE	Module root/network.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.771Z] 11:15:43     INFO - PID 1304 | 1705835743763	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":77,"result":{"intercept":"2bdbee0c-46d4-4ee4-a754-f6d115d7340d"}}
[task 2024-01-21T11:15:43.772Z] 11:15:43     INFO - PID 1304 | 1705835743765	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 -> {"id":78,"method":"script.evaluate","params":{"expression":"\n                 {\n                   const controller = new AbortController();\n                   setTimeout(() => controll ...   \n                     signal: controller.signal\n                   }).then(response => response.text());\n                 }","target":{"context":"12e7136e-6e76-497a-8691-c311244dc310"},"awaitPromise":true}}
[task 2024-01-21T11:15:43.772Z] 11:15:43     INFO - PID 1304 | 1705835743765	RemoteAgent	TRACE	Module root/script.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.773Z] 11:15:43     INFO - PID 1304 | 1705835743765	RemoteAgent	TRACE	Received command script.evaluate for destination ROOT
[task 2024-01-21T11:15:43.773Z] 11:15:43     INFO - PID 1304 | 1705835743765	RemoteAgent	TRACE	Module root/script.sys.mjs found for ROOT
[task 2024-01-21T11:15:43.774Z] 11:15:43     INFO - PID 1304 | 1705835743766	RemoteAgent	TRACE	Received command script.evaluateExpression for destination WINDOW_GLOBAL
[task 2024-01-21T11:15:43.775Z] 11:15:43     INFO - PID 1304 | 1705835743766	RemoteAgent	TRACE	Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.775Z] 11:15:43     INFO - PID 1304 | 1705835743766	RemoteAgent	TRACE	Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2024-01-21T11:15:43.974Z] 11:15:43     INFO - PID 1304 | [Parent 9424, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileChunk.cpp:402
[task 2024-01-21T11:15:56.830Z] 11:15:56     INFO - PID 1304 | 1705835756828	RemoteAgent	DEBUG	WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":78,"result":{"realm":"5038ac4d-1c15-4003-9052-c6d6c35f38ab","type":"exception","exceptionDetails":{"columnNumber":22,"exception":{"type":"error"},"lineNumber":8,"stackTrace":{"callFrames":[{"columnNumber":22,"functionName":"","lineNumber":8,"url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html"}]},"text":"AbortError: The operation was aborted. "}}}
[task 2024-01-21T11:17:21.866Z] 11:17:21     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/network/continue_with_auth/invalid.py | expected OK
[task 2024-01-21T11:17:21.866Z] 11:17:21     INFO - TEST-INFO took 105010ms
[task 2024-01-21T11:17:21.868Z] 11:17:21     INFO - Restarting browser for new test group
[task 2024-01-21T11:17:21.933Z] 11:17:21  WARNING - Firefox didn't exit cleanly, not processing leak logs
[task 2024-01-21T11:17:31.936Z] 11:17:31  WARNING - Forcibly terminating runner process
[task 2024-01-21T11:17:32.170Z] 11:17:32     INFO - PID 8572 | 1705835852087	geckodriver	INFO	Listening on 127.0.0.1:55338
[task 2024-01-21T11:17:32.173Z] 11:17:32     INFO - Starting runner
[task 2024-01-21T11:17:32.858Z] 11:17:32     INFO - TEST-START | /webdriver/tests/bidi/network/provide_response/invalid.py

Comment hidden (Intermittent Failures Robot)

Henrik Skupin [:whimboo][⌚️UTC+1]

Comment 2

•

5 months ago

Hi Julian, could this happen because we are awaiting an event? The fetch request seems to be successful. But lets not spent too much time here given that it failed only once so far. Thanks.

Flags: needinfo?(jdescottes)

Julian Descottes [:jdescottes]

Comment 3

•

5 months ago

Looking quickly, we are timing out in the setup_blocked_request helper, specifically at https://searchfox.org/mozilla-central/rev/c130c69b7b863d5e28ab9524b65c27c7a9507c48/testing/web-platform/tests/webdriver/tests/bidi/network/conftest.py#177.

So we are indeed waiting for a network.authRequired event, but we don't really have much logs which can help us here. The test is a quite basic "invalid" test, and it seems this could happen for any of the other tests in this file.

It's interesting to note that the fetch call gets aborted after 12 seconds (I guess 3s multiplied by 4 because Debug platform), but the test itself still takes another 90 seconds to timeout. If we see more issues with authentication requests, maybe we should increase the timeout?

Flags: needinfo?(jdescottes)

Henrik Skupin [:whimboo][⌚️UTC+1]

Comment 4

•

5 months ago

Interesting. It could be also related to bug 1825501 (navigation timeouts) or even bug 1760291 (loading a page via wptserve), whereby my favorite here is the latter given that it doesn't use the wait for navigation logic at all.

Updated

•

4 months ago

Attachment #9384648 - Attachment is obsolete: true

BugBot [:suhaib / :marco/ :calixte]

Comment 9

•

2 months ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Status: NEW → RESOLVED

Closed: 2 months ago

Resolution: --- → INCOMPLETE

Bugzilla

Quick Search

Intermittent [tier 2] webdriver/tests/bidi/network/continue_with_auth/invalid.py | single tracking bug (Hang at RemoteAgent DEBUG WebDriverBiDiConnection cc458062-f48b-48d0-90a8-659662d03e77 <- {"type":"success","id":78,"result":{"realm":"5038ac4d-1<...>)

Categories

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

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, intermittent-testcase)

Crash Data

Security

(public)

User Story

Attachments

(1 obsolete file)

Description

Comment 1

Comment 2

Comment 3

Comment 4

Comment 5

Comment 6

Comment 7

Comment 8

Updated

Comment 9

Attachment

General

Description

File Name

Content Type