1846254 - Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/navigate/data_url.py | expected OK

Status: RESOLVED INCOMPLETE

(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=424340079&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MYmG6ODNTvyb_xSHRYhBdw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2023-07-31T11:36:31.939Z] 11:36:31     INFO - STDOUT: PASSED
[task 2023-07-31T11:36:32.621Z] 11:36:32     INFO - PID 2262 | 1690803392619	webdriver::server	DEBUG	-> POST /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/timeouts {"implicit": 0}
[task 2023-07-31T11:36:32.631Z] 11:36:32     INFO - PID 2262 | 1690803392630	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-31T11:36:32.632Z] 11:36:32     INFO - PID 2262 | 1690803392631	webdriver::server	DEBUG	-> POST /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/timeouts {"pageLoad": 300000}
[task 2023-07-31T11:36:32.644Z] 11:36:32     INFO - PID 2262 | 1690803392643	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-31T11:36:32.644Z] 11:36:32     INFO - PID 2262 | 1690803392644	webdriver::server	DEBUG	-> POST /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/timeouts {"script": 30000}
[task 2023-07-31T11:36:32.655Z] 11:36:32     INFO - PID 2262 | 1690803392654	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-31T11:36:32.655Z] 11:36:32     INFO - PID 2262 | 1690803392655	webdriver::server	DEBUG	-> GET /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/window
[task 2023-07-31T11:36:32.665Z] 11:36:32     INFO - PID 2262 | 1690803392664	webdriver::server	DEBUG	<- 200 OK {"value":"c9243a46-f2d3-46bd-afc8-7bcb2ecf2e8a"}
[task 2023-07-31T11:36:32.665Z] 11:36:32     INFO - PID 2262 | 1690803392665	webdriver::server	DEBUG	-> GET /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/window
[task 2023-07-31T11:36:32.673Z] 11:36:32     INFO - PID 2262 | 1690803392672	webdriver::server	DEBUG	<- 200 OK {"value":"c9243a46-f2d3-46bd-afc8-7bcb2ecf2e8a"}
[task 2023-07-31T11:36:32.674Z] 11:36:32     INFO - PID 2262 | 1690803392673	webdriver::server	DEBUG	-> GET /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/window/handles
[task 2023-07-31T11:36:32.681Z] 11:36:32     INFO - PID 2262 | 1690803392680	webdriver::server	DEBUG	<- 200 OK {"value":["c9243a46-f2d3-46bd-afc8-7bcb2ecf2e8a"]}
[task 2023-07-31T11:36:32.682Z] 11:36:32     INFO - PID 2262 | 1690803392681	webdriver::server	DEBUG	-> POST /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/window {"handle": "c9243a46-f2d3-46bd-afc8-7bcb2ecf2e8a"}
[task 2023-07-31T11:36:32.691Z] 11:36:32     INFO - PID 2262 | 1690803392690	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-31T11:36:32.692Z] 11:36:32     INFO - PID 2262 | 1690803392691	webdriver::server	DEBUG	-> POST /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/alert/dismiss {}
[task 2023-07-31T11:36:32.700Z] 11:36:32     INFO - PID 2262 | 1690803392699	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-07-31T11:36:32.701Z] 11:36:32     INFO - PID 2262 | 1690803392700	webdriver::server	DEBUG	-> POST /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/window {"handle": "c9243a46-f2d3-46bd-afc8-7bcb2ecf2e8a"}
[task 2023-07-31T11:36:32.709Z] 11:36:32     INFO - PID 2262 | 1690803392709	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-31T11:36:32.710Z] 11:36:32     INFO - PID 2262 | 1690803392710	webdriver::server	DEBUG	-> GET /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/window
[task 2023-07-31T11:36:32.718Z] 11:36:32     INFO - PID 2262 | 1690803392718	webdriver::server	DEBUG	<- 200 OK {"value":"c9243a46-f2d3-46bd-afc8-7bcb2ecf2e8a"}
[task 2023-07-31T11:36:32.719Z] 11:36:32     INFO - PID 2262 | 1690803392719	webdriver::server	DEBUG	-> GET /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/window/handles
[task 2023-07-31T11:36:32.726Z] 11:36:32     INFO - PID 2262 | 1690803392725	webdriver::server	DEBUG	<- 200 OK {"value":["c9243a46-f2d3-46bd-afc8-7bcb2ecf2e8a"]}
[task 2023-07-31T11:36:32.727Z] 11:36:32     INFO - PID 2262 | 1690803392726	webdriver::server	DEBUG	-> POST /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/window {"handle": "c9243a46-f2d3-46bd-afc8-7bcb2ecf2e8a"}
[task 2023-07-31T11:36:32.734Z] 11:36:32     INFO - PID 2262 | 1690803392734	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-31T11:36:32.735Z] 11:36:32     INFO - PID 2262 | 1690803392735	webdriver::server	DEBUG	-> POST /session/cb82bfde-a9f5-4ee2-afc0-5ea03490eec7/frame {"id": null}
[task 2023-07-31T11:36:32.765Z] 11:36:32     INFO - PID 2262 | 1690803392764	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-31T11:36:32.767Z] 11:36:32     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/browsing_context/navigate/data_url.py::test_navigate_from_single_page[data:image to data:image] 
[task 2023-07-31T11:37:47.140Z] 11:37:47     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/browsing_context/navigate/data_url.py | expected OK
[task 2023-07-31T11:37:47.140Z] 11:37:47     INFO - TEST-INFO took 80004ms
[task 2023-07-31T11:37:57.179Z] 11:37:57  WARNING - Forcibly terminating runner process
[task 2023-07-31T11:37:57.279Z] 11:37:57     INFO - Starting runner
[task 2023-07-31T11:37:57.283Z] 11:37:57     INFO - PID 2329 | 1690803477278	geckodriver	INFO	Listening on 127.0.0.1:45095

Comment 1

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

Not sure why that is failing. Usually the wptrunner sends the new session command to geckodriver which should look like the following:

[task 2023-07-31T11:36:27.207Z] 11:36:27     INFO - PID 2262 | 1690803387206	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/tmp/tmpff20hrs3.mozrunner"] ... -5554", "env": {"MOZ_CRASHREPORTER": "1", "MOZ_CRASHREPORTER_SHUTDOWN": "1", "RUST_BACKTRACE": "1"}}, "webSocketUrl": true}}}

But that's not happening here. The test execution seems to be stalled for about 70s. Lets observe how often this happens.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-beta: 1

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 1
  • debug-isolated-process: 1
• linux1804-64-tsan-qr: 1
  • opt: 1

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

Comment 3

[Intermittent Failures Robot]

1 failures in 3019 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=1846254&startday=2023-08-28&endday=2023-09-03&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 3337 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=1846254&startday=2023-09-11&endday=2023-09-17&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 3726 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=1846254&startday=2023-09-25&endday=2023-10-01&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 3484 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=1846254&startday=2023-10-16&endday=2023-10-22&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• android-em-7-0-x86_64-shippable-lite-qr: 1
  • opt: 1

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

Comment 8

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

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