Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=350360493&repo=mozilla-release
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/T-qAGFWHRQub6U1pyf4chA/runs/0/artifacts/public/logs/live_backing.log
[task 2021-09-02T14:57:01.722Z] 14:57:01 INFO - TEST-OK | /webdriver/tests/bidi/new_session/connect.py | took 48495ms
[task 2021-09-02T14:57:01.722Z] 14:57:01 INFO - Restarting browser for new test group
[task 2021-09-02T14:57:01.779Z] 14:57:01 INFO - STDOUT: request.addfinalizer(finalizer)
[task 2021-09-02T14:57:01.780Z] 14:57:01 INFO - STDOUT: > return loop.run_until_complete(setup())
[task 2021-09-02T14:57:01.780Z] 14:57:01 INFO - STDOUT: args = ()
[task 2021-09-02T14:57:01.780Z] 14:57:01 INFO - STDOUT: finalizer = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7fe84a6467b8>
[task 2021-09-02T14:57:01.780Z] 14:57:01 INFO - STDOUT: fixture_stripper = <pytest_asyncio.plugin.FixtureStripper object at 0x7fe84a612860>
[task 2021-09-02T14:57:01.781Z] 14:57:01 INFO - STDOUT: gen_obj = <async_generator object bidi_session at 0x7fe84a5b0db0>
[task 2021-09-02T14:57:01.781Z] 14:57:01 INFO - STDOUT: generator = <function bidi_session at 0x7fe84aefa620>
[task 2021-09-02T14:57:01.782Z] 14:57:01 INFO - STDOUT: kwargs = {'capabilities': {'acceptInsecureCerts': True},
[task 2021-09-02T14:57:01.782Z] 14:57:01 INFO - 'configuration': {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-02T14:57:01.782Z] 14:57:01 INFO - '/tmp/tmpm_zprjip.mozrunner'],
[task 2021-09-02T14:57:01.782Z] 14:57:01 INFO - 'binary': '/builds/worker/workspace/build/application/firefox/firefox'}},
[task 2021-09-02T14:57:01.782Z] 14:57:01 INFO - 'host': '127.0.0.1',
[task 2021-09-02T14:57:01.782Z] 14:57:01 INFO - 'port': 46022},
[task 2021-09-02T14:57:01.783Z] 14:57:01 INFO - 'request': <SubRequest 'bidi_session' for <Function test_bidi_session_with_different_capability[capabilities0]>>}
[task 2021-09-02T14:57:01.783Z] 14:57:01 INFO - STDOUT: loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2021-09-02T14:57:01.783Z] 14:57:01 INFO - STDOUT: request = <SubRequest 'bidi_session' for <Function test_bidi_session_with_different_capability[capabilities0]>>
[task 2021-09-02T14:57:01.784Z] 14:57:01 INFO - STDOUT: setup = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7fe8580e2488>
[task 2021-09-02T14:57:01.784Z] 14:57:01 INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2021-09-02T14:57:01.784Z] 14:57:01 INFO - STDOUT: :127:
[task 2021-09-02T14:57:01.785Z] 14:57:01 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2021-09-02T14:57:01.785Z] 14:57:01 INFO - STDOUT: /usr/lib/python3.6/asyncio/base_events.py
[task 2021-09-02T14:57:01.785Z] 14:57:01 INFO - STDOUT: :484: in run_until_complete
[task 2021-09-02T14:57:01.786Z] 14:57:01 INFO - STDOUT: return future.result()
[task 2021-09-02T14:57:01.786Z] 14:57:01 INFO - STDOUT: 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 2021-09-02T14:57:01.786Z] 14:57:01 INFO - STDOUT: new_task = True
[task 2021-09-02T14:57:01.787Z] 14:57:01 INFO - STDOUT: self = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2021-09-02T14:57:01.787Z] 14:57:01 INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2021-09-02T14:57:01.787Z] 14:57:01 INFO - STDOUT: :110: in setup
[task 2021-09-02T14:57:01.788Z] 14:57:01 INFO - STDOUT: res = await gen_obj.__anext__()
[task 2021-09-02T14:57:01.788Z] 14:57:01 INFO - STDOUT: gen_obj = <async_generator object bidi_session at 0x7fe84a5b0db0>
[task 2021-09-02T14:57:01.789Z] 14:57:01 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/fixtures.py
[task 2021-09-02T14:57:01.789Z] 14:57:01 INFO - STDOUT: :205: in bidi_session
[task 2021-09-02T14:57:01.789Z] 14:57:01 INFO - STDOUT: _current_session.start()
[task 2021-09-02T14:57:01.790Z] 14:57:01 INFO - STDOUT: capabilities = {'acceptInsecureCerts': True}
[task 2021-09-02T14:57:01.790Z] 14:57:01 INFO - STDOUT: caps = {'alwaysMatch': {'acceptInsecureCerts': True,
[task 2021-09-02T14:57:01.790Z] 14:57:01 INFO - 'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-02T14:57:01.790Z] 14:57:01 INFO - '/tmp/tmpm_zprjip.mozrunner'],
[task 2021-09-02T14:57:01.790Z] 14:57:01 INFO - 'binary': '/builds/worker/workspace/build/application/firefox/firefox'},
[task 2021-09-02T14:57:01.791Z] 14:57:01 INFO - 'webSocketUrl': True}}
[task 2021-09-02T14:57:01.791Z] 14:57:01 INFO - STDOUT: configuration = {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-02T14:57:01.791Z] 14:57:01 INFO - '/tmp/tmpm_zprjip.mozrunner'],
[task 2021-09-02T14:57:01.791Z] 14:57:01 INFO - 'binary': '/builds/worker/workspace/build/application/firefox/firefox'}},
[task 2021-09-02T14:57:01.791Z] 14:57:01 INFO - 'host': '127.0.0.1',
[task 2021-09-02T14:57:01.791Z] 14:57:01 INFO - 'port': 46022}
[task 2021-09-02T14:57:01.792Z] 14:57:01 INFO - STDOUT: request = <SubRequest 'bidi_session' for <Function test_bidi_session_with_different_capability[capabilities0]>>
[task 2021-09-02T14:57:01.792Z] 14:57:01 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2021-09-02T14:57:01.792Z] 14:57:01 INFO - STDOUT: self = <Session (disconnected)>
[task 2021-09-02T14:57:01.793Z] 14:57:01 INFO - STDOUT: def start(self):
[task 2021-09-02T14:57:01.793Z] 14:57:01 INFO - STDOUT: """Start a new WebDriver session.
[task 2021-09-02T14:57:01.793Z] 14:57:01 INFO - STDOUT:
[task 2021-09-02T14:57:01.794Z] 14:57:01 INFO - STDOUT: :return: Dictionary with `capabilities` and `sessionId`.
[task 2021-09-02T14:57:01.794Z] 14:57:01 INFO - STDOUT:
[task 2021-09-02T14:57:01.794Z] 14:57:01 INFO - STDOUT: :raises error.WebDriverException: If the remote end returns
[task 2021-09-02T14:57:01.795Z] 14:57:01 INFO - STDOUT: an error.
[task 2021-09-02T14:57:01.795Z] 14:57:01 INFO - STDOUT: """
[task 2021-09-02T14:57:01.795Z] 14:57:01 INFO - STDOUT: if self.session_id is not None:
[task 2021-09-02T14:57:01.796Z] 14:57:01 INFO - STDOUT: return
[task 2021-09-02T14:57:01.796Z] 14:57:01 INFO - STDOUT:
[task 2021-09-02T14:57:01.796Z] 14:57:01 INFO - STDOUT: self.transport.close()
[task 2021-09-02T14:57:01.797Z] 14:57:01 INFO - STDOUT:
[task 2021-09-02T14:57:01.797Z] 14:57:01 INFO - STDOUT: body = {"capabilities": {}}
[task 2021-09-02T14:57:01.797Z] 14:57:01 INFO - STDOUT:
[task 2021-09-02T14:57:01.798Z] 14:57:01 INFO - STDOUT: if self.requested_capabilities is not None:
[task 2021-09-02T14:57:01.798Z] 14:57:01 INFO - STDOUT: body["capabilities"] = self.requested_capabilities
[task 2021-09-02T14:57:01.798Z] 14:57:01 INFO - STDOUT:
[task 2021-09-02T14:57:01.799Z] 14:57:01 INFO - STDOUT: value = self.send_command("POST", "session", body=body)
[task 2021-09-02T14:57:01.799Z] 14:57:01 INFO - STDOUT: self.session_id = value["sessionId"]
[task 2021-09-02T14:57:01.799Z] 14:57:01 INFO - STDOUT: self.capabilities = value["capabilities"]
[task 2021-09-02T14:57:01.800Z] 14:57:01 INFO - STDOUT:
[task 2021-09-02T14:57:01.800Z] 14:57:01 INFO - STDOUT: if "webSocketUrl" in self.capabilities:
[task 2021-09-02T14:57:01.800Z] 14:57:01 INFO - STDOUT: self.bidi_session = BidiSession.from_http(self.session_id,
[task 2021-09-02T14:57:01.801Z] 14:57:01 INFO - STDOUT: self.capabilities)
[task 2021-09-02T14:57:01.801Z] 14:57:01 INFO - STDOUT: elif self.enable_bidi:
[task 2021-09-02T14:57:01.801Z] 14:57:01 INFO - STDOUT: self.end()
[task 2021-09-02T14:57:01.802Z] 14:57:01 INFO - STDOUT: raise error.SessionNotCreatedException(
[task 2021-09-02T14:57:01.802Z] 14:57:01 INFO - STDOUT: > "Requested bidi session, but webSocketUrl capability not found")
[task 2021-09-02T14:57:01.803Z] 14:57:01 INFO - STDOUT: E webdriver.error.SessionNotCreatedException: session not created (Requested bidi session, but webSocketUrl capability not found)
[task 2021-09-02T14:57:01.803Z] 14:57:01 INFO - STDOUT: body = {'capabilities': {'alwaysMatch': {'acceptInsecureCerts': True,
[task 2021-09-02T14:57:01.803Z] 14:57:01 INFO - 'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-02T14:57:01.803Z] 14:57:01 INFO - '/tmp/tmpm_zprjip.mozrunner'],
[task 2021-09-02T14:57:01.804Z] 14:57:01 INFO - 'binary': '/builds/worker/workspace/build/application/firefox/firefox'},
[task 2021-09-02T14:57:01.804Z] 14:57:01 INFO - 'webSocketUrl': True}}}
[task 2021-09-02T14:57:01.804Z] 14:57:01 INFO - STDOUT: self = <Session (disconnected)>
[task 2021-09-02T14:57:01.805Z] 14:57:01 INFO - STDOUT: value = {'capabilities': {'acceptInsecureCerts': True,
[task 2021-09-02T14:57:01.805Z] 14:57:01 INFO - 'browserName': 'firefox',
[task 2021-09-02T14:57:01.805Z] 14:57:01 INFO - 'browserVersion': '92.0',
[task 2021-09-02T14:57:01.805Z] 14:57:01 INFO - 'moz:accessibilityChecks': False,
[task 2021-09-02T14:57:01.805Z] 14:57:01 INFO - 'moz:buildID': '20210902133412',
[task 2021-09-02T14:57:01.805Z] 14:57:01 INFO - 'moz:debuggerAddress': 'localhost:9222',
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'moz:geckodriverVersion': '0.29.1',
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'moz:headless': False,
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'moz:processID': 1663,
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'moz:profile': '/tmp/tmpm_zprjip.mozrunner',
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'moz:shutdownTimeout': 300000,
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'moz:useNonSpecCompliantPointerOrigin': False,
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'moz:webdriverClick': True,
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'pageLoadStrategy': 'normal',
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'platformName': 'linux',
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'platformVersion': '4.4.0-1014-aws',
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'proxy': {},
[task 2021-09-02T14:57:01.806Z] 14:57:01 INFO - 'setWindowRect': True,
[task 2021-09-02T14:57:01.807Z] 14:57:01 INFO - 'strictFileInteractability': False,
[task 2021-09-02T14:57:01.807Z] 14:57:01 INFO - 'timeouts': {'implicit': 0,
[task 2021-09-02T14:57:01.807Z] 14:57:01 INFO - 'pageLoad': 300000,
[task 2021-09-02T14:57:01.807Z] 14:57:01 INFO - 'script': 30000},
[task 2021-09-02T14:57:01.807Z] 14:57:01 INFO - 'unhandledPromptBehavior': 'dismiss and notify'},
[task 2021-09-02T14:57:01.807Z] 14:57:01 INFO - 'sessionId': 'd33ac4c6-47ed-4d27-89e7-5962e8bd51f9'}
[task 2021-09-02T14:57:01.808Z] 14:57:01 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2021-09-02T14:57:01.808Z] 14:57:01 INFO - STDOUT: :553: SessionNotCreatedException
[task 2021-09-02T14:57:01.809Z] 14:57:01 INFO - STDOUT: =================================== FAILURES ===================================
[task 2021-09-02T14:57:01.809Z] 14:57:01 INFO - STDOUT: __________________ test_websocket_url_connect[capabilities0] ___________________
[task 2021-09-02T14:57:01.810Z] 14:57:01 INFO - STDOUT: session = <Session db9fbed3-0110-4e10-a214-a3ce95664b83>
[task 2021-09-02T14:57:01.810Z] 14:57:01 INFO - STDOUT: @pytest.mark.asyncio
[task 2021-09-02T14:57:01.810Z] 14:57:01 INFO - STDOUT: @pytest.mark.capabilities({"webSocketUrl": True})
[task 2021-09-02T14:57:01.811Z] 14:57:01 INFO - STDOUT: async def test_websocket_url_connect(session):
[task 2021-09-02T14:57:01.811Z] 14:57:01 INFO - STDOUT: > websocket_url = session.capabilities["webSocketUrl"]
[task 2021-09-02T14:57:01.816Z] 14:57:01 INFO - STDOUT: E KeyError: 'webSocketUrl'
[task 2021-09-02T14:57:01.816Z] 14:57:01 INFO - STDOUT: session = <Session db9fbed3-0110-4e10-a214-a3ce95664b83>
[task 2021-09-02T14:57:01.816Z] 14:57:01 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/new_session/connect.py
[task 2021-09-02T14:57:01.817Z] 14:57:01 INFO - STDOUT: :11: KeyError
[task 2021-09-02T14:57:01.817Z] 14:57:01 INFO - STDOUT: =========================== short test summary info ============================
[task 2021-09-02T14:57:01.817Z] 14:57:01 INFO - STDOUT: FAILED tests/web-platform/tests/webdriver/tests/bidi/new_session/connect.py::test_websocket_url_connect[capabilities0]
[task 2021-09-02T14:57:01.817Z] 14:57:01 INFO - STDOUT: ERROR tests/web-platform/tests/webdriver/tests/bidi/new_session/connect.py::test_bidi_session_send
[task 2021-09-02T14:57:01.817Z] 14:57:01 INFO - STDOUT: ERROR tests/web-platform/tests/webdriver/tests/bidi/new_session/connect.py::test_bidi_session_with_different_capability[capabilities0]
[task 2021-09-02T14:57:01.818Z] 14:57:01 INFO - STDOUT: ==================== 1 failed, 1 passed, 2 errors in 48.27s ====================
[task 2021-09-02T14:57:01.818Z] 14:57:01 INFO - ERROR | LeakSanitizer | SUMMARY: AddressSanitizer: 15710 byte(s) leaked in 140 allocation(s).
[task 2021-09-02T14:57:01.818Z] 14:57:01 INFO - LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2021-09-02T14:57:01.818Z] 14:57:01 INFO - This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2021-09-02T14:57:01.819Z] 14:57:01 INFO - Allowed depth was 4
[task 2021-09-02T14:57:01.819Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::extensions::AtomSet::Get, mozilla::extensions::MatchPattern::Init, mozilla::extensions::MatchPattern::Constructor, mozilla::extensions::MatchPatternSet::Constructor
[task 2021-09-02T14:57:01.819Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at XPCNativeInterface::NewInstance, XPCNativeInterface::GetNewOrUsed, XPCNativeSet::GetNewOrUsed, XPCWrappedNativeProto::GetNewOrUsed
[task 2021-09-02T14:57:01.819Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at XPCNativeSet::NewInstance, XPCNativeSet::GetNewOrUsed, XPCWrappedNative::GetNewOrUsed, XPCConvert::NativeInterface2JSObject
[task 2021-09-02T14:57:01.819Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at XPCWrappedNativeProto::GetNewOrUsed, XPCWrappedNative::GetNewOrUsed, XPCConvert::NativeInterface2JSObject, GetServiceImpl
[task 2021-09-02T14:57:01.819Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::extensions::MatchPattern::Init, mozilla::extensions::MatchPattern::Constructor, mozilla::extensions::MatchPatternSet::Constructor, mozilla::dom::MatchPatternSet_Binding::_constructor
[task 2021-09-02T14:57:01.819Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::extensions::MatchPattern::Constructor, mozilla::extensions::MatchPatternSet::Constructor, mozilla::dom::MatchPatternSet_Binding::_constructor, CallJSNative
[task 2021-09-02T14:57:01.824Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at XPCNativeSet::NewInstance, XPCNativeSet::GetNewOrUsed, XPCWrappedNativeProto::GetNewOrUsed, XPCWrappedNative::GetNewOrUsed
[task 2021-09-02T14:57:01.824Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Alloc, nsTSubstring, Gecko_StartBulkWriteCString, nsstring::nsACString::start_bulk_write_impl
[task 2021-09-02T14:57:01.824Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at XPCWrappedNative::GetNewOrUsed, XPCConvert::NativeInterface2JSObject, GetServiceImpl, GetService
[task 2021-09-02T14:57:01.824Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at XPCNativeInterface::NewInstance, XPCNativeInterface::GetNewOrUsed, XPCConvert::NativeInterface2JSObject, GetServiceImpl
[task 2021-09-02T14:57:01.824Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at nsDynamicAtom::Create, nsAtomTable::Atomize, NS_Atomize, mozilla::extensions::AtomSet::AtomSet
[task 2021-09-02T14:57:01.825Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Realloc, nsTArray_base, Compact, mozilla::extensions::AtomSet::SortAndUniquify
[task 2021-09-02T14:57:01.825Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::extensions::MatchPattern::Constructor, mozilla::extensions::MatchPatternSet::Constructor, mozilla::dom::MatchPatternSet_Binding::_constructor, EnterJit
[task 2021-09-02T14:57:01.825Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Alloc, nsTSubstring, nsTSubstring, AssignJSString
[task 2021-09-02T14:57:01.825Z] 14:57:01 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at Alloc, nsTSubstring, nsTSubstring, nsTSubstring
[task 2021-09-02T14:57:01.825Z] 14:57:01 INFO - Closing logging queue
[task 2021-09-02T14:57:01.825Z] 14:57:01 INFO - queue closed
[task 2021-09-02T14:57:01.825Z] 14:57:01 INFO - Starting runner
[task 2021-09-02T14:57:02.240Z] 14:57:02 INFO - WebDriver HTTP server listening at http://127.0.0.1:54579/
Description
•