1832966 - Intermittent TEST-UNEXPECTED-TIMEOUT | mozilla/tests/webdriver/get_window_handles/chrome.py | expected OK

Status: RESOLVED INCOMPLETE

(Testing :: web-platform-tests, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-05-13T13:07:28.004Z] 13:07:28     INFO - TEST-START | /_mozilla/webdriver/get_window_handles/chrome.py
[task 2023-05-13T13:07:28.598Z] 13:07:28     INFO - STDOUT: ============================= test session starts =============================
[task 2023-05-13T13:07:28.608Z] 13:07:28     INFO - STDOUT: platform win32 -- Python 3.9.10, pytest-7.0.1, pluggy-unknown -- Z:\task_168397809835056\build\venv\Scripts\python.exe
[task 2023-05-13T13:07:28.610Z] 13:07:28     INFO - STDOUT: rootdir: Z:\task_168397809835056\build
[task 2023-05-13T13:07:28.610Z] 13:07:28     INFO - STDOUT: plugins: asyncio-0.19.0
[task 2023-05-13T13:07:28.611Z] 13:07:28     INFO - STDOUT: asyncio: mode=strict
[task 2023-05-13T13:07:28.611Z] 13:07:28     INFO - STDOUT: collecting ... 
[task 2023-05-13T13:07:28.645Z] 13:07:28     INFO - STDOUT: collected 3 items
[task 2023-05-13T13:07:28.657Z] 13:07:28     INFO - STDOUT: tests/web-platform/mozilla/tests/webdriver/get_window_handles/chrome.py::test_basic 
[task 2023-05-13T13:07:28.667Z] 13:07:28     INFO - PID 6840 | 1683983248666	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "C:\\Users\\task_168397809835 ... ata\\Local\\Temp\\tmplst2nzqv.mozrunner"], "binary": "Z:\\task_168397809835056\\build\\application\\firefox\\firefox.exe"}}}}
[task 2023-05-13T13:07:28.668Z] 13:07:28     INFO - PID 6840 | 1683983248666	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2023-05-13T13:07:28.669Z] 13:07:28     INFO - PID 6840 | 1683983248667	geckodriver::capabilities	DEBUG	Found version 115.0
[task 2023-05-13T13:07:28.679Z] 13:07:28     INFO - PID 6840 | 1683983248678	geckodriver::browser	DEBUG	Backing up prefs to "C:\\Users\\task_168397809835056\\AppData\\Local\\Temp\\tmplst2nzqv.mozrunner\\user.geckodriver_backup"
[task 2023-05-13T13:07:28.692Z] 13:07:28     INFO - PID 6840 | 1683983248695	mozrunner::runner	INFO	Running command: "Z:\\task_168397809835056\\build\\application\\firefox\\firefox.exe" "--marionette" "--profile" "C:\\Users\\task_168397809835056\\AppData\\Local\\Temp\\tmplst2nzqv.mozrunner" "-no-remote"
[task 2023-05-13T13:07:28.708Z] 13:07:28     INFO - PID 6840 | 1683983248706	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2023-05-13T13:07:28.709Z] 13:07:28     INFO - PID 6840 | 1683983248706	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:28.709Z] 13:07:28     INFO - PID 6840 | 1683983248706	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:28.817Z] 13:07:28     INFO - PID 6840 | 1683983248817	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:28.819Z] 13:07:28     INFO - PID 6840 | 1683983248817	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:28.927Z] 13:07:28     INFO - PID 6840 | 1683983248927	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:28.928Z] 13:07:28     INFO - PID 6840 | 1683983248927	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.036Z] 13:07:29     INFO - PID 6840 | 1683983249036	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.038Z] 13:07:29     INFO - PID 6840 | 1683983249036	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.146Z] 13:07:29     INFO - PID 6840 | 1683983249146	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.148Z] 13:07:29     INFO - PID 6840 | 1683983249146	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.256Z] 13:07:29     INFO - PID 6840 | 1683983249256	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.258Z] 13:07:29     INFO - PID 6840 | 1683983249256	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.366Z] 13:07:29     INFO - PID 6840 | 1683983249366	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.368Z] 13:07:29     INFO - PID 6840 | 1683983249366	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.475Z] 13:07:29     INFO - PID 6840 | 1683983249475	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.478Z] 13:07:29     INFO - PID 6840 | 1683983249476	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.585Z] 13:07:29     INFO - PID 6840 | 1683983249585	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.587Z] 13:07:29     INFO - PID 6840 | 1683983249585	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.695Z] 13:07:29     INFO - PID 6840 | 1683983249695	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.697Z] 13:07:29     INFO - PID 6840 | 1683983249695	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.804Z] 13:07:29     INFO - PID 6840 | 1683983249805	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.806Z] 13:07:29     INFO - PID 6840 | 1683983249805	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:29.914Z] 13:07:29     INFO - PID 6840 | 1683983249915	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:29.916Z] 13:07:29     INFO - PID 6840 | 1683983249915	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:30.024Z] 13:07:30     INFO - PID 6840 | 1683983250024	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:30.026Z] 13:07:30     INFO - PID 6840 | 1683983250025	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:30.134Z] 13:07:30     INFO - PID 6840 | 1683983250134	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:30.136Z] 13:07:30     INFO - PID 6840 | 1683983250134	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:30.244Z] 13:07:30     INFO - PID 6840 | 1683983250244	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:30.246Z] 13:07:30     INFO - PID 6840 | 1683983250244	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:30.290Z] 13:07:30     INFO - PID 6840 | console.warn: services.settings: Ignoring preference override of remote settings server
[task 2023-05-13T13:07:30.299Z] 13:07:30     INFO - PID 6840 | console.warn: services.settings: Allow by setting MOZ_REMOTE_SETTINGS_DEVTOOLS=1 in the environment
[task 2023-05-13T13:07:30.353Z] 13:07:30     INFO - PID 6840 | 1683983250353	geckodriver::browser	TRACE	Failed to open C:\Users\task_168397809835056\AppData\Local\Temp\tmplst2nzqv.mozrunner\MarionetteActivePort
[task 2023-05-13T13:07:30.355Z] 13:07:30     INFO - PID 6840 | 1683983250353	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-05-13T13:07:30.364Z] 13:07:30     INFO - PID 6840 | 1683983250362	Marionette	INFO	Marionette enabled
[task 2023-05-13T13:07:30.365Z] 13:07:30     INFO - PID 6840 | 1683983250364	Marionette	TRACE	Received observer notification final-ui-startup
[task 2023-05-13T13:07:30.366Z] 13:07:30     INFO - PID 6840 | 1683983250365	Marionette	INFO	Listening on port 63572
[task 2023-05-13T13:07:30.366Z] 13:07:30     INFO - PID 6840 | 1683983250365	Marionette	DEBUG	Marionette is listening
[task 2023-05-13T13:07:30.462Z] 13:07:30     INFO - PID 6840 | Read port: 63572
<...>
[task 2023-05-13T13:07:33.506Z] 13:07:33     INFO - PID 6840 | 1683983253503	webdriver::server	DEBUG	-> DELETE /session/66f24b5c-3207-45bb-af87-92d4dc0f385c
[task 2023-05-13T13:07:33.506Z] 13:07:33     INFO - PID 6840 | 1683983253504	Marionette	DEBUG	0 -> [0,86,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2023-05-13T13:07:33.507Z] 13:07:33     INFO - PID 6840 | 1683983253504	Marionette	INFO	Stopped listening on port 63572
[task 2023-05-13T13:07:33.508Z] 13:07:33     INFO - PID 6840 | 1683983253505	Marionette	TRACE	[3] MarionetteCommands actor destroyed for window id 4294967297
[task 2023-05-13T13:07:33.529Z] 13:07:33     INFO - PID 6840 | 1683983253531	Marionette	TRACE	Received observer notification quit-application
[task 2023-05-13T13:07:33.536Z] 13:07:33     INFO - PID 6840 | 1683983253535	Marionette	TRACE	Received observer notification quit-application
[task 2023-05-13T13:07:33.536Z] 13:07:33     INFO - PID 6840 | 1683983253535	Marionette	DEBUG	Marionette stopped listening
[task 2023-05-13T13:07:33.537Z] 13:07:33     INFO - PID 6840 | 1683983253535	Marionette	DEBUG	0 <- [1,86,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-05-13T13:07:33.549Z] 13:07:33     INFO - PID 6840 | 1683983253551	webdriver::server	DEBUG	Teardown session
[task 2023-05-13T13:07:33.554Z] 13:07:33     INFO - PID 6840 | 1683983253553	Marionette	DEBUG	Closed connection 0
[task 2023-05-13T13:08:01.719Z] 13:08:01     INFO - TEST-UNEXPECTED-TIMEOUT | /_mozilla/webdriver/get_window_handles/chrome.py | expected OK
[task 2023-05-13T13:08:01.719Z] 13:08:01     INFO - TEST-INFO took 33715ms
[task 2023-05-13T13:08:01.725Z] 13:08:01     INFO - Restarting browser for new test group
[task 2023-05-13T13:08:02.126Z] 13:08:02     INFO - Closing logging queue
[task 2023-05-13T13:08:02.136Z] 13:08:02     INFO - queue closed
[task 2023-05-13T13:08:02.673Z] 13:08:02     INFO - PID 8584 | 1683983282189	geckodriver	INFO	Listening on 127.0.0.1:64409
[task 2023-05-13T13:08:02.675Z] 13:08:02     INFO - Starting runner
[task 2023-05-13T13:08:03.674Z] 13:08:03     INFO - TEST-START | /_mozilla/webdriver/take_full_screenshot/iframe.py

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

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

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

Comment 2

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

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

Comment 3

[Treeherder Bug Filer]

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr115: 1

Platform and build breakdown:

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

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

Comment 5

[Intermittent Failures Robot]

1 failures in 3356 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=1832966&startday=2023-10-30&endday=2023-11-05&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 3382 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
  • opt: 1

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

Comment 7

[Intermittent Failures Robot]

1 failures in 3590 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=1832966&startday=2024-03-25&endday=2024-03-31&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.

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Table

	no_variant
linux2204-x86_64/opt	1

For more details, see:

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