Skip to content

[wasm-mt] Wasm.Browser.Threads.Minimal.Sample failing on main #88668

Closed
@radical

Description

@radical

Build, and log:

ChromeDriver was started successfully.
[08:38:41] info: smoke: running LockTest
[08:38:41] info: LockTest A ManagedThreadId: 1
[08:38:41] info: LockTest B ManagedThreadId: 1
[08:38:41] info: LockTest C ManagedThreadId: 1
[08:38:41] info: LockTest D ManagedThreadId: 2
[08:38:41] info: LockTest E ManagedThreadId: 2
[08:38:41] info: LockTest F ManagedThreadId: 2
[08:38:41] info: smoke: LockTest done
[08:38:41] info: smoke: running DisposeTest
[08:38:41] info: DisposeTest A ManagedThreadId: 1
[08:38:41] info: DisposeTest 0 ManagedThreadId: 1
[08:38:41] info: DisposeTest 1 ManagedThreadId: 2
[08:38:41] info: DisposeTest 2 ManagedThreadId: 2
[08:38:41] info: DisposeTest 3 ManagedThreadId: 2
[08:38:41] info: DisposeTest 4 ManagedThreadId: 4
[08:38:41] info: DisposeTest 5 ManagedThreadId: 4
[08:38:41] info: smoke: DisposeTest done
[08:38:41] info: smoke: running TestHelloWebWorker
[08:38:41] info: smoke: TestHelloWebWorker 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 2 ManagedThreadId:5, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 6 ManagedThreadId:4, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 2 ManagedThreadId:6, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 6 ManagedThreadId:4, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 2 ManagedThreadId:7, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 6 ManagedThreadId:4, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 2 ManagedThreadId:8, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 6 ManagedThreadId:4, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 2 ManagedThreadId:9, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 6 ManagedThreadId:4, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 2 ManagedThreadId:10, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 6 ManagedThreadId:4, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 2 ManagedThreadId:11, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 6 ManagedThreadId:4, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 2 ManagedThreadId:12, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestHelloWebWorker 6 ManagedThreadId:4, SynchronizationContext: null
[08:38:41] info: smoke: TestHelloWebWorker done
[08:38:41] info: smoke: running TestCanStartThread
[08:38:41] info: smoke: TestCanStartThread 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestCanStartThread 2 ManagedThreadId:13, SynchronizationContext: null
[08:38:41] info: smoke: TestCanStartThread 3 ManagedThreadId:13, SynchronizationContext: null
[08:38:41] info: smoke: TestCanStartThread 4 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestCanStartThread 5 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestCanStartThread 6 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestCanStartThread done
[08:38:41] info: smoke: running TestTLS
[08:38:41] info: smoke 42: TestTLS 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke 0: TestTLS 2 ManagedThreadId:14, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke 41: TestTLS 3 ManagedThreadId:14, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke 43: TestTLS 4 ManagedThreadId:14, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke 44: TestTLS 5 ManagedThreadId:14, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke 40: TestTLS 9 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: TestTLS done
[08:38:41] info: smoke: running StartTimerFromWorker
[08:38:41] info: smoke: StartTimerFromWorker 1 utc 7/11/2023 8:38:41 AM
[08:38:41] info: smoke: running TestCallSetTimeoutOnWorker
[08:38:41] info: smoke: Task running tid:16
[08:38:41] info: smoke: Task sleeping tid:16
[08:38:41] info: smoke: Task resumed tid:16
[08:38:41] info: smoke: Timeout fired tid:16
[08:38:41] info: XYZ: Main Thread caught task tid:1
[08:38:41] info: smoke: TestCallSetTimeoutOnWorker done
[08:38:41] info: smoke: running HttpClientMain(blurst.txt)
[08:38:41] info: smoke: HttpClientMain 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: HttpClientMain 2 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: http://127.0.0.1:46527/main.js 63:12 "smoke: HttpClientMain(blurst.txt) done \u003C!DOCTYPE html>\n\u003C!--  Licensed to the .NET Foundation under one or more agreements. -->\n\u003C!-- The .NET Foundation licenses this file to you under the MIT license. -->\n\u003Chtml>\n\n\u003Chead>\n    \u003Ctitle>Wasm Browser Smoke Test Threading Sample\u003C/title>\n    \u003Cmeta charset=\"UTF-8\">\n    \u003Cmeta name=\"viewport\" content=\"width=device-width, initial-scale=1.0\">\n    \u003Cscript type='module' src=\"./main.js\">\u003C/script>\n\u003C/head>\n\n\u003Cbody>\n    \u003Ch3 id=\"header\">Wasm Browser Smoke Test Threading Sample\u003C/h3>\n    This calculation will take a long time: \u003Cspan id=\"out\">\u003C/span>\n\u003C/body>\n\n\u003C/html>\n"
[08:38:41] info: smoke: running HttpClientWorker(blurst.txt)
[08:38:41] info: smoke: HttpClientWorker 1 ManagedThreadId:17, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: smoke: HttpClientWorker 2 ManagedThreadId:17, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:41] info: http://127.0.0.1:46527/main.js 67:12 "smoke: HttpClientWorker(blurst.txt) done \u003C!DOCTYPE html>\n\u003C!--  Licensed to the .NET Foundation under one or more agreements. -->\n\u003C!-- The .NET Foundation licenses this file to you under the MIT license. -->\n\u003Chtml>\n\n\u003Chead>\n    \u003Ctitle>Wasm Browser Smoke Test Threading Sample\u003C/title>\n    \u003Cmeta charset=\"UTF-8\">\n    \u003Cmeta name=\"viewport\" content=\"width=device-width, initial-scale=1.0\">\n    \u003Cscript type='module' src=\"./main.js\">\u003C/script>\n\u003C/head>\n\n\u003Cbody>\n    \u003Ch3 id=\"header\">Wasm Browser Smoke Test Threading Sample\u003C/h3>\n    This calculation will take a long time: \u003Cspan id=\"out\">\u003C/span>\n\u003C/body>\n\n\u003C/html>\n"
[08:38:41] info: smoke: running HttpClientPool(blurst.txt)
[08:38:41] info: smoke: HttpClientPool 1 ManagedThreadId:2, SynchronizationContext: null
[08:38:41] info: http://127.0.0.1:46527/_framework/dotnet.runtime.js 2 MONO_WASM [0x2dda308]: Proxy of JS object with JSHandleandle 1 was still alive
[08:38:41] info: smoke: HttpClientPool 2 ManagedThreadId:2, SynchronizationContext: null
[08:38:41] info: http://127.0.0.1:46527/main.js 71:12 "smoke: HttpClientPool(blurst.txt) done \u003C!DOCTYPE html>\n\u003C!--  Licensed to the .NET Foundation under one or more agreements. -->\n\u003C!-- The .NET Foundation licenses this file to you under the MIT license. -->\n\u003Chtml>\n\n\u003Chead>\n    \u003Ctitle>Wasm Browser Smoke Test Threading Sample\u003C/title>\n    \u003Cmeta charset=\"UTF-8\">\n    \u003Cmeta name=\"viewport\" content=\"width=device-width, initial-scale=1.0\">\n    \u003Cscript type='module' src=\"./main.js\">\u003C/script>\n\u003C/head>\n\n\u003Cbody>\n    \u003Ch3 id=\"header\">Wasm Browser Smoke Test Threading Sample\u003C/h3>\n    This calculation will take a long time: \u003Cspan id=\"out\">\u003C/span>\n\u003C/body>\n\n\u003C/html>\n"
[08:38:41] info: smoke: running HttpClientThread(blurst.txt)
[08:38:41] info: smoke: HttpClientThread 1 ManagedThreadId:18, SynchronizationContext: null
[08:38:42] info: smoke: HttpClientThread 2 ManagedThreadId:2, SynchronizationContext: null
[08:38:42] info: http://127.0.0.1:46527/main.js 75:12 "smoke: HttpClientThread(blurst.txt) done \u003C!DOCTYPE html>\n\u003C!--  Licensed to the .NET Foundation under one or more agreements. -->\n\u003C!-- The .NET Foundation licenses this file to you under the MIT license. -->\n\u003Chtml>\n\n\u003Chead>\n    \u003Ctitle>Wasm Browser Smoke Test Threading Sample\u003C/title>\n    \u003Cmeta charset=\"UTF-8\">\n    \u003Cmeta name=\"viewport\" content=\"width=device-width, initial-scale=1.0\">\n    \u003Cscript type='module' src=\"./main.js\">\u003C/script>\n\u003C/head>\n\n\u003Cbody>\n    \u003Ch3 id=\"header\">Wasm Browser Smoke Test Threading Sample\u003C/h3>\n    This calculation will take a long time: \u003Cspan id=\"out\">\u003C/span>\n\u003C/body>\n\n\u003C/html>\n"
[08:38:42] info: smoke: running WsClientMain
[08:38:42] info: smoke: WsClientHello 1 ManagedThreadId:1, SynchronizationContext: System.Runtime.InteropServices.JavaScript.JSSynchronizationContext
[08:38:42] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:42 AM
[08:38:43] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:43 AM
[08:38:44] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:44 AM
[08:38:45] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:45 AM
[08:38:46] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:46 AM
[08:38:47] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:47 AM
[08:38:48] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:48 AM
[08:38:49] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:49 AM
[08:38:50] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:50 AM
[08:38:51] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:51 AM
[08:38:52] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:52 AM
[08:38:53] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:53 AM
[08:38:54] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:54 AM
[08:38:55] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:55 AM
[08:38:56] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:56 AM
[08:38:57] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:57 AM
[08:38:58] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:58 AM
[08:38:59] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:38:59 AM
[08:39:00] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:00 AM
[08:39:01] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:01 AM
[08:39:02] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:02 AM
[08:39:03] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:03 AM
[08:39:04] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:04 AM
[08:39:05] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:05 AM
[08:39:06] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:06 AM
[08:39:07] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:07 AM
[08:39:08] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:08 AM
[08:39:09] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:09 AM
[08:39:10] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:10 AM
[08:39:11] info: smoke: StartTimerFromWorker 2 utc 7/11/2023 8:39:11 AM
[08:39:12] fail: [out of order message from the browser]: http://127.0.0.1:46527/_framework/dotnet.runtime.js 2 WebSocket connection to 'wss://socketsbay.com/wss/v2/1/demo/' failed: WebSocket opening handshake timed out
[08:39:12] fail: [out of order message from the browser]: http://127.0.0.1:46527/_framework/dotnet.js 2:594 "MONO_WASM:    at System.Net.WebSockets.BrowserWebSocket.CancelationHelper(Task , CancellationToken , WebSocketState )\n   at System.Net.WebSockets.BrowserWebSocket.ConnectAsyncCore(CancellationToken )\n   at System.Net.WebSockets.ClientWebSocket.ConnectAsyncCore(Uri , HttpMessageInvoker , CancellationToken )\n   at Sample.Test.WsClientHello(String , String )\nError: net_webstatus_ConnectFailure\n    at Cr (http://127.0.0.1:46527/_framework/dotnet.runtime.js:3:36672)\n    at xr (http://127.0.0.1:46527/_framework/dotnet.runtime.js:3:36357)\n    at do_icall (http://127.0.0.1:46527/_framework/dotnet.native.wasm:wasm-function[229]:0x21305)\n    at do_icall_wrapper (http://127.0.0.1:46527/_framework/dotnet.native.wasm:wasm-function[123]:0x1dd8a)\n    at mono_interp_exec_method (http://127.0.0.1:46527/_framework/dotnet.native.wasm:wasm-function[116]:0x112d1)\n    at interp_entry (http://127.0.0.1:46527/_framework/dotnet.native.wasm:wasm-function[187]:0x20466)\n    at interp_entry_static_0 (http://127.0.0.1:46527/_framework/dotnet.native.wasm:wasm-function[208]:0x20b57)\n    at wasm_native_to_interp_System_Runtime_InteropServices_JavaScript_JSSynchronizationContext_BackgroundJobHandler (http://127.0.0.1:46527/_framework/dotnet.native.wasm:wasm-function[8399]:0x206653)\n    at mono_background_exec (http://127.0.0.1:46527/_framework/dotnet.native.wasm:wasm-function[916]:0x486a5)\n    at Is (http://127.0.0.1:46527/_framework/dotnet.runtime.js:3:63225)"
[08:39:12] fail: [out of order message from the browser]: http://127.0.0.1:46527/_framework/dotnet.runtime.js 2:36671 Uncaught ManagedError: net_webstatus_ConnectFailure
[08:39:12] fail: [out of order message from the browser]: http://127.0.0.1:46527/_framework/dotnet.js 2:214 Uncaught ManagedError: net_webstatus_ConnectFailure
[08:39:12] info: WASM EXIT 2

Known Issue Error Message

Fill the error message using step by step known issues guidance.

{
  "ErrorMessage": "",
  "ErrorPattern": "WebSocket connection to .*socketsbay.com.*failed",
  "BuildRetry": false,
  "ExcludeConsoleLog": false
}

Report

Build Definition Test Pull Request
335087 dotnet/runtime Wasm.Browser.Threads.Minimal.Sample.WorkItemExecution #88521
335065 dotnet/runtime Wasm.Browser.Threads.Minimal.Sample.WorkItemExecution #88520
335053 dotnet/runtime Wasm.Browser.Threads.Minimal.Sample.WorkItemExecution #87959
334916 dotnet/runtime Wasm.Browser.Threads.Minimal.Sample.WorkItemExecution #88268
334877 dotnet/runtime Wasm.Browser.Threads.Minimal.Sample.WorkItemExecution #88620
334868 dotnet/runtime Wasm.Browser.Threads.Minimal.Sample.WorkItemExecution

Summary

24-Hour Hit Count 7-Day Hit Count 1-Month Count
3 6 6

Known issue validation

Build: 🔎 https://dev.azure.com/dnceng-public/public/_build/results?buildId=334868
Error message validated: WebSocket connection to .*socketsbay.com.*failed
Result validation: ✅ Known issue matched with the provided build.
Validation performed at: 7/11/2023 5:03:21 PM UTC

Metadata

Metadata

Assignees

Labels

Known Build ErrorUse this to report build issues in the .NET Helix tabarch-wasmWebAssembly architecturearea-VM-meta-monoblocking-clean-ciBlocking PR or rolling runs of 'runtime' or 'runtime-extra-platforms'

Type

No type

Projects

No projects

Milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions