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.

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

[browser] Process terminated assert handling at icall.c:6162 #100249

Closed
pavelsavara opened this issue Mar 25, 2024 · 5 comments
Closed

[browser] Process terminated assert handling at icall.c:6162 #100249

pavelsavara opened this issue Mar 25, 2024 · 5 comments
Assignees
Labels
arch-wasm WebAssembly architecture area-System.Runtime.InteropServices.JavaScript 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 os-browser Browser variant of arch-wasm
Milestone

Comments

@pavelsavara
Copy link
Member

pavelsavara commented Mar 25, 2024

Log

[18:45:39] warn: [0x06538340-jsww 18:45:39.652] [MONO] /__w/1/s/src/mono/mono/metadata/icall.c:6162 <disabled>
[18:45:39] warn: [0x06538340-jsww 18:45:39.653] [MONO] /__w/1/s/src/mono/mono/metadata/icall.c:6171 <disabled>

[18:45:39] warn: Aborted()
[18:45:39] info: [0x06538340-jsww 18:45:39.662] MONO_WASM: Dumping web worker info as seen by UI thread, it could be stale:
[18:45:39] info: HttpClient_CancelInDifferentThread: ManagedThreadId: 142 NativeThreadId: 104005632
[18:45:39] info: 073 | 0x06538340-jsww: isRunning:false isAttached: true isEventLoop: true reuseCount:  1 - JSWebWorker
[18:45:39] warn: [0x046f0040-jsww 18:45:39.681] MONO_WASM: JSSynchronizationContext is still installed on worker.
[18:45:39] fail: [0x06538340-jsww 18:45:39.697] MONO_WASM: unreachable
                 RuntimeError: unreachable
                     at __trap (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[9210]:0x23a170)
                     at ___trap (http://127.0.0.1:33313/_framework/dotnet.native.js:8:177871)
                     at abort (http://127.0.0.1:33313/_framework/dotnet.native.js:8:11481)
                     at _abort (http://127.0.0.1:33313/_framework/dotnet.native.js:8:105071)
                     at ves_icall_System_Environment_FailFast (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[2327]:0xb9803)
                     at ves_icall_System_Environment_FailFast_raw (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[2358]:0xba914)
                     at do_icall (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[209]:0x21fc9)
                     at do_icall_wrapper (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[137]:0x1f769)
                     at mono_interp_exec_method (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[128]:0x1203e)
                     at interp_runtime_invoke (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[167]:0x203eb)
                     at mono_jit_runtime_invoke (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[3762]:0x112e32)
                     at do_runtime_invoke (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[2973]:0xe41d5)
                     at mono_runtime_try_invoke (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[2981]:0xe4ca3)
                     at mono_runtime_invoke (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[3008]:0xe74a5)
                     at mono_wasm_invoke_jsexport (http://127.0.0.1:33313/_framework/dotnet.native.wasm:wasm-function[9233]:0x23b1ea)
                     at Module._mono_wasm_invoke_jsexport [as mono_wasm_invoke_jsexport] (http://127.0.0.1:33313/_framework/dotnet.native.js:8:137857)
                     at Xn (http://127.0.0.1:33313/_framework/dotnet.runtime.js:3:37237)
                     at http://127.0.0.1:33313/_framework/dotnet.runtime.js:3:55039
                     at Po.complete_task_wrapper (http://127.0.0.1:33313/_framework/dotnet.runtime.js:3:55106)
                     at Po.resolve (http://127.0.0.1:33313/_framework/dotnet.runtime.js:3:53554)
                     at http://127.0.0.1:33313/_framework/dotnet.runtime.js:3:58222
[18:45:39] warn: [0x06538340-jsww 18:45:39.698] MONO_WASM: JSSynchronizationContext is still installed on worker.
[18:45:39] fail: [out of order message from the browser]: http://127.0.0.1:33313/_framework/dotnet.native.js 7:1295 Uncaught ExitStatus: Program terminated with exit(1)
[18:45:39] info: WASM EXIT 1

Build Information

Build: https://dev.azure.com/dnceng-public/public/_build/results?buildId=617169
Build error leg or test failing:

Error Message

{
  "ErrorPattern": "icall\\.c:61.*disabled",
  "BuildRetry": false,
  "ExcludeConsoleLog": false
}

Known issue validation

Build: 🔎
Result validation: ⚠️ Provided build not found. Provide a valid build in the "Build: 🔎" line.
Validation performed at: 4/8/2024 6:23:15 PM UTC

Report

Build Definition Test Pull Request
643543 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100823
642895 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101021
643252 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101021
643820 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101053
643754 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101051
643460 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100664
642546 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101017
643415 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #101044
643403 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100386
643408 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #100947
643400 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #101042
643372 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100945
643384 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100951
643339 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100763
643318 dotnet/runtime WasmTestOnBrowser-System.Text.Json.Tests.WorkItemExecution #100664
643266 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101039
642959 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution
642976 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100842
642793 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100765
642823 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101021
642783 dotnet/runtime WasmTestOnBrowser-System.Text.Json.Tests.WorkItemExecution #101009
642723 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101020
642663 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution
642475 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101001
642469 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101009
641917 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101002
642449 dotnet/runtime WasmTestOnBrowser-System.Text.Json.Tests.WorkItemExecution
642419 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100664
641528 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100951
642193 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #101009
642160 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101008
642123 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101007
642028 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution
642020 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100980
641971 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100664
641981 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100993
641909 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #101001
641852 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100997
641901 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #99818
641896 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100821
641886 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100998
641780 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100386
641646 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100993
641334 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100982
641471 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100664
641542 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100650
641522 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100966
641482 dotnet/runtime WasmTestOnBrowser-System.Text.Json.Tests.WorkItemExecution #100938
641521 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution
641439 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100989
641399 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100980
641332 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100981
641303 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100976
641261 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100974
641077 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100898
641067 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100030
635016 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #98812
640998 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #100890
641013 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100951
640958 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100765
640979 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100949
640906 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100520
640867 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100956
640857 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #100954
640860 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100954
640844 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #100951
640764 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100664
640792 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100949
640748 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #99199
640730 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #100729
640726 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #100669
640708 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution
640682 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100945
640634 dotnet/runtime normal-System.Text.Json.Tests.WorkItemExecution #100743
640612 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #91400
640416 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100942
640358 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100941
640311 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #99829
640285 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100157
640353 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100743
640282 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100938
640239 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100876
640242 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100812
640289 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100897
638100 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100876
640274 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100898
640148 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution
640064 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100935
640012 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100933
639997 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100925
640044 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100897
639914 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100828
639876 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100706
639714 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100664
639604 dotnet/runtime WasmTestOnBrowser-System.Private.Uri.Functional.Tests.WorkItemExecution #100141
639606 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100661
639588 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100587
639593 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100669
639629 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100916
639614 dotnet/runtime WasmTestOnBrowser-System.Runtime.InteropServices.JavaScript.Tests.WorkItemExecution #100619
Displaying 100 of 312 results

Summary

24-Hour Hit Count 7-Day Hit Count 1-Month Count
0 7 312
@pavelsavara pavelsavara added arch-wasm WebAssembly architecture area-System.Runtime.InteropServices.JavaScript os-browser Browser variant of arch-wasm labels Mar 25, 2024
@pavelsavara pavelsavara added this to the 9.0.0 milestone Mar 25, 2024
@pavelsavara pavelsavara self-assigned this Mar 25, 2024
Copy link
Contributor

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

@pavelsavara pavelsavara added the Known Build Error Use this to report build issues in the .NET Helix tab label Mar 26, 2024
@pavelsavara
Copy link
Member Author

Here is single-threaded AOT version of that problem

Log

[18:48:37] info: Application arguments: --run WasmTestRunner.dll System.Runtime.Tests.dll -notrait category=AdditionalTimezoneChecks -notrait category=IgnoreForCI -notrait category=OuterLoop -notrait category=failing
[18:48:38] info: [MONO] /__w/1/s/src/mono/mono/metadata/icall.c:6162 <disabled>
[18:48:38] info: [MONO] /__w/1/s/src/mono/mono/metadata/icall.c:6171 <disabled>
[18:48:38] info: Process v8-12.3.219.sh exited with 1
                 
[18:48:38] info: Waiting to flush log messages with a timeout of 120 secs ..
[18:48:38] fail: Application has finished with exit code 1 but 0 was expected

@pavelsavara pavelsavara changed the title [browser][MT] Process terminated assert handling [browser] assert failed at icall.c:6162 <disabled> Mar 28, 2024
@pavelsavara pavelsavara added the blocking-clean-ci Blocking PR or rolling runs of 'runtime' or 'runtime-extra-platforms' label Mar 28, 2024
@pavelsavara pavelsavara changed the title [browser] assert failed at icall.c:6162 <disabled> [browser] Process terminated assert handling at icall.c:6162 Mar 28, 2024
@pavelsavara
Copy link
Member Author

Few interesting logs

Sample startup

[12:39:53] info: http://127.0.0.1:39081/_framework/dotnet.runtime.js 2 [MONO] Running class .cctor for System.Array/EmptyArray`1<byte> from 'System.Private.CoreLib.dll'
[12:39:53] info: http://127.0.0.1:39081/_framework/dotnet.runtime.js 2 [MONO] DllImport attempting to load: 'libSystem.Native'.
[12:54:51] fail: Tests timed out. Killing driver service pid 79
[12:54:51] fail: Application has finished with exit code TIMED_OUT but 0 was expected

Timeout even after exit/dump on UI thread

[12:40:03] info: [PASS] Microsoft.Extensions.Http.Logging.HttpClientLoggerTest.RemoveAllLoggers_OnlyForSpecifiedNames
[12:40:03] fail: [0x056102f0-long 12:40:03.658] [MONO] * Assertion at /__w/1/s/src/mono/mono/metadata/loader.c:1821, condition `<disabled>' not met
[12:40:03] fail: [0x05100040-long 12:40:03.686] [MONO] /__w/1/s/src/mono/mono/mini/interp/interp.c:3734 <disabled>
[12:40:03] info: [PASS] Microsoft.Extensions.DependencyInjection.HttpClientFactoryServiceCollectionExtensionsTest.AddHttpClient_AddTypedClient_WithServiceDelegate_ConfiguresNamedClient

...

[12:40:04] info: [PASS] Microsoft.Extensions.DependencyInjection.HttpClientFactoryServiceCollectionExtensionsTest.AddHttpClient_WithTypedClient_WithFactoryAndName_ConfiguresNamedClient
[12:40:04] info: Microsoft.Extensions.DependencyInjection.HttpClientFactoryServiceCollectionExtensionsTest 1.8503313 ms
[12:47:08] info: [0x000dc8fc--UI- 12:47:08.526] MONO_WASM: Dumping web worker info as seen by UI thread, it could be stale:
[12:47:08] info: 000 | 0x000dc8fc--UI-: isRunning: true isAttached: true isEventLoop:false reuseCount:  0 - UI Thread
[12:47:08] info: 001 | 0x01b376c0-dpty: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 -
[12:47:08] info: 002 | 0x0350ad98--IO-: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 -
[12:47:08] info: 004 | 0x03e68c68-gate: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET TP Gate
[12:47:08] info: 006 | 0x0452b768-long: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET Long Running Task
[12:47:08] info: 005 | 0x0483a8f0-long: isRunning: true isAttached: true isEventLoop:false reuseCount:  2 - .NET Long Running Task
[12:47:08] info: 007 | 0x05100040-long: isRunning: true isAttached: true isEventLoop:false reuseCount:  2 - .NET Long Running Task
[12:47:08] info: 009 | 0x05410040-long: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET Long Running Task
[12:47:08] info: 008 | 0x056102f0-long: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET Long Running Task
[12:47:08] info: 011 | 0x05920040-long: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET Long Running Task
[12:47:08] info: 012 | 0x05c30040-long: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET Long Running Task
[12:47:08] info: 010 | 0x05f40040-long: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET Long Running Task
[12:47:08] info: 014 | 0x064da430-norm: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - Finalizer
[12:47:08] info: 013 | 0x068f7850-timr: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET Timer
[12:47:08] info: 003 | 0x07af0040-pool: isRunning: true isAttached: true isEventLoop:false reuseCount:  2 - .NET TP Worker
[12:47:08] info: 017 | 0x07424f28-pool: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET TP Worker
[12:47:08] info: 016 | 0x077e02c8-pool: isRunning: true isAttached: true isEventLoop:false reuseCount:  2 - .NET TP Worker
[12:47:08] info: 015 | --077e02c8-emsc: isRunning:false isAttached:false isEventLoop:false reuseCount:  2 - unregistered:(.NET TP Worker)
[12:47:08] info: STATE CUE CARD: (? means a positive number, usually 1 or 2, * means any number)
[12:47:08] info: 	0x0	- starting (GOOD, unless the thread is running managed code)
[12:47:08] info: 	0x1	- detached (GOOD, unless the thread is running managed code)
[12:47:08] info: 	0x2	- running (BAD, unless it's the gc thread)
[12:47:08] info: 	0x?03	- async suspended (GOOD)
[12:47:08] info: 	0x?04	- self suspended (GOOD)
[12:47:08] info: 	0x?05	- async suspend requested (BAD)
[12:47:08] info: 	0x6	- blocking (BAD, unless there's no suspend initiator)
[12:47:08] info: 	0x?07	- blocking async suspended (GOOD)
[12:47:08] info: 	0x?08	- blocking self suspended (GOOD)
[12:47:08] info: 	0x?09	- blocking suspend requested (GOOD in coop; BAD in hybrid)
[12:47:08] info: --thread 0x3503cc8 id 0xdc8fc [0] state 109
[12:47:08] info: --thread 0x2041120 id 0x1b376c0 [0] state 109
[12:47:08] info: --thread 0x3507f90 id 0x350ad98 [0] state 109
[12:47:08] info: --thread 0x3e64658 id 0x3e68c68 [0] state 108
[12:47:08] info: --thread 0x47d2ff0 id 0x452b768 [0] state 109
[12:47:08] info: --thread 0x47c03f0 id 0x483a8f0 [0] state 109
[12:47:08] info: --thread 0x4818760 id 0x5100040 [0] state 105
[12:47:08] info: --thread 0x4787498 id 0x5410040 [0] state 109
[12:47:08] info: --thread 0x47edaf0 id 0x56102f0 [0] state 105
[12:47:08] info: --thread 0x48098c8 id 0x5920040 [0] state 109
[12:47:08] info: --thread 0x480a100 id 0x5c30040 [0] state 109
[12:47:08] info: --thread 0x4cbfb18 id 0x5f40040 [0] state 109
[12:47:08] info: --thread 0x6cf0128 id 0x64da430 [0] state 6
[12:47:08] info: --thread 0x6b26db0 id 0x68f7850 [0] state 108
[12:47:08] info: --thread 0x3c881e8 id 0x7424f28 [0] state 109
[12:47:08] info: --thread 0x77cd1b8 id 0x77e02c8 [0] state 109
[12:47:08] info: --thread 0x79ef9b0 id 0x7af0040 [0] state 2  GC INITIATOR
[12:47:08] info: WAITING for 2 threads, got 0 suspended
[12:47:08] fail: [0x07af0040-pool 12:47:08.557] [MONO] /__w/1/s/src/mono/mono/utils/mono-threads.c:336 <disabled>
[13:09:57] fail: Tests timed out. Killing driver service pid 80
[13:09:57] fail: Application has finished with exit code TIMED_OUT but 0 was expected

GC fail and timeout

[12:40:34] fail: [0x05970040-long 12:40:34.088] [MONO] * Assertion: should not be reached at /__w/1/s/src/mono/mono/sgen/sgen-scan-object.h:93
[13:10:22] fail: Tests timed out. Killing driver service pid 79
[13:10:22] fail: Application has finished with exit code TIMED_OUT but 0 was expected

Uncaught, probably after exit

[12:40:32] info: [STRT] System.Runtime.InteropServices.JavaScript.Tests.WebWorkerWebSocketTest.WebSocketClient_ResponseCloseInDifferentThread(executor1: JSWebWorker, executor2: JSWebWorker)
[12:40:33] warn: [0x05548048-jsww 12:40:33.000] [MONO] /__w/1/s/src/mono/mono/metadata/icall.c:6162 <disabled>
[12:40:33] warn: [0x05548048-jsww 12:40:33.001] [MONO] /__w/1/s/src/mono/mono/metadata/icall.c:6171 <disabled>
[12:40:33] info: [0x000dd83c--UI- 12:40:33.003] MONO_WASM: Dumping web worker info as seen by UI thread, it could be stale:
[12:40:33] info: 000 | 0x000dd83c--UI-: isRunning: true isAttached: true isEventLoop:false reuseCount:  0 - UI Thread
[12:40:33] info: 001 | 0x00c68478-dpty: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 -
[12:40:33] info: 002 | 0x025cb848--IO-: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 -
[12:40:33] info: 003 | 0x02c06068-pool: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET TP Worker
[12:40:33] info: 004 | 0x02f10040-gate: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET TP Gate
[12:40:33] info: 005 | 0x0322aee0-norm: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - Finalizer
[12:40:33] info: 007 | 0x03785c90-timr: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET Timer
[12:40:33] info: 008 | 0x04000040-pool: isRunning: true isAttached: true isEventLoop:false reuseCount:  2 - .NET TP Worker
[12:40:33] info: 020 | 0x048f0040-pool: isRunning: true isAttached: true isEventLoop:false reuseCount:  1 - .NET TP Worker
[12:40:33] info: [0x05548048-jsww 12:40:33.012] MONO_WASM: Dumping web worker info as seen by UI thread, it could be stale:
[12:40:33] info: 023 | 0x05548048-jsww: isRunning: true isAttached: true isEventLoop: true reuseCount:  2 - JSWebWorker
[12:40:33] info: 026 | 0x05960040-jsww: isRunning: true isAttached: true isEventLoop: true reuseCount:  1 - JSWebWorker
[12:40:33] info: 025 |           -    : isRunning:false isAttached:false isEventLoop:false reuseCount:  0 - emscripten-pool
[12:40:33] info: 023 | 0x05548048-jsww: isRunning:false isAttached: true isEventLoop: true reuseCount:  2 - JSWebWorker
[12:40:33] warn: [0x05960040-jsww 12:40:33.019] MONO_WASM: JSSynchronizationContext is still installed on worker.
[12:40:33] fail: [out of order message from the browser]: http://127.0.0.1:46827/_framework/dotnet.native.js 7:1295 Uncaught ExitStatus: 
[12:40:33] warn: Aborted(Error
                     at tt (http://127.0.0.1:46827/_framework/dotnet.js:3:21066)
                     at Object.et [as onAbort] (http://127.0.0.1:46827/_framework/dotnet.js:3:20680)
                     at abort (http://127.0.0.1:46827/_framework/dotnet.native.js:8:11343)
                     at _abort (http://127.0.0.1:46827/_framework/dotnet.native.js:8:105071)
                     at ves_icall_System_Environment_FailFast (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[2326]:0xb9713)
                     at ves_icall_System_Environment_FailFast_raw (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[2357]:0xba824)
                     at do_icall (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[210]:0x21fdb)
                     at do_icall_wrapper (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[138]:0x1f77b)
                     at mono_interp_exec_method (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[129]:0x12050)
                     at interp_runtime_invoke (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[168]:0x203fd)
                     at mono_jit_runtime_invoke (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[3759]:0x112c75)
                     at do_runtime_invoke (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[2970]:0xe4020)
                     at mono_runtime_try_invoke (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[2978]:0xe4aee)
                     at mono_runtime_invoke (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[3005]:0xe72f0)
                     at mono_wasm_invoke_jsexport (http://127.0.0.1:46827/_framework/dotnet.native.wasm:wasm-function[9230]:0x23b1e2)
                     at Module._mono_wasm_invoke_jsexport [as mono_wasm_invoke_jsexport] (http://127.0.0.1:46827/_framework/dotnet.native.js:8:138084)
                     at zn (http://127.0.0.1:46827/_framework/dotnet.runtime.js:3:36460)
                     at http://127.0.0.1:46827/_framework/dotnet.runtime.js:3:53804
                     at Lo.complete_task_wrapper (http://127.0.0.1:46827/_framework/dotnet.runtime.js:3:53871)
                     at Lo.resolve (http://127.0.0.1:46827/_framework/dotnet.runtime.js:3:52319)
                     at http://127.0.0.1:46827/_framework/dotnet.runtime.js:3:56987)

@pavelsavara
Copy link
Member Author

Last few are always in HttpClient_ContentInDifferentThread(executor1: JSWebWorker, executor2: NewThread)

Log

[23:04:31] info: [STRT] System.Runtime.InteropServices.JavaScript.Tests.WebWorkerHttpTest.HttpClient_ContentInDifferentThread(executor1: JSWebWorker, executor2: NewThread)
[23:04:31] warn: [0x06280000-jsww 23:04:31.379] [MONO] /__w/1/s/src/mono/mono/metadata/icall.c:6189 <disabled>
[23:04:31] warn: [0x06280000-jsww 23:04:31.379] [MONO] /__w/1/s/src/mono/mono/metadata/icall.c:6198 <disabled>

@pavelsavara
Copy link
Member Author

This PR changed that there will be different message now #101056
And this fixed the underlying issue in System.Runtime.InteropServices.JavaScript.Tests #101078

@github-actions github-actions bot locked and limited conversation to collaborators May 23, 2024
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
arch-wasm WebAssembly architecture area-System.Runtime.InteropServices.JavaScript 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 os-browser Browser variant of arch-wasm
Projects
None yet
Development

No branches or pull requests

1 participant