1846905 - Intermittent Wd | <random> - setup error: websockets.exceptions.InvalidStatusCode: server rejected WebSocket connection: HTTP 404

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

[task 2023-08-03T01:37:26.873Z] 01:37:26     INFO - STDOUT: /Library/Frameworks/Python.framework/Versions/3.7/lib/python3.7/asyncio/base_events.py
[task 2023-08-03T01:37:26.873Z] 01:37:26     INFO - STDOUT: :587: in run_until_complete
[task 2023-08-03T01:37:26.874Z] 01:37:26     INFO - STDOUT:     return future.result()
[task 2023-08-03T01:37:26.874Z] 01:37:26     INFO - STDOUT:         future     = <Task finished coro=<_wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup() done, defined at /opt/worker/tasks/task_169102646699440/build/tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:274> exception=InvalidStatusCode('server rejected WebSocket connection: HTTP 404')>
[task 2023-08-03T01:37:26.875Z] 01:37:26     INFO - STDOUT:         new_task   = True
[task 2023-08-03T01:37:26.875Z] 01:37:26     INFO - STDOUT:         self       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T01:37:26.875Z] 01:37:26     INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2023-08-03T01:37:26.876Z] 01:37:26     INFO - STDOUT: :275: in setup
[task 2023-08-03T01:37:26.876Z] 01:37:26     INFO - STDOUT:     res = await gen_obj.__anext__()
[task 2023-08-03T01:37:26.876Z] 01:37:26     INFO - STDOUT:         gen_obj    = <async_generator object bidi_session at 0x7fcb555c2b00>
[task 2023-08-03T01:37:26.877Z] 01:37:26     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/fixtures.py
[task 2023-08-03T01:37:26.877Z] 01:37:26     INFO - STDOUT: :184: in bidi_session
[task 2023-08-03T01:37:26.877Z] 01:37:26     INFO - STDOUT:     await _current_session.bidi_session.start()
[task 2023-08-03T01:37:26.878Z] 01:37:26     INFO - STDOUT:         capabilities = {}
[task 2023-08-03T01:37:26.878Z] 01:37:26     INFO - STDOUT:         caps       = {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T01:37:26.878Z] 01:37:26     INFO -                                                  '/var/folders/6k/2vg3bwq97ydbnwx63vzfvzjc000014/T/tmpuyqyf_q0.mozrunner']},
[task 2023-08-03T01:37:26.878Z] 01:37:26     INFO -                  'webSocketUrl': True}}
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO - STDOUT:         configuration = {'browser': {'args': ['--profile',
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -                       '/var/folders/6k/2vg3bwq97ydbnwx63vzfvzjc000014/T/tmpuyqyf_q0.mozrunner'],
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -              'binary': '/opt/worker/tasks/task_169102646699440/build/application/Firefox '
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -                        'Nightly.app/Contents/MacOS/firefox'},
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -  'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -                                                   '/var/folders/6k/2vg3bwq97ydbnwx63vzfvzjc000014/T/tmpuyqyf_q0.mozrunner']}},
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -  'host': '127.0.0.1',
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -  'port': 54009,
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -  'timeout_multiplier': 1,
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -  'webdriver': {'args': ['-vv',
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -                         '--binary',
[task 2023-08-03T01:37:26.879Z] 01:37:26     INFO -                         '/opt/worker/tasks/task_169102646699440/build/application/Firefox '
[task 2023-08-03T01:37:26.880Z] 01:37:26     INFO -                         'Nightly.app/Contents/MacOS/firefox'],
[task 2023-08-03T01:37:26.880Z] 01:37:26     INFO -                'binary': '/opt/worker/tasks/task_169102646699440/fetches/geckodriver'}}
[task 2023-08-03T01:37:26.880Z] 01:37:26     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/bidi/client.py
[task 2023-08-03T01:37:26.880Z] 01:37:26     INFO - STDOUT: :151: in start
[task 2023-08-03T01:37:26.881Z] 01:37:26     INFO - STDOUT:     await self.start_transport(loop)
[task 2023-08-03T01:37:26.882Z] 01:37:26     INFO - STDOUT:         loop       = None
[task 2023-08-03T01:37:26.882Z] 01:37:26     INFO - STDOUT:         self       = <webdriver.bidi.client.BidiSession object at 0x7fcb557cad10>
[task 2023-08-03T01:37:26.883Z] 01:37:26     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/bidi/client.py
[task 2023-08-03T01:37:26.883Z] 01:37:26     INFO - STDOUT: :145: in start_transport
[task 2023-08-03T01:37:26.884Z] 01:37:26     INFO - STDOUT:     await self.transport.start()
[task 2023-08-03T01:37:26.885Z] 01:37:26     INFO - STDOUT:         loop       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T01:37:26.885Z] 01:37:26     INFO - STDOUT:         self       = <webdriver.bidi.client.BidiSession object at 0x7fcb557cad10>
[task 2023-08-03T01:37:26.886Z] 01:37:26     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/bidi/transport.py
[task 2023-08-03T01:37:26.887Z] 01:37:26     INFO - STDOUT: :40: in start
[task 2023-08-03T01:37:26.887Z] 01:37:26     INFO - STDOUT:     self.connection = await websockets.connect(self.url)  # type: ignore
[task 2023-08-03T01:37:26.903Z] 01:37:26     INFO - 
[task 2023-08-03T01:37:26.903Z] 01:37:26     INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/bidi/browsing_context/activate/activate.py | test_activate - setup error: websockets.exceptions.InvalidStatusCode: server rejected WebSocket connection: HTTP 404
[task 2023-08-03T01:37:26.903Z] 01:37:26     INFO - event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T01:37:26.903Z] 01:37:26     INFO - request = <SubRequest 'bidi_session' for <Function test_activate>>
[task 2023-08-03T01:37:26.903Z] 01:37:26     INFO - kwargs = {'capabilities': {}, 'configuration': {'browser': {'args': ['--profile', '/var/folders/6k/2vg3bwq97ydbnwx63vzfvzjc0000... '/var/folders/6k/2vg3bwq97ydbnwx63vzfvzjc000014/T/tmpuyqyf_q0.mozrunner']}}, 'host': '127.0.0.1', 'port': 54009, ...}}
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO - setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7fcb555c2b90>
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO - finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fcb555c2c20>
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO - 
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -     @functools.wraps(func)
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -     def _asyncgen_fixture_wrapper(
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -         event_loop: asyncio.AbstractEventLoop, request: SubRequest, **kwargs: Any
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -     ) -> _R:
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -         gen_obj = func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -     
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -         async def setup() -> _R:
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -             res = await gen_obj.__anext__()
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -             return res
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -     
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -         def finalizer() -> None:
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -             """Yield again, to finalize."""
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -     
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -             async def async_finalizer() -> None:
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -                 try:
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -                     await gen_obj.__anext__()
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -                 except StopAsyncIteration:
[task 2023-08-03T01:37:26.904Z] 01:37:26     INFO -                     pass
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -                 else:
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -                     msg = "Async generator fixture didn't stop."
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -                     msg += "Yield only once."
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -                     raise ValueError(msg)
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -     
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -             event_loop.run_until_complete(async_finalizer())
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -     
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO - >       result = event_loop.run_until_complete(setup())
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO - 
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO - event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO - finalizer  = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fcb555c2c20>
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO - func       = <function bidi_session at 0x7fcb561ae320>
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO - gen_obj    = <async_generator object bidi_session at 0x7fcb555c2b00>
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO - kwargs     = {'capabilities': {},
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -  'configuration': {'browser': {'args': ['--profile',
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -                                         '/var/folders/6k/2vg3bwq97ydbnwx63vzfvzjc000014/T/tmpuyqyf_q0.mozrunner'],
[task 2023-08-03T01:37:26.905Z] 01:37:26     INFO -                                'binary': '/opt/worker/tasks/task_169102646699440/build/application/Firefox '
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                                          'Nightly.app/Contents/MacOS/firefox'},
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                    'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                                                                     '/var/folders/6k/2vg3bwq97ydbnwx63vzfvzjc000014/T/tmpuyqyf_q0.mozrunner']}},
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                    'host': '127.0.0.1',
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                    'port': 54009,
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                    'timeout_multiplier': 1,
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                    'webdriver': {'args': ['-vv',
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                                           '--binary',
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                                           '/opt/worker/tasks/task_169102646699440/build/application/Firefox '
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                                           'Nightly.app/Contents/MacOS/firefox'],
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO -                                  'binary': '/opt/worker/tasks/task_169102646699440/fetches/geckodriver'}}}
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO - request    = <SubRequest 'bidi_session' for <Function test_activate>>
[task 2023-08-03T01:37:26.906Z] 01:37:26     INFO - setup      = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7fcb555c2b90>
[task 2023-08-03T01:37:26.907Z] 01:37:26     INFO - 
[task 2023-08-03T01:37:26.907Z] 01:37:26     INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:293: 
[task 2023-08-03T01:37:26.907Z] 01:37:26     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 

Comment 2

[Intermittent Failures Robot]

2 failures in 3276 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• macosx1015-64-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1846905&startday=2023-07-31&endday=2023-08-06&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 3076 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• macosx1015-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1846905&startday=2023-08-14&endday=2023-08-20&tree=all

Comment 4

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 5

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Table

	no_variant
windows11-x86_64/opt	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1846905&startday=2025-06-30&endday=2025-07-06&tree=all