[2025-11-28T00:33:36.634Z] Running test hotspot_tier1_gc_0 ... [2025-11-28T00:33:36.634Z] =============================================== [2025-11-28T00:33:36.634Z] hotspot_tier1_gc_0 Start Time: Fri Nov 28 00:33:34 2025 Epoch Time (ms): 1764290014948 [2025-11-28T00:33:36.634Z] variation: NoOptions [2025-11-28T00:33:36.634Z] JVM_OPTIONS: [2025-11-28T00:33:36.634Z] { \ [2025-11-28T00:33:36.634Z] echo ""; echo "TEST SETUP:"; \ [2025-11-28T00:33:36.634Z] echo "Nothing to be done for setup."; \ [2025-11-28T00:33:36.634Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17642865493326/hotspot_tier1_gc_0"; \ [2025-11-28T00:33:36.634Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17642865493326/hotspot_tier1_gc_0"; \ [2025-11-28T00:33:36.634Z] echo ""; echo "TESTING:"; \ [2025-11-28T00:33:36.634Z] "/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-11-28T00:33:36.634Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:12 -xml:verify -concurrency:3 -nativepath:"/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2025-11-28T00:33:36.634Z] -w ""/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17642865493326/hotspot_tier1_gc_0"/work" \ [2025-11-28T00:33:36.634Z] -r ""/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17642865493326/hotspot_tier1_gc_0"/report" \ [2025-11-28T00:33:36.634Z] -jdk:"/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image" \ [2025-11-28T00:33:36.634Z] -exclude:"/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2025-11-28T00:33:36.634Z] -exclude:"/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk26.txt" \ [2025-11-28T00:33:36.634Z] \ [2025-11-28T00:33:36.634Z] \ [2025-11-28T00:33:36.634Z] "/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:tier1_gc"; \ [2025-11-28T00:33:36.634Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_tier1_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_tier1_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-28T00:33:36.634Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-28T00:33:36.634Z] echo "Nothing to be done for teardown."; \ [2025-11-28T00:33:36.634Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17642865493326/TestTargetResult"; [2025-11-28T00:33:36.634Z] [2025-11-28T00:33:36.634Z] TEST SETUP: [2025-11-28T00:33:36.634Z] Nothing to be done for setup. [2025-11-28T00:33:36.634Z] [2025-11-28T00:33:36.634Z] TESTING: [2025-11-28T00:33:38.595Z] Directory "/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17642865493326/hotspot_tier1_gc_0/work" not found: creating [2025-11-28T00:33:38.595Z] Directory "/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17642865493326/hotspot_tier1_gc_0/report" not found: creating [2025-11-28T00:33:59.879Z] XML output with verification to /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work [2025-11-28T00:42:27.850Z] -------------------------------------------------- [2025-11-28T00:42:27.850Z] TEST: gc/TestAllocHumongousFragment.java#generational [2025-11-28T00:42:27.850Z] TEST JDK: /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image [2025-11-28T00:42:27.850Z] [2025-11-28T00:42:27.850Z] ACTION: build -- Passed. Build successful [2025-11-28T00:42:27.850Z] REASON: Named class compiled on demand [2025-11-28T00:42:27.850Z] TIME: 1.948 seconds [2025-11-28T00:42:27.850Z] messages: [2025-11-28T00:42:27.850Z] command: build TestAllocHumongousFragment [2025-11-28T00:42:27.850Z] reason: Named class compiled on demand [2025-11-28T00:42:27.850Z] started: Fri Nov 28 00:40:59.740 UTC 2025 [2025-11-28T00:42:27.850Z] Test directory: [2025-11-28T00:42:27.850Z] compile: TestAllocHumongousFragment [2025-11-28T00:42:27.850Z] finished: Fri Nov 28 00:41:01.688 UTC 2025 [2025-11-28T00:42:27.850Z] elapsed time (seconds): 1.948 [2025-11-28T00:42:27.850Z] [2025-11-28T00:42:27.850Z] ACTION: compile -- Passed. Compilation successful [2025-11-28T00:42:27.850Z] REASON: .class file out of date or does not exist [2025-11-28T00:42:27.850Z] TIME: 1.943 seconds [2025-11-28T00:42:27.850Z] messages: [2025-11-28T00:42:27.850Z] command: compile /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java [2025-11-28T00:42:27.850Z] reason: .class file out of date or does not exist [2025-11-28T00:42:27.850Z] started: Fri Nov 28 00:40:59.745 UTC 2025 [2025-11-28T00:42:27.850Z] Mode: agentvm [2025-11-28T00:42:27.850Z] Agent id: 1 [2025-11-28T00:42:27.850Z] Process id: 789944 [2025-11-28T00:42:27.850Z] finished: Fri Nov 28 00:41:01.688 UTC 2025 [2025-11-28T00:42:27.850Z] elapsed time (seconds): 1.943 [2025-11-28T00:42:27.850Z] configuration: [2025-11-28T00:42:27.850Z] Boot Layer (javac runtime environment) [2025-11-28T00:42:27.850Z] class path: /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-11-28T00:42:27.850Z] /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-11-28T00:42:27.850Z] patch: java.base /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/patches/java.base [2025-11-28T00:42:27.850Z] [2025-11-28T00:42:27.850Z] javac compilation environment [2025-11-28T00:42:27.850Z] source path: /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc [2025-11-28T00:42:27.850Z] /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-11-28T00:42:27.850Z] class path: /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc [2025-11-28T00:42:27.850Z] /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d [2025-11-28T00:42:27.850Z] /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib [2025-11-28T00:42:27.850Z] [2025-11-28T00:42:27.850Z] rerun: [2025-11-28T00:42:27.850Z] cd /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/scratch/0 && \ [2025-11-28T00:42:27.850Z] DISPLAY=:0 \ [2025-11-28T00:42:27.850Z] HOME=/home/jenkins \ [2025-11-28T00:42:27.850Z] LANG=C.UTF-8 \ [2025-11-28T00:42:27.850Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-11-28T00:42:27.850Z] /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image/bin/javac \ [2025-11-28T00:42:27.850Z] -J-ea \ [2025-11-28T00:42:27.850Z] -J-esa \ [2025-11-28T00:42:27.850Z] -J-Xmx512m \ [2025-11-28T00:42:27.850Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-11-28T00:42:27.850Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-11-28T00:42:27.850Z] -J-Dtest.compiler.opts= \ [2025-11-28T00:42:27.850Z] -J-Dtest.java.opts= \ [2025-11-28T00:42:27.850Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image \ [2025-11-28T00:42:27.850Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image \ [2025-11-28T00:42:27.850Z] -J-Dtest.timeout.factor=12.0 \ [2025-11-28T00:42:27.850Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-11-28T00:42:27.851Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-11-28T00:42:27.851Z] -J-Dtest.name=gc/TestAllocHumongousFragment.java#generational \ [2025-11-28T00:42:27.851Z] -J-Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2025-11-28T00:42:27.851Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java \ [2025-11-28T00:42:27.851Z] -J-Dtest.main.class=TestAllocHumongousFragment \ [2025-11-28T00:42:27.851Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc \ [2025-11-28T00:42:27.851Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-11-28T00:42:27.851Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d \ [2025-11-28T00:42:27.851Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T00:42:27.851Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T00:42:27.851Z] -d /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d \ [2025-11-28T00:42:27.851Z] -sourcepath /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-11-28T00:42:27.851Z] -classpath /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java [2025-11-28T00:42:27.851Z] [2025-11-28T00:42:27.851Z] ACTION: main -- Passed. Execution successful [2025-11-28T00:42:27.851Z] REASON: User specified action: run main/othervm -Xmx1g -Xms1g -Xlog:gc -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:ShenandoahTargetNumRegions=2048 -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=adaptive -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify TestAllocHumongousFragment [2025-11-28T00:42:27.851Z] TIME: 38.493 seconds [2025-11-28T00:42:27.851Z] messages: [2025-11-28T00:42:27.851Z] command: main -Xmx1g -Xms1g -Xlog:gc -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:ShenandoahTargetNumRegions=2048 -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=adaptive -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify TestAllocHumongousFragment [2025-11-28T00:42:27.851Z] reason: User specified action: run main/othervm -Xmx1g -Xms1g -Xlog:gc -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:ShenandoahTargetNumRegions=2048 -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=adaptive -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify TestAllocHumongousFragment [2025-11-28T00:42:27.851Z] started: Fri Nov 28 00:41:01.689 UTC 2025 [2025-11-28T00:42:27.851Z] Mode: othervm [/othervm specified] [2025-11-28T00:42:27.851Z] Process id: 795179 [2025-11-28T00:42:27.851Z] finished: Fri Nov 28 00:41:40.182 UTC 2025 [2025-11-28T00:42:27.851Z] elapsed time (seconds): 38.493 [2025-11-28T00:42:27.851Z] configuration: [2025-11-28T00:42:27.851Z] STDOUT: [2025-11-28T00:42:27.851Z] [0.003s][info][gc] Min heap equals to max heap, disabling ShenandoahUncommit [2025-11-28T00:42:27.851Z] [0.013s][info][gc] Heuristics ergonomically sets -XX:GCTimeRatio=70 [2025-11-28T00:42:27.851Z] [0.013s][info][gc] Heuristics ergonomically sets -XX:+ExplicitGCInvokesConcurrent [2025-11-28T00:42:27.851Z] [0.013s][info][gc] Heuristics ergonomically sets -XX:+ShenandoahImplicitGCInvokesConcurrent [2025-11-28T00:42:27.851Z] [0.013s][info][gc] Heuristics ergonomically sets -XX:+UseCondCardMark [2025-11-28T00:42:27.851Z] [0.013s][info][gc] Using Shenandoah [2025-11-28T00:42:27.851Z] For random generator using seed: 3723532219 [2025-11-28T00:42:27.851Z] To re-run test with same seed value please add "-Djdk.test.lib.random.seed=3723532219" to command line. [2025-11-28T00:42:27.851Z] Allocated: 51 Mb [2025-11-28T00:42:27.851Z] Allocated: 106 Mb [2025-11-28T00:42:27.851Z] Allocated: 122 Mb [2025-11-28T00:42:27.851Z] Allocated: 158 Mb [2025-11-28T00:42:27.851Z] Allocated: 216 Mb [2025-11-28T00:42:27.851Z] Allocated: 246 Mb [2025-11-28T00:42:27.851Z] [1.145s][info][gc] Trigger (Young): Learning 1 of 5. Free (698M) is below initial threshold (716M) [2025-11-28T00:42:27.851Z] [1.148s][info][gc] GC(0) Concurrent reset (Young) 2.088ms [2025-11-28T00:42:27.851Z] [1.238s][info][gc] GC(0) Verify Before Mark, Level 4 (34669 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [1.239s][info][gc] GC(0) Pause Init Mark (Young) 54.338ms [2025-11-28T00:42:27.851Z] [1.239s][info][gc] GC(0) Concurrent remembered set scanning 0.245ms [2025-11-28T00:42:27.851Z] [1.240s][info][gc] GC(0) Concurrent marking roots 0.701ms [2025-11-28T00:42:27.851Z] Allocated: 269 Mb [2025-11-28T00:42:27.851Z] Allocated: 314 Mb [2025-11-28T00:42:27.851Z] [1.318s][info][gc] GC(0) Concurrent marking (Young) 77.930ms [2025-11-28T00:42:27.851Z] [1.372s][info][gc] GC(0) Verify Before Evacuation, Level 4 (34653 reachable, 1764 marked) [2025-11-28T00:42:27.851Z] [1.372s][info][gc] GC(0) Pause Final Mark (Young) 40.293ms [2025-11-28T00:42:27.851Z] [1.373s][info][gc] GC(0) Concurrent thread roots 0.424ms [2025-11-28T00:42:27.851Z] [1.373s][info][gc] GC(0) Concurrent weak references (Young) 0.189ms [2025-11-28T00:42:27.851Z] Allocated: 328 Mb [2025-11-28T00:42:27.851Z] [1.377s][info][gc] GC(0) Concurrent weak roots (Young) 3.414ms [2025-11-28T00:42:27.851Z] [1.377s][info][gc] GC(0) Concurrent cleanup (Young) 383M->383M(1024M) 0.090ms [2025-11-28T00:42:27.851Z] [1.378s][info][gc] GC(0) Concurrent strong roots 1.329ms [2025-11-28T00:42:27.851Z] [1.384s][info][gc] GC(0) Concurrent evacuation 5.745ms [2025-11-28T00:42:27.851Z] Allocated: 377 Mb [2025-11-28T00:42:27.851Z] [1.458s][info][gc] GC(0) Concurrent Init Update Refs (Young) 73.452ms [2025-11-28T00:42:27.851Z] [1.532s][info][gc] GC(0) Verify Before Updating References, Level 4 (36464 reachable, 12690 marked) [2025-11-28T00:42:27.851Z] [1.533s][info][gc] GC(0) Pause Init Update Refs 67.927ms [2025-11-28T00:42:27.851Z] Allocated: 380 Mb [2025-11-28T00:42:27.851Z] [1.556s][info][gc] GC(0) Concurrent update references 22.654ms [2025-11-28T00:42:27.851Z] [1.676s][info][gc] GC(0) Concurrent update thread roots 119.836ms [2025-11-28T00:42:27.851Z] [1.732s][info][gc] GC(0) Verify After Updating References, Level 4 (36403 reachable, 46 marked) [2025-11-28T00:42:27.851Z] [1.732s][info][gc] GC(0) Pause Final Update Refs 55.796ms [2025-11-28T00:42:27.851Z] [1.732s][info][gc] GC(0) Concurrent cleanup (Young) 449M->449M(1024M) 0.055ms [2025-11-28T00:42:27.851Z] Allocated: 444 Mb [2025-11-28T00:42:27.851Z] [1.745s][info][gc] GC(0) Concurrent reset after collect (Young) 13.145ms [2025-11-28T00:42:27.851Z] [1.755s][info][gc] Trigger (Young): Learning 2 of 5. Free (480M) is below initial threshold (716M) [2025-11-28T00:42:27.851Z] [1.757s][info][gc] GC(1) Concurrent reset (Young) 1.583ms [2025-11-28T00:42:27.851Z] [1.870s][info][gc] GC(1) Verify Before Mark, Level 4 (34657 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [1.871s][info][gc] GC(1) Pause Init Mark (Young) 43.500ms [2025-11-28T00:42:27.851Z] Allocated: 485 Mb [2025-11-28T00:42:27.851Z] [1.878s][info][gc] GC(1) Concurrent remembered set scanning 4.687ms [2025-11-28T00:42:27.851Z] [1.891s][info][gc] GC(1) Concurrent marking roots 13.190ms [2025-11-28T00:42:27.851Z] Allocated: 549 Mb [2025-11-28T00:42:27.851Z] Allocated: 572 Mb [2025-11-28T00:42:27.851Z] [2.089s][info][gc] GC(1) Concurrent marking (Young) 197.462ms [2025-11-28T00:42:27.851Z] [2.156s][info][gc] GC(1) Verify Before Evacuation, Level 4 (34660 reachable, 1766 marked) [2025-11-28T00:42:27.851Z] [2.156s][info][gc] GC(1) Pause Final Mark (Young) 66.793ms [2025-11-28T00:42:27.851Z] [2.160s][info][gc] GC(1) Concurrent thread roots 1.001ms [2025-11-28T00:42:27.851Z] [2.160s][info][gc] GC(1) Concurrent weak references (Young) 0.232ms [2025-11-28T00:42:27.851Z] Allocated: 610 Mb [2025-11-28T00:42:27.851Z] [2.247s][info][gc] GC(1) Concurrent weak roots (Young) 86.740ms [2025-11-28T00:42:27.851Z] [2.247s][info][gc] GC(1) Concurrent cleanup (Young) 617M->617M(1024M) 0.077ms [2025-11-28T00:42:27.851Z] [2.248s][info][gc] GC(1) Concurrent strong roots 0.931ms [2025-11-28T00:42:27.851Z] [2.251s][info][gc] GC(1) Concurrent evacuation 3.323ms [2025-11-28T00:42:27.851Z] Allocated: 626 Mb [2025-11-28T00:42:27.851Z] [2.290s][info][gc] GC(1) Concurrent Init Update Refs (Young) 38.971ms [2025-11-28T00:42:27.851Z] [2.438s][info][gc] GC(1) Verify Before Updating References, Level 4 (36408 reachable, 7522 marked) [2025-11-28T00:42:27.851Z] [2.438s][info][gc] GC(1) Pause Init Update Refs 44.045ms [2025-11-28T00:42:27.851Z] Allocated: 674 Mb [2025-11-28T00:42:27.851Z] [2.444s][info][gc] GC(1) Concurrent update references 5.431ms [2025-11-28T00:42:27.851Z] Allocated: 733 Mb [2025-11-28T00:42:27.851Z] [2.532s][info][gc] GC(1) Concurrent update thread roots 87.753ms [2025-11-28T00:42:27.851Z] [2.568s][info][gc] GC(1) Verify After Updating References, Level 4 (36410 reachable, 45 marked) [2025-11-28T00:42:27.851Z] [2.568s][info][gc] GC(1) Pause Final Update Refs 36.601ms [2025-11-28T00:42:27.851Z] [2.569s][info][gc] GC(1) Concurrent cleanup (Young) 766M->766M(1024M) 0.065ms [2025-11-28T00:42:27.851Z] [2.586s][info][gc] GC(1) Concurrent reset after collect (Young) 17.388ms [2025-11-28T00:42:27.851Z] [2.589s][info][gc] Trigger (Young): Learning 3 of 5. Free (206M) is below initial threshold (716M) [2025-11-28T00:42:27.851Z] [2.590s][info][gc] GC(2) Concurrent reset (Young) 0.495ms [2025-11-28T00:42:27.851Z] [2.608s][info][gc] GC(2) Verify Before Mark, Level 4 (34662 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [2.609s][info][gc] GC(2) Pause Init Mark (Young) 16.875ms [2025-11-28T00:42:27.851Z] [2.611s][info][gc] GC(2) Concurrent remembered set scanning 0.217ms [2025-11-28T00:42:27.851Z] Allocated: 681 Mb [2025-11-28T00:42:27.851Z] [2.613s][info][gc] GC(2) Concurrent marking roots 1.152ms [2025-11-28T00:42:27.851Z] Allocated: 701 Mb [2025-11-28T00:42:27.851Z] Allocated: 655 Mb [2025-11-28T00:42:27.851Z] [2.644s][info][gc] GC(2) Concurrent marking (Young) 30.977ms [2025-11-28T00:42:27.851Z] [2.683s][info][gc] GC(2) Verify Before Evacuation, Level 4 (34664 reachable, 1768 marked) [2025-11-28T00:42:27.851Z] [2.684s][info][gc] GC(2) Pause Final Mark (Young) 27.367ms [2025-11-28T00:42:27.851Z] [2.684s][info][gc] GC(2) Concurrent thread roots 0.248ms [2025-11-28T00:42:27.851Z] Allocated: 661 Mb [2025-11-28T00:42:27.851Z] [2.694s][info][gc] GC(2) Concurrent weak references (Young) 9.957ms [2025-11-28T00:42:27.851Z] Allocated: 711 Mb [2025-11-28T00:42:27.851Z] [2.758s][info][gc] GC(2) Concurrent weak roots (Young) 63.153ms [2025-11-28T00:42:27.851Z] [2.758s][info][gc] GC(2) Concurrent cleanup (Young) 833M->833M(1024M) 0.120ms [2025-11-28T00:42:27.851Z] [2.758s][info][gc] GC(2) Concurrent strong roots 0.628ms [2025-11-28T00:42:27.851Z] [2.761s][info][gc] GC(2) Concurrent evacuation 2.653ms [2025-11-28T00:42:27.851Z] Allocated: 733 Mb [2025-11-28T00:42:27.851Z] [2.810s][info][gc] GC(2) Concurrent Init Update Refs (Young) 49.172ms [2025-11-28T00:42:27.851Z] [2.833s][info][gc] GC(2) Verify Before Updating References, Level 4 (2166 reachable, 18328 marked) [2025-11-28T00:42:27.851Z] [2.833s][info][gc] GC(2) Pause Init Update Refs 21.799ms [2025-11-28T00:42:27.851Z] Allocated: 725 Mb [2025-11-28T00:42:27.851Z] [2.849s][info][gc] GC(2) Concurrent update references 15.226ms [2025-11-28T00:42:27.851Z] Allocated: 695 Mb [2025-11-28T00:42:27.851Z] [2.869s][info][gc] GC(2) Concurrent update thread roots 20.144ms [2025-11-28T00:42:27.851Z] [2.903s][info][gc] GC(2) Verify After Updating References, Level 4 (2167 reachable, 10786 marked) [2025-11-28T00:42:27.851Z] [2.904s][info][gc] GC(2) Pause Final Update Refs 18.879ms [2025-11-28T00:42:27.851Z] Allocated: 715 Mb [2025-11-28T00:42:27.851Z] [2.904s][info][gc] Failed to allocate Shared, 16454K [2025-11-28T00:42:27.851Z] [2.904s][info][gc] GC(2) Concurrent cleanup (Young) 927M->927M(1024M) 0.066ms [2025-11-28T00:42:27.851Z] [2.924s][info][gc] GC(2) Concurrent reset after collect (Young) 19.498ms [2025-11-28T00:42:27.851Z] [2.925s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.851Z] [2.943s][info][gc] GC(3) Verify Before Full GC, Level 4 (34665 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.418s][info][gc] GC(3) Verify After Full GC, Level 4 (34530 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.418s][info][gc] GC(3) Pause Full 927M->684M(1024M) 491.920ms [2025-11-28T00:42:27.851Z] Allocated: 694 Mb [2025-11-28T00:42:27.851Z] Allocated: 736 Mb [2025-11-28T00:42:27.851Z] Allocated: 662 Mb [2025-11-28T00:42:27.851Z] [3.423s][info][gc] Trigger (Young): Learning 4 of 5. Free (183M) is below initial threshold (716M) [2025-11-28T00:42:27.851Z] [3.424s][info][gc] GC(4) Concurrent reset (Young) 0.506ms [2025-11-28T00:42:27.851Z] [3.426s][info][gc] GC(4) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.426s][info][gc] GC(4) Pause Init Mark (Young) 1.894ms [2025-11-28T00:42:27.851Z] Allocated: 702 Mb [2025-11-28T00:42:27.851Z] Allocated: 700 Mb [2025-11-28T00:42:27.851Z] Allocated: 713 Mb [2025-11-28T00:42:27.851Z] Allocated: 677 Mb [2025-11-28T00:42:27.851Z] Allocated: 723 Mb [2025-11-28T00:42:27.851Z] [3.434s][info][gc] GC(4) Concurrent remembered set scanning 4.625ms [2025-11-28T00:42:27.851Z] Allocated: 703 Mb [2025-11-28T00:42:27.851Z] [3.437s][info][gc] GC(4) Concurrent marking roots 2.554ms [2025-11-28T00:42:27.851Z] Allocated: 708 Mb [2025-11-28T00:42:27.851Z] Allocated: 689 Mb [2025-11-28T00:42:27.851Z] [3.442s][info][gc] Failed to allocate Shared, 25376K [2025-11-28T00:42:27.851Z] [3.448s][info][gc] GC(4) Concurrent marking (Young) 10.514ms [2025-11-28T00:42:27.851Z] [3.448s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.851Z] [3.460s][info][gc] GC(5) Verify Before Full GC, Level 4 (34637 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.460s][info][gc] GC(5) Cancel marking: Young [2025-11-28T00:42:27.851Z] [3.460s][info][gc] GC(5) Cancel marking: Old [2025-11-28T00:42:27.851Z] [3.460s][info][gc] GC(5) Cancel marking: Global [2025-11-28T00:42:27.851Z] [3.625s][info][gc] GC(5) Verify After Full GC, Level 4 (34533 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.625s][info][gc] GC(5) Pause Full 954M->697M(1024M) 176.776ms [2025-11-28T00:42:27.851Z] [3.626s][info][gc] Trigger (Young): Learning 4 of 5. Free (249M) is below initial threshold (716M) [2025-11-28T00:42:27.851Z] [3.626s][info][gc] GC(6) Concurrent reset (Young) 0.155ms [2025-11-28T00:42:27.851Z] [3.632s][info][gc] GC(6) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.632s][info][gc] GC(6) Pause Init Mark (Young) 5.248ms [2025-11-28T00:42:27.851Z] Allocated: 714 Mb [2025-11-28T00:42:27.851Z] [3.633s][info][gc] GC(6) Concurrent remembered set scanning 0.236ms [2025-11-28T00:42:27.851Z] [3.634s][info][gc] GC(6) Concurrent marking roots 0.495ms [2025-11-28T00:42:27.851Z] Allocated: 657 Mb [2025-11-28T00:42:27.851Z] [3.636s][info][gc] GC(6) Concurrent marking (Young) 0.915ms [2025-11-28T00:42:27.851Z] [3.641s][info][gc] GC(6) Verify After Mark, Level 4 (26 reachable, 10 marked) [2025-11-28T00:42:27.851Z] [3.642s][info][gc] GC(6) Pause Final Mark (Young) 5.126ms [2025-11-28T00:42:27.851Z] [3.643s][info][gc] GC(6) Concurrent weak references (Young) 0.103ms [2025-11-28T00:42:27.851Z] Allocated: 716 Mb [2025-11-28T00:42:27.851Z] [3.650s][info][gc] GC(6) Concurrent weak roots (Young) 7.316ms [2025-11-28T00:42:27.851Z] [3.650s][info][gc] GC(6) Concurrent cleanup (Young) 841M->841M(1024M) 0.059ms [2025-11-28T00:42:27.851Z] Allocated: 712 Mb [2025-11-28T00:42:27.851Z] Allocated: 660 Mb [2025-11-28T00:42:27.851Z] [3.656s][info][gc] GC(6) Concurrent Final Roots (Young) 6.077ms [2025-11-28T00:42:27.851Z] Allocated: 675 Mb [2025-11-28T00:42:27.851Z] [3.661s][info][gc] GC(6) Concurrent reset after collect (Young) 4.863ms [2025-11-28T00:42:27.851Z] Allocated: 684 Mb [2025-11-28T00:42:27.851Z] [3.663s][info][gc] Trigger (Young): Free (73471K) is below minimum threshold (102M) [2025-11-28T00:42:27.851Z] [3.664s][info][gc] GC(7) Concurrent reset (Young) 0.375ms [2025-11-28T00:42:27.851Z] [3.669s][info][gc] GC(7) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.669s][info][gc] GC(7) Pause Init Mark (Young) 2.727ms [2025-11-28T00:42:27.851Z] [3.669s][info][gc] GC(7) Concurrent remembered set scanning 0.244ms [2025-11-28T00:42:27.851Z] Allocated: 713 Mb [2025-11-28T00:42:27.851Z] [3.669s][info][gc] GC(7) Concurrent marking roots 0.344ms [2025-11-28T00:42:27.851Z] Allocated: 675 Mb [2025-11-28T00:42:27.851Z] [3.670s][info][gc] GC(7) Concurrent marking (Young) 0.708ms [2025-11-28T00:42:27.851Z] [3.674s][info][gc] GC(7) Verify After Mark, Level 4 (27 reachable, 11 marked) [2025-11-28T00:42:27.851Z] [3.674s][info][gc] GC(7) Pause Final Mark (Young) 3.939ms [2025-11-28T00:42:27.851Z] [3.676s][info][gc] GC(7) Concurrent weak references (Young) 0.083ms [2025-11-28T00:42:27.851Z] Allocated: 729 Mb [2025-11-28T00:42:27.851Z] [3.680s][info][gc] GC(7) Concurrent weak roots (Young) 3.861ms [2025-11-28T00:42:27.851Z] [3.680s][info][gc] GC(7) Concurrent cleanup (Young) 835M->835M(1024M) 0.058ms [2025-11-28T00:42:27.851Z] Allocated: 749 Mb [2025-11-28T00:42:27.851Z] [3.685s][info][gc] GC(7) Concurrent Final Roots (Young) 5.785ms [2025-11-28T00:42:27.851Z] Allocated: 692 Mb [2025-11-28T00:42:27.851Z] Allocated: 717 Mb [2025-11-28T00:42:27.851Z] [3.693s][info][gc] GC(7) Concurrent reset after collect (Young) 6.944ms [2025-11-28T00:42:27.851Z] [3.693s][info][gc] Failed to allocate Shared, 60508K [2025-11-28T00:42:27.851Z] [3.693s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.851Z] [3.705s][info][gc] GC(8) Verify Before Full GC, Level 4 (34638 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.882s][info][gc] GC(8) Verify After Full GC, Level 4 (34534 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.883s][info][gc] GC(8) Pause Full 869M->681M(1024M) 188.889ms [2025-11-28T00:42:27.851Z] [3.884s][info][gc] Trigger (Young): Average GC time (585.79 ms) is above the time for average allocation rate (2287 MB/s) to deplete free headroom (179M) (margin of error = 1.80) [2025-11-28T00:42:27.851Z] [3.884s][info][gc] GC(9) Concurrent reset (Young) 0.129ms [2025-11-28T00:42:27.851Z] [3.887s][info][gc] GC(9) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.887s][info][gc] GC(9) Pause Init Mark (Young) 1.163ms [2025-11-28T00:42:27.851Z] Allocated: 731 Mb [2025-11-28T00:42:27.851Z] [3.888s][info][gc] GC(9) Concurrent remembered set scanning 0.103ms [2025-11-28T00:42:27.851Z] [3.888s][info][gc] GC(9) Concurrent marking roots 0.131ms [2025-11-28T00:42:27.851Z] Allocated: 712 Mb [2025-11-28T00:42:27.851Z] [3.892s][info][gc] GC(9) Concurrent marking (Young) 4.028ms [2025-11-28T00:42:27.851Z] [3.895s][info][gc] GC(9) Verify After Mark, Level 4 (24 reachable, 13 marked) [2025-11-28T00:42:27.851Z] [3.895s][info][gc] GC(9) Pause Final Mark (Young) 2.321ms [2025-11-28T00:42:27.851Z] Allocated: 697 Mb [2025-11-28T00:42:27.851Z] [3.896s][info][gc] GC(9) Concurrent weak references (Young) 0.014ms [2025-11-28T00:42:27.851Z] [3.896s][info][gc] GC(9) Concurrent weak roots (Young) 0.103ms [2025-11-28T00:42:27.851Z] [3.896s][info][gc] GC(9) Concurrent cleanup (Young) 822M->822M(1024M) 0.014ms [2025-11-28T00:42:27.851Z] [3.898s][info][gc] GC(9) Concurrent Final Roots (Young) 1.186ms [2025-11-28T00:42:27.851Z] Allocated: 735 Mb [2025-11-28T00:42:27.851Z] [3.901s][info][gc] GC(9) Concurrent reset after collect (Young) 2.899ms [2025-11-28T00:42:27.851Z] [3.901s][info][gc] Trigger (Young): Free (54527K) is below minimum threshold (102M) [2025-11-28T00:42:27.851Z] Allocated: 724 Mb [2025-11-28T00:42:27.851Z] [3.903s][info][gc] GC(10) Concurrent reset (Young) 0.124ms [2025-11-28T00:42:27.851Z] [3.904s][info][gc] GC(10) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.851Z] [3.904s][info][gc] GC(10) Pause Init Mark (Young) 1.272ms [2025-11-28T00:42:27.851Z] [3.905s][info][gc] GC(10) Concurrent remembered set scanning 0.168ms [2025-11-28T00:42:27.851Z] [3.905s][info][gc] GC(10) Concurrent marking roots 0.161ms [2025-11-28T00:42:27.851Z] [3.905s][info][gc] GC(10) Concurrent marking (Young) 0.065ms [2025-11-28T00:42:27.851Z] [3.907s][info][gc] GC(10) Verify After Mark, Level 4 (25 reachable, 2 marked) [2025-11-28T00:42:27.851Z] [3.907s][info][gc] GC(10) Pause Final Mark (Young) 2.300ms [2025-11-28T00:42:27.851Z] [3.909s][info][gc] GC(10) Concurrent weak references (Young) 0.015ms [2025-11-28T00:42:27.851Z] [3.909s][info][gc] GC(10) Concurrent weak roots (Young) 0.939ms [2025-11-28T00:42:27.851Z] [3.910s][info][gc] GC(10) Concurrent cleanup (Young) 774M->774M(1024M) 0.039ms [2025-11-28T00:42:27.851Z] [3.910s][info][gc] GC(10) Concurrent Final Roots (Young) 0.433ms [2025-11-28T00:42:27.851Z] Allocated: 681 Mb [2025-11-28T00:42:27.851Z] [3.914s][info][gc] GC(10) Concurrent reset after collect (Young) 3.559ms [2025-11-28T00:42:27.851Z] [3.914s][info][gc] Trigger (Young): Average GC time (291.90 ms) is above the time for average allocation rate (6055 MB/s) to deplete free headroom (108M) (margin of error = 1.81) [2025-11-28T00:42:27.852Z] [3.914s][info][gc] GC(11) Concurrent reset (Young) 0.101ms [2025-11-28T00:42:27.852Z] Allocated: 717 Mb [2025-11-28T00:42:27.852Z] [3.917s][info][gc] GC(11) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [3.917s][info][gc] GC(11) Pause Init Mark (Young) 1.837ms [2025-11-28T00:42:27.852Z] [3.917s][info][gc] GC(11) Concurrent remembered set scanning 0.184ms [2025-11-28T00:42:27.852Z] [3.918s][info][gc] GC(11) Concurrent marking roots 0.673ms [2025-11-28T00:42:27.852Z] [3.918s][info][gc] Failed to allocate Shared, 59298K [2025-11-28T00:42:27.852Z] [3.918s][info][gc] GC(11) Concurrent marking (Young) 0.100ms [2025-11-28T00:42:27.852Z] [3.918s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.852Z] [3.924s][info][gc] GC(12) Verify Before Full GC, Level 4 (34635 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [3.924s][info][gc] GC(12) Cancel marking: Young [2025-11-28T00:42:27.852Z] [3.924s][info][gc] GC(12) Cancel marking: Old [2025-11-28T00:42:27.852Z] [3.924s][info][gc] GC(12) Cancel marking: Global [2025-11-28T00:42:27.852Z] [4.042s][info][gc] GC(12) Verify After Full GC, Level 4 (34531 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.042s][info][gc] GC(12) Pause Full 811M->695M(1024M) 124.159ms [2025-11-28T00:42:27.852Z] [4.043s][info][gc] Trigger (Young): Average GC time (291.90 ms) is above the time for average allocation rate (2077 MB/s) to deplete free headroom (167M) (margin of error = 1.81) [2025-11-28T00:42:27.852Z] [4.044s][info][gc] GC(13) Concurrent reset (Young) 0.134ms [2025-11-28T00:42:27.852Z] [4.047s][info][gc] GC(13) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.047s][info][gc] GC(13) Pause Init Mark (Young) 1.169ms [2025-11-28T00:42:27.852Z] Allocated: 744 Mb [2025-11-28T00:42:27.852Z] [4.047s][info][gc] GC(13) Concurrent remembered set scanning 0.144ms [2025-11-28T00:42:27.852Z] [4.048s][info][gc] GC(13) Concurrent marking roots 0.132ms [2025-11-28T00:42:27.852Z] Allocated: 704 Mb [2025-11-28T00:42:27.852Z] [4.051s][info][gc] GC(13) Concurrent marking (Young) 2.487ms [2025-11-28T00:42:27.852Z] [4.055s][info][gc] GC(13) Verify After Mark, Level 4 (22 reachable, 11 marked) [2025-11-28T00:42:27.852Z] [4.055s][info][gc] GC(13) Pause Final Mark (Young) 4.084ms [2025-11-28T00:42:27.852Z] [4.055s][info][gc] GC(13) Concurrent weak references (Young) 0.014ms [2025-11-28T00:42:27.852Z] [4.056s][info][gc] GC(13) Concurrent weak roots (Young) 1.060ms [2025-11-28T00:42:27.852Z] [4.056s][info][gc] GC(13) Concurrent cleanup (Young) 842M->842M(1024M) 0.017ms [2025-11-28T00:42:27.852Z] [4.057s][info][gc] GC(13) Concurrent Final Roots (Young) 0.870ms [2025-11-28T00:42:27.852Z] [4.059s][info][gc] GC(13) Concurrent reset after collect (Young) 1.676ms [2025-11-28T00:42:27.852Z] [4.059s][info][gc] Trigger (Young): Average GC time (206.62 ms) is above the time for average allocation rate (2077 MB/s) to deplete free headroom (80435K) (margin of error = 1.81) [2025-11-28T00:42:27.852Z] Allocated: 725 Mb [2025-11-28T00:42:27.852Z] Allocated: 700 Mb [2025-11-28T00:42:27.852Z] Allocated: 655 Mb [2025-11-28T00:42:27.852Z] [4.062s][info][gc] GC(14) Concurrent reset (Young) 0.134ms [2025-11-28T00:42:27.852Z] [4.064s][info][gc] GC(14) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.064s][info][gc] GC(14) Pause Init Mark (Young) 1.186ms [2025-11-28T00:42:27.852Z] Allocated: 685 Mb [2025-11-28T00:42:27.852Z] [4.064s][info][gc] GC(14) Concurrent remembered set scanning 0.121ms [2025-11-28T00:42:27.852Z] [4.065s][info][gc] GC(14) Concurrent marking roots 0.112ms [2025-11-28T00:42:27.852Z] [4.065s][info][gc] GC(14) Concurrent marking (Young) 0.097ms [2025-11-28T00:42:27.852Z] [4.066s][info][gc] GC(14) Verify After Mark, Level 4 (23 reachable, 5 marked) [2025-11-28T00:42:27.852Z] [4.066s][info][gc] GC(14) Pause Final Mark (Young) 1.625ms [2025-11-28T00:42:27.852Z] Allocated: 726 Mb [2025-11-28T00:42:27.852Z] [4.068s][info][gc] GC(14) Concurrent weak references (Young) 0.015ms [2025-11-28T00:42:27.852Z] [4.068s][info][gc] GC(14) Concurrent weak roots (Young) 0.060ms [2025-11-28T00:42:27.852Z] [4.068s][info][gc] GC(14) Concurrent cleanup (Young) 735M->735M(1024M) 0.043ms [2025-11-28T00:42:27.852Z] [4.068s][info][gc] GC(14) Concurrent Final Roots (Young) 0.075ms [2025-11-28T00:42:27.852Z] Allocated: 677 Mb [2025-11-28T00:42:27.852Z] Allocated: 694 Mb [2025-11-28T00:42:27.852Z] [4.071s][info][gc] GC(14) Concurrent reset after collect (Young) 2.848ms [2025-11-28T00:42:27.852Z] [4.071s][info][gc] Failed to allocate Shared, 64922K [2025-11-28T00:42:27.852Z] [4.071s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.852Z] [4.078s][info][gc] GC(15) Verify Before Full GC, Level 4 (34637 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.205s][info][gc] GC(15) Verify After Full GC, Level 4 (34533 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.205s][info][gc] GC(15) Pause Full 760M->703M(1024M) 133.142ms [2025-11-28T00:42:27.852Z] [4.206s][info][gc] Trigger (Young): Average GC time (145.81 ms) is above the time for average allocation rate (3422 MB/s) to deplete free headroom (153M) (margin of error = 1.81) [2025-11-28T00:42:27.852Z] [4.206s][info][gc] GC(16) Concurrent reset (Young) 0.153ms [2025-11-28T00:42:27.852Z] [4.209s][info][gc] GC(16) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.209s][info][gc] GC(16) Pause Init Mark (Young) 1.119ms [2025-11-28T00:42:27.852Z] Allocated: 758 Mb [2025-11-28T00:42:27.852Z] [4.210s][info][gc] GC(16) Concurrent remembered set scanning 0.144ms [2025-11-28T00:42:27.852Z] [4.210s][info][gc] GC(16) Concurrent marking roots 0.150ms [2025-11-28T00:42:27.852Z] [4.210s][info][gc] GC(16) Concurrent marking (Young) 0.243ms [2025-11-28T00:42:27.852Z] [4.213s][info][gc] GC(16) Verify After Mark, Level 4 (25 reachable, 5 marked) [2025-11-28T00:42:27.852Z] [4.213s][info][gc] GC(16) Pause Final Mark (Young) 2.785ms [2025-11-28T00:42:27.852Z] Allocated: 724 Mb [2025-11-28T00:42:27.852Z] Allocated: 710 Mb [2025-11-28T00:42:27.852Z] [4.216s][info][gc] GC(16) Concurrent weak references (Young) 0.018ms [2025-11-28T00:42:27.852Z] [4.217s][info][gc] GC(16) Concurrent weak roots (Young) 0.173ms [2025-11-28T00:42:27.852Z] [4.217s][info][gc] GC(16) Concurrent cleanup (Young) 828M->828M(1024M) 0.015ms [2025-11-28T00:42:27.852Z] [4.217s][info][gc] GC(16) Concurrent Final Roots (Young) 0.657ms [2025-11-28T00:42:27.852Z] [4.219s][info][gc] GC(16) Concurrent reset after collect (Young) 1.826ms [2025-11-28T00:42:27.852Z] Allocated: 674 Mb [2025-11-28T00:42:27.852Z] [4.220s][info][gc] Trigger (Young): Free (100M) is below minimum threshold (102M) [2025-11-28T00:42:27.852Z] [4.220s][info][gc] GC(17) Concurrent reset (Young) 0.103ms [2025-11-28T00:42:27.852Z] [4.223s][info][gc] GC(17) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.223s][info][gc] GC(17) Pause Init Mark (Young) 1.445ms [2025-11-28T00:42:27.852Z] Allocated: 717 Mb [2025-11-28T00:42:27.852Z] [4.225s][info][gc] GC(17) Concurrent remembered set scanning 0.170ms [2025-11-28T00:42:27.852Z] [4.225s][info][gc] GC(17) Concurrent marking roots 0.117ms [2025-11-28T00:42:27.852Z] Allocated: 706 Mb [2025-11-28T00:42:27.852Z] [4.230s][info][gc] GC(17) Concurrent marking (Young) 5.296ms [2025-11-28T00:42:27.852Z] [4.230s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.852Z] [4.231s][info][gc] Failed to allocate Shared, 29017K [2025-11-28T00:42:27.852Z] [4.237s][info][gc] GC(18) Verify Before Full GC, Level 4 (34635 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.237s][info][gc] GC(18) Cancel marking: Young [2025-11-28T00:42:27.852Z] [4.237s][info][gc] GC(18) Cancel marking: Old [2025-11-28T00:42:27.852Z] [4.237s][info][gc] GC(18) Cancel marking: Global [2025-11-28T00:42:27.852Z] [4.349s][info][gc] GC(18) Verify After Full GC, Level 4 (34531 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.349s][info][gc] GC(18) Pause Full 924M->661M(1024M) 117.980ms [2025-11-28T00:42:27.852Z] [4.350s][info][gc] Trigger (Young): Average GC time (105.80 ms) is above the time for average allocation rate (2772 MB/s) to deplete free headroom (230M) (margin of error = 1.81) [2025-11-28T00:42:27.852Z] [4.350s][info][gc] GC(19) Concurrent reset (Young) 0.128ms [2025-11-28T00:42:27.852Z] [4.352s][info][gc] GC(19) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.352s][info][gc] GC(19) Pause Init Mark (Young) 1.197ms [2025-11-28T00:42:27.852Z] [4.352s][info][gc] GC(19) Concurrent remembered set scanning 0.096ms [2025-11-28T00:42:27.852Z] [4.352s][info][gc] GC(19) Concurrent marking roots 0.147ms [2025-11-28T00:42:27.852Z] Allocated: 682 Mb [2025-11-28T00:42:27.852Z] [4.353s][info][gc] GC(19) Concurrent marking (Young) 0.903ms [2025-11-28T00:42:27.852Z] [4.355s][info][gc] GC(19) Verify After Mark, Level 4 (23 reachable, 5 marked) [2025-11-28T00:42:27.852Z] [4.356s][info][gc] GC(19) Pause Final Mark (Young) 2.449ms [2025-11-28T00:42:27.852Z] [4.356s][info][gc] GC(19) Concurrent weak references (Young) 0.018ms [2025-11-28T00:42:27.852Z] Allocated: 703 Mb [2025-11-28T00:42:27.852Z] [4.358s][info][gc] GC(19) Concurrent weak roots (Young) 2.224ms [2025-11-28T00:42:27.852Z] [4.358s][info][gc] GC(19) Concurrent cleanup (Young) 717M->717M(1024M) 0.024ms [2025-11-28T00:42:27.852Z] Allocated: 680 Mb [2025-11-28T00:42:27.852Z] [4.361s][info][gc] GC(19) Concurrent Final Roots (Young) 2.277ms [2025-11-28T00:42:27.852Z] [4.363s][info][gc] GC(19) Concurrent reset after collect (Young) 1.778ms [2025-11-28T00:42:27.852Z] [4.365s][info][gc] Trigger (Young): Average GC time (78.75 ms) is above the time for average allocation rate (2772 MB/s) to deplete free headroom (157M) (margin of error = 1.81) [2025-11-28T00:42:27.852Z] [4.366s][info][gc] GC(20) Concurrent reset (Young) 0.150ms [2025-11-28T00:42:27.852Z] [4.372s][info][gc] GC(20) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.372s][info][gc] GC(20) Pause Init Mark (Young) 4.486ms [2025-11-28T00:42:27.852Z] [4.374s][info][gc] GC(20) Concurrent remembered set scanning 0.965ms [2025-11-28T00:42:27.852Z] [4.374s][info][gc] GC(20) Concurrent marking roots 0.190ms [2025-11-28T00:42:27.852Z] Allocated: 726 Mb [2025-11-28T00:42:27.852Z] Allocated: 709 Mb [2025-11-28T00:42:27.852Z] [4.377s][info][gc] GC(20) Concurrent marking (Young) 2.927ms [2025-11-28T00:42:27.852Z] [4.383s][info][gc] GC(20) Verify Before Evacuation, Level 4 (24 reachable, 12 marked) [2025-11-28T00:42:27.852Z] [4.383s][info][gc] GC(20) Pause Final Mark (Young) 4.148ms [2025-11-28T00:42:27.852Z] [4.383s][info][gc] GC(20) Concurrent thread roots 0.156ms [2025-11-28T00:42:27.852Z] [4.384s][info][gc] GC(20) Concurrent weak references (Young) 0.072ms [2025-11-28T00:42:27.852Z] [4.384s][info][gc] GC(20) Concurrent weak roots (Young) 0.415ms [2025-11-28T00:42:27.852Z] [4.384s][info][gc] GC(20) Concurrent cleanup (Young) 787M->787M(1024M) 0.019ms [2025-11-28T00:42:27.852Z] Allocated: 721 Mb [2025-11-28T00:42:27.852Z] [4.386s][info][gc] GC(20) Concurrent strong roots 1.726ms [2025-11-28T00:42:27.852Z] [4.386s][info][gc] GC(20) Concurrent evacuation 0.230ms [2025-11-28T00:42:27.852Z] [4.390s][info][gc] GC(20) Concurrent Init Update Refs (Young) 4.127ms [2025-11-28T00:42:27.852Z] [4.392s][info][gc] GC(20) Verify Before Updating References, Level 4 (25 reachable, 29 marked) [2025-11-28T00:42:27.852Z] [4.392s][info][gc] GC(20) Pause Init Update Refs 1.798ms [2025-11-28T00:42:27.852Z] [4.396s][info][gc] GC(20) Concurrent update references 3.535ms [2025-11-28T00:42:27.852Z] Allocated: 725 Mb [2025-11-28T00:42:27.852Z] [4.399s][info][gc] GC(20) Concurrent update thread roots 2.268ms [2025-11-28T00:42:27.852Z] [4.403s][info][gc] GC(20) Verify After Updating References, Level 4 (25 reachable, 22 marked) [2025-11-28T00:42:27.852Z] [4.403s][info][gc] GC(20) Pause Final Update Refs 4.223ms [2025-11-28T00:42:27.852Z] [4.404s][info][gc] GC(20) Concurrent cleanup (Young) 820M->820M(1024M) 0.026ms [2025-11-28T00:42:27.852Z] [4.406s][info][gc] GC(20) Concurrent reset after collect (Young) 2.033ms [2025-11-28T00:42:27.852Z] [4.407s][info][gc] Trigger (Young): Free (89088K) is below minimum threshold (102M) [2025-11-28T00:42:27.852Z] [4.407s][info][gc] GC(21) Concurrent reset (Young) 0.120ms [2025-11-28T00:42:27.852Z] [4.437s][info][gc] GC(21) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.437s][info][gc] GC(21) Pause Init Mark (Young) 1.277ms [2025-11-28T00:42:27.852Z] [4.437s][info][gc] GC(21) Concurrent remembered set scanning 0.102ms [2025-11-28T00:42:27.852Z] [4.437s][info][gc] GC(21) Concurrent marking roots 0.182ms [2025-11-28T00:42:27.852Z] [4.447s][info][gc] GC(21) Concurrent marking (Young) 9.202ms [2025-11-28T00:42:27.852Z] [4.450s][info][gc] GC(21) Verify Before Evacuation, Level 4 (24 reachable, 5 marked) [2025-11-28T00:42:27.852Z] [4.450s][info][gc] GC(21) Pause Final Mark (Young) 2.857ms [2025-11-28T00:42:27.852Z] [4.450s][info][gc] GC(21) Concurrent thread roots 0.414ms [2025-11-28T00:42:27.852Z] [4.450s][info][gc] GC(21) Concurrent weak references (Young) 0.046ms [2025-11-28T00:42:27.852Z] [4.450s][info][gc] GC(21) Concurrent weak roots (Young) 0.083ms [2025-11-28T00:42:27.852Z] Allocated: 733 Mb [2025-11-28T00:42:27.852Z] [4.450s][info][gc] GC(21) Concurrent cleanup (Young) 741M->741M(1024M) 0.036ms [2025-11-28T00:42:27.852Z] [4.451s][info][gc] Failed to allocate Shared, 53517K [2025-11-28T00:42:27.852Z] [4.451s][info][gc] GC(21) Concurrent strong roots 0.476ms [2025-11-28T00:42:27.852Z] [4.451s][info][gc] GC(21) Concurrent evacuation 0.014ms [2025-11-28T00:42:27.852Z] [4.451s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.852Z] [4.461s][info][gc] GC(22) Verify Before Full GC, Level 4 (34635 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.598s][info][gc] GC(22) Verify After Full GC, Level 4 (34531 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.598s][info][gc] GC(22) Pause Full 741M->701M(1024M) 146.604ms [2025-11-28T00:42:27.852Z] Allocated: 747 Mb [2025-11-28T00:42:27.852Z] Allocated: 663 Mb [2025-11-28T00:42:27.852Z] Allocated: 721 Mb [2025-11-28T00:42:27.852Z] [4.609s][info][gc] Trigger (Young): Free (85247K) is below minimum threshold (102M) [2025-11-28T00:42:27.852Z] [4.609s][info][gc] GC(23) Concurrent reset (Young) 0.147ms [2025-11-28T00:42:27.852Z] [4.614s][info][gc] GC(23) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.614s][info][gc] GC(23) Pause Init Mark (Young) 1.301ms [2025-11-28T00:42:27.852Z] Allocated: 740 Mb [2025-11-28T00:42:27.852Z] Allocated: 666 Mb [2025-11-28T00:42:27.852Z] [4.615s][info][gc] GC(23) Concurrent remembered set scanning 0.124ms [2025-11-28T00:42:27.852Z] [4.616s][info][gc] GC(23) Concurrent marking roots 0.154ms [2025-11-28T00:42:27.852Z] [4.616s][info][gc] GC(23) Concurrent marking (Young) 0.356ms [2025-11-28T00:42:27.852Z] [4.618s][info][gc] GC(23) Verify Before Evacuation, Level 4 (20 reachable, 9 marked) [2025-11-28T00:42:27.852Z] [4.618s][info][gc] GC(23) Pause Final Mark (Young) 2.110ms [2025-11-28T00:42:27.852Z] Allocated: 676 Mb [2025-11-28T00:42:27.852Z] [4.620s][info][gc] GC(23) Concurrent thread roots 2.155ms [2025-11-28T00:42:27.852Z] [4.620s][info][gc] GC(23) Concurrent weak references (Young) 0.018ms [2025-11-28T00:42:27.852Z] [4.621s][info][gc] GC(23) Concurrent weak roots (Young) 0.254ms [2025-11-28T00:42:27.852Z] [4.621s][info][gc] GC(23) Concurrent cleanup (Young) 697M->697M(1024M) 0.045ms [2025-11-28T00:42:27.852Z] [4.621s][info][gc] GC(23) Concurrent strong roots 0.376ms [2025-11-28T00:42:27.852Z] [4.621s][info][gc] GC(23) Concurrent evacuation 0.076ms [2025-11-28T00:42:27.852Z] Allocated: 690 Mb [2025-11-28T00:42:27.852Z] [4.621s][info][gc] GC(23) Concurrent Init Update Refs (Young) 0.225ms [2025-11-28T00:42:27.852Z] [4.625s][info][gc] GC(23) Verify Before Updating References, Level 4 (22 reachable, 30 marked) [2025-11-28T00:42:27.852Z] [4.625s][info][gc] GC(23) Pause Init Update Refs 3.151ms [2025-11-28T00:42:27.852Z] [4.628s][info][gc] GC(23) Concurrent update references 3.358ms [2025-11-28T00:42:27.852Z] Allocated: 716 Mb [2025-11-28T00:42:27.852Z] Allocated: 695 Mb [2025-11-28T00:42:27.852Z] Allocated: 717 Mb [2025-11-28T00:42:27.852Z] [4.632s][info][gc] GC(23) Concurrent update thread roots 4.411ms [2025-11-28T00:42:27.852Z] [4.637s][info][gc] GC(23) Verify After Updating References, Level 4 (23 reachable, 35 marked) [2025-11-28T00:42:27.852Z] [4.637s][info][gc] GC(23) Pause Final Update Refs 3.281ms [2025-11-28T00:42:27.852Z] Allocated: 707 Mb [2025-11-28T00:42:27.852Z] [4.637s][info][gc] GC(23) Concurrent cleanup (Young) 814M->814M(1024M) 0.015ms [2025-11-28T00:42:27.852Z] [4.640s][info][gc] GC(23) Concurrent reset after collect (Young) 2.642ms [2025-11-28T00:42:27.852Z] Allocated: 698 Mb [2025-11-28T00:42:27.852Z] [4.640s][info][gc] Trigger (Young): Average GC time (64.75 ms) is above the time for average allocation rate (7264 MB/s) to deplete free headroom (83251K) (margin of error = 1.82) [2025-11-28T00:42:27.852Z] [4.641s][info][gc] GC(24) Concurrent reset (Young) 0.128ms [2025-11-28T00:42:27.852Z] [4.642s][info][gc] GC(24) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.642s][info][gc] GC(24) Pause Init Mark (Young) 1.147ms [2025-11-28T00:42:27.852Z] Allocated: 751 Mb [2025-11-28T00:42:27.852Z] [4.645s][info][gc] Failed to allocate Shared, 62450K [2025-11-28T00:42:27.852Z] [4.645s][info][gc] GC(24) Concurrent remembered set scanning 0.110ms [2025-11-28T00:42:27.852Z] [4.645s][info][gc] GC(24) Concurrent marking roots 0.120ms [2025-11-28T00:42:27.852Z] [4.645s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.852Z] [4.651s][info][gc] GC(25) Verify Before Full GC, Level 4 (34636 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.651s][info][gc] GC(25) Cancel marking: Young [2025-11-28T00:42:27.852Z] [4.651s][info][gc] GC(25) Cancel marking: Old [2025-11-28T00:42:27.852Z] [4.651s][info][gc] GC(25) Cancel marking: Global [2025-11-28T00:42:27.852Z] [4.780s][info][gc] GC(25) Verify After Full GC, Level 4 (34532 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.780s][info][gc] GC(25) Pause Full 892M->693M(1024M) 134.663ms [2025-11-28T00:42:27.852Z] [4.780s][info][gc] Trigger (Young): Average GC time (64.75 ms) is above the time for average allocation rate (4763 MB/s) to deplete free headroom (227M) (margin of error = 1.82) [2025-11-28T00:42:27.852Z] [4.780s][info][gc] GC(26) Concurrent reset (Young) 0.176ms [2025-11-28T00:42:27.852Z] [4.782s][info][gc] GC(26) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.782s][info][gc] GC(26) Pause Init Mark (Young) 1.463ms [2025-11-28T00:42:27.852Z] [4.782s][info][gc] GC(26) Concurrent remembered set scanning 0.185ms [2025-11-28T00:42:27.852Z] [4.783s][info][gc] GC(26) Concurrent marking roots 0.222ms [2025-11-28T00:42:27.852Z] [4.783s][info][gc] GC(26) Concurrent marking (Young) 0.623ms [2025-11-28T00:42:27.852Z] [4.785s][info][gc] GC(26) Verify After Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.785s][info][gc] GC(26) Pause Final Mark (Young) 1.486ms [2025-11-28T00:42:27.852Z] [4.785s][info][gc] GC(26) Concurrent weak references (Young) 0.012ms [2025-11-28T00:42:27.852Z] [4.785s][info][gc] GC(26) Concurrent weak roots (Young) 0.061ms [2025-11-28T00:42:27.852Z] [4.785s][info][gc] GC(26) Concurrent cleanup (Young) 693M->693M(1024M) 0.014ms [2025-11-28T00:42:27.852Z] [4.785s][info][gc] GC(26) Concurrent Final Roots (Young) 0.085ms [2025-11-28T00:42:27.852Z] [4.788s][info][gc] GC(26) Concurrent reset after collect (Young) 2.555ms [2025-11-28T00:42:27.852Z] [4.789s][info][gc] Trigger (Young): Average GC time (50.08 ms) is above the time for average allocation rate (4760 MB/s) to deplete free headroom (166M) (margin of error = 1.80) [2025-11-28T00:42:27.852Z] [4.789s][info][gc] GC(27) Concurrent reset (Young) 0.103ms [2025-11-28T00:42:27.852Z] [4.792s][info][gc] GC(27) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.852Z] [4.793s][info][gc] GC(27) Pause Init Mark (Young) 2.621ms [2025-11-28T00:42:27.852Z] Allocated: 748 Mb [2025-11-28T00:42:27.852Z] [4.793s][info][gc] GC(27) Concurrent remembered set scanning 0.123ms [2025-11-28T00:42:27.852Z] [4.793s][info][gc] GC(27) Concurrent marking roots 0.103ms [2025-11-28T00:42:27.852Z] [4.793s][info][gc] GC(27) Concurrent marking (Young) 0.160ms [2025-11-28T00:42:27.852Z] [4.796s][info][gc] GC(27) Verify Before Evacuation, Level 4 (24 reachable, 5 marked) [2025-11-28T00:42:27.852Z] [4.796s][info][gc] GC(27) Pause Final Mark (Young) 2.218ms [2025-11-28T00:42:27.852Z] Allocated: 727 Mb [2025-11-28T00:42:27.852Z] [4.797s][info][gc] GC(27) Concurrent thread roots 0.067ms [2025-11-28T00:42:27.852Z] [4.797s][info][gc] GC(27) Concurrent weak references (Young) 0.017ms [2025-11-28T00:42:27.852Z] [4.797s][info][gc] GC(27) Concurrent weak roots (Young) 0.059ms [2025-11-28T00:42:27.852Z] [4.797s][info][gc] GC(27) Concurrent cleanup (Young) 782M->782M(1024M) 0.014ms [2025-11-28T00:42:27.852Z] [4.798s][info][gc] GC(27) Concurrent strong roots 0.373ms [2025-11-28T00:42:27.852Z] [4.798s][info][gc] GC(27) Concurrent evacuation 0.058ms [2025-11-28T00:42:27.852Z] [4.798s][info][gc] GC(27) Concurrent Init Update Refs (Young) 0.034ms [2025-11-28T00:42:27.852Z] [4.799s][info][gc] GC(27) Verify Before Updating References, Level 4 (22 reachable, 13 marked) [2025-11-28T00:42:27.853Z] [4.799s][info][gc] GC(27) Pause Init Update Refs 1.056ms [2025-11-28T00:42:27.853Z] Allocated: 711 Mb [2025-11-28T00:42:27.853Z] Allocated: 689 Mb [2025-11-28T00:42:27.853Z] Allocated: 691 Mb [2025-11-28T00:42:27.853Z] [4.804s][info][gc] GC(27) Concurrent update references 3.379ms [2025-11-28T00:42:27.853Z] [4.804s][info][gc] GC(27) Concurrent update thread roots 0.202ms [2025-11-28T00:42:27.853Z] [4.805s][info][gc] GC(27) Verify After Updating References, Level 4 (23 reachable, 26 marked) [2025-11-28T00:42:27.853Z] [4.805s][info][gc] GC(27) Pause Final Update Refs 1.265ms [2025-11-28T00:42:27.853Z] [4.807s][info][gc] GC(27) Concurrent cleanup (Young) 869M->869M(1024M) 0.015ms [2025-11-28T00:42:27.853Z] Allocated: 736 Mb [2025-11-28T00:42:27.853Z] Allocated: 692 Mb [2025-11-28T00:42:27.853Z] [4.810s][info][gc] GC(27) Concurrent reset after collect (Young) 3.154ms [2025-11-28T00:42:27.853Z] [4.811s][info][gc] Trigger (Young): Free (76032K) is below minimum threshold (102M) [2025-11-28T00:42:27.853Z] [4.811s][info][gc] GC(28) Concurrent reset (Young) 0.127ms [2025-11-28T00:42:27.853Z] [4.814s][info][gc] GC(28) Verify Before Mark, Level 4 (21 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [4.815s][info][gc] GC(28) Pause Init Mark (Young) 1.241ms [2025-11-28T00:42:27.853Z] Allocated: 734 Mb [2025-11-28T00:42:27.853Z] [4.815s][info][gc] GC(28) Concurrent remembered set scanning 0.098ms [2025-11-28T00:42:27.853Z] [4.815s][info][gc] GC(28) Concurrent marking roots 0.105ms [2025-11-28T00:42:27.853Z] [4.815s][info][gc] GC(28) Concurrent marking (Young) 0.090ms [2025-11-28T00:42:27.853Z] [4.817s][info][gc] GC(28) Verify Before Evacuation, Level 4 (22 reachable, 5 marked) [2025-11-28T00:42:27.853Z] [4.817s][info][gc] GC(28) Pause Final Mark (Young) 1.689ms [2025-11-28T00:42:27.853Z] Allocated: 676 Mb [2025-11-28T00:42:27.853Z] [4.818s][info][gc] GC(28) Concurrent thread roots 0.058ms [2025-11-28T00:42:27.853Z] [4.818s][info][gc] GC(28) Concurrent weak references (Young) 0.014ms [2025-11-28T00:42:27.853Z] [4.818s][info][gc] GC(28) Concurrent weak roots (Young) 0.058ms [2025-11-28T00:42:27.853Z] [4.818s][info][gc] GC(28) Concurrent cleanup (Young) 753M->753M(1024M) 0.042ms [2025-11-28T00:42:27.853Z] [4.818s][info][gc] GC(28) Concurrent strong roots 0.353ms [2025-11-28T00:42:27.853Z] [4.818s][info][gc] GC(28) Concurrent evacuation 0.108ms [2025-11-28T00:42:27.853Z] [4.818s][info][gc] GC(28) Concurrent Init Update Refs (Young) 0.044ms [2025-11-28T00:42:27.853Z] [4.819s][info][gc] GC(28) Verify Before Updating References, Level 4 (21 reachable, 20 marked) [2025-11-28T00:42:27.853Z] [4.820s][info][gc] GC(28) Pause Init Update Refs 1.077ms [2025-11-28T00:42:27.853Z] Allocated: 722 Mb [2025-11-28T00:42:27.853Z] [4.826s][info][gc] GC(28) Concurrent update references 6.328ms [2025-11-28T00:42:27.853Z] Allocated: 706 Mb [2025-11-28T00:42:27.853Z] [4.828s][info][gc] Failed to allocate Shared, 60005K [2025-11-28T00:42:27.853Z] [4.828s][info][gc] GC(28) Concurrent update thread roots 2.182ms [2025-11-28T00:42:27.853Z] [4.828s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.853Z] [4.835s][info][gc] GC(29) Verify Before Full GC, Level 4 (34633 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [4.945s][info][gc] GC(29) Verify After Full GC, Level 4 (34529 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [4.946s][info][gc] GC(29) Pause Full 850M->683M(1024M) 117.145ms [2025-11-28T00:42:27.853Z] Allocated: 736 Mb [2025-11-28T00:42:27.853Z] Allocated: 679 Mb [2025-11-28T00:42:27.853Z] [4.949s][info][gc] Trigger (Young): Average GC time (42.25 ms) is above the time for average allocation rate (3833 MB/s) to deplete free headroom (148M) (margin of error = 1.81) [2025-11-28T00:42:27.853Z] [4.949s][info][gc] GC(30) Concurrent reset (Young) 0.189ms [2025-11-28T00:42:27.853Z] [4.951s][info][gc] GC(30) Verify Before Mark, Level 4 (21 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [4.951s][info][gc] GC(30) Pause Init Mark (Young) 1.196ms [2025-11-28T00:42:27.853Z] Allocated: 704 Mb [2025-11-28T00:42:27.853Z] [4.952s][info][gc] GC(30) Concurrent remembered set scanning 0.102ms [2025-11-28T00:42:27.853Z] [4.952s][info][gc] GC(30) Concurrent marking roots 0.118ms [2025-11-28T00:42:27.853Z] Allocated: 733 Mb [2025-11-28T00:42:27.853Z] [4.956s][info][gc] GC(30) Concurrent marking (Young) 3.938ms [2025-11-28T00:42:27.853Z] [4.960s][info][gc] GC(30) Verify Before Evacuation, Level 4 (21 reachable, 12 marked) [2025-11-28T00:42:27.853Z] [4.961s][info][gc] GC(30) Pause Final Mark (Young) 2.613ms [2025-11-28T00:42:27.853Z] [4.961s][info][gc] GC(30) Concurrent thread roots 0.079ms [2025-11-28T00:42:27.853Z] [4.961s][info][gc] GC(30) Concurrent weak references (Young) 0.013ms [2025-11-28T00:42:27.853Z] Allocated: 736 Mb [2025-11-28T00:42:27.853Z] [4.961s][info][gc] GC(30) Concurrent weak roots (Young) 0.229ms [2025-11-28T00:42:27.853Z] [4.961s][info][gc] GC(30) Concurrent cleanup (Young) 828M->828M(1024M) 0.047ms [2025-11-28T00:42:27.853Z] [4.962s][info][gc] GC(30) Concurrent strong roots 0.572ms [2025-11-28T00:42:27.853Z] [4.962s][info][gc] GC(30) Concurrent evacuation 0.084ms [2025-11-28T00:42:27.853Z] Allocated: 712 Mb [2025-11-28T00:42:27.853Z] [4.963s][info][gc] GC(30) Concurrent Init Update Refs (Young) 1.527ms [2025-11-28T00:42:27.853Z] [4.964s][info][gc] GC(30) Verify Before Updating References, Level 4 (22 reachable, 32 marked) [2025-11-28T00:42:27.853Z] [4.965s][info][gc] GC(30) Pause Init Update Refs 1.112ms [2025-11-28T00:42:27.853Z] Allocated: 709 Mb [2025-11-28T00:42:27.853Z] [4.969s][info][gc] GC(30) Concurrent update references 4.280ms [2025-11-28T00:42:27.853Z] Allocated: 672 Mb [2025-11-28T00:42:27.853Z] [4.969s][info][gc] GC(30) Concurrent update thread roots 0.201ms [2025-11-28T00:42:27.853Z] [4.972s][info][gc] GC(30) Verify After Updating References, Level 4 (22 reachable, 33 marked) [2025-11-28T00:42:27.853Z] [4.972s][info][gc] GC(30) Pause Final Update Refs 1.313ms [2025-11-28T00:42:27.853Z] [4.972s][info][gc] GC(30) Concurrent cleanup (Young) 916M->916M(1024M) 0.015ms [2025-11-28T00:42:27.853Z] [4.973s][info][gc] GC(30) Concurrent reset after collect (Young) 1.536ms [2025-11-28T00:42:27.853Z] [4.974s][info][gc] Trigger (Young): Free (56320K) is below minimum threshold (102M) [2025-11-28T00:42:27.853Z] Allocated: 695 Mb [2025-11-28T00:42:27.853Z] Allocated: 699 Mb [2025-11-28T00:42:27.853Z] Allocated: 726 Mb [2025-11-28T00:42:27.853Z] [4.975s][info][gc] Failed to allocate Shared, 40146K [2025-11-28T00:42:27.853Z] [4.976s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.853Z] [4.982s][info][gc] GC(31) Verify Before Full GC, Level 4 (34636 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [5.099s][info][gc] GC(31) Verify After Full GC, Level 4 (34532 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [5.099s][info][gc] GC(31) Pause Full 947M->681M(1024M) 123.373ms [2025-11-28T00:42:27.853Z] [5.100s][info][gc] GC(32) Concurrent reset (Young) 0.150ms [2025-11-28T00:42:27.853Z] [5.101s][info][gc] GC(32) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [5.101s][info][gc] GC(32) Pause Init Mark (Young) 1.222ms [2025-11-28T00:42:27.853Z] [5.101s][info][gc] GC(32) Concurrent remembered set scanning 0.167ms [2025-11-28T00:42:27.853Z] [5.102s][info][gc] GC(32) Concurrent marking roots 0.196ms [2025-11-28T00:42:27.853Z] [5.102s][info][gc] GC(32) Concurrent marking (Young) 0.312ms [2025-11-28T00:42:27.853Z] [5.106s][info][gc] GC(32) Verify After Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [5.106s][info][gc] GC(32) Pause Final Mark (Young) 3.845ms [2025-11-28T00:42:27.853Z] [5.107s][info][gc] GC(32) Concurrent weak references (Young) 0.021ms [2025-11-28T00:42:27.853Z] Allocated: 713 Mb [2025-11-28T00:42:27.853Z] Allocated: 697 Mb [2025-11-28T00:42:27.853Z] [5.109s][info][gc] GC(32) Concurrent weak roots (Young) 2.511ms [2025-11-28T00:42:27.853Z] [5.110s][info][gc] GC(32) Concurrent cleanup (Young) 728M->728M(1024M) 0.019ms [2025-11-28T00:42:27.853Z] Allocated: 723 Mb [2025-11-28T00:42:27.853Z] [5.113s][info][gc] GC(32) Concurrent Final Roots (Young) 3.145ms [2025-11-28T00:42:27.853Z] Allocated: 722 Mb [2025-11-28T00:42:27.853Z] [5.116s][info][gc] GC(32) Concurrent reset after collect (Young) 2.545ms [2025-11-28T00:42:27.853Z] [5.116s][info][gc] Trigger (Young): GC start is already pending [2025-11-28T00:42:27.853Z] [5.117s][info][gc] GC(33) Concurrent reset (Young) 0.126ms [2025-11-28T00:42:27.853Z] [5.126s][info][gc] GC(33) Verify Before [2025-11-28T00:42:27.853Z] [2025-11-28T00:42:27.853Z] ... [2025-11-28T00:42:27.853Z] Output overflow: [2025-11-28T00:42:27.853Z] JT Harness has limited the test output to the text [2025-11-28T00:42:27.853Z] at the beginning and the end, so that you can see how the [2025-11-28T00:42:27.853Z] test began, and how it completed. [2025-11-28T00:42:27.853Z] [2025-11-28T00:42:27.853Z] If you need to see more of the output from the test, [2025-11-28T00:42:27.853Z] set the system property javatest.maxOutputSize to a higher [2025-11-28T00:42:27.853Z] value. The current value is 100000 [2025-11-28T00:42:27.853Z] ... [2025-11-28T00:42:27.853Z] [2025-11-28T00:42:27.853Z] ][info][gc] GC(186) Verify Before Full GC, Level 4 (34639 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [23.219s][info][gc] GC(186) Cancel marking: Young [2025-11-28T00:42:27.853Z] [23.219s][info][gc] GC(186) Cancel marking: Old [2025-11-28T00:42:27.853Z] [23.219s][info][gc] GC(186) Cancel marking: Global [2025-11-28T00:42:27.853Z] [23.291s][info][gc] GC(186) Verify After Full GC, Level 4 (34535 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [23.292s][info][gc] GC(186) Pause Full 971M->681M(1024M) 78.625ms [2025-11-28T00:42:27.853Z] [23.293s][info][gc] Trigger (Young): Average GC time (67.94 ms) is above the time for average allocation rate (7580 MB/s) to deplete free headroom (182M) (margin of error = 1.90) [2025-11-28T00:42:27.853Z] [23.293s][info][gc] GC(187) Concurrent reset (Young) 0.131ms [2025-11-28T00:42:27.853Z] [23.296s][info][gc] GC(187) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [23.296s][info][gc] GC(187) Pause Init Mark (Young) 1.029ms [2025-11-28T00:42:27.853Z] [23.296s][info][gc] GC(187) Concurrent remembered set scanning 0.162ms [2025-11-28T00:42:27.853Z] [23.297s][info][gc] GC(187) Concurrent marking roots 0.184ms [2025-11-28T00:42:27.853Z] Allocated: 729 Mb [2025-11-28T00:42:27.853Z] [23.297s][info][gc] GC(187) Concurrent marking (Young) 0.768ms [2025-11-28T00:42:27.853Z] [23.302s][info][gc] GC(187) Verify After Mark, Level 4 (25 reachable, 8 marked) [2025-11-28T00:42:27.853Z] [23.302s][info][gc] GC(187) Pause Final Mark (Young) 4.220ms [2025-11-28T00:42:27.853Z] Allocated: 694 Mb [2025-11-28T00:42:27.853Z] Allocated: 739 Mb [2025-11-28T00:42:27.853Z] [23.304s][info][gc] GC(187) Concurrent weak references (Young) 0.067ms [2025-11-28T00:42:27.853Z] [23.304s][info][gc] GC(187) Concurrent weak roots (Young) 0.101ms [2025-11-28T00:42:27.853Z] [23.304s][info][gc] GC(187) Concurrent cleanup (Young) 798M->798M(1024M) 0.013ms [2025-11-28T00:42:27.853Z] [23.304s][info][gc] GC(187) Concurrent Final Roots (Young) 0.070ms [2025-11-28T00:42:27.853Z] [23.306s][info][gc] GC(187) Concurrent reset after collect (Young) 1.576ms [2025-11-28T00:42:27.853Z] Allocated: 727 Mb [2025-11-28T00:42:27.853Z] [23.308s][info][gc] Trigger (Young): Free (92415K) is below minimum threshold (102M) [2025-11-28T00:42:27.853Z] [23.309s][info][gc] GC(188) Concurrent reset (Young) 0.117ms [2025-11-28T00:42:27.853Z] [23.312s][info][gc] GC(188) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [23.312s][info][gc] GC(188) Pause Init Mark (Young) 1.161ms [2025-11-28T00:42:27.853Z] Allocated: 741 Mb [2025-11-28T00:42:27.853Z] [23.312s][info][gc] GC(188) Concurrent remembered set scanning 0.117ms [2025-11-28T00:42:27.853Z] [23.312s][info][gc] GC(188) Concurrent marking roots 0.141ms [2025-11-28T00:42:27.853Z] [23.313s][info][gc] GC(188) Concurrent marking (Young) 0.095ms [2025-11-28T00:42:27.853Z] [23.315s][info][gc] GC(188) Verify Before Evacuation, Level 4 (25 reachable, 5 marked) [2025-11-28T00:42:27.853Z] [23.315s][info][gc] GC(188) Pause Final Mark (Young) 2.559ms [2025-11-28T00:42:27.853Z] Allocated: 697 Mb [2025-11-28T00:42:27.853Z] [23.316s][info][gc] GC(188) Concurrent thread roots 0.058ms [2025-11-28T00:42:27.853Z] [23.316s][info][gc] GC(188) Concurrent weak references (Young) 0.015ms [2025-11-28T00:42:27.853Z] [23.316s][info][gc] GC(188) Concurrent weak roots (Young) 0.057ms [2025-11-28T00:42:27.853Z] [23.316s][info][gc] GC(188) Concurrent cleanup (Young) 758M->758M(1024M) 0.035ms [2025-11-28T00:42:27.853Z] [23.317s][info][gc] GC(188) Concurrent strong roots 0.523ms [2025-11-28T00:42:27.853Z] [23.317s][info][gc] GC(188) Concurrent evacuation 0.075ms [2025-11-28T00:42:27.853Z] [23.317s][info][gc] GC(188) Concurrent Init Update Refs (Young) 0.164ms [2025-11-28T00:42:27.853Z] [23.318s][info][gc] GC(188) Verify Before Updating References, Level 4 (24 reachable, 19 marked) [2025-11-28T00:42:27.853Z] [23.318s][info][gc] GC(188) Pause Init Update Refs 1.054ms [2025-11-28T00:42:27.853Z] Allocated: 747 Mb [2025-11-28T00:42:27.853Z] [23.323s][info][gc] GC(188) Concurrent update references 3.791ms [2025-11-28T00:42:27.853Z] Allocated: 742 Mb [2025-11-28T00:42:27.853Z] [23.326s][info][gc] GC(188) Concurrent update thread roots 3.237ms [2025-11-28T00:42:27.853Z] [23.329s][info][gc] GC(188) Verify After Updating References, Level 4 (22 reachable, 26 marked) [2025-11-28T00:42:27.853Z] [23.329s][info][gc] GC(188) Pause Final Update Refs 1.266ms [2025-11-28T00:42:27.853Z] [23.329s][info][gc] GC(188) Concurrent cleanup (Young) 917M->917M(1024M) 0.014ms [2025-11-28T00:42:27.853Z] [23.330s][info][gc] GC(188) Concurrent reset after collect (Young) 1.595ms [2025-11-28T00:42:27.853Z] [23.331s][info][gc] Trigger (Young): Free (55552K) is below minimum threshold (102M) [2025-11-28T00:42:27.853Z] Allocated: 718 Mb [2025-11-28T00:42:27.853Z] [23.331s][info][gc] Failed to allocate Shared, 28730K [2025-11-28T00:42:27.853Z] [23.331s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.853Z] [23.336s][info][gc] GC(189) Verify Before Full GC, Level 4 (34635 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [23.525s][info][gc] GC(189) Verify After Full GC, Level 4 (34531 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [23.525s][info][gc] GC(189) Pause Full 917M->705M(1024M) 194.405ms [2025-11-28T00:42:27.853Z] [23.525s][info][gc] GC(190) Concurrent reset (Young) 0.172ms [2025-11-28T00:42:27.853Z] [23.534s][info][gc] GC(190) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T00:42:27.853Z] [23.534s][info][gc] GC(190) Pause Init Mark (Young) 8.033ms [2025-11-28T00:42:27.853Z] [23.535s][info][gc] GC(190) Concurrent remembered set scanning 1.153ms [2025-11-28T00:42:27.853Z] [23.535s][info][gc] GC(190) Concurrent marking roots 0.174ms [2025-11-28T00:42:27.853Z] Allocated: 726 Mb [2025-11-28T00:42:27.855Z] [23.536s][info][gc] GC(190) Concurrent marking (Young) 0.397ms [2025-11-28T00:42:27.855Z] [23.539s][info][gc] GC(190) Verify After Mark, Level 4 (23 reachable, 5 marked) [2025-11-28T00:42:27.855Z] [23.539s][info][gc] GC(190) Pause Final Mark (Young) 3.205ms [2025-11-28T00:42:27.855Z] [23.540s][info][gc] GC(190) Concurrent weak references (Young) 0.019ms [2025-11-28T00:42:27.855Z] Allocated: 727 Mb [2025-11-28T00:42:27.855Z] [23.545s][info][gc] GC(190) Concurrent weak roots (Young) 4.261ms [2025-11-28T00:42:27.855Z] [23.545s][info][gc] GC(190) Concurrent cleanup (Young) 781M->781M(1024M) 0.014ms [2025-11-28T00:42:27.855Z] [23.545s][info][gc] GC(190) Concurrent Final Roots (Young) 0.070ms [2025-11-28T00:42:27.855Z] [23.546s][info][gc] GC(190) Concurrent reset after collect (Young) 1.504ms [2025-11-28T00:42:27.855Z] [23.546s][info][gc] Trigger (Young): GC start is already pending [2025-11-28T00:42:27.855Z] [23.551s][info][gc] GC(191) Concurrent reset (Young) 0.114ms [2025-11-28T00:42:27.855Z] [23.558s][info][gc] GC(191) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [23.558s][info][gc] GC(191) Pause Init Mark (Young) 2.561ms [2025-11-28T00:42:27.855Z] Allocated: 720 Mb [2025-11-28T00:42:27.855Z] Allocated: 711 Mb [2025-11-28T00:42:27.855Z] Allocated: 664 Mb [2025-11-28T00:42:27.855Z] [23.561s][info][gc] GC(191) Concurrent remembered set scanning 0.147ms [2025-11-28T00:42:27.855Z] [23.562s][info][gc] GC(191) Concurrent marking roots 0.159ms [2025-11-28T00:42:27.855Z] Allocated: 711 Mb [2025-11-28T00:42:27.855Z] [23.567s][info][gc] GC(191) Concurrent marking (Young) 5.528ms [2025-11-28T00:42:27.855Z] [23.569s][info][gc] GC(191) Verify After Mark, Level 4 (23 reachable, 17 marked) [2025-11-28T00:42:27.855Z] [23.569s][info][gc] GC(191) Pause Final Mark (Young) 1.528ms [2025-11-28T00:42:27.855Z] [23.569s][info][gc] GC(191) Concurrent weak references (Young) 0.016ms [2025-11-28T00:42:27.855Z] [23.569s][info][gc] GC(191) Concurrent weak roots (Young) 0.076ms [2025-11-28T00:42:27.855Z] [23.569s][info][gc] GC(191) Concurrent cleanup (Young) 720M->720M(1024M) 0.040ms [2025-11-28T00:42:27.855Z] Allocated: 663 Mb [2025-11-28T00:42:27.855Z] [23.570s][info][gc] GC(191) Concurrent Final Roots (Young) 0.877ms [2025-11-28T00:42:27.855Z] [23.574s][info][gc] Failed to allocate Shared, 53632K [2025-11-28T00:42:27.855Z] [23.575s][info][gc] GC(191) Concurrent reset after collect (Young) 4.837ms [2025-11-28T00:42:27.855Z] [23.575s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [23.589s][info][gc] GC(192) Verify Before Full GC, Level 4 (34636 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [23.888s][info][gc] GC(192) Verify After Full GC, Level 4 (34532 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [23.888s][info][gc] GC(192) Pause Full 732M->671M(1024M) 312.676ms [2025-11-28T00:42:27.855Z] Allocated: 715 Mb [2025-11-28T00:42:27.855Z] Allocated: 713 Mb [2025-11-28T00:42:27.855Z] Allocated: 711 Mb [2025-11-28T00:42:27.855Z] [23.904s][info][gc] Trigger (Young): Average GC time (33.60 ms) is above the time for average allocation rate (2279 MB/s) to deplete free headroom (67123K) (margin of error = 1.90) [2025-11-28T00:42:27.855Z] [23.904s][info][gc] GC(193) Concurrent reset (Young) 0.139ms [2025-11-28T00:42:27.855Z] [23.912s][info][gc] GC(193) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [23.913s][info][gc] GC(193) Pause Init Mark (Young) 1.131ms [2025-11-28T00:42:27.855Z] [23.913s][info][gc] GC(193) Concurrent remembered set scanning 0.103ms [2025-11-28T00:42:27.855Z] [23.913s][info][gc] GC(193) Concurrent marking roots 0.158ms [2025-11-28T00:42:27.855Z] Allocated: 723 Mb [2025-11-28T00:42:27.855Z] Allocated: 724 Mb [2025-11-28T00:42:27.855Z] [23.914s][info][gc] GC(193) Concurrent marking (Young) 1.545ms [2025-11-28T00:42:27.855Z] [23.916s][info][gc] GC(193) Verify After Mark, Level 4 (24 reachable, 10 marked) [2025-11-28T00:42:27.855Z] [23.916s][info][gc] GC(193) Pause Final Mark (Young) 1.446ms [2025-11-28T00:42:27.855Z] [23.916s][info][gc] GC(193) Concurrent weak references (Young) 0.015ms [2025-11-28T00:42:27.855Z] Allocated: 682 Mb [2025-11-28T00:42:27.855Z] [23.918s][info][gc] GC(193) Concurrent weak roots (Young) 1.936ms [2025-11-28T00:42:27.855Z] [23.918s][info][gc] GC(193) Concurrent cleanup (Young) 776M->776M(1024M) 0.031ms [2025-11-28T00:42:27.855Z] [23.918s][info][gc] GC(193) Concurrent Final Roots (Young) 0.076ms [2025-11-28T00:42:27.855Z] [23.920s][info][gc] GC(193) Concurrent reset after collect (Young) 1.569ms [2025-11-28T00:42:27.855Z] [23.920s][info][gc] Trigger (Young): Average GC time (29.09 ms) is above the time for average allocation rate (5882 MB/s) to deplete free headroom (143M) (margin of error = 1.89) [2025-11-28T00:42:27.855Z] Allocated: 713 Mb [2025-11-28T00:42:27.855Z] Allocated: 669 Mb [2025-11-28T00:42:27.855Z] [23.929s][info][gc] GC(194) Concurrent reset (Young) 0.149ms [2025-11-28T00:42:27.855Z] [23.933s][info][gc] GC(194) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [23.933s][info][gc] GC(194) Pause Init Mark (Young) 1.117ms [2025-11-28T00:42:27.855Z] [23.934s][info][gc] GC(194) Concurrent remembered set scanning 0.118ms [2025-11-28T00:42:27.855Z] [23.934s][info][gc] GC(194) Concurrent marking roots 0.139ms [2025-11-28T00:42:27.855Z] Allocated: 717 Mb [2025-11-28T00:42:27.855Z] Allocated: 714 Mb [2025-11-28T00:42:27.855Z] Allocated: 663 Mb [2025-11-28T00:42:27.855Z] [23.937s][info][gc] Failed to allocate Shared, 52741K [2025-11-28T00:42:27.855Z] [23.938s][info][gc] GC(194) Concurrent marking (Young) 3.920ms [2025-11-28T00:42:27.855Z] [23.938s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [23.953s][info][gc] GC(195) Verify Before Full GC, Level 4 (34639 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [23.953s][info][gc] GC(195) Cancel marking: Young [2025-11-28T00:42:27.855Z] [23.953s][info][gc] GC(195) Cancel marking: Old [2025-11-28T00:42:27.855Z] [23.953s][info][gc] GC(195) Cancel marking: Global [2025-11-28T00:42:27.855Z] [24.171s][info][gc] GC(195) Verify After Full GC, Level 4 (34535 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [24.171s][info][gc] GC(195) Pause Full 896M->671M(1024M) 233.391ms [2025-11-28T00:42:27.855Z] Allocated: 714 Mb [2025-11-28T00:42:27.855Z] Allocated: 695 Mb [2025-11-28T00:42:27.855Z] Allocated: 727 Mb [2025-11-28T00:42:27.855Z] Allocated: 692 Mb [2025-11-28T00:42:27.855Z] Allocated: 704 Mb [2025-11-28T00:42:27.855Z] Allocated: 654 Mb [2025-11-28T00:42:27.855Z] Allocated: 678 Mb [2025-11-28T00:42:27.855Z] [24.181s][info][gc] Trigger (Young): Free (101M) is below minimum threshold (102M) [2025-11-28T00:42:27.855Z] [24.181s][info][gc] GC(196) Concurrent reset (Young) 0.149ms [2025-11-28T00:42:27.855Z] [24.191s][info][gc] GC(196) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [24.192s][info][gc] GC(196) Pause Init Mark (Young) 8.266ms [2025-11-28T00:42:27.855Z] Allocated: 711 Mb [2025-11-28T00:42:27.855Z] [24.192s][info][gc] GC(196) Concurrent remembered set scanning 0.180ms [2025-11-28T00:42:27.855Z] [24.196s][info][gc] GC(196) Concurrent marking roots 3.681ms [2025-11-28T00:42:27.855Z] Allocated: 694 Mb [2025-11-28T00:42:27.855Z] Allocated: 723 Mb [2025-11-28T00:42:27.855Z] [24.199s][info][gc] GC(196) Concurrent marking (Young) 3.267ms [2025-11-28T00:42:27.855Z] [24.203s][info][gc] GC(196) Verify After Mark, Level 4 (25 reachable, 16 marked) [2025-11-28T00:42:27.855Z] [24.203s][info][gc] GC(196) Pause Final Mark (Young) 3.372ms [2025-11-28T00:42:27.855Z] [24.203s][info][gc] GC(196) Concurrent weak references (Young) 0.013ms [2025-11-28T00:42:27.855Z] [24.203s][info][gc] Failed to allocate Shared, 51118K [2025-11-28T00:42:27.855Z] [24.203s][info][gc] GC(196) Concurrent weak roots (Young) 0.160ms [2025-11-28T00:42:27.855Z] [24.203s][info][gc] GC(196) Concurrent cleanup (Young) 781M->781M(1024M) 0.035ms [2025-11-28T00:42:27.855Z] [24.203s][info][gc] GC(196) Concurrent Final Roots (Young) 0.049ms [2025-11-28T00:42:27.855Z] [24.209s][info][gc] GC(196) Concurrent reset after collect (Young) 5.413ms [2025-11-28T00:42:27.855Z] [24.209s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [24.229s][info][gc] GC(197) Verify Before Full GC, Level 4 (34637 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [24.516s][info][gc] GC(197) Verify After Full GC, Level 4 (34533 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [24.516s][info][gc] GC(197) Pause Full 781M->682M(1024M) 307.145ms [2025-11-28T00:42:27.855Z] Allocated: 725 Mb [2025-11-28T00:42:27.855Z] Allocated: 685 Mb [2025-11-28T00:42:27.855Z] Allocated: 732 Mb [2025-11-28T00:42:27.855Z] Allocated: 706 Mb [2025-11-28T00:42:27.855Z] [24.537s][info][gc] Trigger (Young): Average GC time (34.39 ms) is above the time for average allocation rate (3101 MB/s) to deplete free headroom (79923K) (margin of error = 1.89) [2025-11-28T00:42:27.855Z] [24.538s][info][gc] GC(198) Concurrent reset (Young) 0.468ms [2025-11-28T00:42:27.855Z] [24.549s][info][gc] GC(198) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [24.549s][info][gc] GC(198) Pause Init Mark (Young) 6.392ms [2025-11-28T00:42:27.855Z] Allocated: 731 Mb [2025-11-28T00:42:27.855Z] Allocated: 700 Mb [2025-11-28T00:42:27.855Z] [24.554s][info][gc] GC(198) Concurrent remembered set scanning 4.114ms [2025-11-28T00:42:27.855Z] [24.556s][info][gc] GC(198) Concurrent marking roots 1.351ms [2025-11-28T00:42:27.855Z] Allocated: 715 Mb [2025-11-28T00:42:27.855Z] Allocated: 709 Mb [2025-11-28T00:42:27.855Z] [24.565s][info][gc] Failed to allocate Shared, 46770K [2025-11-28T00:42:27.855Z] [24.565s][info][gc] GC(198) Concurrent marking (Young) 9.052ms [2025-11-28T00:42:27.855Z] [24.565s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [24.586s][info][gc] GC(199) Verify Before Full GC, Level 4 (34638 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [24.587s][info][gc] GC(199) Cancel marking: Young [2025-11-28T00:42:27.855Z] [24.587s][info][gc] GC(199) Cancel marking: Old [2025-11-28T00:42:27.855Z] [24.587s][info][gc] GC(199) Cancel marking: Global [2025-11-28T00:42:27.855Z] [25.124s][info][gc] GC(199) Verify After Full GC, Level 4 (34534 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [25.124s][info][gc] GC(199) Pause Full 919M->670M(1024M) 558.629ms [2025-11-28T00:42:27.855Z] Allocated: 709 Mb [2025-11-28T00:42:27.855Z] Allocated: 687 Mb [2025-11-28T00:42:27.855Z] Allocated: 706 Mb [2025-11-28T00:42:27.855Z] Allocated: 737 Mb [2025-11-28T00:42:27.855Z] Allocated: 685 Mb [2025-11-28T00:42:27.855Z] [25.159s][info][gc] Trigger (Young): Free (102M) is below minimum threshold (102M) [2025-11-28T00:42:27.855Z] [25.160s][info][gc] GC(200) Concurrent reset (Young) 0.841ms [2025-11-28T00:42:27.855Z] [25.169s][info][gc] GC(200) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [25.170s][info][gc] GC(200) Pause Init Mark (Young) 2.378ms [2025-11-28T00:42:27.855Z] Allocated: 742 Mb [2025-11-28T00:42:27.855Z] [25.177s][info][gc] GC(200) Concurrent remembered set scanning 5.695ms [2025-11-28T00:42:27.855Z] Allocated: 722 Mb [2025-11-28T00:42:27.855Z] [25.178s][info][gc] GC(200) Concurrent marking roots 1.426ms [2025-11-28T00:42:27.855Z] Allocated: 717 Mb [2025-11-28T00:42:27.855Z] Allocated: 701 Mb [2025-11-28T00:42:27.855Z] Allocated: 689 Mb [2025-11-28T00:42:27.855Z] Allocated: 704 Mb [2025-11-28T00:42:27.855Z] [25.189s][info][gc] Failed to allocate Shared, 19014K [2025-11-28T00:42:27.855Z] [25.190s][info][gc] GC(200) Concurrent marking (Young) 11.450ms [2025-11-28T00:42:27.855Z] [25.190s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [25.223s][info][gc] GC(201) Verify Before Full GC, Level 4 (34637 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [25.228s][info][gc] GC(201) Cancel marking: Young [2025-11-28T00:42:27.855Z] [25.228s][info][gc] GC(201) Cancel marking: Old [2025-11-28T00:42:27.855Z] [25.228s][info][gc] GC(201) Cancel marking: Global [2025-11-28T00:42:27.855Z] [25.646s][info][gc] GC(201) Verify After Full GC, Level 4 (34533 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [25.646s][info][gc] GC(201) Pause Full 946M->671M(1024M) 456.751ms [2025-11-28T00:42:27.855Z] Allocated: 683 Mb [2025-11-28T00:42:27.855Z] Allocated: 692 Mb [2025-11-28T00:42:27.855Z] Allocated: 706 Mb [2025-11-28T00:42:27.855Z] Allocated: 752 Mb [2025-11-28T00:42:27.855Z] Allocated: 706 Mb [2025-11-28T00:42:27.855Z] Allocated: 693 Mb [2025-11-28T00:42:27.855Z] Allocated: 702 Mb [2025-11-28T00:42:27.855Z] Allocated: 698 Mb [2025-11-28T00:42:27.855Z] Allocated: 710 Mb [2025-11-28T00:42:27.855Z] Allocated: 712 Mb [2025-11-28T00:42:27.855Z] [25.687s][info][gc] Trigger (Young): Free (93951K) is below minimum threshold (102M) [2025-11-28T00:42:27.855Z] [25.687s][info][gc] GC(202) Concurrent reset (Young) 0.186ms [2025-11-28T00:42:27.855Z] [25.702s][info][gc] GC(202) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [25.702s][info][gc] GC(202) Pause Init Mark (Young) 10.800ms [2025-11-28T00:42:27.855Z] Allocated: 703 Mb [2025-11-28T00:42:27.855Z] Allocated: 719 Mb [2025-11-28T00:42:27.855Z] [25.710s][info][gc] GC(202) Concurrent remembered set scanning 5.270ms [2025-11-28T00:42:27.855Z] [25.714s][info][gc] GC(202) Concurrent marking roots 4.145ms [2025-11-28T00:42:27.855Z] Allocated: 678 Mb [2025-11-28T00:42:27.855Z] [25.718s][info][gc] Failed to allocate Shared, 64628K [2025-11-28T00:42:27.855Z] [25.718s][info][gc] GC(202) Concurrent marking (Young) 4.071ms [2025-11-28T00:42:27.855Z] [25.718s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [25.768s][info][gc] GC(203) Verify Before Full GC, Level 4 (34638 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [25.769s][info][gc] GC(203) Cancel marking: Young [2025-11-28T00:42:27.855Z] [25.769s][info][gc] GC(203) Cancel marking: Old [2025-11-28T00:42:27.855Z] [25.769s][info][gc] GC(203) Cancel marking: Global [2025-11-28T00:42:27.855Z] [26.521s][info][gc] GC(203) Verify After Full GC, Level 4 (34534 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [26.522s][info][gc] GC(203) Pause Full 944M->686M(1024M) 803.491ms [2025-11-28T00:42:27.855Z] Allocated: 741 Mb [2025-11-28T00:42:27.855Z] Allocated: 695 Mb [2025-11-28T00:42:27.855Z] Allocated: 698 Mb [2025-11-28T00:42:27.855Z] Allocated: 702 Mb [2025-11-28T00:42:27.855Z] Allocated: 681 Mb [2025-11-28T00:42:27.855Z] Allocated: 692 Mb [2025-11-28T00:42:27.855Z] Allocated: 731 Mb [2025-11-28T00:42:27.855Z] [26.543s][info][gc] Trigger (Young): Free (80127K) is below minimum threshold (102M) [2025-11-28T00:42:27.855Z] [26.544s][info][gc] GC(204) Concurrent reset (Young) 0.173ms [2025-11-28T00:42:27.855Z] [26.555s][info][gc] GC(204) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [26.555s][info][gc] GC(204) Pause Init Mark (Young) 3.696ms [2025-11-28T00:42:27.855Z] Allocated: 733 Mb [2025-11-28T00:42:27.855Z] [26.557s][info][gc] GC(204) Concurrent remembered set scanning 0.132ms [2025-11-28T00:42:27.855Z] [26.559s][info][gc] GC(204) Concurrent marking roots 1.230ms [2025-11-28T00:42:27.855Z] Allocated: 714 Mb [2025-11-28T00:42:27.855Z] [26.563s][info][gc] Failed to allocate Shared, 19507K [2025-11-28T00:42:27.855Z] [26.564s][info][gc] GC(204) Concurrent marking (Young) 5.074ms [2025-11-28T00:42:27.855Z] [26.564s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [26.583s][info][gc] GC(205) Verify Before Full GC, Level 4 (34639 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [26.583s][info][gc] GC(205) Cancel marking: Young [2025-11-28T00:42:27.855Z] [26.583s][info][gc] GC(205) Cancel marking: Old [2025-11-28T00:42:27.855Z] [26.583s][info][gc] GC(205) Cancel marking: Global [2025-11-28T00:42:27.855Z] [27.057s][info][gc] GC(205) Verify After Full GC, Level 4 (34535 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [27.057s][info][gc] GC(205) Pause Full 938M->676M(1024M) 492.953ms [2025-11-28T00:42:27.855Z] Allocated: 687 Mb [2025-11-28T00:42:27.855Z] Allocated: 747 Mb [2025-11-28T00:42:27.855Z] Allocated: 714 Mb [2025-11-28T00:42:27.855Z] Allocated: 702 Mb [2025-11-28T00:42:27.855Z] Allocated: 680 Mb [2025-11-28T00:42:27.855Z] Allocated: 711 Mb [2025-11-28T00:42:27.855Z] Allocated: 679 Mb [2025-11-28T00:42:27.855Z] [27.111s][info][gc] Trigger (Young): Average GC time (34.39 ms) is above the time for average allocation rate (1718 MB/s) to deplete free headroom (58419K) (margin of error = 1.89) [2025-11-28T00:42:27.855Z] [27.111s][info][gc] GC(206) Concurrent reset (Young) 0.164ms [2025-11-28T00:42:27.855Z] [27.119s][info][gc] GC(206) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [27.120s][info][gc] GC(206) Pause Init Mark (Young) 8.259ms [2025-11-28T00:42:27.855Z] [27.120s][info][gc] GC(206) Concurrent remembered set scanning 0.163ms [2025-11-28T00:42:27.855Z] Allocated: 680 Mb [2025-11-28T00:42:27.855Z] [27.121s][info][gc] GC(206) Concurrent marking roots 1.316ms [2025-11-28T00:42:27.855Z] Allocated: 687 Mb [2025-11-28T00:42:27.855Z] [27.125s][info][gc] GC(206) Concurrent marking (Young) 3.937ms [2025-11-28T00:42:27.855Z] [27.142s][info][gc] GC(206) Verify After Mark, Level 4 (29 reachable, 10 marked) [2025-11-28T00:42:27.855Z] [27.142s][info][gc] GC(206) Pause Final Mark (Young) 4.382ms [2025-11-28T00:42:27.855Z] Allocated: 738 Mb [2025-11-28T00:42:27.855Z] Allocated: 703 Mb [2025-11-28T00:42:27.855Z] [27.147s][info][gc] GC(206) Concurrent weak references (Young) 0.035ms [2025-11-28T00:42:27.855Z] Allocated: 700 Mb [2025-11-28T00:42:27.855Z] Allocated: 694 Mb [2025-11-28T00:42:27.855Z] [27.151s][info][gc] Failed to allocate Shared, 27997K [2025-11-28T00:42:27.855Z] [27.151s][info][gc] GC(206) Concurrent weak roots (Young) 3.719ms [2025-11-28T00:42:27.855Z] [27.151s][info][gc] GC(206) Concurrent cleanup (Young) 769M->769M(1024M) 0.134ms [2025-11-28T00:42:27.855Z] [27.152s][info][gc] GC(206) Concurrent Final Roots (Young) 0.852ms [2025-11-28T00:42:27.855Z] [27.156s][info][gc] GC(206) Concurrent reset after collect (Young) 3.461ms [2025-11-28T00:42:27.855Z] [27.156s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [27.183s][info][gc] GC(207) Verify Before Full GC, Level 4 (34643 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [27.658s][info][gc] GC(207) Verify After Full GC, Level 4 (34539 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [27.659s][info][gc] GC(207) Pause Full 769M->704M(1024M) 502.875ms [2025-11-28T00:42:27.855Z] Allocated: 721 Mb [2025-11-28T00:42:27.855Z] Allocated: 719 Mb [2025-11-28T00:42:27.855Z] Allocated: 689 Mb [2025-11-28T00:42:27.855Z] Allocated: 730 Mb [2025-11-28T00:42:27.855Z] [27.703s][info][gc] Trigger (Young): Free (95487K) is below minimum threshold (102M) [2025-11-28T00:42:27.855Z] [27.703s][info][gc] GC(208) Concurrent reset (Young) 0.219ms [2025-11-28T00:42:27.855Z] [27.722s][info][gc] GC(208) Verify Before Mark, Level 4 (29 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [27.722s][info][gc] GC(208) Pause Init Mark (Young) 7.327ms [2025-11-28T00:42:27.855Z] Allocated: 726 Mb [2025-11-28T00:42:27.855Z] [27.730s][info][gc] GC(208) Concurrent remembered set scanning 4.354ms [2025-11-28T00:42:27.855Z] [27.730s][info][gc] GC(208) Concurrent marking roots 0.159ms [2025-11-28T00:42:27.855Z] Allocated: 719 Mb [2025-11-28T00:42:27.855Z] [27.735s][info][gc] GC(208) Concurrent marking (Young) 4.890ms [2025-11-28T00:42:27.855Z] [27.755s][info][gc] GC(208) Verify After Mark, Level 4 (25 reachable, 13 marked) [2025-11-28T00:42:27.855Z] [27.755s][info][gc] GC(208) Pause Final Mark (Young) 12.451ms [2025-11-28T00:42:27.855Z] [27.755s][info][gc] GC(208) Concurrent weak references (Young) 0.035ms [2025-11-28T00:42:27.855Z] [27.756s][info][gc] GC(208) Concurrent weak roots (Young) 0.734ms [2025-11-28T00:42:27.855Z] [27.756s][info][gc] GC(208) Concurrent cleanup (Young) 771M->771M(1024M) 0.074ms [2025-11-28T00:42:27.855Z] Allocated: 686 Mb [2025-11-28T00:42:27.855Z] [27.758s][info][gc] GC(208) Concurrent Final Roots (Young) 1.923ms [2025-11-28T00:42:27.855Z] Allocated: 690 Mb [2025-11-28T00:42:27.855Z] [27.759s][info][gc] GC(208) Concurrent reset after collect (Young) 1.519ms [2025-11-28T00:42:27.855Z] [27.760s][info][gc] Trigger (Young): Average GC time (70.53 ms) is above the time for average allocation rate (2424 MB/s) to deplete free headroom (122M) (margin of error = 1.88) [2025-11-28T00:42:27.855Z] [27.764s][info][gc] GC(209) Concurrent reset (Young) 0.152ms [2025-11-28T00:42:27.855Z] [27.770s][info][gc] GC(209) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [27.771s][info][gc] GC(209) Pause Init Mark (Young) 6.095ms [2025-11-28T00:42:27.855Z] [27.771s][info][gc] GC(209) Concurrent remembered set scanning 0.220ms [2025-11-28T00:42:27.855Z] Allocated: 712 Mb [2025-11-28T00:42:27.855Z] [27.772s][info][gc] GC(209) Concurrent marking roots 1.282ms [2025-11-28T00:42:27.855Z] Allocated: 730 Mb [2025-11-28T00:42:27.855Z] Allocated: 735 Mb [2025-11-28T00:42:27.855Z] [27.803s][info][gc] GC(209) Concurrent marking (Young) 30.836ms [2025-11-28T00:42:27.855Z] [27.812s][info][gc] GC(209) Verify After Mark, Level 4 (27 reachable, 16 marked) [2025-11-28T00:42:27.855Z] [27.812s][info][gc] GC(209) Pause Final Mark (Young) 8.338ms [2025-11-28T00:42:27.855Z] [27.816s][info][gc] GC(209) Concurrent weak references (Young) 0.056ms [2025-11-28T00:42:27.855Z] Allocated: 734 Mb [2025-11-28T00:42:27.855Z] [27.825s][info][gc] GC(209) Concurrent weak roots (Young) 8.987ms [2025-11-28T00:42:27.855Z] [27.825s][info][gc] GC(209) Concurrent cleanup (Young) 880M->880M(1024M) 0.044ms [2025-11-28T00:42:27.855Z] Allocated: 705 Mb [2025-11-28T00:42:27.855Z] [27.831s][info][gc] GC(209) Concurrent Final Roots (Young) 6.538ms [2025-11-28T00:42:27.855Z] Allocated: 651 Mb [2025-11-28T00:42:27.855Z] Allocated: 656 Mb [2025-11-28T00:42:27.855Z] [27.832s][info][gc] Failed to allocate Shared, 50257K [2025-11-28T00:42:27.855Z] [27.833s][info][gc] GC(209) Concurrent reset after collect (Young) 1.682ms [2025-11-28T00:42:27.855Z] [27.833s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.855Z] [27.865s][info][gc] GC(210) Verify Before Full GC, Level 4 (34639 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [28.324s][info][gc] GC(210) Verify After Full GC, Level 4 (34535 reachable, 0 marked) [2025-11-28T00:42:27.855Z] [28.324s][info][gc] GC(210) Pause Full 903M->664M(1024M) 490.414ms [2025-11-28T00:42:27.855Z] Allocated: 705 Mb [2025-11-28T00:42:27.855Z] Allocated: 682 Mb [2025-11-28T00:42:27.855Z] Allocated: 738 Mb [2025-11-28T00:42:27.856Z] [28.364s][info][gc] Trigger (Young): Average GC time (86.98 ms) is above the time for average allocation rate (1179 MB/s) to deplete free headroom (98867K) (margin of error = 1.89) [2025-11-28T00:42:27.856Z] [28.364s][info][gc] GC(211) Concurrent reset (Young) 0.151ms [2025-11-28T00:42:27.856Z] [28.373s][info][gc] GC(211) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [28.374s][info][gc] GC(211) Pause Init Mark (Young) 7.195ms [2025-11-28T00:42:27.856Z] Allocated: 705 Mb [2025-11-28T00:42:27.856Z] [28.375s][info][gc] GC(211) Concurrent remembered set scanning 1.443ms [2025-11-28T00:42:27.856Z] [28.376s][info][gc] GC(211) Concurrent marking roots 0.468ms [2025-11-28T00:42:27.856Z] Allocated: 740 Mb [2025-11-28T00:42:27.856Z] Allocated: 715 Mb [2025-11-28T00:42:27.856Z] [28.401s][info][gc] GC(211) Concurrent marking (Young) 24.619ms [2025-11-28T00:42:27.856Z] [28.412s][info][gc] GC(211) Verify After Mark, Level 4 (24 reachable, 17 marked) [2025-11-28T00:42:27.856Z] [28.412s][info][gc] GC(211) Pause Final Mark (Young) 10.925ms [2025-11-28T00:42:27.856Z] Allocated: 669 Mb [2025-11-28T00:42:27.856Z] [28.413s][info][gc] GC(211) Concurrent weak references (Young) 0.102ms [2025-11-28T00:42:27.856Z] Allocated: 725 Mb [2025-11-28T00:42:27.856Z] [28.428s][info][gc] GC(211) Concurrent weak roots (Young) 15.116ms [2025-11-28T00:42:27.856Z] [28.429s][info][gc] GC(211) Concurrent cleanup (Young) 860M->860M(1024M) 0.052ms [2025-11-28T00:42:27.856Z] [28.429s][info][gc] GC(211) Concurrent Final Roots (Young) 0.193ms [2025-11-28T00:42:27.856Z] [28.432s][info][gc] GC(211) Concurrent reset after collect (Young) 2.731ms [2025-11-28T00:42:27.856Z] [28.432s][info][gc] Trigger (Young): Free (69120K) is below minimum threshold (102M) [2025-11-28T00:42:27.856Z] [28.436s][info][gc] GC(212) Concurrent reset (Young) 0.150ms [2025-11-28T00:42:27.856Z] [28.446s][info][gc] GC(212) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [28.446s][info][gc] GC(212) Pause Init Mark (Young) 4.586ms [2025-11-28T00:42:27.856Z] Allocated: 718 Mb [2025-11-28T00:42:27.856Z] [28.451s][info][gc] Failed to allocate Shared, 56899K [2025-11-28T00:42:27.856Z] [28.454s][info][gc] GC(212) Concurrent remembered set scanning 0.402ms [2025-11-28T00:42:27.856Z] [28.454s][info][gc] GC(212) Concurrent marking roots 0.163ms [2025-11-28T00:42:27.856Z] [28.454s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [28.476s][info][gc] GC(213) Verify Before Full GC, Level 4 (34636 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [28.477s][info][gc] GC(213) Cancel marking: Young [2025-11-28T00:42:27.856Z] [28.477s][info][gc] GC(213) Cancel marking: Old [2025-11-28T00:42:27.856Z] [28.477s][info][gc] GC(213) Cancel marking: Global [2025-11-28T00:42:27.856Z] [29.028s][info][gc] GC(213) Verify After Full GC, Level 4 (34532 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [29.028s][info][gc] GC(213) Pause Full 904M->672M(1024M) 574.055ms [2025-11-28T00:42:27.856Z] Allocated: 720 Mb [2025-11-28T00:42:27.856Z] Allocated: 748 Mb [2025-11-28T00:42:27.856Z] Allocated: 706 Mb [2025-11-28T00:42:27.856Z] Allocated: 702 Mb [2025-11-28T00:42:27.856Z] [29.070s][info][gc] Trigger (Young): Free (102143K) is below minimum threshold (102M) [2025-11-28T00:42:27.856Z] [29.071s][info][gc] GC(214) Concurrent reset (Young) 0.181ms [2025-11-28T00:42:27.856Z] [29.095s][info][gc] GC(214) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [29.095s][info][gc] GC(214) Pause Init Mark (Young) 4.126ms [2025-11-28T00:42:27.856Z] Allocated: 755 Mb [2025-11-28T00:42:27.856Z] Allocated: 659 Mb [2025-11-28T00:42:27.856Z] [29.099s][info][gc] GC(214) Concurrent remembered set scanning 3.307ms [2025-11-28T00:42:27.856Z] [29.099s][info][gc] GC(214) Concurrent marking roots 0.482ms [2025-11-28T00:42:27.856Z] [29.110s][info][gc] GC(214) Concurrent marking (Young) 10.465ms [2025-11-28T00:42:27.856Z] Allocated: 697 Mb [2025-11-28T00:42:27.856Z] [29.121s][info][gc] GC(214) Verify After Mark, Level 4 (23 reachable, 13 marked) [2025-11-28T00:42:27.856Z] [29.121s][info][gc] GC(214) Pause Final Mark (Young) 11.022ms [2025-11-28T00:42:27.856Z] [29.121s][info][gc] GC(214) Concurrent weak references (Young) 0.082ms [2025-11-28T00:42:27.856Z] [29.121s][info][gc] Failed to allocate Shared, 58566K [2025-11-28T00:42:27.856Z] [29.122s][info][gc] GC(214) Concurrent weak roots (Young) 0.479ms [2025-11-28T00:42:27.856Z] [29.122s][info][gc] GC(214) Concurrent cleanup (Young) 705M->705M(1024M) 0.152ms [2025-11-28T00:42:27.856Z] [29.125s][info][gc] GC(214) Concurrent Final Roots (Young) 3.442ms [2025-11-28T00:42:27.856Z] [29.127s][info][gc] GC(214) Concurrent reset after collect (Young) 1.369ms [2025-11-28T00:42:27.856Z] [29.127s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [29.157s][info][gc] GC(215) Verify Before Full GC, Level 4 (34636 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [29.633s][info][gc] GC(215) Verify After Full GC, Level 4 (34532 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [29.634s][info][gc] GC(215) Pause Full 705M->704M(1024M) 506.710ms [2025-11-28T00:42:27.856Z] Allocated: 754 Mb [2025-11-28T00:42:27.856Z] Allocated: 711 Mb [2025-11-28T00:42:27.856Z] Allocated: 685 Mb [2025-11-28T00:42:27.856Z] Allocated: 702 Mb [2025-11-28T00:42:27.856Z] Allocated: 706 Mb [2025-11-28T00:42:27.856Z] [29.676s][info][gc] Trigger (Young): Average GC time (76.96 ms) is above the time for average allocation rate (880 MB/s) to deplete free headroom (67123K) (margin of error = 1.90) [2025-11-28T00:42:27.856Z] Allocated: 682 Mb [2025-11-28T00:42:27.856Z] Allocated: 687 Mb [2025-11-28T00:42:27.856Z] [29.682s][info][gc] GC(216) Concurrent reset (Young) 0.403ms [2025-11-28T00:42:27.856Z] [29.701s][info][gc] GC(216) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [29.701s][info][gc] GC(216) Pause Init Mark (Young) 6.473ms [2025-11-28T00:42:27.856Z] [29.702s][info][gc] GC(216) Concurrent remembered set scanning 0.333ms [2025-11-28T00:42:27.856Z] Allocated: 743 Mb [2025-11-28T00:42:27.856Z] [29.704s][info][gc] GC(216) Concurrent marking roots 1.832ms [2025-11-28T00:42:27.856Z] Allocated: 708 Mb [2025-11-28T00:42:27.856Z] Allocated: 681 Mb [2025-11-28T00:42:27.856Z] [29.716s][info][gc] Failed to allocate Shared, 20758K [2025-11-28T00:42:27.856Z] [29.716s][info][gc] GC(216) Concurrent marking (Young) 11.983ms [2025-11-28T00:42:27.856Z] [29.716s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [29.742s][info][gc] GC(217) Verify Before Full GC, Level 4 (34638 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [29.742s][info][gc] GC(217) Cancel marking: Young [2025-11-28T00:42:27.856Z] [29.742s][info][gc] GC(217) Cancel marking: Old [2025-11-28T00:42:27.856Z] [29.743s][info][gc] GC(217) Cancel marking: Global [2025-11-28T00:42:27.856Z] [30.402s][info][gc] GC(217) Verify After Full GC, Level 4 (34534 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [30.403s][info][gc] GC(217) Pause Full 956M->690M(1024M) 686.316ms [2025-11-28T00:42:27.856Z] Allocated: 701 Mb [2025-11-28T00:42:27.856Z] Allocated: 682 Mb [2025-11-28T00:42:27.856Z] Allocated: 731 Mb [2025-11-28T00:42:27.856Z] Allocated: 723 Mb [2025-11-28T00:42:27.856Z] Allocated: 700 Mb [2025-11-28T00:42:27.856Z] Allocated: 693 Mb [2025-11-28T00:42:27.856Z] [30.475s][info][gc] Trigger (Young): Free (82175K) is below minimum threshold (102M) [2025-11-28T00:42:27.856Z] [30.476s][info][gc] GC(218) Concurrent reset (Young) 0.165ms [2025-11-28T00:42:27.856Z] [30.500s][info][gc] GC(218) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [30.501s][info][gc] GC(218) Pause Init Mark (Young) 7.939ms [2025-11-28T00:42:27.856Z] Allocated: 737 Mb [2025-11-28T00:42:27.856Z] Allocated: 666 Mb [2025-11-28T00:42:27.856Z] Allocated: 667 Mb [2025-11-28T00:42:27.856Z] [30.510s][info][gc] GC(218) Concurrent remembered set scanning 1.372ms [2025-11-28T00:42:27.856Z] [30.511s][info][gc] GC(218) Concurrent marking roots 0.691ms [2025-11-28T00:42:27.856Z] Allocated: 693 Mb [2025-11-28T00:42:27.856Z] Allocated: 723 Mb [2025-11-28T00:42:27.856Z] Allocated: 700 Mb [2025-11-28T00:42:27.856Z] [30.539s][info][gc] Failed to allocate Shared, 46139K [2025-11-28T00:42:27.856Z] [30.539s][info][gc] GC(218) Concurrent marking (Young) 28.244ms [2025-11-28T00:42:27.856Z] [30.540s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [30.580s][info][gc] GC(219) Verify Before Full GC, Level 4 (34639 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [30.581s][info][gc] GC(219) Cancel marking: Young [2025-11-28T00:42:27.856Z] [30.581s][info][gc] GC(219) Cancel marking: Old [2025-11-28T00:42:27.856Z] [30.581s][info][gc] GC(219) Cancel marking: Global [2025-11-28T00:42:27.856Z] [31.123s][info][gc] GC(219) Verify After Full GC, Level 4 (34535 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [31.123s][info][gc] GC(219) Pause Full 971M->652M(1024M) 583.590ms [2025-11-28T00:42:27.856Z] Allocated: 687 Mb [2025-11-28T00:42:27.856Z] Allocated: 698 Mb [2025-11-28T00:42:27.856Z] Allocated: 750 Mb [2025-11-28T00:42:27.856Z] Allocated: 751 Mb [2025-11-28T00:42:27.856Z] [31.175s][info][gc] Trigger (Young): Average GC time (76.96 ms) is above the time for average allocation rate (886 MB/s) to deplete free headroom (68147K) (margin of error = 1.90) [2025-11-28T00:42:27.856Z] [31.176s][info][gc] GC(220) Concurrent reset (Young) 0.170ms [2025-11-28T00:42:27.856Z] [31.190s][info][gc] GC(220) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [31.191s][info][gc] GC(220) Pause Init Mark (Young) 6.118ms [2025-11-28T00:42:27.856Z] [31.191s][info][gc] GC(220) Concurrent remembered set scanning 0.189ms [2025-11-28T00:42:27.856Z] Allocated: 676 Mb [2025-11-28T00:42:27.856Z] [31.192s][info][gc] GC(220) Concurrent marking roots 1.404ms [2025-11-28T00:42:27.856Z] Allocated: 732 Mb [2025-11-28T00:42:27.856Z] [31.208s][info][gc] GC(220) Concurrent marking (Young) 15.015ms [2025-11-28T00:42:27.856Z] [31.223s][info][gc] GC(220) Verify After Mark, Level 4 (24 reachable, 12 marked) [2025-11-28T00:42:27.856Z] [31.223s][info][gc] GC(220) Pause Final Mark (Young) 10.308ms [2025-11-28T00:42:27.856Z] [31.223s][info][gc] GC(220) Concurrent weak references (Young) 0.221ms [2025-11-28T00:42:27.856Z] [31.224s][info][gc] GC(220) Concurrent weak roots (Young) 0.849ms [2025-11-28T00:42:27.856Z] Allocated: 716 Mb [2025-11-28T00:42:27.856Z] [31.225s][info][gc] GC(220) Concurrent cleanup (Young) 765M->790M(1024M) 0.355ms [2025-11-28T00:42:27.856Z] Allocated: 680 Mb [2025-11-28T00:42:27.856Z] [31.233s][info][gc] GC(220) Concurrent Final Roots (Young) 8.004ms [2025-11-28T00:42:27.856Z] [31.234s][info][gc] GC(220) Concurrent reset after collect (Young) 1.566ms [2025-11-28T00:42:27.856Z] [31.235s][info][gc] Trigger (Young): Average GC time (71.54 ms) is above the time for average allocation rate (2033 MB/s) to deplete free headroom (98611K) (margin of error = 1.90) [2025-11-28T00:42:27.856Z] [31.235s][info][gc] GC(221) Concurrent reset (Young) 0.132ms [2025-11-28T00:42:27.856Z] [31.248s][info][gc] GC(221) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [31.249s][info][gc] GC(221) Pause Init Mark (Young) 6.079ms [2025-11-28T00:42:27.856Z] Allocated: 714 Mb [2025-11-28T00:42:27.856Z] [31.253s][info][gc] GC(221) Concurrent remembered set scanning 2.674ms [2025-11-28T00:42:27.856Z] [31.253s][info][gc] GC(221) Concurrent marking roots 0.536ms [2025-11-28T00:42:27.856Z] Allocated: 735 Mb [2025-11-28T00:42:27.856Z] Allocated: 703 Mb [2025-11-28T00:42:27.856Z] [31.276s][info][gc] GC(221) Concurrent marking (Young) 23.016ms [2025-11-28T00:42:27.856Z] [31.290s][info][gc] GC(221) Verify After Mark, Level 4 (24 reachable, 17 marked) [2025-11-28T00:42:27.856Z] [31.291s][info][gc] GC(221) Pause Final Mark (Young) 7.132ms [2025-11-28T00:42:27.856Z] Allocated: 706 Mb [2025-11-28T00:42:27.856Z] [31.291s][info][gc] GC(221) Concurrent weak references (Young) 0.192ms [2025-11-28T00:42:27.856Z] Allocated: 652 Mb [2025-11-28T00:42:27.856Z] [31.298s][info][gc] Failed to allocate Shared, 14793K [2025-11-28T00:42:27.856Z] [31.298s][info][gc] GC(221) Concurrent weak roots (Young) 6.791ms [2025-11-28T00:42:27.856Z] [31.298s][info][gc] GC(221) Concurrent cleanup (Young) 819M->819M(1024M) 0.117ms [2025-11-28T00:42:27.856Z] [31.299s][info][gc] GC(221) Concurrent Final Roots (Young) 0.487ms [2025-11-28T00:42:27.856Z] [31.300s][info][gc] GC(221) Concurrent reset after collect (Young) 1.557ms [2025-11-28T00:42:27.856Z] [31.300s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [31.315s][info][gc] GC(222) Verify Before Full GC, Level 4 (34637 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [31.899s][info][gc] GC(222) Verify After Full GC, Level 4 (34533 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [31.899s][info][gc] GC(222) Pause Full 819M->661M(1024M) 598.302ms [2025-11-28T00:42:27.856Z] Allocated: 667 Mb [2025-11-28T00:42:27.856Z] Allocated: 705 Mb [2025-11-28T00:42:27.856Z] Allocated: 689 Mb [2025-11-28T00:42:27.856Z] Allocated: 691 Mb [2025-11-28T00:42:27.856Z] Allocated: 711 Mb [2025-11-28T00:42:27.856Z] Allocated: 671 Mb [2025-11-28T00:42:27.856Z] Allocated: 733 Mb [2025-11-28T00:42:27.856Z] [31.953s][info][gc] Trigger (Young): Average GC time (72.93 ms) is above the time for average allocation rate (1126 MB/s) to deplete free headroom (53299K) (margin of error = 1.90) [2025-11-28T00:42:27.856Z] [31.953s][info][gc] GC(223) Concurrent reset (Young) 0.578ms [2025-11-28T00:42:27.856Z] [31.976s][info][gc] GC(223) Verify Before Mark, Level 4 (28 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [31.977s][info][gc] GC(223) Pause Init Mark (Young) 11.156ms [2025-11-28T00:42:27.856Z] Allocated: 722 Mb [2025-11-28T00:42:27.856Z] [31.978s][info][gc] GC(223) Concurrent remembered set scanning 0.499ms [2025-11-28T00:42:27.856Z] [31.978s][info][gc] GC(223) Concurrent marking roots 0.328ms [2025-11-28T00:42:27.856Z] Allocated: 707 Mb [2025-11-28T00:42:27.856Z] [31.990s][info][gc] Failed to allocate Shared, 58667K [2025-11-28T00:42:27.856Z] [31.992s][info][gc] GC(223) Concurrent marking (Young) 13.650ms [2025-11-28T00:42:27.856Z] [31.992s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [32.024s][info][gc] GC(224) Verify Before Full GC, Level 4 (34640 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [32.024s][info][gc] GC(224) Cancel marking: Young [2025-11-28T00:42:27.856Z] [32.024s][info][gc] GC(224) Cancel marking: Old [2025-11-28T00:42:27.856Z] [32.024s][info][gc] GC(224) Cancel marking: Global [2025-11-28T00:42:27.856Z] [32.504s][info][gc] GC(224) Verify After Full GC, Level 4 (34536 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [32.504s][info][gc] GC(224) Pause Full 916M->660M(1024M) 512.093ms [2025-11-28T00:42:27.856Z] Allocated: 708 Mb [2025-11-28T00:42:27.856Z] Allocated: 753 Mb [2025-11-28T00:42:27.856Z] Allocated: 743 Mb [2025-11-28T00:42:27.856Z] Allocated: 669 Mb [2025-11-28T00:42:27.856Z] Allocated: 676 Mb [2025-11-28T00:42:27.856Z] [32.563s][info][gc] Trigger (Young): Average GC time (72.93 ms) is above the time for average allocation rate (1121 MB/s) to deplete free headroom (69171K) (margin of error = 1.90) [2025-11-28T00:42:27.856Z] Allocated: 689 Mb [2025-11-28T00:42:27.856Z] [32.566s][info][gc] GC(225) Concurrent reset (Young) 0.503ms [2025-11-28T00:42:27.856Z] [32.585s][info][gc] GC(225) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [32.585s][info][gc] GC(225) Pause Init Mark (Young) 4.587ms [2025-11-28T00:42:27.856Z] Allocated: 747 Mb [2025-11-28T00:42:27.856Z] [32.588s][info][gc] GC(225) Concurrent remembered set scanning 1.004ms [2025-11-28T00:42:27.856Z] [32.588s][info][gc] GC(225) Concurrent marking roots 0.411ms [2025-11-28T00:42:27.856Z] Allocated: 725 Mb [2025-11-28T00:42:27.856Z] [32.600s][info][gc] Failed to allocate Shared, 43337K [2025-11-28T00:42:27.856Z] [32.601s][info][gc] GC(225) Concurrent marking (Young) 12.361ms [2025-11-28T00:42:27.856Z] [32.601s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [32.622s][info][gc] GC(226) Verify Before Full GC, Level 4 (34639 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [32.626s][info][gc] GC(226) Cancel marking: Young [2025-11-28T00:42:27.856Z] [32.627s][info][gc] GC(226) Cancel marking: Old [2025-11-28T00:42:27.856Z] [32.627s][info][gc] GC(226) Cancel marking: Global [2025-11-28T00:42:27.856Z] [33.049s][info][gc] GC(226) Verify After Full GC, Level 4 (34535 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [33.050s][info][gc] GC(226) Pause Full 947M->670M(1024M) 448.465ms [2025-11-28T00:42:27.856Z] Allocated: 703 Mb [2025-11-28T00:42:27.856Z] Allocated: 747 Mb [2025-11-28T00:42:27.856Z] Allocated: 721 Mb [2025-11-28T00:42:27.856Z] [33.093s][info][gc] Trigger (Young): Average GC time (72.93 ms) is above the time for average allocation rate (1141 MB/s) to deplete free headroom (65075K) (margin of error = 1.90) [2025-11-28T00:42:27.856Z] [33.096s][info][gc] GC(227) Concurrent reset (Young) 0.552ms [2025-11-28T00:42:27.856Z] [33.115s][info][gc] GC(227) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [33.115s][info][gc] GC(227) Pause Init Mark (Young) 4.367ms [2025-11-28T00:42:27.856Z] Allocated: 715 Mb [2025-11-28T00:42:27.856Z] [33.120s][info][gc] GC(227) Concurrent remembered set scanning 0.280ms [2025-11-28T00:42:27.856Z] [33.121s][info][gc] GC(227) Concurrent marking roots 0.757ms [2025-11-28T00:42:27.856Z] Allocated: 687 Mb [2025-11-28T00:42:27.856Z] Allocated: 702 Mb [2025-11-28T00:42:27.856Z] [33.134s][info][gc] GC(227) Concurrent marking (Young) 13.195ms [2025-11-28T00:42:27.856Z] [33.147s][info][gc] GC(227) Verify After Mark, Level 4 (25 reachable, 14 marked) [2025-11-28T00:42:27.856Z] [33.147s][info][gc] GC(227) Pause Final Mark (Young) 13.067ms [2025-11-28T00:42:27.856Z] Allocated: 691 Mb [2025-11-28T00:42:27.856Z] Allocated: 694 Mb [2025-11-28T00:42:27.856Z] [33.156s][info][gc] GC(227) Concurrent weak references (Young) 0.172ms [2025-11-28T00:42:27.856Z] Allocated: 730 Mb [2025-11-28T00:42:27.856Z] [33.162s][info][gc] GC(227) Concurrent weak roots (Young) 6.287ms [2025-11-28T00:42:27.856Z] [33.162s][info][gc] GC(227) Concurrent cleanup (Young) 837M->837M(1024M) 0.301ms [2025-11-28T00:42:27.856Z] Allocated: 707 Mb [2025-11-28T00:42:27.856Z] [33.167s][info][gc] GC(227) Concurrent Final Roots (Young) 4.942ms [2025-11-28T00:42:27.856Z] Allocated: 699 Mb [2025-11-28T00:42:27.856Z] [33.181s][info][gc] GC(227) Concurrent reset after collect (Young) 13.177ms [2025-11-28T00:42:27.856Z] [33.181s][info][gc] Trigger (Young): Free (78848K) is below minimum threshold (102M) [2025-11-28T00:42:27.856Z] Allocated: 728 Mb [2025-11-28T00:42:27.856Z] [33.189s][info][gc] GC(228) Concurrent reset (Young) 0.488ms [2025-11-28T00:42:27.856Z] [33.201s][info][gc] GC(228) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [33.202s][info][gc] GC(228) Pause Init Mark (Young) 5.878ms [2025-11-28T00:42:27.856Z] Allocated: 730 Mb [2025-11-28T00:42:27.856Z] [33.203s][info][gc] Failed to allocate Shared, 53159K [2025-11-28T00:42:27.856Z] [33.213s][info][gc] GC(228) Concurrent remembered set scanning 9.387ms [2025-11-28T00:42:27.856Z] [33.214s][info][gc] GC(228) Concurrent marking roots 0.667ms [2025-11-28T00:42:27.856Z] [33.214s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [33.243s][info][gc] GC(229) Verify Before Full GC, Level 4 (34639 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [33.243s][info][gc] GC(229) Cancel marking: Young [2025-11-28T00:42:27.856Z] [33.243s][info][gc] GC(229) Cancel marking: Old [2025-11-28T00:42:27.856Z] [33.243s][info][gc] GC(229) Cancel marking: Global [2025-11-28T00:42:27.856Z] [33.730s][info][gc] GC(229) Verify After Full GC, Level 4 (34535 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [33.730s][info][gc] GC(229) Pause Full 932M->690M(1024M) 515.222ms [2025-11-28T00:42:27.856Z] Allocated: 733 Mb [2025-11-28T00:42:27.856Z] Allocated: 707 Mb [2025-11-28T00:42:27.856Z] Allocated: 694 Mb [2025-11-28T00:42:27.856Z] Allocated: 753 Mb [2025-11-28T00:42:27.856Z] Allocated: 674 Mb [2025-11-28T00:42:27.856Z] [33.780s][info][gc] Trigger (Young): Average GC time (96.68 ms) is above the time for average allocation rate (720 MB/s) to deplete free headroom (59443K) (margin of error = 1.92) [2025-11-28T00:42:27.856Z] [33.781s][info][gc] GC(230) Concurrent reset (Young) 0.477ms [2025-11-28T00:42:27.856Z] [33.790s][info][gc] GC(230) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [33.790s][info][gc] GC(230) Pause Init Mark (Young) 4.104ms [2025-11-28T00:42:27.856Z] Allocated: 688 Mb [2025-11-28T00:42:27.856Z] [33.792s][info][gc] GC(230) Concurrent remembered set scanning 0.248ms [2025-11-28T00:42:27.856Z] [33.794s][info][gc] GC(230) Concurrent marking roots 2.438ms [2025-11-28T00:42:27.856Z] Allocated: 734 Mb [2025-11-28T00:42:27.856Z] [33.804s][info][gc] Failed to allocate Shared, 46925K [2025-11-28T00:42:27.856Z] [33.805s][info][gc] GC(230) Concurrent marking (Young) 10.766ms [2025-11-28T00:42:27.856Z] [33.806s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [33.828s][info][gc] GC(231) Verify Before Full GC, Level 4 (34639 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [33.828s][info][gc] GC(231) Cancel marking: Young [2025-11-28T00:42:27.856Z] [33.828s][info][gc] GC(231) Cancel marking: Old [2025-11-28T00:42:27.856Z] [33.828s][info][gc] GC(231) Cancel marking: Global [2025-11-28T00:42:27.856Z] [34.490s][info][gc] GC(231) Verify After Full GC, Level 4 (34535 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [34.491s][info][gc] GC(231) Pause Full 908M->701M(1024M) 684.790ms [2025-11-28T00:42:27.856Z] Allocated: 738 Mb [2025-11-28T00:42:27.856Z] Allocated: 747 Mb [2025-11-28T00:42:27.856Z] Allocated: 712 Mb [2025-11-28T00:42:27.856Z] [34.552s][info][gc] Trigger (Young): Average GC time (96.68 ms) is above the time for average allocation rate (877 MB/s) to deplete free headroom (68147K) (margin of error = 1.92) [2025-11-28T00:42:27.856Z] [34.553s][info][gc] GC(232) Concurrent reset (Young) 0.539ms [2025-11-28T00:42:27.856Z] [34.575s][info][gc] GC(232) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [34.576s][info][gc] GC(232) Pause Init Mark (Young) 11.740ms [2025-11-28T00:42:27.856Z] Allocated: 720 Mb [2025-11-28T00:42:27.856Z] [34.582s][info][gc] GC(232) Concurrent remembered set scanning 6.390ms [2025-11-28T00:42:27.856Z] [34.584s][info][gc] GC(232) Concurrent marking roots 1.387ms [2025-11-28T00:42:27.856Z] Allocated: 731 Mb [2025-11-28T00:42:27.856Z] [34.621s][info][gc] GC(232) Concurrent marking (Young) 37.403ms [2025-11-28T00:42:27.856Z] [34.640s][info][gc] GC(232) Verify After Mark, Level 4 (23 reachable, 16 marked) [2025-11-28T00:42:27.856Z] [34.641s][info][gc] GC(232) Pause Final Mark (Young) 16.842ms [2025-11-28T00:42:27.856Z] Allocated: 710 Mb [2025-11-28T00:42:27.856Z] [34.641s][info][gc] GC(232) Concurrent weak references (Young) 0.114ms [2025-11-28T00:42:27.856Z] Allocated: 699 Mb [2025-11-28T00:42:27.856Z] [34.641s][info][gc] Failed to allocate Shared, 52250K [2025-11-28T00:42:27.856Z] [34.646s][info][gc] GC(232) Concurrent weak roots (Young) 5.035ms [2025-11-28T00:42:27.856Z] [34.646s][info][gc] GC(232) Concurrent cleanup (Young) 805M->805M(1024M) 0.233ms [2025-11-28T00:42:27.856Z] [34.647s][info][gc] GC(232) Concurrent Final Roots (Young) 0.310ms [2025-11-28T00:42:27.856Z] [34.659s][info][gc] GC(232) Concurrent reset after collect (Young) 11.915ms [2025-11-28T00:42:27.856Z] [34.659s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [34.704s][info][gc] GC(233) Verify Before Full GC, Level 4 (34636 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [35.278s][info][gc] GC(233) Verify After Full GC, Level 4 (34532 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [35.279s][info][gc] GC(233) Pause Full 805M->707M(1024M) 618.602ms [2025-11-28T00:42:27.856Z] Allocated: 750 Mb [2025-11-28T00:42:27.856Z] Allocated: 725 Mb [2025-11-28T00:42:27.856Z] [35.327s][info][gc] Trigger (Young): Average GC time (125.84 ms) is above the time for average allocation rate (765 MB/s) to deplete free headroom (69683K) (margin of error = 1.92) [2025-11-28T00:42:27.856Z] [35.328s][info][gc] GC(234) Concurrent reset (Young) 0.486ms [2025-11-28T00:42:27.856Z] [35.361s][info][gc] GC(234) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [35.362s][info][gc] GC(234) Pause Init Mark (Young) 9.457ms [2025-11-28T00:42:27.856Z] Allocated: 722 Mb [2025-11-28T00:42:27.856Z] [35.366s][info][gc] GC(234) Concurrent remembered set scanning 1.595ms [2025-11-28T00:42:27.856Z] [35.367s][info][gc] GC(234) Concurrent marking roots 0.764ms [2025-11-28T00:42:27.856Z] Allocated: 734 Mb [2025-11-28T00:42:27.856Z] Allocated: 653 Mb [2025-11-28T00:42:27.856Z] [35.398s][info][gc] GC(234) Concurrent marking (Young) 30.993ms [2025-11-28T00:42:27.856Z] [35.420s][info][gc] GC(234) Verify Before Evacuation, Level 4 (21 reachable, 17 marked) [2025-11-28T00:42:27.856Z] [35.421s][info][gc] GC(234) Pause Final Mark (Young) 13.056ms [2025-11-28T00:42:27.856Z] Allocated: 661 Mb [2025-11-28T00:42:27.856Z] [35.425s][info][gc] GC(234) Concurrent thread roots 2.866ms [2025-11-28T00:42:27.856Z] [35.425s][info][gc] GC(234) Concurrent weak references (Young) 0.061ms [2025-11-28T00:42:27.856Z] Allocated: 679 Mb [2025-11-28T00:42:27.856Z] [35.433s][info][gc] GC(234) Concurrent weak roots (Young) 7.547ms [2025-11-28T00:42:27.856Z] [35.433s][info][gc] GC(234) Concurrent cleanup (Young) 780M->780M(1024M) 0.270ms [2025-11-28T00:42:27.856Z] [35.435s][info][gc] GC(234) Concurrent strong roots 1.404ms [2025-11-28T00:42:27.856Z] [35.438s][info][gc] GC(234) Concurrent evacuation 3.465ms [2025-11-28T00:42:27.856Z] Allocated: 706 Mb [2025-11-28T00:42:27.856Z] [35.449s][info][gc] GC(234) Concurrent Init Update Refs (Young) 10.629ms [2025-11-28T00:42:27.856Z] [35.470s][info][gc] GC(234) Verify Before Updating References, Level 4 (23 reachable, 46 marked) [2025-11-28T00:42:27.856Z] [35.470s][info][gc] GC(234) Pause Init Update Refs 16.215ms [2025-11-28T00:42:27.856Z] Allocated: 691 Mb [2025-11-28T00:42:27.856Z] [35.476s][info][gc] GC(234) Concurrent update references 5.355ms [2025-11-28T00:42:27.856Z] Allocated: 723 Mb [2025-11-28T00:42:27.856Z] [35.489s][info][gc] GC(234) Concurrent update thread roots 12.532ms [2025-11-28T00:42:27.856Z] [35.518s][info][gc] GC(234) Verify After Updating References, Level 4 (23 reachable, 42 marked) [2025-11-28T00:42:27.856Z] [35.519s][info][gc] GC(234) Pause Final Update Refs 17.255ms [2025-11-28T00:42:27.856Z] [35.520s][info][gc] GC(234) Concurrent cleanup (Young) 898M->898M(1024M) 0.111ms [2025-11-28T00:42:27.856Z] Allocated: 723 Mb [2025-11-28T00:42:27.856Z] Allocated: 665 Mb [2025-11-28T00:42:27.856Z] Allocated: 676 Mb [2025-11-28T00:42:27.856Z] [35.534s][info][gc] GC(234) Concurrent reset after collect (Young) 8.689ms [2025-11-28T00:42:27.856Z] [35.535s][info][gc] Trigger (Young): Free (34816K) is below minimum threshold (102M) [2025-11-28T00:42:27.856Z] [35.539s][info][gc] GC(235) Concurrent reset (Young) 0.481ms [2025-11-28T00:42:27.856Z] Allocated: 702 Mb [2025-11-28T00:42:27.856Z] [35.556s][info][gc] GC(235) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [35.557s][info][gc] GC(235) Pause Init Mark (Young) 5.970ms [2025-11-28T00:42:27.856Z] [35.557s][info][gc] GC(235) Concurrent remembered set scanning 0.344ms [2025-11-28T00:42:27.856Z] Allocated: 694 Mb [2025-11-28T00:42:27.856Z] Allocated: 696 Mb [2025-11-28T00:42:27.856Z] [35.558s][info][gc] Failed to allocate Shared, 18814K [2025-11-28T00:42:27.856Z] [35.558s][info][gc] GC(235) Concurrent marking roots 0.466ms [2025-11-28T00:42:27.856Z] [35.558s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.856Z] [35.619s][info][gc] GC(236) Verify Before Full GC, Level 4 (34640 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [35.620s][info][gc] GC(236) Cancel marking: Young [2025-11-28T00:42:27.856Z] [35.620s][info][gc] GC(236) Cancel marking: Old [2025-11-28T00:42:27.856Z] [35.620s][info][gc] GC(236) Cancel marking: Global [2025-11-28T00:42:27.856Z] [36.367s][info][gc] GC(236) Verify After Full GC, Level 4 (34536 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [36.368s][info][gc] GC(236) Pause Full 950M->704M(1024M) 803.383ms [2025-11-28T00:42:27.856Z] Allocated: 714 Mb [2025-11-28T00:42:27.856Z] [36.381s][info][gc] Trigger (Young): Average GC time (264.59 ms) is above the time for average allocation rate (645 MB/s) to deplete free headroom (141M) (margin of error = 1.94) [2025-11-28T00:42:27.856Z] [36.382s][info][gc] GC(237) Concurrent reset (Young) 0.534ms [2025-11-28T00:42:27.856Z] [36.421s][info][gc] GC(237) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T00:42:27.856Z] [36.422s][info][gc] GC(237) Pause Init Mark (Young) 11.036ms [2025-11-28T00:42:27.857Z] [36.422s][info][gc] GC(237) Concurrent remembered set scanning 0.224ms [2025-11-28T00:42:27.857Z] [36.426s][info][gc] GC(237) Concurrent marking roots 3.743ms [2025-11-28T00:42:27.857Z] Allocated: 721 Mb [2025-11-28T00:42:27.857Z] [36.427s][info][gc] GC(237) Concurrent marking (Young) 0.651ms [2025-11-28T00:42:27.857Z] [36.450s][info][gc] GC(237) Verify Before Evacuation, Level 4 (27 reachable, 7 marked) [2025-11-28T00:42:27.857Z] [36.450s][info][gc] GC(237) Pause Final Mark (Young) 12.517ms [2025-11-28T00:42:27.857Z] Allocated: 705 Mb [2025-11-28T00:42:27.857Z] [36.452s][info][gc] GC(237) Concurrent thread roots 2.389ms [2025-11-28T00:42:27.857Z] [36.453s][info][gc] GC(237) Concurrent weak references (Young) 0.231ms [2025-11-28T00:42:27.857Z] Allocated: 702 Mb [2025-11-28T00:42:27.857Z] [36.477s][info][gc] GC(237) Concurrent weak roots (Young) 24.470ms [2025-11-28T00:42:27.857Z] [36.477s][info][gc] GC(237) Concurrent cleanup (Young) 820M->820M(1024M) 0.114ms [2025-11-28T00:42:27.857Z] [36.479s][info][gc] GC(237) Concurrent strong roots 1.207ms [2025-11-28T00:42:27.857Z] [36.479s][info][gc] GC(237) Concurrent evacuation 0.236ms [2025-11-28T00:42:27.857Z] [36.480s][info][gc] GC(237) Concurrent Init Update Refs (Young) 0.401ms [2025-11-28T00:42:27.857Z] [36.490s][info][gc] GC(237) Verify Before Updating References, Level 4 (28 reachable, 23 marked) [2025-11-28T00:42:27.857Z] [36.491s][info][gc] GC(237) Pause Init Update Refs 10.289ms [2025-11-28T00:42:27.857Z] Allocated: 653 Mb [2025-11-28T00:42:27.857Z] [36.493s][info][gc] GC(237) Concurrent update references 2.429ms [2025-11-28T00:42:27.857Z] Allocated: 691 Mb [2025-11-28T00:42:27.857Z] [36.508s][info][gc] GC(237) Concurrent update thread roots 11.362ms [2025-11-28T00:42:27.857Z] [36.518s][info][gc] GC(237) Verify After Updating References, Level 4 (29 reachable, 27 marked) [2025-11-28T00:42:27.857Z] [36.519s][info][gc] GC(237) Pause Final Update Refs 4.330ms [2025-11-28T00:42:27.857Z] [36.519s][info][gc] GC(237) Concurrent cleanup (Young) 871M->871M(1024M) 0.089ms [2025-11-28T00:42:27.857Z] Allocated: 702 Mb [2025-11-28T00:42:27.857Z] Allocated: 707 Mb [2025-11-28T00:42:27.857Z] Allocated: 659 Mb [2025-11-28T00:42:27.857Z] [36.530s][info][gc] GC(237) Concurrent reset after collect (Young) 10.871ms [2025-11-28T00:42:27.857Z] [36.531s][info][gc] Trigger (Young): Free (49664K) is below minimum threshold (102M) [2025-11-28T00:42:27.857Z] [36.532s][info][gc] GC(238) Concurrent reset (Young) 0.462ms [2025-11-28T00:42:27.857Z] [36.541s][info][gc] GC(238) Verify Before Mark, Level 4 (31 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [36.541s][info][gc] GC(238) Pause Init Mark (Young) 5.084ms [2025-11-28T00:42:27.857Z] Allocated: 688 Mb [2025-11-28T00:42:27.857Z] [36.541s][info][gc] Failed to allocate Shared, 48958K [2025-11-28T00:42:27.857Z] [36.544s][info][gc] GC(238) Concurrent remembered set scanning 2.762ms [2025-11-28T00:42:27.857Z] [36.545s][info][gc] GC(238) Concurrent marking roots 1.232ms [2025-11-28T00:42:27.857Z] [36.545s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.857Z] [36.569s][info][gc] GC(239) Verify Before Full GC, Level 4 (34644 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [36.569s][info][gc] GC(239) Cancel marking: Young [2025-11-28T00:42:27.857Z] [36.569s][info][gc] GC(239) Cancel marking: Old [2025-11-28T00:42:27.857Z] [36.569s][info][gc] GC(239) Cancel marking: Global [2025-11-28T00:42:27.857Z] [37.097s][info][gc] GC(239) Verify After Full GC, Level 4 (34540 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [37.098s][info][gc] GC(239) Pause Full 923M->697M(1024M) 552.121ms [2025-11-28T00:42:27.857Z] [37.099s][info][gc] Trigger (Young): Average GC time (231.56 ms) is above the time for average allocation rate (855 MB/s) to deplete free headroom (174M) (margin of error = 1.95) [2025-11-28T00:42:27.857Z] [37.100s][info][gc] GC(240) Concurrent reset (Young) 0.572ms [2025-11-28T00:42:27.857Z] [37.122s][info][gc] GC(240) Verify Before Mark, Level 4 (31 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [37.123s][info][gc] GC(240) Pause Init Mark (Young) 12.022ms [2025-11-28T00:42:27.857Z] [37.124s][info][gc] GC(240) Concurrent remembered set scanning 0.776ms [2025-11-28T00:42:27.857Z] Allocated: 736 Mb [2025-11-28T00:42:27.857Z] [37.127s][info][gc] GC(240) Concurrent marking roots 3.523ms [2025-11-28T00:42:27.857Z] Allocated: 711 Mb [2025-11-28T00:42:27.857Z] Allocated: 696 Mb [2025-11-28T00:42:27.857Z] [37.134s][info][gc] GC(240) Concurrent marking (Young) 6.547ms [2025-11-28T00:42:27.857Z] [37.153s][info][gc] GC(240) Verify After Mark, Level 4 (31 reachable, 17 marked) [2025-11-28T00:42:27.857Z] [37.153s][info][gc] GC(240) Pause Final Mark (Young) 11.708ms [2025-11-28T00:42:27.857Z] [37.154s][info][gc] GC(240) Concurrent weak references (Young) 0.259ms [2025-11-28T00:42:27.857Z] Allocated: 727 Mb [2025-11-28T00:42:27.857Z] [37.155s][info][gc] GC(240) Concurrent weak roots (Young) 1.241ms [2025-11-28T00:42:27.857Z] [37.156s][info][gc] GC(240) Concurrent cleanup (Young) 844M->844M(1024M) 0.077ms [2025-11-28T00:42:27.857Z] Allocated: 739 Mb [2025-11-28T00:42:27.857Z] [37.170s][info][gc] GC(240) Concurrent Final Roots (Young) 13.917ms [2025-11-28T00:42:27.857Z] Allocated: 702 Mb [2025-11-28T00:42:27.857Z] [37.179s][info][gc] GC(240) Concurrent reset after collect (Young) 9.127ms [2025-11-28T00:42:27.857Z] Allocated: 698 Mb [2025-11-28T00:42:27.857Z] [37.182s][info][gc] Trigger (Young): Free (26880K) is below minimum threshold (102M) [2025-11-28T00:42:27.857Z] [37.182s][info][gc] GC(241) Concurrent reset (Young) 0.535ms [2025-11-28T00:42:27.857Z] [37.202s][info][gc] GC(241) Verify Before Mark, Level 4 (30 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [37.202s][info][gc] GC(241) Pause Init Mark (Young) 5.128ms [2025-11-28T00:42:27.857Z] Allocated: 758 Mb [2025-11-28T00:42:27.857Z] Allocated: 697 Mb [2025-11-28T00:42:27.857Z] [37.204s][info][gc] Failed to allocate Shared, 29961K [2025-11-28T00:42:27.857Z] [37.209s][info][gc] GC(241) Concurrent remembered set scanning 2.355ms [2025-11-28T00:42:27.857Z] [37.212s][info][gc] GC(241) Concurrent marking roots 2.920ms [2025-11-28T00:42:27.857Z] [37.212s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.857Z] [37.245s][info][gc] GC(242) Verify Before Full GC, Level 4 (34641 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [37.246s][info][gc] GC(242) Cancel marking: Young [2025-11-28T00:42:27.857Z] [37.246s][info][gc] GC(242) Cancel marking: Old [2025-11-28T00:42:27.857Z] [37.246s][info][gc] GC(242) Cancel marking: Global [2025-11-28T00:42:27.857Z] [37.743s][info][gc] GC(242) Verify After Full GC, Level 4 (34537 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [37.743s][info][gc] GC(242) Pause Full 953M->704M(1024M) 530.646ms [2025-11-28T00:42:27.857Z] Allocated: 726 Mb [2025-11-28T00:42:27.857Z] Allocated: 743 Mb [2025-11-28T00:42:27.857Z] [37.777s][info][gc] Trigger (Young): Average GC time (203.73 ms) is above the time for average allocation rate (512 MB/s) to deplete free headroom (79411K) (margin of error = 1.97) [2025-11-28T00:42:27.857Z] [37.781s][info][gc] GC(243) Concurrent reset (Young) 2.750ms [2025-11-28T00:42:27.857Z] [37.802s][info][gc] GC(243) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [37.803s][info][gc] GC(243) Pause Init Mark (Young) 8.093ms [2025-11-28T00:42:27.857Z] Allocated: 746 Mb [2025-11-28T00:42:27.857Z] [37.805s][info][gc] GC(243) Concurrent remembered set scanning 0.860ms [2025-11-28T00:42:27.857Z] [37.807s][info][gc] GC(243) Concurrent marking roots 0.966ms [2025-11-28T00:42:27.857Z] [37.812s][info][gc] GC(243) Concurrent marking (Young) 1.759ms [2025-11-28T00:42:27.857Z] [37.825s][info][gc] GC(243) Verify After Mark, Level 4 (28 reachable, 35 marked) [2025-11-28T00:42:27.857Z] [37.825s][info][gc] GC(243) Pause Final Mark (Young) 12.741ms [2025-11-28T00:42:27.857Z] [37.826s][info][gc] GC(243) Concurrent weak references (Young) 0.393ms [2025-11-28T00:42:27.857Z] [37.830s][info][gc] GC(243) Concurrent weak roots (Young) 4.554ms [2025-11-28T00:42:27.857Z] [37.831s][info][gc] GC(243) Concurrent cleanup (Young) 753M->753M(1024M) 0.691ms [2025-11-28T00:42:27.857Z] [37.835s][info][gc] GC(243) Concurrent Final Roots (Young) 3.801ms [2025-11-28T00:42:27.857Z] [37.855s][info][gc] GC(243) Concurrent reset after collect (Young) 19.597ms [2025-11-28T00:42:27.857Z] [37.860s][info][gc] Trigger (Young): Average GC time (168.67 ms) is above the time for average allocation rate (2725 MB/s) to deplete free headroom (166M) (margin of error = 1.95) [2025-11-28T00:42:27.857Z] [37.861s][info][gc] GC(244) Concurrent reset (Young) 0.754ms [2025-11-28T00:42:27.857Z] [37.870s][info][gc] GC(244) Verify Before Mark, Level 4 (471 reachable, 0 marked) [2025-11-28T00:42:27.857Z] [37.871s][info][gc] GC(244) Pause Init Mark (Young) 5.087ms [2025-11-28T00:42:27.857Z] [37.887s][info][gc] GC(244) Concurrent remembered set scanning 7.964ms [2025-11-28T00:42:27.857Z] [37.896s][info][gc] GC(244) Concurrent marking roots 8.372ms [2025-11-28T00:42:27.857Z] [37.901s][info][gc] GC(244) Concurrent marking (Young) 4.148ms [2025-11-28T00:42:27.857Z] [37.926s][info][gc] GC(244) Verify Before Evacuation, Level 4 (484 reachable, 199 marked) [2025-11-28T00:42:27.857Z] [37.926s][info][gc] GC(244) Pause Final Mark (Young) 25.043ms [2025-11-28T00:42:27.857Z] [37.936s][info][gc] GC(244) Concurrent thread roots 9.066ms [2025-11-28T00:42:27.857Z] [37.936s][info][gc] GC(244) Concurrent weak references (Young) 0.322ms [2025-11-28T00:42:27.857Z] [37.945s][info][gc] GC(244) Concurrent weak roots (Young) 9.011ms [2025-11-28T00:42:27.857Z] [37.946s][info][gc] GC(244) Concurrent cleanup (Young) 754M->754M(1024M) 0.130ms [2025-11-28T00:42:27.857Z] [37.949s][info][gc] GC(244) Concurrent strong roots 1.769ms [2025-11-28T00:42:27.857Z] [37.956s][info][gc] GC(244) Concurrent evacuation 5.273ms [2025-11-28T00:42:27.857Z] [37.968s][info][gc] GC(244) Concurrent Init Update Refs (Young) 11.786ms [2025-11-28T00:42:27.857Z] [37.979s][info][gc] GC(244) Verify Before Updating References, Level 4 (641 reachable, 260 marked) [2025-11-28T00:42:27.857Z] [37.980s][info][gc] GC(244) Pause Init Update Refs 11.444ms [2025-11-28T00:42:27.857Z] [37.998s][info][gc] GC(244) Concurrent update references 17.505ms [2025-11-28T00:42:27.857Z] [38.005s][info][gc] GC(244) Concurrent update thread roots 6.031ms [2025-11-28T00:42:27.857Z] [38.029s][info][gc] GC(244) Verify After Updating References, Level 4 (654 reachable, 444 marked) [2025-11-28T00:42:27.857Z] [38.029s][info][gc] GC(244) Pause Final Update Refs 17.761ms [2025-11-28T00:42:27.857Z] [38.030s][info][gc] GC(244) Concurrent cleanup (Young) 753M->753M(1024M) 0.163ms [2025-11-28T00:42:27.857Z] [38.050s][info][gc] GC(244) Concurrent reset after collect (Young) 20.009ms [2025-11-28T00:42:27.857Z] STDERR: [2025-11-28T00:42:27.857Z] STATUS:Passed. [2025-11-28T00:42:27.857Z] rerun: [2025-11-28T00:42:27.857Z] cd /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/scratch/0 && \ [2025-11-28T00:42:27.857Z] DISPLAY=:0 \ [2025-11-28T00:42:27.857Z] HOME=/home/jenkins \ [2025-11-28T00:42:27.857Z] LANG=C.UTF-8 \ [2025-11-28T00:42:27.857Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-11-28T00:42:27.857Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-11-28T00:42:27.857Z] /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image/bin/java \ [2025-11-28T00:42:27.857Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-11-28T00:42:27.857Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-11-28T00:42:27.857Z] -Dtest.compiler.opts= \ [2025-11-28T00:42:27.857Z] -Dtest.java.opts= \ [2025-11-28T00:42:27.857Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image \ [2025-11-28T00:42:27.857Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image \ [2025-11-28T00:42:27.857Z] -Dtest.timeout.factor=12.0 \ [2025-11-28T00:42:27.857Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-11-28T00:42:27.857Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-11-28T00:42:27.857Z] -Dtest.name=gc/TestAllocHumongousFragment.java#generational \ [2025-11-28T00:42:27.857Z] -Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2025-11-28T00:42:27.857Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java \ [2025-11-28T00:42:27.857Z] -Dtest.main.class=TestAllocHumongousFragment \ [2025-11-28T00:42:27.857Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc \ [2025-11-28T00:42:27.857Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-11-28T00:42:27.857Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d \ [2025-11-28T00:42:27.857Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T00:42:27.857Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T00:42:27.857Z] -ea \ [2025-11-28T00:42:27.857Z] -esa \ [2025-11-28T00:42:27.857Z] -Xmx512m \ [2025-11-28T00:42:27.857Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-11-28T00:42:27.857Z] -Xmx1g \ [2025-11-28T00:42:27.857Z] -Xms1g \ [2025-11-28T00:42:27.857Z] -Xlog:gc \ [2025-11-28T00:42:27.857Z] -XX:+UnlockDiagnosticVMOptions \ [2025-11-28T00:42:27.857Z] -XX:+UnlockExperimentalVMOptions \ [2025-11-28T00:42:27.857Z] -XX:ShenandoahTargetNumRegions=2048 \ [2025-11-28T00:42:27.857Z] -XX:+UseShenandoahGC \ [2025-11-28T00:42:27.857Z] -XX:ShenandoahGCHeuristics=adaptive \ [2025-11-28T00:42:27.857Z] -XX:ShenandoahGCMode=generational \ [2025-11-28T00:42:27.857Z] -XX:+ShenandoahVerify \ [2025-11-28T00:42:27.857Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/gc/TestAllocHumongousFragment_generational.d/main.0.jta [2025-11-28T00:42:27.857Z] [2025-11-28T00:42:27.857Z] ACTION: build -- Passed. All files up to date [2025-11-28T00:42:27.857Z] REASON: Named class compiled on demand [2025-11-28T00:42:27.857Z] TIME: 0.001 seconds [2025-11-28T00:42:27.857Z] messages: [2025-11-28T00:42:27.857Z] command: build TestAllocHumongousFragment [2025-11-28T00:42:27.857Z] reason: Named class compiled on demand [2025-11-28T00:42:27.857Z] started: Fri Nov 28 00:41:40.183 UTC 2025 [2025-11-28T00:42:27.857Z] finished: Fri Nov 28 00:41:40.184 UTC 2025 [2025-11-28T00:42:27.857Z] elapsed time (seconds): 0.001 [2025-11-28T00:42:27.857Z] [2025-11-28T00:42:27.857Z] ACTION: main -- Failed. Unexpected exit from test [exit code: 134] [2025-11-28T00:42:27.857Z] REASON: User specified action: run main/othervm -Xmx1g -Xms1g -Xlog:gc -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:ShenandoahTargetNumRegions=2048 -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=adaptive -XX:ShenandoahGCMode=generational TestAllocHumongousFragment [2025-11-28T00:42:27.857Z] TIME: 36.583 seconds [2025-11-28T00:42:27.857Z] messages: [2025-11-28T00:42:27.857Z] command: main -Xmx1g -Xms1g -Xlog:gc -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:ShenandoahTargetNumRegions=2048 -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=adaptive -XX:ShenandoahGCMode=generational TestAllocHumongousFragment [2025-11-28T00:42:27.857Z] reason: User specified action: run main/othervm -Xmx1g -Xms1g -Xlog:gc -XX:+UnlockDiagnosticVMOptions -XX:+UnlockExperimentalVMOptions -XX:ShenandoahTargetNumRegions=2048 -XX:+UseShenandoahGC -XX:ShenandoahGCHeuristics=adaptive -XX:ShenandoahGCMode=generational TestAllocHumongousFragment [2025-11-28T00:42:27.857Z] started: Fri Nov 28 00:41:40.184 UTC 2025 [2025-11-28T00:42:27.857Z] Mode: othervm [/othervm specified] [2025-11-28T00:42:27.857Z] Process id: 795230 [2025-11-28T00:42:27.857Z] finished: Fri Nov 28 00:42:16.767 UTC 2025 [2025-11-28T00:42:27.857Z] elapsed time (seconds): 36.583 [2025-11-28T00:42:27.857Z] configuration: [2025-11-28T00:42:27.857Z] STDOUT: [2025-11-28T00:42:27.857Z] [0.003s][info][gc] Min heap equals to max heap, disabling ShenandoahUncommit [2025-11-28T00:42:27.857Z] [0.008s][info][gc] Heuristics ergonomically sets -XX:GCTimeRatio=70 [2025-11-28T00:42:27.857Z] [0.009s][info][gc] Heuristics ergonomically sets -XX:+ExplicitGCInvokesConcurrent [2025-11-28T00:42:27.857Z] [0.009s][info][gc] Heuristics ergonomically sets -XX:+ShenandoahImplicitGCInvokesConcurrent [2025-11-28T00:42:27.857Z] [0.009s][info][gc] Heuristics ergonomically sets -XX:+UseCondCardMark [2025-11-28T00:42:27.857Z] [0.009s][info][gc] Using Shenandoah [2025-11-28T00:42:27.857Z] For random generator using seed: 3723532219 [2025-11-28T00:42:27.857Z] To re-run test with same seed value please add "-Djdk.test.lib.random.seed=3723532219" to command line. [2025-11-28T00:42:27.857Z] Allocated: 51 Mb [2025-11-28T00:42:27.857Z] Allocated: 106 Mb [2025-11-28T00:42:27.857Z] Allocated: 122 Mb [2025-11-28T00:42:27.857Z] Allocated: 158 Mb [2025-11-28T00:42:27.857Z] Allocated: 216 Mb [2025-11-28T00:42:27.857Z] Allocated: 246 Mb [2025-11-28T00:42:27.857Z] [1.294s][info][gc] Trigger (Young): Learning 1 of 5. Free (698M) is below initial threshold (716M) [2025-11-28T00:42:27.857Z] [1.296s][info][gc] GC(0) Concurrent reset (Young) 1.554ms [2025-11-28T00:42:27.857Z] [1.328s][info][gc] GC(0) Pause Init Mark (Young) 0.179ms [2025-11-28T00:42:27.857Z] [1.328s][info][gc] GC(0) Concurrent remembered set scanning 0.200ms [2025-11-28T00:42:27.857Z] Allocated: 269 Mb [2025-11-28T00:42:27.857Z] [1.334s][info][gc] GC(0) Concurrent marking roots 5.231ms [2025-11-28T00:42:27.857Z] [1.407s][info][gc] GC(0) Concurrent marking (Young) 73.211ms [2025-11-28T00:42:27.857Z] [1.409s][info][gc] GC(0) Pause Final Mark (Young) 1.269ms [2025-11-28T00:42:27.857Z] Allocated: 314 Mb [2025-11-28T00:42:27.857Z] [1.410s][info][gc] GC(0) Concurrent thread roots 1.101ms [2025-11-28T00:42:27.857Z] [1.410s][info][gc] GC(0) Concurrent weak references (Young) 0.266ms [2025-11-28T00:42:27.857Z] [1.431s][info][gc] GC(0) Concurrent weak roots (Young) 20.093ms [2025-11-28T00:42:27.857Z] [1.431s][info][gc] GC(0) Concurrent cleanup (Young) 334M->334M(1024M) 0.075ms [2025-11-28T00:42:27.857Z] [1.432s][info][gc] GC(0) Concurrent strong roots 1.121ms [2025-11-28T00:42:27.857Z] [1.434s][info][gc] GC(0) Concurrent evacuation 1.926ms [2025-11-28T00:42:27.857Z] Allocated: 328 Mb [2025-11-28T00:42:27.857Z] [1.439s][info][gc] GC(0) Concurrent Init Update Refs (Young) 4.933ms [2025-11-28T00:42:27.857Z] [1.451s][info][gc] GC(0) Concurrent update references 12.486ms [2025-11-28T00:42:27.857Z] Allocated: 377 Mb [2025-11-28T00:42:27.857Z] [1.509s][info][gc] GC(0) Concurrent update thread roots 57.408ms [2025-11-28T00:42:27.857Z] [1.509s][info][gc] GC(0) Pause Final Update Refs 0.321ms [2025-11-28T00:42:27.857Z] [1.510s][info][gc] GC(0) Concurrent cleanup (Young) 382M->382M(1024M) 0.109ms [2025-11-28T00:42:27.857Z] Allocated: 380 Mb [2025-11-28T00:42:27.857Z] [1.526s][info][gc] GC(0) Concurrent reset after collect (Young) 16.141ms [2025-11-28T00:42:27.857Z] [1.531s][info][gc] Trigger (Young): Learning 2 of 5. Free (522M) is below initial threshold (716M) [2025-11-28T00:42:27.857Z] [1.533s][info][gc] GC(1) Concurrent reset (Young) 1.396ms [2025-11-28T00:42:27.857Z] [1.582s][info][gc] GC(1) Pause Init Mark (Young) 0.205ms [2025-11-28T00:42:27.857Z] Allocated: 444 Mb [2025-11-28T00:42:27.857Z] [1.584s][info][gc] GC(1) Concurrent remembered set scanning 0.282ms [2025-11-28T00:42:27.857Z] [1.588s][info][gc] GC(1) Concurrent marking roots 3.651ms [2025-11-28T00:42:27.857Z] [1.630s][info][gc] GC(1) Concurrent marking (Young) 39.014ms [2025-11-28T00:42:27.857Z] [1.631s][info][gc] GC(1) Pause Final Mark (Young) 1.287ms [2025-11-28T00:42:27.857Z] Allocated: 485 Mb [2025-11-28T00:42:27.857Z] [1.634s][info][gc] GC(1) Concurrent thread roots 1.493ms [2025-11-28T00:42:27.857Z] [1.635s][info][gc] GC(1) Concurrent weak references (Young) 0.188ms [2025-11-28T00:42:27.858Z] [1.700s][info][gc] GC(1) Concurrent weak roots (Young) 65.184ms [2025-11-28T00:42:27.858Z] [1.700s][info][gc] GC(1) Concurrent cleanup (Young) 555M->555M(1024M) 0.080ms [2025-11-28T00:42:27.858Z] Allocated: 549 Mb [2025-11-28T00:42:27.858Z] [1.706s][info][gc] GC(1) Concurrent strong roots 5.361ms [2025-11-28T00:42:27.858Z] [1.710s][info][gc] GC(1) Concurrent evacuation 3.644ms [2025-11-28T00:42:27.858Z] [1.727s][info][gc] GC(1) Concurrent Init Update Refs (Young) 14.684ms [2025-11-28T00:42:27.858Z] Allocated: 572 Mb [2025-11-28T00:42:27.858Z] [1.741s][info][gc] GC(1) Concurrent update references 12.999ms [2025-11-28T00:42:27.858Z] Allocated: 610 Mb [2025-11-28T00:42:27.858Z] [1.771s][info][gc] GC(1) Concurrent update thread roots 30.176ms [2025-11-28T00:42:27.858Z] [1.786s][info][gc] GC(1) Pause Final Update Refs 0.366ms [2025-11-28T00:42:27.858Z] [1.787s][info][gc] GC(1) Concurrent cleanup (Young) 633M->633M(1024M) 0.161ms [2025-11-28T00:42:27.858Z] Allocated: 626 Mb [2025-11-28T00:42:27.858Z] [1.806s][info][gc] GC(1) Concurrent reset after collect (Young) 19.056ms [2025-11-28T00:42:27.858Z] [1.807s][info][gc] Trigger (Young): Learning 3 of 5. Free (291M) is below initial threshold (716M) [2025-11-28T00:42:27.858Z] [1.808s][info][gc] GC(2) Concurrent reset (Young) 0.696ms [2025-11-28T00:42:27.858Z] [1.842s][info][gc] GC(2) Pause Init Mark (Young) 0.209ms [2025-11-28T00:42:27.858Z] Allocated: 674 Mb [2025-11-28T00:42:27.858Z] [1.848s][info][gc] GC(2) Concurrent remembered set scanning 5.656ms [2025-11-28T00:42:27.858Z] [1.849s][info][gc] GC(2) Concurrent marking roots 0.703ms [2025-11-28T00:42:27.858Z] Allocated: 733 Mb [2025-11-28T00:42:27.858Z] Allocated: 681 Mb [2025-11-28T00:42:27.858Z] [1.960s][info][gc] GC(2) Concurrent marking (Young) 111.615ms [2025-11-28T00:42:27.858Z] [1.961s][info][gc] GC(2) Pause Final Mark (Young) 0.932ms [2025-11-28T00:42:27.858Z] [1.968s][info][gc] GC(2) Concurrent thread roots 5.939ms [2025-11-28T00:42:27.858Z] [1.968s][info][gc] GC(2) Concurrent weak references (Young) 0.596ms [2025-11-28T00:42:27.858Z] Allocated: 701 Mb [2025-11-28T00:42:27.858Z] [1.991s][info][gc] GC(2) Concurrent weak roots (Young) 22.346ms [2025-11-28T00:42:27.858Z] [1.991s][info][gc] GC(2) Concurrent cleanup (Young) 787M->787M(1024M) 0.085ms [2025-11-28T00:42:27.858Z] [1.992s][info][gc] GC(2) Concurrent strong roots 1.148ms [2025-11-28T00:42:27.858Z] Allocated: 655 Mb [2025-11-28T00:42:27.858Z] [1.997s][info][gc] GC(2) Concurrent evacuation 4.758ms [2025-11-28T00:42:27.858Z] Allocated: 661 Mb [2025-11-28T00:42:27.858Z] [2.001s][info][gc] GC(2) Concurrent Init Update Refs (Young) 4.052ms [2025-11-28T00:42:27.858Z] [2.014s][info][gc] GC(2) Concurrent update references 13.025ms [2025-11-28T00:42:27.858Z] [2.051s][info][gc] GC(2) Concurrent update thread roots 36.461ms [2025-11-28T00:42:27.858Z] [2.052s][info][gc] GC(2) Pause Final Update Refs 0.381ms [2025-11-28T00:42:27.858Z] [2.053s][info][gc] GC(2) Concurrent cleanup (Young) 846M->846M(1024M) 0.245ms [2025-11-28T00:42:27.858Z] Allocated: 711 Mb [2025-11-28T00:42:27.858Z] [2.083s][info][gc] GC(2) Concurrent reset after collect (Young) 29.652ms [2025-11-28T00:42:27.858Z] [2.084s][info][gc] Trigger (Young): Free (63744K) is below minimum threshold (102M) [2025-11-28T00:42:27.858Z] [2.085s][info][gc] GC(3) Concurrent reset (Young) 0.797ms [2025-11-28T00:42:27.858Z] [2.169s][info][gc] GC(3) Pause Init Mark (Young) 0.221ms [2025-11-28T00:42:27.858Z] Allocated: 733 Mb [2025-11-28T00:42:27.858Z] [2.178s][info][gc] GC(3) Concurrent remembered set scanning 7.944ms [2025-11-28T00:42:27.858Z] [2.178s][info][gc] GC(3) Concurrent marking roots 0.612ms [2025-11-28T00:42:27.858Z] Allocated: 725 Mb [2025-11-28T00:42:27.858Z] [2.243s][info][gc] Failed to allocate Shared, 26684K [2025-11-28T00:42:27.858Z] [2.243s][info][gc] GC(3) Concurrent marking (Young) 64.942ms [2025-11-28T00:42:27.858Z] [2.245s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.858Z] [2.245s][info][gc] GC(4) Cancel marking: Young [2025-11-28T00:42:27.858Z] [2.245s][info][gc] GC(4) Cancel marking: Old [2025-11-28T00:42:27.858Z] [2.245s][info][gc] GC(4) Cancel marking: Global [2025-11-28T00:42:27.858Z] [2.799s][info][gc] GC(4) Pause Full 959M->675M(1024M) 554.505ms [2025-11-28T00:42:27.858Z] [2.802s][info][gc] Trigger (Young): Learning 4 of 5. Free (269M) is below initial threshold (716M) [2025-11-28T00:42:27.858Z] [2.803s][info][gc] GC(5) Concurrent reset (Young) 0.689ms [2025-11-28T00:42:27.858Z] [2.812s][info][gc] GC(5) Pause Init Mark (Young) 0.219ms [2025-11-28T00:42:27.858Z] Allocated: 695 Mb [2025-11-28T00:42:27.858Z] [2.815s][info][gc] GC(5) Concurrent remembered set scanning 2.785ms [2025-11-28T00:42:27.858Z] [2.816s][info][gc] GC(5) Concurrent marking roots 0.720ms [2025-11-28T00:42:27.858Z] Allocated: 715 Mb [2025-11-28T00:42:27.858Z] Allocated: 694 Mb [2025-11-28T00:42:27.858Z] [2.827s][info][gc] GC(5) Concurrent marking (Young) 10.941ms [2025-11-28T00:42:27.858Z] [2.828s][info][gc] GC(5) Pause Final Mark (Young) 0.927ms [2025-11-28T00:42:27.858Z] [2.829s][info][gc] GC(5) Concurrent weak references (Young) 0.245ms [2025-11-28T00:42:27.858Z] [2.830s][info][gc] GC(5) Concurrent weak roots (Young) 0.688ms [2025-11-28T00:42:27.858Z] [2.830s][info][gc] GC(5) Concurrent cleanup (Young) 738M->738M(1024M) 0.143ms [2025-11-28T00:42:27.858Z] [2.832s][info][gc] GC(5) Concurrent Final Roots (Young) 2.749ms [2025-11-28T00:42:27.858Z] [2.859s][info][gc] GC(5) Concurrent reset after collect (Young) 26.337ms [2025-11-28T00:42:27.858Z] [2.862s][info][gc] Trigger (Young): Learning 5 of 5. Free (191M) is below initial threshold (716M) [2025-11-28T00:42:27.858Z] [2.863s][info][gc] GC(6) Concurrent reset (Young) 0.576ms [2025-11-28T00:42:27.858Z] [2.866s][info][gc] GC(6) Pause Init Mark (Young) 0.460ms [2025-11-28T00:42:27.858Z] [2.866s][info][gc] GC(6) Concurrent remembered set scanning 0.548ms [2025-11-28T00:42:27.858Z] Allocated: 736 Mb [2025-11-28T00:42:27.858Z] Allocated: 662 Mb [2025-11-28T00:42:27.858Z] [2.870s][info][gc] GC(6) Concurrent marking roots 3.670ms [2025-11-28T00:42:27.858Z] [2.882s][info][gc] GC(6) Concurrent marking (Young) 11.541ms [2025-11-28T00:42:27.858Z] [2.885s][info][gc] GC(6) Pause Final Mark (Young) 1.454ms [2025-11-28T00:42:27.858Z] [2.887s][info][gc] GC(6) Concurrent thread roots 0.391ms [2025-11-28T00:42:27.858Z] [2.887s][info][gc] GC(6) Concurrent weak references (Young) 0.249ms [2025-11-28T00:42:27.858Z] Allocated: 702 Mb [2025-11-28T00:42:27.858Z] Allocated: 700 Mb [2025-11-28T00:42:27.858Z] [2.899s][info][gc] GC(6) Concurrent weak roots (Young) 11.610ms [2025-11-28T00:42:27.858Z] [2.899s][info][gc] GC(6) Concurrent cleanup (Young) 827M->827M(1024M) 0.202ms [2025-11-28T00:42:27.858Z] [2.901s][info][gc] GC(6) Concurrent strong roots 1.257ms [2025-11-28T00:42:27.858Z] Allocated: 713 Mb [2025-11-28T00:42:27.858Z] [2.901s][info][gc] GC(6) Concurrent evacuation 0.539ms [2025-11-28T00:42:27.858Z] [2.903s][info][gc] GC(6) Concurrent Init Update Refs (Young) 1.154ms [2025-11-28T00:42:27.858Z] Allocated: 677 Mb [2025-11-28T00:42:27.858Z] [2.910s][info][gc] GC(6) Concurrent update references 6.623ms [2025-11-28T00:42:27.858Z] [2.916s][info][gc] GC(6) Concurrent update thread roots 6.257ms [2025-11-28T00:42:27.858Z] [2.917s][info][gc] GC(6) Pause Final Update Refs 0.402ms [2025-11-28T00:42:27.858Z] [2.917s][info][gc] GC(6) Concurrent cleanup (Young) 878M->878M(1024M) 0.131ms [2025-11-28T00:42:27.858Z] Allocated: 723 Mb [2025-11-28T00:42:27.858Z] [2.935s][info][gc] GC(6) Concurrent reset after collect (Young) 17.413ms [2025-11-28T00:42:27.858Z] [2.937s][info][gc] Trigger (Young): Free (63488K) is below minimum threshold (102M) [2025-11-28T00:42:27.858Z] Allocated: 703 Mb [2025-11-28T00:42:27.858Z] [2.938s][info][gc] GC(7) Concurrent reset (Young) 0.548ms [2025-11-28T00:42:27.858Z] [2.951s][info][gc] GC(7) Pause Init Mark (Young) 0.232ms [2025-11-28T00:42:27.858Z] [2.954s][info][gc] GC(7) Concurrent remembered set scanning 1.774ms [2025-11-28T00:42:27.858Z] Allocated: 708 Mb [2025-11-28T00:42:27.858Z] [2.955s][info][gc] GC(7) Concurrent marking roots 1.153ms [2025-11-28T00:42:27.858Z] Allocated: 689 Mb [2025-11-28T00:42:27.858Z] [2.956s][info][gc] Failed to allocate Shared, 25376K [2025-11-28T00:42:27.858Z] [2.957s][info][gc] GC(7) Concurrent marking (Young) 1.321ms [2025-11-28T00:42:27.858Z] [2.957s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.858Z] [2.958s][info][gc] GC(8) Cancel marking: Young [2025-11-28T00:42:27.858Z] [2.958s][info][gc] GC(8) Cancel marking: Old [2025-11-28T00:42:27.858Z] [2.958s][info][gc] GC(8) Cancel marking: Global [2025-11-28T00:42:27.858Z] [3.375s][info][gc] GC(8) Pause Full 955M->697M(1024M) 416.625ms [2025-11-28T00:42:27.858Z] [3.377s][info][gc] Trigger (Young): Average GC time (272.43 ms) is above the time for average allocation rate (1032 MB/s) to deplete free headroom (223M) (margin of error = 1.80) [2025-11-28T00:42:27.858Z] [3.379s][info][gc] GC(9) Concurrent reset (Young) 1.189ms [2025-11-28T00:42:27.858Z] [3.380s][info][gc] GC(9) Pause Init Mark (Young) 0.094ms [2025-11-28T00:42:27.858Z] [3.382s][info][gc] GC(9) Concurrent remembered set scanning 1.420ms [2025-11-28T00:42:27.858Z] [3.383s][info][gc] GC(9) Concurrent marking roots 1.258ms [2025-11-28T00:42:27.858Z] [3.388s][info][gc] GC(9) Concurrent marking (Young) 5.407ms [2025-11-28T00:42:27.858Z] Allocated: 714 Mb [2025-11-28T00:42:27.858Z] [3.400s][info][gc] GC(9) Pause Final Mark (Young) 10.997ms [2025-11-28T00:42:27.858Z] [3.400s][info][gc] GC(9) Concurrent weak references (Young) 0.121ms [2025-11-28T00:42:27.858Z] [3.400s][info][gc] GC(9) Concurrent weak roots (Young) 0.335ms [2025-11-28T00:42:27.858Z] [3.400s][info][gc] GC(9) Concurrent cleanup (Young) 730M->730M(1024M) 0.063ms [2025-11-28T00:42:27.858Z] Allocated: 657 Mb [2025-11-28T00:42:27.858Z] Allocated: 716 Mb [2025-11-28T00:42:27.858Z] [3.431s][info][gc] GC(9) Concurrent Final Roots (Young) 28.084ms [2025-11-28T00:42:27.858Z] [3.448s][info][gc] GC(9) Concurrent reset after collect (Young) 10.363ms [2025-11-28T00:42:27.858Z] [3.449s][info][gc] Trigger (Young): Average GC time (211.86 ms) is above the time for average allocation rate (1032 MB/s) to deplete free headroom (81971K) (margin of error = 1.81) [2025-11-28T00:42:27.858Z] [3.452s][info][gc] GC(10) Concurrent reset (Young) 0.556ms [2025-11-28T00:42:27.858Z] [3.466s][info][gc] GC(10) Pause Init Mark (Young) 0.077ms [2025-11-28T00:42:27.858Z] [3.468s][info][gc] GC(10) Concurrent remembered set scanning 0.652ms [2025-11-28T00:42:27.858Z] [3.469s][info][gc] GC(10) Concurrent marking roots 0.710ms [2025-11-28T00:42:27.858Z] Allocated: 712 Mb [2025-11-28T00:42:27.858Z] Allocated: 660 Mb [2025-11-28T00:42:27.858Z] Allocated: 675 Mb [2025-11-28T00:42:27.858Z] [3.480s][info][gc] GC(10) Concurrent marking (Young) 11.204ms [2025-11-28T00:42:27.858Z] [3.490s][info][gc] GC(10) Pause Final Mark (Young) 0.557ms [2025-11-28T00:42:27.858Z] Allocated: 684 Mb [2025-11-28T00:42:27.858Z] [3.494s][info][gc] GC(10) Concurrent weak references (Young) 4.286ms [2025-11-28T00:42:27.858Z] Allocated: 713 Mb [2025-11-28T00:42:27.858Z] Allocated: 675 Mb [2025-11-28T00:42:27.858Z] [3.508s][info][gc] GC(10) Concurrent weak roots (Young) 12.664ms [2025-11-28T00:42:27.858Z] [3.509s][info][gc] GC(10) Concurrent cleanup (Young) 842M->842M(1024M) 0.160ms [2025-11-28T00:42:27.858Z] Allocated: 729 Mb [2025-11-28T00:42:27.858Z] [3.533s][info][gc] GC(10) Concurrent Final Roots (Young) 24.091ms [2025-11-28T00:42:27.858Z] [3.542s][info][gc] GC(10) Concurrent reset after collect (Young) 9.234ms [2025-11-28T00:42:27.858Z] [3.549s][info][gc] Trigger (Young): Free (80896K) is below minimum threshold (102M) [2025-11-28T00:42:27.858Z] [3.549s][info][gc] GC(11) Concurrent reset (Young) 0.431ms [2025-11-28T00:42:27.858Z] [3.556s][info][gc] GC(11) Pause Init Mark (Young) 0.080ms [2025-11-28T00:42:27.858Z] [3.561s][info][gc] GC(11) Concurrent remembered set scanning 4.137ms [2025-11-28T00:42:27.858Z] [3.562s][info][gc] GC(11) Concurrent marking roots 0.892ms [2025-11-28T00:42:27.858Z] Allocated: 749 Mb [2025-11-28T00:42:27.858Z] Allocated: 692 Mb [2025-11-28T00:42:27.858Z] [3.567s][info][gc] GC(11) Concurrent marking (Young) 4.802ms [2025-11-28T00:42:27.858Z] [3.579s][info][gc] GC(11) Pause Final Mark (Young) 0.591ms [2025-11-28T00:42:27.858Z] [3.580s][info][gc] GC(11) Concurrent weak references (Young) 0.317ms [2025-11-28T00:42:27.858Z] Allocated: 717 Mb [2025-11-28T00:42:27.858Z] [3.580s][info][gc] Failed to allocate Shared, 60508K [2025-11-28T00:42:27.858Z] [3.583s][info][gc] GC(11) Concurrent weak roots (Young) 3.081ms [2025-11-28T00:42:27.858Z] [3.583s][info][gc] GC(11) Concurrent cleanup (Young) 793M->793M(1024M) 0.254ms [2025-11-28T00:42:27.858Z] [3.588s][info][gc] GC(11) Concurrent Final Roots (Young) 3.857ms [2025-11-28T00:42:27.858Z] [3.597s][info][gc] GC(11) Concurrent reset after collect (Young) 8.091ms [2025-11-28T00:42:27.858Z] [3.598s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.858Z] [4.186s][info][gc] GC(12) Pause Full 793M->681M(1024M) 587.441ms [2025-11-28T00:42:27.858Z] Allocated: 731 Mb [2025-11-28T00:42:27.858Z] Allocated: 712 Mb [2025-11-28T00:42:27.858Z] Allocated: 697 Mb [2025-11-28T00:42:27.858Z] [4.260s][info][gc] Trigger (Young): Average GC time (144.22 ms) is above the time for average allocation rate (624 MB/s) to deplete free headroom (61491K) (margin of error = 1.81) [2025-11-28T00:42:27.858Z] [4.266s][info][gc] GC(13) Concurrent reset (Young) 2.855ms [2025-11-28T00:42:27.858Z] [4.285s][info][gc] GC(13) Pause Init Mark (Young) 0.214ms [2025-11-28T00:42:27.858Z] Allocated: 735 Mb [2025-11-28T00:42:27.858Z] [4.287s][info][gc] GC(13) Concurrent remembered set scanning 0.993ms [2025-11-28T00:42:27.858Z] [4.288s][info][gc] GC(13) Concurrent marking roots 0.723ms [2025-11-28T00:42:27.858Z] Allocated: 724 Mb [2025-11-28T00:42:27.858Z] [4.310s][info][gc] GC(13) Concurrent marking (Young) 22.118ms [2025-11-28T00:42:27.858Z] [4.311s][info][gc] GC(13) Pause Final Mark (Young) 0.844ms [2025-11-28T00:42:27.858Z] [4.311s][info][gc] GC(13) Concurrent weak references (Young) 0.142ms [2025-11-28T00:42:27.858Z] Allocated: 681 Mb [2025-11-28T00:42:27.858Z] [4.334s][info][gc] Failed to allocate Shared, 37842K [2025-11-28T00:42:27.858Z] [4.334s][info][gc] GC(13) Concurrent weak roots (Young) 22.734ms [2025-11-28T00:42:27.858Z] [4.334s][info][gc] GC(13) Concurrent cleanup (Young) 791M->791M(1024M) 0.218ms [2025-11-28T00:42:27.858Z] [4.336s][info][gc] GC(13) Concurrent Final Roots (Young) 1.197ms [2025-11-28T00:42:27.858Z] [4.344s][info][gc] GC(13) Concurrent reset after collect (Young) 8.489ms [2025-11-28T00:42:27.858Z] [4.348s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T00:42:27.858Z] [4.728s][info][gc] GC(14) Pause Full 791M->638M(1024M) 380.263ms [2025-11-28T00:42:27.858Z] Allocated: 717 Mb [2025-11-28T00:42:27.858Z] Allocated: 744 Mb [2025-11-28T00:42:27.858Z] Allocated: 704 Mb [2025-11-28T00:42:27.858Z] Allocated: 725 Mb [2025-11-28T00:42:27.858Z] [4.802s][info][gc] Trigger (Young): Average GC time (130.81 ms) is above the time for average allocation rate (713 MB/s) to deplete free headroom (59955K) (margin of error = 1.81) [2025-11-28T00:42:27.858Z] [4.803s][info][gc] GC(15) Concurrent reset (Young) 0.466ms [2025-11-28T00:42:27.858Z] [4.824s][info][gc] GC(15) Pause Init Mark (Young) 0.219ms [2025-11-28T00:42:27.858Z] Allocated: 700 Mb [2025-11-28T00:42:27.858Z] Allocated: 655 Mb [2025-11-28T00:42:27.858Z] [4.830s][info][gc] GC(15) Concurrent remembered set scanning 5.854ms [2025-11-28T00:42:27.858Z] [4.832s][info][gc] GC(15) Concurrent marking roots 1.412ms [2025-11-28T00:42:27.858Z] # [2025-11-28T00:42:27.858Z] # A fatal error has been detected by the Java Runtime Environment: [2025-11-28T00:42:27.858Z] # [2025-11-28T00:42:27.858Z] # SIGSEGV (0xb) at pc=0x0000f625a248ceb4, pid=795230, tid=795234 [2025-11-28T00:42:27.858Z] # [2025-11-28T00:42:27.858Z] # JRE version: OpenJDK Runtime Environment Temurin-26+26-202511272133 (26.0+26) (build 26-beta+26-ea) [2025-11-28T00:42:27.858Z] # Java VM: OpenJDK 64-Bit Server VM Temurin-26+26-202511272133 (26-beta+26-ea, mixed mode, sharing, tiered, compressed oops, compressed class ptrs, shenandoah gc, linux-aarch64) [2025-11-28T00:42:27.858Z] # Problematic frame: [2025-11-28T00:42:27.858Z] # V [libjvm.so+0xe8ceb4]Allocated: 685 Mb [2025-11-28T00:42:27.858Z] Allocated: 726 Mb [2025-11-28T00:42:27.858Z] [thread 795254 also had an error] [2025-11-28T00:42:27.858Z] void ShenandoahMark::do_task<ShenandoahMarkRefsClosure<(ShenandoahGenerationType)2>, (ShenandoahGenerationType)2, (StringDedupMode)0>(Padded<BufferedOverflowTaskQueue<ShenandoahMarkTask, (MemTag)5, 131072u>, 128ul>*, ShenandoahMarkRefsClosure<(ShenandoahGenerationType)2>*, unsigned short*, StringDedup::Requests*, ShenandoahMarkTask*, unsigned int) [clone .isra.0]+0x54 [2025-11-28T00:42:27.858Z] # [2025-11-28T00:42:27.858Z] # Core dump will be written. Default location: Determined by the following: "/usr/share/apport/apport -p%p -s%s -c%c -d%d -P%P -u%u -g%g -F%F -- %E" (alternatively, falling back to /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/scratch/0/core.795230) [2025-11-28T00:42:27.858Z] # [2025-11-28T00:42:27.858Z] # An error report file with more information is saved as: [2025-11-28T00:42:27.858Z] # /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/scratch/0/hs_err_pid795230.log [2025-11-28T00:42:27.858Z] # [2025-11-28T00:42:27.858Z] # If you would like to submit a bug report, please visit: [2025-11-28T00:42:27.858Z] # https://github.com/adoptium/adoptium-support/issues [2025-11-28T00:42:27.858Z] # [2025-11-28T00:42:27.858Z] STDERR: [2025-11-28T00:42:27.858Z] rerun: [2025-11-28T00:42:27.858Z] cd /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/scratch/0 && \ [2025-11-28T00:42:27.858Z] DISPLAY=:0 \ [2025-11-28T00:42:27.858Z] HOME=/home/jenkins \ [2025-11-28T00:42:27.858Z] LANG=C.UTF-8 \ [2025-11-28T00:42:27.858Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-11-28T00:42:27.858Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-11-28T00:42:27.858Z] /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image/bin/java \ [2025-11-28T00:42:27.858Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-11-28T00:42:27.858Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-11-28T00:42:27.858Z] -Dtest.compiler.opts= \ [2025-11-28T00:42:27.858Z] -Dtest.java.opts= \ [2025-11-28T00:42:27.858Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image \ [2025-11-28T00:42:27.858Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/j2sdk-image \ [2025-11-28T00:42:27.858Z] -Dtest.timeout.factor=12.0 \ [2025-11-28T00:42:27.858Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-11-28T00:42:27.858Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-11-28T00:42:27.858Z] -Dtest.name=gc/TestAllocHumongousFragment.java#generational \ [2025-11-28T00:42:27.858Z] -Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2025-11-28T00:42:27.858Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java \ [2025-11-28T00:42:27.858Z] -Dtest.main.class=TestAllocHumongousFragment \ [2025-11-28T00:42:27.858Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc \ [2025-11-28T00:42:27.858Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-11-28T00:42:27.858Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d \ [2025-11-28T00:42:27.859Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T00:42:27.859Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/classes/0/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T00:42:27.859Z] -ea \ [2025-11-28T00:42:27.859Z] -esa \ [2025-11-28T00:42:27.859Z] -Xmx512m \ [2025-11-28T00:42:27.859Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-11-28T00:42:27.859Z] -Xmx1g \ [2025-11-28T00:42:27.859Z] -Xms1g \ [2025-11-28T00:42:27.859Z] -Xlog:gc \ [2025-11-28T00:42:27.859Z] -XX:+UnlockDiagnosticVMOptions \ [2025-11-28T00:42:27.859Z] -XX:+UnlockExperimentalVMOptions \ [2025-11-28T00:42:27.859Z] -XX:ShenandoahTargetNumRegions=2048 \ [2025-11-28T00:42:27.859Z] -XX:+UseShenandoahGC \ [2025-11-28T00:42:27.859Z] -XX:ShenandoahGCHeuristics=adaptive \ [2025-11-28T00:42:27.859Z] -XX:ShenandoahGCMode=generational \ [2025-11-28T00:42:27.859Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work/gc/TestAllocHumongousFragment_generational.d/main.1.jta [2025-11-28T00:42:27.859Z] [2025-11-28T00:42:27.859Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 134] [2025-11-28T00:42:27.859Z] -------------------------------------------------- [2025-11-28T00:56:10.152Z] Test results: passed: 368; failed: 1; skipped: 3; excluded: 7; did not meet platform requirements: 24 [2025-11-28T00:57:47.483Z] Report written to /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/report/html/report.html [2025-11-28T00:57:47.483Z] Results written to /home/jenkins/workspace/Test_openjdk26_hs_sanity.openjdk_aarch64_linux_testList_1/aqa-tests/TKG/output_17642865493326/hotspot_tier1_gc_0/work [2025-11-28T00:57:47.483Z] Error: Some tests failed or other problems occurred. [2025-11-28T00:57:47.483Z] ----------------------------------- [2025-11-28T00:57:47.483Z] hotspot_tier1_gc_0_FAILED [2025-11-28T00:57:47.483Z] ----------------------------------- [2025-11-28T00:57:47.483Z] [2025-11-28T00:57:47.483Z] TEST TEARDOWN: [2025-11-28T00:57:47.483Z] Nothing to be done for teardown. [2025-11-28T00:57:47.483Z] hotspot_tier1_gc_0 Finish Time: Fri Nov 28 00:57:33 2025 Epoch Time (ms): 1764291453954