Closed Bug 1837255 Opened 3 years ago Closed 3 years ago

Intermittent /websockets/keeping-connection-open/001.html | single tracking bug

Categories

(Core :: Networking: WebSockets, defect, P5)

Product:
Core
Component:
Networking: WebSockets
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1834476

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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

[task 2023-06-07T17:37:31.673Z] 17:37:31     INFO - TEST-START | /websockets/keeping-connection-open/001.html?wpt_flags=h2
[task 2023-06-07T17:37:32.096Z] 17:37:32     INFO - PID 9800 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-06-07T17:37:32.520Z] 17:37:32     INFO - PID 9800 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-06-07T17:37:32.523Z] 17:37:32     INFO - PID 9800 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_168615021124839\AppData\Local\Temp\tmpo0biemeo\runtests_leaks_7272_tab_pid8588.log
[task 2023-06-07T17:37:32.528Z] 17:37:32     INFO - PID 9800 | [8588, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-06-07T17:37:32.539Z] 17:37:32     INFO - PID 9800 | [Child 8588, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-06-07T17:37:32.552Z] 17:37:32     INFO - PID 9800 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_168615021124839\AppData\Local\Temp\tmpo0biemeo\runtests_leaks_7272_tab_pid3864.log
[task 2023-06-07T17:37:32.552Z] 17:37:32     INFO - PID 9800 | [3864, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-06-07T17:37:32.568Z] 17:37:32     INFO - PID 9800 | [Child 3864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-06-07T17:37:32.583Z] 17:37:32     INFO - PID 9800 | [Child 8588, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-06-07T17:37:32.583Z] 17:37:32     INFO - PID 9800 | [Child 8588, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-06-07T17:37:32.598Z] 17:37:32     INFO - PID 9800 | [Child 8588, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-06-07T17:37:32.614Z] 17:37:32     INFO - PID 9800 | [Child 3864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-06-07T17:37:32.614Z] 17:37:32     INFO - PID 9800 | [Child 3864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-06-07T17:37:32.636Z] 17:37:32     INFO - PID 9800 | [Child 3864, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-06-07T17:37:34.544Z] 17:37:34     INFO - PID 9800 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2023-06-07T17:37:34.569Z] 17:37:34     INFO - PID 9800 | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 324))
[task 2023-06-07T17:38:18.054Z] 17:38:18     INFO - PID 9800 | [Child 8500, Main Thread] WARNING: IPC message 'PContent::Msg_InsertNewFocusActionId' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:507
[task 2023-06-07T17:38:18.077Z] 17:38:18     INFO - PID 9800 | [Child 8500, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3426
[task 2023-06-07T17:38:18.086Z] 17:38:18     INFO - PID 9800 | [Child 8500, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-06-07T17:38:18.092Z] 17:38:18     INFO - PID 9800 | [Child 8500, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4687
[task 2023-06-07T17:38:39.275Z] 17:38:39     INFO - Test harness output was not a valid structured log message
[task 2023-06-07T17:38:39.275Z] 17:38:39     INFO - Exception in thread Thread-26:
[task 2023-06-07T17:38:39.278Z] 17:38:39     INFO - Traceback (most recent call last):
[task 2023-06-07T17:38:39.279Z] 17:38:39     INFO -   File "Z:\task_168615021124839\build\tests\web-platform\tests\tools\third_party\h2\h2\connection.py", line 224, in process_input
[task 2023-06-07T17:38:39.279Z] 17:38:39  WARNING - wptserve (ee7b60d7 - 25) Stream Reset, Thread Closing
[task 2023-06-07T17:38:39.290Z] 17:38:39     INFO - Test harness output was not a valid structured log message
[task 2023-06-07T17:38:39.290Z] 17:38:39     INFO -     func, target_state = self._transitions[(self.state, input_)]
[task 2023-06-07T17:38:39.297Z] 17:38:39     INFO - KeyError: (<ConnectionState.CLOSED: 3>, <ConnectionInputs.SEND_DATA: 2>)
[task 2023-06-07T17:38:39.298Z] 17:38:39     INFO - During handling of the above exception, another exception occurred:
[task 2023-06-07T17:38:39.298Z] 17:38:39     INFO - Traceback (most recent call last):
[task 2023-06-07T17:38:39.298Z] 17:38:39     INFO -   File "c:\mozilla-build\python3\lib\threading.py", line 973, in _bootstrap_inner
[task 2023-06-07T17:38:39.298Z] 17:38:39     INFO -     self.run()
[task 2023-06-07T17:38:39.299Z] 17:38:39     INFO -   File "c:\mozilla-build\python3\lib\threading.py", line 910, in run
[task 2023-06-07T17:38:39.299Z] 17:38:39     INFO -     self._target(*self._args, **self._kwargs)
[task 2023-06-07T17:38:39.299Z] 17:38:39     INFO -   File "Z:\task_168615021124839\build\tests\web-platform\tests\tools\wptserve\wptserve\server.py", line 546, in _stream_ws_sub_thread
[task 2023-06-07T17:38:39.310Z] 17:38:39     INFO -     connection.end_stream(stream_id)
[task 2023-06-07T17:38:39.312Z] 17:38:39     INFO -   File "Z:\task_168615021124839\build\tests\web-platform\tests\tools\third_party\h2\h2\connection.py", line 882, in end_stream
[task 2023-06-07T17:38:39.312Z] 17:38:39     INFO -     self.state_machine.process_input(ConnectionInputs.SEND_DATA)
[task 2023-06-07T17:38:39.313Z] 17:38:39     INFO -   File "Z:\task_168615021124839\build\tests\web-platform\tests\tools\third_party\h2\h2\connection.py", line 228, in process_input
[task 2023-06-07T17:38:39.313Z] 17:38:39     INFO -     raise ProtocolError(
[task 2023-06-07T17:38:39.313Z] 17:38:39     INFO - h2.exceptions.ProtocolError: Invalid input ConnectionInputs.SEND_DATA in state ConnectionState.CLOSED
[task 2023-06-07T17:38:39.324Z] 17:38:39     INFO - 
[task 2023-06-07T17:38:39.324Z] 17:38:39     INFO - TEST-UNEXPECTED-FAIL | /websockets/keeping-connection-open/001.html?wpt_flags=h2 | WebSockets: 20s inactivity after handshake - assert_unreached: Reached unreachable code
[task 2023-06-07T17:38:39.328Z] 17:38:39     INFO - TEST-OK | /websockets/keeping-connection-open/001.html?wpt_flags=h2 | took 67655ms
Status: NEW → RESOLVED
Closed: 3 years ago
Duplicate of bug: 1834476
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.