[2026-03-05T01:43:21.056Z] Running test hotspot_gc_0 ... [2026-03-05T01:43:21.056Z] =============================================== [2026-03-05T01:43:21.056Z] hotspot_gc_0 Start Time: Thu Mar 5 01:43:20 2026 Epoch Time (ms): 1772675000987 [2026-03-05T01:43:21.407Z] variation: NoOptions [2026-03-05T01:43:21.407Z] JVM_OPTIONS: [2026-03-05T01:43:21.407Z] { \ [2026-03-05T01:43:21.407Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T01:43:21.407Z] echo "Nothing to be done for setup."; \ [2026-03-05T01:43:21.407Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17726749975704\\hotspot_gc_0"; \ [2026-03-05T01:43:21.408Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17726749975704\\hotspot_gc_0"; \ [2026-03-05T01:43:21.408Z] echo ""; echo "TESTING:"; \ [2026-03-05T01:43:21.408Z] "c:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/jdkbinary/j2sdk-image\\bin\\java" -Xmx512m -jar "C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\openjdk\\jtreg\\lib\\jtreg.jar" \ [2026-03-05T01:43:21.408Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:3 -nativepath:"c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/jdkbinary/openjdk-test-image\\hotspot\\jtreg\\native" -vmoptions:"-Xmx512m " \ [2026-03-05T01:43:21.408Z] -w ""C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17726749975704\\hotspot_gc_0"\\work" \ [2026-03-05T01:43:21.408Z] -r ""C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17726749975704\\hotspot_gc_0"\\report" \ [2026-03-05T01:43:21.408Z] -jdk:"c:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/jdkbinary/j2sdk-image" \ [2026-03-05T01:43:21.408Z] -exclude:"C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/\\openjdk\\openjdk-jdk\\test\\hotspot\\jtreg\\ProblemList.txt" \ [2026-03-05T01:43:21.408Z] -exclude:"C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\openjdk\\excludes/ProblemList_openjdk21.txt" \ [2026-03-05T01:43:21.408Z] \ [2026-03-05T01:43:21.408Z] -exclude:"C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/\\openjdk\\excludes\\vendors\\eclipse\\ProblemList_openjdk21.txt" \ [2026-03-05T01:43:21.408Z] "C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/\\openjdk\\openjdk-jdk\\test\\hotspot\\jtreg:hotspot_gc"; \ [2026-03-05T01:43:21.408Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T01:43:21.408Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T01:43:21.408Z] echo "Nothing to be done for teardown."; \ [2026-03-05T01:43:21.408Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17726749975704\\TestTargetResult"; [2026-03-05T01:43:21.408Z] [2026-03-05T01:43:21.408Z] TEST SETUP: [2026-03-05T01:43:21.408Z] Nothing to be done for setup. [2026-03-05T01:43:21.408Z] [2026-03-05T01:43:21.408Z] TESTING: [2026-03-05T01:43:22.148Z] Directory "C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work" not found: creating [2026-03-05T01:43:22.148Z] Directory "C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\report" not found: creating [2026-03-05T01:43:27.006Z] XML output with verification to C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work [2026-03-05T02:33:33.057Z] -------------------------------------------------- [2026-03-05T02:33:33.057Z] TEST: gc/stress/gclocker/TestGCLockerWithShenandoah.java#aggressive [2026-03-05T02:33:33.057Z] TEST JDK: c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image [2026-03-05T02:33:33.057Z] [2026-03-05T02:33:33.057Z] ACTION: build -- Passed. Build successful [2026-03-05T02:33:33.057Z] REASON: Named class compiled on demand [2026-03-05T02:33:33.057Z] TIME: 0.047 seconds [2026-03-05T02:33:33.057Z] messages: [2026-03-05T02:33:33.057Z] command: build gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.057Z] reason: Named class compiled on demand [2026-03-05T02:33:33.057Z] started: Thu Mar 05 02:31:19 UTC 2026 [2026-03-05T02:33:33.057Z] Test directory: [2026-03-05T02:33:33.057Z] compile: gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.057Z] finished: Thu Mar 05 02:31:19 UTC 2026 [2026-03-05T02:33:33.057Z] elapsed time (seconds): 0.047 [2026-03-05T02:33:33.057Z] [2026-03-05T02:33:33.057Z] ACTION: compile -- Passed. Compilation successful [2026-03-05T02:33:33.057Z] REASON: .class file out of date or does not exist [2026-03-05T02:33:33.057Z] TIME: 0.045 seconds [2026-03-05T02:33:33.057Z] messages: [2026-03-05T02:33:33.057Z] command: compile C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker\TestGCLockerWithShenandoah.java [2026-03-05T02:33:33.057Z] reason: .class file out of date or does not exist [2026-03-05T02:33:33.057Z] started: Thu Mar 05 02:31:19 UTC 2026 [2026-03-05T02:33:33.057Z] Mode: agentvm [2026-03-05T02:33:33.057Z] Agent id: 1 [2026-03-05T02:33:33.057Z] Process id: 7884 [2026-03-05T02:33:33.057Z] finished: Thu Mar 05 02:31:19 UTC 2026 [2026-03-05T02:33:33.057Z] elapsed time (seconds): 0.045 [2026-03-05T02:33:33.057Z] configuration: [2026-03-05T02:33:33.057Z] Boot Layer (javac runtime environment) [2026-03-05T02:33:33.057Z] class path: C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jvmtest\openjdk\jtreg\lib\javatest.jar [2026-03-05T02:33:33.057Z] C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jvmtest\openjdk\jtreg\lib\jtreg.jar [2026-03-05T02:33:33.057Z] patch: java.base C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\patches\java.base [2026-03-05T02:33:33.057Z] [2026-03-05T02:33:33.057Z] javac compilation environment [2026-03-05T02:33:33.057Z] source path: C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker [2026-03-05T02:33:33.057Z] C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg [2026-03-05T02:33:33.057Z] class path: C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker [2026-03-05T02:33:33.057Z] C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d [2026-03-05T02:33:33.057Z] C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0 [2026-03-05T02:33:33.057Z] [2026-03-05T02:33:33.057Z] rerun: [2026-03-05T02:33:33.057Z] cd 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\scratch\0' && \ [2026-03-05T02:33:33.057Z] PATH='C:\cygwin64\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0;C:\Windows\System32\OpenSSH;C:\Program Files\dotnet;C:\Users\adoptopenjdk\AppData\Local\Microsoft\WindowsApps;C:\Strawberry\perl\bin;C:\Program Files\Git\cmd;C:\openjdk\jdk-8\bin;C:\apache-ant\apache-ant-1.10.15\bin;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps' \ [2026-03-05T02:33:33.057Z] SystemDrive=C: \ [2026-03-05T02:33:33.057Z] SystemRoot='C:\Windows' \ [2026-03-05T02:33:33.057Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-03-05T02:33:33.057Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-03-05T02:33:33.057Z] windir='C:\Windows' \ [2026-03-05T02:33:33.057Z] 'c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image\bin\javac' \ [2026-03-05T02:33:33.057Z] -J-ea \ [2026-03-05T02:33:33.057Z] -J-esa \ [2026-03-05T02:33:33.057Z] -J-Xmx512m \ [2026-03-05T02:33:33.057Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2026-03-05T02:33:33.057Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2026-03-05T02:33:33.057Z] -J-Dtest.compiler.opts= \ [2026-03-05T02:33:33.057Z] -J-Dtest.java.opts= \ [2026-03-05T02:33:33.057Z] -J-Dtest.jdk='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image' \ [2026-03-05T02:33:33.057Z] -J-Dcompile.jdk='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image' \ [2026-03-05T02:33:33.057Z] -J-Dtest.timeout.factor=8.0 \ [2026-03-05T02:33:33.057Z] -J-Dtest.nativepath='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2026-03-05T02:33:33.057Z] -J-Dtest.root='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2026-03-05T02:33:33.057Z] -J-Dtest.name=gc/stress/gclocker/TestGCLockerWithShenandoah.java#aggressive \ [2026-03-05T02:33:33.057Z] -J-Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2026-03-05T02:33:33.057Z] -J-Dtest.file='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker\TestGCLockerWithShenandoah.java' \ [2026-03-05T02:33:33.057Z] -J-Dtest.main.class=gc.stress.gclocker.TestGCLockerWithShenandoah \ [2026-03-05T02:33:33.057Z] -J-Dtest.src='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker' \ [2026-03-05T02:33:33.057Z] -J-Dtest.src.path='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2026-03-05T02:33:33.057Z] -J-Dtest.classes='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d' \ [2026-03-05T02:33:33.058Z] -J-Dtest.class.path='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0' \ [2026-03-05T02:33:33.058Z] -J-Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0' \ [2026-03-05T02:33:33.058Z] -d 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d' \ [2026-03-05T02:33:33.058Z] -sourcepath 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2026-03-05T02:33:33.058Z] -classpath 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0' 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker\TestGCLockerWithShenandoah.java' [2026-03-05T02:33:33.058Z] [2026-03-05T02:33:33.058Z] ACTION: main -- Passed. Execution successful [2026-03-05T02:33:33.058Z] REASON: User specified action: run main/native/othervm/timeout=200 -Xlog:gc*=info -Xms1500m -Xmx1500m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=aggressive -XX:+ShenandoahOOMDuringEvacALot gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.058Z] TIME: 123.22 seconds [2026-03-05T02:33:33.058Z] messages: [2026-03-05T02:33:33.058Z] command: main -Xlog:gc*=info -Xms1500m -Xmx1500m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=aggressive -XX:+ShenandoahOOMDuringEvacALot gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.058Z] reason: User specified action: run main/native/othervm/timeout=200 -Xlog:gc*=info -Xms1500m -Xmx1500m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=aggressive -XX:+ShenandoahOOMDuringEvacALot gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.058Z] started: Thu Mar 05 02:31:19 UTC 2026 [2026-03-05T02:33:33.058Z] Mode: othervm [/othervm specified] [2026-03-05T02:33:33.058Z] Process id: 39440 [2026-03-05T02:33:33.058Z] finished: Thu Mar 05 02:33:22 UTC 2026 [2026-03-05T02:33:33.058Z] elapsed time (seconds): 123.22 [2026-03-05T02:33:33.058Z] configuration: [2026-03-05T02:33:33.058Z] STDOUT: [2026-03-05T02:33:33.058Z] [0.005s][info][gc] Min heap equals to max heap, disabling ShenandoahUncommit [2026-03-05T02:33:33.058Z] [0.015s][info][gc] Heuristics ergonomically sets -XX:+ExplicitGCInvokesConcurrent [2026-03-05T02:33:33.058Z] [0.015s][info][gc] Heuristics ergonomically sets -XX:+ShenandoahImplicitGCInvokesConcurrent [2026-03-05T02:33:33.058Z] [0.015s][info][gc] Using Shenandoah [2026-03-05T02:33:33.058Z] [0.015s][info][gc] Heuristics ergonomically sets -XX:ShenandoahImmediateThreshold=100 [2026-03-05T02:33:33.058Z] [0.020s][info][gc,ergo] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Version: 21.0.11-beta+5-ea (release) [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] CPUs: 4 total, 4 available [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Memory: 8136M [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Large Page Support: Disabled [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] NUMA Support: Disabled [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Compressed Oops: Enabled (32-bit) [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Heap Min Capacity: 1500M [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Heap Initial Capacity: 1500M [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Heap Max Capacity: 1500M [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Pre-touch: Disabled [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Mode: Snapshot-At-The-Beginning (SATB) [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Heuristics: Aggressive [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Heap Region Count: 3000 [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Heap Region Size: 512K [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] TLAB Size Max: 512K [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Humongous Object Threshold: 512K [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Parallel Workers: 2 [2026-03-05T02:33:33.058Z] [0.021s][info][gc,init] Concurrent Workers: 1 [2026-03-05T02:33:33.058Z] [0.037s][info][gc,metaspace] CDS archive(s) mapped at: [0x000001cd80000000-0x000001cd80c80000-0x000001cd80c80000), size 13107200, SharedBaseAddress: 0x000001cd80000000, ArchiveRelocationMode: 1. [2026-03-05T02:33:33.058Z] [0.037s][info][gc,metaspace] Compressed class space mapped at: 0x000001cd81000000-0x000001cdc1000000, reserved size: 1073741824 [2026-03-05T02:33:33.058Z] [0.037s][info][gc,metaspace] Narrow klass base: 0x000001cd80000000, Narrow klass shift: 0, Narrow klass range: 0x100000000 [2026-03-05T02:33:33.058Z] [0.065s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.058Z] [0.065s][info][gc,ergo ] Free: 1424M, Max: 512K regular, 1424M humongous, Frag: 0% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.058Z] [0.065s][info][gc,start ] GC(0) Concurrent reset [2026-03-05T02:33:33.058Z] [0.065s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.058Z] [0.065s][info][gc,ergo ] GC(0) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.058Z] [0.065s][info][gc ] GC(0) Concurrent reset 0.206ms [2026-03-05T02:33:33.058Z] [0.071s][info][gc,start ] GC(0) Pause Init Mark [2026-03-05T02:33:33.058Z] [0.071s][info][gc,task ] GC(0) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.058Z] [0.071s][info][gc,ergo ] GC(0) Pacer for Mark. Expected Live: 150M, Free: 1424M, Non-Taxable: 142M, Alloc Tax Rate: 0.1x [2026-03-05T02:33:33.058Z] [0.071s][info][gc ] GC(0) Pause Init Mark 0.197ms [2026-03-05T02:33:33.058Z] [0.071s][info][gc,start ] GC(0) Concurrent marking roots [2026-03-05T02:33:33.058Z] [0.071s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.058Z] [0.071s][info][gc ] GC(0) Concurrent marking roots 0.138ms [2026-03-05T02:33:33.058Z] [0.071s][info][gc,start ] GC(0) Concurrent marking [2026-03-05T02:33:33.058Z] [0.071s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.058Z] [0.080s][info][gc ] GC(0) Concurrent marking 9.131ms [2026-03-05T02:33:33.058Z] [0.080s][info][gc,start ] GC(0) Pause Final Mark [2026-03-05T02:33:33.058Z] [0.080s][info][gc,task ] GC(0) Using 2 of 2 workers for final marking [2026-03-05T02:33:33.058Z] [0.081s][info][gc,ergo ] GC(0) Collectable Garbage: 338K (100%), Immediate: 0B (0%), CSet: 338K (100%) [2026-03-05T02:33:33.058Z] [0.081s][info][gc,ergo ] GC(0) Pacer for Evacuation. Used CSet: 512K, Free: 1423M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.058Z] [0.081s][info][gc ] GC(0) Pause Final Mark 0.208ms [2026-03-05T02:33:33.058Z] [0.081s][info][gc,start ] GC(0) Concurrent thread roots [2026-03-05T02:33:33.058Z] [0.081s][info][gc,task ] GC(0) Using 1 of 2 workers for Concurrent thread roots [2026-03-05T02:33:33.058Z] [0.081s][info][gc ] GC(0) Concurrent thread roots 0.248ms [2026-03-05T02:33:33.058Z] [0.081s][info][gc,start ] GC(0) Concurrent weak references [2026-03-05T02:33:33.058Z] [0.081s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent weak references [2026-03-05T02:33:33.058Z] [0.081s][info][gc,ref ] GC(0) Encountered references: Soft: 1, Weak: 1, Final: 0, Phantom: 0 [2026-03-05T02:33:33.058Z] [0.081s][info][gc,ref ] GC(0) Discovered references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.058Z] [0.081s][info][gc,ref ] GC(0) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.058Z] [0.081s][info][gc ] GC(0) Concurrent weak references 0.062ms [2026-03-05T02:33:33.058Z] [0.081s][info][gc,start ] GC(0) Concurrent weak roots [2026-03-05T02:33:33.058Z] [0.259s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent weak root [2026-03-05T02:33:33.058Z] [0.259s][info][gc ] GC(0) Concurrent weak roots 178.211ms [2026-03-05T02:33:33.058Z] [0.259s][info][gc,start ] GC(0) Concurrent cleanup [2026-03-05T02:33:33.058Z] [0.259s][info][gc ] GC(0) Concurrent cleanup 3M->3M(1500M) 0.074ms [2026-03-05T02:33:33.058Z] [0.259s][info][gc,ergo ] GC(0) Free: 1422M, Max: 512K regular, 1422M humongous, Frag: 0% external, 0% internal; Reserve: 76703K, Max: 512K [2026-03-05T02:33:33.058Z] [0.259s][info][gc,start ] GC(0) Concurrent strong roots [2026-03-05T02:33:33.058Z] [0.259s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent strong root [2026-03-05T02:33:33.058Z] [0.259s][info][gc ] GC(0) Concurrent strong roots 0.189ms [2026-03-05T02:33:33.058Z] [0.259s][info][gc,start ] GC(0) Concurrent evacuation [2026-03-05T02:33:33.058Z] [0.260s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent evacuation [2026-03-05T02:33:33.058Z] [0.260s][info][gc ] GC(0) Concurrent evacuation 0.274ms [2026-03-05T02:33:33.058Z] [0.260s][info][gc,start ] GC(0) Pause Init Update Refs [2026-03-05T02:33:33.058Z] [0.260s][info][gc,ergo ] GC(0) Pacer for Update Refs. Used: 3278K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.058Z] [0.260s][info][gc ] GC(0) Pause Init Update Refs 0.048ms [2026-03-05T02:33:33.058Z] [0.260s][info][gc,start ] GC(0) Concurrent update references [2026-03-05T02:33:33.058Z] [0.260s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent reference update [2026-03-05T02:33:33.058Z] [0.260s][info][gc ] GC(0) Concurrent update references 0.462ms [2026-03-05T02:33:33.059Z] [0.260s][info][gc,start ] GC(0) Concurrent update thread roots [2026-03-05T02:33:33.059Z] [0.261s][info][gc ] GC(0) Concurrent update thread roots 0.126ms [2026-03-05T02:33:33.059Z] [0.261s][info][gc,start ] GC(0) Pause Final Update Refs [2026-03-05T02:33:33.059Z] [0.261s][info][gc,task ] GC(0) Using 2 of 2 workers for final reference update [2026-03-05T02:33:33.059Z] [0.261s][info][gc ] GC(0) Pause Final Update Refs 0.113ms [2026-03-05T02:33:33.059Z] [0.261s][info][gc,start ] GC(0) Concurrent cleanup [2026-03-05T02:33:33.059Z] [0.261s][info][gc ] GC(0) Concurrent cleanup 3M->2M(1500M) 0.046ms [2026-03-05T02:33:33.059Z] [0.261s][info][gc,ergo ] Free: 1422M, Max: 512K regular, 1421M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Reset 229 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Pause Init Mark (G) 5870 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Pause Init Mark (N) 209 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Update Region States 65 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Mark Roots 154 us, parallelism: 0.30x [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CMR: <total> 47 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CMR: Thread Roots 11 us, workers (us): 11, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CMR: Code Cache Roots 1 us, workers (us): 1, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CMR: VM Strong Roots 8 us, workers (us): 8, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CMR: CLDG Roots 27 us, workers (us): 27, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Marking 9153 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Pause Final Mark (G) 307 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Pause Final Mark (N) 216 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Finish Mark 28 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Update Region States 69 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Choose Collection Set 56 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Rebuild Free Set 18 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Thread Roots 263 us, parallelism: 0.77x [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CTR: <total> 203 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CTR: Thread Roots 203 us, workers (us): 203, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Weak References 68 us, parallelism: 0.01x [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CWRF: <total> 1 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CWRF: Weak References 1 us, workers (us): 1, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Weak Roots 178226 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Roots 155 us, parallelism: 0.73x [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CWR: <total> 112 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CWR: VM Weak Roots 112 us, workers (us): 112, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Rendezvous 86 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Cleanup 84 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Strong Roots 200 us, parallelism: 0.75x [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CSR: <total> 150 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CSR: Code Cache Roots 83 us, workers (us): 83, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CSR: VM Strong Roots 19 us, workers (us): 19, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] CSR: CLDG Roots 49 us, workers (us): 49, ---, [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Evacuation 283 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Pause Init Update Refs (G) 125 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Pause Init Update Refs (N) 57 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Manage GCLABs 4 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Update Refs 480 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Update Thread Roots 139 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Pause Final Update Refs (G) 153 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Pause Final Update Refs (N) 121 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Update Region States 63 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Trash Collection Set 3 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Rebuild Free Set 23 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Concurrent Cleanup 54 us [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] 0 of 241 ms ( 0.0%): <total> [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] 0 of 241 ms ( 0.0%): <average total> [2026-03-05T02:33:33.059Z] [0.261s][info][gc,stats ] [2026-03-05T02:33:33.059Z] [0.261s][info][gc,metaspace] Metaspace: 30K(128K)->127K(192K) NonClass: 28K(64K)->125K(128K) Class: 1K(64K)->1K(64K) [2026-03-05T02:33:33.059Z] [0.262s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.059Z] [0.264s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.059Z] [0.264s][info][gc,ergo ] Free: 1422M, Max: 512K regular, 1421M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.059Z] [0.264s][info][gc,start ] GC(1) Concurrent reset [2026-03-05T02:33:33.059Z] [0.264s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.059Z] [0.264s][info][gc,ergo ] GC(1) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.059Z] [0.264s][info][gc ] GC(1) Concurrent reset 0.169ms [2026-03-05T02:33:33.059Z] [0.264s][info][gc,start ] GC(1) Pause Init Mark (unload classes) [2026-03-05T02:33:33.059Z] [0.264s][info][gc,task ] GC(1) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.059Z] [0.264s][info][gc,ergo ] GC(1) Pacer for Mark. Expected Live: 173K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 0.0x [2026-03-05T02:33:33.059Z] [0.264s][info][gc ] GC(1) Pause Init Mark (unload classes) 0.058ms [2026-03-05T02:33:33.059Z] [0.264s][info][gc,start ] GC(1) Concurrent marking roots [2026-03-05T02:33:33.059Z] [0.264s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.059Z] [0.264s][info][gc ] GC(1) Concurrent marking roots 0.423ms [2026-03-05T02:33:33.059Z] [0.264s][info][gc,start ] GC(1) Concurrent marking (unload classes) [2026-03-05T02:33:33.059Z] [0.264s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.059Z] [0.266s][info][gc ] GC(1) Concurrent marking (unload classes) 1.419ms [2026-03-05T02:33:33.059Z] [0.266s][info][gc,start ] GC(1) Pause Final Mark (unload classes) [2026-03-05T02:33:33.059Z] [0.266s][info][gc,task ] GC(1) Using 2 of 2 workers for final marking [2026-03-05T02:33:33.059Z] [0.266s][info][gc,ergo ] GC(1) Collectable Garbage: 2273K (100%), Immediate: 0B (0%), CSet: 2273K (100%) [2026-03-05T02:33:33.059Z] [0.266s][info][gc,ergo ] GC(1) Pacer for Evacuation. Used CSet: 2766K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.059Z] [0.266s][info][gc ] GC(1) Pause Final Mark (unload classes) 0.164ms [2026-03-05T02:33:33.059Z] [0.266s][info][gc,start ] GC(1) Concurrent thread roots [2026-03-05T02:33:33.059Z] [0.266s][info][gc,task ] GC(1) Using 1 of 2 workers for Concurrent thread roots [2026-03-05T02:33:33.059Z] [0.266s][info][gc ] GC(1) Concurrent thread roots 0.124ms [2026-03-05T02:33:33.059Z] [0.266s][info][gc,start ] GC(1) Concurrent weak references [2026-03-05T02:33:33.059Z] [0.367s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent weak references [2026-03-05T02:33:33.059Z] [0.367s][info][gc,ref ] GC(1) Encountered references: Soft: 1, Weak: 6, Final: 0, Phantom: 0 [2026-03-05T02:33:33.059Z] [0.367s][info][gc,ref ] GC(1) Discovered references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.059Z] [0.367s][info][gc,ref ] GC(1) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.059Z] [0.367s][info][gc ] GC(1) Concurrent weak references 100.698ms [2026-03-05T02:33:33.059Z] [0.367s][info][gc,start ] GC(1) Concurrent weak roots [2026-03-05T02:33:33.059Z] [0.367s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent weak root [2026-03-05T02:33:33.060Z] [0.367s][info][gc ] GC(1) Concurrent weak roots 0.340ms [2026-03-05T02:33:33.060Z] [0.367s][info][gc,start ] GC(1) Concurrent cleanup [2026-03-05T02:33:33.060Z] [0.367s][info][gc ] GC(1) Concurrent cleanup 4M->4M(1500M) 0.073ms [2026-03-05T02:33:33.060Z] [0.367s][info][gc,ergo ] GC(1) Free: 1420M, Max: 512K regular, 1420M humongous, Frag: 0% external, 0% internal; Reserve: 76656K, Max: 512K [2026-03-05T02:33:33.060Z] [0.367s][info][gc,start ] GC(1) Concurrent class unloading [2026-03-05T02:33:33.060Z] [0.367s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent class unloading [2026-03-05T02:33:33.060Z] [0.368s][info][gc ] GC(1) Concurrent class unloading 0.325ms [2026-03-05T02:33:33.060Z] [0.368s][info][gc,start ] GC(1) Concurrent strong roots [2026-03-05T02:33:33.060Z] [0.368s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent strong root [2026-03-05T02:33:33.060Z] [0.368s][info][gc ] GC(1) Concurrent strong roots 0.163ms [2026-03-05T02:33:33.060Z] [0.368s][info][gc,start ] GC(1) Concurrent evacuation [2026-03-05T02:33:33.060Z] [0.368s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent evacuation [2026-03-05T02:33:33.060Z] [0.369s][info][gc ] GC(1) Concurrent evacuation 0.829ms [2026-03-05T02:33:33.060Z] [0.369s][info][gc,start ] GC(1) Pause Init Update Refs [2026-03-05T02:33:33.060Z] [0.369s][info][gc,ergo ] GC(1) Pacer for Update Refs. Used: 5836K, Free: 1420M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.060Z] [0.369s][info][gc ] GC(1) Pause Init Update Refs 0.039ms [2026-03-05T02:33:33.060Z] [0.369s][info][gc,start ] GC(1) Concurrent update references [2026-03-05T02:33:33.060Z] [0.369s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent reference update [2026-03-05T02:33:33.060Z] [0.370s][info][gc ] GC(1) Concurrent update references 0.910ms [2026-03-05T02:33:33.060Z] [0.370s][info][gc,start ] GC(1) Concurrent update thread roots [2026-03-05T02:33:33.060Z] [0.370s][info][gc ] GC(1) Concurrent update thread roots 0.269ms [2026-03-05T02:33:33.060Z] [0.370s][info][gc,start ] GC(1) Pause Final Update Refs [2026-03-05T02:33:33.060Z] [0.370s][info][gc,task ] GC(1) Using 2 of 2 workers for final reference update [2026-03-05T02:33:33.060Z] [0.370s][info][gc ] GC(1) Pause Final Update Refs 0.066ms [2026-03-05T02:33:33.060Z] [0.370s][info][gc,start ] GC(1) Concurrent cleanup [2026-03-05T02:33:33.060Z] [0.370s][info][gc ] GC(1) Concurrent cleanup 5M->2M(1500M) 0.054ms [2026-03-05T02:33:33.060Z] [0.370s][info][gc,ergo ] Free: 1422M, Max: 512K regular, 1419M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Concurrent Reset 181 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Pause Init Mark (G) 105 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Pause Init Mark (N) 65 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Update Region States 14 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Concurrent Mark Roots 436 us, parallelism: 0.86x [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] CMR: <total> 375 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] CMR: Thread Roots 311 us, workers (us): 311, ---, [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] CMR: VM Strong Roots 22 us, workers (us): 22, ---, [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] CMR: CLDG Roots 43 us, workers (us): 43, ---, [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Concurrent Marking 1436 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Pause Final Mark (G) 196 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Pause Final Mark (N) 170 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Finish Mark 54 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Update Region States 26 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Choose Collection Set 27 us [2026-03-05T02:33:33.060Z] [0.370s][info][gc,stats ] Rebuild Free Set 20 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Thread Roots 134 us, parallelism: 0.60x [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CTR: <total> 80 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CTR: Thread Roots 80 us, workers (us): 80, ---, [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Weak References 100707 us, parallelism: 0.00x [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CWRF: <total> 2 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CWRF: Weak References 2 us, workers (us): 2, ---, [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Weak Roots 355 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Roots 250 us, parallelism: 0.88x [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CWR: <total> 219 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CWR: Code Cache Roots 20 us, workers (us): 20, ---, [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CWR: VM Weak Roots 198 us, workers (us): 198, ---, [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CWR: CLDG Roots 2 us, workers (us): 2, ---, [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Rendezvous 74 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Cleanup 80 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Class Unloading 343 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Unlink Stale 217 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] System Dictionary 4 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Weak Class Links 0 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Code Roots 212 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Rendezvous 64 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Purge Unlinked 26 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Code Roots 17 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CLDG 9 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Exception Caches 0 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Strong Roots 172 us, parallelism: 0.73x [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CSR: <total> 125 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CSR: VM Strong Roots 10 us, workers (us): 10, ---, [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] CSR: CLDG Roots 116 us, workers (us): 116, ---, [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Evacuation 847 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Pause Init Update Refs (G) 101 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Pause Init Update Refs (N) 47 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Manage GCLABs 4 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Update Refs 928 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Update Thread Roots 278 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Pause Final Update Refs (G) 132 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Pause Final Update Refs (N) 72 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Update Region States 24 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Trash Collection Set 3 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Rebuild Free Set 19 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Concurrent Cleanup 64 us [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] 0 of 110 ms ( 0.0%): <total> [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] 0 of 110 ms ( 0.0%): <average total> [2026-03-05T02:33:33.060Z] [0.371s][info][gc,stats ] [2026-03-05T02:33:33.060Z] [0.371s][info][gc,metaspace] Metaspace: 135K(256K)->403K(576K) NonClass: 132K(192K)->368K(448K) Class: 2K(64K)->34K(128K) [2026-03-05T02:33:33.060Z] [0.371s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.060Z] [0.373s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.060Z] [0.373s][info][gc,ergo ] Free: 1422M, Max: 512K regular, 1419M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.061Z] [0.373s][info][gc,start ] GC(2) Concurrent reset [2026-03-05T02:33:33.061Z] [0.373s][info][gc,task ] GC(2) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.061Z] [0.373s][info][gc,ergo ] GC(2) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.061Z] [0.373s][info][gc ] GC(2) Concurrent reset 0.204ms [2026-03-05T02:33:33.061Z] [0.373s][info][gc,start ] GC(2) Pause Init Mark [2026-03-05T02:33:33.061Z] [0.373s][info][gc,task ] GC(2) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.061Z] [0.373s][info][gc,ergo ] GC(2) Pacer for Mark. Expected Live: 333K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 0.0x [2026-03-05T02:33:33.061Z] [0.373s][info][gc ] GC(2) Pause Init Mark 0.076ms [2026-03-05T02:33:33.061Z] [0.373s][info][gc,start ] GC(2) Concurrent marking roots [2026-03-05T02:33:33.061Z] [0.373s][info][gc,task ] GC(2) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.061Z] [0.374s][info][gc ] GC(2) Concurrent marking roots 0.590ms [2026-03-05T02:33:33.061Z] [0.374s][info][gc,start ] GC(2) Concurrent marking [2026-03-05T02:33:33.061Z] [0.374s][info][gc,task ] GC(2) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.061Z] [0.376s][info][gc ] GC(2) Concurrent marking 2.545ms [2026-03-05T02:33:33.061Z] [0.376s][info][gc,start ] GC(2) Pause Final Mark [2026-03-05T02:33:33.061Z] [0.376s][info][gc,task ] GC(2) Using 2 of 2 workers for final marking [2026-03-05T02:33:33.061Z] [0.376s][info][gc,ergo ] GC(2) Collectable Garbage: 2172K (100%), Immediate: 0B (0%), CSet: 2172K (100%) [2026-03-05T02:33:33.061Z] [0.376s][info][gc,ergo ] GC(2) Pacer for Evacuation. Used CSet: 3069K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.061Z] [0.376s][info][gc ] GC(2) Pause Final Mark 0.172ms [2026-03-05T02:33:33.061Z] [0.376s][info][gc,start ] GC(2) Concurrent thread roots [2026-03-05T02:33:33.061Z] [0.376s][info][gc,task ] GC(2) Using 1 of 2 workers for Concurrent thread roots [2026-03-05T02:33:33.061Z] [0.376s][info][gc ] GC(2) Concurrent thread roots 0.135ms [2026-03-05T02:33:33.061Z] [0.377s][info][gc,start ] GC(2) Concurrent weak references [2026-03-05T02:33:33.061Z] [0.377s][info][gc,task ] GC(2) Using 1 of 2 workers for concurrent weak references [2026-03-05T02:33:33.061Z] [0.377s][info][gc,ref ] GC(2) Encountered references: Soft: 22, Weak: 58, Final: 0, Phantom: 9 [2026-03-05T02:33:33.061Z] [0.377s][info][gc,ref ] GC(2) Discovered references: Soft: 0, Weak: 7, Final: 0, Phantom: 9 [2026-03-05T02:33:33.061Z] [0.377s][info][gc,ref ] GC(2) Enqueued references: Soft: 0, Weak: 5, Final: 0, Phantom: 0 [2026-03-05T02:33:33.061Z] [0.377s][info][gc ] GC(2) Concurrent weak references 0.064ms [2026-03-05T02:33:33.061Z] [0.377s][info][gc,start ] GC(2) Concurrent weak roots [2026-03-05T02:33:33.061Z] [0.377s][info][gc,task ] GC(2) Using 1 of 2 workers for concurrent weak root [2026-03-05T02:33:33.061Z] [0.377s][info][gc ] GC(2) Concurrent weak roots 0.281ms [2026-03-05T02:33:33.061Z] [0.377s][info][gc,start ] GC(2) Concurrent cleanup [2026-03-05T02:33:33.061Z] [0.377s][info][gc ] GC(2) Concurrent cleanup 3M->3M(1500M) 0.036ms [2026-03-05T02:33:33.061Z] [0.377s][info][gc,ergo ] GC(2) Free: 1422M, Max: 512K regular, 1419M humongous, Frag: 1% external, 0% internal; Reserve: 76668K, Max: 512K [2026-03-05T02:33:33.061Z] [0.377s][info][gc,start ] GC(2) Concurrent strong roots [2026-03-05T02:33:33.061Z] [0.377s][info][gc,task ] GC(2) Using 1 of 2 workers for concurrent strong root [2026-03-05T02:33:33.061Z] [0.377s][info][gc ] GC(2) Concurrent strong roots 0.319ms [2026-03-05T02:33:33.061Z] [0.377s][info][gc,start ] GC(2) Concurrent evacuation [2026-03-05T02:33:33.061Z] [0.377s][info][gc,task ] GC(2) Using 1 of 2 workers for concurrent evacuation [2026-03-05T02:33:33.061Z] [0.378s][info][gc ] GC(2) Concurrent evacuation 1.189ms [2026-03-05T02:33:33.061Z] [0.379s][info][gc,start ] GC(2) Pause Init Update Refs [2026-03-05T02:33:33.061Z] [0.379s][info][gc,ergo ] GC(2) Pacer for Update Refs. Used: 4478K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.061Z] [0.379s][info][gc ] GC(2) Pause Init Update Refs 0.027ms [2026-03-05T02:33:33.061Z] [0.379s][info][gc,start ] GC(2) Concurrent update references [2026-03-05T02:33:33.061Z] [0.379s][info][gc,task ] GC(2) Using 1 of 2 workers for concurrent reference update [2026-03-05T02:33:33.061Z] [0.380s][info][gc ] GC(2) Concurrent update references 1.049ms [2026-03-05T02:33:33.061Z] [0.380s][info][gc,start ] GC(2) Concurrent update thread roots [2026-03-05T02:33:33.061Z] [0.380s][info][gc ] GC(2) Concurrent update thread roots 0.117ms [2026-03-05T02:33:33.061Z] [0.380s][info][gc,start ] GC(2) Pause Final Update Refs [2026-03-05T02:33:33.061Z] [0.380s][info][gc,task ] GC(2) Using 2 of 2 workers for final reference update [2026-03-05T02:33:33.061Z] [0.380s][info][gc ] GC(2) Pause Final Update Refs 0.069ms [2026-03-05T02:33:33.061Z] [0.380s][info][gc,start ] GC(2) Concurrent cleanup [2026-03-05T02:33:33.061Z] [0.380s][info][gc ] GC(2) Concurrent cleanup 4M->1M(1500M) 0.023ms [2026-03-05T02:33:33.061Z] [0.380s][info][gc,ergo ] Free: 1423M, Max: 512K regular, 1423M humongous, Frag: 0% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Concurrent Reset 221 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Pause Init Mark (G) 128 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Pause Init Mark (N) 87 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Update Region States 36 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Concurrent Mark Roots 607 us, parallelism: 0.86x [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CMR: <total> 525 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CMR: Thread Roots 288 us, workers (us): 288, ---, [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CMR: Code Cache Roots 131 us, workers (us): 131, ---, [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CMR: VM Strong Roots 24 us, workers (us): 24, ---, [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CMR: CLDG Roots 81 us, workers (us): 81, ---, [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Concurrent Marking 2566 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Pause Final Mark (G) 213 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Pause Final Mark (N) 178 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Finish Mark 44 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Update Region States 38 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Choose Collection Set 29 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Rebuild Free Set 21 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Concurrent Thread Roots 145 us, parallelism: 0.69x [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CTR: <total> 100 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CTR: Thread Roots 100 us, workers (us): 100, ---, [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Concurrent Weak References 69 us, parallelism: 0.12x [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CWRF: <total> 8 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CWRF: Weak References 8 us, workers (us): 8, ---, [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Concurrent Weak Roots 297 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Roots 209 us, parallelism: 0.86x [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CWR: <total> 179 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] CWR: VM Weak Roots 179 us, workers (us): 179, ---, [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Rendezvous 60 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Concurrent Cleanup 43 us [2026-03-05T02:33:33.061Z] [0.380s][info][gc,stats ] Concurrent Strong Roots 331 us, parallelism: 0.86x [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] CSR: <total> 284 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] CSR: Code Cache Roots 105 us, workers (us): 105, ---, [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] CSR: VM Strong Roots 12 us, workers (us): 12, ---, [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] CSR: CLDG Roots 166 us, workers (us): 166, ---, [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Concurrent Evacuation 1206 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Pause Init Update Refs (G) 70 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Pause Init Update Refs (N) 34 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Manage GCLABs 3 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Concurrent Update Refs 1066 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Concurrent Update Thread Roots 127 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Pause Final Update Refs (G) 99 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Pause Final Update Refs (N) 75 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Update Region States 25 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Trash Collection Set 3 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Rebuild Free Set 22 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Concurrent Cleanup 30 us [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] 0 of 10 ms ( 0.0%): <total> [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] 0 of 10 ms ( 0.0%): <average total> [2026-03-05T02:33:33.061Z] [0.381s][info][gc,stats ] [2026-03-05T02:33:33.061Z] [0.381s][info][gc,metaspace] Metaspace: 405K(576K)->406K(576K) NonClass: 370K(448K)->370K(448K) Class: 35K(128K)->35K(128K) [2026-03-05T02:33:33.061Z] [0.381s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.061Z] [0.383s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.061Z] [0.383s][info][gc,ergo ] Free: 1423M, Max: 512K regular, 1423M humongous, Frag: 0% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.061Z] [0.383s][info][gc,start ] GC(3) Concurrent reset [2026-03-05T02:33:33.061Z] [0.383s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.061Z] [0.383s][info][gc,ergo ] GC(3) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.061Z] [0.383s][info][gc ] GC(3) Concurrent reset 0.161ms [2026-03-05T02:33:33.061Z] [0.386s][info][gc,start ] GC(3) Pause Init Mark (unload classes) [2026-03-05T02:33:33.061Z] [0.386s][info][gc,task ] GC(3) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.061Z] [0.386s][info][gc,ergo ] GC(3) Pacer for Mark. Expected Live: 521K, Free: 1423M, Non-Taxable: 142M, Alloc Tax Rate: 0.0x [2026-03-05T02:33:33.062Z] [0.386s][info][gc ] GC(3) Pause Init Mark (unload classes) 0.071ms [2026-03-05T02:33:33.062Z] [0.386s][info][gc,start ] GC(3) Concurrent marking roots [2026-03-05T02:33:33.062Z] [0.386s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.062Z] [0.386s][info][gc ] GC(3) Concurrent marking roots 0.201ms [2026-03-05T02:33:33.062Z] [0.386s][info][gc,start ] GC(3) Concurrent marking (unload classes) [2026-03-05T02:33:33.062Z] [0.386s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.062Z] [0.655s][info][gc ] GC(3) Concurrent marking (unload classes) 268.659ms [2026-03-05T02:33:33.062Z] [0.655s][info][gc,start ] GC(3) Pause Final Mark (unload classes) [2026-03-05T02:33:33.062Z] [0.655s][info][gc,task ] GC(3) Using 2 of 2 workers for final marking [2026-03-05T02:33:33.062Z] [0.655s][info][gc,ergo ] GC(3) Collectable Garbage: 1022K (100%), Immediate: 0B (0%), CSet: 1022K (100%) [2026-03-05T02:33:33.062Z] [0.655s][info][gc,ergo ] GC(3) Pacer for Evacuation. Used CSet: 1921K, Free: 1274M, Non-Taxable: 127M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.062Z] [0.655s][info][gc ] GC(3) Pause Final Mark (unload classes) 0.261ms [2026-03-05T02:33:33.062Z] [0.655s][info][gc,start ] GC(3) Concurrent thread roots [2026-03-05T02:33:33.062Z] [0.655s][info][gc,task ] GC(3) Using 1 of 2 workers for Concurrent thread roots [2026-03-05T02:33:33.062Z] [0.655s][info][gc ] GC(3) Concurrent thread roots 0.229ms [2026-03-05T02:33:33.062Z] [0.655s][info][gc,start ] GC(3) Concurrent weak references [2026-03-05T02:33:33.062Z] [0.655s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent weak references [2026-03-05T02:33:33.062Z] [0.655s][info][gc,ref ] GC(3) Encountered references: Soft: 22, Weak: 53, Final: 0, Phantom: 9 [2026-03-05T02:33:33.062Z] [0.655s][info][gc,ref ] GC(3) Discovered references: Soft: 0, Weak: 2, Final: 0, Phantom: 9 [2026-03-05T02:33:33.062Z] [0.655s][info][gc,ref ] GC(3) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.062Z] [0.655s][info][gc ] GC(3) Concurrent weak references 0.054ms [2026-03-05T02:33:33.062Z] [0.655s][info][gc,start ] GC(3) Concurrent weak roots [2026-03-05T02:33:33.062Z] [0.655s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent weak root [2026-03-05T02:33:33.062Z] [0.656s][info][gc ] GC(3) Concurrent weak roots 0.458ms [2026-03-05T02:33:33.062Z] [0.656s][info][gc,start ] GC(3) Concurrent cleanup [2026-03-05T02:33:33.062Z] [0.656s][info][gc ] GC(3) Concurrent cleanup 146M->146M(1500M) 0.066ms [2026-03-05T02:33:33.062Z] [0.656s][info][gc,ergo ] GC(3) Free: 1273M, Max: 512K regular, 1273M humongous, Frag: 0% external, 0% internal; Reserve: 76733K, Max: 512K [2026-03-05T02:33:33.062Z] [0.656s][info][gc,start ] GC(3) Concurrent class unloading [2026-03-05T02:33:33.062Z] [0.656s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent class unloading [2026-03-05T02:33:33.062Z] [0.657s][info][gc ] GC(3) Concurrent class unloading 0.646ms [2026-03-05T02:33:33.062Z] [0.657s][info][gc,start ] GC(3) Concurrent strong roots [2026-03-05T02:33:33.062Z] [0.657s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent strong root [2026-03-05T02:33:33.062Z] [0.657s][info][gc ] GC(3) Concurrent strong roots 0.162ms [2026-03-05T02:33:33.062Z] [0.657s][info][gc,start ] GC(3) Concurrent evacuation [2026-03-05T02:33:33.062Z] [0.657s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent evacuation [2026-03-05T02:33:33.062Z] [0.658s][info][gc ] GC(3) Concurrent evacuation 1.197ms [2026-03-05T02:33:33.062Z] [0.659s][info][gc,start ] GC(3) Pause Init Update Refs [2026-03-05T02:33:33.062Z] [0.659s][info][gc,ergo ] GC(3) Pacer for Update Refs. Used: 149M, Free: 1271M, Non-Taxable: 127M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.062Z] [0.659s][info][gc ] GC(3) Pause Init Update Refs 0.036ms [2026-03-05T02:33:33.062Z] [0.659s][info][gc,start ] GC(3) Concurrent update references [2026-03-05T02:33:33.062Z] [0.659s][info][gc,task ] GC(3) Using 1 of 2 worke [2026-03-05T02:33:33.062Z] [2026-03-05T02:33:33.062Z] ... [2026-03-05T02:33:33.062Z] Output overflow: [2026-03-05T02:33:33.062Z] JT Harness has limited the test output to the text [2026-03-05T02:33:33.062Z] at the beginning and the end, so that you can see how the [2026-03-05T02:33:33.062Z] test began, and how it completed. [2026-03-05T02:33:33.062Z] [2026-03-05T02:33:33.062Z] If you need to see more of the output from the test, [2026-03-05T02:33:33.062Z] set the system property javatest.maxOutputSize to a higher [2026-03-05T02:33:33.062Z] value. The current value is 100000 [2026-03-05T02:33:33.062Z] ... [2026-03-05T02:33:33.062Z] [2026-03-05T02:33:33.062Z] dle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.062Z] [122.306s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.062Z] [122.306s][info][gc,ergo ] Free: 1386M, Max: 512K regular, 1261M humongous, Frag: 10% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.062Z] [122.306s][info][gc,start ] GC(400) Concurrent reset [2026-03-05T02:33:33.062Z] [122.306s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.062Z] [122.306s][info][gc,ergo ] GC(400) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.062Z] [122.307s][info][gc ] GC(400) Concurrent reset 1.583ms [2026-03-05T02:33:33.062Z] [122.308s][info][gc,start ] GC(400) Pause Init Mark (unload classes) [2026-03-05T02:33:33.062Z] [122.308s][info][gc,task ] GC(400) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.062Z] [122.308s][info][gc,ergo ] GC(400) Pacer for Mark. Expected Live: 330M, Free: 1383M, Non-Taxable: 138M, Alloc Tax Rate: 0.3x [2026-03-05T02:33:33.062Z] [122.308s][info][gc ] GC(400) Pause Init Mark (unload classes) 0.077ms [2026-03-05T02:33:33.062Z] [122.308s][info][gc,start ] GC(400) Concurrent marking roots [2026-03-05T02:33:33.062Z] [122.308s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.062Z] [122.308s][info][gc ] GC(400) Concurrent marking roots 0.200ms [2026-03-05T02:33:33.062Z] [122.308s][info][gc,start ] GC(400) Concurrent marking (unload classes) [2026-03-05T02:33:33.062Z] [122.308s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.062Z] [122.332s][info][gc ] GC(400) Concurrent marking (unload classes) 24.494ms [2026-03-05T02:33:33.062Z] [122.332s][info][gc,start ] GC(400) Pause Final Mark (unload classes) [2026-03-05T02:33:33.062Z] [122.332s][info][gc,task ] GC(400) Using 2 of 2 workers for final marking [2026-03-05T02:33:33.062Z] [122.333s][info][gc,ergo ] GC(400) Collectable Garbage: 178K (100%), Immediate: 0B (0%), CSet: 178K (100%) [2026-03-05T02:33:33.062Z] [122.333s][info][gc,ergo ] GC(400) Pacer for Evacuation. Used CSet: 2806K, Free: 1336M, Non-Taxable: 133M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.062Z] [122.333s][info][gc ] GC(400) Pause Final Mark (unload classes) 0.219ms [2026-03-05T02:33:33.062Z] [122.333s][info][gc,start ] GC(400) Concurrent thread roots [2026-03-05T02:33:33.062Z] [122.333s][info][gc,task ] GC(400) Using 1 of 2 workers for Concurrent thread roots [2026-03-05T02:33:33.062Z] [122.333s][info][gc ] GC(400) Concurrent thread roots 0.156ms [2026-03-05T02:33:33.062Z] [122.333s][info][gc,start ] GC(400) Concurrent weak references [2026-03-05T02:33:33.062Z] [122.333s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent weak references [2026-03-05T02:33:33.062Z] [122.333s][info][gc,ref ] GC(400) Encountered references: Soft: 55, Weak: 172, Final: 0, Phantom: 9 [2026-03-05T02:33:33.062Z] [122.333s][info][gc,ref ] GC(400) Discovered references: Soft: 0, Weak: 74, Final: 0, Phantom: 9 [2026-03-05T02:33:33.062Z] [122.333s][info][gc,ref ] GC(400) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.062Z] [122.333s][info][gc ] GC(400) Concurrent weak references 0.060ms [2026-03-05T02:33:33.062Z] [122.333s][info][gc,start ] GC(400) Concurrent weak roots [2026-03-05T02:33:33.062Z] [122.333s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent weak root [2026-03-05T02:33:33.062Z] [122.334s][info][gc ] GC(400) Concurrent weak roots 0.906ms [2026-03-05T02:33:33.062Z] [122.334s][info][gc,start ] GC(400) Concurrent cleanup [2026-03-05T02:33:33.062Z] [122.334s][info][gc ] GC(400) Concurrent cleanup 87M->88M(1500M) 0.085ms [2026-03-05T02:33:33.062Z] [122.334s][info][gc,ergo ] GC(400) Free: 1331M, Max: 512K regular, 1261M humongous, Frag: 6% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.062Z] [122.334s][info][gc,start ] GC(400) Concurrent class unloading [2026-03-05T02:33:33.062Z] [122.334s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent class unloading [2026-03-05T02:33:33.062Z] [122.335s][info][gc ] GC(400) Concurrent class unloading 0.585ms [2026-03-05T02:33:33.062Z] [122.335s][info][gc,start ] GC(400) Concurrent strong roots [2026-03-05T02:33:33.062Z] [122.335s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent strong root [2026-03-05T02:33:33.062Z] [122.335s][info][gc ] GC(400) Concurrent strong roots 0.054ms [2026-03-05T02:33:33.062Z] [122.335s][info][gc,start ] GC(400) Concurrent evacuation [2026-03-05T02:33:33.062Z] [122.335s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent evacuation [2026-03-05T02:33:33.062Z] [122.337s][info][gc ] GC(400) Concurrent evacuation 2.344ms [2026-03-05T02:33:33.062Z] [122.337s][info][gc,start ] GC(400) Pause Init Update Refs [2026-03-05T02:33:33.062Z] [122.337s][info][gc,ergo ] GC(400) Pacer for Update Refs. Used: 98126K, Free: 1327M, Non-Taxable: 132M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.062Z] [122.337s][info][gc ] GC(400) Pause Init Update Refs 0.029ms [2026-03-05T02:33:33.062Z] [122.337s][info][gc,start ] GC(400) Concurrent update references [2026-03-05T02:33:33.062Z] [122.337s][info][gc,task ] GC(400) Using 1 of 2 workers for concurrent reference update [2026-03-05T02:33:33.062Z] [122.359s][info][gc ] GC(400) Concurrent update references 21.760ms [2026-03-05T02:33:33.062Z] [122.359s][info][gc,start ] GC(400) Concurrent update thread roots [2026-03-05T02:33:33.062Z] [122.359s][info][gc ] GC(400) Concurrent update thread roots 0.212ms [2026-03-05T02:33:33.062Z] [122.359s][info][gc,start ] GC(400) Pause Final Update Refs [2026-03-05T02:33:33.062Z] [122.359s][info][gc,task ] GC(400) Using 2 of 2 workers for final reference update [2026-03-05T02:33:33.062Z] [122.359s][info][gc ] GC(400) Pause Final Update Refs 0.120ms [2026-03-05T02:33:33.062Z] [122.359s][info][gc,start ] GC(400) Concurrent cleanup [2026-03-05T02:33:33.062Z] [122.359s][info][gc ] GC(400) Concurrent cleanup 138M->135M(1500M) 0.044ms [2026-03-05T02:33:33.062Z] [122.359s][info][gc,ergo ] Free: 1284M, Max: 512K regular, 1258M humongous, Frag: 2% external, 1% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.062Z] [122.360s][info][gc,stats ] [2026-03-05T02:33:33.062Z] [122.360s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.062Z] [122.360s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.062Z] [122.360s][info][gc,stats ] [2026-03-05T02:33:33.062Z] [122.360s][info][gc,stats ] Concurrent Reset 1609 us [2026-03-05T02:33:33.062Z] [122.360s][info][gc,stats ] Pause Init Mark (G) 139 us [2026-03-05T02:33:33.062Z] [122.360s][info][gc,stats ] Pause Init Mark (N) 85 us [2026-03-05T02:33:33.062Z] [122.360s][info][gc,stats ] Update Region States 22 us [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] Concurrent Mark Roots 212 us, parallelism: 0.71x [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] CMR: <total> 150 us [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] CMR: Thread Roots 89 us, workers (us): 89, ---, [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] CMR: VM Strong Roots 7 us, workers (us): 7, ---, [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] CMR: CLDG Roots 55 us, workers (us): 55, ---, [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] Concurrent Marking 24533 us [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] Pause Final Mark (G) 305 us [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] Pause Final Mark (N) 227 us [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] Finish Mark 30 us [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] Update Region States 76 us [2026-03-05T02:33:33.063Z] [122.360s][info][gc,stats ] Choose Collection Set 49 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Rebuild Free Set 21 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Thread Roots 169 us, parallelism: 0.59x [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CTR: <total> 100 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CTR: Thread Roots 100 us, workers (us): 100, ---, [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Weak References 66 us, parallelism: 0.12x [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CWRF: <total> 8 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CWRF: Weak References 8 us, workers (us): 8, ---, [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Weak Roots 941 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Roots 304 us, parallelism: 0.91x [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CWR: <total> 276 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CWR: Code Cache Roots 173 us, workers (us): 173, ---, [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CWR: VM Weak Roots 101 us, workers (us): 101, ---, [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CWR: CLDG Roots 3 us, workers (us): 3, ---, [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Rendezvous 586 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Cleanup 101 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Class Unloading 602 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Unlink Stale 495 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] System Dictionary 5 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Weak Class Links 0 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Code Roots 489 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Rendezvous 58 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Purge Unlinked 8 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Code Roots 1 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CLDG 6 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Exception Caches 0 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Strong Roots 60 us, parallelism: 0.36x [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CSR: <total> 22 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CSR: VM Strong Roots 5 us, workers (us): 5, ---, [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] CSR: CLDG Roots 17 us, workers (us): 17, ---, [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Evacuation 2358 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Pause Init Update Refs (G) 98 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Pause Init Update Refs (N) 36 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Manage GCLABs 3 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Update Refs 21816 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Update Thread Roots 221 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Pause Final Update Refs (G) 203 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Pause Final Update Refs (N) 136 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Update Region States 73 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Trash Collection Set 2 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Rebuild Free Set 26 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Concurrent Cleanup 54 us [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] 0 of 56 ms ( 0.0%): <total> [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] 0 of 56 ms ( 0.0%): <average total> [2026-03-05T02:33:33.064Z] [122.360s][info][gc,stats ] [2026-03-05T02:33:33.064Z] [122.360s][info][gc,metaspace ] Metaspace: 899K(1088K)->899K(1088K) NonClass: 812K(896K)->812K(896K) Class: 86K(192K)->86K(192K) [2026-03-05T02:33:33.064Z] [122.360s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.064Z] [122.362s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.064Z] [122.362s][info][gc,ergo ] Free: 1279M, Max: 512K regular, 1258M humongous, Frag: 2% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.064Z] [122.362s][info][gc,start ] GC(401) Concurrent reset [2026-03-05T02:33:33.064Z] [122.362s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.064Z] [122.362s][info][gc,ergo ] GC(401) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.064Z] [122.362s][info][gc ] GC(401) Concurrent reset 0.359ms [2026-03-05T02:33:33.064Z] [122.362s][info][gc,start ] GC(401) Pause Init Mark (unload classes) [2026-03-05T02:33:33.064Z] [122.362s][info][gc,task ] GC(401) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.064Z] [122.362s][info][gc,ergo ] GC(401) Pacer for Mark. Expected Live: 271M, Free: 1279M, Non-Taxable: 127M, Alloc Tax Rate: 0.3x [2026-03-05T02:33:33.064Z] [122.362s][info][gc ] GC(401) Pause Init Mark (unload classes) 0.069ms [2026-03-05T02:33:33.064Z] [122.362s][info][gc,start ] GC(401) Concurrent marking roots [2026-03-05T02:33:33.064Z] [122.362s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.064Z] [122.363s][info][gc ] GC(401) Concurrent marking roots 0.191ms [2026-03-05T02:33:33.064Z] [122.363s][info][gc,start ] GC(401) Concurrent marking (unload classes) [2026-03-05T02:33:33.064Z] [122.363s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.064Z] [122.460s][info][gc ] GC(401) Concurrent marking (unload classes) 97.059ms [2026-03-05T02:33:33.064Z] [122.460s][info][gc,start ] GC(401) Pause Final Mark (unload classes) [2026-03-05T02:33:33.064Z] [122.460s][info][gc,task ] GC(401) Using 2 of 2 workers for final marking [2026-03-05T02:33:33.064Z] [122.460s][info][gc,ergo ] GC(401) Collectable Garbage: 2889K (100%), Immediate: 0B (0%), CSet: 2889K (100%) [2026-03-05T02:33:33.064Z] [122.460s][info][gc,ergo ] GC(401) Pacer for Evacuation. Used CSet: 44032K, Free: 1084M, Non-Taxable: 108M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.064Z] [122.460s][info][gc ] GC(401) Pause Final Mark (unload classes) 0.257ms [2026-03-05T02:33:33.064Z] [122.460s][info][gc,start ] GC(401) Concurrent thread roots [2026-03-05T02:33:33.064Z] [122.460s][info][gc,task ] GC(401) Using 1 of 2 workers for Concurrent thread roots [2026-03-05T02:33:33.064Z] [122.460s][info][gc ] GC(401) Concurrent thread roots 0.140ms [2026-03-05T02:33:33.064Z] [122.460s][info][gc,start ] GC(401) Concurrent weak references [2026-03-05T02:33:33.064Z] [122.460s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent weak references [2026-03-05T02:33:33.064Z] [122.460s][info][gc,ref ] GC(401) Encountered references: Soft: 55, Weak: 172, Final: 0, Phantom: 9 [2026-03-05T02:33:33.064Z] [122.460s][info][gc,ref ] GC(401) Discovered references: Soft: 0, Weak: 74, Final: 0, Phantom: 9 [2026-03-05T02:33:33.064Z] [122.460s][info][gc,ref ] GC(401) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.064Z] [122.460s][info][gc ] GC(401) Concurrent weak references 0.048ms [2026-03-05T02:33:33.064Z] [122.460s][info][gc,start ] GC(401) Concurrent weak roots [2026-03-05T02:33:33.064Z] [122.460s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent weak root [2026-03-05T02:33:33.064Z] [122.461s][info][gc ] GC(401) Concurrent weak roots 0.368ms [2026-03-05T02:33:33.064Z] [122.461s][info][gc,start ] GC(401) Concurrent cleanup [2026-03-05T02:33:33.064Z] [122.461s][info][gc ] GC(401) Concurrent cleanup 331M->331M(1500M) 0.069ms [2026-03-05T02:33:33.064Z] [122.461s][info][gc,ergo ] GC(401) Free: 1084M, Max: 512K regular, 1082M humongous, Frag: 0% external, 3% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.064Z] [122.461s][info][gc,start ] GC(401) Concurrent class unloading [2026-03-05T02:33:33.064Z] [122.461s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent class unloading [2026-03-05T02:33:33.064Z] [122.461s][info][gc ] GC(401) Concurrent class unloading 0.587ms [2026-03-05T02:33:33.064Z] [122.461s][info][gc,start ] GC(401) Concurrent strong roots [2026-03-05T02:33:33.064Z] [122.461s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent strong root [2026-03-05T02:33:33.064Z] [122.461s][info][gc ] GC(401) Concurrent strong roots 0.052ms [2026-03-05T02:33:33.064Z] [122.461s][info][gc,start ] GC(401) Concurrent evacuation [2026-03-05T02:33:33.064Z] [122.461s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent evacuation [2026-03-05T02:33:33.064Z] [122.509s][info][gc ] GC(401) Concurrent evacuation 47.219ms [2026-03-05T02:33:33.064Z] [122.509s][info][gc,start ] GC(401) Pause Init Update Refs [2026-03-05T02:33:33.064Z] [122.509s][info][gc,ergo ] GC(401) Pacer for Update Refs. Used: 463M, Free: 990M, Non-Taxable: 101376K, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.064Z] [122.509s][info][gc ] GC(401) Pause Init Update Refs 0.030ms [2026-03-05T02:33:33.064Z] [122.509s][info][gc,start ] GC(401) Concurrent update references [2026-03-05T02:33:33.064Z] [122.509s][info][gc,task ] GC(401) Using 1 of 2 workers for concurrent reference update [2026-03-05T02:33:33.064Z] [122.606s][info][gc ] GC(401) Concurrent update references 97.102ms [2026-03-05T02:33:33.064Z] [122.606s][info][gc,start ] GC(401) Concurrent update thread roots [2026-03-05T02:33:33.064Z] [122.606s][info][gc ] GC(401) Concurrent update thread roots 0.196ms [2026-03-05T02:33:33.064Z] [122.606s][info][gc,start ] GC(401) Pause Final Update Refs [2026-03-05T02:33:33.064Z] [122.606s][info][gc,task ] GC(401) Using 2 of 2 workers for final reference update [2026-03-05T02:33:33.064Z] [122.606s][info][gc ] GC(401) Pause Final Update Refs 0.130ms [2026-03-05T02:33:33.064Z] [122.606s][info][gc,start ] GC(401) Concurrent cleanup [2026-03-05T02:33:33.064Z] [122.607s][info][gc ] GC(401) Concurrent cleanup 650M->607M(1500M) 0.044ms [2026-03-05T02:33:33.064Z] [122.607s][info][gc,ergo ] Free: 800M, Max: 512K regular, 757M humongous, Frag: 6% external, 8% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Concurrent Reset 386 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Pause Init Mark (G) 138 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Pause Init Mark (N) 76 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Update Region States 24 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Concurrent Mark Roots 203 us, parallelism: 0.70x [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CMR: <total> 141 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CMR: Thread Roots 81 us, workers (us): 81, ---, [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CMR: VM Strong Roots 7 us, workers (us): 7, ---, [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CMR: CLDG Roots 54 us, workers (us): 54, ---, [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Concurrent Marking 97100 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Pause Final Mark (G) 329 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Pause Final Mark (N) 265 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Finish Mark 33 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Update Region States 79 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Choose Collection Set 60 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Rebuild Free Set 20 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Concurrent Thread Roots 147 us, parallelism: 0.68x [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CTR: <total> 100 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CTR: Thread Roots 100 us, workers (us): 100, ---, [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Concurrent Weak References 53 us, parallelism: 0.16x [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CWRF: <total> 9 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CWRF: Weak References 9 us, workers (us): 9, ---, [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Concurrent Weak Roots 384 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Roots 303 us, parallelism: 0.92x [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CWR: <total> 278 us [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CWR: Code Cache Roots 174 us, workers (us): 174, ---, [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CWR: VM Weak Roots 103 us, workers (us): 103, ---, [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] CWR: CLDG Roots 2 us, workers (us): 2, ---, [2026-03-05T02:33:33.064Z] [122.607s][info][gc,stats ] Rendezvous 53 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Concurrent Cleanup 76 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Concurrent Class Unloading 602 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Unlink Stale 519 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] System Dictionary 5 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Weak Class Links 0 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Code Roots 512 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Rendezvous 48 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Purge Unlinked 5 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Code Roots 1 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] CLDG 4 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Exception Caches 0 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Concurrent Strong Roots 62 us, parallelism: 0.31x [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] CSR: <total> 19 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] CSR: VM Strong Roots 4 us, workers (us): 4, ---, [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] CSR: CLDG Roots 15 us, workers (us): 15, ---, [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Concurrent Evacuation 47263 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Pause Init Update Refs (G) 188 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Pause Init Update Refs (N) 37 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Manage GCLABs 5 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Concurrent Update Refs 97157 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Concurrent Update Thread Roots 217 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Pause Final Update Refs (G) 195 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Pause Final Update Refs (N) 143 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Update Region States 74 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Trash Collection Set 5 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Rebuild Free Set 23 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Concurrent Cleanup 60 us [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] 0 of 247 ms ( 0.0%): <total> [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] 0 of 247 ms ( 0.0%): <average total> [2026-03-05T02:33:33.065Z] [122.607s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.607s][info][gc,metaspace ] Metaspace: 899K(1088K)->899K(1088K) NonClass: 812K(896K)->812K(896K) Class: 86K(192K)->86K(192K) [2026-03-05T02:33:33.065Z] [122.607s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.065Z] [122.609s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.065Z] [122.609s][info][gc,ergo ] Free: 793M, Max: 512K regular, 757M humongous, Frag: 5% external, 8% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.065Z] [122.609s][info][gc,start ] GC(402) Concurrent reset [2026-03-05T02:33:33.065Z] [122.609s][info][gc,task ] GC(402) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.065Z] [122.609s][info][gc,ergo ] GC(402) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.065Z] [122.610s][info][gc ] GC(402) Concurrent reset 0.933ms [2026-03-05T02:33:33.065Z] [122.610s][info][gc,start ] GC(402) Pause Init Mark (unload classes) [2026-03-05T02:33:33.065Z] [122.610s][info][gc,task ] GC(402) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.065Z] [122.610s][info][gc,ergo ] GC(402) Pacer for Mark. Expected Live: 292M, Free: 793M, Non-Taxable: 81205K, Alloc Tax Rate: 0.5x [2026-03-05T02:33:33.065Z] [122.610s][info][gc ] GC(402) Pause Init Mark (unload classes) 0.062ms [2026-03-05T02:33:33.065Z] [122.610s][info][gc,start ] GC(402) Concurrent marking roots [2026-03-05T02:33:33.065Z] [122.610s][info][gc,task ] GC(402) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.065Z] [122.610s][info][gc ] GC(402) Concurrent marking roots 0.200ms [2026-03-05T02:33:33.065Z] [122.610s][info][gc,start ] GC(402) Concurrent marking (unload classes) [2026-03-05T02:33:33.065Z] [122.610s][info][gc,task ] GC(402) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.065Z] [122.726s][info][gc ] Cancelling GC: Stopping VM [2026-03-05T02:33:33.065Z] [122.726s][info][gc ] GC(402) Concurrent marking (unload classes) 115.557ms [2026-03-05T02:33:33.065Z] [122.726s][info][gc,ergo ] Free: 573M, Max: 512K regular, 573M humongous, Frag: 0% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] Concurrent Reset 963 us [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] Pause Init Mark (G) 115 us [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] Pause Init Mark (N) 68 us [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] Update Region States 17 us [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] Concurrent Mark Roots 213 us, parallelism: 0.72x [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] CMR: <total> 154 us [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] CMR: Thread Roots 89 us, workers (us): 89, ---, [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] CMR: VM Strong Roots 7 us, workers (us): 7, ---, [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] CMR: CLDG Roots 58 us, workers (us): 58, ---, [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] 0 of 119 ms ( 0.0%): <total> [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] 0 of 119 ms ( 0.0%): <average total> [2026-03-05T02:33:33.065Z] [122.726s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.726s][info][gc,metaspace ] Metaspace: 899K(1088K)->1009K(1216K) NonClass: 812K(896K)->910K(1024K) Class: 86K(192K)->99K(192K) [2026-03-05T02:33:33.065Z] [122.726s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] Heap [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] Shenandoah Heap [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] 1500M max, 1500M soft max, 1500M committed, 824M used [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] 3000 x 512K regions [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] Status: marking, cancelled [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] Reserved region: [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] - [0x00000000a2400000, 0x0000000100000000) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] Collection set: [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] - map (vanilla): 0x0000000000011448 [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] - map (biased): 0x0000000000010000 [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] Metaspace used 1009K, committed 1216K, reserved 1114112K [2026-03-05T02:33:33.065Z] [122.728s][info][gc,heap,exit ] class space used 99K, committed 192K, reserved 1048576K [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] GC STATISTICS: [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] "(G)" (gross) pauses include VM time: time to notify and block threads, do the pre- [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] and post-safepoint housekeeping. Use -Xlog:safepoint+stats to dissect. [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] "(N)" (net) pauses are the times spent in the actual GC code. [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] "a" is average time for each phase, look at levels to see if average makes sense. [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] "lvls" are quantiles: 0% (minimum), 25%, 50% (median), 75%, 100% (maximum). [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Pacing delays are measured from entering the pacing code till exiting it. Therefore, [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] observed pacing delays may be higher than the threshold when paced thread spent more [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] time in the pacing code. It usually happens when thread is de-scheduled while paced, [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] OS takes longer to unblock the thread, or JVM experiences an STW pause. [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Higher delay would prevent application outpacing the GC, but it will hide the GC latencies [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] from the STW pause times. Pacing affects the individual threads, and so it would also be [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] invisible to the usual profiling tools, but would add up to end-to-end application latency. [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Raise max pacing delay with care. [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Concurrent Reset = 0.409 s (a = 1166 us) (n = 351) (lvls, us = 166, 426, 752, 1660, 6836) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Pause Init Mark (G) = 0.406 s (a = 1157 us) (n = 351) (lvls, us = 86, 131, 152, 209, 163810) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Pause Init Mark (N) = 0.032 s (a = 92 us) (n = 351) (lvls, us = 47, 67, 78, 92, 3126) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Update Region States = 0.011 s (a = 31 us) (n = 351) (lvls, us = 13, 20, 25, 36, 101) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Concurrent Mark Roots = 0.123 s (a = 350 us) (n = 351) (lvls, us = 152, 219, 404, 461, 708) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CMR: <total> = 0.097 s (a = 275 us) (n = 351) (lvls, us = 46, 152, 344, 385, 602) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CMR: Thread Roots = 0.035 s (a = 100 us) (n = 351) (lvls, us = 11, 87, 97, 107, 311) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CMR: Code Cache Roots = 0.040 s (a = 228 us) (n = 177) (lvls, us = 0, 219, 225, 230, 335) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CMR: VM Strong Roots = 0.003 s (a = 7 us) (n = 351) (lvls, us = 5, 6, 7, 7, 24) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CMR: CLDG Roots = 0.019 s (a = 54 us) (n = 351) (lvls, us = 27, 49, 52, 56, 88) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Concurrent Marking = 56.222 s (a = 160634 us) (n = 350) (lvls, us = 1426, 27930, 92188, 275391, 650908) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Pause Final Mark (G) = 0.111 s (a = 349 us) (n = 317) (lvls, us = 193, 287, 316, 367, 3198) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Pause Final Mark (N) = 0.078 s (a = 245 us) (n = 317) (lvls, us = 164, 227, 240, 252, 485) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Finish Mark = 3.413 s (a = 9752 us) (n = 350) (lvls, us = 19, 34, 37, 41, 206402) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Update Region States = 0.023 s (a = 74 us) (n = 317) (lvls, us = 26, 68, 74, 78, 136) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Choose Collection Set = 0.018 s (a = 56 us) (n = 317) (lvls, us = 27, 47, 54, 61, 146) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Rebuild Free Set = 0.007 s (a = 21 us) (n = 317) (lvls, us = 14, 19, 20, 22, 93) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Concurrent Thread Roots = 0.058 s (a = 184 us) (n = 316) (lvls, us = 133, 164, 180, 197, 276) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CTR: <total> = 0.039 s (a = 125 us) (n = 316) (lvls, us = 80, 109, 121, 137, 208) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CTR: Thread Roots = 0.039 s (a = 125 us) (n = 316) (lvls, us = 80, 109, 121, 137, 208) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Concurrent Weak References = 0.173 s (a = 545 us) (n = 317) (lvls, us = 51, 62, 68, 73, 100707) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CWRF: <total> = 0.007 s (a = 23 us) (n = 317) (lvls, us = 1, 8, 12, 14, 3465) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CWRF: Weak References = 0.007 s (a = 23 us) (n = 317) (lvls, us = 1, 8, 12, 14, 3465) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Concurrent Weak Roots = 0.976 s (a = 3080 us) (n = 317) (lvls, us = 176, 293, 451, 967, 273432) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] Roots = 0.084 s (a = 266 us) (n = 317) (lvls, us = 113, 188, 285, 361, 935) [2026-03-05T02:33:33.065Z] [122.728s][info][gc,stats ] CWR: <total> = 0.076 s (a = 238 us) (n = 317) (lvls, us = 90, 164, 260, 336, 906) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CWR: Code Cache Roots = 0.028 s (a = 177 us) (n = 155) (lvls, us = 19, 168, 172, 178, 274) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CWR: VM Weak Roots = 0.048 s (a = 150 us) (n = 317) (lvls, us = 84, 107, 162, 176, 906) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CWR: CLDG Roots = 0.000 s (a = 3 us) (n = 155) (lvls, us = 1, 2, 3, 3, 9) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Rendezvous = 0.653 s (a = 2059 us) (n = 317) (lvls, us = 44, 56, 65, 596, 273225) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Concurrent Cleanup = 0.030 s (a = 95 us) (n = 317) (lvls, us = 35, 69, 74, 88, 279) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Concurrent Class Unloading = 0.181 s (a = 1169 us) (n = 155) (lvls, us = 342, 590, 631, 773, 10889) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Unlink Stale = 0.081 s (a = 522 us) (n = 155) (lvls, us = 217, 484, 518, 545, 1150) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] System Dictionary = 0.001 s (a = 5 us) (n = 155) (lvls, us = 3, 4, 5, 6, 9) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Weak Class Links = 0.000 s (a = 0 us) (n = 155) (lvls, us = 0, 0, 0, 0, 1) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Code Roots = 0.080 s (a = 515 us) (n = 155) (lvls, us = 211, 479, 512, 533, 1142) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Rendezvous = 0.093 s (a = 602 us) (n = 155) (lvls, us = 35, 49, 57, 123, 10278) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Purge Unlinked = 0.001 s (a = 8 us) (n = 155) (lvls, us = 2, 6, 6, 7, 48) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Code Roots = 0.000 s (a = 2 us) (n = 155) (lvls, us = 0, 1, 1, 1, 40) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CLDG = 0.001 s (a = 5 us) (n = 155) (lvls, us = 1, 4, 5, 6, 18) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Exception Caches = 0.000 s (a = 0 us) (n = 155) (lvls, us = 0, 0, 0, 0, 1) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Concurrent Strong Roots = 0.088 s (a = 279 us) (n = 316) (lvls, us = 52, 152, 314, 422, 675) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CSR: <total> = 0.071 s (a = 226 us) (n = 316) (lvls, us = 19, 109, 264, 367, 589) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CSR: Code Cache Roots = 0.043 s (a = 265 us) (n = 162) (lvls, us = 83, 252, 256, 264, 393) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CSR: VM Strong Roots = 0.003 s (a = 10 us) (n = 316) (lvls, us = 3, 5, 11, 14, 37) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CSR: CLDG Roots = 0.025 s (a = 80 us) (n = 316) (lvls, us = 14, 19, 98, 107, 250) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Concurrent Evacuation = 20.361 s (a = 64433 us) (n = 316) (lvls, us = 281, 11523, 31836, 107422, 471364) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pause Final Roots (G) = 0.000 s (a = 72 us) (n = 1) (lvls, us = 72, 72, 72, 72, 72) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pause Final Roots (N) = 0.000 s (a = 33 us) (n = 1) (lvls, us = 32, 32, 32, 32, 33) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pause Init Update Refs (G) = 0.065 s (a = 217 us) (n = 301) (lvls, us = 67, 98, 121, 160, 5403) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pause Init Update Refs (N) = 0.014 s (a = 47 us) (n = 301) (lvls, us = 28, 38, 43, 54, 102) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Manage GCLABs = 0.001 s (a = 4 us) (n = 301) (lvls, us = 2, 4, 4, 5, 17) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Concurrent Update Refs = 29.688 s (a = 98632 us) (n = 301) (lvls, us = 479, 19141, 59961, 148438, 355793) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Concurrent Update Thread Roots = 0.947 s (a = 3189 us) (n = 297) (lvls, us = 125, 184, 203, 244, 325507) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pause Final Update Refs (G) = 0.066 s (a = 222 us) (n = 297) (lvls, us = 98, 180, 203, 234, 1203) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pause Final Update Refs (N) = 0.043 s (a = 144 us) (n = 297) (lvls, us = 72, 131, 141, 148, 483) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Update Region States = 0.021 s (a = 71 us) (n = 297) (lvls, us = 24, 65, 71, 75, 147) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Trash Collection Set = 0.001 s (a = 5 us) (n = 297) (lvls, us = 2, 3, 4, 6, 38) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Rebuild Free Set = 0.007 s (a = 25 us) (n = 297) (lvls, us = 16, 21, 23, 25, 81) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Concurrent Cleanup = 0.020 s (a = 69 us) (n = 297) (lvls, us = 26, 54, 66, 78, 161) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pause Degenerated GC (G) = 7.761 s (a = 149241 us) (n = 52) (lvls, us = 5039, 77148, 138672, 201172, 436484) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pause Degenerated GC (N) = 7.756 s (a = 149160 us) (n = 52) (lvls, us = 4961, 77148, 138672, 201172, 436390) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] System Purge = 0.011 s (a = 346 us) (n = 33) (lvls, us = 100, 143, 438, 504, 607) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Weak References = 0.003 s (a = 90 us) (n = 33) (lvls, us = 51, 86, 91, 97, 110) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] WRP: <total> = 0.000 s (a = 8 us) (n = 33) (lvls, us = 4, 7, 7, 8, 11) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] WRP: Weak References = 0.000 s (a = 8 us) (n = 33) (lvls, us = 4, 7, 7, 8, 11) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Unload Classes = 0.006 s (a = 358 us) (n = 18) (lvls, us = 309, 322, 348, 363, 425) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DCU: <total> = 0.011 s (a = 621 us) (n = 18) (lvls, us = 557, 580, 605, 615, 729) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DCU: Unload Code Caches = 0.011 s (a = 621 us) (n = 18) (lvls, us = 557, 580, 605, 615, 729) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Weak Roots = 0.002 s (a = 52 us) (n = 33) (lvls, us = 46, 49, 51, 54, 61) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DWR: <total> = 0.002 s (a = 53 us) (n = 33) (lvls, us = 45, 50, 53, 53, 60) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DWR: VM Weak Roots = 0.002 s (a = 53 us) (n = 33) (lvls, us = 45, 50, 53, 53, 60) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] CLDG = 0.000 s (a = 2 us) (n = 18) (lvls, us = 1, 1, 2, 2, 2) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Update Region States = 0.002 s (a = 70 us) (n = 33) (lvls, us = 62, 65, 66, 68, 141) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Manage GC/TLABs = 0.000 s (a = 8 us) (n = 33) (lvls, us = 5, 7, 8, 8, 12) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Choose Collection Set = 0.002 s (a = 61 us) (n = 33) (lvls, us = 42, 51, 57, 62, 140) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Rebuild Free Set = 0.001 s (a = 19 us) (n = 33) (lvls, us = 15, 16, 17, 19, 60) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Evacuation = 1.984 s (a = 41326 us) (n = 48) (lvls, us = 15039, 36523, 38672, 41406, 147562) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Manage GCLABs = 0.000 s (a = 13 us) (n = 2) (lvls, us = 7, 7, 7, 7, 19) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Update References = 0.504 s (a = 84079 us) (n = 6) (lvls, us = 4141, 4141, 62500, 136719, 145580) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Update Region States = 0.000 s (a = 77 us) (n = 6) (lvls, us = 62, 62, 65, 65, 132) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Trash Collection Set = 0.000 s (a = 11 us) (n = 6) (lvls, us = 7, 7, 9, 10, 23) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Rebuild Free Set = 0.000 s (a = 29 us) (n = 6) (lvls, us = 19, 19, 23, 27, 51) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Degen Update Roots = 0.003 s (a = 478 us) (n = 6) (lvls, us = 422, 422, 465, 477, 566) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DU: <total> = 0.004 s (a = 707 us) (n = 6) (lvls, us = 650, 650, 680, 688, 772) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DU: Thread Roots = 0.001 s (a = 99 us) (n = 6) (lvls, us = 89, 89, 92, 92, 138) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DU: Code Cache Roots = 0.003 s (a = 483 us) (n = 6) (lvls, us = 441, 441, 455, 482, 554) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DU: VM Strong Roots = 0.000 s (a = 6 us) (n = 6) (lvls, us = 4, 4, 5, 5, 8) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DU: VM Weak Roots = 0.001 s (a = 93 us) (n = 6) (lvls, us = 83, 83, 93, 94, 102) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] DU: CLDG Roots = 0.000 s (a = 26 us) (n = 6) (lvls, us = 16, 16, 18, 18, 43) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Cleanup = 0.000 s (a = 68 us) (n = 6) (lvls, us = 43, 43, 54, 71, 98) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Pre Heap Dump = 0.000 s (a = 1 us) (n = 46) (lvls, us = 1, 1, 1, 1, 3) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Prepare = 0.136 s (a = 2950 us) (n = 46) (lvls, us = 1934, 2422, 2832, 3164, 4915) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Update Roots = 0.021 s (a = 453 us) (n = 46) (lvls, us = 410, 430, 441, 455, 616) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] FU: <total> = 0.032 s (a = 687 us) (n = 46) (lvls, us = 633, 652, 672, 701, 813) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] FU: Thread Roots = 0.004 s (a = 96 us) (n = 46) (lvls, us = 86, 91, 93, 96, 129) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] FU: Code Cache Roots = 0.022 s (a = 468 us) (n = 46) (lvls, us = 432, 449, 459, 469, 592) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] FU: VM Strong Roots = 0.000 s (a = 5 us) (n = 46) (lvls, us = 3, 4, 5, 7, 9) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] FU: VM Weak Roots = 0.004 s (a = 86 us) (n = 46) (lvls, us = 55, 79, 85, 92, 124) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] FU: CLDG Roots = 0.001 s (a = 31 us) (n = 46) (lvls, us = 14, 17, 25, 43, 61) [2026-03-05T02:33:33.065Z] [122.729s][info][gc,stats ] Mark = 0.642 s (a = 13961 us) (n = 46) (lvls, us = 11133, 11914, 12109, 12891, 41514) [2026-03-05T02:33:33.066Z] [122.729s][info][gc,stats ] FM: <total> = 1.214 s (a = 26393 us) (n = 46) (lvls, us = 21094, 22461, 22852, 23828, 81246) [2026-03-05T02:33:33.066Z] [122.729s][info][gc,stats ] FM: Thread Roots = 0.005 s (a = 110 us) (n = 46) (lvls, us = 98, 104, 107, 113, 130) [2026-03-05T02:33:33.066Z] [122.729s][info][gc,stats ] FM: VM Strong Roots = 0.000 s (a = 5 us) (n = 46) (lvls, us = 4, 4, 4, 5, 7) [2026-03-05T02:33:33.066Z] [122.729s][info][gc,stats ] FM: CLDG Roots = 0.002 s (a = 46 us) (n = 46) (lvls, us = 38, 43, 45, 48, 63) [2026-03-05T02:33:33.066Z] [122.729s][info][gc,stats ] FM: Parallel Mark = 1.207 s (a = 26231 us) (n = 46) (lvls, us = 21094, 22461, 22852, 23633, 81079) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] System Purge = 0.023 s (a = 499 us) (n = 46) (lvls, us = 406, 438, 459, 496, 1580) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Weak References = 0.004 s (a = 84 us) (n = 46) (lvls, us = 62, 73, 79, 91, 149) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] WRP: <total> = 0.001 s (a = 27 us) (n = 46) (lvls, us = 19, 23, 26, 29, 51) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] WRP: Weak References = 0.001 s (a = 27 us) (n = 46) (lvls, us = 19, 23, 26, 29, 51) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Unload Classes = 0.016 s (a = 342 us) (n = 46) (lvls, us = 262, 291, 312, 336, 1292) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] CU: <total> = 0.027 s (a = 581 us) (n = 46) (lvls, us = 477, 510, 527, 561, 2226) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] CU: Unload Code Caches = 0.025 s (a = 548 us) (n = 46) (lvls, us = 477, 510, 527, 561, 706) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] CU: Unlink CLDs = 0.002 s (a = 1527 us) (n = 1) (lvls, us = 1523, 1523, 1523, 1523, 1527) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Weak Roots = 0.003 s (a = 56 us) (n = 46) (lvls, us = 47, 50, 52, 55, 150) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] WR: <total> = 0.003 s (a = 60 us) (n = 46) (lvls, us = 45, 56, 57, 61, 98) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] WR: VM Weak Roots = 0.003 s (a = 60 us) (n = 46) (lvls, us = 45, 56, 57, 61, 98) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] CLDG = 0.000 s (a = 3 us) (n = 46) (lvls, us = 1, 1, 2, 2, 50) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Calculate Addresses = 0.242 s (a = 5254 us) (n = 46) (lvls, us = 4102, 4609, 4844, 5195, 14714) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Regular Objects = 0.221 s (a = 4799 us) (n = 46) (lvls, us = 3691, 4180, 4414, 4746, 14119) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Humongous Objects = 0.002 s (a = 41 us) (n = 46) (lvls, us = 21, 33, 37, 45, 85) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Adjust Pointers = 0.294 s (a = 6385 us) (n = 46) (lvls, us = 5020, 5391, 5664, 5957, 21006) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] FA: <total> = 0.028 s (a = 608 us) (n = 46) (lvls, us = 531, 570, 590, 621, 828) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] FA: Thread Roots = 0.004 s (a = 86 us) (n = 46) (lvls, us = 71, 79, 86, 91, 116) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] FA: Code Cache Roots = 0.019 s (a = 420 us) (n = 46) (lvls, us = 365, 391, 406, 424, 586) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] FA: VM Strong Roots = 0.000 s (a = 5 us) (n = 46) (lvls, us = 3, 5, 5, 6, 10) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] FA: VM Weak Roots = 0.003 s (a = 67 us) (n = 46) (lvls, us = 53, 63, 65, 69, 87) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] FA: CLDG Roots = 0.001 s (a = 30 us) (n = 46) (lvls, us = 19, 26, 31, 34, 42) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Copy Objects = 0.463 s (a = 10074 us) (n = 46) (lvls, us = 8301, 8809, 9121, 9551, 32375) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Regular Objects = 0.243 s (a = 5274 us) (n = 46) (lvls, us = 4102, 4434, 4727, 5195, 17576) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Humongous Objects = 0.205 s (a = 4462 us) (n = 46) (lvls, us = 3594, 3945, 4102, 4238, 14205) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Reset Complete Bitmap = 0.010 s (a = 227 us) (n = 46) (lvls, us = 172, 188, 221, 240, 477) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Rebuild Region Sets = 0.005 s (a = 109 us) (n = 46) (lvls, us = 79, 97, 102, 111, 193) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Post Heap Dump = 0.000 s (a = 2 us) (n = 46) (lvls, us = 1, 1, 1, 2, 6) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Pacing = 22.711 s (a = 56495 us) (n = 402) (lvls, us = 0, 0, 0, 55273, 454478) [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] Under allocation pressure, concurrent cycles may cancel, and either continue cycle [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] under stop-the-world pause or result in stop-the-world Full GC. Increase heap size, [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] tune GC heuristics, set more aggressive pacing delay, or lower allocation rate [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] to avoid Degenerated and Full GC cycles. [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 298 successful concurrent GCs [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 0 invoked explicitly [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 0 invoked implicitly [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 52 Degenerated GCs [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 52 caused by allocation failure [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 33 happened at Mark [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 15 happened at Evacuation [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 4 happened at Update References [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 46 upgraded to Full GC [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 46 Full GCs [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 0 invoked explicitly [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 0 invoked implicitly [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 0 caused by allocation failure [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] 46 upgraded from Degenerated GC [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] [2026-03-05T02:33:33.066Z] [122.730s][info][gc,stats ] [2026-03-05T02:33:33.066Z] STDERR: [2026-03-05T02:33:33.066Z] STATUS:Passed. [2026-03-05T02:33:33.066Z] rerun: [2026-03-05T02:33:33.066Z] cd 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\scratch\0' && \ [2026-03-05T02:33:33.066Z] PATH='C:\cygwin64\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0;C:\Windows\System32\OpenSSH;C:\Program Files\dotnet;C:\Users\adoptopenjdk\AppData\Local\Microsoft\WindowsApps;C:\Strawberry\perl\bin;C:\Program Files\Git\cmd;C:\openjdk\jdk-8\bin;C:\apache-ant\apache-ant-1.10.15\bin;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps;c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2026-03-05T02:33:33.066Z] SystemDrive=C: \ [2026-03-05T02:33:33.066Z] SystemRoot='C:\Windows' \ [2026-03-05T02:33:33.066Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-03-05T02:33:33.066Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-03-05T02:33:33.066Z] windir='C:\Windows' \ [2026-03-05T02:33:33.066Z] CLASSPATH='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jvmtest\openjdk\jtreg\lib\javatest.jar;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jvmtest\openjdk\jtreg\lib\jtreg.jar' \ [2026-03-05T02:33:33.066Z] 'c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image\bin\java' \ [2026-03-05T02:33:33.066Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2026-03-05T02:33:33.066Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2026-03-05T02:33:33.066Z] -Dtest.compiler.opts= \ [2026-03-05T02:33:33.066Z] -Dtest.java.opts= \ [2026-03-05T02:33:33.066Z] -Dtest.jdk='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image' \ [2026-03-05T02:33:33.066Z] -Dcompile.jdk='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image' \ [2026-03-05T02:33:33.066Z] -Dtest.timeout.factor=8.0 \ [2026-03-05T02:33:33.066Z] -Dtest.nativepath='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2026-03-05T02:33:33.066Z] -Dtest.root='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2026-03-05T02:33:33.066Z] -Dtest.name=gc/stress/gclocker/TestGCLockerWithShenandoah.java#aggressive \ [2026-03-05T02:33:33.066Z] -Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2026-03-05T02:33:33.066Z] -Dtest.file='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker\TestGCLockerWithShenandoah.java' \ [2026-03-05T02:33:33.066Z] -Dtest.main.class=gc.stress.gclocker.TestGCLockerWithShenandoah \ [2026-03-05T02:33:33.066Z] -Dtest.src='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker' \ [2026-03-05T02:33:33.066Z] -Dtest.src.path='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2026-03-05T02:33:33.066Z] -Dtest.classes='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d' \ [2026-03-05T02:33:33.066Z] -Dtest.class.path='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0' \ [2026-03-05T02:33:33.066Z] -Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0' \ [2026-03-05T02:33:33.066Z] -ea \ [2026-03-05T02:33:33.066Z] -esa \ [2026-03-05T02:33:33.066Z] -Xmx512m \ [2026-03-05T02:33:33.066Z] '-Djava.library.path=c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2026-03-05T02:33:33.066Z] -Xlog:gc*=info \ [2026-03-05T02:33:33.066Z] -Xms1500m \ [2026-03-05T02:33:33.066Z] -Xmx1500m \ [2026-03-05T02:33:33.066Z] -XX:+UnlockExperimentalVMOptions \ [2026-03-05T02:33:33.066Z] -XX:+UnlockDiagnosticVMOptions \ [2026-03-05T02:33:33.066Z] -XX:+UseShenandoahGC \ [2026-03-05T02:33:33.066Z] -XX:ShenandoahGCHeuristics=aggressive \ [2026-03-05T02:33:33.066Z] -XX:+ShenandoahOOMDuringEvacALot \ [2026-03-05T02:33:33.066Z] com.sun.javatest.regtest.agent.MainWrapper 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d\main.0.jta' [2026-03-05T02:33:33.066Z] [2026-03-05T02:33:33.066Z] ACTION: build -- Passed. All files up to date [2026-03-05T02:33:33.066Z] REASON: Named class compiled on demand [2026-03-05T02:33:33.066Z] TIME: 0.001 seconds [2026-03-05T02:33:33.066Z] messages: [2026-03-05T02:33:33.066Z] command: build gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.066Z] reason: Named class compiled on demand [2026-03-05T02:33:33.066Z] started: Thu Mar 05 02:33:22 UTC 2026 [2026-03-05T02:33:33.066Z] finished: Thu Mar 05 02:33:22 UTC 2026 [2026-03-05T02:33:33.066Z] elapsed time (seconds): 0.001 [2026-03-05T02:33:33.066Z] [2026-03-05T02:33:33.066Z] ACTION: main -- Failed. Execution failed: `main' threw exception: java.lang.OutOfMemoryError: Java heap space [2026-03-05T02:33:33.066Z] REASON: User specified action: run main/native/othervm/timeout=200 -Xlog:gc*=info -Xms1500m -Xmx1500m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=aggressive -XX:+ShenandoahAllocFailureALot gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.066Z] TIME: 7.802 seconds [2026-03-05T02:33:33.066Z] messages: [2026-03-05T02:33:33.066Z] command: main -Xlog:gc*=info -Xms1500m -Xmx1500m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=aggressive -XX:+ShenandoahAllocFailureALot gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.066Z] reason: User specified action: run main/native/othervm/timeout=200 -Xlog:gc*=info -Xms1500m -Xmx1500m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=aggressive -XX:+ShenandoahAllocFailureALot gc.stress.gclocker.TestGCLockerWithShenandoah [2026-03-05T02:33:33.066Z] started: Thu Mar 05 02:33:22 UTC 2026 [2026-03-05T02:33:33.066Z] Mode: othervm [/othervm specified] [2026-03-05T02:33:33.066Z] Process id: 31012 [2026-03-05T02:33:33.066Z] finished: Thu Mar 05 02:33:30 UTC 2026 [2026-03-05T02:33:33.066Z] elapsed time (seconds): 7.802 [2026-03-05T02:33:33.066Z] configuration: [2026-03-05T02:33:33.066Z] STDOUT: [2026-03-05T02:33:33.066Z] [0.008s][info][gc] Min heap equals to max heap, disabling ShenandoahUncommit [2026-03-05T02:33:33.066Z] [0.018s][info][gc] Heuristics ergonomically sets -XX:+ExplicitGCInvokesConcurrent [2026-03-05T02:33:33.066Z] [0.018s][info][gc] Heuristics ergonomically sets -XX:+ShenandoahImplicitGCInvokesConcurrent [2026-03-05T02:33:33.066Z] [0.018s][info][gc] Using Shenandoah [2026-03-05T02:33:33.066Z] [0.018s][info][gc] Heuristics ergonomically sets -XX:ShenandoahImmediateThreshold=100 [2026-03-05T02:33:33.066Z] [0.023s][info][gc,ergo] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Version: 21.0.11-beta+5-ea (release) [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] CPUs: 4 total, 4 available [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Memory: 8136M [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Large Page Support: Disabled [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] NUMA Support: Disabled [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Compressed Oops: Enabled (32-bit) [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Heap Min Capacity: 1500M [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Heap Initial Capacity: 1500M [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Heap Max Capacity: 1500M [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Pre-touch: Disabled [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Mode: Snapshot-At-The-Beginning (SATB) [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Heuristics: Aggressive [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Heap Region Count: 3000 [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Heap Region Size: 512K [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] TLAB Size Max: 512K [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Humongous Object Threshold: 512K [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Parallel Workers: 2 [2026-03-05T02:33:33.066Z] [0.023s][info][gc,init] Concurrent Workers: 1 [2026-03-05T02:33:33.066Z] [0.040s][info][gc,metaspace] CDS archive(s) mapped at: [0x0000022127000000-0x0000022127c80000-0x0000022127c80000), size 13107200, SharedBaseAddress: 0x0000022127000000, ArchiveRelocationMode: 1. [2026-03-05T02:33:33.066Z] [0.040s][info][gc,metaspace] Compressed class space mapped at: 0x0000022128000000-0x0000022168000000, reserved size: 1073741824 [2026-03-05T02:33:33.066Z] [0.040s][info][gc,metaspace] Narrow klass base: 0x0000022127000000, Narrow klass shift: 0, Narrow klass range: 0x100000000 [2026-03-05T02:33:33.066Z] [0.061s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.066Z] [0.061s][info][gc,ergo ] Free: 1424M, Max: 512K regular, 1424M humongous, Frag: 0% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.066Z] [0.061s][info][gc,start ] GC(0) Concurrent reset [2026-03-05T02:33:33.066Z] [0.061s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.066Z] [0.061s][info][gc,ergo ] GC(0) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.066Z] [0.061s][info][gc ] GC(0) Concurrent reset 0.237ms [2026-03-05T02:33:33.066Z] [0.064s][info][gc,start ] GC(0) Pause Init Mark [2026-03-05T02:33:33.066Z] [0.064s][info][gc,task ] GC(0) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.066Z] [0.064s][info][gc,ergo ] GC(0) Pacer for Mark. Expected Live: 150M, Free: 1424M, Non-Taxable: 142M, Alloc Tax Rate: 0.1x [2026-03-05T02:33:33.066Z] [0.064s][info][gc ] GC(0) Pause Init Mark 0.171ms [2026-03-05T02:33:33.066Z] [0.064s][info][gc,start ] GC(0) Concurrent marking roots [2026-03-05T02:33:33.066Z] [0.064s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.066Z] [0.064s][info][gc ] GC(0) Concurrent marking roots 0.081ms [2026-03-05T02:33:33.066Z] [0.064s][info][gc,start ] GC(0) Concurrent marking [2026-03-05T02:33:33.066Z] [0.064s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.066Z] [0.073s][info][gc ] GC(0) Concurrent marking 8.769ms [2026-03-05T02:33:33.066Z] [0.073s][info][gc,start ] GC(0) Pause Final Mark [2026-03-05T02:33:33.066Z] [0.073s][info][gc,task ] GC(0) Using 2 of 2 workers for final marking [2026-03-05T02:33:33.066Z] [0.073s][info][gc,ergo ] GC(0) Collectable Garbage: 338K (100%), Immediate: 0B (0%), CSet: 338K (100%) [2026-03-05T02:33:33.066Z] [0.073s][info][gc,ergo ] GC(0) Pacer for Evacuation. Used CSet: 512K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.066Z] [0.073s][info][gc ] GC(0) Pause Final Mark 0.218ms [2026-03-05T02:33:33.066Z] [0.073s][info][gc,start ] GC(0) Concurrent thread roots [2026-03-05T02:33:33.066Z] [0.073s][info][gc,task ] GC(0) Using 1 of 2 workers for Concurrent thread roots [2026-03-05T02:33:33.066Z] [0.075s][info][gc ] GC(0) Concurrent thread roots 1.990ms [2026-03-05T02:33:33.066Z] [0.075s][info][gc,start ] GC(0) Concurrent weak references [2026-03-05T02:33:33.066Z] [0.075s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent weak references [2026-03-05T02:33:33.066Z] [0.075s][info][gc,ref ] GC(0) Encountered references: Soft: 1, Weak: 1, Final: 0, Phantom: 0 [2026-03-05T02:33:33.066Z] [0.075s][info][gc,ref ] GC(0) Discovered references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.066Z] [0.075s][info][gc,ref ] GC(0) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.066Z] [0.075s][info][gc ] GC(0) Concurrent weak references 0.072ms [2026-03-05T02:33:33.066Z] [0.075s][info][gc,start ] GC(0) Concurrent weak roots [2026-03-05T02:33:33.066Z] [0.075s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent weak root [2026-03-05T02:33:33.066Z] [0.076s][info][gc ] GC(0) Concurrent weak roots 0.152ms [2026-03-05T02:33:33.066Z] [0.076s][info][gc,start ] GC(0) Concurrent cleanup [2026-03-05T02:33:33.066Z] [0.076s][info][gc ] GC(0) Concurrent cleanup 3M->3M(1500M) 0.044ms [2026-03-05T02:33:33.066Z] [0.076s][info][gc,ergo ] GC(0) Free: 1422M, Max: 512K regular, 1422M humongous, Frag: 0% external, 0% internal; Reserve: 76763K, Max: 512K [2026-03-05T02:33:33.066Z] [0.076s][info][gc,start ] GC(0) Concurrent strong roots [2026-03-05T02:33:33.066Z] [0.076s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent strong root [2026-03-05T02:33:33.066Z] [0.076s][info][gc ] GC(0) Concurrent strong roots 0.148ms [2026-03-05T02:33:33.066Z] [0.076s][info][gc,start ] GC(0) Concurrent evacuation [2026-03-05T02:33:33.066Z] [0.076s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent evacuation [2026-03-05T02:33:33.066Z] [0.076s][info][gc ] GC(0) Concurrent evacuation 0.242ms [2026-03-05T02:33:33.066Z] [0.076s][info][gc,start ] GC(0) Pause Init Update Refs [2026-03-05T02:33:33.067Z] [0.076s][info][gc,ergo ] GC(0) Pacer for Update Refs. Used: 3266K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.067Z] [0.076s][info][gc ] GC(0) Pause Init Update Refs 0.042ms [2026-03-05T02:33:33.067Z] [0.076s][info][gc,start ] GC(0) Concurrent update references [2026-03-05T02:33:33.067Z] [0.076s][info][gc,task ] GC(0) Using 1 of 2 workers for concurrent reference update [2026-03-05T02:33:33.067Z] [0.077s][info][gc ] GC(0) Concurrent update references 0.401ms [2026-03-05T02:33:33.067Z] [0.077s][info][gc,start ] GC(0) Concurrent update thread roots [2026-03-05T02:33:33.067Z] [0.080s][info][gc ] GC(0) Concurrent update thread roots 3.299ms [2026-03-05T02:33:33.067Z] [0.080s][info][gc,start ] GC(0) Pause Final Update Refs [2026-03-05T02:33:33.067Z] [0.080s][info][gc,task ] GC(0) Using 2 of 2 workers for final reference update [2026-03-05T02:33:33.067Z] [0.080s][info][gc ] GC(0) Pause Final Update Refs 0.084ms [2026-03-05T02:33:33.067Z] [0.080s][info][gc,start ] GC(0) Concurrent cleanup [2026-03-05T02:33:33.067Z] [0.080s][info][gc ] GC(0) Concurrent cleanup 3M->2M(1500M) 0.020ms [2026-03-05T02:33:33.067Z] [0.080s][info][gc,ergo ] Free: 1422M, Max: 512K regular, 1421M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Reset 264 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Pause Init Mark (G) 2739 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Pause Init Mark (N) 178 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Update Region States 33 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Mark Roots 91 us, parallelism: 0.42x [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CMR: <total> 39 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CMR: Thread Roots 6 us, workers (us): 6, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CMR: Code Cache Roots 0 us, workers (us): 0, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CMR: VM Strong Roots 8 us, workers (us): 8, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CMR: CLDG Roots 25 us, workers (us): 25, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Marking 8793 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Pause Final Mark (G) 300 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Pause Final Mark (N) 227 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Finish Mark 36 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Update Region States 51 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Choose Collection Set 59 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Rebuild Free Set 21 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Thread Roots 2015 us, parallelism: 0.96x [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CTR: <total> 1942 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CTR: Thread Roots 1942 us, workers (us): 1942, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Weak References 80 us, parallelism: 0.01x [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CWRF: <total> 1 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CWRF: Weak References 1 us, workers (us): 1, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Weak Roots 168 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Roots 90 us, parallelism: 0.75x [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CWR: <total> 68 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CWR: VM Weak Roots 68 us, workers (us): 68, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Rendezvous 46 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Cleanup 54 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Strong Roots 162 us, parallelism: 0.69x [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CSR: <total> 111 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CSR: Code Cache Roots 12 us, workers (us): 12, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CSR: VM Strong Roots 12 us, workers (us): 12, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] CSR: CLDG Roots 87 us, workers (us): 87, ---, [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Evacuation 253 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Pause Init Update Refs (G) 96 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Pause Init Update Refs (N) 48 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Manage GCLABs 3 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Update Refs 418 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Concurrent Update Thread Roots 3321 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Pause Final Update Refs (G) 192 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Pause Final Update Refs (N) 91 us [2026-03-05T02:33:33.067Z] [0.080s][info][gc,stats ] Update Region States 36 us [2026-03-05T02:33:33.067Z] [0.081s][info][gc,stats ] Trash Collection Set 3 us [2026-03-05T02:33:33.067Z] [0.081s][info][gc,stats ] Rebuild Free Set 22 us [2026-03-05T02:33:33.067Z] [0.081s][info][gc,stats ] Concurrent Cleanup 27 us [2026-03-05T02:33:33.067Z] [0.081s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.081s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.067Z] [0.081s][info][gc,stats ] 0 of 58 ms ( 0.0%): <total> [2026-03-05T02:33:33.067Z] [0.081s][info][gc,stats ] 0 of 58 ms ( 0.0%): <average total> [2026-03-05T02:33:33.067Z] [0.081s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.081s][info][gc,metaspace] Metaspace: 30K(128K)->64K(128K) NonClass: 28K(64K)->62K(64K) Class: 1K(64K)->1K(64K) [2026-03-05T02:33:33.067Z] [0.081s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.067Z] [0.082s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.067Z] [0.082s][info][gc,ergo ] Free: 1422M, Max: 512K regular, 1421M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.067Z] [0.082s][info][gc,start ] GC(1) Concurrent reset [2026-03-05T02:33:33.067Z] [0.082s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.067Z] [0.084s][info][gc,ergo ] GC(1) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.067Z] [0.084s][info][gc ] GC(1) Concurrent reset 2.084ms [2026-03-05T02:33:33.067Z] [0.084s][info][gc,start ] GC(1) Pause Init Mark [2026-03-05T02:33:33.067Z] [0.084s][info][gc,task ] GC(1) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.067Z] [0.084s][info][gc,ergo ] GC(1) Pacer for Mark. Expected Live: 173K, Free: 1422M, Non-Taxable: 142M, Alloc Tax Rate: 0.0x [2026-03-05T02:33:33.067Z] [0.084s][info][gc ] GC(1) Pause Init Mark 0.068ms [2026-03-05T02:33:33.067Z] [0.084s][info][gc,start ] GC(1) Concurrent marking roots [2026-03-05T02:33:33.067Z] [0.084s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.067Z] [0.084s][info][gc ] GC(1) Concurrent marking roots 0.311ms [2026-03-05T02:33:33.067Z] [0.084s][info][gc ] Failed to allocate TLAB, 511K [2026-03-05T02:33:33.067Z] [0.084s][info][gc,start ] GC(1) Concurrent marking [2026-03-05T02:33:33.067Z] [0.084s][info][gc ] Cancelling GC: Allocation Failure [2026-03-05T02:33:33.067Z] [0.084s][info][gc,task ] GC(1) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.067Z] [0.085s][info][gc ] GC(1) Concurrent marking 0.043ms [2026-03-05T02:33:33.067Z] [0.085s][info][gc,ergo ] Free: 1422M, Max: 512K regular, 1421M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] Concurrent Reset 2100 us [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] Pause Init Mark (G) 110 us [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] Pause Init Mark (N) 74 us [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] Update Region States 18 us [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] Concurrent Mark Roots 331 us, parallelism: 0.74x [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] CMR: <total> 244 us [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] CMR: Thread Roots 186 us, workers (us): 186, ---, [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] CMR: Code Cache Roots 23 us, workers (us): 23, ---, [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] CMR: VM Strong Roots 4 us, workers (us): 4, ---, [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] CMR: CLDG Roots 31 us, workers (us): 31, ---, [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] Concurrent Marking 55 us [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] 0 of 4 ms ( 0.0%): <total> [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] 0 of 4 ms ( 0.0%): <average total> [2026-03-05T02:33:33.067Z] [0.085s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.085s][info][gc,metaspace] Metaspace: 66K(192K)->73K(192K) NonClass: 65K(128K)->72K(128K) Class: 1K(64K)->1K(64K) [2026-03-05T02:33:33.067Z] [0.085s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.067Z] [0.086s][info][gc ] Trigger: Handle Allocation Failure [2026-03-05T02:33:33.067Z] [0.086s][info][gc,ergo ] Free: 1422M, Max: 512K regular, 1421M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.067Z] [0.086s][info][gc,start ] GC(2) Pause Degenerated GC (Mark) [2026-03-05T02:33:33.067Z] [0.086s][info][gc,task ] GC(2) Using 2 of 2 workers for stw degenerated gc [2026-03-05T02:33:33.067Z] [0.086s][info][gc,ref ] GC(2) Encountered references: Soft: 1, Weak: 1, Final: 0, Phantom: 0 [2026-03-05T02:33:33.067Z] [0.087s][info][gc,ref ] GC(2) Discovered references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.067Z] [0.087s][info][gc,ref ] GC(2) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.067Z] [0.087s][info][gc,ergo ] GC(2) Collectable Garbage: 2409K (100%), Immediate: 0B (0%), CSet: 2409K (100%) [2026-03-05T02:33:33.067Z] [0.088s][info][gc,ergo ] GC(2) Good progress for free space: 1424M, need 15360K [2026-03-05T02:33:33.067Z] [0.088s][info][gc,ergo ] GC(2) Good progress for used space: 2371K, need 512K [2026-03-05T02:33:33.067Z] [0.088s][info][gc ] GC(2) Pause Degenerated GC (Mark) 2M->0M(1500M) 1.775ms [2026-03-05T02:33:33.067Z] [0.088s][info][gc,ergo ] Free: 1424M, Max: 512K regular, 1424M humongous, Frag: 0% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Finish Mark 450 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Pause Degenerated GC (G) 1821 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Pause Degenerated GC (N) 1782 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] System Purge 98 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Weak References 55 us, parallelism: 0.02x [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] WRP: <total> 1 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] WRP: Weak References 1 us, workers (us): 1, 0, [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Weak Roots 43 us, parallelism: 0.86x [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] DWR: <total> 37 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] DWR: VM Weak Roots 37 us, workers (us): 19, 18, [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Update Region States 20 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Manage GC/TLABs 2 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Choose Collection Set 36 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Rebuild Free Set 21 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Evacuation 466 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Manage GCLABs 3 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Update References 352 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Update Region States 15 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Trash Collection Set 2 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Rebuild Free Set 13 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Degen Update Roots 100 us, parallelism: 1.43x [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] DU: <total> 142 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] DU: Thread Roots 41 us, workers (us): 4, 37, [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] DU: Code Cache Roots 42 us, workers (us): 41, 0, [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] DU: VM Strong Roots 3 us, workers (us): 2, 2, [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] DU: VM Weak Roots 47 us, workers (us): 24, 24, [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] DU: CLDG Roots 9 us, workers (us): 1, 8, [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Cleanup 12 us [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] 0 of 3 ms ( 0.0%): <total> [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] 0 of 3 ms ( 0.0%): <average total> [2026-03-05T02:33:33.067Z] [0.088s][info][gc,stats ] [2026-03-05T02:33:33.067Z] [0.088s][info][gc,metaspace] Metaspace: 73K(192K)->73K(192K) NonClass: 72K(128K)->72K(128K) Class: 1K(64K)->1K(64K) [2026-03-05T02:33:33.067Z] [0.088s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.067Z] [0.090s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.067Z] [0.090s][info][gc,ergo ] Free: 1424M, Max: 512K regular, 1424M humongous, Frag: 0% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.067Z] [0.090s][info][gc,start ] GC(3) Concurrent reset [2026-03-05T02:33:33.067Z] [0.090s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.067Z] [0.090s][info][gc,ergo ] GC(3) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.067Z] [0.091s][info][gc ] GC(3) Concurrent reset 0.322ms [2026-03-05T02:33:33.067Z] [0.091s][info][gc,start ] GC(3) Pause Init Mark (unload classes) [2026-03-05T02:33:33.067Z] [0.091s][info][gc,task ] GC(3) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.067Z] [0.091s][info][gc,ergo ] GC(3) Pacer for Mark. Expected Live: 259K, Free: 1424M, Non-Taxable: 142M, Alloc Tax Rate: 0.0x [2026-03-05T02:33:33.067Z] [0.091s][info][gc ] GC(3) Pause Init Mark (unload classes) 0.250ms [2026-03-05T02:33:33.067Z] [0.091s][info][gc,start ] GC(3) Concurrent marking roots [2026-03-05T02:33:33.067Z] [0.091s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.067Z] [0.092s][info][gc ] GC(3) Concurrent marking roots 0.663ms [2026-03-05T02:33:33.067Z] [0.092s][info][gc,start ] GC(3) Concurrent marking (unload classes) [2026-03-05T02:33:33.067Z] [0.092s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.067Z] [0.093s][info][gc ] GC(3) Concurrent marking (unload classes) 0.920ms [2026-03-05T02:33:33.067Z] [0.093s][info][gc,start ] GC(3) Pause Final Mark (unload classes) [2026-03-05T02:33:33.067Z] [0.093s][info][gc,task ] GC(3) Using 2 of 2 workers for final marking [2026-03-05T02:33:33.067Z] [0.093s][info][gc,ergo ] GC(3) Collectable Garbage: 539K (100%), Immediate: 0B (0%), CSet: 539K (100%) [2026-03-05T02:33:33.067Z] [0.093s][info][gc,ergo ] GC(3) Pacer for Evacuation. Used CSet: 894K, Free: 1423M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.067Z] [0.093s][info][gc ] GC(3) Pause Final Mark (unload classes) 0.143ms [2026-03-05T02:33:33.067Z] [0.093s][info][gc,start ] GC(3) Concurrent thread roots [2026-03-05T02:33:33.067Z] [0.093s][info][gc,task ] GC(3) Using 1 of 2 workers for Concurrent thread roots [2026-03-05T02:33:33.067Z] [0.093s][info][gc ] GC(3) Concurrent thread roots 0.127ms [2026-03-05T02:33:33.067Z] [0.093s][info][gc,start ] GC(3) Concurrent weak references [2026-03-05T02:33:33.067Z] [0.093s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent weak references [2026-03-05T02:33:33.067Z] [0.093s][info][gc,ref ] GC(3) Encountered references: Soft: 1, Weak: 5, Final: 0, Phantom: 0 [2026-03-05T02:33:33.067Z] [0.093s][info][gc,ref ] GC(3) Discovered references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.067Z] [0.093s][info][gc,ref ] GC(3) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.068Z] [0.093s][info][gc ] GC(3) Concurrent weak references 0.040ms [2026-03-05T02:33:33.068Z] [0.093s][info][gc,start ] GC(3) Concurrent weak roots [2026-03-05T02:33:33.068Z] [0.093s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent weak root [2026-03-05T02:33:33.068Z] [0.093s][info][gc ] GC(3) Concurrent weak roots 0.184ms [2026-03-05T02:33:33.068Z] [0.093s][info][gc,start ] GC(3) Concurrent cleanup [2026-03-05T02:33:33.068Z] [0.093s][info][gc ] GC(3) Concurrent cleanup 1M->1M(1500M) 0.024ms [2026-03-05T02:33:33.068Z] [0.093s][info][gc,ergo ] GC(3) Free: 1423M, Max: 512K regular, 1423M humongous, Frag: 0% external, 0% internal; Reserve: 76723K, Max: 512K [2026-03-05T02:33:33.068Z] [0.093s][info][gc,start ] GC(3) Concurrent class unloading [2026-03-05T02:33:33.068Z] [0.093s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent class unloading [2026-03-05T02:33:33.068Z] [0.093s][info][gc ] GC(3) Concurrent class unloading 0.142ms [2026-03-05T02:33:33.068Z] [0.093s][info][gc,start ] GC(3) Concurrent strong roots [2026-03-05T02:33:33.068Z] [0.093s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent strong root [2026-03-05T02:33:33.068Z] [0.094s][info][gc ] GC(3) Concurrent strong roots 0.073ms [2026-03-05T02:33:33.068Z] [0.094s][info][gc,start ] GC(3) Concurrent evacuation [2026-03-05T02:33:33.068Z] [0.094s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent evacuation [2026-03-05T02:33:33.068Z] [0.094s][info][gc ] GC(3) Concurrent evacuation 0.493ms [2026-03-05T02:33:33.068Z] [0.094s][info][gc,start ] GC(3) Pause Init Update Refs [2026-03-05T02:33:33.068Z] [0.094s][info][gc,ergo ] GC(3) Pacer for Update Refs. Used: 2314K, Free: 1423M, Non-Taxable: 142M, Alloc Tax Rate: 1.1x [2026-03-05T02:33:33.068Z] [0.094s][info][gc ] GC(3) Pause Init Update Refs 0.024ms [2026-03-05T02:33:33.068Z] [0.094s][info][gc,start ] GC(3) Concurrent update references [2026-03-05T02:33:33.068Z] [0.094s][info][gc,task ] GC(3) Using 1 of 2 workers for concurrent reference update [2026-03-05T02:33:33.068Z] [0.095s][info][gc ] GC(3) Concurrent update references 0.484ms [2026-03-05T02:33:33.068Z] [0.095s][info][gc,start ] GC(3) Concurrent update thread roots [2026-03-05T02:33:33.068Z] [0.095s][info][gc ] GC(3) Concurrent update thread roots 0.071ms [2026-03-05T02:33:33.068Z] [0.095s][info][gc,start ] GC(3) Pause Final Update Refs [2026-03-05T02:33:33.068Z] [0.095s][info][gc,task ] GC(3) Using 2 of 2 workers for final reference update [2026-03-05T02:33:33.068Z] [0.095s][info][gc ] GC(3) Pause Final Update Refs 0.065ms [2026-03-05T02:33:33.068Z] [0.095s][info][gc,start ] GC(3) Concurrent cleanup [2026-03-05T02:33:33.068Z] [0.095s][info][gc ] GC(3) Concurrent cleanup 2M->1M(1500M) 0.025ms [2026-03-05T02:33:33.068Z] [0.095s][info][gc,ergo ] Free: 1423M, Max: 512K regular, 1423M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Reset 376 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Pause Init Mark (G) 451 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Pause Init Mark (N) 301 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Update Region States 50 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Mark Roots 729 us, parallelism: 0.74x [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CMR: <total> 538 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CMR: Thread Roots 502 us, workers (us): 502, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CMR: VM Strong Roots 3 us, workers (us): 3, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CMR: CLDG Roots 33 us, workers (us): 33, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Marking 936 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Pause Final Mark (G) 182 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Pause Final Mark (N) 156 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Finish Mark 36 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Update Region States 23 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Choose Collection Set 29 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Rebuild Free Set 20 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Thread Roots 139 us, parallelism: 0.67x [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CTR: <total> 93 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CTR: Thread Roots 93 us, workers (us): 93, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Weak References 45 us, parallelism: 0.01x [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CWRF: <total> 0 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CWRF: Weak References 0 us, workers (us): 0, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Weak Roots 196 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Roots 134 us, parallelism: 0.87x [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CWR: <total> 116 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CWR: Code Cache Roots 10 us, workers (us): 10, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CWR: VM Weak Roots 106 us, workers (us): 106, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CWR: CLDG Roots 0 us, workers (us): 0, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Rendezvous 37 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Cleanup 30 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Class Unloading 154 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Unlink Stale 65 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] System Dictionary 1 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Weak Class Links 0 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Code Roots 62 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Rendezvous 43 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Purge Unlinked 19 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Code Roots 12 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CLDG 6 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Exception Caches 0 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Strong Roots 81 us, parallelism: 0.56x [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CSR: <total> 45 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CSR: VM Strong Roots 5 us, workers (us): 5, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] CSR: CLDG Roots 40 us, workers (us): 40, ---, [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Evacuation 508 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Pause Init Update Refs (G) 55 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Pause Init Update Refs (N) 29 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Manage GCLABs 2 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Update Refs 499 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Update Thread Roots 80 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Pause Final Update Refs (G) 96 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Pause Final Update Refs (N) 72 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Update Region States 20 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Trash Collection Set 3 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Rebuild Free Set 22 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Concurrent Cleanup 32 us [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] 0 of 7 ms ( 0.0%): <total> [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] 0 of 7 ms ( 0.0%): <average total> [2026-03-05T02:33:33.068Z] [0.095s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.095s][info][gc,metaspace] Metaspace: 78K(192K)->81K(192K) NonClass: 77K(128K)->79K(128K) Class: 1K(64K)->1K(64K) [2026-03-05T02:33:33.068Z] [0.095s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.068Z] [0.097s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.068Z] [0.097s][info][gc,ergo ] Free: 1423M, Max: 512K regular, 1423M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.068Z] [0.097s][info][gc,start ] GC(4) Concurrent reset [2026-03-05T02:33:33.068Z] [0.097s][info][gc,task ] GC(4) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.068Z] [0.097s][info][gc,ergo ] GC(4) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.068Z] [0.097s][info][gc ] GC(4) Concurrent reset 0.142ms [2026-03-05T02:33:33.068Z] [0.097s][info][gc,start ] GC(4) Pause Init Mark [2026-03-05T02:33:33.068Z] [0.097s][info][gc,task ] GC(4) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.068Z] [0.097s][info][gc,ergo ] GC(4) Pacer for Mark. Expected Live: 290K, Free: 1423M, Non-Taxable: 142M, Alloc Tax Rate: 0.0x [2026-03-05T02:33:33.068Z] [0.097s][info][gc ] GC(4) Pause Init Mark 0.060ms [2026-03-05T02:33:33.068Z] [0.097s][info][gc,start ] GC(4) Concurrent marking roots [2026-03-05T02:33:33.068Z] [0.097s][info][gc,task ] GC(4) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.068Z] [0.097s][info][gc ] Failed to allocate TLAB, 511K [2026-03-05T02:33:33.068Z] [0.097s][info][gc ] Cancelling GC: Allocation Failure [2026-03-05T02:33:33.068Z] [0.099s][info][gc ] GC(4) Allocation failure was successfully injected [2026-03-05T02:33:33.068Z] [0.099s][info][gc ] GC(4) Concurrent marking roots 1.936ms [2026-03-05T02:33:33.068Z] [0.099s][info][gc,ergo ] Free: 1423M, Max: 512K regular, 1423M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] Concurrent Reset 155 us [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] Pause Init Mark (G) 160 us [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] Pause Init Mark (N) 66 us [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] Update Region States 17 us [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] Concurrent Mark Roots 1955 us, parallelism: 0.13x [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] CMR: <total> 250 us [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] CMR: Thread Roots 198 us, workers (us): 198, ---, [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] CMR: Code Cache Roots 28 us, workers (us): 28, ---, [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] CMR: VM Strong Roots 3 us, workers (us): 3, ---, [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] CMR: CLDG Roots 21 us, workers (us): 21, ---, [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] 0 of 4 ms ( 0.0%): <total> [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] 0 of 4 ms ( 0.0%): <average total> [2026-03-05T02:33:33.068Z] [0.099s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.099s][info][gc,metaspace] Metaspace: 85K(192K)->85K(192K) NonClass: 83K(128K)->83K(128K) Class: 1K(64K)->1K(64K) [2026-03-05T02:33:33.068Z] [0.099s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.068Z] [0.101s][info][gc ] Trigger: Handle Allocation Failure [2026-03-05T02:33:33.068Z] [0.101s][info][gc,ergo ] Free: 1423M, Max: 512K regular, 1423M humongous, Frag: 1% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.068Z] [0.101s][info][gc,start ] GC(5) Pause Degenerated GC (Outside of Cycle) [2026-03-05T02:33:33.068Z] [0.101s][info][gc,task ] GC(5) Using 2 of 2 workers for stw degenerated gc [2026-03-05T02:33:33.068Z] [0.101s][info][gc,ref ] GC(5) Clearing All SoftReferences [2026-03-05T02:33:33.068Z] [0.102s][info][gc,ref ] GC(5) Encountered references: Soft: 1, Weak: 6, Final: 0, Phantom: 0 [2026-03-05T02:33:33.068Z] [0.102s][info][gc,ref ] GC(5) Discovered references: Soft: 1, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.068Z] [0.102s][info][gc,ref ] GC(5) Enqueued references: Soft: 1, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.068Z] [0.102s][info][gc,ergo ] GC(5) Collectable Garbage: 1042K (100%), Immediate: 0B (0%), CSet: 1042K (100%) [2026-03-05T02:33:33.068Z] [0.103s][info][gc,ergo ] GC(5) Good progress for free space: 1424M, need 15360K [2026-03-05T02:33:33.068Z] [0.103s][info][gc,ergo ] GC(5) Good progress for used space: 908K, need 512K [2026-03-05T02:33:33.068Z] [0.103s][info][gc ] GC(5) Pause Degenerated GC (Outside of Cycle) 1M->0M(1500M) 2.178ms [2026-03-05T02:33:33.068Z] [0.103s][info][gc,ergo ] Free: 1424M, Max: 512K regular, 1424M humongous, Frag: 0% external, 0% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] Pause Degenerated GC (G) 2229 us [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] Pause Degenerated GC (N) 2186 us [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] Degen STW Mark 531 us, parallelism: 1.67x [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] DSM: <total> 887 us [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] DSM: Thread Roots 45 us, workers (us): 16, 29, [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] DSM: VM Strong Roots 3 us, workers (us): 0, 2, [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] DSM: CLDG Roots 18 us, workers (us): 18, 0, [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] DSM: Parallel Mark 821 us, workers (us): 408, 412, [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] System Purge 163 us [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] Weak References 54 us, parallelism: 0.03x [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] WRP: <total> 2 us [2026-03-05T02:33:33.068Z] [0.103s][info][gc,stats ] WRP: Weak References 2 us, workers [2026-03-05T02:33:33.068Z] [2026-03-05T02:33:33.068Z] ... [2026-03-05T02:33:33.068Z] Output overflow: [2026-03-05T02:33:33.068Z] JT Harness has limited the test output to the text [2026-03-05T02:33:33.068Z] at the beginning and the end, so that you can see how the [2026-03-05T02:33:33.068Z] test began, and how it completed. [2026-03-05T02:33:33.068Z] [2026-03-05T02:33:33.068Z] If you need to see more of the output from the test, [2026-03-05T02:33:33.068Z] set the system property javatest.maxOutputSize to a higher [2026-03-05T02:33:33.068Z] value. The current value is 100000 [2026-03-05T02:33:33.068Z] ... [2026-03-05T02:33:33.068Z] [2026-03-05T02:33:33.068Z] c,ref ] GC(39) Clearing All SoftReferences [2026-03-05T02:33:33.068Z] [6.274s][info][gc,ref ] GC(39) Encountered references: Soft: 55, Weak: 172, Final: 0, Phantom: 9 [2026-03-05T02:33:33.068Z] [6.274s][info][gc,ref ] GC(39) Discovered references: Soft: 41, Weak: 124, Final: 0, Phantom: 6 [2026-03-05T02:33:33.068Z] [6.274s][info][gc,ref ] GC(39) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.068Z] [6.274s][info][gc,ergo ] GC(39) Collectable Garbage: 0B (0%), Immediate: 0B (0%), CSet: 0B (0%) [2026-03-05T02:33:33.068Z] [6.275s][info][gc,ergo ] GC(39) Good progress for free space: 206M, need 15360K [2026-03-05T02:33:33.068Z] [6.275s][info][gc,ergo ] GC(39) Bad progress for used space: 0B, need 512K [2026-03-05T02:33:33.068Z] [6.275s][info][gc,ergo ] GC(39) Bad progress for internal fragmentation: 0.0%, need 1.0% [2026-03-05T02:33:33.068Z] [6.275s][info][gc,ergo ] GC(39) Bad progress for external fragmentation: 0.0%, need 1.0% [2026-03-05T02:33:33.068Z] [6.275s][info][gc ] GC(39) Cancelling GC: Upgrade To Full GC [2026-03-05T02:33:33.068Z] [6.279s][info][gc,phases,start] GC(39) Phase 1: Mark live objects [2026-03-05T02:33:33.068Z] [6.279s][info][gc,ref ] GC(39) Clearing All SoftReferences [2026-03-05T02:33:33.068Z] [6.279s][info][gc,ref ] GC(39) Clearing All SoftReferences [2026-03-05T02:33:33.068Z] [6.698s][info][gc,ref ] GC(39) Encountered references: Soft: 55, Weak: 172, Final: 0, Phantom: 9 [2026-03-05T02:33:33.068Z] [6.698s][info][gc,ref ] GC(39) Discovered references: Soft: 41, Weak: 124, Final: 0, Phantom: 6 [2026-03-05T02:33:33.068Z] [6.698s][info][gc,ref ] GC(39) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2026-03-05T02:33:33.068Z] [6.698s][info][gc,phases ] GC(39) Phase 1: Mark live objects 419.538ms [2026-03-05T02:33:33.068Z] [6.698s][info][gc,phases,start] GC(39) Phase 2: Compute new object addresses [2026-03-05T02:33:33.068Z] [6.849s][info][gc,phases ] GC(39) Phase 2: Compute new object addresses 150.255ms [2026-03-05T02:33:33.068Z] [6.849s][info][gc,phases,start] GC(39) Phase 3: Adjust pointers [2026-03-05T02:33:33.068Z] [7.102s][info][gc,phases ] GC(39) Phase 3: Adjust pointers 252.995ms [2026-03-05T02:33:33.068Z] [7.102s][info][gc,phases,start] GC(39) Phase 4: Move objects [2026-03-05T02:33:33.068Z] [7.255s][info][gc,phases ] GC(39) Phase 4: Move objects 153.361ms [2026-03-05T02:33:33.068Z] [7.273s][info][gc,ergo ] GC(39) Good progress for free space: 206M, need 15360K [2026-03-05T02:33:33.068Z] [7.273s][info][gc,ergo ] GC(39) Bad progress for used space: 0B, need 512K [2026-03-05T02:33:33.068Z] [7.273s][info][gc,ergo ] GC(39) Bad progress for internal fragmentation: -0.0%, need 1.0% [2026-03-05T02:33:33.068Z] [7.273s][info][gc,ergo ] GC(39) Bad progress for external fragmentation: 0.0%, need 1.0% [2026-03-05T02:33:33.068Z] [7.273s][info][gc ] GC(39) Pause Degenerated GC (Outside of Cycle) 1186M->1186M(1500M) 1415.317ms [2026-03-05T02:33:33.068Z] [7.273s][info][gc,ergo ] Free: 206M, Max: 512K regular, 512K humongous, Frag: 100% external, 63% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.068Z] [7.273s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [7.273s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.068Z] [7.273s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.068Z] [7.273s][info][gc,stats ] [2026-03-05T02:33:33.068Z] [7.273s][info][gc,stats ] Pause Degenerated GC (G) 1415414 us [2026-03-05T02:33:33.068Z] [7.273s][info][gc,stats ] Pause Degenerated GC (N) 1415332 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Degen STW Mark 415641 us, parallelism: 2.00x [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DSM: <total> 830748 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DSM: Thread Roots 137 us, workers (us): 67, 70, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DSM: VM Strong Roots 5 us, workers (us): 0, 5, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DSM: CLDG Roots 44 us, workers (us): 43, 2, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DSM: Parallel Mark 830563 us, workers (us): 415262, 415301, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] System Purge 413 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Weak References 95 us, parallelism: 0.35x [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] WRP: <total> 33 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] WRP: Weak References 33 us, workers (us): 25, 8, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Unload Classes 260 us, parallelism: 1.80x [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DCU: <total> 469 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DCU: Unload Code Caches 469 us, workers (us): 234, 235, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Weak Roots 48 us, parallelism: 1.05x [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DWR: <total> 50 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] DWR: VM Weak Roots 50 us, workers (us): 26, 25, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] CLDG 1 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Update Region States 66 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Manage GC/TLABs 5 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Choose Collection Set 53 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Rebuild Free Set 26 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Cleanup 35 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Pre Heap Dump 1 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Prepare 4023 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Mark 419515 us, parallelism: 2.00x [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] FM: <total> 837506 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] FM: Thread Roots 108 us, workers (us): 56, 52, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] FM: VM Strong Roots 4 us, workers (us): 0, 4, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] FM: CLDG Roots 40 us, workers (us): 40, 0, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] FM: Parallel Mark 837353 us, workers (us): 418653, 418700, [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] System Purge 464 us [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] Weak References 120 us, parallelism: 0.34x [2026-03-05T02:33:33.068Z] [7.274s][info][gc,stats ] WRP: <total> 41 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] WRP: Weak References 41 us, workers (us): 33, 9, [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Unload Classes 273 us, parallelism: 1.79x [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] CU: <total> 489 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] CU: Unload Code Caches 489 us, workers (us): 244, 245, [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Weak Roots 53 us, parallelism: 0.95x [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] WR: <total> 51 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] WR: VM Weak Roots 51 us, workers (us): 26, 25, [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] CLDG 1 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Calculate Addresses 150246 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Regular Objects 149775 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Humongous Objects 249 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Adjust Pointers 252984 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] FA: <total> 668 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] FA: Thread Roots 110 us, workers (us): 5, 105, [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] FA: Code Cache Roots 450 us, workers (us): 368, 82, [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] FA: VM Strong Roots 6 us, workers (us): 5, 1, [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] FA: VM Weak Roots 64 us, workers (us): 32, 32, [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] FA: CLDG Roots 39 us, workers (us): 5, 34, [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Copy Objects 153350 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Regular Objects 146390 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Humongous Objects 660 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Reset Complete Bitmap 6179 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Rebuild Region Sets 118 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Post Heap Dump 2 us [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] 0 of 1418 ms ( 0.0%): <total> [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] 0 of 1418 ms ( 0.0%): <average total> [2026-03-05T02:33:33.069Z] [7.274s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.274s][info][gc,metaspace ] Metaspace: 865K(1088K)->865K(1088K) NonClass: 778K(896K)->778K(896K) Class: 86K(192K)->86K(192K) [2026-03-05T02:33:33.069Z] [7.274s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.069Z] [7.277s][info][gc ] Trigger: Start next cycle immediately [2026-03-05T02:33:33.069Z] [7.277s][info][gc,ergo ] Free: 206M, Max: 512K regular, 512K humongous, Frag: 100% external, 63% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.069Z] [7.277s][info][gc,start ] GC(40) Concurrent reset [2026-03-05T02:33:33.069Z] [7.277s][info][gc,task ] GC(40) Using 1 of 2 workers for concurrent reset [2026-03-05T02:33:33.069Z] [7.277s][info][gc,ergo ] GC(40) Pacer for Reset. Non-Taxable: 1500M [2026-03-05T02:33:33.069Z] [7.277s][info][gc ] GC(40) Concurrent reset 0.233ms [2026-03-05T02:33:33.069Z] [7.280s][info][gc,start ] GC(40) Pause Init Mark [2026-03-05T02:33:33.069Z] [7.280s][info][gc,task ] GC(40) Using 2 of 2 workers for init marking [2026-03-05T02:33:33.069Z] [7.280s][info][gc,ergo ] GC(40) Pacer for Mark. Expected Live: 1676M, Free: 206M, Non-Taxable: 21098K, Alloc Tax Rate: 9.9x [2026-03-05T02:33:33.069Z] [7.280s][info][gc ] GC(40) Pause Init Mark 0.128ms [2026-03-05T02:33:33.069Z] [7.280s][info][gc,start ] GC(40) Concurrent marking roots [2026-03-05T02:33:33.069Z] [7.280s][info][gc,task ] GC(40) Using 1 of 2 workers for concurrent marking roots [2026-03-05T02:33:33.069Z] [7.281s][info][gc ] GC(40) Concurrent marking roots 0.644ms [2026-03-05T02:33:33.069Z] [7.281s][info][gc,start ] GC(40) Concurrent marking [2026-03-05T02:33:33.069Z] [7.281s][info][gc,task ] GC(40) Using 1 of 2 workers for concurrent marking [2026-03-05T02:33:33.069Z] [7.326s][info][gc ] Cancelling GC: Stopping VM [2026-03-05T02:33:33.069Z] [7.326s][info][gc ] GC(40) Concurrent marking 44.964ms [2026-03-05T02:33:33.069Z] [7.326s][info][gc,ergo ] Free: 205M, Max: 512K regular, 512K humongous, Frag: 100% external, 62% internal; Reserve: 76800K, Max: 512K [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] Concurrent Reset 257 us [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] Pause Init Mark (G) 2815 us [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] Pause Init Mark (N) 134 us [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] Update Region States 71 us [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] Concurrent Mark Roots 662 us, parallelism: 0.85x [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] CMR: <total> 563 us [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] CMR: Thread Roots 281 us, workers (us): 281, ---, [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] CMR: Code Cache Roots 229 us, workers (us): 229, ---, [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] CMR: VM Strong Roots 7 us, workers (us): 7, ---, [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] CMR: CLDG Roots 46 us, workers (us): 46, ---, [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] Allocation pacing accrued: [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] 0 of 52 ms ( 0.0%): <total> [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] 0 of 52 ms ( 0.0%): <average total> [2026-03-05T02:33:33.069Z] [7.326s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.326s][info][gc,metaspace ] Metaspace: 874K(1088K)->1008K(1216K) NonClass: 787K(896K)->906K(1024K) Class: 87K(192K)->101K(192K) [2026-03-05T02:33:33.069Z] [7.326s][info][gc,ergo ] Pacer for Idle. Initial: 30720K, Alloc Tax Rate: 1.0x [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] Heap [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] Shenandoah Heap [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] 1500M max, 1500M soft max, 1500M committed, 1187M used [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] 3000 x 512K regions [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] Status: marking, cancelled [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] Reserved region: [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] - [0x00000000a2400000, 0x0000000100000000) [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] Collection set: [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] - map (vanilla): 0x0000000000011448 [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] - map (biased): 0x0000000000010000 [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] Metaspace used 1010K, committed 1216K, reserved 1114112K [2026-03-05T02:33:33.069Z] [7.328s][info][gc,heap,exit ] class space used 101K, committed 192K, reserved 1048576K [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] GC STATISTICS: [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] "(G)" (gross) pauses include VM time: time to notify and block threads, do the pre- [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] and post-safepoint housekeeping. Use -Xlog:safepoint+stats to dissect. [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] "(N)" (net) pauses are the times spent in the actual GC code. [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] "a" is average time for each phase, look at levels to see if average makes sense. [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] "lvls" are quantiles: 0% (minimum), 25%, 50% (median), 75%, 100% (maximum). [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] Pacing delays are measured from entering the pacing code till exiting it. Therefore, [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] observed pacing delays may be higher than the threshold when paced thread spent more [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] time in the pacing code. It usually happens when thread is de-scheduled while paced, [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] OS takes longer to unblock the thread, or JVM experiences an STW pause. [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] Higher delay would prevent application outpacing the GC, but it will hide the GC latencies [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] from the STW pause times. Pacing affects the individual threads, and so it would also be [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] invisible to the usual profiling tools, but would add up to end-to-end application latency. [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] Raise max pacing delay with care. [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] Concurrent Reset = 0.013 s (a = 458 us) (n = 28) (lvls, us = 146, 150, 160, 258, 2788) [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] Pause Init Mark (G) = 0.015 s (a = 529 us) (n = 28) (lvls, us = 79, 107, 156, 260, 2870) [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] Pause Init Mark (N) = 0.002 s (a = 84 us) (n = 28) (lvls, us = 48, 59, 69, 82, 301) [2026-03-05T02:33:33.069Z] [7.328s][info][gc,stats ] Update Region States = 0.001 s (a = 25 us) (n = 28) (lvls, us = 14, 16, 18, 24, 71) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Mark Roots = 0.013 s (a = 451 us) (n = 28) (lvls, us = 91, 238, 330, 426, 1974) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CMR: <total> = 0.007 s (a = 258 us) (n = 28) (lvls, us = 39, 176, 242, 320, 563) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CMR: Thread Roots = 0.005 s (a = 177 us) (n = 28) (lvls, us = 6, 92, 148, 242, 502) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CMR: Code Cache Roots = 0.001 s (a = 75 us) (n = 16) (lvls, us = 0, 30, 43, 58, 229) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CMR: VM Strong Roots = 0.000 s (a = 5 us) (n = 28) (lvls, us = 3, 3, 3, 6, 14) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CMR: CLDG Roots = 0.001 s (a = 33 us) (n = 28) (lvls, us = 19, 23, 30, 35, 58) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Marking = 1.186 s (a = 51552 us) (n = 23) (lvls, us = 55, 936, 1387, 4004, 622651) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Pause Final Mark (G) = 0.006 s (a = 308 us) (n = 20) (lvls, us = 164, 182, 189, 291, 1561) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Pause Final Mark (N) = 0.003 s (a = 169 us) (n = 20) (lvls, us = 129, 137, 146, 164, 271) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Finish Mark = 0.310 s (a = 13479 us) (n = 23) (lvls, us = 20, 25, 29, 36, 308499) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Update Region States = 0.001 s (a = 33 us) (n = 20) (lvls, us = 19, 20, 21, 36, 79) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Choose Collection Set = 0.001 s (a = 37 us) (n = 20) (lvls, us = 25, 27, 32, 36, 67) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Rebuild Free Set = 0.000 s (a = 21 us) (n = 20) (lvls, us = 18, 19, 20, 20, 35) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Thread Roots = 0.006 s (a = 315 us) (n = 20) (lvls, us = 121, 141, 193, 293, 2015) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CTR: <total> = 0.005 s (a = 254 us) (n = 20) (lvls, us = 74, 93, 121, 201, 1942) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CTR: Thread Roots = 0.005 s (a = 254 us) (n = 20) (lvls, us = 74, 93, 121, 201, 1942) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Weak References = 0.001 s (a = 66 us) (n = 20) (lvls, us = 45, 57, 62, 74, 99) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CWRF: <total> = 0.000 s (a = 6 us) (n = 20) (lvls, us = 0, 1, 3, 4, 28) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CWRF: Weak References = 0.000 s (a = 6 us) (n = 20) (lvls, us = 0, 1, 3, 4, 28) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Weak Roots = 0.007 s (a = 354 us) (n = 20) (lvls, us = 168, 205, 254, 400, 1137) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Roots = 0.004 s (a = 178 us) (n = 20) (lvls, us = 90, 135, 160, 178, 358) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CWR: <total> = 0.003 s (a = 156 us) (n = 20) (lvls, us = 68, 115, 139, 156, 332) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CWR: Code Cache Roots = 0.000 s (a = 49 us) (n = 10) (lvls, us = 10, 13, 26, 41, 159) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CWR: VM Weak Roots = 0.003 s (a = 131 us) (n = 20) (lvls, us = 68, 105, 117, 145, 196) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CWR: CLDG Roots = 0.000 s (a = 1 us) (n = 10) (lvls, us = 0, 0, 0, 1, 4) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Rendezvous = 0.003 s (a = 146 us) (n = 20) (lvls, us = 36, 45, 59, 182, 746) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Cleanup = 0.005 s (a = 234 us) (n = 20) (lvls, us = 29, 32, 39, 54, 1967) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Class Unloading = 0.045 s (a = 4546 us) (n = 10) (lvls, us = 143, 152, 225, 256, 43141) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Unlink Stale = 0.002 s (a = 177 us) (n = 10) (lvls, us = 65, 78, 133, 137, 447) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] System Dictionary = 0.000 s (a = 2 us) (n = 10) (lvls, us = 1, 1, 2, 2, 4) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Weak Class Links = 0.000 s (a = 0 us) (n = 10) (lvls, us = 0, 0, 0, 0, 0) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Code Roots = 0.002 s (a = 173 us) (n = 10) (lvls, us = 62, 77, 129, 133, 442) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Rendezvous = 0.043 s (a = 4321 us) (n = 10) (lvls, us = 38, 43, 50, 59, 42589) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Purge Unlinked = 0.000 s (a = 15 us) (n = 10) (lvls, us = 4, 6, 9, 18, 42) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Code Roots = 0.000 s (a = 10 us) (n = 10) (lvls, us = 0, 1, 6, 12, 36) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CLDG = 0.000 s (a = 5 us) (n = 10) (lvls, us = 3, 3, 5, 6, 6) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Exception Caches = 0.000 s (a = 0 us) (n = 10) (lvls, us = 0, 0, 0, 0, 0) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Strong Roots = 0.009 s (a = 441 us) (n = 20) (lvls, us = 81, 105, 162, 191, 5448) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CSR: <total> = 0.003 s (a = 130 us) (n = 20) (lvls, us = 41, 55, 109, 143, 383) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CSR: Code Cache Roots = 0.001 s (a = 99 us) (n = 10) (lvls, us = 12, 37, 57, 69, 252) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CSR: VM Strong Roots = 0.000 s (a = 8 us) (n = 20) (lvls, us = 5, 6, 7, 9, 18) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CSR: CLDG Roots = 0.001 s (a = 72 us) (n = 20) (lvls, us = 36, 47, 61, 87, 131) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Evacuation = 0.333 s (a = 16664 us) (n = 20) (lvls, us = 59, 508, 670, 963, 198993) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Pause Init Update Refs (G) = 0.003 s (a = 155 us) (n = 17) (lvls, us = 55, 74, 88, 152, 429) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Pause Init Update Refs (N) = 0.001 s (a = 38 us) (n = 17) (lvls, us = 28, 33, 36, 37, 64) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Manage GCLABs = 0.000 s (a = 3 us) (n = 17) (lvls, us = 2, 2, 2, 3, 7) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Update Refs = 0.262 s (a = 15400 us) (n = 17) (lvls, us = 152, 611, 842, 928, 165374) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Update Thread Roots = 0.016 s (a = 990 us) (n = 16) (lvls, us = 80, 189, 293, 781, 5107) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Pause Final Update Refs (G) = 0.003 s (a = 215 us) (n = 16) (lvls, us = 96, 113, 141, 209, 690) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Pause Final Update Refs (N) = 0.001 s (a = 88 us) (n = 16) (lvls, us = 68, 72, 78, 92, 151) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Update Region States = 0.000 s (a = 30 us) (n = 16) (lvls, us = 20, 21, 21, 27, 75) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Trash Collection Set = 0.000 s (a = 3 us) (n = 16) (lvls, us = 3, 3, 3, 3, 6) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Rebuild Free Set = 0.000 s (a = 20 us) (n = 16) (lvls, us = 17, 18, 20, 21, 23) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Concurrent Cleanup = 0.001 s (a = 44 us) (n = 16) (lvls, us = 25, 32, 37, 49, 104) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Pause Degenerated GC (G) = 4.915 s (a = 378046 us) (n = 13) (lvls, us = 1133, 1582, 2227, 2852, 1630152) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Pause Degenerated GC (N) = 4.914 s (a = 377991 us) (n = 13) (lvls, us = 1055, 1543, 2168, 2793, 1630074) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Degen STW Mark = 0.837 s (a = 139566 us) (n = 6) (lvls, us = 531, 531, 734, 883, 418920) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DSM: <total> = 1.673 s (a = 278815 us) (n = 6) (lvls, us = 887, 887, 1270, 1523, 837296) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DSM: Thread Roots = 0.000 s (a = 83 us) (n = 6) (lvls, us = 38, 38, 62, 84, 137) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DSM: VM Strong Roots = 0.000 s (a = 3 us) (n = 6) (lvls, us = 3, 3, 3, 3, 5) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DSM: CLDG Roots = 0.000 s (a = 31 us) (n = 6) (lvls, us = 18, 18, 26, 38, 44) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DSM: Parallel Mark = 1.672 s (a = 278698 us) (n = 6) (lvls, us = 820, 820, 1172, 1387, 837119) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] System Purge = 0.002 s (a = 242 us) (n = 9) (lvls, us = 84, 98, 170, 199, 440) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Weak References = 0.001 s (a = 68 us) (n = 9) (lvls, us = 49, 49, 54, 55, 106) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] WRP: <total> = 0.000 s (a = 10 us) (n = 9) (lvls, us = 1, 1, 3, 6, 34) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] WRP: Weak References = 0.000 s (a = 10 us) (n = 9) (lvls, us = 1, 1, 3, 6, 34) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Unload Classes = 0.001 s (a = 159 us) (n = 7) (lvls, us = 69, 69, 78, 260, 279) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DCU: <total> = 0.002 s (a = 270 us) (n = 7) (lvls, us = 84, 84, 109, 469, 507) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DCU: Unload Code Caches = 0.002 s (a = 270 us) (n = 7) (lvls, us = 84, 84, 109, 469, 507) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Weak Roots = 0.000 s (a = 43 us) (n = 9) (lvls, us = 33, 34, 41, 48, 53) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DWR: <total> = 0.000 s (a = 41 us) (n = 9) (lvls, us = 30, 30, 31, 50, 57) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] DWR: VM Weak Roots = 0.000 s (a = 41 us) (n = 9) (lvls, us = 30, 30, 31, 50, 57) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] CLDG = 0.000 s (a = 1 us) (n = 7) (lvls, us = 1, 1, 1, 1, 3) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Update Region States = 0.000 s (a = 35 us) (n = 9) (lvls, us = 19, 20, 20, 21, 66) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Manage GC/TLABs = 0.000 s (a = 4 us) (n = 9) (lvls, us = 2, 2, 2, 2, 9) [2026-03-05T02:33:33.069Z] [7.329s][info][gc,stats ] Choose Collection Set = 0.000 s (a = 39 us) (n = 9) (lvls, us = 23, 27, 36, 38, 57) [2026-03-05T02:33:33.069Z] [7.330s][info][gc,stats ] Rebuild Free Set = 0.000 s (a = 22 us) (n = 9) (lvls, us = 18, 20, 21, 22, 26) [2026-03-05T02:33:33.069Z] [7.330s][info][gc,stats ] Evacuation = 0.191 s (a = 17365 us) (n = 11) (lvls, us = 381, 410, 465, 629, 180260) [2026-03-05T02:33:33.069Z] [7.330s][info][gc,stats ] Manage GCLABs = 0.000 s (a = 4 us) (n = 10) (lvls, us = 2, 2, 3, 3, 9) [2026-03-05T02:33:33.069Z] [7.330s][info][gc,stats ] Update References = 0.304 s (a = 27682 us) (n = 11) (lvls, us = 352, 355, 498, 613, 152027) [2026-03-05T02:33:33.069Z] [7.330s][info][gc,stats ] Update Region States = 0.000 s (a = 31 us) (n = 11) (lvls, us = 15, 19, 22, 25, 68) [2026-03-05T02:33:33.069Z] [7.330s][info][gc,stats ] Trash Collection Set = 0.000 s (a = 3 us) (n = 11) (lvls, us = 2, 2, 3, 3, 5) [2026-03-05T02:33:33.069Z] [7.330s][info][gc,stats ] Rebuild Free Set = 0.000 s (a = 21 us) (n = 11) (lvls, us = 13, 13, 21, 22, 28) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Degen Update Roots = 0.003 s (a = 230 us) (n = 11) (lvls, us = 96, 100, 139, 234, 482) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] DU: <total> = 0.004 s (a = 322 us) (n = 11) (lvls, us = 119, 141, 211, 289, 672) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] DU: Thread Roots = 0.001 s (a = 94 us) (n = 11) (lvls, us = 18, 28, 46, 88, 413) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] DU: Code Cache Roots = 0.002 s (a = 152 us) (n = 11) (lvls, us = 41, 45, 84, 107, 454) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] DU: VM Strong Roots = 0.000 s (a = 3 us) (n = 11) (lvls, us = 2, 2, 3, 4, 5) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] DU: VM Weak Roots = 0.001 s (a = 62 us) (n = 11) (lvls, us = 46, 46, 53, 60, 96) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] DU: CLDG Roots = 0.000 s (a = 11 us) (n = 11) (lvls, us = 5, 5, 9, 13, 19) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Cleanup = 0.000 s (a = 24 us) (n = 12) (lvls, us = 12, 16, 18, 24, 71) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Pre Heap Dump = 0.000 s (a = 1 us) (n = 3) (lvls, us = 1, 1, 1, 1, 2) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Prepare = 0.015 s (a = 5012 us) (n = 3) (lvls, us = 4004, 4004, 4004, 4062, 6945) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Update Roots = 0.000 s (a = 454 us) (n = 1) (lvls, us = 453, 453, 453, 453, 454) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FU: <total> = 0.001 s (a = 641 us) (n = 1) (lvls, us = 641, 641, 641, 641, 641) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FU: Thread Roots = 0.000 s (a = 98 us) (n = 1) (lvls, us = 98, 98, 98, 98, 98) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FU: Code Cache Roots = 0.000 s (a = 441 us) (n = 1) (lvls, us = 439, 439, 439, 439, 441) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FU: VM Strong Roots = 0.000 s (a = 3 us) (n = 1) (lvls, us = 3, 3, 3, 3, 3) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FU: VM Weak Roots = 0.000 s (a = 82 us) (n = 1) (lvls, us = 82, 82, 82, 82, 82) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FU: CLDG Roots = 0.000 s (a = 17 us) (n = 1) (lvls, us = 17, 17, 17, 17, 17) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Mark = 1.392 s (a = 463967 us) (n = 3) (lvls, us = 417969, 417969, 417969, 443359, 528799) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FM: <total> = 2.778 s (a = 925980 us) (n = 3) (lvls, us = 835938, 835938, 835938, 884766, 1054749) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FM: Thread Roots = 0.000 s (a = 117 us) (n = 3) (lvls, us = 107, 107, 107, 119, 122) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FM: VM Strong Roots = 0.000 s (a = 4 us) (n = 3) (lvls, us = 4, 4, 4, 4, 5) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FM: CLDG Roots = 0.000 s (a = 47 us) (n = 3) (lvls, us = 40, 40, 40, 46, 55) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FM: Parallel Mark = 2.777 s (a = 925811 us) (n = 3) (lvls, us = 835938, 835938, 835938, 884766, 1054570) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] System Purge = 0.002 s (a = 678 us) (n = 3) (lvls, us = 430, 430, 430, 463, 1140) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Weak References = 0.000 s (a = 115 us) (n = 3) (lvls, us = 109, 109, 109, 113, 120) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] WRP: <total> = 0.000 s (a = 39 us) (n = 3) (lvls, us = 34, 34, 34, 40, 41) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] WRP: Weak References = 0.000 s (a = 39 us) (n = 3) (lvls, us = 34, 34, 34, 40, 41) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Unload Classes = 0.001 s (a = 476 us) (n = 3) (lvls, us = 250, 250, 250, 271, 905) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] CU: <total> = 0.002 s (a = 808 us) (n = 3) (lvls, us = 447, 447, 447, 488, 1487) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] CU: Unload Code Caches = 0.001 s (a = 482 us) (n = 3) (lvls, us = 447, 447, 447, 488, 508) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] CU: Unlink CLDs = 0.001 s (a = 979 us) (n = 1) (lvls, us = 979, 979, 979, 979, 979) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Weak Roots = 0.000 s (a = 54 us) (n = 3) (lvls, us = 52, 52, 52, 53, 57) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] WR: <total> = 0.000 s (a = 54 us) (n = 3) (lvls, us = 50, 50, 50, 51, 60) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] WR: VM Weak Roots = 0.000 s (a = 54 us) (n = 3) (lvls, us = 50, 50, 50, 51, 60) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] CLDG = 0.000 s (a = 18 us) (n = 3) (lvls, us = 1, 1, 1, 2, 49) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Calculate Addresses = 0.493 s (a = 164226 us) (n = 3) (lvls, us = 148438, 148438, 148438, 154297, 187235) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Regular Objects = 0.491 s (a = 163759 us) (n = 3) (lvls, us = 148438, 148438, 148438, 154297, 186780) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Humongous Objects = 0.001 s (a = 249 us) (n = 3) (lvls, us = 225, 225, 225, 248, 274) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Adjust Pointers = 0.738 s (a = 246136 us) (n = 3) (lvls, us = 240234, 240234, 240234, 242188, 252984) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FA: <total> = 0.002 s (a = 657 us) (n = 3) (lvls, us = 629, 629, 629, 668, 674) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FA: Thread Roots = 0.000 s (a = 108 us) (n = 3) (lvls, us = 105, 105, 105, 105, 110) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FA: Code Cache Roots = 0.001 s (a = 437 us) (n = 3) (lvls, us = 406, 406, 406, 449, 454) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FA: VM Strong Roots = 0.000 s (a = 6 us) (n = 3) (lvls, us = 6, 6, 6, 6, 7) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FA: VM Weak Roots = 0.000 s (a = 68 us) (n = 3) (lvls, us = 64, 64, 64, 68, 71) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] FA: CLDG Roots = 0.000 s (a = 39 us) (n = 3) (lvls, us = 37, 37, 37, 39, 40) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Copy Objects = 0.533 s (a = 177692 us) (n = 3) (lvls, us = 152344, 152344, 152344, 173828, 204998) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Regular Objects = 0.516 s (a = 171886 us) (n = 3) (lvls, us = 144531, 144531, 144531, 167969, 199847) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Humongous Objects = 0.002 s (a = 819 us) (n = 3) (lvls, us = 195, 195, 195, 658, 1599) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Reset Complete Bitmap = 0.015 s (a = 4871 us) (n = 3) (lvls, us = 3594, 3594, 3594, 4824, 6179) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Rebuild Region Sets = 0.000 s (a = 112 us) (n = 3) (lvls, us = 102, 102, 102, 115, 118) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Post Heap Dump = 0.000 s (a = 2 us) (n = 3) (lvls, us = 2, 2, 2, 2, 3) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Pacing = 0.012 s (a = 301 us) (n = 40) (lvls, us = 0, 0, 0, 0, 12032) [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] Under allocation pressure, concurrent cycles may cancel, and either continue cycle [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] under stop-the-world pause or result in stop-the-world Full GC. Increase heap size, [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] tune GC heuristics, set more aggressive pacing delay, or lower allocation rate [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] to avoid Degenerated and Full GC cycles. [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 16 successful concurrent GCs [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 0 invoked explicitly [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 0 invoked implicitly [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 13 Degenerated GCs [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 13 caused by allocation failure [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 6 happened at Outside of Cycle [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 3 happened at Mark [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 3 happened at Evacuation [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 1 happened at Update References [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 3 upgraded to Full GC [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 3 Full GCs [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 0 invoked explicitly [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 0 invoked implicitly [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 0 caused by allocation failure [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] 3 upgraded from Degenerated GC [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] [2026-03-05T02:33:33.070Z] [7.330s][info][gc,stats ] [2026-03-05T02:33:33.070Z] STDERR: [2026-03-05T02:33:33.070Z] java.lang.OutOfMemoryError: Java heap space [2026-03-05T02:33:33.070Z] at gc.stress.gclocker.Filler.<init>(TestGCLocker.java:51) [2026-03-05T02:33:33.070Z] at gc.stress.gclocker.MemoryUser.load(TestGCLocker.java:152) [2026-03-05T02:33:33.070Z] at gc.stress.gclocker.MemoryUser.run(TestGCLocker.java:166) [2026-03-05T02:33:33.070Z] at java.base/java.lang.Thread.runWith(Thread.java:1596) [2026-03-05T02:33:33.070Z] at java.base/java.lang.Thread.run(Thread.java:1583) [2026-03-05T02:33:33.070Z] STATUS:Failed.`main' threw exception: java.lang.OutOfMemoryError: Java heap space [2026-03-05T02:33:33.070Z] rerun: [2026-03-05T02:33:33.070Z] cd 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\scratch\0' && \ [2026-03-05T02:33:33.070Z] PATH='C:\cygwin64\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0;C:\Windows\System32\OpenSSH;C:\Program Files\dotnet;C:\Users\adoptopenjdk\AppData\Local\Microsoft\WindowsApps;C:\Strawberry\perl\bin;C:\Program Files\Git\cmd;C:\openjdk\jdk-8\bin;C:\apache-ant\apache-ant-1.10.15\bin;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps;c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2026-03-05T02:33:33.070Z] SystemDrive=C: \ [2026-03-05T02:33:33.070Z] SystemRoot='C:\Windows' \ [2026-03-05T02:33:33.070Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-03-05T02:33:33.070Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-03-05T02:33:33.070Z] windir='C:\Windows' \ [2026-03-05T02:33:33.070Z] CLASSPATH='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jvmtest\openjdk\jtreg\lib\javatest.jar;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jvmtest\openjdk\jtreg\lib\jtreg.jar' \ [2026-03-05T02:33:33.070Z] 'c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image\bin\java' \ [2026-03-05T02:33:33.070Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2026-03-05T02:33:33.070Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2026-03-05T02:33:33.070Z] -Dtest.compiler.opts= \ [2026-03-05T02:33:33.070Z] -Dtest.java.opts= \ [2026-03-05T02:33:33.070Z] -Dtest.jdk='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image' \ [2026-03-05T02:33:33.070Z] -Dcompile.jdk='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\j2sdk-image' \ [2026-03-05T02:33:33.070Z] -Dtest.timeout.factor=8.0 \ [2026-03-05T02:33:33.070Z] -Dtest.nativepath='c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2026-03-05T02:33:33.070Z] -Dtest.root='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2026-03-05T02:33:33.070Z] -Dtest.name=gc/stress/gclocker/TestGCLockerWithShenandoah.java#aggressive \ [2026-03-05T02:33:33.070Z] -Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2026-03-05T02:33:33.070Z] -Dtest.file='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker\TestGCLockerWithShenandoah.java' \ [2026-03-05T02:33:33.070Z] -Dtest.main.class=gc.stress.gclocker.TestGCLockerWithShenandoah \ [2026-03-05T02:33:33.070Z] -Dtest.src='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker' \ [2026-03-05T02:33:33.070Z] -Dtest.src.path='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2026-03-05T02:33:33.070Z] -Dtest.classes='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d' \ [2026-03-05T02:33:33.070Z] -Dtest.class.path='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0' \ [2026-03-05T02:33:33.070Z] -Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gclocker;C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\classes\0' \ [2026-03-05T02:33:33.070Z] -ea \ [2026-03-05T02:33:33.070Z] -esa \ [2026-03-05T02:33:33.070Z] -Xmx512m \ [2026-03-05T02:33:33.070Z] '-Djava.library.path=c:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2026-03-05T02:33:33.070Z] -Xlog:gc*=info \ [2026-03-05T02:33:33.070Z] -Xms1500m \ [2026-03-05T02:33:33.070Z] -Xmx1500m \ [2026-03-05T02:33:33.070Z] -XX:+UnlockExperimentalVMOptions \ [2026-03-05T02:33:33.070Z] -XX:+UnlockDiagnosticVMOptions \ [2026-03-05T02:33:33.070Z] -XX:+UseShenandoahGC \ [2026-03-05T02:33:33.070Z] -XX:ShenandoahGCHeuristics=aggressive \ [2026-03-05T02:33:33.070Z] -XX:+ShenandoahAllocFailureALot \ [2026-03-05T02:33:33.070Z] com.sun.javatest.regtest.agent.MainWrapper 'C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work\gc\stress\gclocker\TestGCLockerWithShenandoah_aggressive.d\main.1.jta' [2026-03-05T02:33:33.070Z] [2026-03-05T02:33:33.070Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.OutOfMemoryError: Java heap space [2026-03-05T02:33:33.070Z] -------------------------------------------------- [2026-03-05T03:30:08.743Z] Test results: passed: 530; failed: 1; skipped: 2; excluded: 23; did not match keywords: 1; did not meet platform requirements: 25 [2026-03-05T03:32:32.423Z] Report written to C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\report\html\report.html [2026-03-05T03:32:32.423Z] Results written to C:\jenkins\workspace\Test_openjdk21_hs_extended.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_17726749975704\hotspot_gc_0\work [2026-03-05T03:32:32.423Z] Error: Some tests failed or other problems occurred. [2026-03-05T03:32:32.423Z] ----------------------------------- [2026-03-05T03:32:32.423Z] hotspot_gc_0_FAILED [2026-03-05T03:32:32.423Z] ----------------------------------- [2026-03-05T03:32:32.423Z] [2026-03-05T03:32:32.423Z] TEST TEARDOWN: [2026-03-05T03:32:32.423Z] Nothing to be done for teardown. [2026-03-05T03:32:32.423Z] hotspot_gc_0 Finish Time: Thu Mar 5 03:32:22 2026 Epoch Time (ms): 1772681542580