1832294 - Intermittent Wd TEST-UNEXPECTED-TIMEOUT | expected OK (hang in "browsingContext.navigate")

Status: REOPENED

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

Description

[Treeherder Bug Filer]

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

---

INFO - PID 8472 | 1683716804480	RemoteAgent	DEBUG	WebDriverBiDiConnection 02d31a18-cc69-4b6b-9f23-cddbb9464e69 -> {"id":14,"method":"browsingContext.navigate","params":{"context":"26fe2224-d729-40be-92c0-c665f6b19352","url":"http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cdiv%3Efoo%3C%2Fdiv%3E&mime=text%2Fhtml&charset=UTF-8"}}
[task 2023-05-10T11:06:44.485Z] 11:06:44     INFO - PID 8472 | 1683716804480	RemoteAgent	TRACE	Module root/browsingContext.jsm found for ROOT
[task 2023-05-10T11:06:44.487Z] 11:06:44     INFO - PID 8472 | 1683716804480	RemoteAgent	TRACE	Received command browsingContext.navigate for destination ROOT
[task 2023-05-10T11:06:44.487Z] 11:06:44     INFO - PID 8472 | 1683716804480	RemoteAgent	TRACE	Module root/browsingContext.jsm found for ROOT
[task 2023-05-10T11:06:44.488Z] 11:06:44     INFO - PID 8472 | 1683716804480	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2023-05-10T11:06:44.489Z] 11:06:44     INFO - PID 8472 | 1683716804480	RemoteAgent	TRACE	Module windowglobal-in-root/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.490Z] 11:06:44     INFO - PID 8472 | 1683716804480	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.491Z] 11:06:44     INFO - PID 8472 | 1683716804482	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session fc6749e0-c91b-40a5-8819-d915ab06b4de is being destroyed
[task 2023-05-10T11:06:44.492Z] 11:06:44     INFO - PID 8472 | 1683716804482	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session fc6749e0-c91b-40a5-8819-d915ab06b4de
[task 2023-05-10T11:06:44.493Z] 11:06:44     INFO - PID 8472 | 1683716804482	RemoteAgent	TRACE	Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2023-05-10T11:06:44.494Z] 11:06:44     INFO - PID 8472 | 1683716804482	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.495Z] 11:06:44     INFO - PID 8472 | 1683716804483	RemoteAgent	TRACE	[14] ProgressListener Start: expectNavigation=true resolveWhenStarted=true unloadTimeout=200 waitForExplicitStart=true
[task 2023-05-10T11:06:44.495Z] 11:06:44     INFO - PID 8472 | 1683716804483	RemoteAgent	TRACE	[14] ProgressListener Document already loading about:blank
[task 2023-05-10T11:06:44.496Z] 11:06:44     INFO - PID 8472 | 1683716804483	RemoteAgent	TRACE	[14] ProgressListener Skip setting the unload timer
[task 2023-05-10T11:06:44.497Z] 11:06:44     INFO - PID 8472 | 1683716804492	RemoteAgent	DEBUG	WebDriverBiDiConnection 02d31a18-cc69-4b6b-9f23-cddbb9464e69 <- {"method":"browsingContext.domContentLoaded","params":{"context":"26fe2224-d729-40be-92c0-c665f6b19352","navigation":null,"timestamp":1683716804489,"url":"about:blank"}}
[task 2023-05-10T11:06:44.498Z] 11:06:44     INFO - PID 8472 | 1683716804495	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session fc6749e0-c91b-40a5-8819-d915ab06b4de is being destroyed
[task 2023-05-10T11:06:44.499Z] 11:06:44     INFO - PID 8472 | 1683716804495	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session fc6749e0-c91b-40a5-8819-d915ab06b4de
[task 2023-05-10T11:06:44.504Z] 11:06:44     INFO - PID 8472 | 1683716804507	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.509Z] 11:06:44     INFO - PID 8472 | 1683716804507	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-05-10T11:06:44.510Z] 11:06:44     INFO - PID 8472 | 1683716804507	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.510Z] 11:06:44     INFO - PID 8472 | 1683716804508	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.511Z] 11:06:44     INFO - PID 8472 | 1683716804509	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session fc6749e0-c91b-40a5-8819-d915ab06b4de
[task 2023-05-10T11:06:44.517Z] 11:06:44     INFO - PID 8472 | 1683716804517	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.517Z] 11:06:44     INFO - PID 8472 | 1683716804517	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-05-10T11:06:44.517Z] 11:06:44     INFO - PID 8472 | 1683716804517	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.521Z] 11:06:44     INFO - PID 8472 | 1683716804517	RemoteAgent	TRACE	Module windowglobal/browsingContext.jsm found for WINDOW_GLOBAL
[task 2023-05-10T11:06:44.522Z] 11:06:44     INFO - PID 8472 | 1683716804519	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session fc6749e0-c91b-40a5-8819-d915ab06b4de
[task 2023-05-10T11:06:44.523Z] 11:06:44     INFO - PID 8472 | 1683716804520	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session fc6749e0-c91b-40a5-8819-d915ab06b4de is being destroyed
[task 2023-05-10T11:06:44.523Z] 11:06:44     INFO - PID 8472 | 1683716804520	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session fc6749e0-c91b-40a5-8819-d915ab06b4de
[task 2023-05-10T11:06:44.533Z] 11:06:44     INFO - PID 8472 | 1683716804531	RemoteAgent	DEBUG	WebDriverBiDiConnection 02d31a18-cc69-4b6b-9f23-cddbb9464e69 <- {"method":"browsingContext.domContentLoaded","params":{"context":"26fe2224-d729-40be-92c0-c665f6b19352","navigation":null,"timestamp":1683716804531,"url":"http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cdiv%3Efoo%3C%2Fdiv%3E&mime=text%2Fhtml&charset=UTF-8"}}
[task 2023-05-10T11:06:44.534Z] 11:06:44     INFO - PID 8472 | 1683716804534	RemoteAgent	TRACE	[15] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-05-10T11:07:07.381Z] 11:07:07     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/dom_content_loaded/dom_content_loaded.py | expected OK
[task 2023-05-10T11:07:07.381Z] 11:07:07     INFO - TEST-INFO took 30005ms
[task 2023-05-10T11:07:17.433Z] 11:07:17  WARNING - Forcibly terminating runner process
INFO - PID 6324 | 1683716837476	geckodriver	INFO	Listening on 127.0.0.1:59869
[task 2023-05-10T11:07:17.968Z] 11:07:17     INFO - Starting runner
[task 2023-05-10T11:07:18.700Z] 11:07:18     INFO - TEST-START | /webdriver/tests/bidi/browsing_context/get_tree/frames.py

Comment 1

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

This is a hang when loading a page from wptserve. As such it is similar to bug 1760291.

Comment 2

[Julian Descottes [:jdescottes]]

Note that we have a 200ms navigation timeout when starting the test:

[task 2023-05-10T11:06:43.617Z] 11:06:43 INFO - PID 8472 | 1683716803586 RemoteAgent TRACE [14] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=false
[task 2023-05-10T11:06:43.618Z] 11:06:43 INFO - PID 8472 | 1683716803586 RemoteAgent TRACE [14] ProgressListener Setting unload timer (200ms)
[task 2023-05-10T11:06:43.795Z] 11:06:43 INFO - PID 8472 | 1683716803795 RemoteAgent TRACE [14] ProgressListener No navigation detected: about:blank
[task 2023-05-10T11:06:43.797Z] 11:06:43 INFO - PID 8472 | 1683716803795 RemoteAgent TRACE [14] ProgressListener Stop: has error=false

That might be responsible for the failure here.

Comment 3

[Julian Descottes [:jdescottes]]

FWIW, this 200ms navigation timeout is a recurring issue (eg Bug 1828108). We keep closing those bugs, but maybe we should keep one open ?

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2023-05-08&endday=2023-05-14&tree=all

Comment 5

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

There might be a problem when we start a new navigation while a former navigation (e.g. the initial one for about:blank) hasn't been completed yet and we see an overlap of requests. Fixing bug 1837949 might help for this situation.

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform and build breakdown:

• windows11-64-2009-shippable-qr: 1
  • opt: 1
• windows11-64-2009-qr: 1
  • opt: 1

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 2
• mozilla-central: 2

Platform and build breakdown:

• windows11-32-2009-qr: 1
  • debug: 1
• windows11-64-2009-shippable-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2023-07-03&endday=2023-07-09&tree=all

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2023-07-10&endday=2023-07-16&tree=all

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr115: 1

Platform and build breakdown:

• windows11-32-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2023-08-07&endday=2023-08-13&tree=all

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• mozilla-beta: 1

Platform and build breakdown:

• windows11-64-2009-ccov-qr: 1
  • opt: 1
• windows11-64-2009-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2023-10-02&endday=2023-10-08&tree=all

Comment 13

[Intermittent Failures Robot]

1 failures in 3657 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-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2023-10-23&endday=2023-10-29&tree=all

Comment 14

[Intermittent Failures Robot]

1 failures in 3288 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=1832294&startday=2023-11-06&endday=2023-11-12&tree=all

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2024-01-29&endday=2024-02-04&tree=all

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 2
• mozilla-esr115: 1

Platform and build breakdown:

• windows11-32-2009-shippable-qr: 2
  • opt: 2
• windows11-64-2009-qr: 1
  • debug: 1

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

Comment 17

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

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

Comment 18

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

This cannot be fixed as of this time. I assume that we just classified those failures wrongly.

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

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

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

Comment 20

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

As per bug 1825501 comment 85 this is not related to wptserve.

Comment 21

[Intermittent Failures Robot]

1 failures in 4547 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=1832294&startday=2024-05-13&endday=2024-05-19&tree=all

Comment 23

[Intermittent Failures Robot]

1 failures in 3199 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=1832294&startday=2024-07-01&endday=2024-07-07&tree=all

Comment 24

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

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

Comment 25

[Intermittent Failures Robot]

1 failures in 3459 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=1832294&startday=2024-08-05&endday=2024-08-11&tree=all

Comment 26

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3

Platform and build breakdown:

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

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

Comment 27

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr128: 1

Platform and build breakdown:

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

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

Comment 29

[Intermittent Failures Robot]

1 failures in 4579 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-asan-qr: 1
  • opt: 1

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

Comment 30

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-asan-qr: 2
  • opt: 2

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

Comment 31

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3
• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1
• linux1804-64-ccov-qr: 1
  • opt: 1
• linux1804-64-qr: 2
  • debug: 2

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

Comment 32

[Intermittent Failures Robot]

Summary

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

Table

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

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2025-01-13&endday=2025-01-19&tree=all

Comment 33

[Intermittent Failures Robot]

Summary

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

Repository breakdown:

• mozilla-central: 1
• mozilla-beta: 1

Platform and build breakdown:

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

Table

	no_variant
linux1804-64-ccov-qr--/opt	1
linux1804-64-tsan-qr--/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2025-02-03&endday=2025-02-09&tree=all

Comment 34

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Table

	no_variant
linux1804-64-ccov-qr--/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2025-04-14&endday=2025-04-20&tree=all

Comment 35

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Table

	no_variant
macosx1470-64--/opt	1

For more details, see:

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

Comment 36

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Table

	headless
windows11-x86_64/opt	1

For more details, see:

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

Comment 37

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr140: 1

Table

	headless
macosx1470-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1832294&startday=2025-06-30&endday=2025-07-06&tree=all