Closed Bug 1755295 Opened 2 years ago Closed 2 years ago

Perma /webdriver/tests/bidi/browsing_context/context_created/context_created.py | test_not_unsubscribed - setup error: webdriver.error.SessionNotCreatedException: session not created (Requested bidi session.. when Gecko 98 merges to Beta on 2022-03-08

Categories

(Remote Protocol :: WebDriver BiDi, defect)

Product:
Remote Protocol
Component:
WebDriver BiDi
Type:
defect

Tracking

(firefox-esr91 unaffected, firefox97 unaffected, firefox98 unaffected, firefox99+ verified)

Status:
VERIFIED FIXED
Milestone:
99 Branch
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox97 --- unaffected
firefox98 --- unaffected
firefox99 + verified

People

(Reporter: noriszfay, Assigned: whimboo)

References

(Regression)

Details

(Keywords: regression)

Central-as-beta simulation
How to run these simulations

Failure log:

[task 2022-02-14T12:45:14.217Z] 12:45:14     INFO - PID 1695 | 1644842714216	Marionette	DEBUG	Closed connection 0
[task 2022-02-14T12:45:14.309Z] 12:45:14     INFO - PID 1695 | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-02-14T12:45:14.414Z] 12:45:14     INFO - PID 1695 | 1644842714413	geckodriver::browser	DEBUG	Browser process stopped: exit status: 0
[task 2022-02-14T12:45:14.415Z] 12:45:14     INFO - PID 1695 | 1644842714413	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2022-02-14T12:45:14.463Z] 12:45:14     INFO - STDOUT: ERROR
[task 2022-02-14T12:45:14.465Z] 12:45:14     INFO - STDOUT: ==================================== ERRORS ====================================
[task 2022-02-14T12:45:14.466Z] 12:45:14     INFO - STDOUT: ___________________ ERROR at setup of test_not_unsubscribed ____________________
[task 2022-02-14T12:45:14.466Z] 12:45:14     INFO - STDOUT: args = ()
[task 2022-02-14T12:45:14.467Z] 12:45:14     INFO - STDOUT: kwargs = {'capabilities': {}, 'configuration': {'capabilities': {'moz:firefoxOptions': {'args': ['--profile', '/tmp/tmpz0anaea1...}, 'host': '127.0.0.1', 'port': 47390, 'webdriver': {'args': ['-vv'], 'binary': '/builds/worker/fetches/geckodriver'}}}
[task 2022-02-14T12:45:14.468Z] 12:45:14     INFO - STDOUT: request = <SubRequest 'bidi_session' for <Function test_not_unsubscribed>>
[task 2022-02-14T12:45:14.468Z] 12:45:14     INFO - STDOUT: setup = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7ff094405d90>
[task 2022-02-14T12:45:14.469Z] 12:45:14     INFO - STDOUT: finalizer = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7ff094501400>
[task 2022-02-14T12:45:14.470Z] 12:45:14     INFO - STDOUT:     def wrapper(*args, **kwargs):
[task 2022-02-14T12:45:14.473Z] 12:45:14     INFO - STDOUT:         loop = fixture_stripper.get_and_strip_from(FixtureStripper.EVENT_LOOP, kwargs)
[task 2022-02-14T12:45:14.473Z] 12:45:14     INFO - STDOUT:         request = fixture_stripper.get_and_strip_from(FixtureStripper.REQUEST, kwargs)
[task 2022-02-14T12:45:14.473Z] 12:45:14     INFO - STDOUT:     
[task 2022-02-14T12:45:14.474Z] 12:45:14     INFO - STDOUT:         gen_obj = generator(*args, **kwargs)
[task 2022-02-14T12:45:14.474Z] 12:45:14     INFO - STDOUT:     
[task 2022-02-14T12:45:14.474Z] 12:45:14     INFO - STDOUT:         async def setup():
[task 2022-02-14T12:45:14.481Z] 12:45:14     INFO - 
[task 2022-02-14T12:45:14.482Z] 12:45:14     INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/bidi/browsing_context/context_created/context_created.py | test_not_unsubscribed - setup error: webdriver.error.SessionNotCreatedException: session not created (Requested bidi session, but webSocketUrl capability not found)
[task 2022-02-14T12:45:14.482Z] 12:45:14     INFO - args = ()
[task 2022-02-14T12:45:14.482Z] 12:45:14     INFO - kwargs = {'capabilities': {}, 'configuration': {'capabilities': {'moz:firefoxOptions': {'args': ['--profile', '/tmp/tmpz0anaea1...}, 'host': '127.0.0.1', 'port': 47390, 'webdriver': {'args': ['-vv'], 'binary': '/builds/worker/fetches/geckodriver'}}}
[task 2022-02-14T12:45:14.482Z] 12:45:14     INFO - request = <SubRequest 'bidi_session' for <Function test_not_unsubscribed>>
[task 2022-02-14T12:45:14.482Z] 12:45:14     INFO - setup = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7ff094405d90>
[task 2022-02-14T12:45:14.482Z] 12:45:14     INFO - finalizer = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7ff094501400>
[task 2022-02-14T12:45:14.482Z] 12:45:14     INFO - 
[task 2022-02-14T12:45:14.482Z] 12:45:14     INFO -     def wrapper(*args, **kwargs):
[task 2022-02-14T12:45:14.483Z] 12:45:14     INFO -         loop = fixture_stripper.get_and_strip_from(FixtureStripper.EVENT_LOOP, kwargs)
[task 2022-02-14T12:45:14.483Z] 12:45:14     INFO -         request = fixture_stripper.get_and_strip_from(FixtureStripper.REQUEST, kwargs)
[task 2022-02-14T12:45:14.483Z] 12:45:14     INFO -     
[task 2022-02-14T12:45:14.483Z] 12:45:14     INFO -         gen_obj = generator(*args, **kwargs)
[task 2022-02-14T12:45:14.484Z] 12:45:14     INFO -     
[task 2022-02-14T12:45:14.484Z] 12:45:14     INFO -         async def setup():
[task 2022-02-14T12:45:14.484Z] 12:45:14     INFO -             res = await gen_obj.__anext__()
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -             return res
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -     
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -         def finalizer():
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -             """Yield again, to finalize."""
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -             async def async_finalizer():
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                 try:
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                     await gen_obj.__anext__()
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                 except StopAsyncIteration:
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                     pass
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                 else:
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                     msg = "Async generator fixture didn't stop."
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                     msg += "Yield only once."
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                     raise ValueError(msg)
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -             loop.run_until_complete(async_finalizer())
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -     
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -         request.addfinalizer(finalizer)
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO - >       return loop.run_until_complete(setup())
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO - 
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO - args       = ()
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO - finalizer  = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7ff094501400>
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO - fixture_stripper = <pytest_asyncio.plugin.FixtureStripper object at 0x7ff094356208>
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO - gen_obj    = <async_generator object bidi_session at 0x7ff0943708d0>
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO - generator  = <function bidi_session at 0x7ff0943ca048>
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO - kwargs     = {'capabilities': {},
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -  'configuration': {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                                                                     '/tmp/tmpz0anaea1.mozrunner'],
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                                                            'binary': '/builds/worker/workspace/build/application/firefox/firefox'}},
[task 2022-02-14T12:45:14.485Z] 12:45:14     INFO -                    'host': '127.0.0.1',
[task 2022-02-14T12:45:14.486Z] 12:45:14     INFO -                    'port': 47390,
[task 2022-02-14T12:45:14.486Z] 12:45:14     INFO -                    'webdriver': {'args': ['-vv'],
[task 2022-02-14T12:45:14.486Z] 12:45:14     INFO -                                  'binary': '/builds/worker/fetches/geckodriver'}}}
[task 2022-02-14T12:45:14.486Z] 12:45:14     INFO - loop       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2022-02-14T12:45:14.487Z] 12:45:14     INFO - request    = <SubRequest 'bidi_session' for <Function test_not_unsubscribed>>
[task 2022-02-14T12:45:14.487Z] 12:45:14     INFO - setup      = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7ff094405d90>
[task 2022-02-14T12:45:14.487Z] 12:45:14     INFO - 
[task 2022-02-14T12:45:14.487Z] 12:45:14     INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:127: 
[task 2022-02-14T12:45:14.487Z] 12:45:14     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-02-14T12:45:14.487Z] 12:45:14     INFO - /usr/lib/python3.6/asyncio/base_events.py:484: in run_until_complete
[task 2022-02-14T12:45:14.487Z] 12:45:14     INFO -     return future.result()
[task 2022-02-14T12:45:14.488Z] 12:45:14     INFO -         future     = <Task finished coro=<pytest_fixture_setup.<locals>.wrapper.<locals>.setup() done, defined at /builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:109> exception=<SessionNotCreatedException http_status=Requested bidi session, but webSocketUrl capability not found>>
[task 2022-02-14T12:45:14.488Z] 12:45:14     INFO -         new_task   = True
[task 2022-02-14T12:45:14.489Z] 12:45:14     INFO -         self       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2022-02-14T12:45:14.489Z] 12:45:14     INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:110: in setup
[task 2022-02-14T12:45:14.489Z] 12:45:14     INFO -     res = await gen_obj.__anext__()
[task 2022-02-14T12:45:14.489Z] 12:45:14     INFO -         gen_obj    = <async_generator object bidi_session at 0x7ff0943708d0>
[task 2022-02-14T12:45:14.489Z] 12:45:14     INFO - tests/web-platform/tests/webdriver/tests/support/fixtures.py:186: in bidi_session
[task 2022-02-14T12:45:14.489Z] 12:45:14     INFO -     _current_session.start()
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -         capabilities = {}
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -         caps       = {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -                                                  '/tmp/tmpz0anaea1.mozrunner'],
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -                                         'binary': '/builds/worker/workspace/build/application/firefox/firefox'},
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -                  'webSocketUrl': True}}
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -         configuration = {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -                                                   '/tmp/tmpz0anaea1.mozrunner'],
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -                                          'binary': '/builds/worker/workspace/build/application/firefox/firefox'}},
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -  'host': '127.0.0.1',
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -  'port': 47390,
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO -  'webdriver': {'args': ['-vv'], 'binary': '/builds/worker/fetches/geckodriver'}}
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO - 
[task 2022-02-14T12:45:14.490Z] 12:45:14     INFO - self = <Session (disconnected)>
Flags: needinfo?(hskupin)

Will be fixed by the follow-up patch on bug 1694389.

Depends on: 1694389
Flags: needinfo?(hskupin)
Has Regression Range: --- → yes

Fixed with the patch in Bug 1694389 https://bugzilla.mozilla.org/show_bug.cgi?id=1694389#c30. And verified in today's beta-sim.

Status: NEW → RESOLVED
Closed: 2 years ago
status-firefox99: affected → fixed
Resolution: --- → FIXED
Status: RESOLVED → VERIFIED
status-firefox99: fixed → verified
Target Milestone: --- → 99 Branch
Assignee: nobody → hskupin
You need to log in before you can comment on or make changes to this bug.