Closed
Bug 1847127
Opened 2 years ago
Closed 2 years ago
Intermittent /webdriver/tests/bidi/browsing_context/classic_interop/window_handle.py | test_top_level_context_id_equals_window_handle - setup error: websockets.exceptions.InvalidStatusCode: server rejected WebSocket connection: HTTP 404
Categories
(Remote Protocol :: WebDriver BiDi, defect, P5)
Remote Protocol
WebDriver BiDi
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1846905
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=424844824&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/AfeyK7_vQ42Wy9fN3bTwxg/runs/0/artifacts/public/logs/live_backing.log
[task 2023-08-03T21:48:29.767Z] 21:48:29 INFO - TEST-START | /webdriver/tests/bidi/browsing_context/classic_interop/window_handle.py
[task 2023-08-03T21:48:30.034Z] 21:48:30 INFO - STDOUT: ============================= test session starts ==============================
[task 2023-08-03T21:48:30.035Z] 21:48:30 INFO - STDOUT: platform darwin -- Python 3.7.9, pytest-7.0.1, pluggy-unknown -- /opt/worker/tasks/task_169109224600552/build/venv/bin/python
[task 2023-08-03T21:48:30.036Z] 21:48:30 INFO - STDOUT: rootdir: /opt/worker/tasks/task_169109224600552/build
[task 2023-08-03T21:48:30.037Z] 21:48:30 INFO - STDOUT: plugins: asyncio-0.19.0
[task 2023-08-03T21:48:30.037Z] 21:48:30 INFO - STDOUT: asyncio: mode=strict
[task 2023-08-03T21:48:30.037Z] 21:48:30 INFO - STDOUT: collecting ...
[task 2023-08-03T21:48:30.047Z] 21:48:30 INFO - STDOUT: collected 1 item
[task 2023-08-03T21:48:30.047Z] 21:48:30 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/browsing_context/classic_interop/window_handle.py::test_top_level_context_id_equals_window_handle
[task 2023-08-03T21:48:30.050Z] 21:48:30 INFO - PID 30105 | 1691099310048 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner"]}, "webSocketUrl": true}}}
[task 2023-08-03T21:48:30.050Z] 21:48:30 INFO - PID 30105 | 1691099310049 geckodriver::capabilities DEBUG Trying to read firefox version from ini files
[task 2023-08-03T21:48:30.051Z] 21:48:30 INFO - PID 30105 | 1691099310049 geckodriver::capabilities DEBUG Found version 118.0a1
[task 2023-08-03T21:48:30.051Z] 21:48:30 INFO - PID 30105 | 1691099310050 geckodriver::browser DEBUG Backing up prefs to "/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner/user.geckodriver_backup"
[task 2023-08-03T21:48:30.063Z] 21:48:30 INFO - PID 30105 | 1691099310062 mozrunner::runner INFO Running command: MOZ_CRASHREPORTER="1" MOZ_CRASHREPORTER_NO_REPORT="1" MOZ_CRASHREPORTER_SHUTDOWN="1" MOZ_NO_REMOTE="1" "/opt ... 00014/T/tmp5hsv765k.mozrunner" "--remote-debugging-port" "9222" "--remote-allow-hosts" "localhost" "-foreground" "-no-remote"
[task 2023-08-03T21:48:30.064Z] 21:48:30 INFO - PID 30105 | 1691099310063 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1
[task 2023-08-03T21:48:30.064Z] 21:48:30 INFO - PID 30105 | 1691099310063 geckodriver::browser TRACE Failed to open /var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner/MarionetteActivePort
[task 2023-08-03T21:48:30.065Z] 21:48:30 INFO - PID 30105 | 1691099310063 geckodriver::marionette TRACE Retrying in 100ms
[task 2023-08-03T21:48:30.120Z] 21:48:30 INFO - PID 30105 | *** You are running in headless mode.
[task 2023-08-03T21:48:30.232Z] 21:48:30 INFO - PID 30105 | 1691099310231 RemoteAgent DEBUG WebDriver BiDi enabled
[task 2023-08-03T21:48:30.232Z] 21:48:30 INFO - PID 30105 | 1691099310231 RemoteAgent DEBUG CDP enabled
[task 2023-08-03T21:48:30.233Z] 21:48:30 INFO - PID 30105 | 1691099310231 Marionette INFO Marionette enabled
[task 2023-08-03T21:48:30.299Z] 21:48:30 INFO - PID 30105 | 1691099310298 geckodriver::browser TRACE Failed to open /var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner/MarionetteActivePort
[task 2023-08-03T21:48:30.300Z] 21:48:30 INFO - PID 30105 | 1691099310298 geckodriver::marionette TRACE Retrying in 100ms
[task 2023-08-03T21:48:30.388Z] 21:48:30 INFO - PID 30105 | 1691099310387 Marionette TRACE Received observer notification final-ui-startup
[task 2023-08-03T21:48:30.389Z] 21:48:30 INFO - PID 30105 | 1691099310388 RemoteAgent TRACE Received observer notification final-ui-startup
[task 2023-08-03T21:48:30.401Z] 21:48:30 INFO - PID 30105 | console.error: "Warning: unrecognized command line flag" "-remote-allow-hosts"
[task 2023-08-03T21:48:30.409Z] 21:48:30 INFO - PID 30105 | 1691099310408 Marionette INFO Listening on port 59290
[task 2023-08-03T21:48:30.409Z] 21:48:30 INFO - PID 30105 | 1691099310408 Marionette DEBUG Marionette is listening
[task 2023-08-03T21:48:30.413Z] 21:48:30 INFO - PID 30105 | 1691099310412 RemoteAgent TRACE Available local IP addresses: 127.0.0.1, [::1]
[task 2023-08-03T21:48:30.420Z] 21:48:30 INFO - PID 30105 | !!! could not start server on port 9222: [Exception... "Component returned failure code: 0x804b0036 (NS_ERROR_SOCKET_ADDRESS_IN_USE) [nsIServerSocket.init]" nsresult: "0x804b0036 (NS_ERROR_SOCKET_ADDRESS_IN_USE)" location: "JS frame :: chrome://remote/content/server/httpd.sys.mjs :: _start :: line 556" data: no]
[task 2023-08-03T21:48:30.420Z] 21:48:30 INFO - PID 30105 | 1691099310418 RemoteAgent ERROR Unable to stop listener: [Exception... "Unexpected error" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: chrome://remote/content/server/httpd.sys.mjs :: stop :: line 610" data: no] Stack trace: stop()@httpd.sys.mjs:610
[task 2023-08-03T21:48:30.421Z] 21:48:30 INFO - PID 30105 | #stop()@RemoteAgent.sys.mjs:310
[task 2023-08-03T21:48:30.422Z] 21:48:30 INFO - PID 30105 | 1691099310418 RemoteAgent ERROR Unable to start remote agent: : [Exception... "Component is not available" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: chrome://remote/content/server/httpd.sys.mjs :: _start :: line 601" data: no] Stack trace: _start()@httpd.sys.mjs:601
[task 2023-08-03T21:48:30.422Z] 21:48:30 INFO - PID 30105 | #listen()@RemoteAgent.sys.mjs:229
[task 2023-08-03T21:48:30.513Z] 21:48:30 INFO - PID 30105 | Read port: 59290
[task 2023-08-03T21:48:30.686Z] 21:48:30 INFO - PID 30105 | 1691099310685 Marionette DEBUG Accepted connection 0 from 127.0.0.1:59291
[task 2023-08-03T21:48:30.689Z] 21:48:30 INFO - PID 30105 | 1691099310688 geckodriver::marionette DEBUG Connection to Marionette established on 127.0.0.1:59290.
[task 2023-08-03T21:48:30.690Z] 21:48:30 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:30.690Z] 21:48:30 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.025Z] 21:48:31 INFO - PID 30105 | 1691099311024 Marionette DEBUG 0 -> [0,1,"WebDriver:NewSession",{"webSocketUrl":true}]
[task 2023-08-03T21:48:31.026Z] 21:48:31 INFO - PID 30105 | 1691099311025 WebDriver BiDi DEBUG Waiting for initial application window
[task 2023-08-03T21:48:31.182Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.183Z] 21:48:31 INFO - PID 30105 | [GFX1-]: RenderCompositorSWGL failed mapping default framebuffer, no dt
[task 2023-08-03T21:48:31.243Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.253Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.253Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.254Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.262Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.335Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.382Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.682Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.926Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.926Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:31.927Z] 21:48:31 INFO - PID 30105 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-03T21:48:32.065Z] 21:48:32 INFO - PID 30105 | 1691099312064 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2023-08-03T21:48:32.066Z] 21:48:32 INFO - PID 30105 | 1691099312064 RemoteAgent TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2023-08-03T21:48:32.067Z] 21:48:32 INFO - PID 30105 | 1691099312064 WebDriver BiDi DEBUG Registered session handler: /session/bcdba14a-66cd-48d8-bf35-89e4349b5e4c
[task 2023-08-03T21:48:32.067Z] 21:48:32 INFO - PID 30105 | 1691099312064 Marionette DEBUG Waiting for initial application window
[task 2023-08-03T21:48:32.068Z] 21:48:32 INFO - PID 30105 | 1691099312066 RemoteAgent TRACE [4] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2023-08-03T21:48:32.069Z] 21:48:32 INFO - PID 30105 | 1691099312066 RemoteAgent TRACE [4] ProgressListener Setting unload timer (5000ms)
[task 2023-08-03T21:48:32.069Z] 21:48:32 INFO - PID 30105 | 1691099312066 RemoteAgent TRACE [4] Document already finished loading: about:blank
[task 2023-08-03T21:48:32.070Z] 21:48:32 INFO - PID 30105 | 1691099312067 RemoteAgent TRACE [4] ProgressListener Stop: has error=false
[task 2023-08-03T21:48:32.073Z] 21:48:32 INFO - PID 30105 | 1691099312071 Marionette DEBUG 0 <- [1,1,null,{"sessionId":"bcdba14a-66cd-48d8-bf35-89e4349b5e4c","capabilities":{"browserName":"firefox","browserVersion":"118.0a1","platformName":"mac","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/bcdba14a-66cd-48d8-bf35-89e4349b5e4c","moz:accessibilityChecks":false,"moz:buildID":"20230803191036","moz:debuggerAddress":"127.0.0.1:9222","moz:headless":true,"moz:platformVersion":"19.6.0","moz:processID":30110,"moz:profile":"/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner","moz:shutdownTimeout":60000,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-08-03T21:48:32.074Z] 21:48:32 INFO - PID 30105 | 1691099312072 webdriver::server DEBUG <- 200 OK {"value":{"sessionId":"bcdba14a-66cd-48d8-bf35-89e4349b5e4c","capabilities":{"acceptInsecureCerts":false,"browserNa ... dledPromptBehavior":"dismiss and notify","webSocketUrl":"ws://127.0.0.1:9222/session/bcdba14a-66cd-48d8-bf35-89e4349b5e4c"}}}
[task 2023-08-03T21:48:32.250Z] 21:48:32 INFO - STDOUT: ERROR
[task 2023-08-03T21:48:32.251Z] 21:48:32 INFO - PID 30105 | 1691099312250 webdriver::server DEBUG -> DELETE /session/bcdba14a-66cd-48d8-bf35-89e4349b5e4c
[task 2023-08-03T21:48:32.252Z] 21:48:32 INFO - PID 30105 | 1691099312251 Marionette DEBUG 0 -> [0,2,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2023-08-03T21:48:32.252Z] 21:48:32 INFO - PID 30105 | 1691099312251 Marionette INFO Stopped listening on port 59290
[task 2023-08-03T21:48:32.365Z] 21:48:32 INFO - PID 30105 | 1691099312364 WebDriver BiDi DEBUG Unregistered session handler: /session/bcdba14a-66cd-48d8-bf35-89e4349b5e4c
[task 2023-08-03T21:48:32.390Z] 21:48:32 INFO - PID 30105 | 1691099312389 Marionette TRACE Received observer notification quit-application
[task 2023-08-03T21:48:32.393Z] 21:48:32 INFO - PID 30105 | 1691099312392 Marionette TRACE Received observer notification quit-application
[task 2023-08-03T21:48:32.394Z] 21:48:32 INFO - PID 30105 | 1691099312392 RemoteAgent TRACE Received observer notification quit-application
[task 2023-08-03T21:48:32.394Z] 21:48:32 INFO - PID 30105 | 1691099312394 Marionette DEBUG Marionette stopped listening
[task 2023-08-03T21:48:32.395Z] 21:48:32 INFO - PID 30105 | 1691099312394 RemoteAgent ERROR Unable to stop listener: [Exception... "Unexpected error" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: chrome://remote/content/server/httpd.sys.mjs :: stop :: line 610" data: no] Stack trace: stop()@httpd.sys.mjs:610
[task 2023-08-03T21:48:32.396Z] 21:48:32 INFO - PID 30105 | #stop()@RemoteAgent.sys.mjs:310
[task 2023-08-03T21:48:32.396Z] 21:48:32 INFO - PID 30105 | 1691099312394 Marionette DEBUG 0 <- [1,2,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-08-03T21:48:32.405Z] 21:48:32 INFO - PID 30105 | 1691099312404 webdriver::server DEBUG Teardown session
[task 2023-08-03T21:48:32.409Z] 21:48:32 INFO - PID 30105 | 1691099312408 Marionette DEBUG Closed connection 0
[task 2023-08-03T21:48:32.414Z] 21:48:32 INFO - PID 30105 | console.error: ({})
[task 2023-08-03T21:48:33.550Z] 21:48:33 INFO - PID 30105 | 1691099313549 geckodriver::browser DEBUG Browser process stopped: exit status: 0
[task 2023-08-03T21:48:33.551Z] 21:48:33 INFO - PID 30105 | 1691099313550 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-08-03T21:48:33.554Z] 21:48:33 INFO - STDOUT: ==================================== ERRORS ====================================
[task 2023-08-03T21:48:33.555Z] 21:48:33 INFO - STDOUT: _______ ERROR at setup of test_top_level_context_id_equals_window_handle _______
[task 2023-08-03T21:48:33.555Z] 21:48:33 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T21:48:33.556Z] 21:48:33 INFO - STDOUT: request = <SubRequest 'bidi_session' for <Function test_top_level_context_id_equals_window_handle>>
[task 2023-08-03T21:48:33.556Z] 21:48:33 INFO - STDOUT: kwargs = {'capabilities': {}, 'configuration': {'browser': {'args': ['--profile', '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh0000... '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner']}}, 'host': '127.0.0.1', 'port': 59258, ...}}
[task 2023-08-03T21:48:33.557Z] 21:48:33 INFO - STDOUT: setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7fdc57bfe8c0>
[task 2023-08-03T21:48:33.557Z] 21:48:33 INFO - STDOUT: finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fdc57bfe950>
[task 2023-08-03T21:48:33.558Z] 21:48:33 INFO - STDOUT: @functools.wraps(func)
[task 2023-08-03T21:48:33.558Z] 21:48:33 INFO - STDOUT: def _asyncgen_fixture_wrapper(
[task 2023-08-03T21:48:33.559Z] 21:48:33 INFO - STDOUT: event_loop: asyncio.AbstractEventLoop, request: SubRequest, **kwargs: Any
[task 2023-08-03T21:48:33.559Z] 21:48:33 INFO - STDOUT: ) -> _R:
[task 2023-08-03T21:48:33.560Z] 21:48:33 INFO - STDOUT: gen_obj = func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2023-08-03T21:48:33.560Z] 21:48:33 INFO - STDOUT:
[task 2023-08-03T21:48:33.561Z] 21:48:33 INFO - STDOUT: async def setup() -> _R:
[task 2023-08-03T21:48:33.561Z] 21:48:33 INFO - STDOUT: res = await gen_obj.__anext__()
[task 2023-08-03T21:48:33.562Z] 21:48:33 INFO - STDOUT: return res
[task 2023-08-03T21:48:33.562Z] 21:48:33 INFO - STDOUT:
[task 2023-08-03T21:48:33.563Z] 21:48:33 INFO - STDOUT: def finalizer() -> None:
[task 2023-08-03T21:48:33.563Z] 21:48:33 INFO - STDOUT: """Yield again, to finalize."""
[task 2023-08-03T21:48:33.564Z] 21:48:33 INFO - STDOUT:
[task 2023-08-03T21:48:33.564Z] 21:48:33 INFO - STDOUT: async def async_finalizer() -> None:
[task 2023-08-03T21:48:33.564Z] 21:48:33 INFO - STDOUT: try:
[task 2023-08-03T21:48:33.565Z] 21:48:33 INFO - STDOUT: await gen_obj.__anext__()
[task 2023-08-03T21:48:33.565Z] 21:48:33 INFO - STDOUT: except StopAsyncIteration:
[task 2023-08-03T21:48:33.566Z] 21:48:33 INFO - STDOUT: pass
[task 2023-08-03T21:48:33.566Z] 21:48:33 INFO - STDOUT: else:
[task 2023-08-03T21:48:33.567Z] 21:48:33 INFO - STDOUT: msg = "Async generator fixture didn't stop."
[task 2023-08-03T21:48:33.567Z] 21:48:33 INFO - STDOUT: msg += "Yield only once."
[task 2023-08-03T21:48:33.568Z] 21:48:33 INFO - STDOUT: raise ValueError(msg)
[task 2023-08-03T21:48:33.568Z] 21:48:33 INFO - STDOUT:
[task 2023-08-03T21:48:33.569Z] 21:48:33 INFO - STDOUT: event_loop.run_until_complete(async_finalizer())
[task 2023-08-03T21:48:33.569Z] 21:48:33 INFO - STDOUT:
[task 2023-08-03T21:48:33.570Z] 21:48:33 INFO - STDOUT: > result = event_loop.run_until_complete(setup())
[task 2023-08-03T21:48:33.570Z] 21:48:33 INFO - STDOUT: event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T21:48:33.571Z] 21:48:33 INFO - STDOUT: finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fdc57bfe950>
[task 2023-08-03T21:48:33.571Z] 21:48:33 INFO - STDOUT: func = <function bidi_session at 0x7fdc57b69b00>
[task 2023-08-03T21:48:33.572Z] 21:48:33 INFO - STDOUT: gen_obj = <async_generator object bidi_session at 0x7fdc57bfe830>
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - STDOUT: kwargs = {'capabilities': {},
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - 'configuration': {'browser': {'args': ['--profile',
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner'],
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - 'binary': '/opt/worker/tasks/task_169109224600552/build/application/Firefox '
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - 'Nightly.app/Contents/MacOS/firefox'},
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - 'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner']}},
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - 'host': '127.0.0.1',
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - 'port': 59258,
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - 'timeout_multiplier': 1,
[task 2023-08-03T21:48:33.573Z] 21:48:33 INFO - 'webdriver': {'args': ['-vv',
[task 2023-08-03T21:48:33.574Z] 21:48:33 INFO - '--binary',
[task 2023-08-03T21:48:33.574Z] 21:48:33 INFO - '/opt/worker/tasks/task_169109224600552/build/application/Firefox '
[task 2023-08-03T21:48:33.574Z] 21:48:33 INFO - 'Nightly.app/Contents/MacOS/firefox'],
[task 2023-08-03T21:48:33.574Z] 21:48:33 INFO - 'binary': '/opt/worker/tasks/task_169109224600552/fetches/geckodriver'}}}
[task 2023-08-03T21:48:33.574Z] 21:48:33 INFO - STDOUT: request = <SubRequest 'bidi_session' for <Function test_top_level_context_id_equals_window_handle>>
[task 2023-08-03T21:48:33.575Z] 21:48:33 INFO - STDOUT: setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7fdc57bfe8c0>
[task 2023-08-03T21:48:33.575Z] 21:48:33 INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2023-08-03T21:48:33.576Z] 21:48:33 INFO - STDOUT: :293:
[task 2023-08-03T21:48:33.576Z] 21:48:33 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2023-08-03T21:48:33.577Z] 21:48:33 INFO - STDOUT: /Library/Frameworks/Python.framework/Versions/3.7/lib/python3.7/asyncio/base_events.py
[task 2023-08-03T21:48:33.577Z] 21:48:33 INFO - STDOUT: :587: in run_until_complete
[task 2023-08-03T21:48:33.577Z] 21:48:33 INFO - STDOUT: return future.result()
[task 2023-08-03T21:48:33.578Z] 21:48:33 INFO - STDOUT: future = <Task finished coro=<_wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup() done, defined at /opt/worker/tasks/task_169109224600552/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-03T21:48:33.579Z] 21:48:33 INFO - STDOUT: new_task = True
[task 2023-08-03T21:48:33.579Z] 21:48:33 INFO - STDOUT: self = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T21:48:33.579Z] 21:48:33 INFO - STDOUT: tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py
[task 2023-08-03T21:48:33.580Z] 21:48:33 INFO - STDOUT: :275: in setup
[task 2023-08-03T21:48:33.580Z] 21:48:33 INFO - STDOUT: res = await gen_obj.__anext__()
[task 2023-08-03T21:48:33.581Z] 21:48:33 INFO - STDOUT: gen_obj = <async_generator object bidi_session at 0x7fdc57bfe830>
[task 2023-08-03T21:48:33.581Z] 21:48:33 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/fixtures.py
[task 2023-08-03T21:48:33.582Z] 21:48:33 INFO - STDOUT: :184: in bidi_session
[task 2023-08-03T21:48:33.582Z] 21:48:33 INFO - STDOUT: await _current_session.bidi_session.start()
[task 2023-08-03T21:48:33.583Z] 21:48:33 INFO - STDOUT: capabilities = {}
[task 2023-08-03T21:48:33.583Z] 21:48:33 INFO - STDOUT: caps = {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T21:48:33.583Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner']},
[task 2023-08-03T21:48:33.583Z] 21:48:33 INFO - 'webSocketUrl': True}}
[task 2023-08-03T21:48:33.584Z] 21:48:33 INFO - STDOUT: configuration = {'browser': {'args': ['--profile',
[task 2023-08-03T21:48:33.584Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner'],
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'binary': '/opt/worker/tasks/task_169109224600552/build/application/Firefox '
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'Nightly.app/Contents/MacOS/firefox'},
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner']}},
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'host': '127.0.0.1',
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'port': 59258,
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'timeout_multiplier': 1,
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'webdriver': {'args': ['-vv',
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - '--binary',
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - '/opt/worker/tasks/task_169109224600552/build/application/Firefox '
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'Nightly.app/Contents/MacOS/firefox'],
[task 2023-08-03T21:48:33.585Z] 21:48:33 INFO - 'binary': '/opt/worker/tasks/task_169109224600552/fetches/geckodriver'}}
[task 2023-08-03T21:48:33.586Z] 21:48:33 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/bidi/client.py
[task 2023-08-03T21:48:33.586Z] 21:48:33 INFO - STDOUT: :151: in start
[task 2023-08-03T21:48:33.587Z] 21:48:33 INFO - STDOUT: await self.start_transport(loop)
[task 2023-08-03T21:48:33.587Z] 21:48:33 INFO - STDOUT: loop = None
[task 2023-08-03T21:48:33.587Z] 21:48:33 INFO - STDOUT: self = <webdriver.bidi.client.BidiSession object at 0x7fdc57c49410>
[task 2023-08-03T21:48:33.588Z] 21:48:33 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/bidi/client.py
[task 2023-08-03T21:48:33.588Z] 21:48:33 INFO - STDOUT: :145: in start_transport
[task 2023-08-03T21:48:33.589Z] 21:48:33 INFO - STDOUT: await self.transport.start()
[task 2023-08-03T21:48:33.589Z] 21:48:33 INFO - STDOUT: loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T21:48:33.590Z] 21:48:33 INFO - STDOUT: self = <webdriver.bidi.client.BidiSession object at 0x7fdc57c49410>
[task 2023-08-03T21:48:33.590Z] 21:48:33 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/bidi/transport.py
[task 2023-08-03T21:48:33.591Z] 21:48:33 INFO - STDOUT: :40: in start
[task 2023-08-03T21:48:33.591Z] 21:48:33 INFO - STDOUT: self.connection = await websockets.connect(self.url) # type: ignore
[task 2023-08-03T21:48:33.592Z] 21:48:33 INFO - STDOUT: self = <webdriver.bidi.transport.Transport object at 0x7fdc57c49710>
[task 2023-08-03T21:48:33.592Z] 21:48:33 INFO - STDOUT: tests/web-platform/tests/tools/third_party/websockets/src/websockets/client.py
[task 2023-08-03T21:48:33.593Z] 21:48:33 INFO - STDOUT: :547: in __await_impl__
[task 2023-08-03T21:48:33.593Z] 21:48:33 INFO - STDOUT: extra_headers=protocol.extra_headers,
[task 2023-08-03T21:48:33.594Z] 21:48:33 INFO - STDOUT: protocol = <websockets.client.WebSocketClientProtocol object at 0x7fdc57c491d0>
[task 2023-08-03T21:48:33.594Z] 21:48:33 INFO - STDOUT: redirects = 0
[task 2023-08-03T21:48:33.595Z] 21:48:33 INFO - STDOUT: self = <websockets.client.Connect object at 0x7fdc57c49690>
[task 2023-08-03T21:48:33.595Z] 21:48:33 INFO - STDOUT: transport = <_SelectorSocketTransport closed fd=34>
[task 2023-08-03T21:48:33.596Z] 21:48:33 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2023-08-03T21:48:33.596Z] 21:48:33 INFO - STDOUT: self = <websockets.client.WebSocketClientProtocol object at 0x7fdc57c491d0>
[task 2023-08-03T21:48:33.597Z] 21:48:33 INFO - STDOUT: wsuri = WebSocketURI(secure=False, host='127.0.0.1', port=9222, resource_name='/session/bcdba14a-66cd-48d8-bf35-89e4349b5e4c', user_info=None)
[task 2023-08-03T21:48:33.597Z] 21:48:33 INFO - STDOUT: origin = None
[task 2023-08-03T21:48:33.597Z] 21:48:33 INFO - STDOUT: available_extensions = [<websockets.extensions.permessage_deflate.ClientPerMessageDeflateFactory object at 0x7fdc57c57190>]
[task 2023-08-03T21:48:33.598Z] 21:48:33 INFO - STDOUT: available_subprotocols = None, extra_headers = None
[task 2023-08-03T21:48:33.598Z] 21:48:33 INFO - STDOUT: async def handshake(
[task 2023-08-03T21:48:33.599Z] 21:48:33 INFO - STDOUT: self,
[task 2023-08-03T21:48:33.599Z] 21:48:33 INFO - STDOUT: wsuri: WebSocketURI,
[task 2023-08-03T21:48:33.600Z] 21:48:33 INFO - STDOUT: origin: Optional[Origin] = None,
[task 2023-08-03T21:48:33.600Z] 21:48:33 INFO - STDOUT: available_extensions: Optional[Sequence[ClientExtensionFactory]] = None,
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/bidi/browsing_context/classic_interop/window_handle.py | test_top_level_context_id_equals_window_handle - setup error: websockets.exceptions.InvalidStatusCode: server rejected WebSocket connection: HTTP 404
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - request = <SubRequest 'bidi_session' for <Function test_top_level_context_id_equals_window_handle>>
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - kwargs = {'capabilities': {}, 'configuration': {'browser': {'args': ['--profile', '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh0000... '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner']}}, 'host': '127.0.0.1', 'port': 59258, ...}}
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7fdc57bfe8c0>
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fdc57bfe950>
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - @functools.wraps(func)
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - def _asyncgen_fixture_wrapper(
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - event_loop: asyncio.AbstractEventLoop, request: SubRequest, **kwargs: Any
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - ) -> _R:
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - gen_obj = func(**_add_kwargs(func, kwargs, event_loop, request))
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - async def setup() -> _R:
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - res = await gen_obj.__anext__()
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - return res
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.612Z] 21:48:33 INFO - def finalizer() -> None:
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - """Yield again, to finalize."""
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - async def async_finalizer() -> None:
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - try:
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - await gen_obj.__anext__()
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - except StopAsyncIteration:
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - pass
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - else:
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - msg = "Async generator fixture didn't stop."
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - msg += "Yield only once."
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - raise ValueError(msg)
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - event_loop.run_until_complete(async_finalizer())
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - > result = event_loop.run_until_complete(setup())
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - finalizer = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.finalizer at 0x7fdc57bfe950>
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - func = <function bidi_session at 0x7fdc57b69b00>
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - gen_obj = <async_generator object bidi_session at 0x7fdc57bfe830>
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - kwargs = {'capabilities': {},
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - 'configuration': {'browser': {'args': ['--profile',
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner'],
[task 2023-08-03T21:48:33.613Z] 21:48:33 INFO - 'binary': '/opt/worker/tasks/task_169109224600552/build/application/Firefox '
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - 'Nightly.app/Contents/MacOS/firefox'},
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - 'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner']}},
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - 'host': '127.0.0.1',
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - 'port': 59258,
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - 'timeout_multiplier': 1,
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - 'webdriver': {'args': ['-vv',
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - '--binary',
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - '/opt/worker/tasks/task_169109224600552/build/application/Firefox '
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - 'Nightly.app/Contents/MacOS/firefox'],
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - 'binary': '/opt/worker/tasks/task_169109224600552/fetches/geckodriver'}}}
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - request = <SubRequest 'bidi_session' for <Function test_top_level_context_id_equals_window_handle>>
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - setup = <function _wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup at 0x7fdc57bfe8c0>
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO -
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:293:
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - /Library/Frameworks/Python.framework/Versions/3.7/lib/python3.7/asyncio/base_events.py:587: in run_until_complete
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - return future.result()
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - future = <Task finished coro=<_wrap_asyncgen.<locals>._asyncgen_fixture_wrapper.<locals>.setup() done, defined at /opt/worker/tasks/task_169109224600552/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-03T21:48:33.614Z] 21:48:33 INFO - new_task = True
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - self = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:275: in setup
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - res = await gen_obj.__anext__()
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - gen_obj = <async_generator object bidi_session at 0x7fdc57bfe830>
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - tests/web-platform/tests/webdriver/tests/support/fixtures.py:184: in bidi_session
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - await _current_session.bidi_session.start()
[task 2023-08-03T21:48:33.614Z] 21:48:33 INFO - capabilities = {}
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - caps = {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner']},
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - 'webSocketUrl': True}}
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - configuration = {'browser': {'args': ['--profile',
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner'],
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - 'binary': '/opt/worker/tasks/task_169109224600552/build/application/Firefox '
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - 'Nightly.app/Contents/MacOS/firefox'},
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - 'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - '/var/folders/8m/vhpcr4t55yl_wx5n61j3yvxh000014/T/tmp5hsv765k.mozrunner']}},
[task 2023-08-03T21:48:33.615Z] 21:48:33 INFO - 'host': '127.0.0.1',
<...>
[task 2023-08-03T21:48:34.375Z] 21:48:34 INFO - Starting runner
[task 2023-08-03T21:48:34.704Z] 21:48:34 INFO - TEST-START | /webdriver/tests/bidi/browsing_context/create/background.py
|
||
Updated•2 years ago
|
You need to log in
before you can comment on or make changes to this bug.
Description
•