Closed Bug 1870951 Opened 2 years ago Closed 1 year ago

Intermittent /webdriver/tests/bidi/input/release_actions/release.py | single tracking bug

Categories

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

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Attachments

(1 obsolete file)

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


[task 2023-12-19T22:56:09.925Z] 22:56:09     INFO - STDOUT: ==================================== ERRORS ====================================
[task 2023-12-19T22:56:09.928Z] 22:56:09     INFO - STDOUT: __________ ERROR at setup of test_release_no_actions_sends_no_events ___________
[task 2023-12-19T22:56:09.928Z] 22:56:09     INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-12-19T22:56:09.928Z] 22:56:09     INFO - STDOUT: request = <SubRequest 'bidi_session' for <Function test_release_no_actions_sends_no_events>>
[task 2023-12-19T22:56:09.928Z] 22:56:09     INFO - STDOUT: kwargs = {'capabilities': {}, 'configuration': {'browser': {'args': ['--profile', '/tmp/tmpij6cif3v.mozrunner'], 'binary': '/bu...FRONTEND': 'noninteractive', 'DISPLAY': ':0', 'ENABLE_E10S': 'false', ...}}}, 'host': '127.0.0.1', 'port': 33722, ...}}
[task 2023-12-19T22:56:09.928Z] 22:56:09     INFO - STDOUT: setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7f43aac3f790>
[task 2023-12-19T22:56:09.929Z] 22:56:09     INFO - STDOUT: finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7f43aac3faf0>
[task 2023-12-19T22:56:09.929Z] 22:56:09     INFO - STDOUT:     @functools.wraps(func)
[task 2023-12-19T22:56:09.932Z] 22:56:09     INFO - STDOUT:     def _asyncgen_fixture_wrapper(
[task 2023-12-19T22:56:09.933Z] 22:56:09     INFO - STDOUT:         event_loop: asyncio.AbstractEventLoop, request: SubRequest, **kwargs: Any
[task 2023-12-19T22:56:09.933Z] 22:56:09     INFO - STDOUT:     ) -> _R:
[task 2023-12-19T22:56:09.934Z] 22:56:09     INFO - STDOUT:         gen_obj = func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2023-12-19T22:56:09.934Z] 22:56:09     INFO - STDOUT:     
[task 2023-12-19T22:56:09.935Z] 22:56:09     INFO - STDOUT:         async def setup() -> _R:
[task 2023-12-19T22:56:09.936Z] 22:56:09     INFO - STDOUT:             res = await gen_obj.__anext__()
[task 2023-12-19T22:56:09.936Z] 22:56:09     INFO - STDOUT:             return res
[task 2023-12-19T22:56:09.937Z] 22:56:09     INFO - STDOUT:     
[task 2023-12-19T22:56:09.938Z] 22:56:09     INFO - STDOUT:         def finalizer() -> None:
[task 2023-12-19T22:56:09.939Z] 22:56:09     INFO - STDOUT:             """Yield again, to finalize."""
[task 2023-12-19T22:56:09.939Z] 22:56:09     INFO - STDOUT:     
[task 2023-12-19T22:56:09.939Z] 22:56:09     INFO - STDOUT:             async def async_finalizer() -> None:
[task 2023-12-19T22:56:09.939Z] 22:56:09     INFO - STDOUT:                 try:
[task 2023-12-19T22:56:09.940Z] 22:56:09     INFO - STDOUT:                     await gen_obj.__anext__()
[task 2023-12-19T22:56:09.940Z] 22:56:09     INFO - STDOUT:                 except StopAsyncIteration:
[task 2023-12-19T22:56:09.941Z] 22:56:09     INFO - STDOUT:                     pass
[task 2023-12-19T22:56:09.941Z] 22:56:09     INFO - STDOUT:                 else:
[task 2023-12-19T22:56:09.942Z] 22:56:09     INFO - STDOUT:                     msg = "Async generator fixture didn't stop."
[task 2023-12-19T22:56:09.942Z] 22:56:09     INFO - STDOUT:                     msg += "Yield only once."
[task 2023-12-19T22:56:09.943Z] 22:56:09     INFO - STDOUT:                     raise ValueError(msg)
[task 2023-12-19T22:56:09.944Z] 22:56:09     INFO - STDOUT:     
[task 2023-12-19T22:56:09.944Z] 22:56:09     INFO - STDOUT:             event_loop.run_until_complete(async_finalizer())
[task 2023-12-19T22:56:09.945Z] 22:56:09     INFO - STDOUT:     
[task 2023-12-19T22:56:09.945Z] 22:56:09     INFO - STDOUT: >       result = event_loop.run_until_complete(setup())
[task 2023-12-19T22:56:09.946Z] 22:56:09     INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-12-19T22:56:09.946Z] 22:56:09     INFO - STDOUT: finalizer  = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7f43aac3faf0>
[task 2023-12-19T22:56:09.947Z] 22:56:09     INFO - STDOUT: func       = <function bidi_session at 0x7f43ab5bb430>
[task 2023-12-19T22:56:09.947Z] 22:56:09     INFO - STDOUT: gen_obj    = <async_generator object bidi_session at 0x7f43aac3f820>
[........................]
[task 2023-12-19T22:56:09.986Z] 22:56:09     INFO -                    'host': '127.0.0.1',
[task 2023-12-19T22:56:09.986Z] 22:56:09     INFO -                    'port': 33722,
[task 2023-12-19T22:56:09.986Z] 22:56:09     INFO -                    'timeout_multiplier': 8,
[task 2023-12-19T22:56:09.986Z] 22:56:09     INFO -                    'webdriver': {'args': ['-vv',
[task 2023-12-19T22:56:09.986Z] 22:56:09     INFO -                                           '--binary',
[task 2023-12-19T22:56:09.986Z] 22:56:09     INFO -                                           '/builds/worker/workspace/build/application/firefox/firefox'],
[task 2023-12-19T22:56:09.986Z] 22:56:09     INFO -                                  'binary': '/builds/worker/fetches/geckodriver'}}}
[task 2023-12-19T22:56:10.044Z] 22:56:10     INFO - 
[task 2023-12-19T22:56:10.044Z] 22:56:10     INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/bidi/input/release_actions/release.py | test_release_no_actions_sends_no_events - setup error: asyncio.exceptions.TimeoutError

The problem is at:
https://treeherder.mozilla.org/logviewer?job_id=440767086&repo=mozilla-beta&lineNumber=24841-24843

[task 2023-12-19T22:55:44.839Z] 22:55:44     INFO - PID 7000 | 1703026544836	Marionette	DEBUG	0 <- [1,1,null,{"sessionId":"c8981e62-8aa3-4023-8b48-036afca57534","capabilities":{"browserName":"firefox","browserVersion":"122.0","platformName":"linux","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":false,"unhandledPromptBehavior":"dismiss and notify","webSocketUrl":"ws://127.0.0.1:9222/session/c8981e62-8aa3-4023-8b48-036afca57534","moz:accessibilityChecks":false,"moz:buildID":"20231219220830","moz:debuggerAddress":"127.0.0.1:9222","moz:headless":true,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":7572,"moz:profile":"/tmp/tmpij6cif3v.mozrunner","moz:shutdownTimeout":360000,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-12-19T22:55:44.868Z] 22:55:44     INFO - PID 7000 | 1703026544861	webdriver::server	DEBUG	<- 200 OK {"value":{"sessionId":"c8981e62-8aa3-4023-8b48-036afca57534","capabilities":{"acceptInsecureCerts":false,"browserNa ... dledPromptBehavior":"dismiss and notify","webSocketUrl":"ws://127.0.0.1:9222/session/c8981e62-8aa3-4023-8b48-036afca57534"}}}
[task 2023-12-19T22:56:04.998Z] 22:56:04     INFO - STDOUT: ERROR

Something stalled most likely in the Python WebDriver BiDi client or the test.

Attachment #9381421 - Attachment is obsolete: true
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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

This is actually bug 1832294.

Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: