[2025-11-28T03:44:25.235Z] Running test hotspot_gc_0 ... [2025-11-28T03:44:25.235Z] =============================================== [2025-11-28T03:44:25.236Z] hotspot_gc_0 Start Time: Fri Nov 28 03:44:12 2025 Epoch Time (ms): 1764301452711 [2025-11-28T03:44:25.236Z] variation: NoOptions [2025-11-28T03:44:25.236Z] JVM_OPTIONS: [2025-11-28T03:44:25.236Z] { \ [2025-11-28T03:44:25.236Z] echo ""; echo "TEST SETUP:"; \ [2025-11-28T03:44:25.236Z] echo "Nothing to be done for setup."; \ [2025-11-28T03:44:25.236Z] mkdir -p "C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests/\\TKG\\output_17642919364527\\hotspot_gc_0"; \ [2025-11-28T03:44:25.236Z] cd "C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests/\\TKG\\output_17642919364527\\hotspot_gc_0"; \ [2025-11-28T03:44:25.236Z] echo ""; echo "TESTING:"; \ [2025-11-28T03:44:25.236Z] "c:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/jdkbinary/j2sdk-image\\bin\\java" -Xmx512m -jar "C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests///..//jvmtest\\openjdk\\jtreg\\lib\\jtreg.jar" \ [2025-11-28T03:44:25.236Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:2 -nativepath:"c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/jdkbinary/openjdk-test-image\\hotspot\\jtreg\\native" -vmoptions:"-Xmx512m " \ [2025-11-28T03:44:25.236Z] -w ""C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests/\\TKG\\output_17642919364527\\hotspot_gc_0"\\work" \ [2025-11-28T03:44:25.236Z] -r ""C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests/\\TKG\\output_17642919364527\\hotspot_gc_0"\\report" \ [2025-11-28T03:44:25.236Z] -jdk:"c:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/jdkbinary/j2sdk-image" \ [2025-11-28T03:44:25.236Z] -exclude:"C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests/\\openjdk\\openjdk-jdk\\test\\hotspot\\jtreg\\ProblemList.txt" \ [2025-11-28T03:44:25.236Z] -exclude:"C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests///..//jvmtest\\openjdk\\excludes/ProblemList_openjdk26.txt" \ [2025-11-28T03:44:25.236Z] \ [2025-11-28T03:44:25.236Z] \ [2025-11-28T03:44:25.236Z] "C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests/\\openjdk\\openjdk-jdk\\test\\hotspot\\jtreg:hotspot_gc"; \ [2025-11-28T03:44:25.236Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests/; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-28T03:44:25.236Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-28T03:44:25.236Z] echo "Nothing to be done for teardown."; \ [2025-11-28T03:44:25.236Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2/aqa-tests/\\TKG\\output_17642919364527\\TestTargetResult"; [2025-11-28T03:44:25.236Z] [2025-11-28T03:44:25.236Z] TEST SETUP: [2025-11-28T03:44:25.236Z] Nothing to be done for setup. [2025-11-28T03:44:25.236Z] [2025-11-28T03:44:25.236Z] TESTING: [2025-11-28T03:44:25.236Z] Directory "C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work" not found: creating [2025-11-28T03:44:25.236Z] Directory "C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\report" not found: creating [2025-11-28T03:44:25.236Z] XML output with verification to C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work [2025-11-28T04:46:20.929Z] -------------------------------------------------- [2025-11-28T04:46:20.929Z] TEST: gc/stress/gcbasher/TestGCBasherWithShenandoah.java#generational [2025-11-28T04:46:20.929Z] TEST JDK: c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\j2sdk-image [2025-11-28T04:46:20.929Z] [2025-11-28T04:46:20.929Z] ACTION: build -- Passed. Build successful [2025-11-28T04:46:20.929Z] REASON: Named class compiled on demand [2025-11-28T04:46:20.929Z] TIME: 0.088 seconds [2025-11-28T04:46:20.929Z] messages: [2025-11-28T04:46:20.929Z] command: build gc.stress.gcbasher.TestGCBasherWithShenandoah [2025-11-28T04:46:20.929Z] reason: Named class compiled on demand [2025-11-28T04:46:20.929Z] started: Fri Nov 28 04:45:18.125 UTC 2025 [2025-11-28T04:46:20.929Z] Test directory: [2025-11-28T04:46:20.929Z] compile: gc.stress.gcbasher.TestGCBasherWithShenandoah [2025-11-28T04:46:20.929Z] finished: Fri Nov 28 04:45:18.213 UTC 2025 [2025-11-28T04:46:20.929Z] elapsed time (seconds): 0.088 [2025-11-28T04:46:20.929Z] [2025-11-28T04:46:20.929Z] ACTION: compile -- Passed. Compilation successful [2025-11-28T04:46:20.929Z] REASON: .class file out of date or does not exist [2025-11-28T04:46:20.929Z] TIME: 0.085 seconds [2025-11-28T04:46:20.929Z] messages: [2025-11-28T04:46:20.929Z] command: compile C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher\TestGCBasherWithShenandoah.java [2025-11-28T04:46:20.929Z] reason: .class file out of date or does not exist [2025-11-28T04:46:20.929Z] started: Fri Nov 28 04:45:18.127 UTC 2025 [2025-11-28T04:46:20.929Z] Mode: agentvm [2025-11-28T04:46:20.929Z] Agent id: 2 [2025-11-28T04:46:20.929Z] Process id: 75416 [2025-11-28T04:46:20.929Z] finished: Fri Nov 28 04:45:18.212 UTC 2025 [2025-11-28T04:46:20.929Z] elapsed time (seconds): 0.085 [2025-11-28T04:46:20.929Z] configuration: [2025-11-28T04:46:20.929Z] Boot Layer (javac runtime environment) [2025-11-28T04:46:20.929Z] class path: C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jvmtest\openjdk\jtreg\lib\javatest.jar [2025-11-28T04:46:20.929Z] C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jvmtest\openjdk\jtreg\lib\jtreg.jar [2025-11-28T04:46:20.929Z] patch: java.base C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\patches\java.base [2025-11-28T04:46:20.929Z] [2025-11-28T04:46:20.929Z] javac compilation environment [2025-11-28T04:46:20.929Z] source path: C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher [2025-11-28T04:46:20.929Z] C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg [2025-11-28T04:46:20.929Z] class path: C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher [2025-11-28T04:46:20.929Z] C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d [2025-11-28T04:46:20.929Z] [2025-11-28T04:46:20.929Z] rerun: [2025-11-28T04:46:20.929Z] cd 'C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\scratch\1' && \ [2025-11-28T04:46:20.929Z] 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:\Program Files (x86)\IncrediBuild;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps' \ [2025-11-28T04:46:20.929Z] SystemDrive=C: \ [2025-11-28T04:46:20.929Z] SystemRoot='C:\Windows' \ [2025-11-28T04:46:20.929Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2025-11-28T04:46:20.929Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2025-11-28T04:46:20.929Z] windir='C:\Windows' \ [2025-11-28T04:46:20.929Z] 'c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\j2sdk-image\bin\javac' \ [2025-11-28T04:46:20.929Z] -J-ea \ [2025-11-28T04:46:20.929Z] -J-esa \ [2025-11-28T04:46:20.929Z] -J-Xmx512m \ [2025-11-28T04:46:20.929Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-11-28T04:46:20.929Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-11-28T04:46:20.929Z] -J-Dtest.compiler.opts= \ [2025-11-28T04:46:20.929Z] -J-Dtest.java.opts= \ [2025-11-28T04:46:20.929Z] -J-Dtest.jdk='c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\j2sdk-image' \ [2025-11-28T04:46:20.929Z] -J-Dcompile.jdk='c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\j2sdk-image' \ [2025-11-28T04:46:20.929Z] -J-Dtest.timeout.factor=8.0 \ [2025-11-28T04:46:20.929Z] -J-Dtest.nativepath='c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2025-11-28T04:46:20.929Z] -J-Dtest.root='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2025-11-28T04:46:20.929Z] -J-Dtest.name=gc/stress/gcbasher/TestGCBasherWithShenandoah.java#generational \ [2025-11-28T04:46:20.929Z] -J-Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2025-11-28T04:46:20.929Z] -J-Dtest.file='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher\TestGCBasherWithShenandoah.java' \ [2025-11-28T04:46:20.929Z] -J-Dtest.main.class=gc.stress.gcbasher.TestGCBasherWithShenandoah \ [2025-11-28T04:46:20.929Z] -J-Dtest.src='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher' \ [2025-11-28T04:46:20.929Z] -J-Dtest.src.path='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2025-11-28T04:46:20.929Z] -J-Dtest.classes='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d' \ [2025-11-28T04:46:20.929Z] -J-Dtest.class.path='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d' \ [2025-11-28T04:46:20.929Z] -J-Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher' \ [2025-11-28T04:46:20.929Z] -d 'C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d' \ [2025-11-28T04:46:20.929Z] -sourcepath 'C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2025-11-28T04:46:20.929Z] -classpath 'C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d' 'C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher\TestGCBasherWithShenandoah.java' [2025-11-28T04:46:20.929Z] [2025-11-28T04:46:20.929Z] ACTION: main -- Failed. Unexpected exit from test [exit code: 1] [2025-11-28T04:46:20.929Z] REASON: User specified action: run main/othervm/timeout=200 -Xlog:gc*=info -Xmx1g -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify gc.stress.gcbasher.TestGCBasherWithShenandoah 120000 [2025-11-28T04:46:20.929Z] TIME: 56.215 seconds [2025-11-28T04:46:20.929Z] messages: [2025-11-28T04:46:20.929Z] command: main -Xlog:gc*=info -Xmx1g -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify gc.stress.gcbasher.TestGCBasherWithShenandoah 120000 [2025-11-28T04:46:20.929Z] reason: User specified action: run main/othervm/timeout=200 -Xlog:gc*=info -Xmx1g -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify gc.stress.gcbasher.TestGCBasherWithShenandoah 120000 [2025-11-28T04:46:20.929Z] started: Fri Nov 28 04:45:18.213 UTC 2025 [2025-11-28T04:46:20.929Z] Mode: othervm [/othervm specified] [2025-11-28T04:46:20.929Z] Process id: 38996 [2025-11-28T04:46:20.929Z] finished: Fri Nov 28 04:46:14.428 UTC 2025 [2025-11-28T04:46:20.929Z] elapsed time (seconds): 56.215 [2025-11-28T04:46:20.929Z] configuration: [2025-11-28T04:46:20.929Z] STDOUT: [2025-11-28T04:46:20.929Z] [0.017s][info][gc,init] CardTable entry size: 512 [2025-11-28T04:46:20.929Z] [0.017s][info][gc ] Heuristics ergonomically sets -XX:GCTimeRatio=70 [2025-11-28T04:46:20.929Z] [0.017s][info][gc ] Heuristics ergonomically sets -XX:+ExplicitGCInvokesConcurrent [2025-11-28T04:46:20.929Z] [0.017s][info][gc ] Heuristics ergonomically sets -XX:+ShenandoahImplicitGCInvokesConcurrent [2025-11-28T04:46:20.929Z] [0.017s][info][gc ] Heuristics ergonomically sets -XX:+UseCondCardMark [2025-11-28T04:46:20.929Z] [0.017s][info][gc ] Using Shenandoah [2025-11-28T04:46:20.929Z] [0.018s][info][gc,ref ] Clearing All SoftReferences [2025-11-28T04:46:20.929Z] [0.024s][info][gc,free] Free: 972M, Max: 512K regular, 972M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1945 Collector Reserve: 52736K, Max: 512K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.929Z] [0.024s][info][gc,init] Version: 26-beta+26-ea (release) [2025-11-28T04:46:20.929Z] [0.024s][info][gc,init] CPUs: 2 total, 2 available [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Memory: 8140M [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Large Page Support: Disabled [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] NUMA Support: Disabled [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Compressed Oops: Enabled (32-bit) [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Heap Min Capacity: 6656K [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Heap Initial Capacity: 16896K [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Heap Max Capacity: 1G [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Pre-touch: Disabled [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Heap Region Count: 2048 [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Heap Region Size: 512K [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] TLAB Size Max: 256K [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Soft Max Heap Size: 1G [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Parallel Workers: 1 [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Concurrent Workers: 1 [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Mode: Generational [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Heuristics: Adaptive [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Young Heuristics: Adaptive [2025-11-28T04:46:20.929Z] [0.025s][info][gc,init] Old Heuristics: Old [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] CDS archive(s) mapped at: [0x0000000017000000-0x0000000017de0000-0x0000000017de0000), size 14548992, SharedBaseAddress: 0x0000000017000000, ArchiveRelocationMode: 1. [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] Compressed class space mapped at: 0x0000000018000000-0x0000000058000000, reserved size: 1073741824 [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] UseCompressedClassPointers 1, UseCompactObjectHeaders 0 [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] Narrow klass pointer bits 32, Max shift 3 [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] Narrow klass base: 0x0000000017000000, Narrow klass shift: 0 [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] Encoding Range: [0x0000000017000000 - 0x0000000117000000), (4294967296 bytes) [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] Klass Range: [0x0000000017000000 - 0x0000000058000000), (1090519040 bytes) [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] Klass ID Range: [8 - 1090519033) (1090519025) [2025-11-28T04:46:20.930Z] [0.069s][info][gc,metaspace] Protection zone: [0x0000000017000000 - 0x0000000017010000), (65536 bytes) [2025-11-28T04:46:20.930Z] [1.668s][info][gc ] Trigger (Young): Learning 1 of 5. Free (716M) is below initial threshold (716M) [2025-11-28T04:46:20.930Z] [1.668s][info][gc,free ] Free: 716M, Max: 512K regular, 716M humongous, Frag: 0% external, 0% internal; Used: 435K, Mutator Free: 1434 Collector Reserve: 52736K, Max: 512K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.930Z] [1.668s][info][gc,ergo ] GC(0) Start GC cycle (Young) [2025-11-28T04:46:20.930Z] [1.668s][info][gc,start ] GC(0) Concurrent reset (Young) [2025-11-28T04:46:20.930Z] [1.668s][info][gc,task ] GC(0) Using 1 of 1 workers for Concurrent reset (Young) [2025-11-28T04:46:20.930Z] [1.670s][info][gc ] GC(0) Concurrent reset (Young) 1.888ms [2025-11-28T04:46:20.930Z] [1.670s][info][gc,start ] GC(0) Pause Init Mark (Young) [2025-11-28T04:46:20.930Z] [1.670s][info][gc,task ] GC(0) Using 1 of 1 workers for init marking [2025-11-28T04:46:20.930Z] [1.670s][info][gc,start ] GC(0) Verify Before Mark, Level 4 [2025-11-28T04:46:20.930Z] [1.812s][info][gc ] GC(0) Verify Before Mark, Level 4 (781651 reachable, 0 marked) [2025-11-28T04:46:20.930Z] [1.812s][info][gc ] GC(0) Pause Init Mark (Young) 142.262ms [2025-11-28T04:46:20.930Z] [1.812s][info][gc,start ] GC(0) Concurrent remembered set scanning [2025-11-28T04:46:20.930Z] [1.812s][info][gc,task ] GC(0) Using 1 of 1 workers for Concurrent remembered set scanning [2025-11-28T04:46:20.930Z] [1.812s][info][gc ] GC(0) Concurrent remembered set scanning 0.158ms [2025-11-28T04:46:20.930Z] [1.812s][info][gc,start ] GC(0) Concurrent marking roots [2025-11-28T04:46:20.930Z] [1.812s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent marking roots [2025-11-28T04:46:20.930Z] [1.813s][info][gc ] GC(0) Concurrent marking roots 0.890ms [2025-11-28T04:46:20.930Z] [1.813s][info][gc,start ] GC(0) Concurrent marking (Young) [2025-11-28T04:46:20.930Z] [1.813s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent marking [2025-11-28T04:46:20.930Z] [2.657s][info][gc ] GC(0) Concurrent marking (Young) 843.920ms [2025-11-28T04:46:20.930Z] [2.657s][info][gc,start ] GC(0) Pause Final Mark (Young) [2025-11-28T04:46:20.930Z] [2.657s][info][gc,task ] GC(0) Using 1 of 1 workers for final marking [2025-11-28T04:46:20.930Z] [2.658s][info][gc,age ] GC(0) New tenuring threshold 1 (min 1, max 15) [2025-11-28T04:46:20.930Z] [2.658s][info][gc,ergo ] GC(0) Promotion potential of aged regions with sufficient garbage: 26246K [2025-11-28T04:46:20.930Z] [2.658s][info][gc,ergo ] GC(0) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-11-28T04:46:20.930Z] [2.658s][info][gc,ergo ] GC(0) Adaptive CSet Selection for YOUNG. Max Evacuation: 43690K, Actual Free: 1024K. [2025-11-28T04:46:20.930Z] [2.658s][info][gc,ergo ] GC(0) Collectable Garbage: 229M (99%), Immediate: 1024K (0%), 2 regions, CSet: 228M (99%), 508 regions [2025-11-28T04:46:20.930Z] [2.658s][info][gc,ergo ] GC(0) Evacuation Targets: YOUNG: 26246K, PROMOTE: 0B, OLD: 0B, TOTAL: 26246K [2025-11-28T04:46:20.930Z] [2.658s][info][gc,free ] Free: 411M, Max: 512K regular, 389M humongous, Frag: 6% external, 0% internal; Used: 32792B, Mutator Free: 823 Collector Reserve: 31744K, Max: 512K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.930Z] [2.658s][info][gc,start ] GC(0) Verify Before Evacuation, Level 4 [2025-11-28T04:46:20.930Z] [3.650s][info][gc ] GC(0) Verify Before Evacuation, Level 4 (171475 reachable, 6394891 marked) [2025-11-28T04:46:20.930Z] [3.650s][info][gc ] GC(0) Pause Final Mark (Young) 993.008ms [2025-11-28T04:46:20.930Z] [3.650s][info][gc,start ] GC(0) Concurrent thread roots [2025-11-28T04:46:20.930Z] [3.650s][info][gc,task ] GC(0) Using 1 of 1 workers for Concurrent thread roots [2025-11-28T04:46:20.930Z] [3.650s][info][gc ] GC(0) Concurrent thread roots 0.263ms [2025-11-28T04:46:20.930Z] [3.650s][info][gc,start ] GC(0) Concurrent weak references (Young) [2025-11-28T04:46:20.930Z] [3.650s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent weak references [2025-11-28T04:46:20.930Z] [3.651s][info][gc,ref ] GC(0) Encountered references: Soft: 50, Weak: 156, Final: 0, Phantom: 14 [2025-11-28T04:46:20.930Z] [3.651s][info][gc,ref ] GC(0) Discovered references: Soft: 0, Weak: 70, Final: 0, Phantom: 14 [2025-11-28T04:46:20.930Z] [3.651s][info][gc,ref ] GC(0) Enqueued references: Soft: 0, Weak: 23, Final: 0, Phantom: 0 [2025-11-28T04:46:20.930Z] [3.651s][info][gc ] GC(0) Concurrent weak references (Young) 0.086ms [2025-11-28T04:46:20.930Z] [3.651s][info][gc,start ] GC(0) Concurrent weak roots (Young) [2025-11-28T04:46:20.930Z] [3.651s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent weak root [2025-11-28T04:46:20.930Z] [3.675s][info][gc ] GC(0) Concurrent weak roots (Young) 23.930ms [2025-11-28T04:46:20.930Z] [3.675s][info][gc,start ] GC(0) Concurrent cleanup (Young) [2025-11-28T04:46:20.930Z] [3.675s][info][gc,task ] GC(0) Using 1 of 1 workers for cleanup early. [2025-11-28T04:46:20.930Z] [3.675s][info][gc ] GC(0) Concurrent cleanup (Young) 585M->585M(587M) 0.079ms [2025-11-28T04:46:20.930Z] [3.675s][info][gc,free ] Free: 407M, Max: 512K regular, 389M humongous, Frag: 5% external, 0% internal; Used: 32792B, Mutator Free: 815 Collector Reserve: 31721K, Max: 512K; Used: 22704B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.930Z] [3.675s][info][gc,start ] GC(0) Concurrent strong roots [2025-11-28T04:46:20.930Z] [3.675s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent strong root [2025-11-28T04:46:20.930Z] [3.675s][info][gc ] GC(0) Concurrent strong roots 0.668ms [2025-11-28T04:46:20.930Z] [3.675s][info][gc,start ] GC(0) Concurrent evacuation [2025-11-28T04:46:20.930Z] [3.675s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent evacuation [2025-11-28T04:46:20.930Z] [3.776s][info][gc ] GC(0) Concurrent evacuation 101.084ms [2025-11-28T04:46:20.930Z] [3.777s][info][gc,start ] GC(0) Concurrent Init Update Refs (Young) [2025-11-28T04:46:20.930Z] [3.777s][info][gc ] GC(0) Concurrent Init Update Refs (Young) 0.153ms [2025-11-28T04:46:20.930Z] [3.777s][info][gc,start ] GC(0) Pause Init Update Refs [2025-11-28T04:46:20.930Z] [3.777s][info][gc,start ] GC(0) Verify Before Updating References, Level 4 [2025-11-28T04:46:20.930Z] [5.048s][info][gc ] GC(0) Verify Before Updating References, Level 4 (182630 reachable, 8076140 marked) [2025-11-28T04:46:20.930Z] [5.048s][info][gc ] GC(0) Pause Init Update Refs 1271.240ms [2025-11-28T04:46:20.930Z] [5.048s][info][gc,start ] GC(0) Concurrent update references [2025-11-28T04:46:20.930Z] [5.048s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent reference update [2025-11-28T04:46:20.930Z] [5.048s][info][gc,ergo ] GC(0) At start of update refs, moving 5120K to Mutator free set from Collector Reserve (5120K) and from Old Collector Reserve (0B) [2025-11-28T04:46:20.930Z] [5.259s][info][gc ] GC(0) Concurrent update references 210.653ms [2025-11-28T04:46:20.930Z] [5.259s][info][gc,start ] GC(0) Concurrent update thread roots [2025-11-28T04:46:20.930Z] [5.259s][info][gc ] GC(0) Concurrent update thread roots 0.210ms [2025-11-28T04:46:20.930Z] [5.259s][info][gc,start ] GC(0) Pause Final Update Refs [2025-11-28T04:46:20.930Z] [5.259s][info][gc,task ] GC(0) Using 1 of 1 workers for final reference update [2025-11-28T04:46:20.930Z] [5.260s][info][gc,start ] GC(0) Verify After Updating References, Level 4 [2025-11-28T04:46:20.930Z] [6.365s][info][gc ] GC(0) Verify After Updating References, Level 4 (198273 reachable, 8669564 marked) [2025-11-28T04:46:20.930Z] [6.366s][info][gc,free ] Free: 457M, Max: 512K regular, 394M humongous, Frag: 14% external, 0% internal; Used: 0B, Mutator Free: 915 Collector Reserve: 51200K, Max: 512K; Used: 51183K Old Collector Reserve: 31744K, Max: 512K; Used: 31737K [2025-11-28T04:46:20.930Z] [6.366s][info][gc ] GC(0) Pause Final Update Refs 1106.556ms [2025-11-28T04:46:20.930Z] [6.366s][info][gc,start ] GC(0) Concurrent cleanup (Young) [2025-11-28T04:46:20.930Z] [6.366s][info][gc,task ] GC(0) Using 1 of 1 workers for cleanup complete. [2025-11-28T04:46:20.930Z] [6.366s][info][gc ] GC(0) Concurrent cleanup (Young) 485M->485M(739M) 0.095ms [2025-11-28T04:46:20.930Z] [6.366s][info][gc,start ] GC(0) Concurrent reset after collect (Young) [2025-11-28T04:46:20.930Z] [6.366s][info][gc,task ] GC(0) Using 1 of 1 workers for reset after collection. [2025-11-28T04:46:20.930Z] [6.367s][info][gc ] GC(0) Concurrent reset after collect (Young) 1.190ms [2025-11-28T04:46:20.930Z] [6.367s][info][gc,ergo ] GC(0) At end of Concurrent Young GC: GCU: 30.4%, MU: 31.0% during period of 6.367s [2025-11-28T04:46:20.930Z] [6.367s][info][gc,ergo ] GC(0) At end of Concurrent Young GC: Young generation used: 485M, used regions: 485M, humongous waste: 0B, soft capacity: 1024M, max capacity: 993M, available: 457M [2025-11-28T04:46:20.930Z] [6.367s][info][gc,ergo ] GC(0) At end of Concurrent Young GC: Old generation used: 0B, used regions: 0B, humongous waste: 0B, soft capacity: 1024M, max capacity: 31744K, available: 31744K [2025-11-28T04:46:20.930Z] [6.367s][info][gc,free ] Free: 457M, Max: 512K regular, 394M humongous, Frag: 14% external, 0% internal; Used: 0B, Mutator Free: 915 Collector Reserve: 51200K, Max: 512K; Used: 0B Old Collector Reserve: 31744K, Max: 512K; Used: 0B [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Concurrent Reset 1921 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Concurrent Reset After Collect 1214 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Pause Init Mark (G) 142400 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Pause Init Mark (N) 142284 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Verify 142140 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Swap Remembered Set 2 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Update Region States 72 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Propagate GC State 1 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Concurrent Scan Remembered Set 167 us, parallelism: 0.78x [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] RS: <total> 131 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] RS: Scan Clusters 131 us, workers (us): 131, [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Concurrent Mark Roots 913 us, parallelism: 0.92x [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] CMR: <total> 841 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] CMR: Thread Roots 91 us, workers (us): 91, [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] CMR: Code Cache Roots 592 us, workers (us): 592, [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] CMR: VM Strong Roots 31 us, workers (us): 31, [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] CMR: CLDG Roots 128 us, workers (us): 128, [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Concurrent Marking 843955 us, parallelism: 0.14x [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] CM: <total> 117661 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] CM: Parallel Mark 117661 us, workers (us): 117661, [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Flush SATB 87 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Pause Final Mark (G) 993106 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Pause Final Mark (N) 993020 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Verify 991901 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Finish Mark 51 us, parallelism: 0.02x [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Propagate GC State 1 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Update Region States 67 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Choose Collection Set 189 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Rebuild Free Set 91 us [2025-11-28T04:46:20.930Z] [6.367s][info][gc,stats ] Concurrent Thread Roots 280 us, parallelism: 0.82x [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CTR: <total> 230 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CTR: Thread Roots 230 us, workers (us): 230, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Weak References 93 us, parallelism: 0.41x [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CWRF: <total> 38 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CWRF: Weak References 38 us, workers (us): 38, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Weak Roots 23959 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Roots 196 us, parallelism: 0.40x [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CWR: <total> 78 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CWR: VM Weak Roots 78 us, workers (us): 78, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Rendezvous 23716 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Cleanup 88 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Strong Roots 695 us, parallelism: 0.88x [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CSR: <total> 609 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CSR: Code Cache Roots 520 us, workers (us): 520, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CSR: VM Strong Roots 18 us, workers (us): 18, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] CSR: CLDG Roots 71 us, workers (us): 71, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Evacuation 101139 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Pause Init Update Refs (G) 1271323 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Pause Init Update Refs (N) 1271251 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Verify 1271217 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Update Refs Prepare 170 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Update Refs 210700 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Update Thread Roots 224 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Pause Final Update Refs (G) 1106632 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Pause Final Update Refs (N) 1106564 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Verify 1105570 us, workers (us): ---, [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Update Region States 57 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Trash Collection Set 7 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Rebuild Free Set 188 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Propagate GC State 0 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,stats ] Concurrent Cleanup 104 us [2025-11-28T04:46:20.930Z] [6.408s][info][gc,metaspace] GC(0) Metaspace: 1237K(1408K)->1237K(1408K) NonClass: 1150K(1216K)->1150K(1216K) Class: 86K(192K)->86K(192K) [2025-11-28T04:46:20.930Z] [6.410s][info][gc ] Trigger (Young): Learning 2 of 5. Free (456M) is below initial threshold (716M) [2025-11-28T04:46:20.930Z] [6.410s][info][gc,free ] Free: 456M, Max: 512K regular, 394M humongous, Frag: 14% external, 0% internal; Used: 0B, Mutator Free: 913 Collector Reserve: 51200K, Max: 512K; Used: 0B Old Collector Reserve: 31744K, Max: 512K; Used: 0B [2025-11-28T04:46:20.930Z] [6.410s][info][gc,ergo ] GC(1) Start GC cycle (Young) [2025-11-28T04:46:20.930Z] [6.410s][info][gc,start ] GC(1) Concurrent reset (Young) [2025-11-28T04:46:20.930Z] [6.410s][info][gc,task ] GC(1) Using 1 of 1 workers for Concurrent reset (Young) [2025-11-28T04:46:20.930Z] [6.412s][info][gc ] GC(1) Concurrent reset (Young) 2.083ms [2025-11-28T04:46:20.930Z] [6.412s][info][gc,start ] GC(1) Pause Init Mark (Young) [2025-11-28T04:46:20.930Z] [6.412s][info][gc,task ] GC(1) Using 1 of 1 workers for init marking [2025-11-28T04:46:20.930Z] [6.412s][info][gc,start ] GC(1) Verify Before Mark, Level 4 [2025-11-28T04:46:20.930Z] [6.455s][info][gc ] GC(1) Verify Before Mark, Level 4 (197821 reachable, 0 marked) [2025-11-28T04:46:20.930Z] [6.455s][info][gc ] GC(1) Pause Init Mark (Young) 42.945ms [2025-11-28T04:46:20.930Z] [6.455s][info][gc,start ] GC(1) Concurrent remembered set scanning [2025-11-28T04:46:20.930Z] [6.455s][info][gc,task ] GC(1) Using 1 of 1 workers for Concurrent remembered set scanning [2025-11-28T04:46:20.930Z] [6.455s][info][gc ] GC(1) Concurrent remembered set scanning 0.139ms [2025-11-28T04:46:20.930Z] [6.455s][info][gc,start ] GC(1) Concurrent marking roots [2025-11-28T04:46:20.930Z] [6.455s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent marking roots [2025-11-28T04:46:20.930Z] [6.456s][info][gc ] GC(1) Concurrent marking roots 0.823ms [2025-11-28T04:46:20.930Z] [6.456s][info][gc,start ] GC(1) Concurrent marking (Young) [2025-11-28T04:46:20.930Z] [6.456s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent marking [2025-11-28T04:46:20.930Z] [6.750s][info][gc ] GC(1) Concurrent marking (Young) 294.177ms [2025-11-28T04:46:20.930Z] [6.750s][info][gc,start ] GC(1) Pause Final Mark (Young) [2025-11-28T04:46:20.930Z] [6.750s][info][gc,task ] GC(1) Using 1 of 1 workers for final marking [2025-11-28T04:46:20.930Z] [6.751s][info][gc,age ] GC(1) New tenuring threshold 2 (min 1, max 15) [2025-11-28T04:46:20.930Z] [6.751s][info][gc,ergo ] GC(1) Promotion potential of aged regions with sufficient garbage: 0B [2025-11-28T04:46:20.930Z] [6.751s][info][gc,ergo ] GC(1) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-11-28T04:46:20.930Z] [6.751s][info][gc,ergo ] GC(1) Adaptive CSet Selection for YOUNG. Max Evacuation: 42367K, Actual Free: 2558K. [2025-11-28T04:46:20.930Z] [6.751s][info][gc,ergo ] GC(1) Collectable Garbage: 398M (95%), Immediate: 2558K (0%), 5 regions, CSet: 396M (95%), 875 regions [2025-11-28T04:46:20.930Z] [6.751s][info][gc,ergo ] GC(1) Evacuation Targets: YOUNG: 42356K, PROMOTE: 0B, OLD: 0B, TOTAL: 42356K [2025-11-28T04:46:20.930Z] [6.751s][info][gc,free ] Free: 384M, Max: 512K regular, 351M humongous, Frag: 9% external, 0% internal; Used: 0B, Mutator Free: 769 Collector Reserve: 51200K, Max: 512K; Used: 512K Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.930Z] [6.751s][info][gc,start ] GC(1) Verify Before Evacuation, Level 4 [2025-11-28T04:46:20.930Z] [7.431s][info][gc ] GC(1) Verify Before Evacuation, Level 4 (219718 reachable, 3779957 marked) [2025-11-28T04:46:20.930Z] [7.431s][info][gc ] GC(1) Pause Final Mark (Young) 680.559ms [2025-11-28T04:46:20.930Z] [7.431s][info][gc,start ] GC(1) Concurrent thread roots [2025-11-28T04:46:20.930Z] [7.431s][info][gc,task ] GC(1) Using 1 of 1 workers for Concurrent thread roots [2025-11-28T04:46:20.930Z] [7.431s][info][gc ] GC(1) Concurrent thread roots 0.109ms [2025-11-28T04:46:20.930Z] [7.431s][info][gc,start ] GC(1) Concurrent weak references (Young) [2025-11-28T04:46:20.930Z] [7.431s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent weak references [2025-11-28T04:46:20.930Z] [7.431s][info][gc,ref ] GC(1) Encountered references: Soft: 50, Weak: 133, Final: 0, Phantom: 14 [2025-11-28T04:46:20.930Z] [7.431s][info][gc,ref ] GC(1) Discovered references: Soft: 0, Weak: 47, Final: 0, Phantom: 14 [2025-11-28T04:46:20.930Z] [7.431s][info][gc,ref ] GC(1) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.930Z] [7.431s][info][gc ] GC(1) Concurrent weak references (Young) 0.042ms [2025-11-28T04:46:20.930Z] [7.431s][info][gc,start ] GC(1) Concurrent weak roots (Young) [2025-11-28T04:46:20.930Z] [7.431s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent weak root [2025-11-28T04:46:20.930Z] [7.614s][info][gc ] GC(1) Concurrent weak roots (Young) 182.568ms [2025-11-28T04:46:20.930Z] [7.614s][info][gc,start ] GC(1) Concurrent cleanup (Young) [2025-11-28T04:46:20.930Z] [7.614s][info][gc,task ] GC(1) Using 1 of 1 workers for cleanup early. [2025-11-28T04:46:20.930Z] [7.614s][info][gc ] GC(1) Concurrent cleanup (Young) 694M->694M(855M) 0.099ms [2025-11-28T04:46:20.930Z] [7.614s][info][gc,free ] Free: 280M, Max: 512K regular, 278M humongous, Frag: 1% external, 0% internal; Used: 0B, Mutator Free: 560 Collector Reserve: 51183K, Max: 512K; Used: 16512B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.930Z] [7.614s][info][gc,start ] GC(1) Concurrent strong roots [2025-11-28T04:46:20.930Z] [7.614s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent strong root [2025-11-28T04:46:20.930Z] [7.614s][info][gc ] GC(1) Concurrent strong roots 0.584ms [2025-11-28T04:46:20.930Z] [7.614s][info][gc,start ] GC(1) Concurrent evacuation [2025-11-28T04:46:20.930Z] [7.614s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent evacuation [2025-11-28T04:46:20.930Z] [7.747s][info][gc ] GC(1) Concurrent evacuation 133.065ms [2025-11-28T04:46:20.930Z] [7.747s][info][gc,start ] GC(1) Concurrent Init Update Refs (Young) [2025-11-28T04:46:20.930Z] [7.748s][info][gc ] GC(1) Concurrent Init Update Refs (Young) 0.129ms [2025-11-28T04:46:20.930Z] [7.748s][info][gc,start ] GC(1) Pause Init Update Refs [2025-11-28T04:46:20.930Z] [7.748s][info][gc,start ] GC(1) Verify Before Updating References, Level 4 [2025-11-28T04:46:20.930Z] [9.076s][info][gc ] GC(1) Verify Before Updating References, Level 4 (226810 reachable, 8565567 marked) [2025-11-28T04:46:20.930Z] [9.076s][info][gc ] GC(1) Pause Init Update Refs 1328.405ms [2025-11-28T04:46:20.930Z] [9.076s][info][gc,start ] GC(1) Concurrent update references [2025-11-28T04:46:20.930Z] [9.076s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent reference update [2025-11-28T04:46:20.930Z] [9.076s][info][gc,ergo ] GC(1) At start of update refs, moving 8704K to Mutator free set from Collector Reserve (8704K) and from Old Collector Reserve (0B) [2025-11-28T04:46:20.930Z] [9.263s][info][gc ] GC(1) Concurrent update references 187.200ms [2025-11-28T04:46:20.930Z] [9.263s][info][gc,start ] GC(1) Concurrent update thread roots [2025-11-28T04:46:20.930Z] [9.276s][info][gc ] GC(1) Concurrent update thread roots 12.167ms [2025-11-28T04:46:20.930Z] [9.276s][info][gc,start ] GC(1) Pause Final Update Refs [2025-11-28T04:46:20.930Z] [9.276s][info][gc,task ] GC(1) Using 1 of 1 workers for final reference update [2025-11-28T04:46:20.930Z] [9.276s][info][gc,start ] GC(1) Verify After Updating References, Level 4 [2025-11-28T04:46:20.930Z] [10.345s][info][gc ] GC(1) Verify After Updating References, Level 4 (229712 reachable, 8112664 marked) [2025-11-28T04:46:20.930Z] [10.345s][info][gc,free ] Free: 565M, Max: 512K regular, 179M humongous, Frag: 69% external, 0% internal; Used: 0B, Mutator Free: 1130 Collector Reserve: 52736K, Max: 512K; Used: 52729K Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.930Z] [10.345s][info][gc ] GC(1) Pause Final Update Refs 1069.301ms [2025-11-28T04:46:20.930Z] [10.345s][info][gc,start ] GC(1) Concurrent cleanup (Young) [2025-11-28T04:46:20.930Z] [10.345s][info][gc,task ] GC(1) Using 1 of 1 workers for cleanup complete. [2025-11-28T04:46:20.930Z] [10.345s][info][gc ] GC(1) Concurrent cleanup (Young) 407M->407M(954M) 0.132ms [2025-11-28T04:46:20.930Z] [10.345s][info][gc,start ] GC(1) Concurrent reset after collect (Young) [2025-11-28T04:46:20.930Z] [10.345s][info][gc,task ] GC(1) Using 1 of 1 workers for reset after collection. [2025-11-28T04:46:20.930Z] [10.347s][info][gc ] GC(1) Concurrent reset after collect (Young) 2.199ms [2025-11-28T04:46:20.930Z] [10.347s][info][gc,ergo ] GC(1) At end of Concurrent Young GC: GCU: 46.3%, MU: 9.2% during period of 3.980s [2025-11-28T04:46:20.930Z] [10.347s][info][gc,ergo ] GC(1) At end of Concurrent Young GC: Young generation used: 407M, used regions: 407M, humongous waste: 0B, soft capacity: 1024M, max capacity: 1024M, available: 565M [2025-11-28T04:46:20.930Z] [10.347s][info][gc,ergo ] GC(1) At end of Concurrent Young GC: Old generation used: 0B, used regions: 0B, humongous waste: 0B, soft capacity: 1024M, max capacity: 0B, available: 0B [2025-11-28T04:46:20.930Z] [10.347s][info][gc,free ] Free: 565M, Max: 512K regular, 179M humongous, Frag: 69% external, 0% internal; Used: 0B, Mutator Free: 1130 Collector Reserve: 52736K, Max: 512K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Concurrent Reset 2104 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Concurrent Reset After Collect 2230 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Pause Init Mark (G) 43089 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Pause Init Mark (N) 42956 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Verify 42848 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Swap Remembered Set 3 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Update Region States 63 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Propagate GC State 1 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] Concurrent Scan Remembered Set 146 us, parallelism: 0.77x [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] RS: <total> 112 us [2025-11-28T04:46:20.930Z] [10.348s][info][gc,stats ] RS: Scan Clusters 112 us, workers (us): 112, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Mark Roots 841 us, parallelism: 0.93x [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CMR: <total> 782 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CMR: Thread Roots 87 us, workers (us): 87, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CMR: Code Cache Roots 605 us, workers (us): 605, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CMR: VM Strong Roots 19 us, workers (us): 19, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CMR: CLDG Roots 71 us, workers (us): 71, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Marking 294220 us, parallelism: 1.00x [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CM: <total> 293953 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CM: Parallel Mark 293953 us, workers (us): 293953, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Flush SATB 157 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Pause Final Mark (G) 680645 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Pause Final Mark (N) 680567 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Verify 679410 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Finish Mark 30 us, parallelism: 0.05x [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Propagate GC State 1 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Update Region States 72 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Choose Collection Set 247 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Rebuild Free Set 94 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Thread Roots 116 us, parallelism: 0.74x [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CTR: <total> 86 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CTR: Thread Roots 86 us, workers (us): 86, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Weak References 48 us, parallelism: 0.16x [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CWRF: <total> 8 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CWRF: Weak References 8 us, workers (us): 8, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Weak Roots 182611 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Roots 47 us, parallelism: 0.71x [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CWR: <total> 33 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CWR: VM Weak Roots 33 us, workers (us): 33, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Rendezvous 182505 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Cleanup 109 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Strong Roots 613 us, parallelism: 0.86x [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CSR: <total> 529 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CSR: Code Cache Roots 509 us, workers (us): 509, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CSR: VM Strong Roots 4 us, workers (us): 4, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] CSR: CLDG Roots 17 us, workers (us): 17, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Evacuation 133118 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Pause Init Update Refs (G) 1328495 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Pause Init Update Refs (N) 1328416 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Verify 1328383 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Update Refs Prepare 146 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Update Refs 187248 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Update Thread Roots 12203 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Pause Final Update Refs (G) 1069409 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Pause Final Update Refs (N) 1069311 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Verify 1068237 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Update Region States 65 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Trash Collection Set 10 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Rebuild Free Set 214 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Propagate GC State 0 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,stats ] Concurrent Cleanup 145 us [2025-11-28T04:46:20.931Z] [10.348s][info][gc,metaspace] GC(1) Metaspace: 1237K(1408K)->1237K(1408K) NonClass: 1150K(1216K)->1151K(1216K) Class: 86K(192K)->86K(192K) [2025-11-28T04:46:20.931Z] [10.350s][info][gc ] Trigger (Young): Learning 3 of 5. Free (563M) is below initial threshold (716M) [2025-11-28T04:46:20.931Z] [10.351s][info][gc,free ] Free: 563M, Max: 512K regular, 179M humongous, Frag: 69% external, 0% internal; Used: 0B, Mutator Free: 1127 Collector Reserve: 52736K, Max: 512K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.931Z] [10.351s][info][gc,ergo ] GC(2) Start GC cycle (Young) [2025-11-28T04:46:20.931Z] [10.351s][info][gc,start ] GC(2) Concurrent reset (Young) [2025-11-28T04:46:20.931Z] [10.351s][info][gc,task ] GC(2) Using 1 of 1 workers for Concurrent reset (Young) [2025-11-28T04:46:20.931Z] [10.351s][info][gc ] GC(2) Concurrent reset (Young) 0.404ms [2025-11-28T04:46:20.931Z] [10.351s][info][gc,start ] GC(2) Pause Init Mark (Young) [2025-11-28T04:46:20.931Z] [10.351s][info][gc,task ] GC(2) Using 1 of 1 workers for init marking [2025-11-28T04:46:20.931Z] [10.351s][info][gc,start ] GC(2) Verify Before Mark, Level 4 [2025-11-28T04:46:20.931Z] [10.396s][info][gc ] GC(2) Verify Before Mark, Level 4 (229229 reachable, 0 marked) [2025-11-28T04:46:20.931Z] [10.396s][info][gc ] GC(2) Pause Init Mark (Young) 45.205ms [2025-11-28T04:46:20.931Z] [10.396s][info][gc,start ] GC(2) Concurrent remembered set scanning [2025-11-28T04:46:20.931Z] [10.396s][info][gc,task ] GC(2) Using 1 of 1 workers for Concurrent remembered set scanning [2025-11-28T04:46:20.931Z] [10.397s][info][gc ] GC(2) Concurrent remembered set scanning 0.198ms [2025-11-28T04:46:20.931Z] [10.397s][info][gc,start ] GC(2) Concurrent marking roots [2025-11-28T04:46:20.931Z] [10.397s][info][gc,task ] GC(2) Using 1 of 1 workers for concurrent marking roots [2025-11-28T04:46:20.931Z] [10.397s][info][gc ] GC(2) Concurrent marking roots 0.859ms [2025-11-28T04:46:20.931Z] [10.397s][info][gc,start ] GC(2) Concurrent marking (Young) [2025-11-28T04:46:20.931Z] [10.397s][info][gc,task ] GC(2) Using 1 of 1 workers for concurrent marking [2025-11-28T04:46:20.931Z] [10.442s][info][gc ] GC(2) Concurrent marking (Young) 44.077ms [2025-11-28T04:46:20.931Z] [10.442s][info][gc,start ] GC(2) Pause Final Mark (Young) [2025-11-28T04:46:20.931Z] [10.442s][info][gc,task ] GC(2) Using 1 of 1 workers for final marking [2025-11-28T04:46:20.931Z] [10.442s][info][gc,age ] GC(2) New tenuring threshold 3 (min 1, max 15) [2025-11-28T04:46:20.931Z] [10.442s][info][gc,ergo ] GC(2) Promotion potential of aged regions with sufficient garbage: 0B [2025-11-28T04:46:20.931Z] [10.442s][info][gc,ergo ] GC(2) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-11-28T04:46:20.931Z] [10.443s][info][gc,ergo ] GC(2) Adaptive CSet Selection for YOUNG. Max Evacuation: 43690K, Actual Free: 273M. [2025-11-28T04:46:20.931Z] [10.443s][info][gc,ergo ] GC(2) Collectable Garbage: 396M (99%), Immediate: 273M (68%), 547 regions, CSet: 122M (30%), 266 regions [2025-11-28T04:46:20.931Z] [10.443s][info][gc,ergo ] GC(2) Evacuation Targets: YOUNG: 10491K, PROMOTE: 0B, OLD: 0B, TOTAL: 10491K [2025-11-28T04:46:20.931Z] [10.443s][info][gc,free ] Free: 875M, Max: 512K regular, 179M humongous, Frag: 80% external, 0% internal; Used: 256K, Mutator Free: 1751 Collector Reserve: 12800K, Max: 512K; Used: 4096K Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-11-28T04:46:20.931Z] [10.443s][info][gc,start ] GC(2) Verify Before Evacuation, Level 4 [2025-11-28T04:46:20.931Z] [10.514s][info][gc ] GC(2) Verify Before Evacuation, Level 4 (229229 reachable, 79264 marked) [2025-11-28T04:46:20.931Z] [10.514s][info][gc ] GC(2) Pause Final Mark (Young) 71.939ms [2025-11-28T04:46:20.931Z] [10.514s][info][gc,start ] GC(2) Concurrent thread roots [2025-11-28T04:46:20.931Z] [10.514s][info][gc,task ] GC(2) Using 1 of 1 workers for Concurrent thread roots [2025-11-28T04:46:20.931Z] [10.514s][info][gc ] GC(2) Concurrent thread roots 0.140ms [2025-11-28T04:46:20.931Z] [10.514s][info][gc,start ] GC(2) Concurrent weak references (Young) [2025-11-28T04:46:20.931Z] [10.514s][info][gc,task ] GC(2) Using 1 of 1 workers for concurrent weak references [2025-11-28T04:46:20.931Z] [10.514s][info][gc,ref ] GC(2) Encountered references: Soft: 50, Weak: 133, Final: 0, Phantom: 14 [2025-11-28T04:46:20.931Z] [10.514s][info][gc,ref ] GC(2) Discovered references: Soft: 0, Weak: 47, Final: 0, Phantom: 14 [2025-11-28T04:46:20.931Z] [10.514s][info][gc,ref ] GC(2) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.931Z] [10.514s][info][gc ] GC(2) Concurrent weak references (Young) 0.046ms [2025-11-28T04:46:20.931Z] [10.514s][info][gc,start ] GC(2) Concurrent weak roots (Young) [2025-11-28T04:46:20.931Z] [10.514s][info][gc,task ] GC(2) Using 1 of 1 workers for concurrent weak root [2025-11-28T04:46:20.931Z] [10.516s][info][gc ] GC(2) Concurrent weak roots (Young) 1.728ms [2025-11-28T04:46:20.931Z] [10.516s][info][gc,start ] GC(2) Concurrent cleanup (Young) [2025-11-28T04:46:20.931Z] [10.516s][info][gc,task ] GC(2) Using 1 of 1 workers for cleanup early. [2025-11-28T04:46:20.931Z] [10.516s][info][gc ] GC(2) Concurrent cleanup (Young) 137M->137M(954M) 0.174ms [2025-11-28T04:46:20.931Z] [10.516s][info][gc,free ] Free: 874M, Max: 512K regular, 179M humongous, Frag: 80% external, 0% internal; Used: 25 [2025-11-28T04:46:20.931Z] [2025-11-28T04:46:20.931Z] ... [2025-11-28T04:46:20.931Z] Output overflow: [2025-11-28T04:46:20.931Z] JT Harness has limited the test output to the text [2025-11-28T04:46:20.931Z] at the beginning and the end, so that you can see how the [2025-11-28T04:46:20.931Z] test began, and how it completed. [2025-11-28T04:46:20.931Z] [2025-11-28T04:46:20.931Z] If you need to see more of the output from the test, [2025-11-28T04:46:20.931Z] set the system property javatest.maxOutputSize to a higher [2025-11-28T04:46:20.931Z] value. The current value is 100000 [2025-11-28T04:46:20.931Z] ... [2025-11-28T04:46:20.931Z] [2025-11-28T04:46:20.931Z] us): 90551, [2025-11-28T04:46:20.931Z] [36.467s][info][gc,stats ] Flush SATB 2579 us [2025-11-28T04:46:20.931Z] [36.467s][info][gc,stats ] Pause Final Mark (G) 141308 us [2025-11-28T04:46:20.931Z] [36.467s][info][gc,stats ] Pause Final Mark (N) 141225 us [2025-11-28T04:46:20.931Z] [36.467s][info][gc,stats ] Verify 137601 us [2025-11-28T04:46:20.931Z] [36.467s][info][gc,stats ] Finish Mark 46 us, parallelism: 0.03x [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Propagate GC State 2 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Update Region States 93 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Choose Collection Set 111 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Rebuild Free Set 126 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Thread Roots 130 us, parallelism: 0.76x [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CTR: <total> 98 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CTR: Thread Roots 98 us, workers (us): 98, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Weak References 44 us, parallelism: 0.01x [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CWRF: <total> 1 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CWRF: Weak References 1 us, workers (us): 1, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Weak Roots 1515 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Roots 36 us, parallelism: 0.61x [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CWR: <total> 22 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CWR: VM Weak Roots 22 us, workers (us): 22, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Rendezvous 1433 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Cleanup 83 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Strong Roots 604 us, parallelism: 0.92x [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CSR: <total> 554 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CSR: Code Cache Roots 532 us, workers (us): 532, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CSR: VM Strong Roots 4 us, workers (us): 4, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] CSR: CLDG Roots 19 us, workers (us): 19, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Evacuation 123706 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Pause Init Update Refs (G) 618814 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Pause Init Update Refs (N) 618745 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Verify 618717 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Update Refs Prepare 1522 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Update Refs 132429 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Update Thread Roots 2757 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Pause Final Update Refs (G) 85912 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Pause Final Update Refs (N) 85789 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Verify 81921 us, workers (us): ---, [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Update Region States 85 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Trash Collection Set 5 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Rebuild Free Set 156 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Propagate GC State 0 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,stats ] Concurrent Cleanup 70 us [2025-11-28T04:46:20.931Z] [36.470s][info][gc,metaspace] GC(16) Metaspace: 1242K(1408K)->1242K(1408K) NonClass: 1155K(1216K)->1155K(1216K) Class: 86K(192K)->86K(192K) [2025-11-28T04:46:20.931Z] [36.471s][info][gc ] Trigger (Old): Resume preparing to mark old [2025-11-28T04:46:20.931Z] [36.472s][info][gc,free ] Free: 868M, Max: 512K regular, 789M humongous, Frag: 10% external, 0% internal; Used: 856K, Mutator Free: 1739 Collector Reserve: 51712K, Max: 512K; Used: 0B Old Collector Reserve: 512K, Max: 512K; Used: 0B [2025-11-28T04:46:20.931Z] [36.472s][info][gc,start ] GC(17) Coalescing and filling (Old) [2025-11-28T04:46:20.931Z] [36.472s][info][gc,task ] GC(17) Using 1 of 1 workers for Coalescing and filling (Old) [2025-11-28T04:46:20.931Z] [36.482s][info][gc ] GC(17) Coalescing and filling (Old) 10.509ms [2025-11-28T04:46:20.931Z] [36.482s][info][gc,free ] Free: 868M, Max: 512K regular, 789M humongous, Frag: 10% external, 0% internal; Used: 856K, Mutator Free: 1739 Collector Reserve: 51712K, Max: 512K; Used: 0B Old Collector Reserve: 512K, Max: 512K; Used: 0B [2025-11-28T04:46:20.931Z] [36.482s][info][gc,stats ] [2025-11-28T04:46:20.931Z] [36.482s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-11-28T04:46:20.931Z] [36.482s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-11-28T04:46:20.931Z] [36.482s][info][gc,stats ] [2025-11-28T04:46:20.931Z] [36.482s][info][gc,stats ] Concurrent Coalesce and Fill 10531 us, parallelism: 0.99x [2025-11-28T04:46:20.931Z] [36.482s][info][gc,stats ] CC&F: <total> 10460 us [2025-11-28T04:46:20.931Z] [36.482s][info][gc,stats ] CC&F: Scan Clusters 10460 us, workers (us): 10460, [2025-11-28T04:46:20.931Z] [36.482s][info][gc,metaspace] GC(17) Metaspace: 1242K(1408K)->1242K(1408K) NonClass: 1155K(1216K)->1155K(1216K) Class: 86K(192K)->86K(192K) [2025-11-28T04:46:20.931Z] [36.567s][info][gc ] Trigger (Young): Average GC time (2737.93 ms) is above the time for average allocation rate (465 MB/s) to deplete free headroom (728M) (margin of error = 1.78) [2025-11-28T04:46:20.931Z] [36.568s][info][gc,ergo ] Free headroom: 779M (free) - 52428K (spike) - 0B (penalties) = 728M [2025-11-28T04:46:20.931Z] [36.568s][info][gc,free ] Free: 779M, Max: 512K regular, 779M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1559 Collector Reserve: 51712K, Max: 512K; Used: 0B Old Collector Reserve: 512K, Max: 512K; Used: 0B [2025-11-28T04:46:20.931Z] [36.568s][info][gc,ergo ] GC(18) Start GC cycle (Young) [2025-11-28T04:46:20.931Z] [36.568s][info][gc,start ] GC(18) Concurrent reset (Young) [2025-11-28T04:46:20.931Z] [36.568s][info][gc,task ] GC(18) Using 1 of 1 workers for Concurrent reset (Young) [2025-11-28T04:46:20.931Z] [36.568s][info][gc ] GC(18) Concurrent reset (Young) 0.387ms [2025-11-28T04:46:20.931Z] [36.568s][info][gc,start ] GC(18) Pause Init Mark (Young) [2025-11-28T04:46:20.931Z] [36.568s][info][gc,task ] GC(18) Using 1 of 1 workers for init marking [2025-11-28T04:46:20.931Z] [36.568s][info][gc,start ] GC(18) Verify Before Mark, Level 4 [2025-11-28T04:46:20.931Z] [37.250s][info][gc ] GC(18) Verify Before Mark, Level 4 (686375 reachable, 0 marked) [2025-11-28T04:46:20.931Z] [37.251s][info][gc ] GC(18) Pause Init Mark (Young) 682.373ms [2025-11-28T04:46:20.931Z] [37.251s][info][gc,start ] GC(18) Concurrent remembered set scanning [2025-11-28T04:46:20.931Z] [37.251s][info][gc,task ] GC(18) Using 1 of 1 workers for Concurrent remembered set scanning [2025-11-28T04:46:20.931Z] [37.320s][info][gc ] GC(18) Concurrent remembered set scanning 69.703ms [2025-11-28T04:46:20.931Z] [37.320s][info][gc,start ] GC(18) Concurrent marking roots [2025-11-28T04:46:20.931Z] [37.320s][info][gc,task ] GC(18) Using 1 of 1 workers for concurrent marking roots [2025-11-28T04:46:20.931Z] [37.321s][info][gc ] GC(18) Concurrent marking roots 0.808ms [2025-11-28T04:46:20.931Z] [37.321s][info][gc,start ] GC(18) Concurrent marking (Young) [2025-11-28T04:46:20.931Z] [37.321s][info][gc,task ] GC(18) Using 1 of 1 workers for concurrent marking [2025-11-28T04:46:20.931Z] [37.417s][info][gc ] GC(18) Concurrent marking (Young) 95.365ms [2025-11-28T04:46:20.931Z] [37.417s][info][gc,start ] GC(18) Pause Final Mark (Young) [2025-11-28T04:46:20.931Z] [37.417s][info][gc,task ] GC(18) Using 1 of 1 workers for final marking [2025-11-28T04:46:20.931Z] [37.474s][info][gc,age ] GC(18) New tenuring threshold 2 (min 1, max 15) [2025-11-28T04:46:20.931Z] [37.474s][info][gc,ergo ] GC(18) Promotion potential of aged regions with sufficient garbage: 0B [2025-11-28T04:46:20.931Z] [37.474s][info][gc,ergo ] GC(18) Planning to promote in place 0 humongous regions and 27 regular regions, spanning a total of 14155776 used bytes [2025-11-28T04:46:20.931Z] [37.474s][info][gc,ergo ] GC(18) Adaptive CSet Selection for YOUNG. Max Evacuation: 40063K, Actual Free: 2559K. [2025-11-28T04:46:20.931Z] [37.474s][info][gc,ergo ] GC(18) Collectable Garbage: 87840K (99%), Immediate: 2559K (2%), 5 regions, CSet: 85280K (97%), 184 regions [2025-11-28T04:46:20.931Z] [37.474s][info][gc,ergo ] GC(18) Evacuation Targets: YOUNG: 8546K, PROMOTE: 380K, OLD: 0B, TOTAL: 8927K [2025-11-28T04:46:20.931Z] [37.474s][info][gc,free ] Free: 821M, Max: 512K regular, 778M humongous, Frag: 6% external, 0% internal; Used: 256K, Mutator Free: 1643 Collector Reserve: 10752K, Max: 512K; Used: 0B Old Collector Reserve: 512K, Max: 512K; Used: 0B [2025-11-28T04:46:20.931Z] [37.474s][info][gc,start ] GC(18) Verify Before Evacuation, Level 4 [2025-11-28T04:46:20.934Z] [37.677s][info][gc ] GC(18) Verify Before Evacuation, Level 4 (691476 reachable, 17101 marked) [2025-11-28T04:46:20.934Z] [37.677s][info][gc ] GC(18) Pause Final Mark (Young) 260.486ms [2025-11-28T04:46:20.934Z] [37.677s][info][gc,start ] GC(18) Concurrent thread roots [2025-11-28T04:46:20.934Z] [37.677s][info][gc,task ] GC(18) Using 1 of 1 workers for Concurrent thread roots [2025-11-28T04:46:20.934Z] [37.677s][info][gc ] GC(18) Concurrent thread roots 0.136ms [2025-11-28T04:46:20.934Z] [37.677s][info][gc,start ] GC(18) Concurrent weak references (Young) [2025-11-28T04:46:20.934Z] [37.677s][info][gc,task ] GC(18) Using 1 of 1 workers for concurrent weak references [2025-11-28T04:46:20.934Z] [37.677s][info][gc,ref ] GC(18) Encountered references: Soft: 0, Weak: 1, Final: 0, Phantom: 0 [2025-11-28T04:46:20.934Z] [37.677s][info][gc,ref ] GC(18) Discovered references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.934Z] [37.677s][info][gc,ref ] GC(18) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.934Z] [37.677s][info][gc ] GC(18) Concurrent weak references (Young) 0.046ms [2025-11-28T04:46:20.934Z] [37.677s][info][gc,start ] GC(18) Concurrent weak roots (Young) [2025-11-28T04:46:20.934Z] [37.677s][info][gc,task ] GC(18) Using 1 of 1 workers for concurrent weak root [2025-11-28T04:46:20.934Z] [37.681s][info][gc ] GC(18) Concurrent weak roots (Young) 3.148ms [2025-11-28T04:46:20.934Z] [37.681s][info][gc,start ] GC(18) Concurrent cleanup (Young) [2025-11-28T04:46:20.934Z] [37.681s][info][gc,task ] GC(18) Using 1 of 1 workers for cleanup early. [2025-11-28T04:46:20.934Z] [37.681s][info][gc ] GC(18) Concurrent cleanup (Young) 192M->192M(954M) 0.075ms [2025-11-28T04:46:20.934Z] [37.681s][info][gc,free ] Free: 820M, Max: 512K regular, 778M humongous, Frag: 6% external, 0% internal; Used: 256K, Mutator Free: 1641 Collector Reserve: 10749K, Max: 512K; Used: 2064B Old Collector Reserve: 383K, Max: 383K; Used: 128K [2025-11-28T04:46:20.934Z] [37.681s][info][gc,start ] GC(18) Concurrent strong roots [2025-11-28T04:46:20.934Z] [37.681s][info][gc,task ] GC(18) Using 1 of 1 workers for concurrent strong root [2025-11-28T04:46:20.934Z] [37.681s][info][gc ] GC(18) Concurrent strong roots 0.626ms [2025-11-28T04:46:20.934Z] [37.681s][info][gc,start ] GC(18) Concurrent evacuation [2025-11-28T04:46:20.934Z] [37.681s][info][gc,task ] GC(18) Using 1 of 1 workers for concurrent evacuation [2025-11-28T04:46:20.934Z] [37.714s][info][gc ] GC(18) Concurrent evacuation 32.674ms [2025-11-28T04:46:20.934Z] [37.714s][info][gc,start ] GC(18) Concurrent Init Update Refs (Young) [2025-11-28T04:46:20.934Z] [37.715s][info][gc ] GC(18) Concurrent Init Update Refs (Young) 0.450ms [2025-11-28T04:46:20.934Z] [37.715s][info][gc,start ] GC(18) Pause Init Update Refs [2025-11-28T04:46:20.934Z] [37.715s][info][gc,start ] GC(18) Verify Before Updating References, Level 4 [2025-11-28T04:46:20.934Z] [38.568s][info][gc ] GC(18) Verify Before Updating References, Level 4 (92538 reachable, 458598 marked) [2025-11-28T04:46:20.934Z] [38.568s][info][gc ] GC(18) Pause Init Update Refs 853.286ms [2025-11-28T04:46:20.934Z] [38.568s][info][gc,start ] GC(18) Concurrent update references [2025-11-28T04:46:20.934Z] [38.568s][info][gc,task ] GC(18) Using 1 of 1 workers for concurrent reference update [2025-11-28T04:46:20.934Z] [38.568s][info][gc,ergo ] GC(18) At start of update refs, moving 2048K to Mutator free set from Collector Reserve (2048K) and from Old Collector Reserve (0B) [2025-11-28T04:46:20.934Z] [38.608s][info][gc ] GC(18) Concurrent update references 40.397ms [2025-11-28T04:46:20.934Z] [38.608s][info][gc,start ] GC(18) Concurrent update thread roots [2025-11-28T04:46:20.934Z] [38.609s][info][gc ] GC(18) Concurrent update thread roots 0.167ms [2025-11-28T04:46:20.934Z] [38.609s][info][gc,start ] GC(18) Pause Final Update Refs [2025-11-28T04:46:20.934Z] [38.609s][info][gc,task ] GC(18) Using 1 of 1 workers for final reference update [2025-11-28T04:46:20.934Z] [38.691s][info][gc,start ] GC(18) Verify After Updating References, Level 4 [2025-11-28T04:46:20.934Z] [38.892s][info][gc ] GC(18) Verify After Updating References, Level 4 (99929 reachable, 797707 marked) [2025-11-28T04:46:20.934Z] [38.893s][info][gc,free ] Free: 831M, Max: 512K regular, 778M humongous, Frag: 7% external, 0% internal; Used: 256K, Mutator Free: 1664 Collector Reserve: 47616K, Max: 512K; Used: 42496K Old Collector Reserve: 67584B, Max: 67584B; Used: 446K [2025-11-28T04:46:20.934Z] [38.893s][info][gc ] GC(18) Pause Final Update Refs 283.936ms [2025-11-28T04:46:20.934Z] [38.893s][info][gc,start ] GC(18) Concurrent cleanup (Young) [2025-11-28T04:46:20.934Z] [38.893s][info][gc,task ] GC(18) Using 1 of 1 workers for cleanup complete. [2025-11-28T04:46:20.934Z] [38.893s][info][gc ] GC(18) Concurrent cleanup (Young) 145M->145M(954M) 0.064ms [2025-11-28T04:46:20.934Z] [38.893s][info][gc,start ] GC(18) Concurrent reset after collect (Young) [2025-11-28T04:46:20.934Z] [38.893s][info][gc,task ] GC(18) Using 1 of 1 workers for reset after collection. [2025-11-28T04:46:20.934Z] [38.893s][info][gc ] GC(18) Concurrent reset after collect (Young) 0.450ms [2025-11-28T04:46:20.934Z] [38.893s][info][gc,ergo ] GC(18) At end of Concurrent Young GC: GCU: 20.9%, MU: 29.9% during period of 2.426s [2025-11-28T04:46:20.934Z] [38.893s][info][gc,ergo ] GC(18) At end of Concurrent Young GC: Young generation used: 48384K, used regions: 48640K, humongous waste: 0B, soft capacity: 1024M, max capacity: 925M, available: 831M [2025-11-28T04:46:20.934Z] [38.893s][info][gc,ergo ] GC(18) At end of Concurrent Young GC: Old generation used: 100798K, used regions: 100864K, humongous waste: 0B, soft capacity: 1024M, max capacity: 100864K, available: 67584B [2025-11-28T04:46:20.934Z] [38.893s][info][gc,free ] Free: 831M, Max: 512K regular, 778M humongous, Frag: 7% external, 0% internal; Used: 256K, Mutator Free: 1664 Collector Reserve: 47616K, Max: 512K; Used: 0B Old Collector Reserve: 67584B, Max: 67584B; Used: 446K [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Concurrent Reset 399 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Concurrent Reset After Collect 469 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Pause Init Mark (G) 682504 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Pause Init Mark (N) 682397 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Verify 682201 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Swap Remembered Set 2 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Update Region States 125 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Propagate GC State 1 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Concurrent Scan Remembered Set 69742 us, parallelism: 1.00x [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] RS: <total> 69649 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] RS: Scan Clusters 69649 us, workers (us): 69649, [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Concurrent Mark Roots 830 us, parallelism: 0.91x [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] CMR: <total> 754 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] CMR: Thread Roots 94 us, workers (us): 94, [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] CMR: Code Cache Roots 635 us, workers (us): 635, [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] CMR: VM Strong Roots 3 us, workers (us): 3, [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] CMR: CLDG Roots 21 us, workers (us): 21, [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Concurrent Marking 95402 us, parallelism: 0.92x [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] CM: <total> 88183 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] CM: Parallel Mark 88183 us, workers (us): 88183, [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Flush SATB 7144 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Pause Final Mark (G) 260601 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Pause Final Mark (N) 260497 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Verify 203080 us [2025-11-28T04:46:20.934Z] [38.893s][info][gc,stats ] Finish Mark 50 us, parallelism: 0.02x [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Propagate GC State 1 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Update Region States 91 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Choose Collection Set 106 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Rebuild Free Set 120 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Thread Roots 145 us, parallelism: 0.69x [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CTR: <total> 100 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CTR: Thread Roots 100 us, workers (us): 100, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Weak References 52 us, parallelism: 0.01x [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CWRF: <total> 1 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CWRF: Weak References 1 us, workers (us): 1, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Weak Roots 3178 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Roots 47 us, parallelism: 0.46x [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CWR: <total> 21 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CWR: VM Weak Roots 21 us, workers (us): 21, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Rendezvous 3085 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Cleanup 83 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Strong Roots 643 us, parallelism: 0.88x [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CSR: <total> 564 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CSR: Code Cache Roots 538 us, workers (us): 538, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CSR: VM Strong Roots 5 us, workers (us): 5, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] CSR: CLDG Roots 21 us, workers (us): 21, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Evacuation 32706 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Pause Init Update Refs (G) 853383 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Pause Init Update Refs (N) 853297 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Verify 853269 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Update Refs Prepare 468 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Update Refs 40438 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Update Thread Roots 180 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Pause Final Update Refs (G) 284068 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Pause Final Update Refs (N) 283944 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Verify 200893 us, workers (us): ---, [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Update Region States 107 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Trash Collection Set 7 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Rebuild Free Set 180 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Propagate GC State 0 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,stats ] Concurrent Cleanup 73 us [2025-11-28T04:46:20.934Z] [38.894s][info][gc,metaspace] GC(18) Metaspace: 1242K(1408K)->1242K(1408K) NonClass: 1155K(1216K)->1155K(1216K) Class: 86K(192K)->86K(192K) [2025-11-28T04:46:20.934Z] [38.894s][info][gc ] Trigger (Old): Old has overgrown, live at end of previous OLD marking: 74226K, current usage: 100798K, percent growth: 35.8% [2025-11-28T04:46:20.934Z] [38.895s][info][gc,free ] Free: 831M, Max: 512K regular, 778M humongous, Frag: 7% external, 0% internal; Used: 256K, Mutator Free: 1663 Collector Reserve: 47616K, Max: 512K; Used: 0B Old Collector Reserve: 67584B, Max: 67584B; Used: 446K [2025-11-28T04:46:20.934Z] [38.895s][info][gc,start ] GC(19) Concurrent reset (Young) [2025-11-28T04:46:20.934Z] [38.895s][info][gc,task ] GC(19) Using 1 of 1 workers for Concurrent reset (Young) [2025-11-28T04:46:20.934Z] [38.895s][info][gc ] GC(19) Concurrent reset (Young) 0.601ms [2025-11-28T04:46:20.934Z] [38.895s][info][gc,start ] GC(19) Pause Init Mark (Young) [2025-11-28T04:46:20.934Z] [38.895s][info][gc,task ] GC(19) Using 1 of 1 workers for init marking [2025-11-28T04:46:20.934Z] [38.895s][info][gc,start ] GC(19) Verify Before Mark, Level 4 [2025-11-28T04:46:20.934Z] [38.973s][info][gc ] GC(19) Verify Before Mark, Level 4 (363881 reachable, 0 marked) [2025-11-28T04:46:20.934Z] [38.973s][info][gc ] GC(19) Pause Init Mark (Young) 77.923ms [2025-11-28T04:46:20.934Z] [38.973s][info][gc,start ] GC(19) Concurrent remembered set scanning [2025-11-28T04:46:20.934Z] [38.973s][info][gc,task ] GC(19) Using 1 of 1 workers for Concurrent remembered set scanning [2025-11-28T04:46:20.934Z] [39.008s][info][gc ] GC(19) Concurrent remembered set scanning 34.858ms [2025-11-28T04:46:20.934Z] [39.008s][info][gc,start ] GC(19) Concurrent marking roots [2025-11-28T04:46:20.934Z] [39.008s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent marking roots [2025-11-28T04:46:20.934Z] [39.009s][info][gc ] GC(19) Concurrent marking roots 0.861ms [2025-11-28T04:46:20.934Z] [39.009s][info][gc,start ] GC(19) Concurrent marking (Young) [2025-11-28T04:46:20.934Z] [39.009s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent marking [2025-11-28T04:46:20.934Z] [39.056s][info][gc ] GC(19) Concurrent marking (Young) 46.387ms [2025-11-28T04:46:20.934Z] [39.056s][info][gc,start ] GC(19) Pause Final Mark (Young) [2025-11-28T04:46:20.934Z] [39.056s][info][gc,task ] GC(19) Using 1 of 1 workers for final marking [2025-11-28T04:46:20.934Z] [39.075s][info][gc,age ] GC(19) New tenuring threshold 2 (min 1, max 15) [2025-11-28T04:46:20.934Z] [39.075s][info][gc,ergo ] GC(19) Promotion potential of aged regions with sufficient garbage: 0B [2025-11-28T04:46:20.934Z] [39.075s][info][gc,ergo ] GC(19) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-11-28T04:46:20.934Z] [39.075s][info][gc,ergo ] GC(19) Adaptive CSet Selection for YOUNG. Max Evacuation: 39487K, Actual Free: 1022K. [2025-11-28T04:46:20.934Z] [39.075s][info][gc,ergo ] GC(19) Collectable Garbage: 36102K (99%), Immediate: 1022K (2%), 2 regions, CSet: 35080K (97%), 78 regions [2025-11-28T04:46:20.934Z] [39.075s][info][gc,ergo ] GC(19) Evacuation Targets: YOUNG: 4599K, PROMOTE: 0B, OLD: 0B, TOTAL: 4599K [2025-11-28T04:46:20.934Z] [39.075s][info][gc,free ] Free: 872M, Max: 512K regular, 778M humongous, Frag: 11% external, 0% internal; Used: 256K, Mutator Free: 1745 Collector Reserve: 5632K, Max: 512K; Used: 1022K Old Collector Reserve: 67584B, Max: 67584B; Used: 446K [2025-11-28T04:46:20.934Z] [39.075s][info][gc,start ] GC(19) Verify Before Evacuation, Level 4 [2025-11-28T04:46:20.934Z] [39.171s][info][gc ] GC(19) Verify Before Evacuation, Level 4 (365149 reachable, 9696 marked) [2025-11-28T04:46:20.934Z] [39.171s][info][gc ] GC(19) Pause Final Mark (Young) 115.082ms [2025-11-28T04:46:20.934Z] [39.171s][info][gc,start ] GC(19) Concurrent thread roots [2025-11-28T04:46:20.934Z] [39.171s][info][gc,task ] GC(19) Using 1 of 1 workers for Concurrent thread roots [2025-11-28T04:46:20.934Z] [39.171s][info][gc ] GC(19) Concurrent thread roots 0.132ms [2025-11-28T04:46:20.934Z] [39.171s][info][gc,start ] GC(19) Concurrent weak references (Young) [2025-11-28T04:46:20.934Z] [39.171s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent weak references [2025-11-28T04:46:20.934Z] [39.171s][info][gc,ref ] GC(19) Encountered references: Soft: 0, Weak: 1, Final: 0, Phantom: 0 [2025-11-28T04:46:20.934Z] [39.171s][info][gc,ref ] GC(19) Discovered references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.934Z] [39.171s][info][gc,ref ] GC(19) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.934Z] [39.171s][info][gc ] GC(19) Concurrent weak references (Young) 0.047ms [2025-11-28T04:46:20.934Z] [39.171s][info][gc,start ] GC(19) Concurrent weak roots (Young) [2025-11-28T04:46:20.934Z] [39.171s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent weak root [2025-11-28T04:46:20.934Z] [39.172s][info][gc ] GC(19) Concurrent weak roots (Young) 0.556ms [2025-11-28T04:46:20.934Z] [39.172s][info][gc,start ] GC(19) Concurrent cleanup (Young) [2025-11-28T04:46:20.934Z] [39.172s][info][gc,task ] GC(19) Using 1 of 1 workers for cleanup early. [2025-11-28T04:46:20.934Z] [39.172s][info][gc ] GC(19) Concurrent cleanup (Young) 146M->146M(954M) 0.070ms [2025-11-28T04:46:20.934Z] [39.172s][info][gc,free ] Free: 872M, Max: 512K regular, 778M humongous, Frag: 11% external, 0% internal; Used: 512K, Mutator Free: 1745 Collector Reserve: 5629K, Max: 512K; Used: 2064B Old Collector Reserve: 67584B, Max: 67584B; Used: 446K [2025-11-28T04:46:20.934Z] [39.172s][info][gc,start ] GC(19) Concurrent strong roots [2025-11-28T04:46:20.934Z] [39.172s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent strong root [2025-11-28T04:46:20.934Z] [39.172s][info][gc ] GC(19) Concurrent strong roots 0.583ms [2025-11-28T04:46:20.934Z] [39.172s][info][gc,start ] GC(19) Concurrent evacuation [2025-11-28T04:46:20.934Z] [39.172s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent evacuation [2025-11-28T04:46:20.934Z] [39.185s][info][gc ] GC(19) Concurrent evacuation 12.319ms [2025-11-28T04:46:20.934Z] [39.185s][info][gc,start ] GC(19) Concurrent Init Update Refs (Young) [2025-11-28T04:46:20.934Z] [39.186s][info][gc ] GC(19) Concurrent Init Update Refs (Young) 0.893ms [2025-11-28T04:46:20.934Z] [39.186s][info][gc,start ] GC(19) Pause Init Update Refs [2025-11-28T04:46:20.934Z] [39.186s][info][gc,start ] GC(19) Verify Before Updating References, Level 4 [2025-11-28T04:46:20.934Z] [39.309s][info][gc ] GC(19) Verify Before Updating References, Level 4 (368060 reachable, 146278 marked) [2025-11-28T04:46:20.934Z] [39.309s][info][gc ] GC(19) Pause Init Update Refs 123.007ms [2025-11-28T04:46:20.935Z] [39.309s][info][gc,start ] GC(19) Concurrent update references [2025-11-28T04:46:20.935Z] [39.309s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent reference update [2025-11-28T04:46:20.935Z] [39.309s][info][gc,ergo ] GC(19) At start of update refs, moving 768K to Mutator free set from Collector Reserve (768K) and from Old Collector Reserve (0B) [2025-11-28T04:46:20.935Z] [39.329s][info][gc ] GC(19) Concurrent update references 20.359ms [2025-11-28T04:46:20.935Z] [39.329s][info][gc,start ] GC(19) Concurrent update thread roots [2025-11-28T04:46:20.935Z] [39.331s][info][gc ] GC(19) Concurrent update thread roots 1.652ms [2025-11-28T04:46:20.935Z] [39.331s][info][gc,start ] GC(19) Pause Final Update Refs [2025-11-28T04:46:20.935Z] [39.331s][info][gc,task ] GC(19) Using 1 of 1 workers for final reference update [2025-11-28T04:46:20.935Z] [39.358s][info][gc,start ] GC(19) Verify After Updating References, Level 4 [2025-11-28T04:46:20.935Z] [39.443s][info][gc ] GC(19) Verify After Updating References, Level 4 (370463 reachable, 29769 marked) [2025-11-28T04:46:20.935Z] [39.443s][info][gc,free ] Free: 863M, Max: 512K regular, 831M humongous, Frag: 4% external, 0% internal; Used: 256K, Mutator Free: 1728 Collector Reserve: 47872K, Max: 512K; Used: 38656K Old Collector Reserve: 67584B, Max: 67584B; Used: 446K [2025-11-28T04:46:20.935Z] [39.443s][info][gc ] GC(19) Pause Final Update Refs 112.435ms [2025-11-28T04:46:20.935Z] [39.443s][info][gc,start ] GC(19) Concurrent cleanup (Young) [2025-11-28T04:46:20.935Z] [39.443s][info][gc,task ] GC(19) Using 1 of 1 workers for cleanup complete. [2025-11-28T04:46:20.935Z] [39.443s][info][gc ] GC(19) Concurrent cleanup (Young) 113M->113M(954M) 0.044ms [2025-11-28T04:46:20.935Z] [39.443s][info][gc,start ] GC(19) Concurrent reset after collect (Young) [2025-11-28T04:46:20.935Z] [39.443s][info][gc,task ] GC(19) Using 1 of 1 workers for reset after collection. [2025-11-28T04:46:20.935Z] [39.443s][info][gc ] GC(19) Concurrent reset after collect (Young) 0.012ms [2025-11-28T04:46:20.935Z] [39.443s][info][gc,ergo ] GC(19) At end of Concurrent Bootstrap GC: GCU: 44.0%, MU: 5.7% during period of 0.550s [2025-11-28T04:46:20.935Z] [39.443s][info][gc,ergo ] GC(19) At end of Concurrent Bootstrap GC: Young generation used: 15360K, used regions: 15872K, humongous waste: 0B, soft capacity: 1024M, max capacity: 925M, available: 863M [2025-11-28T04:46:20.935Z] [39.443s][info][gc,ergo ] GC(19) At end of Concurrent Bootstrap GC: Old generation used: 100798K, used regions: 100864K, humongous waste: 0B, soft capacity: 1024M, max capacity: 100864K, available: 67584B [2025-11-28T04:46:20.935Z] [39.443s][info][gc,stats ] [2025-11-28T04:46:20.935Z] [39.443s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-11-28T04:46:20.935Z] [39.443s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Reset 618 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Reset After Collect 18 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Pause Init Mark (G) 78075 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Pause Init Mark (N) 77934 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Verify 77830 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Swap Remembered Set 2 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Update Region States 54 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Propagate GC State 1 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Scan Remembered Set 34896 us, parallelism: 1.00x [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] RS: <total> 34804 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] RS: Scan Clusters 34804 us, workers (us): 34804, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Mark Roots 883 us, parallelism: 0.91x [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CMR: <total> 801 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CMR: Thread Roots 90 us, workers (us): 90, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CMR: Code Cache Roots 633 us, workers (us): 633, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CMR: VM Strong Roots 7 us, workers (us): 7, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CMR: CLDG Roots 70 us, workers (us): 70, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Marking 46425 us, parallelism: 0.97x [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CM: <total> 44948 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CM: Parallel Mark 44948 us, workers (us): 44948, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Flush SATB 1393 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Pause Final Mark (G) 115185 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Pause Final Mark (N) 115092 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Verify 95596 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Finish Mark 45 us, parallelism: 0.04x [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Propagate GC State 2 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Update Region States 92 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Choose Collection Set 90 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Rebuild Free Set 128 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Thread Roots 142 us, parallelism: 0.68x [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CTR: <total> 96 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CTR: Thread Roots 96 us, workers (us): 96, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Weak References 53 us, parallelism: 0.02x [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CWRF: <total> 1 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CWRF: Weak References 1 us, workers (us): 1, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Weak Roots 579 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Roots 41 us, parallelism: 0.51x [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CWR: <total> 21 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CWR: VM Weak Roots 21 us, workers (us): 21, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Rendezvous 499 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Cleanup 79 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Strong Roots 599 us, parallelism: 0.90x [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CSR: <total> 540 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CSR: Code Cache Roots 517 us, workers (us): 517, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CSR: VM Strong Roots 4 us, workers (us): 4, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] CSR: CLDG Roots 19 us, workers (us): 19, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Evacuation 12355 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Pause Init Update Refs (G) 123119 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Pause Init Update Refs (N) 123018 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Verify 122985 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Update Refs Prepare 919 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Update Refs 20396 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Update Thread Roots 1682 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Pause Final Update Refs (G) 112564 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Pause Final Update Refs (N) 112443 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Verify 84636 us, workers (us): ---, [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Update Region States 97 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Trash Collection Set 6 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Rebuild Free Set 153 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Propagate GC State 0 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,stats ] Concurrent Cleanup 54 us [2025-11-28T04:46:20.935Z] [39.444s][info][gc,start ] GC(19) Concurrent marking (Old) [2025-11-28T04:46:20.935Z] [39.444s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent marking [2025-11-28T04:46:20.935Z] [39.582s][info][gc ] GC(19) Concurrent marking (Old) 137.345ms [2025-11-28T04:46:20.935Z] [39.582s][info][gc,start ] GC(19) Pause Final Mark (Old) [2025-11-28T04:46:20.935Z] [39.582s][info][gc,task ] GC(19) Using 1 of 1 workers for final marking [2025-11-28T04:46:20.935Z] [39.582s][info][gc,ergo ] GC(19) Old-Gen Collectable Garbage: 48094K consolidated with free: 0B, over 67 regions [2025-11-28T04:46:20.935Z] [39.582s][info][gc,ergo ] GC(19) Old-Gen Immediate Garbage: 15872K over 31 regions [2025-11-28T04:46:20.935Z] [39.582s][info][gc,ergo ] GC(19) Old regions selected for defragmentation: 0 [2025-11-28T04:46:20.935Z] [39.582s][info][gc,ergo ] GC(19) Old regions not selected: 99 [2025-11-28T04:46:20.935Z] [39.583s][info][gc,free ] Free: 874M, Max: 512K regular, 831M humongous, Frag: 5% external, 0% internal; Used: 256K, Mutator Free: 1749 Collector Reserve: 48896K, Max: 512K; Used: 4352K Old Collector Reserve: 3138K, Max: 512K; Used: 446K [2025-11-28T04:46:20.935Z] [39.583s][info][gc ] GC(19) Pause Final Mark (Old) 0.978ms [2025-11-28T04:46:20.935Z] [39.583s][info][gc,start ] GC(19) Concurrent weak references (Old) [2025-11-28T04:46:20.935Z] [39.583s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent weak references [2025-11-28T04:46:20.935Z] [39.583s][info][gc,ref ] GC(19) Encountered references: Soft: 24, Weak: 131, Final: 0, Phantom: 14 [2025-11-28T04:46:20.935Z] [39.583s][info][gc,ref ] GC(19) Discovered references: Soft: 0, Weak: 45, Final: 0, Phantom: 14 [2025-11-28T04:46:20.935Z] [39.583s][info][gc,ref ] GC(19) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.935Z] [39.583s][info][gc ] GC(19) Concurrent weak references (Old) 0.050ms [2025-11-28T04:46:20.935Z] [39.583s][info][gc,start ] GC(19) Concurrent weak roots (Old) [2025-11-28T04:46:20.935Z] [39.583s][info][gc,task ] GC(19) Using 1 of 1 workers for concurrent weak root [2025-11-28T04:46:20.935Z] [39.584s][info][gc ] GC(19) Concurrent weak roots (Old) 0.801ms [2025-11-28T04:46:20.935Z] [39.584s][info][gc,start ] GC(19) Concurrent cleanup (Old) [2025-11-28T04:46:20.935Z] [39.584s][info][gc,task ] GC(19) Using 1 of 1 workers for cleanup early. [2025-11-28T04:46:20.935Z] [39.584s][info][gc ] GC(19) Concurrent cleanup (Old) 99M->99M(954M) 0.070ms [2025-11-28T04:46:20.935Z] [39.584s][info][gc,free ] Free: 874M, Max: 512K regular, 831M humongous, Frag: 5% external, 0% internal; Used: 512K, Mutator Free: 1749 Collector Reserve: 48896K, Max: 512K; Used: 256K Old Collector Reserve: 3138K, Max: 512K; Used: 446K [2025-11-28T04:46:20.935Z] [39.585s][info][gc,free ] Free: 873M, Max: 512K regular, 831M humongous, Frag: 5% external, 0% internal; Used: 768K, Mutator Free: 1748 Collector Reserve: 48896K, Max: 512K; Used: 256K Old Collector Reserve: 3138K, Max: 512K; Used: 446K [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Concurrent Marking 137384 us, parallelism: 0.99x [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] CM: <total> 136192 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] CM: Parallel Mark 136192 us, workers (us): 136192, [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Flush SATB 1116 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Pause Final Mark (G) 1074 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Pause Final Mark (N) 987 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Finish Mark 26 us, parallelism: 0.07x [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Propagate GC State 2 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Update Region States 65 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Choose Collection Set 68 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Rebuild Free Set 171 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Concurrent Weak References 57 us, parallelism: 0.17x [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] CWRF: <total> 10 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] CWRF: Weak References 10 us, workers (us): 10, [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Concurrent Weak Roots 824 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Roots 34 us, parallelism: 0.58x [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] CWR: <total> 19 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] CWR: VM Weak Roots 19 us, workers (us): 19, [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Rendezvous 753 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,stats ] Concurrent Cleanup 78 us [2025-11-28T04:46:20.935Z] [39.585s][info][gc,metaspace] GC(19) Metaspace: 1242K(1408K)->1242K(1408K) NonClass: 1155K(1216K)->1155K(1216K) Class: 86K(192K)->86K(192K) [2025-11-28T04:46:20.935Z] [39.586s][info][gc ] Trigger (Young): Expedite mixed evacuation of 67 regions [2025-11-28T04:46:20.935Z] [39.586s][info][gc,free ] Free: 872M, Max: 512K regular, 831M humongous, Frag: 5% external, 0% internal; Used: 768K, Mutator Free: 1747 Collector Reserve: 48896K, Max: 512K; Used: 256K Old Collector Reserve: 3138K, Max: 512K; Used: 446K [2025-11-28T04:46:20.935Z] [39.586s][info][gc,ergo ] GC(20) Start GC cycle (Young) [2025-11-28T04:46:20.935Z] [39.586s][info][gc,start ] GC(20) Concurrent reset (Young) [2025-11-28T04:46:20.935Z] [39.586s][info][gc,task ] GC(20) Using 1 of 1 workers for Concurrent reset (Young) [2025-11-28T04:46:20.935Z] [39.586s][info][gc ] GC(20) Concurrent reset (Young) 0.588ms [2025-11-28T04:46:20.935Z] [39.586s][info][gc,start ] GC(20) Pause Init Mark (Young) [2025-11-28T04:46:20.935Z] [39.586s][info][gc,task ] GC(20) Using 1 of 1 workers for init marking [2025-11-28T04:46:20.935Z] [39.586s][info][gc,start ] GC(20) Verify Before Mark, Level 4 [2025-11-28T04:46:20.935Z] [40.013s][info][gc ] GC(20) Verify Before Mark, Level 4 (378259 reachable, 0 marked) [2025-11-28T04:46:20.935Z] [40.013s][info][gc ] GC(20) Pause Init Mark (Young) 426.817ms [2025-11-28T04:46:20.935Z] [40.013s][info][gc,start ] GC(20) Concurrent remembered set scanning [2025-11-28T04:46:20.935Z] [40.013s][info][gc,task ] GC(20) Using 1 of 1 workers for Concurrent remembered set scanning [2025-11-28T04:46:20.935Z] [40.017s][info][gc ] GC(20) Concurrent remembered set scanning 3.412ms [2025-11-28T04:46:20.935Z] [40.017s][info][gc,start ] GC(20) Concurrent marking roots [2025-11-28T04:46:20.935Z] [40.017s][info][gc,task ] GC(20) Using 1 of 1 workers for concurrent marking roots [2025-11-28T04:46:20.935Z] [40.017s][info][gc ] GC(20) Concurrent marking roots 0.790ms [2025-11-28T04:46:20.935Z] [40.017s][info][gc,start ] GC(20) Concurrent marking (Young) [2025-11-28T04:46:20.935Z] [40.017s][info][gc,task ] GC(20) Using 1 of 1 workers for concurrent marking [2025-11-28T04:46:20.935Z] [40.062s][info][gc ] GC(20) Concurrent marking (Young) 44.101ms [2025-11-28T04:46:20.935Z] [40.062s][info][gc,start ] GC(20) Pause Final Mark (Young) [2025-11-28T04:46:20.935Z] [40.062s][info][gc,task ] GC(20) Using 1 of 1 workers for final marking [2025-11-28T04:46:20.935Z] [40.066s][info][gc,age ] GC(20) New tenuring threshold 2 (min 1, max 15) [2025-11-28T04:46:20.935Z] [40.066s][info][gc,ergo ] GC(20) Promotion potential of aged regions with sufficient garbage: 0B [2025-11-28T04:46:20.935Z] [40.066s][info][gc,ergo ] GC(20) Planning to promote in place 0 humongous regions and 2 regular regions, spanning a total of 1048576 used bytes [2025-11-28T04:46:20.935Z] [40.066s][info][gc,ergo ] GC(20) Old-gen piggyback evac (67 regions, evacuating 2081K, reclaiming: 32222K) [2025-11-28T04:46:20.935Z] [40.066s][info][gc,ergo ] GC(20) Adaptive CSet Selection for YOUNG. Max Evacuation: 40021K, Actual Free: 0B. [2025-11-28T04:46:20.935Z] [40.066s][info][gc,ergo ] GC(20) Collectable Garbage: 36968K (774%), Immediate: 0B (0%), 0 regions, CSet: 36968K (774%), 80 regions [2025-11-28T04:46:20.935Z] [40.066s][info][gc,ergo ] GC(20) Evacuation Targets: YOUNG: 1141K, PROMOTE: 0B, OLD: 2081K, TOTAL: 3223K [2025-11-28T04:46:20.935Z] [40.066s][info][gc,free ] Free: 917M, Max: 512K regular, 831M humongous, Frag: 10% external, 0% internal; Used: 256K, Mutator Free: 1836 Collector Reserve: 1536K, Max: 512K; Used: 0B Old Collector Reserve: 3138K, Max: 512K; Used: 446K [2025-11-28T04:46:20.935Z] [40.066s][info][gc,start ] GC(20) Verify Before Evacuation, Level 4 [2025-11-28T04:46:20.935Z] [40.139s][info][gc ] GC(20) Verify Before Evacuation, Level 4 (377514 reachable, 6688 marked) [2025-11-28T04:46:20.935Z] [40.139s][info][gc ] GC(20) Pause Final Mark (Young) 76.983ms [2025-11-28T04:46:20.935Z] [40.139s][info][gc,start ] GC(20) Concurrent thread roots [2025-11-28T04:46:20.935Z] [40.139s][info][gc,task ] GC(20) Using 1 of 1 workers for Concurrent thread roots [2025-11-28T04:46:20.935Z] [40.139s][info][gc ] GC(20) Concurrent thread roots 0.138ms [2025-11-28T04:46:20.935Z] [40.139s][info][gc,start ] GC(20) Concurrent weak references (Young) [2025-11-28T04:46:20.935Z] [40.139s][info][gc,task ] GC(20) Using 1 of 1 workers for concurrent weak references [2025-11-28T04:46:20.935Z] [40.139s][info][gc,ref ] GC(20) Encountered references: Soft: 0, Weak: 1, Final: 0, Phantom: 0 [2025-11-28T04:46:20.935Z] [40.139s][info][gc,ref ] GC(20) Discovered references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.935Z] [40.139s][info][gc,ref ] GC(20) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-11-28T04:46:20.935Z] [40.139s][info][gc ] GC(20) Concurrent weak references (Young) 0.046ms [2025-11-28T04:46:20.935Z] [40.139s][info][gc,start ] GC(20) Concurrent weak roots (Young) [2025-11-28T04:46:20.935Z] [40.139s][info][gc,task ] GC(20) Using 1 of 1 workers for concurrent weak root [2025-11-28T04:46:20.935Z] [40.140s][info][gc ] GC(20) Concurrent weak roots (Young) 0.650ms [2025-11-28T04:46:20.935Z] [40.140s][info][gc,start ] GC(20) Concurrent cleanup (Young) [2025-11-28T04:46:20.935Z] [40.140s][info][gc,task ] GC(20) Using 1 of 1 workers for cleanup early. [2025-11-28T04:46:20.935Z] [40.140s][info][gc ] GC(20) Concurrent cleanup (Young) 102M->102M(954M) 0.074ms [2025-11-28T04:46:20.935Z] [40.140s][info][gc,free ] Free: 917M, Max: 512K regular, 831M humongous, Frag: 10% external, 0% internal; Used: 512K, Mutator Free: 1836 Collector Reserve: 1533K, Max: 512K; Used: 2064B Old Collector Reserve: 2876K, Max: 512K; Used: 707K [2025-11-28T04:46:20.935Z] [40.140s][info][gc,start ] GC(20) Concurrent strong roots [2025-11-28T04:46:20.935Z] [40.140s][info][gc,task ] GC(20) Using 1 of 1 workers for concurrent strong root [2025-11-28T04:46:20.935Z] [40.140s][info][gc ] GC(20) Concurrent strong roots 0.589ms [2025-11-28T04:46:20.935Z] [40.140s][info][gc,start ] GC(20) Concurrent evacuation [2025-11-28T04:46:20.935Z] [40.140s][info][gc,task ] GC(20) Using 1 of 1 workers for concurrent evacuation [2025-11-28T04:46:20.935Z] [40.149s][info][gc ] GC(20) Concurrent evacuation 8.236ms [2025-11-28T04:46:20.935Z] [40.149s][info][gc,start ] GC(20) Concurrent Init Update Refs (Young) [2025-11-28T04:46:20.935Z] [40.150s][info][gc ] GC(20) Concurrent Init Update Refs (Young) 0.981ms [2025-11-28T04:46:20.935Z] [40.150s][info][gc,start ] GC(20) Pause Init Update Refs [2025-11-28T04:46:20.935Z] [40.150s][info][gc,start ] GC(20) Verify Before Updating References, Level 4 [2025-11-28T04:46:20.935Z] # [2025-11-28T04:46:20.935Z] # A fatal error has been detected by the Java Runtime Environment: [2025-11-28T04:46:20.935Z] # [2025-11-28T04:46:20.935Z] # Internal Error (shenandoahVerifier.cpp:1356), pid=38996, tid=48404 [2025-11-28T04:46:20.935Z] # Error: Remembered set violation at init-update-references; object not properly registered [2025-11-28T04:46:20.935Z] [2025-11-28T04:46:20.935Z] Referenced from: [2025-11-28T04:46:20.935Z] interior location: 0x00000000ffd80000 [2025-11-28T04:46:20.935Z] inside Java heap [2025-11-28T04:46:20.935Z] not in collection set [2025-11-28T04:46:20.935Z] region: | 2043|R |O|BTE ffd80000, ffe00000, ffe00000|TAMS ffd80000|UWM ffe00000|U 512K|T 0B|G 0B|P 255K|S 256K|L 0B|CP 0 [2025-11-28T04:46:20.935Z] [2025-11-28T04:46:20.935Z] Object: [2025-11-28T04:46:20.935Z] 0x00000000ffd80000 - nk 1594472 klass 0x0000000017185468 [Ljdk.internal.vm.FillerElement; [2025-11-28T04:46:20.935Z] [2025-11-28T04:46:20.935Z] allocated after mark start [2025-11-28T04:46:20.935Z] not after update watermark [2025-11-28T04:46:20.935Z] marked strong [2025-11-28T04:46:20.935Z] marked weak [2025-11-28T04:46:20.935Z] not in collection set [2025-11-28T04:46:20.935Z] age: 0 [2025-11-28T04:46:20.935Z] mark: mark(is_unlocked no_hash age=0) [2025-11-28T04:46:20.935Z] region: | 2043|R |O|BTE ffd80000, ffe00000, ffe00000|TAMS ffd80000|UWM ffe00000|U 512K|T 0B|G 0B|P 255K|S 256K|L 0B|CP 0 [2025-11-28T04:46:20.935Z] [2025-11-28T04:46:20.935Z] 0x00000000ffd80000: 00000001 00000000 00185468 00010000 feef50a8 00000000 fe000858 00000000 ........hT.......P......X....... [2025-11-28T04:46:20.935Z] 0x00000000ffd80020: 00000000 fe1300d0 00000000 00000000 fe3c0928 00000000 fe3fb250 00000000 ................(.<.....P.?..... [2025-11-28T04:46:20.935Z] [2025-11-28T04:46:20.935Z] Forwardee: [2025-11-28T04:46:20.935Z] (the object itself) [2025-11-28T04:46:20.935Z] [2025-11-28T04:46:20.935Z] # [2025-11-28T04:46:20.935Z] # JRE version: OpenJDK Runtime Environment Temurin-26+26-202511272142 (26.0+26) (build 26-beta+26-ea) [2025-11-28T04:46:20.935Z] # Java VM: OpenJDK 64-Bit Server VM Temurin-26+26-202511272142 (26-beta+26-ea, mixed mode, sharing, tiered, compressed oops, compressed class ptrs, shenandoah gc, windows-amd64) [2025-11-28T04:46:20.935Z] # Core dump will be written. Default location: C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\scratch\1\hs_err_pid38996.mdmp [2025-11-28T04:46:20.935Z] # [2025-11-28T04:46:20.935Z] # An error report file with more information is saved as: [2025-11-28T04:46:20.935Z] # C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\scratch\1\hs_err_pid38996.log [2025-11-28T04:46:20.935Z] # [2025-11-28T04:46:20.935Z] # If you would like to submit a bug report, please visit: [2025-11-28T04:46:20.935Z] # https://github.com/adoptium/adoptium-support/issues [2025-11-28T04:46:20.935Z] # [2025-11-28T04:46:20.935Z] STDERR: [2025-11-28T04:46:20.935Z] rerun: [2025-11-28T04:46:20.935Z] cd 'C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\scratch\1' && \ [2025-11-28T04:46:20.935Z] 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:\Program Files (x86)\IncrediBuild;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps' \ [2025-11-28T04:46:20.935Z] SystemDrive=C: \ [2025-11-28T04:46:20.935Z] SystemRoot='C:\Windows' \ [2025-11-28T04:46:20.935Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2025-11-28T04:46:20.935Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2025-11-28T04:46:20.935Z] windir='C:\Windows' \ [2025-11-28T04:46:20.935Z] CLASSPATH='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jvmtest\openjdk\jtreg\lib\javatest.jar;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jvmtest\openjdk\jtreg\lib\jtreg.jar' \ [2025-11-28T04:46:20.935Z] 'c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\j2sdk-image\bin\java' \ [2025-11-28T04:46:20.935Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-11-28T04:46:20.935Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-11-28T04:46:20.935Z] -Dtest.compiler.opts= \ [2025-11-28T04:46:20.935Z] -Dtest.java.opts= \ [2025-11-28T04:46:20.935Z] -Dtest.jdk='c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\j2sdk-image' \ [2025-11-28T04:46:20.935Z] -Dcompile.jdk='c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\j2sdk-image' \ [2025-11-28T04:46:20.935Z] -Dtest.timeout.factor=8.0 \ [2025-11-28T04:46:20.935Z] -Dtest.nativepath='c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2025-11-28T04:46:20.936Z] -Dtest.root='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2025-11-28T04:46:20.936Z] -Dtest.name=gc/stress/gcbasher/TestGCBasherWithShenandoah.java#generational \ [2025-11-28T04:46:20.936Z] -Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2025-11-28T04:46:20.936Z] -Dtest.file='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher\TestGCBasherWithShenandoah.java' \ [2025-11-28T04:46:20.936Z] -Dtest.main.class=gc.stress.gcbasher.TestGCBasherWithShenandoah \ [2025-11-28T04:46:20.936Z] -Dtest.src='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher' \ [2025-11-28T04:46:20.936Z] -Dtest.src.path='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg' \ [2025-11-28T04:46:20.936Z] -Dtest.classes='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d' \ [2025-11-28T04:46:20.936Z] -Dtest.class.path='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d' \ [2025-11-28T04:46:20.936Z] -Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\classes\1\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d;C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\openjdk\openjdk-jdk\test\hotspot\jtreg\gc\stress\gcbasher' \ [2025-11-28T04:46:20.936Z] -ea \ [2025-11-28T04:46:20.936Z] -esa \ [2025-11-28T04:46:20.936Z] -Xmx512m \ [2025-11-28T04:46:20.936Z] '-Djava.library.path=c:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\jdkbinary\openjdk-test-image\hotspot\jtreg\native' \ [2025-11-28T04:46:20.936Z] -Xlog:gc*=info \ [2025-11-28T04:46:20.936Z] -Xmx1g \ [2025-11-28T04:46:20.936Z] -XX:+UnlockDiagnosticVMOptions \ [2025-11-28T04:46:20.936Z] -XX:+UnlockExperimentalVMOptions \ [2025-11-28T04:46:20.936Z] -XX:+UseShenandoahGC \ [2025-11-28T04:46:20.936Z] -XX:ShenandoahGCMode=generational \ [2025-11-28T04:46:20.936Z] -XX:+ShenandoahVerify \ [2025-11-28T04:46:20.936Z] com.sun.javatest.regtest.agent.MainWrapper 'C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work\gc\stress\gcbasher\TestGCBasherWithShenandoah_generational.d\main.0.jta' 120000 [2025-11-28T04:46:20.936Z] [2025-11-28T04:46:20.936Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 1] [2025-11-28T04:46:20.936Z] -------------------------------------------------- [2025-11-28T04:54:50.451Z] test-azure-win2022-x64-4 seems to be removed or offline (java.lang.InterruptedException); will wait for 5 min 0 sec for it to come back online [2025-11-28T04:55:05.452Z] test-azure-win2022-x64-4 is back online [2025-11-28T06:06:00.162Z] Test results: passed: 532; failed: 1; skipped: 2; excluded: 11; did not meet platform requirements: 40 [2025-11-28T06:09:08.354Z] Report written to C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\report\html\report.html [2025-11-28T06:09:08.354Z] Results written to C:\jenkins\workspace\Test_openjdk26_hs_extended.openjdk_x86-64_windows_testList_2\aqa-tests\TKG\output_17642919364527\hotspot_gc_0\work [2025-11-28T06:09:08.354Z] Error: Some tests failed or other problems occurred. [2025-11-28T06:09:08.354Z] ----------------------------------- [2025-11-28T06:09:08.354Z] hotspot_gc_0_FAILED [2025-11-28T06:09:08.354Z] ----------------------------------- [2025-11-28T06:09:08.354Z] [2025-11-28T06:09:08.354Z] TEST TEARDOWN: [2025-11-28T06:09:08.354Z] Nothing to be done for teardown. [2025-11-28T06:09:08.354Z] hotspot_gc_0 Finish Time: Fri Nov 28 06:09:04 2025 Epoch Time (ms): 1764310144529