Closed
Bug 1874817
Opened 1 year ago
Closed 1 year ago
Intermittent [tier 2] /webdriver/tests/bidi/network/fail_request/request.py | single tracking bug
Categories
(Remote Protocol :: WebDriver BiDi, defect, P5)
Remote Protocol
WebDriver BiDi
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=443428304&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Xfl-Le9tTcK26FMZVMJwAg/runs/0/artifacts/public/logs/live_backing.log
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: try:
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: # wait until the future completes or the timeout
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: try:
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: await waiter
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: except exceptions.CancelledError:
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: fut.remove_done_callback(cb)
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: fut.cancel()
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: raise
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT:
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: if fut.done():
[task 2024-01-16T05:43:29.475Z] 05:43:29 INFO - STDOUT: return fut.result()
[task 2024-01-16T05:43:29.476Z] 05:43:29 INFO - STDOUT: else:
[task 2024-01-16T05:43:29.476Z] 05:43:29 INFO - STDOUT: fut.remove_done_callback(cb)
[task 2024-01-16T05:43:29.477Z] 05:43:29 INFO - STDOUT: # We must ensure that the task is not running
[task 2024-01-16T05:43:29.477Z] 05:43:29 INFO - STDOUT: # after wait_for() returns.
[task 2024-01-16T05:43:29.478Z] 05:43:29 INFO - STDOUT: # See https://bugs.python.org/issue32751
[task 2024-01-16T05:43:29.479Z] 05:43:29 INFO - STDOUT: await _cancel_and_wait(fut, loop=loop)
[task 2024-01-16T05:43:29.479Z] 05:43:29 INFO - STDOUT: > raise exceptions.TimeoutError()
[task 2024-01-16T05:43:29.480Z] 05:43:29 INFO - STDOUT: E asyncio.exceptions.TimeoutError
[task 2024-01-16T05:43:29.481Z] 05:43:29 INFO - STDOUT: cb = functools.partial(<function _release_waiter at 0x7f2ad63ddc10>, <Future finished result=None>)
[task 2024-01-16T05:43:29.481Z] 05:43:29 INFO - STDOUT: fut = <Task cancelled name='Task-97' coro=<Connect.__await_impl__() done, defined at /builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/websockets/src/websockets/legacy/client.py:652>>
[task 2024-01-16T05:43:29.482Z] 05:43:29 INFO - STDOUT: loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2024-01-16T05:43:29.483Z] 05:43:29 INFO - STDOUT: timeout = 10
[task 2024-01-16T05:43:29.483Z] 05:43:29 INFO - STDOUT: timeout_handle = <TimerHandle cancelled when=792.682820499>
[task 2024-01-16T05:43:29.484Z] 05:43:29 INFO - STDOUT: waiter = <Future finished result=None>
[task 2024-01-16T05:43:29.485Z] 05:43:29 INFO - STDOUT: /usr/lib/python3.8/asyncio/tasks.py
[task 2024-01-16T05:43:29.485Z] 05:43:29 INFO - STDOUT: :490: TimeoutError
[task 2024-01-16T05:43:29.489Z] 05:43:29 INFO - STDOUT: ________________ ERROR at setup of test_phases[responseStarted] ________________
[task 2024-01-16T05:43:29.489Z] 05:43:29 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2024-01-16T05:43:29.490Z] 05:43:29 INFO - STDOUT: request = <SubRequest 'top_context' for <Function test_phases[responseStarted]>>
[task 2024-01-16T05:43:29.490Z] 05:43:29 INFO - STDOUT: kwargs = {'bidi_session': <webdriver.bidi.client.BidiSession object at 0x7f2ad56a6940>}
[task 2024-01-16T05:43:29.490Z] 05:43:29 INFO - STDOUT: setup = <function _wrap_async.<locals>._async_fixture_wrapper.<locals>.setup at 0x7f2ad53d5ca0>
[task 2024-01-16T05:43:29.490Z] 05:43:29 INFO - STDOUT: @functools.wraps(func)
[task 2024-01-16T05:43:29.490Z] 05:43:29 INFO - STDOUT: def _async_fixture_wrapper(
[task 2024-01-16T05:43:29.491Z] 05:43:29 INFO - STDOUT: event_loop: asyncio.AbstractEventLoop, request: SubRequest, **kwargs: Any
[task 2024-01-16T05:43:29.491Z] 05:43:29 INFO - STDOUT: ) -> _R:
[task 2024-01-16T05:43:29.491Z] 05:43:29 INFO - STDOUT: async def setup() -> _R:
[task 2024-01-16T05:43:29.491Z] 05:43:29 INFO - STDOUT: res = await func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2024-01-16T05:43:29.491Z] 05:43:29 INFO - STDOUT: return res
[task 2024-01-16T05:43:29.491Z] 05:43:29 INFO - STDOUT:
[task 2024-01-16T05:43:29.491Z] 05:43:29 INFO - STDOUT: > return event_loop.run_until_complete(setup())
[task 2024-01-16T05:43:29.491Z] 05:43:29 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2024-01-16T05:43:29.492Z] 05:43:29 INFO - STDOUT: func = <function top_context at 0x7f2ad604f1f0>
[task 2024-01-16T05:43:29.492Z] 05:43:29 INFO - STDOUT: kwargs = {'bidi_session': <webdriver.bidi.client.BidiSession object at 0x7f2ad56a6940>}
[task 2024-01-16T05:43:29.493Z] 05:43:29 INFO - STDOUT: request = <SubRequest 'top_context' for <Function test_phases[responseStarted]>>
[task 2024-01-16T05:43:29.494Z] 05:43:29 INFO - STDOUT: setup = <function _wrap_async.<locals>._async_fixture_wrapper.<locals>.setup at 0x7f2ad53d5ca0>
[task 2024-01-16T05:43:29.495Z] 05:43:29 INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2024-01-16T05:43:29.495Z] 05:43:29 INFO - STDOUT: :309:
[task 2024-01-16T05:43:29.496Z] 05:43:29 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2024-01-16T05:43:29.497Z] 05:43:29 INFO - STDOUT: /usr/lib/python3.8/asyncio/base_events.py
[task 2024-01-16T05:43:29.497Z] 05:43:29 INFO - STDOUT: :608: in run_until_complete
[task 2024-01-16T05:43:29.497Z] 05:43:29 INFO - STDOUT: return future.result()
[task 2024-01-16T05:43:29.499Z] 05:43:29 INFO - STDOUT: future = <Task finished name='Task-101' coro=<_wrap_async.<locals>._async_fixture_wrapper.<locals>.setup() done, defined at /builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:305> exception=RuntimeError("Task <Task pending name='Task-101' coro=<_wrap_async.<locals>._async_fixture_wrapper.<locals>.setup() running at /builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:306> cb=[_run_until_complete_cb() at /usr/lib/python3.8/asyncio/base_events.py:180]> got Future <Future pending> attached to a different loop")>
[task 2024-01-16T05:43:29.499Z] 05:43:29 INFO - STDOUT: new_task = True
[task 2024-01-16T05:43:29.500Z] 05:43:29 INFO - STDOUT: self = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2024-01-16T05:43:29.501Z] 05:43:29 INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2024-01-16T05:43:29.502Z] 05:43:29 INFO - STDOUT: :306: in setup
[task 2024-01-16T05:43:29.502Z] 05:43:29 INFO - STDOUT: res = await func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2024-01-16T05:43:29.503Z] 05:43:29 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2024-01-16T05:43:29.503Z] 05:43:29 INFO - STDOUT: func = <function top_context at 0x7f2ad604f1f0>
[task 2024-01-16T05:43:29.504Z] 05:43:29 INFO - STDOUT: kwargs = {'bidi_session': <webdriver.bidi.client.BidiSession object at 0x7f2ad56a6940>}
[task 2024-01-16T05:43:29.505Z] 05:43:29 INFO - STDOUT: request = <SubRequest 'top_context' for <Function test_phases[responseStarted]>>
[task 2024-01-16T05:43:29.506Z] 05:43:29 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/fixtures.py
[task 2024-01-16T05:43:29.506Z] 05:43:29 INFO - STDOUT: :488: in top_context
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: contexts = await bidi_session.browsing_context.get_tree()
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f2ad56a6940>
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: self = <webdriver.bidi.modules.browsing_context.BrowsingContext object at 0x7f2ad56a69a0>
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: kwargs = {}, raw_result = False, params = {}, mod_name = 'browsingContext'
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: cmd_name = 'browsingContext.getTree', future = <Future pending>
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: @functools.wraps(params_fn)
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: async def inner(self: Any, **kwargs: Any) -> Any:
[task 2024-01-16T05:43:29.510Z] 05:43:29 INFO - STDOUT: raw_result = kwargs.pop("raw_result", False)
[task 2024-01-16T05:43:29.511Z] 05:43:29 INFO - STDOUT: params = remove_undefined(params_fn(self, **kwargs))
[task 2024-01-16T05:43:29.511Z] 05:43:29 INFO - STDOUT:
[task 2024-01-16T05:43:29.512Z] 05:43:29 INFO - STDOUT: # Convert the classname and the method name to a bidi command name
[task 2024-01-16T05:43:29.512Z] 05:43:29 INFO - STDOUT: mod_name = owner.__name__[0].lower() + owner.__name__[1:]
[task 2024-01-16T05:43:29.513Z] 05:43:29 INFO - STDOUT: if hasattr(owner, "prefix"):
[task 2024-01-16T05:43:29.513Z] 05:43:29 INFO - STDOUT: mod_name = f"{owner.prefix}:{mod_name}"
[task 2024-01-16T05:43:29.517Z] 05:43:29 INFO - STDOUT: cmd_name = f"{mod_name}.{to_camelcase(name)}"
[task 2024-01-16T05:43:29.517Z] 05:43:29 INFO - STDOUT:
[task 2024-01-16T05:43:29.518Z] 05:43:29 INFO - STDOUT: future = await self.session.send_command(cmd_name, params)
[task 2024-01-16T05:43:29.518Z] 05:43:29 INFO - STDOUT: > result = await future
[task 2024-01-16T05:43:29.518Z] 05:43:29 INFO - STDOUT: E RuntimeError: Task <Task pending name='Task-101' coro=<_wrap_async.<locals>._async_fixture_wrapper.<locals>.setup() running at /builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:306> cb=[_run_until_complete_cb() at /usr/lib/python3.8/asyncio/base_events.py:180]> got Future <Future pending> attached to a different loop
[task 2024-01-16T05:43:29.519Z] 05:43:29 INFO - STDOUT: cmd_name = 'browsingContext.getTree'
[task 2024-01-16T05:43:29.519Z] 05:43:29 INFO - STDOUT: future = <Future pending>
[task 2024-01-16T05:43:29.519Z] 05:43:29 INFO - STDOUT: kwargs = {}
[task 2024-01-16T05:43:29.519Z] 05:43:29 INFO - STDOUT: mod_name = 'browsingContext'
[task 2024-01-16T05:43:29.519Z] 05:43:29 INFO - STDOUT: name = 'get_tree'
[task 2024-01-16T05:43:29.519Z] 05:43:29 INFO - STDOUT: owner = <class 'webdriver.bidi.modules.browsing_context.BrowsingContext'>
[task 2024-01-16T05:43:29.520Z] 05:43:29 INFO - STDOUT: params = {}
[task 2024-01-16T05:43:29.520Z] 05:43:29 INFO - STDOUT: params_fn = <function BrowsingContext.get_tree at 0x7f2ad6108af0>
[task 2024-01-16T05:43:29.521Z] 05:43:29 INFO - STDOUT: raw_result = False
[task 2024-01-16T05:43:29.522Z] 05:43:29 INFO - STDOUT: result_fn = <function BrowsingContext._get_tree at 0x7f2ad6108b80>
[task 2024-01-16T05:43:29.522Z] 05:43:29 INFO - STDOUT: self = <webdriver.bidi.modules.browsing_context.BrowsingContext object at 0x7f2ad56a69a0>
[task 2024-01-16T05:43:29.523Z] 05:43:29 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/bidi/modules/_module.py
[task 2024-01-16T05:43:29.524Z] 05:43:29 INFO - STDOUT: :76: RuntimeError
[task 2024-01-16T05:43:29.524Z] 05:43:29 INFO - STDOUT: =========================== short test summary info ============================
[task 2024-01-16T05:43:29.525Z] 05:43:29 INFO - STDOUT: ERROR tests/web-platform/tests/webdriver/tests/bidi/network/fail_request/request.py::test_phases[beforeRequestSent]
[task 2024-01-16T05:43:29.526Z] 05:43:29 INFO - STDOUT: ERROR tests/web-platform/tests/webdriver/tests/bidi/network/fail_request/request.py::test_phases[responseStarted]
[task 2024-01-16T05:43:29.526Z] 05:43:29 INFO - STDOUT: ============================== 2 errors in 34.68s ==============================
[task 2024-01-16T05:43:29.569Z] 05:43:29 INFO -
[task 2024-01-16T05:43:29.570Z] 05:43:29 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/bidi/network/fail_request/request.py | test_phases[beforeRequestSent] - setup error: asyncio.exceptions.TimeoutError
[task 2024-01-16T05:43:29.570Z] 05:43:29 INFO - event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2024-01-16T05:43:29.571Z] 05:43:29 INFO - request = <SubRequest 'bidi_session' for <Function test_phases[beforeRequestSent]>>
[task 2024-01-16T05:43:29.571Z] 05:43:29 INFO - kwargs = {'capabilities': {}, 'configuration': {'browser': {'args': ['--profile', '/tmp/tmp5gb4qxas.mozrunner'], 'binary': '/bu...FRONTEND': 'noninteractive', 'DISPLAY': ':0', 'ENABLE_E10S': 'false', ...}}}, 'host': '127.0.0.1', 'port': 37511, ...}}
[task 2024-01-16T05:43:29.571Z] 05:43:29 INFO - setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7f2ad56ea670>
<...>
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•