Closed Bug 1850961 Opened 9 months ago Closed 8 months ago

Intermittent network Network Events Page.Events.RequestFinished (network.spec.js) | expected PASS

Categories

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

defect

Tracking

(firefox119 fixed)

RESOLVED FIXED
119 Branch
Tracking Status
firefox119 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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


[task 2023-08-31T13:14:05.575Z] TEST-START | network Network Events Page.Events.RequestFinished (network.spec.js)
[task 2023-08-31T13:14:05.575Z] PID 672 | ["test-start",{"title":"Page.Events.RequestFinished","fullTitle":"network Network Events Page.Events.RequestFinished","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/network.spec.js","currentRetry":0}]
[task 2023-08-31T13:14:05.575Z] PID 672 | 1693487645575	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 -> {"id":2097,"method":"browsingContext.close","params":{"context":"9777d212-6c68-482c-b6e4-f6dd0c71ede6"}}
[task 2023-08-31T13:14:05.575Z] PID 672 | 1693487645575	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.576Z] PID 672 | 1693487645575	RemoteAgent	TRACE	Received command browsingContext.close for destination ROOT
[task 2023-08-31T13:14:05.576Z] PID 672 | 1693487645575	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.583Z] PID 672 | 1693487645582	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"success","id":2097,"result":{}}
[task 2023-08-31T13:14:05.583Z] PID 672 | 1693487645582	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae is being destroyed
[task 2023-08-31T13:14:05.584Z] PID 672 | 1693487645582	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.588Z] PID 672 | 1693487645588	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 -> {"id":2098,"method":"browsingContext.create","params":{"type":"tab"}}
[task 2023-08-31T13:14:05.588Z] PID 672 | 1693487645588	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.588Z] PID 672 | 1693487645588	RemoteAgent	TRACE	Received command browsingContext.create for destination ROOT
[task 2023-08-31T13:14:05.588Z] PID 672 | 1693487645588	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.591Z] PID 672 | 1693487645590	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.contextCreated","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","url":"about:blank","children":null,"parent":null}}
[task 2023-08-31T13:14:05.599Z] PID 672 | 1693487645598	RemoteAgent	TRACE	Received DOM event TabSelect for [object XULElement]
[task 2023-08-31T13:14:05.601Z] PID 672 | 1693487645600	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.601Z] PID 672 | 1693487645600	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.601Z] PID 672 | 1693487645600	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.603Z] PID 672 | 1693487645601	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.603Z] PID 672 | 1693487645601	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.603Z] PID 672 | 1693487645601	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.603Z] PID 672 | 1693487645601	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.603Z] PID 672 | 1693487645602	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.603Z] PID 672 | 1693487645602	RemoteAgent	TRACE	[281] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2023-08-31T13:14:05.603Z] PID 672 | 1693487645602	RemoteAgent	TRACE	[281] ProgressListener Setting unload timer (5000ms)
[task 2023-08-31T13:14:05.604Z] PID 672 | 1693487645602	RemoteAgent	TRACE	Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.604Z] PID 672 | 1693487645602	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.604Z] PID 672 | 1693487645603	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.607Z] PID 672 | 1693487645607	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.609Z] PID 672 | 1693487645609	RemoteAgent	TRACE	[281] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-08-31T13:14:05.610Z] PID 672 | 1693487645609	RemoteAgent	TRACE	[281] ProgressListener state=start: about:blank
[task 2023-08-31T13:14:05.610Z] PID 672 | 1693487645609	RemoteAgent	TRACE	[281] ProgressListener Cleared the unload timer
[task 2023-08-31T13:14:05.610Z] PID 672 | 1693487645609	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.611Z] PID 672 | 1693487645610	RemoteAgent	TRACE	[ecc29004-83c9-4bc8-bed4-bd1aec11cbe9] Navigation started for url: about:blank (819070be-8f2a-4dee-9074-3f3dbff936de)
[task 2023-08-31T13:14:05.611Z] PID 672 | 1693487645610	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 131088, status: 2152398850, isStart: false, isStop: true, isNetwork: false, isBindingAborted: true, targetURI: about:blank
[task 2023-08-31T13:14:05.611Z] PID 672 | 1693487645610	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.614Z] PID 672 | 1693487645614	RemoteAgent	TRACE	[281] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-08-31T13:14:05.614Z] PID 672 | 1693487645614	RemoteAgent	TRACE	[281] ProgressListener Ignore aborted navigation error to the initial document, real document will be loaded.
[task 2023-08-31T13:14:05.615Z] PID 672 | 1693487645614	RemoteAgent	TRACE	[281] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-08-31T13:14:05.615Z] PID 672 | 1693487645615	RemoteAgent	TRACE	[ecc29004-83c9-4bc8-bed4-bd1aec11cbe9] Skipping already tracked navigation, navigationId: 819070be-8f2a-4dee-9074-3f3dbff936de
[task 2023-08-31T13:14:05.616Z] PID 672 | 1693487645616	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 196610, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.619Z] PID 672 | 1693487645619	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.620Z] PID 672 | 1693487645619	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.620Z] PID 672 | 1693487645619	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.620Z] PID 672 | 1693487645619	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.620Z] PID 672 | 1693487645619	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.620Z] PID 672 | 1693487645619	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.621Z] PID 672 | 1693487645619	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.621Z] PID 672 | 1693487645619	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.621Z] PID 672 | 1693487645620	RemoteAgent	TRACE	Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.621Z] PID 672 | 1693487645620	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.621Z] PID 672 | 1693487645620	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.621Z] PID 672 | 1693487645620	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.622Z] PID 672 | 1693487645622	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.622Z] PID 672 | 1693487645622	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.623Z] PID 672 | 1693487645622	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae is being destroyed
[task 2023-08-31T13:14:05.623Z] PID 672 | 1693487645622	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.626Z] PID 672 | 1693487645626	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.domContentLoaded","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","timestamp":1693487645626,"url":"about:blank","navigation":"819070be-8f2a-4dee-9074-3f3dbff936de"}}
[task 2023-08-31T13:14:05.630Z] PID 672 | 1693487645630	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.630Z] PID 672 | 1693487645630	RemoteAgent	TRACE	[ecc29004-83c9-4bc8-bed4-bd1aec11cbe9] Navigation finished for url: about:blank (819070be-8f2a-4dee-9074-3f3dbff936de)
[task 2023-08-31T13:14:05.633Z] PID 672 | 1693487645631	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.load","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","timestamp":1693487645630,"url":"about:blank","navigation":"819070be-8f2a-4dee-9074-3f3dbff936de"}}
[task 2023-08-31T13:14:05.635Z] PID 672 | 1693487645635	RemoteAgent	TRACE	[281] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-08-31T13:14:05.636Z] PID 672 | 1693487645635	RemoteAgent	TRACE	[281] ProgressListener state=stop: about:blank
[task 2023-08-31T13:14:05.636Z] PID 672 | 1693487645635	RemoteAgent	TRACE	[281] ProgressListener Stop: has error=false
[task 2023-08-31T13:14:05.636Z] PID 672 | 1693487645636	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"success","id":2098,"result":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9"}}
[task 2023-08-31T13:14:05.637Z] PID 672 | 1693487645637	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 -> {"id":2099,"method":"cdp.getSession","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9"}}
[task 2023-08-31T13:14:05.637Z] PID 672 | 1693487645637	RemoteAgent	TRACE	Module root/cdp.sys.mjs not found for ROOT
[task 2023-08-31T13:14:05.638Z] PID 672 | 1693487645637	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"error","id":2099,"error":"unknown command","message":"cdp.getSession","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... ontent/server/WebSocketTransport.sys.mjs:127:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.sys.mjs:109:14\n"}
[task 2023-08-31T13:14:05.639Z] PID 672 | 1693487645638	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 -> {"id":2100,"method":"browsingContext.navigate","params":{"url":"http://localhost:45632/empty.html","context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","wait":"complete"}}
[task 2023-08-31T13:14:05.639Z] PID 672 | 1693487645638	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.639Z] PID 672 | 1693487645639	RemoteAgent	TRACE	Received command browsingContext.navigate for destination ROOT
[task 2023-08-31T13:14:05.639Z] PID 672 | 1693487645639	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.639Z] PID 672 | 1693487645639	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.639Z] PID 672 | 1693487645639	RemoteAgent	TRACE	Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.639Z] PID 672 | 1693487645639	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.640Z] PID 672 | 1693487645639	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.640Z] PID 672 | 1693487645639	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.640Z] PID 672 | 1693487645640	RemoteAgent	TRACE	[281] ProgressListener Start: expectNavigation=true resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=true
[task 2023-08-31T13:14:05.640Z] PID 672 | 1693487645640	RemoteAgent	TRACE	[281] ProgressListener Skip setting the unload timer
[task 2023-08-31T13:14:05.641Z] PID 672 | 1693487645641	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.642Z] PID 672 | 1693487645642	RemoteAgent	TRACE	[ecc29004-83c9-4bc8-bed4-bd1aec11cbe9] Navigation started for url: http://localhost:45632/empty.html (7ba2bbc4-929a-4e81-be08-273e96b8ee1a)
[task 2023-08-31T13:14:05.643Z] PID 672 | 1693487645642	RemoteAgent	TRACE	[281] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-08-31T13:14:05.643Z] PID 672 | 1693487645642	RemoteAgent	TRACE	[281] ProgressListener state=start: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.644Z] PID 672 | 1693487645643	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.beforeRequestSent","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","navigation":"7ba2bbc4-929a-4e81-be08-273e96b8ee1a","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"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":"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":447,"method":"GET","request":"471","timings":{"timeOrigin":0,"requestTime":1693487645640539,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1693487645641992,"responseStart":1693487645642598,"responseEnd":1693487645642723},"url":"http://localhost:45632/empty.html"},"timestamp":1693487645643,"initiator":{"type":"other"}}}
[task 2023-08-31T13:14:05.645Z] PID 672 | 1693487645644	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.responseStarted","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","navigation":"7ba2bbc4-929a-4e81-be08-273e96b8ee1a","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"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":"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":447,"method":"GET","request":"471","timings":{"timeOrigin":0,"requestTime":1693487645640539,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1693487645641992,"responseStart":1693487645642598,"responseEnd":1693487645642723},"url":"http://localhost:45632/empty.html"},"timestamp":1693487645644,"response":{"bodySize":0,"content":{"size":null},"headersSize":197,"url":"http://localhost:45632/empty.html","bytesReceived":197,"fromCache":false,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"text/html; charset=utf-8"}},{"name":"Date","value":{"type":"string","value":"Thu, 31 Aug 2023 13:14:05 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Keep-Alive","value":{"type":"string","value":"timeout=5"}},{"name":"Content-Length","value":{"type":"string","value":"0"}}],"mimeType":"text/html;charset=utf-8","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-08-31T13:14:05.647Z] PID 672 | 1693487645646	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 196610, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.676Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.677Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.677Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.677Z] PID 672 | 1693487645675	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.684Z] PID 672 | 1693487645683	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae is being destroyed
[task 2023-08-31T13:14:05.684Z] PID 672 | 1693487645683	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.695Z] PID 672 | 1693487645691	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.701Z] PID 672 | 1693487645700	RemoteAgent	TRACE	[281] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.710Z] PID 672 | 1693487645710	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.domContentLoaded","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","timestamp":1693487645700,"url":"http://localhost:45632/empty.html","navigation":"7ba2bbc4-929a-4e81-be08-273e96b8ee1a"}}
[task 2023-08-31T13:14:05.718Z] PID 672 | 1693487645718	RemoteAgent	TRACE	[ecc29004-83c9-4bc8-bed4-bd1aec11cbe9] Navigation finished for url: http://localhost:45632/empty.html (7ba2bbc4-929a-4e81-be08-273e96b8ee1a)
[task 2023-08-31T13:14:05.719Z] PID 672 | 1693487645718	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.load","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","timestamp":1693487645701,"url":"http://localhost:45632/empty.html","navigation":"7ba2bbc4-929a-4e81-be08-273e96b8ee1a"}}
[task 2023-08-31T13:14:05.721Z] PID 672 | 1693487645721	RemoteAgent	TRACE	[281] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-08-31T13:14:05.722Z] PID 672 | 1693487645721	RemoteAgent	TRACE	[281] ProgressListener state=stop: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.722Z] PID 672 | 1693487645721	RemoteAgent	TRACE	[281] ProgressListener Stop: has error=false
[task 2023-08-31T13:14:05.723Z] PID 672 | 1693487645722	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"success","id":2100,"result":{"navigation":"7ba2bbc4-929a-4e81-be08-273e96b8ee1a","url":"http://localhost:45632/empty.html"}}
[task 2023-08-31T13:14:05.725Z] TEST-UNEXPECTED-FAIL | network Network Events Page.Events.RequestFinished (network.spec.js) | expected PASS
[task 2023-08-31T13:14:05.725Z] TEST-INFO took 150ms
[task 2023-08-31T13:14:05.725Z] PID 672 | ["fail",{"title":"Page.Events.RequestFinished","fullTitle":"network Network Events Page.Events.RequestFinished","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/network.spec.js","duration":149,"currentRetry":0,"err":"expect(received).toHaveLength(expected)\n\nExpected length: 1\nReceived length: 0\nReceived array:  []","stack":"Error: expect(received).toHaveLength(expected)\n\nExpected length: 1\nReceived length: 0\nReceived array:  []\n    at Context.<anonymous> (/builds/worker/checkouts/gecko/remote/test/puppeteer/test/src/network.spec.ts:556:24)\n    at runMicrotasks (<anonymous>)\n    at processTicksAndRejections (node:internal/process/task_queues:96:5)"}]
[task 2023-08-31T13:14:05.725Z] TEST-START | network Network Events should fire events in proper order (network.spec.js)
[task 2023-08-31T13:14:05.725Z] PID 672 | ["test-start",{"title":"should fire events in proper order","fullTitle":"network Network Events should fire events in proper order","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/network.spec.js","currentRetry":0}]
[task 2023-08-31T13:14:05.726Z] PID 672 | 1693487645725	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.responseCompleted","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9","navigation":"7ba2bbc4-929a-4e81-be08-273e96b8ee1a","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"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":"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":447,"method":"GET","request":"471","timings":{"timeOrigin":0,"requestTime":1693487645640539,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1693487645641992,"responseStart":1693487645642598,"responseEnd":1693487645642723},"url":"http://localhost:45632/empty.html"},"timestamp":1693487645725,"response":{"bodySize":0,"content":{"size":0},"headersSize":197,"url":"http://localhost:45632/empty.html","bytesReceived":197,"fromCache":false,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"text/html; charset=utf-8"}},{"name":"Date","value":{"type":"string","value":"Thu, 31 Aug 2023 13:14:05 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Keep-Alive","value":{"type":"string","value":"timeout=5"}},{"name":"Content-Length","value":{"type":"string","value":"0"}}],"mimeType":"text/html;charset=utf-8","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-08-31T13:14:05.728Z] PID 672 | 1693487645727	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 -> {"id":2101,"method":"browsingContext.close","params":{"context":"ecc29004-83c9-4bc8-bed4-bd1aec11cbe9"}}
[task 2023-08-31T13:14:05.728Z] PID 672 | 1693487645728	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.728Z] PID 672 | 1693487645728	RemoteAgent	TRACE	Received command browsingContext.close for destination ROOT
[task 2023-08-31T13:14:05.729Z] PID 672 | 1693487645728	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.741Z] PID 672 | 1693487645740	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae is being destroyed
[task 2023-08-31T13:14:05.741Z] PID 672 | 1693487645740	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.743Z] PID 672 | 1693487645742	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"success","id":2101,"result":{}}
[task 2023-08-31T13:14:05.753Z] PID 672 | console.error:
[task 2023-08-31T13:14:05.753Z] PID 672 |   TypeError: can't access property "id", browsingContext is null: #getContextInfo@chrome://remote/content/webdriver-bidi/modules/root/network.sys.mjs:357:7
[task 2023-08-31T13:14:05.753Z] PID 672 | #onBeforeRequestSent@chrome://remote/content/webdriver-bidi/modules/root/network.sys.mjs:418:27
[task 2023-08-31T13:14:05.753Z] PID 672 | emit@resource://gre/modules/EventEmitter.sys.mjs:154:20
[task 2023-08-31T13:14:05.754Z] PID 672 | #emitBeforeRequestSent@chrome://remote/content/shared/listeners/NetworkEventRecord.sys.mjs:232:27
[task 2023-08-31T13:14:05.754Z] PID 672 | NetworkEventRecord@chrome://remote/content/shared/listeners/NetworkEventRecord.sys.mjs:95:32
[task 2023-08-31T13:14:05.754Z] PID 672 | #onNetworkEvent@chrome://remote/content/shared/listeners/NetworkListener.sys.mjs:97:12
[task 2023-08-31T13:14:05.755Z] PID 672 | #createNetworkEvent@resource://devtools/shared/network-observer/NetworkObserver.sys.mjs:684:46
[task 2023-08-31T13:14:05.755Z] PID 672 | @resource://devtools/shared/network-observer/NetworkObserver.sys.mjs:423:50
[task 2023-08-31T13:14:05.755Z] PID 672 | makeInfallible/<@resource://devtools/shared/DevToolsInfaillibleUtils.sys.mjs:43:22
[task 2023-08-31T13:14:05.755Z] PID 672 | 
[task 2023-08-31T13:14:05.755Z] PID 672 | console.error:
[task 2023-08-31T13:14:05.755Z] PID 672 |   TypeError: can't access property "id", browsingContext is null: #getContextInfo@chrome://remote/content/webdriver-bidi/modules/root/network.sys.mjs:357:7
[task 2023-08-31T13:14:05.755Z] PID 672 | #onResponseEvent@chrome://remote/content/webdriver-bidi/modules/root/network.sys.mjs:461:27
[task 2023-08-31T13:14:05.755Z] PID 672 | emit@resource://gre/modules/EventEmitter.sys.mjs:154:20
[task 2023-08-31T13:14:05.756Z] PID 672 | #emitResponseStarted@chrome://remote/content/shared/listeners/NetworkEventRecord.sys.mjs:257:27
[task 2023-08-31T13:14:05.756Z] PID 672 | addResponseStart@chrome://remote/content/shared/listeners/NetworkEventRecord.sys.mjs:146:30
[task 2023-08-31T13:14:05.756Z] PID 672 | @resource://devtools/shared/network-observer/NetworkObserver.sys.mjs:454:28
[task 2023-08-31T13:14:05.756Z] PID 672 | makeInfallible/<@resource://devtools/shared/DevToolsInfaillibleUtils.sys.mjs:43:22
[task 2023-08-31T13:14:05.756Z] PID 672 | 
[task 2023-08-31T13:14:05.757Z] PID 672 | console.error:
[task 2023-08-31T13:14:05.757Z] PID 672 |   TypeError: can't access property "id", browsingContext is null: #getContextInfo@chrome://remote/content/webdriver-bidi/modules/root/network.sys.mjs:357:7
[task 2023-08-31T13:14:05.757Z] PID 672 | #onResponseEvent@chrome://remote/content/webdriver-bidi/modules/root/network.sys.mjs:461:27
[task 2023-08-31T13:14:05.758Z] PID 672 | emit@resource://gre/modules/EventEmitter.sys.mjs:154:20
[task 2023-08-31T13:14:05.758Z] PID 672 | #emitResponseCompleted@chrome://remote/content/shared/listeners/NetworkEventRecord.sys.mjs:244:27
[task 2023-08-31T13:14:05.758Z] PID 672 | addResponseContent@chrome://remote/content/shared/listeners/NetworkEventRecord.sys.mjs:214:32
[task 2023-08-31T13:14:05.758Z] PID 672 | #getResponseContent@resource://devtools/shared/network-observer/NetworkResponseListener.sys.mjs:540:30
[task 2023-08-31T13:14:05.758Z] PID 672 | #onComplete@resource://devtools/shared/network-observer/NetworkResponseListener.sys.mjs:487:29
[task 2023-08-31T13:14:05.758Z] PID 672 | onStreamClose@resource://devtools/shared/network-observer/NetworkResponseListener.sys.mjs:473:23
[task 2023-08-31T13:14:05.758Z] PID 672 | onInputStreamReady@resource://devtools/shared/network-observer/NetworkResponseListener.sys.mjs:596:12
[task 2023-08-31T13:14:05.758Z] PID 672 | 
[task 2023-08-31T13:14:05.764Z] PID 672 | 1693487645763	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 -> {"id":2102,"method":"browsingContext.create","params":{"type":"tab"}}
[task 2023-08-31T13:14:05.764Z] PID 672 | 1693487645763	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.764Z] PID 672 | 1693487645763	RemoteAgent	TRACE	Received command browsingContext.create for destination ROOT
[task 2023-08-31T13:14:05.764Z] PID 672 | 1693487645763	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.767Z] PID 672 | 1693487645766	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.contextCreated","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","url":"about:blank","children":null,"parent":null}}
[task 2023-08-31T13:14:05.771Z] PID 672 | 1693487645771	RemoteAgent	TRACE	Received DOM event TabSelect for [object XULElement]
[task 2023-08-31T13:14:05.772Z] PID 672 | 1693487645772	RemoteAgent	TRACE	[282] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2023-08-31T13:14:05.772Z] PID 672 | 1693487645772	RemoteAgent	TRACE	[282] ProgressListener Setting unload timer (5000ms)
[task 2023-08-31T13:14:05.781Z] PID 672 | 1693487645780	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.781Z] PID 672 | 1693487645781	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.781Z] PID 672 | 1693487645781	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.781Z] PID 672 | 1693487645781	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.782Z] PID 672 | 1693487645782	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.784Z] PID 672 | 1693487645783	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.787Z] PID 672 | 1693487645786	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.787Z] PID 672 | 1693487645786	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.788Z] PID 672 | 1693487645787	RemoteAgent	TRACE	Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.788Z] PID 672 | 1693487645787	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.788Z] PID 672 | 1693487645787	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.789Z] PID 672 | 1693487645789	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.797Z] PID 672 | 1693487645797	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.797Z] PID 672 | 1693487645797	RemoteAgent	TRACE	[282] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-08-31T13:14:05.797Z] PID 672 | 1693487645797	RemoteAgent	TRACE	[282] ProgressListener state=start: about:blank
[task 2023-08-31T13:14:05.797Z] PID 672 | 1693487645797	RemoteAgent	TRACE	[282] ProgressListener Cleared the unload timer
[task 2023-08-31T13:14:05.799Z] PID 672 | 1693487645798	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 131088, status: 2152398850, isStart: false, isStop: true, isNetwork: false, isBindingAborted: true, targetURI: about:blank
[task 2023-08-31T13:14:05.799Z] PID 672 | 1693487645798	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.800Z] PID 672 | 1693487645799	RemoteAgent	TRACE	[31129fb4-9d1d-47d4-bab6-24ce5efef037] Navigation started for url: about:blank (7a17befa-785e-401b-9b51-6e2074a3e8ed)
[task 2023-08-31T13:14:05.802Z] PID 672 | 1693487645802	RemoteAgent	TRACE	[282] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-08-31T13:14:05.802Z] PID 672 | 1693487645802	RemoteAgent	TRACE	[282] ProgressListener Ignore aborted navigation error to the initial document, real document will be loaded.
[task 2023-08-31T13:14:05.803Z] PID 672 | 1693487645803	RemoteAgent	TRACE	[282] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-08-31T13:14:05.804Z] PID 672 | 1693487645803	RemoteAgent	TRACE	[31129fb4-9d1d-47d4-bab6-24ce5efef037] Skipping already tracked navigation, navigationId: 7a17befa-785e-401b-9b51-6e2074a3e8ed
[task 2023-08-31T13:14:05.805Z] PID 672 | 1693487645805	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 196610, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.808Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.808Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.808Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.808Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.808Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.809Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.809Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.809Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.809Z] PID 672 | 1693487645808	RemoteAgent	TRACE	Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.809Z] PID 672 | 1693487645809	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.809Z] PID 672 | 1693487645809	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.809Z] PID 672 | 1693487645809	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.811Z] PID 672 | 1693487645810	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.811Z] PID 672 | 1693487645810	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.813Z] PID 672 | 1693487645813	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae is being destroyed
[task 2023-08-31T13:14:05.813Z] PID 672 | 1693487645813	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.816Z] PID 672 | 1693487645815	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-08-31T13:14:05.818Z] PID 672 | 1693487645817	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.domContentLoaded","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","timestamp":1693487645815,"url":"about:blank","navigation":"7a17befa-785e-401b-9b51-6e2074a3e8ed"}}
[task 2023-08-31T13:14:05.819Z] PID 672 | 1693487645818	RemoteAgent	TRACE	[31129fb4-9d1d-47d4-bab6-24ce5efef037] Navigation finished for url: about:blank (7a17befa-785e-401b-9b51-6e2074a3e8ed)
[task 2023-08-31T13:14:05.819Z] PID 672 | 1693487645818	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.load","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","timestamp":1693487645815,"url":"about:blank","navigation":"7a17befa-785e-401b-9b51-6e2074a3e8ed"}}
[task 2023-08-31T13:14:05.821Z] PID 672 | 1693487645820	RemoteAgent	TRACE	[282] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-08-31T13:14:05.821Z] PID 672 | 1693487645820	RemoteAgent	TRACE	[282] ProgressListener state=stop: about:blank
[task 2023-08-31T13:14:05.821Z] PID 672 | 1693487645821	RemoteAgent	TRACE	[282] ProgressListener Stop: has error=false
[task 2023-08-31T13:14:05.824Z] PID 672 | 1693487645824	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"success","id":2102,"result":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037"}}
[task 2023-08-31T13:14:05.830Z] PID 672 | 1693487645829	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 -> {"id":2103,"method":"cdp.getSession","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037"}}
[task 2023-08-31T13:14:05.831Z] PID 672 | 1693487645829	RemoteAgent	TRACE	Module root/cdp.sys.mjs not found for ROOT
[task 2023-08-31T13:14:05.831Z] PID 672 | 1693487645829	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"error","id":2103,"error":"unknown command","message":"cdp.getSession","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... ontent/server/WebSocketTransport.sys.mjs:127:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.sys.mjs:109:14\n"}
[task 2023-08-31T13:14:05.831Z] PID 672 | 1693487645830	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 -> {"id":2104,"method":"browsingContext.navigate","params":{"url":"http://localhost:45632/empty.html","context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","wait":"complete"}}
[task 2023-08-31T13:14:05.831Z] PID 672 | 1693487645830	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.831Z] PID 672 | 1693487645830	RemoteAgent	TRACE	Received command browsingContext.navigate for destination ROOT
[task 2023-08-31T13:14:05.832Z] PID 672 | 1693487645830	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-08-31T13:14:05.832Z] PID 672 | 1693487645830	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.832Z] PID 672 | 1693487645830	RemoteAgent	TRACE	Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.833Z] PID 672 | 1693487645830	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.833Z] PID 672 | 1693487645831	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.833Z] PID 672 | 1693487645831	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.833Z] PID 672 | 1693487645831	RemoteAgent	TRACE	[282] ProgressListener Start: expectNavigation=true resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=true
[task 2023-08-31T13:14:05.833Z] PID 672 | 1693487645831	RemoteAgent	TRACE	[282] ProgressListener Skip setting the unload timer
[task 2023-08-31T13:14:05.833Z] PID 672 | 1693487645832	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.836Z] PID 672 | 1693487645835	RemoteAgent	TRACE	[31129fb4-9d1d-47d4-bab6-24ce5efef037] Navigation started for url: http://localhost:45632/empty.html (e996e01c-8ab5-41c9-88f5-7bc07d0a0f4d)
[task 2023-08-31T13:14:05.837Z] PID 672 | 1693487645836	RemoteAgent	TRACE	[282] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-08-31T13:14:05.837Z] PID 672 | 1693487645836	RemoteAgent	TRACE	[282] ProgressListener state=start: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.839Z] PID 672 | 1693487645838	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.beforeRequestSent","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","navigation":"e996e01c-8ab5-41c9-88f5-7bc07d0a0f4d","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"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":"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":447,"method":"GET","request":"473","timings":{"timeOrigin":0,"requestTime":1693487645832041,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1693487645834599,"responseStart":1693487645835334,"responseEnd":1693487645835387},"url":"http://localhost:45632/empty.html"},"timestamp":1693487645838,"initiator":{"type":"other"}}}
[task 2023-08-31T13:14:05.840Z] PID 672 | 1693487645840	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.responseStarted","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","navigation":"e996e01c-8ab5-41c9-88f5-7bc07d0a0f4d","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"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":"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":447,"method":"GET","request":"473","timings":{"timeOrigin":0,"requestTime":1693487645832041,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1693487645834599,"responseStart":1693487645835334,"responseEnd":1693487645835387},"url":"http://localhost:45632/empty.html"},"timestamp":1693487645839,"response":{"bodySize":0,"content":{"size":null},"headersSize":197,"url":"http://localhost:45632/empty.html","bytesReceived":197,"fromCache":false,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"text/html; charset=utf-8"}},{"name":"Date","value":{"type":"string","value":"Thu, 31 Aug 2023 13:14:05 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Keep-Alive","value":{"type":"string","value":"timeout=5"}},{"name":"Content-Length","value":{"type":"string","value":"0"}}],"mimeType":"text/html;charset=utf-8","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-08-31T13:14:05.842Z] PID 672 | 1693487645841	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 196610, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.844Z] PID 672 | 1693487645843	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.responseCompleted","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","navigation":"e996e01c-8ab5-41c9-88f5-7bc07d0a0f4d","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"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":"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":447,"method":"GET","request":"473","timings":{"timeOrigin":0,"requestTime":1693487645832041,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1693487645834599,"responseStart":1693487645835334,"responseEnd":1693487645835387},"url":"http://localhost:45632/empty.html"},"timestamp":1693487645843,"response":{"bodySize":0,"content":{"size":0},"headersSize":197,"url":"http://localhost:45632/empty.html","bytesReceived":197,"fromCache":false,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"text/html; charset=utf-8"}},{"name":"Date","value":{"type":"string","value":"Thu, 31 Aug 2023 13:14:05 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Keep-Alive","value":{"type":"string","value":"timeout=5"}},{"name":"Content-Length","value":{"type":"string","value":"0"}}],"mimeType":"text/html;charset=utf-8","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-08-31T13:14:05.846Z] PID 672 | 1693487645846	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.846Z] PID 672 | 1693487645846	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.847Z] PID 672 | 1693487645846	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.847Z] PID 672 | 1693487645846	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.847Z] PID 672 | 1693487645846	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.848Z] PID 672 | 1693487645846	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.848Z] PID 672 | 1693487645846	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.848Z] PID 672 | 1693487645846	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.848Z] PID 672 | 1693487645847	RemoteAgent	TRACE	Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-08-31T13:14:05.848Z] PID 672 | 1693487645847	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.848Z] PID 672 | 1693487645847	RemoteAgent	TRACE	Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-08-31T13:14:05.849Z] PID 672 | 1693487645847	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.849Z] PID 672 | 1693487645848	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.849Z] PID 672 | 1693487645848	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.849Z] PID 672 | 1693487645849	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae is being destroyed
[task 2023-08-31T13:14:05.849Z] PID 672 | 1693487645849	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 882dfd27-012f-4ea6-a4b1-e95d068182ae
[task 2023-08-31T13:14:05.856Z] PID 672 | 1693487645855	RemoteAgent	TRACE	[282] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.864Z] PID 672 | 1693487645864	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.domContentLoaded","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","timestamp":1693487645855,"url":"http://localhost:45632/empty.html","navigation":"e996e01c-8ab5-41c9-88f5-7bc07d0a0f4d"}}
[task 2023-08-31T13:14:05.864Z] PID 672 | 1693487645864	RemoteAgent	TRACE	[31129fb4-9d1d-47d4-bab6-24ce5efef037] Navigation finished for url: http://localhost:45632/empty.html (e996e01c-8ab5-41c9-88f5-7bc07d0a0f4d)
[task 2023-08-31T13:14:05.865Z] PID 672 | 1693487645864	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"browsingContext.load","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","timestamp":1693487645855,"url":"http://localhost:45632/empty.html","navigation":"e996e01c-8ab5-41c9-88f5-7bc07d0a0f4d"}}
[task 2023-08-31T13:14:05.866Z] PID 672 | 1693487645866	RemoteAgent	TRACE	[282] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-08-31T13:14:05.866Z] PID 672 | 1693487645866	RemoteAgent	TRACE	[282] ProgressListener state=stop: http://localhost:45632/empty.html
[task 2023-08-31T13:14:05.866Z] PID 672 | 1693487645866	RemoteAgent	TRACE	[282] ProgressListener Stop: has error=false
[task 2023-08-31T13:14:05.867Z] PID 672 | 1693487645866	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"success","id":2104,"result":{"navigation":"e996e01c-8ab5-41c9-88f5-7bc07d0a0f4d","url":"http://localhost:45632/empty.html"}}
[task 2023-08-31T13:14:05.868Z] PID 672 | 1693487645868	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.beforeRequestSent","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","navigation":null,"redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"name":"Accept","value":{"type":"string","value":"image/avif,image/webp,*/*"}},{"name":"Accept-Language","value":{"type":"string","value":"en-US,en;q=0.5"}},{"name":"Accept-Encoding","value":{"type":"string","value":"gzip, deflate, br"}},{"name":"Referer","value":{"type":"string","value":"http://localhost:45632/empty.html"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Sec-Fetch-Dest","value":{"type":"string","value":"image"}},{"name":"Sec-Fetch-Mode","value":{"type":"string","value":"no-cors"}},{"name":"Sec-Fetch-Site","value":{"type":"string","value":"same-origin"}}],"headersSize":0,"method":"GET","request":"474","timings":{"timeOrigin":0,"requestTime":1693487645862982,"redirectStart":0,"redirectEnd":0,"fetchStart":1693487645868045,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":0,"responseStart":0,"responseEnd":0},"url":"http://localhost:45632/favicon.ico"},"timestamp":1693487645868,"initiator":{"type":"other"}}}
[task 2023-08-31T13:14:05.869Z] PID 672 | 1693487645869	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.responseStarted","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","navigation":null,"redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"name":"Accept","value":{"type":"string","value":"image/avif,image/webp,*/*"}},{"name":"Accept-Language","value":{"type":"string","value":"en-US,en;q=0.5"}},{"name":"Accept-Encoding","value":{"type":"string","value":"gzip, deflate, br"}},{"name":"Referer","value":{"type":"string","value":"http://localhost:45632/empty.html"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Sec-Fetch-Dest","value":{"type":"string","value":"image"}},{"name":"Sec-Fetch-Mode","value":{"type":"string","value":"no-cors"}},{"name":"Sec-Fetch-Site","value":{"type":"string","value":"same-origin"}}],"headersSize":0,"method":"GET","request":"474","timings":{"timeOrigin":0,"requestTime":1693487645862982,"redirectStart":0,"redirectEnd":0,"fetchStart":1693487645868045,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":0,"responseStart":0,"responseEnd":0},"url":"http://localhost:45632/favicon.ico"},"timestamp":1693487645869,"response":{"bodySize":0,"content":{"size":null},"headersSize":0,"url":"http://localhost:45632/favicon.ico","bytesReceived":0,"fromCache":true,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"image/vnd.microsoft.icon"}},{"name":"Date","value":{"type":"string","value":"Thu, 31 Aug 2023 13:09:35 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Keep-Alive","value":{"type":"string","value":"timeout=5"}},{"name":"Content-Length","value":{"type":"string","value":"70"}}],"mimeType":"image/vnd.microsoft.icon","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-08-31T13:14:05.870Z] PID 672 | 1693487645870	RemoteAgent	DEBUG	WebDriverBiDiConnection acd1efaf-0d2d-43b7-b892-f21dcd5f38a9 <- {"type":"event","method":"network.responseCompleted","params":{"context":"31129fb4-9d1d-47d4-bab6-24ce5efef037","navigation":null,"redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:45632"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"name":"Accept","value":{"type":"string","value":"image/avif,image/webp,*/*"}},{"name":"Accept-Language","value":{"type":"string","value":"en-US,en;q=0.5"}},{"name":"Accept-Encoding","value":{"type":"string","value":"gzip, deflate, br"}},{"name":"Referer","value":{"type":"string","value":"http://localhost:45632/empty.html"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Sec-Fetch-Dest","value":{"type":"string","value":"image"}},{"name":"Sec-Fetch-Mode","value":{"type":"string","value":"no-cors"}},{"name":"Sec-Fetch-Site","value":{"type":"string","value":"same-origin"}}],"headersSize":0,"method":"GET","request":"474","timings":{"timeOrigin":0,"requestTime":1693487645862982,"redirectStart":0,"redirectEnd":0,"fetchStart":1693487645868045,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":0,"responseStart":0,"responseEnd":0},"url":"http://localhost:45632/favicon.ico"},"timestamp":1693487645870,"response":{"bodySize":70,"content":{"size":70},"headersSize":0,"url":"http://localhost:45632/favicon.ico","bytesReceived":70,"fromCache":true,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"image/vnd.microsoft.icon"}},{"name":"Date","value":{"type":"string","value":"Thu, 31 Aug 2023 13:09:35 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Keep-Alive","value":{"type":"string","value":"timeout=5"}},{"name":"Content-Length","value":{"type":"string","value":"70"}}],"mimeType":"image/vnd.microsoft.icon","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-08-31T13:14:05.876Z] TEST-PASS | network Network Events should fire events in proper order (network.spec.js) | took 151ms
[task 2023-08-31T13:14:05.876Z] PID 672 | ["pass",{"title":"should fire events in proper order","fullTitle":"network Network Events should fire events in proper order","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/network.spec.js","duration":152,"currentRetry":0}]

Related failure here is: TypeError: can't access property "id", browsingContext is null. So it's similar to bug 1847563.

Depends on: 1846343
See Also: → 1847563

Julian, I assume that with bug 1846343 fixed we can also close this bug?

Flags: needinfo?(jdescottes)

I think so

Status: NEW → RESOLVED
Closed: 8 months ago
Flags: needinfo?(jdescottes)
Resolution: --- → FIXED
Target Milestone: --- → 119 Branch
You need to log in before you can comment on or make changes to this bug.