2001934 - Perma TEST-UNEXPECTED-TIMEOUT | navigation Page.goto should fail when server returns 204 (navigation.spec.js) | expected PASS | single tracking bug

Reporter

Description

•

1 month ago

treeherder

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

[task 2025-11-24T07:29:09.997+00:00] TEST-START | navigation Page.goto should fail when server returns 204 (navigation.spec.js)
[task 2025-11-24T07:29:09.997+00:00] PID 741 | ["test-start",{"title":"should fail when server returns 204","fullTitle":"navigation Page.goto should fail when server returns 204","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/navigation.spec.js","currentRetry":0}]
[task 2025-11-24T07:29:09.997+00:00] PID 741 | 1763969349997	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 -> {"id":5748,"method":"browser.createUserContext","params":{}}
[task 2025-11-24T07:29:09.997+00:00] PID 741 | 1763969349997	RemoteAgent	TRACE	Received command browser.createUserContext for destination ROOT
[task 2025-11-24T07:29:09.998+00:00] PID 741 | 1763969349997	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"success","id":5748,"result":{"userContext":"4f730260-291d-42d7-a1fb-077f420110b7"}}
[task 2025-11-24T07:29:09.998+00:00] PID 741 | 1763969349998	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 -> {"id":5749,"method":"browsingContext.create","params":{"type":"tab","userContext":"4f730260-291d-42d7-a1fb-077f420110b7"}}
[task 2025-11-24T07:29:09.998+00:00] PID 741 | 1763969349998	RemoteAgent	TRACE	Received command browsingContext.create for destination ROOT
[task 2025-11-24T07:29:09.998+00:00] PID 741 | 1763969349998	RemoteAgent	TRACE	Received command browsingContext._awaitVisibilityState for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.000+00:00] PID 741 | 1763969350000	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.contextCreated","params":{"children":null,"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","originalOpener":null,"url":"about:blank","userContext":"4f730260-291d-42d7-a1fb-077f420110b7","clientWindow":"835d2b3a-e2e4-436c-b512-a12f70ba633a","parent":null}}
[task 2025-11-24T07:29:10.000+00:00] PID 741 | 1763969350000	RemoteAgent	TRACE	ParentWebProgressListener Start watching updates for browsing context [context=275]
[task 2025-11-24T07:29:10.000+00:00] PID 741 | 1763969350000	RemoteAgent	TRACE	ParentWebProgressListener The web progress was not monitored yet, adding a progress listener [context=275]
[task 2025-11-24T07:29:10.003+00:00] PID 741 | 1763969350003	RemoteAgent	TRACE	Received DOM event TabSelect for [object XULElement]
[task 2025-11-24T07:29:10.007+00:00] PID 741 | 1763969350006	RemoteAgent	TRACE	[275] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2025-11-24T07:29:10.007+00:00] PID 741 | 1763969350006	RemoteAgent	TRACE	[275] ProgressListener Setting unload timer (5000ms)
[task 2025-11-24T07:29:10.007+00:00] PID 741 | 1763969350006	RemoteAgent	TRACE	[275] Wait for initial navigation: isUncommittedInitial=true, isLoadingDocument=false
[task 2025-11-24T07:29:10.008+00:00] PID 741 | 1763969350008	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"script.realmCreated","params":{"realm":"14f9c103-ccfb-40d6-82c5-a99cd5abe8b7","origin":"null","context":"f3f7537c-ee53-4782-b33d-d611654f9d97","type":"window"}}
[task 2025-11-24T07:29:10.012+00:00] PID 741 | 1763969350012	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.013+00:00] PID 741 | 1763969350012	RemoteAgent	TRACE	Received command network._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.013+00:00] PID 741 | 1763969350013	RemoteAgent	TRACE	Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.014+00:00] PID 741 | 1763969350014	RemoteAgent	TRACE	Received command script._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.014+00:00] PID 741 | 1763969350014	RemoteAgent	TRACE	Received command input._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.014+00:00] PID 741 | 1763969350014	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 0f8cc5fb-4c81-4872-b313-6018abc8993c
[task 2025-11-24T07:29:10.015+00:00] PID 741 | 1763969350015	RemoteAgent	TRACE	ParentWebProgressListener Loading state: flags: 983041, status: 0,  isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: about:blank [context=275]
[task 2025-11-24T07:29:10.015+00:00] PID 741 | 1763969350015	RemoteAgent	TRACE	ParentWebProgressListener Skip initial navigation to about:blank [context=275]
[task 2025-11-24T07:29:10.015+00:00] PID 741 | 1763969350015	RemoteAgent	TRACE	WebDriverDocumentInsertedChild actor created for PID 11390
[task 2025-11-24T07:29:10.016+00:00] PID 741 | 1763969350015	RemoteAgent	TRACE	[275] ProgressListener Loading state: isStart=true isStop=false status=0x0, loadType=0x1, seenStartFlag=false
[task 2025-11-24T07:29:10.016+00:00] PID 741 | 1763969350015	RemoteAgent	TRACE	[275] ProgressListener Started loading about:blank
[task 2025-11-24T07:29:10.016+00:00] PID 741 | 1763969350015	RemoteAgent	TRACE	[275] ProgressListener Cleared the unload timer
[task 2025-11-24T07:29:10.016+00:00] PID 741 | 1763969350016	RemoteAgent	TRACE	[f3f7537c-ee53-4782-b33d-d611654f9d97] Skipping this navigation for url: about:blank, since it's an initial navigation.
[task 2025-11-24T07:29:10.020+00:00] PID 741 | 1763969350020	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.domContentLoaded","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","timestamp":1763969350019,"url":"about:blank","navigation":"c2cb4809-63df-4243-bdfb-3987096a5291"}}
[task 2025-11-24T07:29:10.020+00:00] PID 741 | 1763969350020	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.load","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","timestamp":1763969350020,"url":"about:blank","navigation":"c2cb4809-63df-4243-bdfb-3987096a5291"}}
[task 2025-11-24T07:29:10.021+00:00] PID 741 | 1763969350020	RemoteAgent	TRACE	ParentWebProgressListener Loading state: flags: 786448, status: 0,  isStart: false, isStop: true, isNetwork: true, isBindingAborted: false, targetURI: about:blank [context=275]
[task 2025-11-24T07:29:10.021+00:00] PID 741 | 1763969350020	RemoteAgent	TRACE	[f3f7537c-ee53-4782-b33d-d611654f9d97] Navigation finished for url: about:blank (c2cb4809-63df-4243-bdfb-3987096a5291)
[task 2025-11-24T07:29:10.021+00:00] PID 741 | 1763969350021	RemoteAgent	TRACE	[275] ProgressListener Loading state: isStart=false isStop=true status=0x0, loadType=0x1, seenStartFlag=true
[task 2025-11-24T07:29:10.021+00:00] PID 741 | 1763969350021	RemoteAgent	TRACE	[275] ProgressListener Stop: has error=false url=about:blank
[task 2025-11-24T07:29:10.029+00:00] PID 741 | 1763969350029	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"success","id":5749,"result":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97"}}
[task 2025-11-24T07:29:10.042+00:00] PID 741 | 1763969350042	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 -> {"id":5750,"method":"browsingContext.setViewport","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","viewport":{"width":800,"height":600},"devicePixelRatio":null}}
[task 2025-11-24T07:29:10.042+00:00] PID 741 | 1763969350042	RemoteAgent	TRACE	Received command browsingContext.setViewport for destination ROOT
[task 2025-11-24T07:29:10.045+00:00] PID 741 | 1763969350045	RemoteAgent	TRACE	Received command _configuration._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.046+00:00] PID 741 | 1763969350046	RemoteAgent	TRACE	Received command _configuration._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.047+00:00] PID 741 | 1763969350047	RemoteAgent	TRACE	Received command browsingContext._awaitViewportDimensions for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.069+00:00] PID 741 | 1763969350069	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"success","id":5750,"result":{}}
[task 2025-11-24T07:29:10.070+00:00] PID 741 | 1763969350069	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 -> {"id":5751,"method":"browsingContext.navigate","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","url":"http://localhost:34805/empty.html","wait":"interactive"}}
[task 2025-11-24T07:29:10.070+00:00] PID 741 | 1763969350069	RemoteAgent	TRACE	Received command browsingContext.navigate for destination ROOT
[task 2025-11-24T07:29:10.070+00:00] PID 741 | 1763969350069	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.070+00:00] PID 741 | 1763969350070	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.073+00:00] PID 741 | 1763969350073	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.073+00:00] PID 741 | 1763969350073	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination ROOT
[task 2025-11-24T07:29:10.073+00:00] PID 741 | 1763969350073	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:10.073+00:00] PID 741 | 1763969350073	RemoteAgent	TRACE	[275] ProgressListener Start: expectNavigation=true resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=true
[task 2025-11-24T07:29:10.073+00:00] PID 741 | 1763969350073	RemoteAgent	TRACE	[275] ProgressListener Skip setting the unload timer
[task 2025-11-24T07:29:10.074+00:00] PID 741 | 1763969350074	RemoteAgent	TRACE	[f3f7537c-ee53-4782-b33d-d611654f9d97] Navigation started for url: http://localhost:34805/empty.html (a7e0da27-d948-478d-aa3c-0ff709f3f78c)
[task 2025-11-24T07:29:10.074+00:00] PID 741 | 1763969350074	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.navigationStarted","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","navigation":"a7e0da27-d948-478d-aa3c-0ff709f3f78c","timestamp":1763969350074,"url":"http://localhost:34805/empty.html"}}
[task 2025-11-24T07:29:10.074+00:00] PID 741 | 1763969350074	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"network.beforeRequestSent","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","isBlocked":false,"navigation":"a7e0da27-d948-478d-aa3c-0ff709f3f78c","redirectCount":0,"request":{"request":"555-dba8d05e-02dc-4054-af2c-5437616915e6","url":"http://localhost:34805/empty.html","method":"GET","bodySize":0,"headersSize":0,"headers":[{"name":"Host","value":{"type":"string","value":"localhost:34805"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0"}},{"name":"Accept","value":{"type":"string","value":"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;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, zstd"}},{"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"}}],"cookies":[],"destination":"document","initiatorType":null,"timings":{"timeOrigin":0,"requestTime":1763969350073.797,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":0,"responseStart":0,"responseEnd":0}},"timestamp":1763969350074,"initiator":{"type":"other"}}}
[task 2025-11-24T07:29:10.075+00:00] PID 741 | 1763969350075	RemoteAgent	TRACE	ParentWebProgressListener Loading state: flags: 983041, status: 0,  isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: http://localhost:34805/empty.html [context=275]
[task 2025-11-24T07:29:10.075+00:00] PID 741 | 1763969350075	RemoteAgent	TRACE	[f3f7537c-ee53-4782-b33d-d611654f9d97] Skipping already tracked navigation, navigationId: a7e0da27-d948-478d-aa3c-0ff709f3f78c
[task 2025-11-24T07:29:10.075+00:00] PID 741 | 1763969350075	RemoteAgent	TRACE	[275] ProgressListener Loading state: isStart=true isStop=false status=0x0, loadType=0x200001, seenStartFlag=false
[task 2025-11-24T07:29:10.075+00:00] PID 741 | 1763969350075	RemoteAgent	TRACE	[275] ProgressListener Started loading http://localhost:34805/empty.html
[task 2025-11-24T07:29:10.076+00:00] PID 741 | 1763969350076	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"network.responseStarted","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","isBlocked":false,"navigation":"a7e0da27-d948-478d-aa3c-0ff709f3f78c","redirectCount":0,"request":{"request":"555-dba8d05e-02dc-4054-af2c-5437616915e6","url":"http://localhost:34805/empty.html","method":"GET","bodySize":0,"headersSize":449,"headers":[{"name":"Host","value":{"type":"string","value":"localhost:34805"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0"}},{"name":"Accept","value":{"type":"string","value":"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;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, zstd"}},{"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"}},{"name":"Priority","value":{"type":"string","value":"u=0, i"}}],"cookies":[],"destination":"document","initiatorType":null,"timings":{"timeOrigin":0,"requestTime":1763969350073.797,"redirectStart":0,"redirectEnd":0,"fetchStart":1763969350074.977,"dnsStart":1763969350074.977,"dnsEnd":1763969350075.011,"connectStart":1763969350075.084,"connectEnd":1763969350075.188,"tlsStart":0,"tlsEnd":1763969350075.188,"requestStart":1763969350075.278,"responseStart":1763969350075.628,"responseEnd":1763969350075.65}},"timestamp":1763969350076,"response":{"url":"http://localhost:34805/empty.html","protocol":"http/1.1","status":204,"statusText":"No Content","fromCache":false,"headers":[{"name":"Date","value":{"type":"string","value":"Mon, 24 Nov 2025 07:29:10 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}}],"mimeType":"application/x-unknown-content-type","bytesReceived":88,"headersSize":88,"bodySize":0,"content":{"size":0}}}}
[task 2025-11-24T07:29:10.076+00:00] PID 741 | 1763969350076	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"network.fetchError","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","isBlocked":false,"navigation":"a7e0da27-d948-478d-aa3c-0ff709f3f78c","redirectCount":0,"request":{"request":"555-dba8d05e-02dc-4054-af2c-5437616915e6","url":"http://localhost:34805/empty.html","method":"GET","bodySize":0,"headersSize":449,"headers":[{"name":"Host","value":{"type":"string","value":"localhost:34805"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0"}},{"name":"Accept","value":{"type":"string","value":"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;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, zstd"}},{"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"}},{"name":"Priority","value":{"type":"string","value":"u=0, i"}}],"cookies":[],"destination":"document","initiatorType":null,"timings":{"timeOrigin":0,"requestTime":1763969350073.797,"redirectStart":0,"redirectEnd":0,"fetchStart":1763969350074.047,"dnsStart":1763969350074.047,"dnsEnd":1763969350074.966,"connectStart":1763969350075.084,"connectEnd":1763969350075.188,"tlsStart":0,"tlsEnd":1763969350075.188,"requestStart":1763969350075.278,"responseStart":1763969350075.628,"responseEnd":1763969350075.65}},"timestamp":1763969350076,"errorText":"NS_BINDING_ABORTED"}}
[task 2025-11-24T07:29:10.078+00:00] PID 741 | 1763969350078	RemoteAgent	TRACE	Received command network._setDecodedBodySize for destination ROOT
[task 2025-11-24T07:29:10.078+00:00] PID 741 | 1763969350078	RemoteAgent	TRACE	network.interceptEvent returned null, skipping event: network._beforeStopRequest, data: [object Object]
[task 2025-11-24T07:29:10.078+00:00] PID 741 | 1763969350078	RemoteAgent	TRACE	ParentWebProgressListener Loading state: flags: 786448, status: 2152398850,  isStart: false, isStop: true, isNetwork: true, isBindingAborted: true, targetURI: http://localhost:34805/empty.html [context=275]
[task 2025-11-24T07:29:10.079+00:00] PID 741 | 1763969350079	RemoteAgent	TRACE	[275] ProgressListener Loading state: isStart=false isStop=true status=0x804b0002, loadType=0x200001, seenStartFlag=true
[task 2025-11-24T07:29:10.079+00:00] PID 741 | 1763969350079	RemoteAgent	TRACE	[275] ProgressListener Ignore aborted navigation error to the initial document.
[task 2025-11-24T07:29:30.015+00:00] TEST-UNEXPECTED-TIMEOUT | navigation Page.goto should fail when server returns 204 (navigation.spec.js) | expected PASS
[task 2025-11-24T07:29:30.015+00:00] TEST-INFO took 20018ms
[task 2025-11-24T07:29:30.015+00:00] PID 741 | ["fail",{"title":"should fail when server returns 204","fullTitle":"navigation Page.goto should fail when server returns 204","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/navigation.spec.js","duration":20017,"currentRetry":0,"err":"Timeout of 20000ms exceeded. For async tests and hooks, ensure \"done()\" is called; if returning a Promise, ensure it resolves. (/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/navigation.spec.js)","stack":"Error: Timeout of 20000ms exceeded. For async tests and hooks, ensure \"done()\" is called; if returning a Promise, ensure it resolves. (/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/navigation.spec.js)\n    at createTimeoutError (/builds/worker/checkouts/gecko/remote/test/puppeteer/node_modules/mocha/lib/errors.js:498:15)\n    at Test.Runnable._timeoutError (/builds/worker/checkouts/gecko/remote/test/puppeteer/node_modules/mocha/lib/runnable.js:431:10)\n    at Timeout.<anonymous> (/builds/worker/checkouts/gecko/remote/test/puppeteer/node_modules/mocha/lib/runnable.js:246:24)\n    at listOnTimeout (node:internal/timers:588:17)\n    at processTimers (node:internal/timers:523:7)"}]
[task 2025-11-24T07:29:30.016+00:00] PID 741 | 1763969370015	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 -> {"id":5752,"method":"browser.removeUserContext","params":{"userContext":"4f730260-291d-42d7-a1fb-077f420110b7"}}
[task 2025-11-24T07:29:30.016+00:00] PID 741 | 1763969370016	RemoteAgent	TRACE	Received command browser.removeUserContext for destination ROOT
[task 2025-11-24T07:29:30.023+00:00] PID 741 | 1763969370023	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.contextDestroyed","params":{"children":[],"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","originalOpener":null,"url":"about:blank","userContext":"4f730260-291d-42d7-a1fb-077f420110b7","clientWindow":"835d2b3a-e2e4-436c-b512-a12f70ba633a","parent":null}}
[task 2025-11-24T07:29:30.023+00:00] PID 741 | 1763969370023	RemoteAgent	TRACE	ParentWebProgressListener Stop watching updates for browsing context [context=275]
[task 2025-11-24T07:29:30.023+00:00] PID 741 | 1763969370023	RemoteAgent	TRACE	ParentWebProgressListener All browsing contexts for this web progress deleted, removing the progress listener [context=275]
[task 2025-11-24T07:29:30.025+00:00] PID 741 | 1763969370023	RemoteAgent	TRACE	ParentWebProgressListener Removing the web progress from monitored web progress [context=275]
[task 2025-11-24T07:29:30.025+00:00] PID 741 | 1763969370023	RemoteAgent	TRACE	Received DOM event pagehide for [object HTMLDocument]
[task 2025-11-24T07:29:30.025+00:00] PID 741 | 1763969370023	RemoteAgent	TRACE	[f3f7537c-ee53-4782-b33d-d611654f9d97] Navigation failed for url: http://localhost:34805/empty.html (a7e0da27-d948-478d-aa3c-0ff709f3f78c)
[task 2025-11-24T07:29:30.025+00:00] PID 741 | 1763969370023	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.navigationFailed","params":{"context":"f3f7537c-ee53-4782-b33d-d611654f9d97","navigation":"a7e0da27-d948-478d-aa3c-0ff709f3f78c","timestamp":1763969370023,"url":"http://localhost:34805/empty.html"}}
[task 2025-11-24T07:29:30.025+00:00] PID 741 | 1763969370023	RemoteAgent	TRACE	[275] ProgressListener Received "navigation-failed" event with error=Browsing context got discarded. Stopping the navigation.
[task 2025-11-24T07:29:30.025+00:00] PID 741 | 1763969370023	RemoteAgent	TRACE	[275] ProgressListener Stop: has error=true url=about:blank
[task 2025-11-24T07:29:30.025+00:00] PID 741 | 1763969370024	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 0f8cc5fb-4c81-4872-b313-6018abc8993c is being destroyed
[task 2025-11-24T07:29:30.025+00:00] PID 741 | 1763969370024	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 0f8cc5fb-4c81-4872-b313-6018abc8993c
[task 2025-11-24T07:29:30.029+00:00] PID 741 | 1763969370029	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:30.029+00:00] PID 741 | 1763969370029	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination ROOT
[task 2025-11-24T07:29:30.029+00:00] PID 741 | 1763969370029	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"success","id":5752,"result":{}}
[task 2025-11-24T07:29:30.030+00:00] TEST-START | navigation Page.goto should navigate to empty page with domcontentloaded (navigation.spec.js)
[task 2025-11-24T07:29:30.030+00:00] PID 741 | ["test-start",{"title":"should navigate to empty page with domcontentloaded","fullTitle":"navigation Page.goto should navigate to empty page with domcontentloaded","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/navigation.spec.js","currentRetry":0}]
[task 2025-11-24T07:29:30.030+00:00] PID 741 | 1763969370030	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"error","id":5751,"error":"unknown error","message":"Error: Browsing context got discarded","stacktrace":"#onNavigationFailed@chrome://remote/content/shared/Navigate.sys.mjs:403:26\nemit@resource://gre/modules/EventEmitter.sys.mjs:1 ... ontent/server/WebSocketTransport.sys.mjs:127:18\nhandleEvent@chrome://remote/content/server/WebSocketTransport.sys.mjs:109:14\n"}
[task 2025-11-24T07:29:30.032+00:00] PID 741 | 1763969370032	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"script.realmDestroyed","params":{"realm":"14f9c103-ccfb-40d6-82c5-a99cd5abe8b7"}}
[task 2025-11-24T07:29:30.033+00:00] PID 741 | 1763969370032	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 -> {"id":5753,"method":"browser.createUserContext","params":{}}
[task 2025-11-24T07:29:30.033+00:00] PID 741 | 1763969370032	RemoteAgent	TRACE	Received command browser.createUserContext for destination ROOT
[task 2025-11-24T07:29:30.033+00:00] PID 741 | 1763969370033	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"success","id":5753,"result":{"userContext":"66acbd6c-7729-4559-9524-fe4aaa2488de"}}
[task 2025-11-24T07:29:30.073+00:00] PID 741 | 1763969370073	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 -> {"id":5754,"method":"browsingContext.create","params":{"type":"tab","userContext":"66acbd6c-7729-4559-9524-fe4aaa2488de"}}
[task 2025-11-24T07:29:30.073+00:00] PID 741 | 1763969370073	RemoteAgent	TRACE	Received command browsingContext.create for destination ROOT
[task 2025-11-24T07:29:30.074+00:00] PID 741 | 1763969370073	RemoteAgent	TRACE	Received command browsingContext._awaitVisibilityState for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:30.076+00:00] PID 741 | 1763969370076	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.contextCreated","params":{"children":null,"context":"eef7b7f5-ac90-49bf-925d-6ba197fba28e","originalOpener":null,"url":"about:blank","userContext":"66acbd6c-7729-4559-9524-fe4aaa2488de","clientWindow":"835d2b3a-e2e4-436c-b512-a12f70ba633a","parent":null}}
[task 2025-11-24T07:29:30.076+00:00] PID 741 | 1763969370076	RemoteAgent	TRACE	ParentWebProgressListener Start watching updates for browsing context [context=276]
[task 2025-11-24T07:29:30.076+00:00] PID 741 | 1763969370076	RemoteAgent	TRACE	ParentWebProgressListener The web progress was not monitored yet, adding a progress listener [context=276]
[task 2025-11-24T07:29:30.083+00:00] PID 741 | 1763969370082	RemoteAgent	TRACE	Received DOM event TabSelect for [object XULElement]
[task 2025-11-24T07:29:30.087+00:00] PID 741 | 1763969370087	RemoteAgent	TRACE	[276] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2025-11-24T07:29:30.087+00:00] PID 741 | 1763969370087	RemoteAgent	TRACE	[276] ProgressListener Setting unload timer (5000ms)
[task 2025-11-24T07:29:30.088+00:00] PID 741 | 1763969370087	RemoteAgent	TRACE	[276] Wait for initial navigation: isUncommittedInitial=true, isLoadingDocument=false
[task 2025-11-24T07:29:30.088+00:00] PID 741 | 1763969370088	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"script.realmCreated","params":{"realm":"a20721db-e97d-4444-aa63-454a0b7101fc","origin":"null","context":"eef7b7f5-ac90-49bf-925d-6ba197fba28e","type":"window"}}
[task 2025-11-24T07:29:30.091+00:00] PID 741 | 1763969370091	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:30.092+00:00] PID 741 | 1763969370091	RemoteAgent	TRACE	Received command network._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:30.092+00:00] PID 741 | 1763969370092	RemoteAgent	TRACE	Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:30.093+00:00] PID 741 | 1763969370093	RemoteAgent	TRACE	Received command script._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:30.093+00:00] PID 741 | 1763969370093	RemoteAgent	TRACE	Received command input._applySessionData for destination WINDOW_GLOBAL
[task 2025-11-24T07:29:30.093+00:00] PID 741 | 1763969370093	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 0f8cc5fb-4c81-4872-b313-6018abc8993c
[task 2025-11-24T07:29:30.094+00:00] PID 741 | 1763969370094	RemoteAgent	TRACE	ParentWebProgressListener Loading state: flags: 983041, status: 0,  isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: about:blank [context=276]
[task 2025-11-24T07:29:30.094+00:00] PID 741 | 1763969370094	RemoteAgent	TRACE	ParentWebProgressListener Skip initial navigation to about:blank [context=276]
[task 2025-11-24T07:29:30.094+00:00] PID 741 | 1763969370094	RemoteAgent	TRACE	WebDriverDocumentInsertedChild actor created for PID 11396
[task 2025-11-24T07:29:30.094+00:00] PID 741 | 1763969370094	RemoteAgent	TRACE	[276] ProgressListener Loading state: isStart=true isStop=false status=0x0, loadType=0x1, seenStartFlag=false
[task 2025-11-24T07:29:30.094+00:00] PID 741 | 1763969370094	RemoteAgent	TRACE	[276] ProgressListener Started loading about:blank
[task 2025-11-24T07:29:30.094+00:00] PID 741 | 1763969370094	RemoteAgent	TRACE	[276] ProgressListener Cleared the unload timer
[task 2025-11-24T07:29:30.096+00:00] PID 741 | 1763969370095	RemoteAgent	TRACE	[eef7b7f5-ac90-49bf-925d-6ba197fba28e] Skipping this navigation for url: about:blank, since it's an initial navigation.
[task 2025-11-24T07:29:30.098+00:00] PID 741 | 1763969370098	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.domContentLoaded","params":{"context":"eef7b7f5-ac90-49bf-925d-6ba197fba28e","timestamp":1763969370098,"url":"about:blank","navigation":"eb18e1d2-a1a1-4659-8a7f-beba70610bc3"}}
[task 2025-11-24T07:29:30.099+00:00] PID 741 | 1763969370099	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"event","method":"browsingContext.load","params":{"context":"eef7b7f5-ac90-49bf-925d-6ba197fba28e","timestamp":1763969370098,"url":"about:blank","navigation":"eb18e1d2-a1a1-4659-8a7f-beba70610bc3"}}
[task 2025-11-24T07:29:30.100+00:00] PID 741 | 1763969370100	RemoteAgent	TRACE	ParentWebProgressListener Loading state: flags: 786448, status: 0,  isStart: false, isStop: true, isNetwork: true, isBindingAborted: false, targetURI: about:blank [context=276]
[task 2025-11-24T07:29:30.100+00:00] PID 741 | 1763969370100	RemoteAgent	TRACE	[eef7b7f5-ac90-49bf-925d-6ba197fba28e] Navigation finished for url: about:blank (eb18e1d2-a1a1-4659-8a7f-beba70610bc3)
[task 2025-11-24T07:29:30.101+00:00] PID 741 | 1763969370100	RemoteAgent	TRACE	[276] ProgressListener Loading state: isStart=false isStop=true status=0x0, loadType=0x1, seenStartFlag=true
[task 2025-11-24T07:29:30.101+00:00] PID 741 | 1763969370100	RemoteAgent	TRACE	[276] ProgressListener Stop: has error=false url=about:blank
[task 2025-11-24T07:29:30.101+00:00] PID 741 | 1763969370101	RemoteAgent	DEBUG	WebDriverBiDiConnection dff9c867-8d90-4542-b3ed-f48696f6d817 <- {"type":"success","id":5754,"result":{"context":"eef7b7f5-ac90-49bf-925d-6ba197fba28e"}}

Cristina Horotan [:chorotan]

Updated

•

1 month ago

Regressed by: 1543435
No longer regressed by: sync-about-blank

Vincent Hilla [:vhilla]

Assignee

Updated

•

1 month ago

Regressed by: sync-about-blank
No longer regressed by: 1543435

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

Updated

•

1 month ago

Component: DOM: Navigation → Agent

Product: Core → Remote Protocol

BugBot [:suhaib / :marco/ :calixte]

Comment 1

•

1 month ago

:vhilla, since you are the author of the regressor, bug 543435, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(vhilla)

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

Updated

•

1 month ago

status-firefox145: --- → unaffected

status-firefox146: --- → unaffected

status-firefox147: --- → affected

status-firefox-esr115: --- → unaffected

status-firefox-esr140: --- → unaffected

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

Updated

•

1 month ago

Duplicate of this bug: 2001939

Vincent Hilla [:vhilla]

Assignee

Comment 3

•

1 month ago

Attached file Bug 2001934 - Disable Puppeteer Page.goto 204 test for perma-failing. r=whimboo! — Details

The test "navigation Page.goto should fail when server returns 204" in
navigation.spec.ts started perma-failing when bug 543435 landed.

Phabricator Automation

Updated

•

1 month ago

Assignee: nobody → vhilla

Status: NEW → ASSIGNED

Pulsebot

Comment 4

•

1 month ago

Pushed by chorotan@mozilla.com: https://github.com/mozilla-firefox/firefox/commit/62878264ee03 https://hg.mozilla.org/integration/autoland/rev/e562b30c7d29 Disable Puppeteer Page.goto 204 test for perma-failing. r=whimboo!

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

Comment 5

•

1 month ago

Going to mark as leave-open because we need to fix this perma failure.

Keywords: leave-open, test-disabled

Vincent Hilla [:vhilla]

Assignee

Comment 6

•

1 month ago

I'll try to figure out why the test fails.

Form a first look, this here seems to be the problem. But I'll have to check if this is needed in case the navigation to about:blank aborts somehow.
https://searchfox.org/firefox-main/rev/ac83682a4bb79a331262395ba5237ee621454275/remote/shared/Navigate.sys.mjs#344-352

// Handle an aborted navigation. While for an initial document another
// navigation to the real document will happen it's not the case for
// normal documents. Here we need to stop the listener immediately.
if (status == Cr.NS_BINDING_ABORTED && this.isInitialDocument) {

Vincent Hilla [:vhilla]

Assignee

Comment 7

•

1 month ago

Attached file Bug 2001934 - Fix Puppeteer Page.goto timeout for 204 page. r=whimboo — Details

Vincent Hilla [:vhilla]

Assignee

Comment 8

•

1 month ago

https://treeherder.mozilla.org/jobs?repo=try&revision=32ad744a82595406a58f8a1d1144db3d611528d0

Removing that code passes on try.

I assume this code is for the case where one synchronously creates a window and navigates it, thereby canceling the about:blank in favor of the real document. I.e. window.open("about:blank").location = other. With the initial navigation to about:blank being sync this should not be relevant anymore.

I initially thought it would be impossible to cancel the initial about:blank navigation. But I think a location change listener or so might be able to explicitly cancel the aboutBlankChannel. That would leave the document in a weird state where it is committed but no load event fired.

Flags: needinfo?(vhilla)

pstanciu

Comment 9

•

1 month ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/e562b30c7d29

Pulsebot

Comment 10

•

1 month ago

Pushed by vhilla@mozilla.com: https://github.com/mozilla-firefox/firefox/commit/e68671efd8c8 https://hg.mozilla.org/integration/autoland/rev/78a8eff85171 Fix Puppeteer Page.goto timeout for 204 page. r=whimboo

Cosmin Sabou [:CosminS]

Comment 11

•

1 month ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/78a8eff85171

Comment hidden (Intermittent Failures Robot)

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

Comment 13

•

1 month ago

This bug is fixed now. Thanks Vincent.

Note that I should still check if we need a WebDriver test for this scenario.

Status: ASSIGNED → RESOLVED

Closed: 1 month ago

status-firefox147: affected → fixed

Flags: needinfo?(hskupin)

Keywords: leave-open, test-disabled

Resolution: --- → FIXED

Whiteboard: [webdriver:m18][webdriver:external]

Target Milestone: --- → 147 Branch

Ryan VanderMeulen [:RyanVM]

Updated

•

1 month ago

status-firefox147: fixed → disabled

status-firefox148: --- → fixed

Treeherder Bug Filer

Reporter

Updated

•

1 month ago

Regressions: 2005874

Comment hidden (Intermittent Failures Robot)

Bug 2001934 - Disable Puppeteer Page.goto 204 test for perma-failing. r=whimboo! 1 month ago Vincent Hilla [:vhilla] 48 bytes, text/x-phabricator-request		Details \| Review
Bug 2001934 - Fix Puppeteer Page.goto timeout for 204 page. r=whimboo 1 month ago Vincent Hilla [:vhilla] 48 bytes, text/x-phabricator-request		Details \| Review

Perma TEST-UNEXPECTED-TIMEOUT | navigation Page.goto should fail when server returns 204 (navigation.spec.js) | expected PASS | single tracking bug

Repository breakdown:

Table

For more details, see:

Repository breakdown:

Table

For more details, see: