1865759 - Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/fragment_navigated/fragment_navigated.py | expected OK

Status: REOPENED

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

Description

[Treeherder Bug Filer]

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

---

[task 2023-11-21T00:48:34.854Z] 00:48:34     INFO - STDOUT: PASSED
[task 2023-11-21T00:48:34.968Z] 00:48:34     INFO - PID 2672 | 1700527714967	webdriver::server	DEBUG	-> POST /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/timeouts {"implicit": 0}
[task 2023-11-21T00:48:34.973Z] 00:48:34     INFO - PID 2672 | 1700527714973	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-21T00:48:34.974Z] 00:48:34     INFO - PID 2672 | 1700527714974	webdriver::server	DEBUG	-> POST /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/timeouts {"pageLoad": 300000}
[task 2023-11-21T00:48:34.979Z] 00:48:34     INFO - PID 2672 | 1700527714979	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-21T00:48:34.980Z] 00:48:34     INFO - PID 2672 | 1700527714979	webdriver::server	DEBUG	-> POST /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/timeouts {"script": 30000}
[task 2023-11-21T00:48:34.986Z] 00:48:34     INFO - PID 2672 | 1700527714985	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-21T00:48:34.986Z] 00:48:34     INFO - PID 2672 | 1700527714986	webdriver::server	DEBUG	-> GET /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/window
[task 2023-11-21T00:48:34.992Z] 00:48:34     INFO - PID 2672 | 1700527714992	webdriver::server	DEBUG	<- 200 OK {"value":"83873805-1ef0-4e21-94cf-9502dced4c70"}
[task 2023-11-21T00:48:34.992Z] 00:48:34     INFO - PID 2672 | 1700527714992	webdriver::server	DEBUG	-> GET /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/window
[task 2023-11-21T00:48:34.997Z] 00:48:34     INFO - PID 2672 | 1700527714997	webdriver::server	DEBUG	<- 200 OK {"value":"83873805-1ef0-4e21-94cf-9502dced4c70"}
[task 2023-11-21T00:48:34.998Z] 00:48:34     INFO - PID 2672 | 1700527714997	webdriver::server	DEBUG	-> GET /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/window/handles
[task 2023-11-21T00:48:35.004Z] 00:48:35     INFO - PID 2672 | 1700527715003	webdriver::server	DEBUG	<- 200 OK {"value":["83873805-1ef0-4e21-94cf-9502dced4c70"]}
[task 2023-11-21T00:48:35.004Z] 00:48:35     INFO - PID 2672 | 1700527715004	webdriver::server	DEBUG	-> POST /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/window {"handle": "83873805-1ef0-4e21-94cf-9502dced4c70"}
[task 2023-11-21T00:48:35.010Z] 00:48:35     INFO - PID 2672 | 1700527715009	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-21T00:48:35.010Z] 00:48:35     INFO - PID 2672 | 1700527715010	webdriver::server	DEBUG	-> POST /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/alert/dismiss {}
[task 2023-11-21T00:48:35.023Z] 00:48:35     INFO - PID 2672 | 1700527715022	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:263:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:494:20\n"}}
[task 2023-11-21T00:48:35.023Z] 00:48:35     INFO - PID 2672 | 1700527715022	webdriver::server	DEBUG	-> POST /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/window {"handle": "83873805-1ef0-4e21-94cf-9502dced4c70"}
[task 2023-11-21T00:48:35.028Z] 00:48:35     INFO - PID 2672 | 1700527715028	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-21T00:48:35.029Z] 00:48:35     INFO - PID 2672 | 1700527715028	webdriver::server	DEBUG	-> GET /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/window
[task 2023-11-21T00:48:35.034Z] 00:48:35     INFO - PID 2672 | 1700527715033	webdriver::server	DEBUG	<- 200 OK {"value":"83873805-1ef0-4e21-94cf-9502dced4c70"}
[task 2023-11-21T00:48:35.034Z] 00:48:35     INFO - PID 2672 | 1700527715033	webdriver::server	DEBUG	-> GET /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/window/handles
[task 2023-11-21T00:48:35.038Z] 00:48:35     INFO - PID 2672 | 1700527715038	webdriver::server	DEBUG	<- 200 OK {"value":["83873805-1ef0-4e21-94cf-9502dced4c70"]}
[task 2023-11-21T00:48:35.039Z] 00:48:35     INFO - PID 2672 | 1700527715038	webdriver::server	DEBUG	-> POST /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/window {"handle": "83873805-1ef0-4e21-94cf-9502dced4c70"}
[task 2023-11-21T00:48:35.045Z] 00:48:35     INFO - PID 2672 | 1700527715044	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-21T00:48:35.045Z] 00:48:35     INFO - PID 2672 | 1700527715044	webdriver::server	DEBUG	-> POST /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42/frame {"id": null}
[task 2023-11-21T00:48:35.055Z] 00:48:35     INFO - PID 2672 | 1700527715054	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-11-21T00:48:35.057Z] 00:48:35     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/browsing_context/fragment_navigated/fragment_navigated.py::test_browsing_context_navigate[#foo-#bar] 
[task 2023-11-21T00:50:08.140Z] 00:50:08     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/fragment_navigated/fragment_navigated.py | expected OK
[task 2023-11-21T00:50:08.140Z] 00:50:08     INFO - TEST-INFO took 105003ms

Comment 1

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

That's an Android build and GeckoView is crashing as it looks like:

11-21 00:48:35.119  3450  3465 I Gecko   : 1700527715119	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
11-21 00:48:35.134  3515  3546 D EGL_emulation: eglMakeCurrent: 0x75b984e7bea0: ver 3 0 (tinfo 0x75b97a7b6ac0)
11-21 00:48:35.135  3515  3546 E eglCodecCommon: glUtilsParamSize: unknow param 0x00008caa
11-21 00:48:35.138  3983  3998 I Gecko   : 1700527715138	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session dc448e7d-5cd6-47f3-9641-7d06aa354b42
11-21 00:48:35.155  3983  3998 D GeckoViewSelectionActionDelegate[C]: handleEvent: visibilitychange
11-21 00:48:35.158  3983  3998 D GeckoViewClipboardPermissionChild[C]: handleEvent: deactivate
11-21 00:48:35.159  3450  3465 D GeckoViewClipboardPermissionParent: receiveMessage: DispatcherMessage
11-21 00:48:35.159  3450  3465 W GeckoEventDispatcher: No listener for GeckoView:DismissClipboardPermissionRequest
11-21 00:48:35.163  3983  3998 D GeckoViewSettings[C]: receiveMessage: SettingsUpdate
11-21 00:48:35.168  3450  3465 D GeckoViewXUL: receiveMessage GeckoView:ContentModuleLoaded {"module":"SessionStateAggregator"}
11-21 00:48:35.178  3515  3546 W webrender::device::gl: Cropping texture upload Box2D((0, 0), (0, 1)) to None
11-21 00:48:35.180  3515  3546 W webrender::device::gl: Cropping texture upload Box2D((0, 0), (0, 1)) to None
11-21 00:48:35.185  3515  3546 W webrender::device::gl: Cropping texture upload Box2D((0, 0), (0, 1)) to None
11-21 00:48:35.202  3515  3546 E eglCodecCommon: glUtilsParamSize: unknow param 0x00008caa
11-21 00:48:35.214  3515  3546 W webrender::device::gl: Cropping texture upload Box2D((0, 0), (0, 1)) to None
11-21 00:48:35.215  3515  3546 W webrender::device::gl: Cropping texture upload Box2D((0, 0), (0, 1)) to None
11-21 00:48:35.219  3515  3546 W webrender::device::gl: Cropping texture upload Box2D((0, 0), (0, 1)) to None
11-21 00:48:36.189  3450  3465 W GeckoEventDispatcher: No listener for GeckoView:StateUpdated
11-21 00:48:36.357  3450  3465 I Gecko   : nsWindow 0x75b952e04000 destructor
11-21 00:48:36.369  3450  3465 I Gecko   : nsWindow 0x75b951573400 destructor
11-21 00:48:36.370  3450  3465 I Gecko   : nsWindow 0x75b952910700 destructor
11-21 00:48:36.374  3450  3465 I Gecko   : nsWindow 0x75b95290fb00 destructor
11-21 00:48:36.374  3450  3465 I Gecko   : nsWindow 0x75b951a1de00 destructor
11-21 00:48:36.374  3450  3465 I Gecko   : nsWindow 0x75b95142c700 destructor
11-21 00:48:36.374  3450  3465 I Gecko   : nsWindow 0x75b951a1db00 destructor
11-21 00:48:36.374  3450  3465 I Gecko   : nsWindow 0x75b95142f400 destructor
11-21 00:48:36.374  3450  3465 I Gecko   : nsWindow 0x75b95293d500 destructor
11-21 00:49:00.347  1659  1659 I ActivityManager: Killing 2350:com.android.managedprovisioning/u0a15 (adj 906): empty #17
11-21 00:49:00.350  1659  2147 D ActivityManager: cleanUpApplicationRecord -- 2350
11-21 00:50:08.002  3450  3465 I Gecko   : 1700527808002	WebDriver BiDi	DEBUG	Unregistered session handler: /session/dc448e7d-5cd6-47f3-9641-7d06aa354b42
11-21 00:50:08.004  3478  3494 I Gecko   : 1700527808004	Marionette	TRACE	[2] MarionetteCommands actor destroyed for window id 2147483650
11-21 00:50:08.009  3450  3465 I Gecko   : 1700527808009	RemoteAgent	DEBUG	WebDriverBiDiConnection 2b220a52-9d8b-4edd-8336-dbcbd435a9ca closed
11-21 00:50:08.015  3450  3465 I Gecko   : 1700527808015	RemoteAgent	TRACE	MessageHandler ROOT for session dc448e7d-5cd6-47f3-9641-7d06aa354b42 is being destroyed
11-21 00:50:08.016  3450  3465 I Gecko   : 1700527808016	RemoteAgent	TRACE	Unregistered MessageHandler ROOT for session dc448e7d-5cd6-47f3-9641-7d06aa354b42
11-21 00:50:08.017  3450  3465 I Gecko   : 1700527808017	Marionette	DEBUG	Closed connection 0
11-21 00:50:18.694  4039  4039 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 0 <<<<<<
11-21 00:50:18.695  4039  4039 D AndroidRuntime: CheckJNI is OFF
11-21 00:50:18.718  4039  4039 W art     : Unexpected CPU variant for X86 using defaults: x86_64
11-21 00:50:18.721  4039  4039 D ICU     : No timezone override file found: /data/misc/zoneinfo/current/icu/icu_tzdata.dat
11-21 00:50:18.739  4039  4039 E memtrack: Couldn't load memtrack module (No such file or directory)
11-21 00:50:18.739  4039  4039 E android.os.Debug: failed to load memtrack module: -2
11-21 00:50:18.739  4039  4039 I Radio-JNI: register_android_hardware_Radio DONE
11-21 00:50:18.744  4039  4039 D AndroidRuntime: Calling main entry com.android.commands.pm.Pm
11-21 00:50:18.754  4039  4039 I art     : System.exit called, status: 0
11-21 00:50:18.754  4039  4039 I AndroidRuntime: VM exiting with result code 0.
11-21 00:50:18.756  4039  4047 W MessageQueue: Handler (android.os.Handler) {60ad744} sending message to a Handler on a dead thread
11-21 00:50:18.756  4039  4047 W MessageQueue: java.lang.IllegalStateException: Handler (android.os.Handler) {60ad744} sending message to a Handler on a dead thread

Olivia, could this be related to the GeckoEventDispatcher: No listener for GeckoView:StateUpdated log entry? Is that a known issue? Do you know? Thanks

Comment 2

[Olivia Hall [:olivia]]

That log entry does look suspicious and I don't usually see that one locally. I'll take a look at this closer next week and see if I can find out more.

Comment 3

[Intermittent Failures Robot]

1 failures in 3623 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2023-11-20&endday=2023-11-26&tree=all

Comment 4

[Olivia Hall [:olivia]]

I'm not entirely sure, but could this be a similar situation to bug 1751541? That bug reports a history change before the location change occurs. The GeckoView:StateUpdated listener is also on the session. Could something in the test harness be listening to the history change and considering navigation completed due to that? That bug also mentions Fission/SessionStore/SHIP changes, and some new work is occurring in bug 1677190 for GeckoView.

Comment 5

[Intermittent Failures Robot]

1 failures in 1162 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2023-12-25&endday=2023-12-31&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 4009 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2024-02-05&endday=2024-02-11&tree=all

Comment 7

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 8

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=449339034&repo=autoland

Comment 9

[Intermittent Failures Robot]

1 failures in 4198 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2024-03-04&endday=2024-03-10&tree=all

Comment 10

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 11

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-esr128: 1

Table

	headless
linux1804-64-tsan-qr--/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-04-07&endday=2025-04-13&tree=all

Comment 12

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Table

	headless
macosx1470-64-devedition--/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-04-28&endday=2025-05-04&tree=all

Comment 13

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Table

	no_variant
macosx1470-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-05-05&endday=2025-05-11&tree=all

Comment 14

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=508341421&repo=mozilla-beta

Comment 15

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

Latest failures all all from macOS and are related to bug 1964338.

Comment 16

[Intermittent Failures Robot]

5 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 5

Table

	headless	no_variant
macosx1470-x86_64/opt	4	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-05-12&endday=2025-05-18&tree=all

Comment 17

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Table

	no_variant
windows11-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-05-26&endday=2025-06-01&tree=all

Comment 18

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 19

[Intermittent Failures Robot]

2 failures were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Table

	no_variant
windows11-x86_64/opt	2

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-06-23&endday=2025-06-29&tree=all

Comment 20

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Table

	no_variant
windows11-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-07-21&endday=2025-07-27&tree=all

Comment 21

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Table

	no_variant
windows11-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-08-25&endday=2025-08-31&tree=all

Comment 22

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Table

	no_variant
linux2404-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-09-01&endday=2025-09-07&tree=all

Comment 23

[Intermittent Failures Robot]

2 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Table

	no_variant
linux2404-x86_64/tsan	1
macosx1470-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-09-08&endday=2025-09-14&tree=all

Comment 24

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=527073531&repo=autoland

Comment 25

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Table

	headless
macosx1470-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-09-15&endday=2025-09-21&tree=all

Comment 26

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Table

	no_variant
linux2404-x86_64/tsan	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1865759&startday=2025-09-29&endday=2025-10-05&tree=all