Closed Bug 1884564 Opened 2 years ago Closed 7 months ago

Intermittent /webdriver/tests/bidi/network/fetch_error/fetch_error.py | single tracking bug

Categories

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

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 2 open bugs)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2024-03-10T10:17:39.660Z] 10:17:39     INFO - PID 8156 | [Parent 5360, IPDL Background] WARNING: quota manager shutdown step: '0.032000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2218
[task 2024-03-10T10:17:39.790Z] 10:17:39     INFO - PID 8156 | [Utility 2148, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:534
[task 2024-03-10T10:17:39.792Z] 10:17:39     INFO - PID 8156 | [Parent 5360, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2024-03-10T10:17:40.168Z] 10:17:40     INFO - PID 8156 | [Parent 5360, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2024-03-10T10:17:40.382Z] 10:17:40     INFO - PID 8156 | 1710065860382	geckodriver::browser	DEBUG	Browser process stopped: exit code: 0
[task 2024-03-10T10:17:40.384Z] 10:17:40     INFO - PID 8156 | 1710065860383	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-03-10T10:17:40.393Z] 10:17:40     INFO - STDOUT: ================================== FAILURES ===================================
[task 2024-03-10T10:17:40.393Z] 10:17:40     INFO - STDOUT: ____________________________ test_subscribe_status ____________________________
[task 2024-03-10T10:17:40.394Z] 10:17:40     INFO - STDOUT: fut = <Future cancelled>, timeout = 8.0
[task 2024-03-10T10:17:40.395Z] 10:17:40     INFO - STDOUT:     async def wait_for(fut, timeout):
[task 2024-03-10T10:17:40.396Z] 10:17:40     INFO - STDOUT:         """Wait for the single Future or coroutine to complete, with timeout.
[task 2024-03-10T10:17:40.397Z] 10:17:40     INFO - STDOUT:     
[task 2024-03-10T10:17:40.398Z] 10:17:40     INFO - STDOUT:         Coroutine will be wrapped in Task.
[task 2024-03-10T10:17:40.398Z] 10:17:40     INFO - STDOUT:     
[task 2024-03-10T10:17:40.399Z] 10:17:40     INFO - STDOUT:         Returns result of the Future or coroutine.  When a timeout occurs,
[task 2024-03-10T10:17:40.400Z] 10:17:40     INFO - STDOUT:         it cancels the task and raises TimeoutError.  To avoid the task
[task 2024-03-10T10:17:40.400Z] 10:17:40     INFO - STDOUT:         cancellation, wrap it in shield().
[task 2024-03-10T10:17:40.482Z] 10:17:40     INFO - 
[task 2024-03-10T10:17:40.482Z] 10:17:40     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/bidi/network/fetch_error/fetch_error.py | test_subscribe_status - asyncio.exceptions.CancelledError
[task 2024-03-10T10:17:40.482Z] 10:17:40     INFO - fut = <Future cancelled>, timeout = 8.0
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO - 
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -     async def wait_for(fut, timeout):
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -         """Wait for the single Future or coroutine to complete, with timeout.
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -         Coroutine will be wrapped in Task.
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -         Returns result of the Future or coroutine.  When a timeout occurs,
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -         it cancels the task and raises TimeoutError.  To avoid the task
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -         cancellation, wrap it in shield().
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.483Z] 10:17:40     INFO -         If the wait is cancelled, the task is also cancelled.
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -         This function is a coroutine.
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -         """
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -         loop = events.get_running_loop()
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -         if timeout is None:
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -             return await fut
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -         if timeout <= 0:
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -             fut = ensure_future(fut, loop=loop)
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -             if fut.done():
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -                 return fut.result()
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.484Z] 10:17:40     INFO -             await _cancel_and_wait(fut, loop=loop)
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -             try:
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                 return fut.result()
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -             except exceptions.CancelledError as exc:
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                 raise exceptions.TimeoutError() from exc
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -         waiter = loop.create_future()
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -         timeout_handle = loop.call_later(timeout, _release_waiter, waiter)
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -         cb = functools.partial(_release_waiter, waiter)
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -         fut = ensure_future(fut, loop=loop)
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -         fut.add_done_callback(cb)
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -         try:
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -             # wait until the future completes or the timeout
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -             try:
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                 await waiter
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -             except exceptions.CancelledError:
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                 if fut.done():
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                     return fut.result()
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                 else:
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                     fut.remove_done_callback(cb)
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                     # We must ensure that the task is not running
[task 2024-03-10T10:17:40.485Z] 10:17:40     INFO -                     # after wait_for() returns.
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                     # See https://bugs.python.org/issue32751
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                     await _cancel_and_wait(fut, loop=loop)
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                     raise
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -     
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -             if fut.done():
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 return fut.result()
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -             else:
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 fut.remove_done_callback(cb)
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 # We must ensure that the task is not running
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 # after wait_for() returns.
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 # See https://bugs.python.org/issue32751
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 await _cancel_and_wait(fut, loop=loop)
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 # In case task cancellation failed with some
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 # exception, we should re-raise it
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 # See https://bugs.python.org/issue40607
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO -                 try:
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO - >                   return fut.result()
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO - E                   asyncio.exceptions.CancelledError
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO - 
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO - cb         = functools.partial(<function _release_waiter at 0x000001DF036CEB60>, <Future finished result=None>)
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO - fut        = <Future cancelled>
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO - loop       = <ProactorEventLoop running=False closed=False debug=False>
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO - timeout    = 8.0
[task 2024-03-10T10:17:40.486Z] 10:17:40     INFO - timeout_handle = <TimerHandle cancelled when=983.687>
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - waiter     = <Future finished result=None>
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - 
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - c:\mozilla-build\python3\Lib\asyncio\tasks.py:500: CancelledError
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - 
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - The above exception was the direct cause of the following exception:
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - 
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - future = <Future pending>, timeout = 2.0
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - 
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO -     async def wait_for_future_safe(future: Coroutine, timeout: float = 2.0):
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO -         try:
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO - >           return await asyncio.wait_for(
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO -                 asyncio.shield(future),
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO -                 timeout=timeout * configuration["timeout_multiplier"],
[task 2024-03-10T10:17:40.487Z] 10:17:40     INFO -             )

this failure is actually bug 1774392. I've updated the classification.

Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

(In reply to Treeherder Bug Filer from comment #16)

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

This is just bug 1925075. I've re-classified.

Depends on: 1925075
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

(In reply to Treeherder Bug Filer from comment #19)

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

This is bug 1774392. Adding it as dependency.

Depends on: 1774392
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Summary: Intermittent [tier 2] /webdriver/tests/bidi/network/fetch_error/fetch_error.py | single tracking bug → Intermittent /webdriver/tests/bidi/network/fetch_error/fetch_error.py | single tracking bug
Status: REOPENED → RESOLVED
Closed: 1 year ago7 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.