Closed Bug 1983311 Opened 5 months ago Closed 4 months ago

Perma Android TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/reload/wait.py | expected OK

Categories

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

Product:
Remote Protocol
Component:
WebDriver BiDi
Type:
defect
Points:
1

Tracking

(firefox-esr115 unaffected, firefox-esr128 unaffected, firefox-esr140 disabled, firefox142 disabled, firefox143 disabled, firefox144 fixed)

Status:
RESOLVED FIXED
Milestone:
144 Branch
Tracking Flags:
Tracking Status
firefox-esr115 --- unaffected
firefox-esr128 --- unaffected
firefox-esr140 --- disabled
firefox142 --- disabled
firefox143 --- disabled
firefox144 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jdescottes)

References

(Depends on 1 open bug, Blocks 1 open bug)

Details

(Keywords: intermittent-failure, Whiteboard: [webdriver:m17], [wptsync upstream])

Attachments

(1 file)

Bug 1983311 - [wdspec] Update tests and metadata for android failures in reload/wait.py
48 bytes, text/x-phabricator-request
Details | Review

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

08-15 14:49:46.798 21611 21636 I Gecko   : 1755269386798	RemoteAgent	DEBUG	WebDriverBiDiConnection 2a70f27e-1abb-4585-af3d-4dc8474857d6 -> {"id":6,"method":"browsingContext.reload","params":{"context":"9f6319d9-0a97-4148-934c-3217596057e5","wait":"none"}}
08-15 14:49:46.798 21611 21636 I Gecko   : 1755269386798	RemoteAgent	TRACE	Received command browsingContext.reload for destination ROOT
08-15 14:49:46.799 21611 21636 I Gecko   : 1755269386799	RemoteAgent	TRACE	[5] ProgressListener Start: expectNavigation=true resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=true
08-15 14:49:46.799 21611 21636 I Gecko   : 1755269386799	RemoteAgent	TRACE	[5] ProgressListener Skip setting the unload timer
08-15 14:49:46.838 21611 21636 I Gecko   : 1755269386838	RemoteAgent	TRACE	ParentWebProgressListener Loading state: flags: 983041, status: 0,  isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html [context=5]
08-15 14:49:46.839 21611 21636 I Gecko   : 1755269386839	RemoteAgent	TRACE	[9f6319d9-0a97-4148-934c-3217596057e5] Navigation started for url: https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html (82c4295b-e75b-4987-84a8-8bdc5a48c4ff)
08-15 14:49:46.839 21611 21636 I Gecko   : 1755269386839	RemoteAgent	TRACE	[5] ProgressListener Loading state: isStart=true isStop=false status=0x0, loadType=0x2, seenStartFlag=false
08-15 14:49:46.839 21611 21636 I Gecko   : 1755269386839	RemoteAgent	TRACE	[5] ProgressListener Started loading https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html
08-15 14:49:46.873 21820 21835 I Gecko   : 1755269386873	RemoteAgent	TRACE	Received DOM event pagehide for [object HTMLDocument]
08-15 14:49:46.883 21820 21835 I Gecko   : 1755269386883	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
08-15 14:49:46.884 21820 21835 I Gecko   : 1755269386884	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session 7eef3f80-7a95-4509-88d7-5843d8418feb
08-15 14:49:46.886 21611 21636 W GeckoViewAutofill: Disregarding old session 6e7119e6-8113-4865-b5f0-3ea4054d0ab7
08-15 14:49:46.887 21820 21835 W Isolated Web Content: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html" line: 0}]
08-15 14:49:46.899 21611 21611 D GeckoSession: handleMessage GeckoView:LocationChange uri=https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html
08-15 14:49:46.900 21820 21835 I Gecko   : 1755269386900	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 7eef3f80-7a95-4509-88d7-5843d8418feb is being destroyed
08-15 14:49:46.900 21820 21835 I Gecko   : 1755269386900	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 7eef3f80-7a95-4509-88d7-5843d8418feb
08-15 14:49:46.901 21611 21636 I Gecko   : 1755269386901	RemoteAgent	TRACE	[9f6319d9-0a97-4148-934c-3217596057e5] Navigation committed for url: https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html (82c4295b-e75b-4987-84a8-8bdc5a48c4ff)
08-15 14:49:46.901 21611 21636 I Gecko   : 1755269386901	RemoteAgent	TRACE	[5] ProgressListener Received "navigation-committed" event. Stopping the navigation.
08-15 14:49:46.901 21611 21636 I Gecko   : 1755269386901	RemoteAgent	TRACE	[5] ProgressListener Stop: has error=false url=https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html
08-15 14:49:46.901 21611 21636 I Gecko   : 1755269386901	RemoteAgent	DEBUG	WebDriverBiDiConnection 2a70f27e-1abb-4585-af3d-4dc8474857d6 <- {"type":"success","id":6,"result":{"navigation":"82c4295b-e75b-4987-84a8-8bdc5a48c4ff","url":"https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html"}}
08-15 14:49:46.925 21611 21636 I Gecko   : 1755269386925	RemoteAgent	TRACE	ParentWebProgressListener Loading state: flags: 786448, status: 0,  isStart: false, isStop: true, isNetwork: true, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html [context=5]
08-15 14:49:46.925 21611 21636 I Gecko   : 1755269386925	RemoteAgent	TRACE	[9f6319d9-0a97-4148-934c-3217596057e5] Navigation finished for url: https://web-platform.test:8443/webdriver/tests/support/html/beforeunload.html (82c4295b-e75b-4987-84a8-8bdc5a48c4ff)
08-15 14:49:46.931 21700 21727 E eglCodecCommon: glUtilsParamSize: unknow param 0x00008caa
08-15 14:49:47.270 21820 21835 E Isolated Web Content: [JavaScript Error: "uncaught exception: undefined"]
08-15 14:49:49.605 21611 21865 D ProfileInstaller: Installing profile for org.mozilla.geckoview.test_runner
08-15 14:49:51.212 21611 21636 D GeckoIdleService: Get idle time: time since reset 5438 msec
08-15 14:49:51.212 21611 21636 D GeckoIdleService: Idle timer callback: current idle time 5438 msec
08-15 14:49:51.212 21611 21636 D GeckoIdleService: next timeout 4294967289561 msec from now

Somehow we stall when running the test and then timeout:
https://treeherder.mozilla.org/logviewer?job_id=522518242&repo=try&lineNumber=14331-14339

[task 2025-08-15T14:49:45.031+00:00] 14:49:45     INFO - PID 2816 | 1755269385029	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2025-08-15T14:49:45.033+00:00] 14:49:45     INFO - PID 2816 | 1755269385031	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2025-08-15T14:49:45.134+00:00] 14:49:45     INFO - PID 2816 | 1755269385133	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2025-08-15T14:49:45.236+00:00] 14:49:45     INFO - PID 2816 | 1755269385235	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2025-08-15T14:49:45.337+00:00] 14:49:45     INFO - PID 2816 | 1755269385336	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2025-08-15T14:49:45.599+00:00] 14:49:45     INFO - PID 2816 | 1755269385598	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:36523.
[task 2025-08-15T14:49:46.150+00:00] 14:49:46     INFO - PID 2816 | 1755269386149	webdriver::server	DEBUG	<- 200 OK {"value":{"sessionId":"7eef3f80-7a95-4509-88d7-5843d8418feb","capabilities":{"acceptInsecureCerts":false,"browserNa ... le; rv:143.0) Gecko/143.0 Firefox/143.0","webSocketUrl":"ws://127.0.0.1:9222/session/7eef3f80-7a95-4509-88d7-5843d8418feb"}}}
[taskcluster 2025-08-15T14:59:06.303Z] [taskcluster-proxy] Successfully refreshed taskcluster-proxy credentials: task-client/BmMCKFmdRza6TpxnV9UT4g/0/on/us-west1-a/6059942293546370149/until/1755271146.287
[task 2025-08-15T15:00:46.234+00:00] 15:00:46     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/reload/wait.py | expected OK

Maybe the Isolated Web Content: [JavaScript Error: "uncaught exception: undefined"] entry from the adb logcat might be related and we are waiting for some event from GeckoView. This needs further investigation.

The test was disabled by a wpt downstream sync on bug 1946426 in Firefox 137.

Blocks: 1743116
Keywords: test-disabled
Summary: Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/reload/wait.py | expected OK → Perma TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/reload/wait.py | expected OK
Summary: Perma TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/reload/wait.py | expected OK → Perma Android TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/reload/wait.py | expected OK

That's Bug 1879324

Depends on: 1879324
Assignee: nobody → jdescottes
Status: NEW → ASSIGNED
Points: --- → 1
Whiteboard: [webdriver:m17]
Pushed by jdescottes@mozilla.com: https://github.com/mozilla-firefox/firefox/commit/2293a89b37ea https://hg.mozilla.org/integration/autoland/rev/98cfd9b7941d [wdspec] Update tests and metadata for android failures in reload/wait.py r=webdriver-reviewers,whimboo
Created web-platform-tests PR https://github.com/web-platform-tests/wpt/pull/54519 for changes under testing/web-platform/tests
Whiteboard: [webdriver:m17] → [webdriver:m17], [wptsync upstream]
Upstream PR merged by moz-wptsync-bot

Henrik do you remember why this was set as leave-open? Shouldn't we close it?

Status: ASSIGNED → RESOLVED
Closed: 4 months ago
Flags: needinfo?(hskupin)
Keywords: leave-open
Resolution: --- → FIXED

Looks like that all the bug references in the metadata file are now pointing to bug 1879324, and the test is no longer timing out. So yes, we can mark it as done.

status-firefox144: disabled → fixed
Flags: needinfo?(hskupin)
Keywords: test-disabled
Target Milestone: --- → 144 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: