Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

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

Closed
radical opened this issue Jul 11, 2023 · 1 comment · Fixed by #88676
Closed

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

radical opened this issue Jul 11, 2023 · 1 comment · Fixed by #88676
Assignees
@pavelsavara
Labels
arch-wasm WebAssembly architecture area-VM-meta-mono blocking-clean-ci Blocking PR or rolling runs of 'runtime' or 'runtime-extra-platforms' Known Build Error Use this to report build issues in the .NET Helix tab
Milestone
8.0.0

Comments

@radical
Copy link
Member

radical commented Jul 11, 2023 •
edited by build-analysis bot
Loading

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
@radical radical added arch-wasm WebAssembly architecture blocking-clean-ci Blocking PR or rolling runs of 'runtime' or 'runtime-extra-platforms' area-VM-meta-mono labels Jul 11, 2023
@ghost ghost added the untriaged New issue has not been triaged by the area owner label Jul 11, 2023
@radical radical added this to the 8.0.0 milestone Jul 11, 2023
@ghost
Copy link

ghost commented Jul 11, 2023

Tagging subscribers to 'arch-wasm': @lewing
See info in area-owners.md if you want to be subscribed.

Issue Details

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
Author: radical
Assignees: -
Labels:

arch-wasm, blocking-clean-ci, area-VM-meta-mono
Milestone: -

@ghost ghost removed the untriaged New issue has not been triaged by the area owner label Jul 11, 2023
@radical radical added the Known Build Error Use this to report build issues in the .NET Helix tab label Jul 11, 2023
@radical radical changed the title [wasm] Wasm.Browser.Threads.Minimal.Sample failing on main [wasm-mt] Wasm.Browser.Threads.Minimal.Sample failing on main Jul 11, 2023
@pavelsavara pavelsavara mentioned this issue Jul 12, 2023
Merged
@ghost ghost added the in-pr There is an active PR which will close this issue when it is merged label Jul 12, 2023
@ghost ghost removed the in-pr There is an active PR which will close this issue when it is merged label Jul 12, 2023
@ghost ghost locked as resolved and limited conversation to collaborators Aug 13, 2023
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees

@pavelsavara pavelsavara

Labels
arch-wasm WebAssembly architecture area-VM-meta-mono blocking-clean-ci Blocking PR or rolling runs of 'runtime' or 'runtime-extra-platforms' Known Build Error Use this to report build issues in the .NET Helix tab
Projects
None yet
Milestone
8.0.0
Development

Successfully merging a pull request may close this issue.

[browser][mt] smoke test active issues pavelsavara/runtime
2 participants
@radical @pavelsavara