runtime
runtime copied to clipboard
dotnet
[wasm][mt] Unhandled JS error
Build: https://dev.azure.com/dnceng-public/public/_build/results?buildId=551928
{
"ErrorMessage": "Unhandled error",
"BuildRetry": false,
"ExcludeConsoleLog": false
}
Leg Name: LibraryTests_Threading_Smoke
New WS timeouts in Build browser-wasm linux Release LibraryTests_Threading_Smoke
[08:30:14] info: [2024-01-26T08:30:14.046Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedAfterConnect_ThrowsOperationCanceledException
[08:30:14] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[08:30:14] fail: [out of order message from the browser]: http://127.0.0.1:42881/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://127.0.0.1:37741/' failed: Connection closed before receiving a handshake response
[08:30:14] info: [2024-01-26T08:30:14.119Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedAfterConnect_ThrowsOperationCanceledException
[08:30:14] info: [2024-01-26T08:30:14.122Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedBeforeConnect_ThrowsOperationCanceledException
[08:30:14] info: [2024-01-26T08:30:14.125Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedBeforeConnect_ThrowsOperationCanceledException
[08:30:14] info: [2024-01-26T08:30:14.127Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedInflightConnect_ThrowsOperationCanceledException
[08:30:14] info: [2024-01-26T08:30:14.144Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedInflightConnect_ThrowsOperationCanceledException
[08:30:14] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[08:30:14] info: [2024-01-26T08:30:14.147Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.EchoTextMessage_Success(server: ws://127.0.0.1:42881/WebSocket/EchoWebSocket.ashx)
[08:30:14] fail: [out of order message from the browser]: http://127.0.0.1:42881/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://001284c1617e4d19bc9194791e992b0f/' failed: Error in connection establishment: net::ERR_NAME_NOT_RESOLVED
[08:30:14] info: [2024-01-26T08:30:14.173Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.EchoTextMessage_Success(server: ws://127.0.0.1:42881/WebSocket/EchoWebSocket.ashx)
[08:30:14] info: [2024-01-26T08:30:14.174Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.EchoTextMessage_Success(server: wss://127.0.0.1:44107/WebSocket/EchoWebSocket.ashx)
[08:30:14] info: [2024-01-26T08:30:14.208Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.EchoTextMessage_Success(server: wss://127.0.0.1:44107/WebSocket/EchoWebSocket.ashx)
[08:30:14] info: [2024-01-26T08:30:14.213Z] [STRT] System.Net.WebSockets.Client.Tests.HttpClientConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: ws://127.0.0.1:42881/WebSocket/EchoWebSocket.ashx)
[08:30:14] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[08:30:14] fail: MONO_WASM: {"status":1,"silent":true}
[08:30:14] fail: [out of order message from the browser]: http://127.0.0.1:42881/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://127.0.0.1:42881/WebSocket/EchoWebSocket.ashx?subprotocol=CustomProtocol' failed: Error during WebSocket handshake: Response must not include 'Sec-WebSocket-Protocol' header if not present in request: CustomProtocol
[08:30:14] info: WASM EXIT 1
[08:30:14] info: Waiting to flush log messages with a timeout of 120 secs ..
[08:30:14] fail: Application has finished with exit code TESTS_FAILED but 0 was expected
XHarness exit code: 71 (GENERAL_FAILURE)
/root/helix/work/workitem/e /root/helix/work/workitem/e
----- end Fri Jan 26 08:30:14 AM UTC 2024 ----- exit code 71 ----------------------------------------------------------
and
[10:04:20] info: [2024-01-26T10:04:20.516Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: ws://127.0.0.1:34553/WebSocket/EchoWebSocket.ashx)
[10:04:20] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[10:04:20] fail: [out of order message from the browser]: http://127.0.0.1:34553/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://127.0.0.1:34553/WebSocket/EchoWebSocket.ashx?subprotocol=CustomProtocol' failed: Error during WebSocket handshake: Response must not include 'Sec-WebSocket-Protocol' header if not present in request: CustomProtocol
[10:04:20] info: [2024-01-26T10:04:20.546Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: ws://127.0.0.1:34553/WebSocket/EchoWebSocket.ashx)
[10:04:20] info: [2024-01-26T10:04:20.547Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: wss://127.0.0.1:40241/WebSocket/EchoWebSocket.ashx)
[10:04:20] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[10:04:20] fail: [out of order message from the browser]: http://127.0.0.1:34553/_framework/dotnet.runtime.js 2 WebSocket connection to 'wss://127.0.0.1:40241/WebSocket/EchoWebSocket.ashx?subprotocol=CustomProtocol' failed: Error during WebSocket handshake: Response must not include 'Sec-WebSocket-Protocol' header if not present in request: CustomProtocol
[10:04:20] info: [2024-01-26T10:04:20.586Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: wss://127.0.0.1:40241/WebSocket/EchoWebSocket.ashx)
[10:04:20] info: [2024-01-26T10:04:20.588Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedAfterConnect_ThrowsOperationCanceledException
[10:04:20] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[10:04:20] fail: [out of order message from the browser]: http://127.0.0.1:34553/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://127.0.0.1:42929/' failed: Connection closed before receiving a handshake response
[10:04:20] info: [2024-01-26T10:04:20.611Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedAfterConnect_ThrowsOperationCanceledException
[10:04:20] info: [2024-01-26T10:04:20.612Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedInflightConnect_ThrowsOperationCanceledException
[10:04:20] info: [2024-01-26T10:04:20.625Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedInflightConnect_ThrowsOperationCanceledException
[10:04:20] info: [2024-01-26T10:04:20.630Z] [STRT] System.Net.WebSockets.Client.Tests.CloseTest.CloseAsync_CloseDescriptionIsMaxLength_Success(server: ws://127.0.0.1:34553/WebSocket/EchoWebSocket.ashx)
[10:04:20] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[10:04:20] fail: [out of order message from the browser]: http://127.0.0.1:34553/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://c7a186bce2424e20b569b889dcce8159/' failed: Error in connection establishment: net::ERR_NAME_NOT_RESOLVED
[10:04:20] info: [2024-01-26T10:04:20.652Z] [PASS] System.Net.WebSockets.Client.Tests.CloseTest.CloseAsync_CloseDescriptionIsMaxLength_Success(server: ws://127.0.0.1:34553/WebSocket
...
[10:03:07] info: [2024-01-26T10:03:07.910Z] [STRT] System.Net.Http.Functional.Tests.HttpContentTest.ReadAsStreamAsync_Buffered_IgnoresCancellationToken(readStreamAsync: True)
[11:17:53] fail: Tests timed out. Killing driver service pid 79
[11:17:53] fail: Application has finished with exit code TIMED_OUT but 0 was expected
XHarness exit code: 71 (GENERAL_FAILURE)
/root/helix/work/workitem/e /root/helix/work/workitem/e
----- end Fri Jan 26 11:17:53 AM UTC 2024 ----- exit code 71 ----------------------------------------------------------
Known issue validation
Build: :mag_right: https://dev.azure.com/dnceng-public/public/_build/results?buildId=551928
Error message validated: Unhandled error
Result validation: :white_check_mark: Known issue matched with the provided build.
Validation performed at: 2/3/2024 7:56:48 AM UTC
Report
Summary
| 24-Hour Hit Count | 7-Day Hit Count | 1-Month Count |
|---|---|---|
| 2 | 11 | 95 |
The filter is bit misleading here.
Because there are unit test which try to open invalid URLs or abort the socket early.
Like WebSocket connection to 'ws://001284c1617e4d19bc9194791e992b0f/' failed: Error in connection establishment
In which case the browser complains (which we could not even suppress).
The real problem is that sometimes it kills whole app. I think some exceptions is not marshaled/handled properly somewhere.
This is my attempt on capturing the real issue https://github.com/dotnet/runtime/issues/97518 Let's have both for a while
Tagging subscribers to 'arch-wasm': @lewing See info in area-owners.md if you want to be subscribed.
Issue Details
Build: https://dev.azure.com/dnceng-public/public/_build/results?buildId=541606 Leg Name: LibraryTests_Threading_Smoke
New WS timeouts in Build browser-wasm linux Release LibraryTests_Threading_Smoke
[08:30:14] info: [2024-01-26T08:30:14.046Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedAfterConnect_ThrowsOperationCanceledException
[08:30:14] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[08:30:14] fail: [out of order message from the browser]: http://127.0.0.1:42881/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://127.0.0.1:37741/' failed: Connection closed before receiving a handshake response
[08:30:14] info: [2024-01-26T08:30:14.119Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedAfterConnect_ThrowsOperationCanceledException
[08:30:14] info: [2024-01-26T08:30:14.122Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedBeforeConnect_ThrowsOperationCanceledException
[08:30:14] info: [2024-01-26T08:30:14.125Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedBeforeConnect_ThrowsOperationCanceledException
[08:30:14] info: [2024-01-26T08:30:14.127Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedInflightConnect_ThrowsOperationCanceledException
[08:30:14] info: [2024-01-26T08:30:14.144Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedInflightConnect_ThrowsOperationCanceledException
[08:30:14] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[08:30:14] info: [2024-01-26T08:30:14.147Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.EchoTextMessage_Success(server: ws://127.0.0.1:42881/WebSocket/EchoWebSocket.ashx)
[08:30:14] fail: [out of order message from the browser]: http://127.0.0.1:42881/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://001284c1617e4d19bc9194791e992b0f/' failed: Error in connection establishment: net::ERR_NAME_NOT_RESOLVED
[08:30:14] info: [2024-01-26T08:30:14.173Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.EchoTextMessage_Success(server: ws://127.0.0.1:42881/WebSocket/EchoWebSocket.ashx)
[08:30:14] info: [2024-01-26T08:30:14.174Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.EchoTextMessage_Success(server: wss://127.0.0.1:44107/WebSocket/EchoWebSocket.ashx)
[08:30:14] info: [2024-01-26T08:30:14.208Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.EchoTextMessage_Success(server: wss://127.0.0.1:44107/WebSocket/EchoWebSocket.ashx)
[08:30:14] info: [2024-01-26T08:30:14.213Z] [STRT] System.Net.WebSockets.Client.Tests.HttpClientConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: ws://127.0.0.1:42881/WebSocket/EchoWebSocket.ashx)
[08:30:14] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[08:30:14] fail: MONO_WASM: {"status":1,"silent":true}
[08:30:14] fail: [out of order message from the browser]: http://127.0.0.1:42881/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://127.0.0.1:42881/WebSocket/EchoWebSocket.ashx?subprotocol=CustomProtocol' failed: Error during WebSocket handshake: Response must not include 'Sec-WebSocket-Protocol' header if not present in request: CustomProtocol
[08:30:14] info: WASM EXIT 1
[08:30:14] info: Waiting to flush log messages with a timeout of 120 secs ..
[08:30:14] fail: Application has finished with exit code TESTS_FAILED but 0 was expected
XHarness exit code: 71 (GENERAL_FAILURE)
/root/helix/work/workitem/e /root/helix/work/workitem/e
----- end Fri Jan 26 08:30:14 AM UTC 2024 ----- exit code 71 ----------------------------------------------------------
and
[10:04:20] info: [2024-01-26T10:04:20.516Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: ws://127.0.0.1:34553/WebSocket/EchoWebSocket.ashx)
[10:04:20] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[10:04:20] fail: [out of order message from the browser]: http://127.0.0.1:34553/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://127.0.0.1:34553/WebSocket/EchoWebSocket.ashx?subprotocol=CustomProtocol' failed: Error during WebSocket handshake: Response must not include 'Sec-WebSocket-Protocol' header if not present in request: CustomProtocol
[10:04:20] info: [2024-01-26T10:04:20.546Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: ws://127.0.0.1:34553/WebSocket/EchoWebSocket.ashx)
[10:04:20] info: [2024-01-26T10:04:20.547Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: wss://127.0.0.1:40241/WebSocket/EchoWebSocket.ashx)
[10:04:20] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[10:04:20] fail: [out of order message from the browser]: http://127.0.0.1:34553/_framework/dotnet.runtime.js 2 WebSocket connection to 'wss://127.0.0.1:40241/WebSocket/EchoWebSocket.ashx?subprotocol=CustomProtocol' failed: Error during WebSocket handshake: Response must not include 'Sec-WebSocket-Protocol' header if not present in request: CustomProtocol
[10:04:20] info: [2024-01-26T10:04:20.586Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException(server: wss://127.0.0.1:40241/WebSocket/EchoWebSocket.ashx)
[10:04:20] info: [2024-01-26T10:04:20.588Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedAfterConnect_ThrowsOperationCanceledException
[10:04:20] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[10:04:20] fail: [out of order message from the browser]: http://127.0.0.1:34553/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://127.0.0.1:42929/' failed: Connection closed before receiving a handshake response
[10:04:20] info: [2024-01-26T10:04:20.611Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedAfterConnect_ThrowsOperationCanceledException
[10:04:20] info: [2024-01-26T10:04:20.612Z] [STRT] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedInflightConnect_ThrowsOperationCanceledException
[10:04:20] info: [2024-01-26T10:04:20.625Z] [PASS] System.Net.WebSockets.Client.Tests.ConnectTest.ConnectAsync_CancellationRequestedInflightConnect_ThrowsOperationCanceledException
[10:04:20] info: [2024-01-26T10:04:20.630Z] [STRT] System.Net.WebSockets.Client.Tests.CloseTest.CloseAsync_CloseDescriptionIsMaxLength_Success(server: ws://127.0.0.1:34553/WebSocket/EchoWebSocket.ashx)
[10:04:20] warn: MONO_WASM [0xdc21c-main]: WebSocket error {}
[10:04:20] fail: [out of order message from the browser]: http://127.0.0.1:34553/_framework/dotnet.runtime.js 2 WebSocket connection to 'ws://c7a186bce2424e20b569b889dcce8159/' failed: Error in connection establishment: net::ERR_NAME_NOT_RESOLVED
[10:04:20] info: [2024-01-26T10:04:20.652Z] [PASS] System.Net.WebSockets.Client.Tests.CloseTest.CloseAsync_CloseDescriptionIsMaxLength_Success(server: ws://127.0.0.1:34553/WebSocket
...
[10:03:07] info: [2024-01-26T10:03:07.910Z] [STRT] System.Net.Http.Functional.Tests.HttpContentTest.ReadAsStreamAsync_Buffered_IgnoresCancellationToken(readStreamAsync: True)
[11:17:53] fail: Tests timed out. Killing driver service pid 79
[11:17:53] fail: Application has finished with exit code TIMED_OUT but 0 was expected
XHarness exit code: 71 (GENERAL_FAILURE)
/root/helix/work/workitem/e /root/helix/work/workitem/e
----- end Fri Jan 26 11:17:53 AM UTC 2024 ----- exit code 71 ----------------------------------------------------------
Known Issue Error Message
Fill the error message using step by step known issues guidance.
{
"ErrorPattern": "WebSocket connection to 'ws:.+' failed",
"BuildRetry": false,
"ExcludeConsoleLog": false
}
Report
| Build | Definition | Test | Pull Request |
|---|---|---|---|
| 541769 | dotnet/runtime | WasmTestOnBrowser-System.Net.WebSockets.Client.Tests.WorkItemExecution | dotnet/runtime#96807 |
| 541766 | dotnet/runtime | WasmTestOnBrowser-System.Net.WebSockets.Client.Tests.WorkItemExecution | dotnet/runtime#97213 |
| 541660 | dotnet/runtime | WasmTestOnBrowser-System.Net.WebSockets.Client.Tests.WorkItemExecution | dotnet/runtime#97539 |
| 541439 | dotnet/runtime | WasmTestOnBrowser-System.Net.WebSockets.Client.Tests.WorkItemExecution | dotnet/runtime#97532 |
| 540994 | dotnet/runtime | WasmTestOnBrowser-System.Net.WebSockets.Client.Tests.WorkItemExecution | dotnet/runtime#97458 |
Summary
| 24-Hour Hit Count | 7-Day Hit Count | 1-Month Count |
|---|---|---|
| 5 | 5 | 5 |
| Author: | radekdoulik |
|---|---|
| Assignees: | pavelsavara |
| Labels: |
|
| Milestone: | - |
This one is interesting, it survived ConnectAsync_PassNoSubProtocol_ServerRequires_ThrowsWebSocketException as it should and fails on SendAsync_Cancel_Success. Same here
[08:25:14] info: [2024-01-27T08:25:14.834Z] [FAIL] System.Net.WebSockets.Client.Tests.HttpClientCancelTest.SendAsync_Cancel_Success(server: ws://127.0.0.1:36227/WebSocket/EchoWebSocket.ashx)
[08:25:14] info: Actual Open when System.OperationCanceledException: The operation was canceled.
[08:25:14] info: at System.Threading.CancellationToken.ThrowOperationCanceledException()
[08:25:14] info: at System.Threading.CancellationToken.ThrowIfCancellationRequested()
[08:25:14] info: at System.Net.WebSockets.BrowserWebSocket.SendAsyncCore(ArraySegment`1 buffer, WebSocketMessageType messageType, Boolean endOfMessage, CancellationToken cancellationToken)
[08:25:14] info: at System.Net.WebSockets.Client.Tests.ClientWebSocketTestBase.TestCancellation(Func`2 action, Uri server)
[08:25:14] info: at System.Net.WebSockets.Client.Tests.ClientWebSocketTestBase.TestCancellation(Func`2 action, Uri server)
[08:25:14] info: at System.Net.WebSockets.Client.Tests.CancelTest.SendAsync_Cancel_Success(Uri server)
[08:25:14] info: --- End of stack trace from previous location ---
All recent ones are RuntimeError: memory access out of bounds -> https://github.com/dotnet/runtime/issues/96546
Fixed by https://github.com/dotnet/runtime/pull/100907 and https://github.com/dotnet/runtime/pull/100855