Closed
Bug 1863373
Opened 2 years ago
Closed 2 years ago
Intermittent [tier 2] webdriver/tests/bidi/script/call_function/await_promise.py | test_await_promise_delayed[True] - setup error: concurrent.futures._base.TimeoutError
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=435170208&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/asCNFQ8LSQKw6crSm2NaJw/runs/0/artifacts/public/logs/live_backing.log
[task 2023-11-06T14:59:22.998Z] 14:59:22 INFO - STDOUT: fut = <Task cancelled 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 2023-11-06T14:59:22.999Z] 14:59:22 INFO - STDOUT: loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:22.999Z] 14:59:22 INFO - STDOUT: timeout = 10
[task 2023-11-06T14:59:22.999Z] 14:59:22 INFO - STDOUT: timeout_handle = <TimerHandle cancelled when=773.163166895>
[task 2023-11-06T14:59:23.000Z] 14:59:23 INFO - STDOUT: waiter = <Future finished result=None>
[task 2023-11-06T14:59:23.000Z] 14:59:23 INFO - STDOUT: /usr/lib/python3.7/asyncio/tasks.py
[task 2023-11-06T14:59:23.001Z] 14:59:23 INFO - STDOUT: :449: TimeoutError
[task 2023-11-06T14:59:23.001Z] 14:59:23 INFO - STDOUT: _____________ ERROR at setup of test_await_promise_delayed[False] ______________
[task 2023-11-06T14:59:23.001Z] 14:59:23 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:23.002Z] 14:59:23 INFO - STDOUT: request = <SubRequest 'top_context' for <Function test_await_promise_delayed[False]>>
[task 2023-11-06T14:59:23.002Z] 14:59:23 INFO - STDOUT: kwargs = {'bidi_session': <webdriver.bidi.client.BidiSession object at 0x7fd51eb90a50>}
[task 2023-11-06T14:59:23.003Z] 14:59:23 INFO - STDOUT: setup = <function _wrap_async.<locals>._async_fixture_wrapper.<locals>.setup at 0x7fd51e8ff5f0>
[task 2023-11-06T14:59:23.003Z] 14:59:23 INFO - STDOUT: @functools.wraps(func)
[task 2023-11-06T14:59:23.004Z] 14:59:23 INFO - STDOUT: def _async_fixture_wrapper(
[task 2023-11-06T14:59:23.004Z] 14:59:23 INFO - STDOUT: event_loop: asyncio.AbstractEventLoop, request: SubRequest, **kwargs: Any
[task 2023-11-06T14:59:23.004Z] 14:59:23 INFO - STDOUT: ) -> _R:
[task 2023-11-06T14:59:23.005Z] 14:59:23 INFO - STDOUT: async def setup() -> _R:
[task 2023-11-06T14:59:23.005Z] 14:59:23 INFO - STDOUT: res = await func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2023-11-06T14:59:23.006Z] 14:59:23 INFO - STDOUT: return res
[task 2023-11-06T14:59:23.006Z] 14:59:23 INFO - STDOUT:
[task 2023-11-06T14:59:23.006Z] 14:59:23 INFO - STDOUT: > return event_loop.run_until_complete(setup())
[task 2023-11-06T14:59:23.007Z] 14:59:23 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:23.007Z] 14:59:23 INFO - STDOUT: func = <function top_context at 0x7fd51f500a70>
[task 2023-11-06T14:59:23.008Z] 14:59:23 INFO - STDOUT: kwargs = {'bidi_session': <webdriver.bidi.client.BidiSession object at 0x7fd51eb90a50>}
[task 2023-11-06T14:59:23.008Z] 14:59:23 INFO - STDOUT: request = <SubRequest 'top_context' for <Function test_await_promise_delayed[False]>>
[task 2023-11-06T14:59:23.009Z] 14:59:23 INFO - STDOUT: setup = <function _wrap_async.<locals>._async_fixture_wrapper.<locals>.setup at 0x7fd51e8ff5f0>
[task 2023-11-06T14:59:23.009Z] 14:59:23 INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2023-11-06T14:59:23.009Z] 14:59:23 INFO - STDOUT: :309:
[task 2023-11-06T14:59:23.010Z] 14:59:23 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2023-11-06T14:59:23.010Z] 14:59:23 INFO - STDOUT: /usr/lib/python3.7/asyncio/base_events.py
[task 2023-11-06T14:59:23.011Z] 14:59:23 INFO - STDOUT: :579: in run_until_complete
[task 2023-11-06T14:59:23.011Z] 14:59:23 INFO - STDOUT: return future.result()
[task 2023-11-06T14:59:23.012Z] 14:59:23 INFO - STDOUT: future = <Task finished 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 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.7/asyncio/base_events.py:153]> got Future <Future pending> attached to a different loop')>
[task 2023-11-06T14:59:23.012Z] 14:59:23 INFO - STDOUT: new_task = True
[task 2023-11-06T14:59:23.013Z] 14:59:23 INFO - STDOUT: self = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:23.013Z] 14:59:23 INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2023-11-06T14:59:23.014Z] 14:59:23 INFO - STDOUT: :306: in setup
[task 2023-11-06T14:59:23.014Z] 14:59:23 INFO - STDOUT: res = await func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2023-11-06T14:59:23.014Z] 14:59:23 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:23.015Z] 14:59:23 INFO - STDOUT: func = <function top_context at 0x7fd51f500a70>
[task 2023-11-06T14:59:23.015Z] 14:59:23 INFO - STDOUT: kwargs = {'bidi_session': <webdriver.bidi.client.BidiSession object at 0x7fd51eb90a50>}
[task 2023-11-06T14:59:23.016Z] 14:59:23 INFO - STDOUT: request = <SubRequest 'top_context' for <Function test_await_promise_delayed[False]>>
[task 2023-11-06T14:59:23.016Z] 14:59:23 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/fixtures.py
[task 2023-11-06T14:59:23.017Z] 14:59:23 INFO - STDOUT: :480: in top_context
[task 2023-11-06T14:59:23.017Z] 14:59:23 INFO - STDOUT: contexts = await bidi_session.browsing_context.get_tree()
[task 2023-11-06T14:59:23.018Z] 14:59:23 INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x7fd51eb90a50>
[task 2023-11-06T14:59:23.018Z] 14:59:23 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2023-11-06T14:59:23.018Z] 14:59:23 INFO - STDOUT: self = <webdriver.bidi.modules.browsing_context.BrowsingContext object at 0x7fd51eb3ea50>
[task 2023-11-06T14:59:23.019Z] 14:59:23 INFO - STDOUT: kwargs = {}, raw_result = False, params = {}, mod_name = 'browsingContext'
[task 2023-11-06T14:59:23.019Z] 14:59:23 INFO - STDOUT: cmd_name = 'browsingContext.getTree', future = <Future pending>
[task 2023-11-06T14:59:23.020Z] 14:59:23 INFO - STDOUT: @functools.wraps(params_fn)
[task 2023-11-06T14:59:23.020Z] 14:59:23 INFO - STDOUT: async def inner(self: Any, **kwargs: Any) -> Any:
[task 2023-11-06T14:59:23.021Z] 14:59:23 INFO - STDOUT: raw_result = kwargs.pop("raw_result", False)
[task 2023-11-06T14:59:23.021Z] 14:59:23 INFO - STDOUT: params = params_fn(self, **kwargs)
[task 2023-11-06T14:59:23.021Z] 14:59:23 INFO - STDOUT:
[task 2023-11-06T14:59:23.022Z] 14:59:23 INFO - STDOUT: # Convert the classname and the method name to a bidi command name
[task 2023-11-06T14:59:23.022Z] 14:59:23 INFO - STDOUT: mod_name = owner.__name__[0].lower() + owner.__name__[1:]
[task 2023-11-06T14:59:23.023Z] 14:59:23 INFO - STDOUT: if hasattr(owner, "prefix"):
[task 2023-11-06T14:59:23.023Z] 14:59:23 INFO - STDOUT: mod_name = f"{owner.prefix}:{mod_name}"
[task 2023-11-06T14:59:23.023Z] 14:59:23 INFO - STDOUT: cmd_name = f"{mod_name}.{to_camelcase(name)}"
[task 2023-11-06T14:59:23.024Z] 14:59:23 INFO - STDOUT:
[task 2023-11-06T14:59:23.024Z] 14:59:23 INFO - STDOUT: future = await self.session.send_command(cmd_name, params)
[task 2023-11-06T14:59:23.025Z] 14:59:23 INFO - STDOUT: > result = await future
[task 2023-11-06T14:59:23.025Z] 14:59:23 INFO - STDOUT: E RuntimeError: Task <Task pending 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.7/asyncio/base_events.py:153]> got Future <Future pending> attached to a different loop
[task 2023-11-06T14:59:23.026Z] 14:59:23 INFO - STDOUT: cmd_name = 'browsingContext.getTree'
[task 2023-11-06T14:59:23.026Z] 14:59:23 INFO - STDOUT: future = <Future pending>
[task 2023-11-06T14:59:23.026Z] 14:59:23 INFO - STDOUT: kwargs = {}
[task 2023-11-06T14:59:23.027Z] 14:59:23 INFO - STDOUT: mod_name = 'browsingContext'
[task 2023-11-06T14:59:23.027Z] 14:59:23 INFO - STDOUT: name = 'get_tree'
[task 2023-11-06T14:59:23.028Z] 14:59:23 INFO - STDOUT: owner = <class 'webdriver.bidi.modules.browsing_context.BrowsingContext'>
[task 2023-11-06T14:59:23.028Z] 14:59:23 INFO - STDOUT: params = {}
[task 2023-11-06T14:59:23.029Z] 14:59:23 INFO - STDOUT: params_fn = <function BrowsingContext.get_tree at 0x7fd51f5fed40>
[task 2023-11-06T14:59:23.029Z] 14:59:23 INFO - STDOUT: raw_result = False
[task 2023-11-06T14:59:23.029Z] 14:59:23 INFO - STDOUT: result_fn = <function BrowsingContext._get_tree at 0x7fd51f5fedd0>
[task 2023-11-06T14:59:23.030Z] 14:59:23 INFO - STDOUT: self = <webdriver.bidi.modules.browsing_context.BrowsingContext object at 0x7fd51eb3ea50>
[task 2023-11-06T14:59:23.030Z] 14:59:23 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/bidi/modules/_module.py
[task 2023-11-06T14:59:23.031Z] 14:59:23 INFO - STDOUT: :73: RuntimeError
[task 2023-11-06T14:59:23.031Z] 14:59:23 INFO - STDOUT: ____________ ERROR at setup of test_await_promise_async_arrow[True] ____________
[task 2023-11-06T14:59:23.032Z] 14:59:23 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:23.032Z] 14:59:23 INFO - STDOUT: request = <SubRequest 'bidi_session' for <Function test_await_promise_async_arrow[True]>>
[task 2023-11-06T14:59:23.033Z] 14:59:23 INFO - STDOUT: kwargs = {'capabilities': {}, 'configuration': {'browser': {'args': ['--profile', '/tmp/tmp4pvhvhgy.mozrunner'], 'binary': '/bu...FRONTEND': 'noninteractive', 'DISPLAY': ':0', 'ENABLE_E10S': 'false', ...}}}, 'host': '127.0.0.1', 'port': 44881, ...}}
[task 2023-11-06T14:59:23.033Z] 14:59:23 INFO - STDOUT: setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7fd51eb484d0>
[task 2023-11-06T14:59:23.034Z] 14:59:23 INFO - STDOUT: finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fd51eb48ef0>
[task 2023-11-06T14:59:23.034Z] 14:59:23 INFO - STDOUT: @functools.wraps(func)
[task 2023-11-06T14:59:23.034Z] 14:59:23 INFO - STDOUT: def _asyncgen_fixture_wrapper(
[task 2023-11-06T14:59:23.035Z] 14:59:23 INFO - STDOUT: event_loop: asyncio.AbstractEventLoop, request: SubRequest, **kwargs: Any
[task 2023-11-06T14:59:23.035Z] 14:59:23 INFO - STDOUT: ) -> _R:
[task 2023-11-06T14:59:23.036Z] 14:59:23 INFO - STDOUT: gen_obj = func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2023-11-06T14:59:23.036Z] 14:59:23 INFO - STDOUT:
[task 2023-11-06T14:59:23.036Z] 14:59:23 INFO - STDOUT: async def setup() -> _R:
[task 2023-11-06T14:59:23.037Z] 14:59:23 INFO - STDOUT: res = await gen_obj.__anext__()
[task 2023-11-06T14:59:23.037Z] 14:59:23 INFO - STDOUT: return res
[task 2023-11-06T14:59:23.038Z] 14:59:23 INFO - STDOUT:
[task 2023-11-06T14:59:23.038Z] 14:59:23 INFO - STDOUT: def finalizer() -> None:
[task 2023-11-06T14:59:23.038Z] 14:59:23 INFO - STDOUT: """Yield again, to finalize."""
[task 2023-11-06T14:59:23.039Z] 14:59:23 INFO - STDOUT:
[task 2023-11-06T14:59:23.039Z] 14:59:23 INFO - STDOUT: async def async_finalizer() -> None:
[task 2023-11-06T14:59:23.040Z] 14:59:23 INFO - STDOUT: try:
[task 2023-11-06T14:59:23.040Z] 14:59:23 INFO - STDOUT: await gen_obj.__anext__()
[task 2023-11-06T14:59:23.040Z] 14:59:23 INFO - STDOUT: except StopAsyncIteration:
[task 2023-11-06T14:59:23.041Z] 14:59:23 INFO - STDOUT: pass
[task 2023-11-06T14:59:23.041Z] 14:59:23 INFO - STDOUT: else:
[task 2023-11-06T14:59:23.042Z] 14:59:23 INFO - STDOUT: msg = "Async generator fixture didn't stop."
[task 2023-11-06T14:59:23.042Z] 14:59:23 INFO - STDOUT: msg += "Yield only once."
[task 2023-11-06T14:59:23.042Z] 14:59:23 INFO - STDOUT: raise ValueError(msg)
[task 2023-11-06T14:59:23.043Z] 14:59:23 INFO - STDOUT:
[task 2023-11-06T14:59:23.043Z] 14:59:23 INFO - STDOUT: event_loop.run_until_complete(async_finalizer())
[task 2023-11-06T14:59:23.044Z] 14:59:23 INFO - STDOUT:
[task 2023-11-06T14:59:23.044Z] 14:59:23 INFO - STDOUT: > result = event_loop.run_until_complete(setup())
[task 2023-11-06T14:59:23.045Z] 14:59:23 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:23.045Z] 14:59:23 INFO - STDOUT: finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fd51eb48ef0>
[task 2023-11-06T14:59:23.045Z] 14:59:23 INFO - STDOUT: func = <function bidi_session at 0x7fd51f4fb680>
[task 2023-11-06T14:59:23.046Z] 14:59:23 INFO - STDOUT: gen_obj = <async_generator object bidi_session at 0x7fd51eb48290>
[task 2023-11-06T14:59:23.099Z] 14:59:23 INFO -
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/bidi/script/call_function/await_promise.py | test_await_promise_delayed[True] - setup error: concurrent.futures._base.TimeoutError
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - request = <SubRequest 'bidi_session' for <Function test_await_promise_delayed[True]>>
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - kwargs = {'capabilities': {}, 'configuration': {'browser': {'args': ['--profile', '/tmp/tmp4pvhvhgy.mozrunner'], 'binary': '/bu...FRONTEND': 'noninteractive', 'DISPLAY': ':0', 'ENABLE_E10S': 'false', ...}}}, 'host': '127.0.0.1', 'port': 44881, ...}}
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7fd51ebbdf80>
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fd51ebbd290>
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO -
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - @functools.wraps(func)
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - def _asyncgen_fixture_wrapper(
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - event_loop: asyncio.AbstractEventLoop, request: SubRequest, **kwargs: Any
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - ) -> _R:
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - gen_obj = func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO -
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - async def setup() -> _R:
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - res = await gen_obj.__anext__()
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - return res
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO -
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - def finalizer() -> None:
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - """Yield again, to finalize."""
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO -
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - async def async_finalizer() -> None:
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - try:
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - await gen_obj.__anext__()
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - except StopAsyncIteration:
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - pass
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - else:
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - msg = "Async generator fixture didn't stop."
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - msg += "Yield only once."
[task 2023-11-06T14:59:23.100Z] 14:59:23 INFO - raise ValueError(msg)
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO -
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO - event_loop.run_until_complete(async_finalizer())
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO -
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO - > result = event_loop.run_until_complete(setup())
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO -
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO - event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO - finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fd51ebbd290>
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO - func = <function bidi_session at 0x7fd51f4fb680>
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO - gen_obj = <async_generator object bidi_session at 0x7fd51ebbd170>
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO - kwargs = {'capabilities': {},
[task 2023-11-06T14:59:23.101Z] 14:59:23 INFO - 'configuration': {'browser': {'args': ['--profile',
<...>
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•