1958903 - Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/network/add_intercept/url_patterns.py | expected OK

Status: RESOLVED DUPLICATE of bug 1630162

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

---

TEST-KNOWN-INTERMITTENT-TIMEOUT | /webdriver/tests/bidi/network/add_intercept/contexts.py | took 105006ms
[task 2025-04-07T14:11:41.764Z] 14:11:41     INFO - PID 22256 | 1744035101762	mozrunner::runner	DEBUG	Killing process 22638
[task 2025-04-07T14:11:41.835Z] 14:11:41     INFO - PID 22256 | 1744035101834	geckodriver::browser	DEBUG	Browser process stopped: signal: 9 (SIGKILL)
[task 2025-04-07T14:11:41.836Z] 14:11:41     INFO - PID 22256 | 1744035101834	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2025-04-07T14:11:41.889Z] 14:11:41     INFO - Test harness output was not a valid structured log message
[task 2025-04-07T14:11:41.889Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.890Z] 14:11:41     INFO - task: <Task pending name='Task-97' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a96c8880>()]>>
[task 2025-04-07T14:11:41.890Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.890Z] 14:11:41     INFO - task: <Task pending name='Task-123' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a96e4610>()]>>
[task 2025-04-07T14:11:41.890Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.891Z] 14:11:41     INFO - task: <Task pending name='Task-149' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a96b59a0>()]>>
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - task: <Task pending name='Task-176' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a96b5ac0>()]>>
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - task: <Task pending name='Task-197' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a968e2b0>()]>>
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - task: <Task pending name='Task-218' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a96b58e0>()]>>
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - task: <Task pending name='Task-240' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a968ea30>()]>>
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - task: <Task pending name='Task-263' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a9694460>()]>>
[task 2025-04-07T14:11:41.893Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.894Z] 14:11:41     INFO - task: <Task pending name='Task-266' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a96c8730>()]>>
[task 2025-04-07T14:11:41.894Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.894Z] 14:11:41     INFO - task: <Task pending name='Task-286' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a96a0340>()]>>
[task 2025-04-07T14:11:41.894Z] 14:11:41     INFO - Task was destroyed but it is pending!
[task 2025-04-07T14:11:41.895Z] 14:11:41     INFO - task: <Task pending name='Task-289' coro=<fetch.<locals>.fetch() running at /builds/worker/workspace/build/tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:616> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7ff3a96a0490>()]>>
[task 2025-04-07T14:11:42.076Z] 14:11:42     INFO - INFO | LeakSanitizer | SUMMARY: AddressSanitizer: 1920 byte(s) leaked in 32 allocation(s).
[task 2025-04-07T14:11:42.077Z] 14:11:42     INFO - LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2025-04-07T14:11:42.077Z] 14:11:42     INFO - This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2025-04-07T14:11:42.077Z] 14:11:42     INFO - Allowed depth was 4
[task 2025-04-07T14:11:42.078Z] 14:11:42     INFO - TEST-FAIL | LeakSanitizer | leak at XPCWrappedNative::GetNewOrUsed, XPCConvert::NativeInterface2JSObject, XPCConvert::NativeData2JS, nsXPCWrappedJS::CallMethod
[task 2025-04-07T14:11:42.078Z] 14:11:42     INFO - INFO | LeakSanitizer | Frame XPCWrappedNative::GetNewOrUsed matched a expected leak
[task 2025-04-07T14:11:42.078Z] 14:11:42     INFO - TEST-FAIL | LeakSanitizer | leak at XPCNativeInterface::NewInstance, XPCNativeInterface::GetNewOrUsed, XPCWrappedNative::FindTearOff, xpc::HasInstance
[task 2025-04-07T14:11:42.078Z] 14:11:42     INFO - INFO | LeakSanitizer | Frame XPCNativeInterface::NewInstance matched a expected leak
[task 2025-04-07T14:11:42.079Z] 14:11:42     INFO - TEST-FAIL | LeakSanitizer | leak at MakeUnique, AddTearOff, XPCWrappedNative::FindTearOff, XPCWrappedNative::FindTearOff
[task 2025-04-07T14:11:42.079Z] 14:11:42     INFO - INFO | LeakSanitizer | Frame MakeUnique matched a expected leak
[task 2025-04-07T14:11:42.079Z] 14:11:42     INFO - TEST-FAIL | LeakSanitizer | leak at XPCNativeSet::NewInstanceMutate, XPCNativeSet::GetNewOrUsed, XPCWrappedNative::ExtendSet, XPCWrappedNative::InitTearOff
[task 2025-04-07T14:11:42.080Z] 14:11:42     INFO - INFO | LeakSanitizer | Frame XPCNativeSet::NewInstanceMutate matched a expected leak
[task 2025-04-07T14:11:42.080Z] 14:11:42     INFO - Closing logging queue
[task 2025-04-07T14:11:42.081Z] 14:11:42     INFO - queue closed
[task 2025-04-07T14:11:42.083Z] 14:11:42     INFO - Starting WebDriver: /builds/worker/fetches/geckodriver --host 127.0.0.1 --port 0 -vv --binary /builds/worker/workspace/build/application/firefox/firefox
[task 2025-04-07T14:11:42.197Z] 14:11:42     INFO - PID 23635 | 1744035102095	geckodriver	INFO	Listening on 127.0.0.1:45621
[task 2025-04-07T14:11:42.199Z] 14:11:42     INFO - PID 23635 | 1744035102095	webdriver::server	TRACE	Build standard route for /status
[task 2025-04-07T14:11:42.199Z] 14:11:42     INFO - PID 23635 | 1744035102095	webdriver::server	TRACE	Build standard route for /session
[task 2025-04-07T14:11:42.201Z] 14:11:42     INFO - PID 23635 | 1744035102095	webdriver::server	TRACE	Build standard route for /session/{sessionId}

Comment 1

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

This is a hang during shutdown of Firefox. As such lets dupe to bug 1630162.