[2025-11-28T08:05:12.484Z] Running test hotspot_gc_0 ... [2025-11-28T08:05:12.484Z] =============================================== [2025-11-28T08:05:12.484Z] hotspot_gc_0 Start Time: Fri Nov 28 08:05:12 2025 Epoch Time (ms): 1764317112198 [2025-11-28T08:05:12.484Z] variation: NoOptions [2025-11-28T08:05:12.484Z] JVM_OPTIONS: [2025-11-28T08:05:12.484Z] { \ [2025-11-28T08:05:12.484Z] echo ""; echo "TEST SETUP:"; \ [2025-11-28T08:05:12.484Z] echo "Nothing to be done for setup."; \ [2025-11-28T08:05:12.484Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17643171125740/hotspot_gc_0"; \ [2025-11-28T08:05:12.484Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17643171125740/hotspot_gc_0"; \ [2025-11-28T08:05:12.484Z] echo ""; echo "TESTING:"; \ [2025-11-28T08:05:12.484Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-11-28T08:05:12.484Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -k:'!headful' -concurrency:3 -nativepath:"/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m -Djava.awt.headless=true" \ [2025-11-28T08:05:12.484Z] -w ""/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17643171125740/hotspot_gc_0"/work" \ [2025-11-28T08:05:12.484Z] -r ""/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17643171125740/hotspot_gc_0"/report" \ [2025-11-28T08:05:12.484Z] -jdk:"/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image" \ [2025-11-28T08:05:12.484Z] -exclude:"/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2025-11-28T08:05:12.484Z] -exclude:"/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk26.txt" \ [2025-11-28T08:05:12.484Z] \ [2025-11-28T08:05:12.484Z] \ [2025-11-28T08:05:12.484Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \ [2025-11-28T08:05:12.484Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-28T08:05:12.484Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-28T08:05:12.484Z] echo "Nothing to be done for teardown."; \ [2025-11-28T08:05:12.484Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17643171125740/TestTargetResult"; [2025-11-28T08:05:12.484Z] [2025-11-28T08:05:12.484Z] TEST SETUP: [2025-11-28T08:05:12.484Z] Nothing to be done for setup. [2025-11-28T08:05:12.484Z] [2025-11-28T08:05:12.484Z] TESTING: [2025-11-28T08:05:13.207Z] Directory "/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17643171125740/hotspot_gc_0/work" not found: creating [2025-11-28T08:05:13.207Z] Directory "/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/../TKG/output_17643171125740/hotspot_gc_0/report" not found: creating [2025-11-28T08:05:16.448Z] XML output with verification to /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work [2025-11-28T09:15:08.489Z] -------------------------------------------------- [2025-11-28T09:15:08.489Z] TEST: gc/TestAllocHumongousFragment.java#generational [2025-11-28T09:15:08.489Z] TEST JDK: /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image [2025-11-28T09:15:08.489Z] [2025-11-28T09:15:08.489Z] ACTION: build -- Passed. Build successful [2025-11-28T09:15:08.489Z] REASON: Named class compiled on demand [2025-11-28T09:15:08.489Z] TIME: 0.159 seconds [2025-11-28T09:15:08.489Z] messages: [2025-11-28T09:15:08.489Z] command: build TestAllocHumongousFragment [2025-11-28T09:15:08.489Z] reason: Named class compiled on demand [2025-11-28T09:15:08.489Z] started: Fri Nov 28 09:14:37.859 GMT 2025 [2025-11-28T09:15:08.489Z] Test directory: [2025-11-28T09:15:08.489Z] compile: TestAllocHumongousFragment [2025-11-28T09:15:08.489Z] finished: Fri Nov 28 09:14:38.018 GMT 2025 [2025-11-28T09:15:08.489Z] elapsed time (seconds): 0.159 [2025-11-28T09:15:08.489Z] [2025-11-28T09:15:08.489Z] ACTION: compile -- Passed. Compilation successful [2025-11-28T09:15:08.489Z] REASON: .class file out of date or does not exist [2025-11-28T09:15:08.489Z] TIME: 0.158 seconds [2025-11-28T09:15:08.489Z] messages: [2025-11-28T09:15:08.489Z] command: compile /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java [2025-11-28T09:15:08.489Z] reason: .class file out of date or does not exist [2025-11-28T09:15:08.489Z] started: Fri Nov 28 09:14:37.860 GMT 2025 [2025-11-28T09:15:08.489Z] Mode: agentvm [2025-11-28T09:15:08.489Z] Agent id: 3 [2025-11-28T09:15:08.489Z] Process id: 1875252 [2025-11-28T09:15:08.489Z] finished: Fri Nov 28 09:14:38.018 GMT 2025 [2025-11-28T09:15:08.489Z] elapsed time (seconds): 0.158 [2025-11-28T09:15:08.489Z] configuration: [2025-11-28T09:15:08.489Z] Boot Layer (javac runtime environment) [2025-11-28T09:15:08.489Z] class path: /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-11-28T09:15:08.489Z] /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-11-28T09:15:08.489Z] patch: java.base /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/patches/java.base [2025-11-28T09:15:08.489Z] [2025-11-28T09:15:08.489Z] javac compilation environment [2025-11-28T09:15:08.489Z] source path: /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc [2025-11-28T09:15:08.489Z] /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-11-28T09:15:08.489Z] class path: /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc [2025-11-28T09:15:08.489Z] /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d [2025-11-28T09:15:08.489Z] /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d/test/lib [2025-11-28T09:15:08.489Z] [2025-11-28T09:15:08.489Z] rerun: [2025-11-28T09:15:08.489Z] cd /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/scratch/1 && \ [2025-11-28T09:15:08.489Z] DISPLAY=:0 \ [2025-11-28T09:15:08.489Z] HOME=/home/jenkins \ [2025-11-28T09:15:08.489Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-11-28T09:15:08.489Z] /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image/bin/javac \ [2025-11-28T09:15:08.489Z] -J-ea \ [2025-11-28T09:15:08.489Z] -J-esa \ [2025-11-28T09:15:08.489Z] -J-Xmx512m \ [2025-11-28T09:15:08.489Z] -J-Djava.awt.headless=true \ [2025-11-28T09:15:08.489Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -Djava.awt.headless=true' \ [2025-11-28T09:15:08.489Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-Djava.awt.headless=true' \ [2025-11-28T09:15:08.489Z] -J-Dtest.compiler.opts= \ [2025-11-28T09:15:08.489Z] -J-Dtest.java.opts= \ [2025-11-28T09:15:08.489Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image \ [2025-11-28T09:15:08.489Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image \ [2025-11-28T09:15:08.489Z] -J-Dtest.timeout.factor=8.0 \ [2025-11-28T09:15:08.489Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-11-28T09:15:08.489Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-11-28T09:15:08.489Z] -J-Dtest.name=gc/TestAllocHumongousFragment.java#generational \ [2025-11-28T09:15:08.489Z] -J-Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2025-11-28T09:15:08.489Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java \ [2025-11-28T09:15:08.490Z] -J-Dtest.main.class=TestAllocHumongousFragment \ [2025-11-28T09:15:08.490Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc \ [2025-11-28T09:15:08.490Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-11-28T09:15:08.490Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d \ [2025-11-28T09:15:08.490Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T09:15:08.490Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T09:15:08.490Z] -d /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d \ [2025-11-28T09:15:08.490Z] -sourcepath /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-11-28T09:15:08.490Z] -classpath /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d/test/lib /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java [2025-11-28T09:15:08.490Z] [2025-11-28T09:15:08.490Z] ACTION: main -- Failed. Unexpected exit from test [exit code: 134] [2025-11-28T09:15:08.490Z] 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-28T09:15:08.490Z] TIME: 26.405 seconds [2025-11-28T09:15:08.490Z] messages: [2025-11-28T09:15:08.490Z] 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-28T09:15:08.490Z] 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-28T09:15:08.490Z] started: Fri Nov 28 09:14:38.018 GMT 2025 [2025-11-28T09:15:08.490Z] Mode: othervm [/othervm specified] [2025-11-28T09:15:08.490Z] Process id: 2007366 [2025-11-28T09:15:08.490Z] finished: Fri Nov 28 09:15:04.423 GMT 2025 [2025-11-28T09:15:08.490Z] elapsed time (seconds): 26.405 [2025-11-28T09:15:08.490Z] configuration: [2025-11-28T09:15:08.490Z] STDOUT: [2025-11-28T09:15:08.490Z] [0.001s][info][gc] Min heap equals to max heap, disabling ShenandoahUncommit [2025-11-28T09:15:08.490Z] [0.004s][info][gc] Heuristics ergonomically sets -XX:GCTimeRatio=70 [2025-11-28T09:15:08.490Z] [0.004s][info][gc] Heuristics ergonomically sets -XX:+ExplicitGCInvokesConcurrent [2025-11-28T09:15:08.490Z] [0.004s][info][gc] Heuristics ergonomically sets -XX:+ShenandoahImplicitGCInvokesConcurrent [2025-11-28T09:15:08.490Z] [0.004s][info][gc] Heuristics ergonomically sets -XX:+UseCondCardMark [2025-11-28T09:15:08.490Z] [0.004s][info][gc] Using Shenandoah [2025-11-28T09:15:08.490Z] For random generator using seed: 3723532219 [2025-11-28T09:15:08.490Z] To re-run test with same seed value please add "-Djdk.test.lib.random.seed=3723532219" to command line. [2025-11-28T09:15:08.490Z] Allocated: 51 Mb [2025-11-28T09:15:08.490Z] Allocated: 106 Mb [2025-11-28T09:15:08.490Z] Allocated: 122 Mb [2025-11-28T09:15:08.490Z] Allocated: 158 Mb [2025-11-28T09:15:08.490Z] Allocated: 216 Mb [2025-11-28T09:15:08.490Z] Allocated: 246 Mb [2025-11-28T09:15:08.490Z] [0.268s][info][gc] Trigger (Young): Learning 1 of 5. Free (698M) is below initial threshold (716M) [2025-11-28T09:15:08.490Z] [0.269s][info][gc] GC(0) Concurrent reset (Young) 1.182ms [2025-11-28T09:15:08.490Z] [0.287s][info][gc] GC(0) Verify Before Mark, Level 4 (34378 reachable, 0 marked) [2025-11-28T09:15:08.490Z] [0.288s][info][gc] GC(0) Pause Init Mark (Young) 12.001ms [2025-11-28T09:15:08.490Z] Allocated: 269 Mb [2025-11-28T09:15:08.490Z] [0.288s][info][gc] GC(0) Concurrent remembered set scanning 0.168ms [2025-11-28T09:15:08.490Z] [0.289s][info][gc] GC(0) Concurrent marking roots 0.793ms [2025-11-28T09:15:08.490Z] Allocated: 314 Mb [2025-11-28T09:15:08.490Z] [0.315s][info][gc] GC(0) Concurrent marking (Young) 26.027ms [2025-11-28T09:15:08.490Z] [0.327s][info][gc] GC(0) Verify Before Evacuation, Level 4 (34363 reachable, 1767 marked) [2025-11-28T09:15:08.490Z] [0.327s][info][gc] GC(0) Pause Final Mark (Young) 12.089ms [2025-11-28T09:15:08.490Z] [0.328s][info][gc] GC(0) Concurrent thread roots 0.764ms [2025-11-28T09:15:08.490Z] [0.328s][info][gc] GC(0) Concurrent weak references (Young) 0.042ms [2025-11-28T09:15:08.490Z] [0.328s][info][gc] GC(0) Concurrent weak roots (Young) 0.281ms [2025-11-28T09:15:08.490Z] [0.328s][info][gc] GC(0) Concurrent cleanup (Young) 334M->334M(1024M) 0.024ms [2025-11-28T09:15:08.490Z] Allocated: 328 Mb [2025-11-28T09:15:08.490Z] [0.329s][info][gc] GC(0) Concurrent strong roots 0.240ms [2025-11-28T09:15:08.490Z] [0.330s][info][gc] GC(0) Concurrent evacuation 1.058ms [2025-11-28T09:15:08.490Z] Allocated: 377 Mb [2025-11-28T09:15:08.490Z] [0.345s][info][gc] GC(0) Concurrent Init Update Refs (Young) 15.024ms [2025-11-28T09:15:08.490Z] [0.360s][info][gc] GC(0) Verify Before Updating References, Level 4 (36172 reachable, 12416 marked) [2025-11-28T09:15:08.490Z] [0.360s][info][gc] GC(0) Pause Init Update Refs 12.753ms [2025-11-28T09:15:08.490Z] Allocated: 380 Mb [2025-11-28T09:15:08.490Z] [0.367s][info][gc] GC(0) Concurrent update references 6.864ms [2025-11-28T09:15:08.490Z] Allocated: 444 Mb [2025-11-28T09:15:08.490Z] [0.409s][info][gc] GC(0) Concurrent update thread roots 42.044ms [2025-11-28T09:15:08.490Z] [0.419s][info][gc] GC(0) Verify After Updating References, Level 4 (36112 reachable, 45 marked) [2025-11-28T09:15:08.490Z] [0.419s][info][gc] GC(0) Pause Final Update Refs 10.481ms [2025-11-28T09:15:08.490Z] [0.419s][info][gc] GC(0) Concurrent cleanup (Young) 449M->449M(1024M) 0.023ms [2025-11-28T09:15:08.490Z] [0.453s][info][gc] GC(0) Concurrent reset after collect (Young) 33.574ms [2025-11-28T09:15:08.490Z] [0.455s][info][gc] Trigger (Young): Learning 2 of 5. Free (480M) is below initial threshold (716M) [2025-11-28T09:15:08.490Z] [0.456s][info][gc] GC(1) Concurrent reset (Young) 0.563ms [2025-11-28T09:15:08.490Z] [0.465s][info][gc] GC(1) Verify Before Mark, Level 4 (34366 reachable, 0 marked) [2025-11-28T09:15:08.490Z] [0.465s][info][gc] GC(1) Pause Init Mark (Young) 8.752ms [2025-11-28T09:15:08.490Z] [0.465s][info][gc] GC(1) Concurrent remembered set scanning 0.167ms [2025-11-28T09:15:08.490Z] Allocated: 485 Mb [2025-11-28T09:15:08.490Z] [0.466s][info][gc] GC(1) Concurrent marking roots 0.521ms [2025-11-28T09:15:08.490Z] Allocated: 549 Mb [2025-11-28T09:15:08.490Z] [0.539s][info][gc] GC(1) Concurrent marking (Young) 73.134ms [2025-11-28T09:15:08.490Z] Allocated: 572 Mb [2025-11-28T09:15:08.490Z] [0.582s][info][gc] GC(1) Verify Before Evacuation, Level 4 (34369 reachable, 1767 marked) [2025-11-28T09:15:08.490Z] [0.582s][info][gc] GC(1) Pause Final Mark (Young) 13.303ms [2025-11-28T09:15:08.490Z] [0.582s][info][gc] GC(1) Concurrent thread roots 0.113ms [2025-11-28T09:15:08.490Z] [0.582s][info][gc] GC(1) Concurrent weak references (Young) 0.023ms [2025-11-28T09:15:08.490Z] Allocated: 610 Mb [2025-11-28T09:15:08.490Z] [0.582s][info][gc] GC(1) Concurrent weak roots (Young) 0.185ms [2025-11-28T09:15:08.490Z] [0.582s][info][gc] GC(1) Concurrent cleanup (Young) 634M->634M(1024M) 0.034ms [2025-11-28T09:15:08.490Z] [0.583s][info][gc] GC(1) Concurrent strong roots 0.166ms [2025-11-28T09:15:08.490Z] [0.583s][info][gc] GC(1) Concurrent evacuation 0.698ms [2025-11-28T09:15:08.490Z] Allocated: 626 Mb [2025-11-28T09:15:08.491Z] [0.595s][info][gc] GC(1) Concurrent Init Update Refs (Young) 11.873ms [2025-11-28T09:15:08.491Z] [0.641s][info][gc] GC(1) Verify Before Updating References, Level 4 (36119 reachable, 7429 marked) [2025-11-28T09:15:08.491Z] [0.641s][info][gc] GC(1) Pause Init Update Refs 11.056ms [2025-11-28T09:15:08.491Z] Allocated: 674 Mb [2025-11-28T09:15:08.491Z] [0.648s][info][gc] GC(1) Concurrent update references 6.052ms [2025-11-28T09:15:08.491Z] [0.686s][info][gc] GC(1) Concurrent update thread roots 38.764ms [2025-11-28T09:15:08.491Z] [0.696s][info][gc] GC(1) Verify After Updating References, Level 4 (36119 reachable, 45 marked) [2025-11-28T09:15:08.491Z] [0.697s][info][gc] GC(1) Pause Final Update Refs 10.028ms [2025-11-28T09:15:08.491Z] [0.697s][info][gc] GC(1) Concurrent cleanup (Young) 741M->741M(1024M) 0.022ms [2025-11-28T09:15:08.491Z] Allocated: 733 Mb [2025-11-28T09:15:08.491Z] [0.713s][info][gc] GC(1) Concurrent reset after collect (Young) 15.823ms [2025-11-28T09:15:08.491Z] [0.715s][info][gc] Trigger (Young): Learning 3 of 5. Free (206M) is below initial threshold (716M) [2025-11-28T09:15:08.491Z] [0.715s][info][gc] GC(2) Concurrent reset (Young) 0.422ms [2025-11-28T09:15:08.491Z] [0.732s][info][gc] GC(2) Verify Before Mark, Level 4 (34371 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [0.732s][info][gc] GC(2) Pause Init Mark (Young) 6.224ms [2025-11-28T09:15:08.491Z] Allocated: 681 Mb [2025-11-28T09:15:08.491Z] [0.734s][info][gc] GC(2) Concurrent remembered set scanning 1.200ms [2025-11-28T09:15:08.491Z] [0.734s][info][gc] GC(2) Concurrent marking roots 0.280ms [2025-11-28T09:15:08.491Z] Allocated: 701 Mb [2025-11-28T09:15:08.491Z] Allocated: 655 Mb [2025-11-28T09:15:08.491Z] [0.756s][info][gc] GC(2) Concurrent marking (Young) 22.305ms [2025-11-28T09:15:08.491Z] [0.772s][info][gc] GC(2) Verify Before Evacuation, Level 4 (34373 reachable, 1768 marked) [2025-11-28T09:15:08.491Z] [0.772s][info][gc] GC(2) Pause Final Mark (Young) 11.328ms [2025-11-28T09:15:08.491Z] [0.772s][info][gc] GC(2) Concurrent thread roots 0.097ms [2025-11-28T09:15:08.491Z] [0.772s][info][gc] GC(2) Concurrent weak references (Young) 0.052ms [2025-11-28T09:15:08.491Z] [0.773s][info][gc] GC(2) Concurrent weak roots (Young) 0.261ms [2025-11-28T09:15:08.491Z] [0.773s][info][gc] GC(2) Concurrent cleanup (Young) 718M->718M(1024M) 0.042ms [2025-11-28T09:15:08.491Z] [0.773s][info][gc] GC(2) Concurrent strong roots 0.168ms [2025-11-28T09:15:08.491Z] Allocated: 661 Mb [2025-11-28T09:15:08.491Z] [0.776s][info][gc] GC(2) Concurrent evacuation 2.775ms [2025-11-28T09:15:08.491Z] Allocated: 711 Mb [2025-11-28T09:15:08.491Z] [0.784s][info][gc] GC(2) Concurrent Init Update Refs (Young) 8.616ms [2025-11-28T09:15:08.491Z] [0.846s][info][gc] GC(2) Verify Before Updating References, Level 4 (2166 reachable, 17955 marked) [2025-11-28T09:15:08.491Z] [0.846s][info][gc] GC(2) Pause Init Update Refs 13.730ms [2025-11-28T09:15:08.491Z] Allocated: 733 Mb [2025-11-28T09:15:08.491Z] [0.852s][info][gc] GC(2) Concurrent update references 6.135ms [2025-11-28T09:15:08.491Z] Allocated: 725 Mb [2025-11-28T09:15:08.491Z] [0.888s][info][gc] GC(2) Concurrent update thread roots 35.605ms [2025-11-28T09:15:08.491Z] [0.917s][info][gc] GC(2) Verify After Updating References, Level 4 (2163 reachable, 10576 marked) [2025-11-28T09:15:08.491Z] [0.917s][info][gc] GC(2) Pause Final Update Refs 9.176ms [2025-11-28T09:15:08.491Z] [0.917s][info][gc] GC(2) Concurrent cleanup (Young) 907M->907M(1024M) 0.023ms [2025-11-28T09:15:08.491Z] Allocated: 695 Mb [2025-11-28T09:15:08.491Z] [0.950s][info][gc] GC(2) Concurrent reset after collect (Young) 32.508ms [2025-11-28T09:15:08.491Z] [0.951s][info][gc] Trigger (Young): Free (46848K) is below minimum threshold (102M) [2025-11-28T09:15:08.491Z] [0.951s][info][gc] GC(3) Concurrent reset (Young) 0.418ms [2025-11-28T09:15:08.491Z] [0.970s][info][gc] GC(3) Verify Before Mark, Level 4 (10950 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [0.970s][info][gc] GC(3) Pause Init Mark (Young) 14.836ms [2025-11-28T09:15:08.491Z] Allocated: 715 Mb [2025-11-28T09:15:08.491Z] [0.972s][info][gc] Failed to allocate Shared, 16454K [2025-11-28T09:15:08.491Z] [0.972s][info][gc] GC(3) Concurrent remembered set scanning 1.998ms [2025-11-28T09:15:08.491Z] [0.973s][info][gc] GC(3) Concurrent marking roots 0.217ms [2025-11-28T09:15:08.491Z] [0.973s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.491Z] [0.979s][info][gc] GC(4) Verify Before Full GC, Level 4 (34374 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [0.980s][info][gc] GC(4) Cancel marking: Young [2025-11-28T09:15:08.491Z] [0.980s][info][gc] GC(4) Cancel marking: Old [2025-11-28T09:15:08.491Z] [0.980s][info][gc] GC(4) Cancel marking: Global [2025-11-28T09:15:08.491Z] [1.382s][info][gc] GC(4) Verify After Full GC, Level 4 (34239 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [1.382s][info][gc] GC(4) Pause Full 927M->684M(1024M) 409.732ms [2025-11-28T09:15:08.491Z] [1.385s][info][gc] Trigger (Young): Learning 4 of 5. Free (270M) is below initial threshold (716M) [2025-11-28T09:15:08.491Z] [1.385s][info][gc] GC(5) Concurrent reset (Young) 0.426ms [2025-11-28T09:15:08.491Z] [1.389s][info][gc] GC(5) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [1.389s][info][gc] GC(5) Pause Init Mark (Young) 3.239ms [2025-11-28T09:15:08.491Z] Allocated: 694 Mb [2025-11-28T09:15:08.491Z] [1.390s][info][gc] GC(5) Concurrent remembered set scanning 0.218ms [2025-11-28T09:15:08.491Z] [1.390s][info][gc] GC(5) Concurrent marking roots 0.193ms [2025-11-28T09:15:08.491Z] [1.390s][info][gc] GC(5) Concurrent marking (Young) 0.226ms [2025-11-28T09:15:08.491Z] [1.393s][info][gc] GC(5) Verify After Mark, Level 4 (23 reachable, 5 marked) [2025-11-28T09:15:08.491Z] [1.393s][info][gc] GC(5) Pause Final Mark (Young) 3.263ms [2025-11-28T09:15:08.491Z] [1.394s][info][gc] GC(5) Concurrent weak references (Young) 0.013ms [2025-11-28T09:15:08.491Z] Allocated: 736 Mb [2025-11-28T09:15:08.491Z] [1.400s][info][gc] GC(5) Concurrent weak roots (Young) 6.560ms [2025-11-28T09:15:08.491Z] [1.400s][info][gc] GC(5) Concurrent cleanup (Young) 749M->749M(1024M) 0.121ms [2025-11-28T09:15:08.491Z] [1.401s][info][gc] GC(5) Concurrent Final Roots (Young) 0.602ms [2025-11-28T09:15:08.491Z] Allocated: 662 Mb [2025-11-28T09:15:08.491Z] Allocated: 702 Mb [2025-11-28T09:15:08.491Z] Allocated: 700 Mb [2025-11-28T09:15:08.491Z] Allocated: 713 Mb [2025-11-28T09:15:08.491Z] Allocated: 677 Mb [2025-11-28T09:15:08.491Z] [1.419s][info][gc] GC(5) Concurrent reset after collect (Young) 17.995ms [2025-11-28T09:15:08.491Z] [1.421s][info][gc] Trigger (Young): Free (95999K) is below minimum threshold (102M) [2025-11-28T09:15:08.491Z] [1.422s][info][gc] GC(6) Concurrent reset (Young) 0.769ms [2025-11-28T09:15:08.491Z] [1.429s][info][gc] GC(6) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [1.429s][info][gc] GC(6) Pause Init Mark (Young) 3.478ms [2025-11-28T09:15:08.491Z] Allocated: 723 Mb [2025-11-28T09:15:08.491Z] [1.433s][info][gc] GC(6) Concurrent remembered set scanning 0.284ms [2025-11-28T09:15:08.491Z] [1.433s][info][gc] GC(6) Concurrent marking roots 0.220ms [2025-11-28T09:15:08.491Z] Allocated: 703 Mb [2025-11-28T09:15:08.491Z] Allocated: 708 Mb [2025-11-28T09:15:08.491Z] [1.442s][info][gc] GC(6) Concurrent marking (Young) 9.085ms [2025-11-28T09:15:08.491Z] [1.449s][info][gc] GC(6) Verify After Mark, Level 4 (24 reachable, 18 marked) [2025-11-28T09:15:08.491Z] [1.449s][info][gc] GC(6) Pause Final Mark (Young) 6.061ms [2025-11-28T09:15:08.491Z] Allocated: 689 Mb [2025-11-28T09:15:08.491Z] [1.449s][info][gc] Failed to allocate Shared, 25376K [2025-11-28T09:15:08.491Z] [1.449s][info][gc] GC(6) Concurrent weak references (Young) 0.027ms [2025-11-28T09:15:08.491Z] [1.449s][info][gc] GC(6) Concurrent weak roots (Young) 0.089ms [2025-11-28T09:15:08.491Z] [1.449s][info][gc] GC(6) Concurrent cleanup (Young) 807M->807M(1024M) 0.058ms [2025-11-28T09:15:08.491Z] [1.450s][info][gc] GC(6) Concurrent Final Roots (Young) 0.064ms [2025-11-28T09:15:08.491Z] [1.454s][info][gc] GC(6) Concurrent reset after collect (Young) 3.974ms [2025-11-28T09:15:08.491Z] [1.454s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.491Z] [1.462s][info][gc] GC(7) Verify Before Full GC, Level 4 (34346 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [1.705s][info][gc] GC(7) Verify After Full GC, Level 4 (34242 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [1.706s][info][gc] GC(7) Pause Full 807M->697M(1024M) 251.846ms [2025-11-28T09:15:08.491Z] [1.707s][info][gc] Trigger (Young): Average GC time (236.02 ms) is above the time for average allocation rate (1408 MB/s) to deplete free headroom (198M) (margin of error = 1.80) [2025-11-28T09:15:08.491Z] [1.707s][info][gc] GC(8) Concurrent reset (Young) 0.450ms [2025-11-28T09:15:08.491Z] [1.715s][info][gc] GC(8) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.491Z] [1.715s][info][gc] GC(8) Pause Init Mark (Young) 3.220ms [2025-11-28T09:15:08.491Z] Allocated: 714 Mb [2025-11-28T09:15:08.491Z] [1.715s][info][gc] GC(8) Concurrent remembered set scanning 0.207ms [2025-11-28T09:15:08.491Z] [1.716s][info][gc] GC(8) Concurrent marking roots 0.220ms [2025-11-28T09:15:08.491Z] [1.716s][info][gc] GC(8) Concurrent marking (Young) 0.249ms [2025-11-28T09:15:08.491Z] [1.721s][info][gc] GC(8) Verify After Mark, Level 4 (26 reachable, 5 marked) [2025-11-28T09:15:08.491Z] [1.721s][info][gc] GC(8) Pause Final Mark (Young) 5.546ms [2025-11-28T09:15:08.491Z] Allocated: 657 Mb [2025-11-28T09:15:08.491Z] [1.723s][info][gc] GC(8) Concurrent weak references (Young) 0.017ms [2025-11-28T09:15:08.491Z] [1.723s][info][gc] GC(8) Concurrent weak roots (Young) 0.082ms [2025-11-28T09:15:08.491Z] [1.723s][info][gc] GC(8) Concurrent cleanup (Young) 730M->730M(1024M) 0.018ms [2025-11-28T09:15:08.491Z] [1.723s][info][gc] GC(8) Concurrent Final Roots (Young) 0.045ms [2025-11-28T09:15:08.492Z] [1.732s][info][gc] GC(8) Concurrent reset after collect (Young) 8.934ms [2025-11-28T09:15:08.492Z] [1.732s][info][gc] Trigger (Young): Average GC time (173.83 ms) is above the time for average allocation rate (1408 MB/s) to deplete free headroom (131M) (margin of error = 1.80) [2025-11-28T09:15:08.492Z] [1.733s][info][gc] GC(9) Concurrent reset (Young) 0.410ms [2025-11-28T09:15:08.492Z] [1.741s][info][gc] GC(9) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [1.741s][info][gc] GC(9) Pause Init Mark (Young) 3.283ms [2025-11-28T09:15:08.492Z] [1.741s][info][gc] GC(9) Concurrent remembered set scanning 0.189ms [2025-11-28T09:15:08.492Z] [1.742s][info][gc] GC(9) Concurrent marking roots 0.187ms [2025-11-28T09:15:08.492Z] Allocated: 716 Mb [2025-11-28T09:15:08.492Z] Allocated: 712 Mb [2025-11-28T09:15:08.492Z] [1.752s][info][gc] GC(9) Concurrent marking (Young) 9.847ms [2025-11-28T09:15:08.492Z] [1.757s][info][gc] GC(9) Verify Before Evacuation, Level 4 (25 reachable, 14 marked) [2025-11-28T09:15:08.492Z] [1.757s][info][gc] GC(9) Pause Final Mark (Young) 5.413ms [2025-11-28T09:15:08.492Z] Allocated: 660 Mb [2025-11-28T09:15:08.492Z] [1.758s][info][gc] GC(9) Concurrent thread roots 0.070ms [2025-11-28T09:15:08.492Z] [1.758s][info][gc] GC(9) Concurrent weak references (Young) 0.011ms [2025-11-28T09:15:08.492Z] [1.758s][info][gc] GC(9) Concurrent weak roots (Young) 0.069ms [2025-11-28T09:15:08.492Z] [1.758s][info][gc] GC(9) Concurrent cleanup (Young) 783M->783M(1024M) 0.042ms [2025-11-28T09:15:08.492Z] [1.758s][info][gc] GC(9) Concurrent strong roots 0.144ms [2025-11-28T09:15:08.492Z] [1.758s][info][gc] GC(9) Concurrent evacuation 0.207ms [2025-11-28T09:15:08.492Z] [1.758s][info][gc] GC(9) Concurrent Init Update Refs (Young) 0.039ms [2025-11-28T09:15:08.492Z] [1.760s][info][gc] GC(9) Verify Before Updating References, Level 4 (25 reachable, 31 marked) [2025-11-28T09:15:08.492Z] [1.760s][info][gc] GC(9) Pause Init Update Refs 1.936ms [2025-11-28T09:15:08.492Z] Allocated: 675 Mb [2025-11-28T09:15:08.492Z] Allocated: 684 Mb [2025-11-28T09:15:08.492Z] [1.766s][info][gc] GC(9) Concurrent update references 3.172ms [2025-11-28T09:15:08.492Z] [1.767s][info][gc] GC(9) Concurrent update thread roots 0.264ms [2025-11-28T09:15:08.492Z] [1.770s][info][gc] GC(9) Verify After Updating References, Level 4 (27 reachable, 27 marked) [2025-11-28T09:15:08.492Z] [1.770s][info][gc] GC(9) Pause Final Update Refs 3.153ms [2025-11-28T09:15:08.492Z] [1.770s][info][gc] GC(9) Concurrent cleanup (Young) 807M->807M(1024M) 0.013ms [2025-11-28T09:15:08.492Z] [1.776s][info][gc] GC(9) Concurrent reset after collect (Young) 5.939ms [2025-11-28T09:15:08.492Z] [1.776s][info][gc] Trigger (Young): Average GC time (134.11 ms) is above the time for average allocation rate (2074 MB/s) to deplete free headroom (86323K) (margin of error = 1.80) [2025-11-28T09:15:08.492Z] [1.777s][info][gc] GC(10) Concurrent reset (Young) 0.370ms [2025-11-28T09:15:08.492Z] [1.782s][info][gc] GC(10) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [1.782s][info][gc] GC(10) Pause Init Mark (Young) 3.066ms [2025-11-28T09:15:08.492Z] Allocated: 713 Mb [2025-11-28T09:15:08.492Z] [1.782s][info][gc] GC(10) Concurrent remembered set scanning 0.160ms [2025-11-28T09:15:08.492Z] [1.783s][info][gc] GC(10) Concurrent marking roots 0.187ms [2025-11-28T09:15:08.492Z] [1.783s][info][gc] GC(10) Concurrent marking (Young) 0.108ms [2025-11-28T09:15:08.492Z] [1.787s][info][gc] GC(10) Verify Before Evacuation, Level 4 (28 reachable, 5 marked) [2025-11-28T09:15:08.492Z] [1.787s][info][gc] GC(10) Pause Final Mark (Young) 4.547ms [2025-11-28T09:15:08.492Z] Allocated: 675 Mb [2025-11-28T09:15:08.492Z] [1.789s][info][gc] GC(10) Concurrent thread roots 0.064ms [2025-11-28T09:15:08.492Z] [1.789s][info][gc] GC(10) Concurrent weak references (Young) 0.016ms [2025-11-28T09:15:08.492Z] [1.789s][info][gc] GC(10) Concurrent weak roots (Young) 0.160ms [2025-11-28T09:15:08.492Z] [1.789s][info][gc] GC(10) Concurrent cleanup (Young) 731M->731M(1024M) 0.035ms [2025-11-28T09:15:08.492Z] [1.789s][info][gc] GC(10) Concurrent strong roots 0.149ms [2025-11-28T09:15:08.492Z] [1.789s][info][gc] GC(10) Concurrent evacuation 0.058ms [2025-11-28T09:15:08.492Z] [1.789s][info][gc] GC(10) Concurrent Init Update Refs (Young) 0.038ms [2025-11-28T09:15:08.492Z] [1.791s][info][gc] GC(10) Verify Before Updating References, Level 4 (27 reachable, 16 marked) [2025-11-28T09:15:08.492Z] [1.791s][info][gc] GC(10) Pause Init Update Refs 1.928ms [2025-11-28T09:15:08.492Z] [1.799s][info][gc] GC(10) Concurrent update references 3.383ms [2025-11-28T09:15:08.492Z] Allocated: 729 Mb [2025-11-28T09:15:08.492Z] [1.803s][info][gc] GC(10) Concurrent update thread roots 4.310ms [2025-11-28T09:15:08.492Z] [1.815s][info][gc] GC(10) Verify After Updating References, Level 4 (26 reachable, 21 marked) [2025-11-28T09:15:08.492Z] [1.815s][info][gc] GC(10) Pause Final Update Refs 3.869ms [2025-11-28T09:15:08.492Z] Allocated: 749 Mb [2025-11-28T09:15:08.492Z] [1.816s][info][gc] GC(10) Concurrent cleanup (Young) 835M->835M(1024M) 0.036ms [2025-11-28T09:15:08.492Z] Allocated: 692 Mb [2025-11-28T09:15:08.492Z] [1.823s][info][gc] GC(10) Concurrent reset after collect (Young) 7.156ms [2025-11-28T09:15:08.492Z] [1.825s][info][gc] Trigger (Young): Average GC time (108.83 ms) is above the time for average allocation rate (2381 MB/s) to deplete free headroom (53299K) (margin of error = 1.81) [2025-11-28T09:15:08.492Z] [1.826s][info][gc] GC(11) Concurrent reset (Young) 0.411ms [2025-11-28T09:15:08.492Z] [1.829s][info][gc] GC(11) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [1.829s][info][gc] GC(11) Pause Init Mark (Young) 3.199ms [2025-11-28T09:15:08.492Z] Allocated: 717 Mb [2025-11-28T09:15:08.492Z] [1.830s][info][gc] GC(11) Concurrent remembered set scanning 0.169ms [2025-11-28T09:15:08.492Z] [1.830s][info][gc] GC(11) Concurrent marking roots 0.191ms [2025-11-28T09:15:08.492Z] [1.830s][info][gc] GC(11) Concurrent marking (Young) 0.222ms [2025-11-28T09:15:08.492Z] [1.844s][info][gc] GC(11) Verify Before Evacuation, Level 4 (26 reachable, 7 marked) [2025-11-28T09:15:08.492Z] [1.844s][info][gc] GC(11) Pause Final Mark (Young) 4.180ms [2025-11-28T09:15:08.492Z] [1.844s][info][gc] GC(11) Concurrent thread roots 0.113ms [2025-11-28T09:15:08.492Z] [1.844s][info][gc] GC(11) Concurrent weak references (Young) 0.020ms [2025-11-28T09:15:08.492Z] Allocated: 731 Mb [2025-11-28T09:15:08.492Z] [1.844s][info][gc] GC(11) Concurrent weak roots (Young) 0.276ms [2025-11-28T09:15:08.492Z] [1.844s][info][gc] Failed to allocate Shared, 59411K [2025-11-28T09:15:08.492Z] [1.844s][info][gc] GC(11) Concurrent cleanup (Young) 786M->786M(1024M) 0.062ms [2025-11-28T09:15:08.492Z] [1.845s][info][gc] GC(11) Concurrent strong roots 0.517ms [2025-11-28T09:15:08.492Z] [1.845s][info][gc] GC(11) Concurrent evacuation 0.015ms [2025-11-28T09:15:08.492Z] [1.845s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.492Z] [1.853s][info][gc] GC(12) Verify Before Full GC, Level 4 (34346 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [2.079s][info][gc] GC(12) Verify After Full GC, Level 4 (34242 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [2.079s][info][gc] GC(12) Pause Full 786M->663M(1024M) 233.867ms [2025-11-28T09:15:08.492Z] Allocated: 712 Mb [2025-11-28T09:15:08.492Z] Allocated: 697 Mb [2025-11-28T09:15:08.492Z] [2.092s][info][gc] Trigger (Young): Average GC time (108.83 ms) is above the time for average allocation rate (1544 MB/s) to deplete free headroom (138M) (margin of error = 1.81) [2025-11-28T09:15:08.492Z] [2.093s][info][gc] GC(13) Concurrent reset (Young) 0.795ms [2025-11-28T09:15:08.492Z] [2.102s][info][gc] GC(13) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [2.102s][info][gc] GC(13) Pause Init Mark (Young) 3.455ms [2025-11-28T09:15:08.492Z] [2.102s][info][gc] GC(13) Concurrent remembered set scanning 0.182ms [2025-11-28T09:15:08.492Z] [2.102s][info][gc] GC(13) Concurrent marking roots 0.242ms [2025-11-28T09:15:08.492Z] Allocated: 735 Mb [2025-11-28T09:15:08.492Z] Allocated: 724 Mb [2025-11-28T09:15:08.492Z] [2.112s][info][gc] GC(13) Concurrent marking (Young) 10.121ms [2025-11-28T09:15:08.492Z] [2.125s][info][gc] GC(13) Verify After Mark, Level 4 (22 reachable, 15 marked) [2025-11-28T09:15:08.492Z] [2.125s][info][gc] GC(13) Pause Final Mark (Young) 6.304ms [2025-11-28T09:15:08.492Z] [2.125s][info][gc] GC(13) Concurrent weak references (Young) 0.028ms [2025-11-28T09:15:08.492Z] Allocated: 681 Mb [2025-11-28T09:15:08.492Z] [2.131s][info][gc] GC(13) Concurrent weak roots (Young) 6.168ms [2025-11-28T09:15:08.492Z] [2.131s][info][gc] GC(13) Concurrent cleanup (Young) 880M->880M(1024M) 0.057ms [2025-11-28T09:15:08.492Z] Allocated: 717 Mb [2025-11-28T09:15:08.492Z] [2.132s][info][gc] Failed to allocate Shared, 59298K [2025-11-28T09:15:08.492Z] [2.132s][info][gc] GC(13) Concurrent Final Roots (Young) 0.125ms [2025-11-28T09:15:08.492Z] [2.137s][info][gc] GC(13) Concurrent reset after collect (Young) 5.556ms [2025-11-28T09:15:08.492Z] [2.137s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.492Z] [2.146s][info][gc] GC(14) Verify Before Full GC, Level 4 (34344 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [2.431s][info][gc] GC(14) Verify After Full GC, Level 4 (34240 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [2.431s][info][gc] GC(14) Pause Full 880M->695M(1024M) 293.344ms [2025-11-28T09:15:08.492Z] Allocated: 744 Mb [2025-11-28T09:15:08.492Z] [2.440s][info][gc] Trigger (Young): Average GC time (89.81 ms) is above the time for average allocation rate (1704 MB/s) to deplete free headroom (115M) (margin of error = 1.81) [2025-11-28T09:15:08.492Z] [2.441s][info][gc] GC(15) Concurrent reset (Young) 0.445ms [2025-11-28T09:15:08.492Z] [2.453s][info][gc] GC(15) Verify Before Mark, Level 4 (21 reachable, 0 marked) [2025-11-28T09:15:08.492Z] [2.453s][info][gc] GC(15) Pause Init Mark (Young) 3.420ms [2025-11-28T09:15:08.492Z] Allocated: 704 Mb [2025-11-28T09:15:08.492Z] [2.453s][info][gc] GC(15) Concurrent remembered set scanning 0.211ms [2025-11-28T09:15:08.492Z] [2.454s][info][gc] GC(15) Concurrent marking roots 0.211ms [2025-11-28T09:15:08.493Z] [2.454s][info][gc] GC(15) Concurrent marking (Young) 0.237ms [2025-11-28T09:15:08.493Z] [2.458s][info][gc] GC(15) Verify After Mark, Level 4 (22 reachable, 5 marked) [2025-11-28T09:15:08.493Z] [2.459s][info][gc] GC(15) Pause Final Mark (Young) 4.710ms [2025-11-28T09:15:08.493Z] [2.462s][info][gc] GC(15) Concurrent weak references (Young) 0.023ms [2025-11-28T09:15:08.493Z] Allocated: 725 Mb [2025-11-28T09:15:08.493Z] [2.466s][info][gc] GC(15) Concurrent weak roots (Young) 3.245ms [2025-11-28T09:15:08.493Z] [2.466s][info][gc] GC(15) Concurrent cleanup (Young) 788M->788M(1024M) 0.065ms [2025-11-28T09:15:08.493Z] [2.472s][info][gc] GC(15) Concurrent Final Roots (Young) 6.460ms [2025-11-28T09:15:08.493Z] Allocated: 700 Mb [2025-11-28T09:15:08.493Z] Allocated: 655 Mb [2025-11-28T09:15:08.493Z] [2.478s][info][gc] GC(15) Concurrent reset after collect (Young) 6.317ms [2025-11-28T09:15:08.493Z] [2.480s][info][gc] Trigger (Young): Average GC time (74.07 ms) is above the time for average allocation rate (4550 MB/s) to deplete free headroom (90419K) (margin of error = 1.81) [2025-11-28T09:15:08.493Z] [2.481s][info][gc] GC(16) Concurrent reset (Young) 0.416ms [2025-11-28T09:15:08.493Z] [2.488s][info][gc] GC(16) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [2.488s][info][gc] GC(16) Pause Init Mark (Young) 3.309ms [2025-11-28T09:15:08.493Z] Allocated: 685 Mb [2025-11-28T09:15:08.493Z] [2.490s][info][gc] GC(16) Concurrent remembered set scanning 0.233ms [2025-11-28T09:15:08.493Z] [2.490s][info][gc] GC(16) Concurrent marking roots 0.205ms [2025-11-28T09:15:08.493Z] Allocated: 726 Mb [2025-11-28T09:15:08.493Z] Allocated: 677 Mb [2025-11-28T09:15:08.493Z] [2.498s][info][gc] GC(16) Concurrent marking (Young) 7.853ms [2025-11-28T09:15:08.493Z] [2.502s][info][gc] GC(16) Verify After Mark, Level 4 (23 reachable, 15 marked) [2025-11-28T09:15:08.493Z] [2.502s][info][gc] GC(16) Pause Final Mark (Young) 4.292ms [2025-11-28T09:15:08.493Z] Allocated: 694 Mb [2025-11-28T09:15:08.493Z] [2.505s][info][gc] GC(16) Concurrent weak references (Young) 0.022ms [2025-11-28T09:15:08.493Z] [2.505s][info][gc] GC(16) Concurrent weak roots (Young) 0.111ms [2025-11-28T09:15:08.493Z] [2.505s][info][gc] GC(16) Concurrent cleanup (Young) 761M->761M(1024M) 0.053ms [2025-11-28T09:15:08.493Z] [2.505s][info][gc] GC(16) Concurrent Final Roots (Young) 0.075ms [2025-11-28T09:15:08.493Z] [2.512s][info][gc] GC(16) Concurrent reset after collect (Young) 6.871ms [2025-11-28T09:15:08.493Z] [2.513s][info][gc] Trigger (Young): Average GC time (61.57 ms) is above the time for average allocation rate (3880 MB/s) to deplete free headroom (98867K) (margin of error = 1.81) [2025-11-28T09:15:08.493Z] [2.513s][info][gc] GC(17) Concurrent reset (Young) 0.392ms [2025-11-28T09:15:08.493Z] [2.524s][info][gc] GC(17) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [2.524s][info][gc] GC(17) Pause Init Mark (Young) 3.372ms [2025-11-28T09:15:08.493Z] Allocated: 758 Mb [2025-11-28T09:15:08.493Z] [2.524s][info][gc] GC(17) Concurrent remembered set scanning 0.213ms [2025-11-28T09:15:08.493Z] [2.525s][info][gc] GC(17) Concurrent marking roots 0.201ms [2025-11-28T09:15:08.493Z] [2.525s][info][gc] GC(17) Concurrent marking (Young) 0.176ms [2025-11-28T09:15:08.493Z] [2.528s][info][gc] GC(17) Verify Before Evacuation, Level 4 (25 reachable, 5 marked) [2025-11-28T09:15:08.493Z] [2.528s][info][gc] GC(17) Pause Final Mark (Young) 2.999ms [2025-11-28T09:15:08.493Z] [2.531s][info][gc] GC(17) Concurrent thread roots 0.091ms [2025-11-28T09:15:08.493Z] [2.531s][info][gc] GC(17) Concurrent weak references (Young) 0.032ms [2025-11-28T09:15:08.493Z] Allocated: 724 Mb [2025-11-28T09:15:08.493Z] Allocated: 710 Mb [2025-11-28T09:15:08.493Z] [2.538s][info][gc] GC(17) Concurrent weak roots (Young) 7.042ms [2025-11-28T09:15:08.493Z] [2.539s][info][gc] GC(17) Concurrent cleanup (Young) 829M->829M(1024M) 0.087ms [2025-11-28T09:15:08.493Z] [2.539s][info][gc] GC(17) Concurrent strong roots 0.206ms [2025-11-28T09:15:08.493Z] [2.539s][info][gc] GC(17) Concurrent evacuation 0.162ms [2025-11-28T09:15:08.493Z] [2.540s][info][gc] GC(17) Concurrent Init Update Refs (Young) 0.639ms [2025-11-28T09:15:08.493Z] Allocated: 674 Mb [2025-11-28T09:15:08.493Z] [2.543s][info][gc] GC(17) Verify Before Updating References, Level 4 (22 reachable, 26 marked) [2025-11-28T09:15:08.493Z] [2.543s][info][gc] GC(17) Pause Init Update Refs 3.190ms [2025-11-28T09:15:08.493Z] [2.549s][info][gc] GC(17) Concurrent update references 3.520ms [2025-11-28T09:15:08.493Z] Allocated: 717 Mb [2025-11-28T09:15:08.493Z] [2.553s][info][gc] Failed to allocate Shared, 54018K [2025-11-28T09:15:08.493Z] [2.553s][info][gc] GC(17) Concurrent update thread roots 4.309ms [2025-11-28T09:15:08.493Z] [2.553s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.493Z] [2.562s][info][gc] GC(18) Verify Before Full GC, Level 4 (34344 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [2.760s][info][gc] GC(18) Verify After Full GC, Level 4 (34240 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [2.760s][info][gc] GC(18) Pause Full 872M->661M(1024M) 206.667ms [2025-11-28T09:15:08.493Z] Allocated: 706 Mb [2025-11-28T09:15:08.493Z] Allocated: 682 Mb [2025-11-28T09:15:08.493Z] Allocated: 703 Mb [2025-11-28T09:15:08.493Z] Allocated: 680 Mb [2025-11-28T09:15:08.493Z] [2.778s][info][gc] Trigger (Young): Average GC time (61.57 ms) is above the time for average allocation rate (1735 MB/s) to deplete free headroom (104M) (margin of error = 1.81) [2025-11-28T09:15:08.493Z] [2.779s][info][gc] GC(19) Concurrent reset (Young) 0.816ms [2025-11-28T09:15:08.493Z] [2.789s][info][gc] GC(19) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [2.789s][info][gc] GC(19) Pause Init Mark (Young) 3.477ms [2025-11-28T09:15:08.493Z] [2.789s][info][gc] GC(19) Concurrent remembered set scanning 0.192ms [2025-11-28T09:15:08.493Z] [2.790s][info][gc] GC(19) Concurrent marking roots 0.206ms [2025-11-28T09:15:08.493Z] Allocated: 726 Mb [2025-11-28T09:15:08.493Z] Allocated: 709 Mb [2025-11-28T09:15:08.493Z] Allocated: 721 Mb [2025-11-28T09:15:08.493Z] [2.798s][info][gc] GC(19) Concurrent marking (Young) 8.804ms [2025-11-28T09:15:08.493Z] [2.803s][info][gc] GC(19) Verify After Mark, Level 4 (25 reachable, 15 marked) [2025-11-28T09:15:08.493Z] [2.803s][info][gc] GC(19) Pause Final Mark (Young) 4.028ms [2025-11-28T09:15:08.493Z] [2.806s][info][gc] GC(19) Concurrent weak references (Young) 0.025ms [2025-11-28T09:15:08.493Z] Allocated: 725 Mb [2025-11-28T09:15:08.493Z] [2.808s][info][gc] GC(19) Concurrent weak roots (Young) 2.169ms [2025-11-28T09:15:08.493Z] [2.809s][info][gc] GC(19) Concurrent cleanup (Young) 884M->884M(1024M) 0.057ms [2025-11-28T09:15:08.493Z] Allocated: 733 Mb [2025-11-28T09:15:08.493Z] [2.819s][info][gc] GC(19) Concurrent Final Roots (Young) 10.767ms [2025-11-28T09:15:08.493Z] [2.828s][info][gc] GC(19) Concurrent reset after collect (Young) 9.015ms [2025-11-28T09:15:08.493Z] [2.829s][info][gc] Trigger (Young): Free (35840K) is below minimum threshold (102M) [2025-11-28T09:15:08.493Z] [2.830s][info][gc] GC(20) Concurrent reset (Young) 0.423ms [2025-11-28T09:15:08.493Z] Allocated: 747 Mb [2025-11-28T09:15:08.493Z] [2.835s][info][gc] GC(20) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [2.835s][info][gc] GC(20) Pause Init Mark (Young) 3.367ms [2025-11-28T09:15:08.493Z] Allocated: 663 Mb [2025-11-28T09:15:08.493Z] [2.838s][info][gc] GC(20) Concurrent remembered set scanning 0.249ms [2025-11-28T09:15:08.493Z] [2.838s][info][gc] GC(20) Concurrent marking roots 0.218ms [2025-11-28T09:15:08.493Z] [2.838s][info][gc] GC(20) Concurrent marking (Young) 0.236ms [2025-11-28T09:15:08.493Z] [2.842s][info][gc] GC(20) Verify After Mark, Level 4 (22 reachable, 7 marked) [2025-11-28T09:15:08.493Z] [2.842s][info][gc] GC(20) Pause Final Mark (Young) 3.464ms [2025-11-28T09:15:08.493Z] [2.842s][info][gc] Failed to allocate Shared, 58945K [2025-11-28T09:15:08.493Z] [2.842s][info][gc] GC(20) Concurrent weak references (Young) 0.015ms [2025-11-28T09:15:08.493Z] [2.842s][info][gc] GC(20) Concurrent weak roots (Young) 0.074ms [2025-11-28T09:15:08.493Z] [2.842s][info][gc] GC(20) Concurrent cleanup (Young) 671M->671M(1024M) 0.077ms [2025-11-28T09:15:08.493Z] [2.842s][info][gc] GC(20) Concurrent Final Roots (Young) 0.055ms [2025-11-28T09:15:08.493Z] [2.846s][info][gc] GC(20) Concurrent reset after collect (Young) 3.550ms [2025-11-28T09:15:08.493Z] [2.846s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.493Z] [2.854s][info][gc] GC(21) Verify Before Full GC, Level 4 (34344 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [3.080s][info][gc] GC(21) Verify After Full GC, Level 4 (34240 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [3.080s][info][gc] GC(21) Pause Full 671M->670M(1024M) 233.515ms [2025-11-28T09:15:08.493Z] Allocated: 721 Mb [2025-11-28T09:15:08.493Z] Allocated: 740 Mb [2025-11-28T09:15:08.493Z] Allocated: 666 Mb [2025-11-28T09:15:08.493Z] Allocated: 676 Mb [2025-11-28T09:15:08.493Z] Allocated: 690 Mb [2025-11-28T09:15:08.493Z] [3.105s][info][gc] Trigger (Young): Average GC time (59.11 ms) is above the time for average allocation rate (1372 MB/s) to deplete free headroom (59443K) (margin of error = 1.83) [2025-11-28T09:15:08.493Z] [3.106s][info][gc] GC(22) Concurrent reset (Young) 0.450ms [2025-11-28T09:15:08.493Z] [3.114s][info][gc] GC(22) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.493Z] [3.114s][info][gc] GC(22) Pause Init Mark (Young) 3.426ms [2025-11-28T09:15:08.493Z] Allocated: 716 Mb [2025-11-28T09:15:08.493Z] [3.114s][info][gc] GC(22) Concurrent remembered set scanning 0.187ms [2025-11-28T09:15:08.493Z] [3.114s][info][gc] GC(22) Concurrent marking roots 0.194ms [2025-11-28T09:15:08.493Z] [3.115s][info][gc] GC(22) Concurrent marking (Young) 0.235ms [2025-11-28T09:15:08.493Z] [3.119s][info][gc] GC(22) Verify After Mark, Level 4 (23 reachable, 5 marked) [2025-11-28T09:15:08.493Z] [3.119s][info][gc] GC(22) Pause Final Mark (Young) 4.506ms [2025-11-28T09:15:08.493Z] [3.123s][info][gc] GC(22) Concurrent weak references (Young) 0.022ms [2025-11-28T09:15:08.493Z] Allocated: 695 Mb [2025-11-28T09:15:08.493Z] [3.128s][info][gc] GC(22) Concurrent weak roots (Young) 4.345ms [2025-11-28T09:15:08.493Z] [3.128s][info][gc] GC(22) Concurrent cleanup (Young) 788M->788M(1024M) 0.075ms [2025-11-28T09:15:08.493Z] [3.131s][info][gc] GC(22) Concurrent Final Roots (Young) 3.311ms [2025-11-28T09:15:08.493Z] Allocated: 717 Mb [2025-11-28T09:15:08.493Z] [3.139s][info][gc] GC(22) Concurrent reset after collect (Young) 7.971ms [2025-11-28T09:15:08.493Z] [3.139s][info][gc] Trigger (Young): Average GC time (52.62 ms) is above the time for average allocation rate (3464 MB/s) to deplete free headroom (106M) (margin of error = 1.83) [2025-11-28T09:15:08.494Z] [3.140s][info][gc] GC(23) Concurrent reset (Young) 0.421ms [2025-11-28T09:15:08.494Z] [3.143s][info][gc] GC(23) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.143s][info][gc] GC(23) Pause Init Mark (Young) 3.293ms [2025-11-28T09:15:08.494Z] Allocated: 707 Mb [2025-11-28T09:15:08.494Z] [3.144s][info][gc] GC(23) Concurrent remembered set scanning 0.221ms [2025-11-28T09:15:08.494Z] [3.144s][info][gc] GC(23) Concurrent marking roots 0.199ms [2025-11-28T09:15:08.494Z] [3.144s][info][gc] GC(23) Concurrent marking (Young) 0.182ms [2025-11-28T09:15:08.494Z] [3.149s][info][gc] GC(23) Verify After Mark, Level 4 (24 reachable, 4 marked) [2025-11-28T09:15:08.494Z] [3.149s][info][gc] GC(23) Pause Final Mark (Young) 4.747ms [2025-11-28T09:15:08.494Z] [3.152s][info][gc] GC(23) Concurrent weak references (Young) 0.024ms [2025-11-28T09:15:08.494Z] [3.153s][info][gc] GC(23) Concurrent weak roots (Young) 0.853ms [2025-11-28T09:15:08.494Z] [3.153s][info][gc] GC(23) Concurrent cleanup (Young) 738M->738M(1024M) 0.041ms [2025-11-28T09:15:08.494Z] [3.153s][info][gc] GC(23) Concurrent Final Roots (Young) 0.173ms [2025-11-28T09:15:08.494Z] Allocated: 698 Mb [2025-11-28T09:15:08.494Z] [3.160s][info][gc] GC(23) Concurrent reset after collect (Young) 6.979ms [2025-11-28T09:15:08.494Z] [3.161s][info][gc] Trigger (Young): Average GC time (44.12 ms) is above the time for average allocation rate (3115 MB/s) to deplete free headroom (130M) (margin of error = 1.83) [2025-11-28T09:15:08.494Z] [3.162s][info][gc] GC(24) Concurrent reset (Young) 0.398ms [2025-11-28T09:15:08.494Z] [3.170s][info][gc] GC(24) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.170s][info][gc] GC(24) Pause Init Mark (Young) 3.271ms [2025-11-28T09:15:08.494Z] Allocated: 751 Mb [2025-11-28T09:15:08.494Z] [3.171s][info][gc] GC(24) Concurrent remembered set scanning 0.201ms [2025-11-28T09:15:08.494Z] [3.171s][info][gc] GC(24) Concurrent marking roots 0.186ms [2025-11-28T09:15:08.494Z] [3.171s][info][gc] GC(24) Concurrent marking (Young) 0.229ms [2025-11-28T09:15:08.494Z] [3.175s][info][gc] GC(24) Verify Before Evacuation, Level 4 (25 reachable, 5 marked) [2025-11-28T09:15:08.494Z] [3.175s][info][gc] GC(24) Pause Final Mark (Young) 3.947ms [2025-11-28T09:15:08.494Z] [3.175s][info][gc] Failed to allocate Shared, 62450K [2025-11-28T09:15:08.494Z] [3.176s][info][gc] GC(24) Concurrent thread roots 0.061ms [2025-11-28T09:15:08.494Z] [3.176s][info][gc] GC(24) Concurrent weak references (Young) 0.012ms [2025-11-28T09:15:08.494Z] [3.176s][info][gc] GC(24) Concurrent weak roots (Young) 0.166ms [2025-11-28T09:15:08.494Z] [3.176s][info][gc] GC(24) Concurrent cleanup (Young) 758M->758M(1024M) 0.024ms [2025-11-28T09:15:08.494Z] [3.176s][info][gc] GC(24) Concurrent strong roots 0.124ms [2025-11-28T09:15:08.494Z] [3.176s][info][gc] GC(24) Concurrent evacuation 0.011ms [2025-11-28T09:15:08.494Z] [3.176s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.494Z] [3.184s][info][gc] GC(25) Verify Before Full GC, Level 4 (34345 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.373s][info][gc] GC(25) Verify After Full GC, Level 4 (34241 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.373s][info][gc] GC(25) Pause Full 758M->693M(1024M) 196.995ms [2025-11-28T09:15:08.494Z] Allocated: 748 Mb [2025-11-28T09:15:08.494Z] Allocated: 727 Mb [2025-11-28T09:15:08.494Z] Allocated: 711 Mb [2025-11-28T09:15:08.494Z] Allocated: 689 Mb [2025-11-28T09:15:08.494Z] Allocated: 691 Mb [2025-11-28T09:15:08.494Z] Allocated: 736 Mb [2025-11-28T09:15:08.494Z] [3.401s][info][gc] Trigger (Young): Free (76543K) is below minimum threshold (102M) [2025-11-28T09:15:08.494Z] [3.402s][info][gc] GC(26) Concurrent reset (Young) 0.814ms [2025-11-28T09:15:08.494Z] [3.409s][info][gc] GC(26) Verify Before Mark, Level 4 (20 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.409s][info][gc] GC(26) Pause Init Mark (Young) 3.417ms [2025-11-28T09:15:08.494Z] [3.409s][info][gc] GC(26) Concurrent remembered set scanning 0.178ms [2025-11-28T09:15:08.494Z] Allocated: 692 Mb [2025-11-28T09:15:08.494Z] [3.412s][info][gc] GC(26) Concurrent marking roots 3.536ms [2025-11-28T09:15:08.494Z] Allocated: 734 Mb [2025-11-28T09:15:08.494Z] [3.416s][info][gc] GC(26) Concurrent marking (Young) 3.623ms [2025-11-28T09:15:08.494Z] [3.422s][info][gc] GC(26) Verify Before Evacuation, Level 4 (20 reachable, 12 marked) [2025-11-28T09:15:08.494Z] [3.422s][info][gc] GC(26) Pause Final Mark (Young) 3.549ms [2025-11-28T09:15:08.494Z] Allocated: 676 Mb [2025-11-28T09:15:08.494Z] [3.422s][info][gc] GC(26) Concurrent thread roots 0.070ms [2025-11-28T09:15:08.494Z] [3.422s][info][gc] GC(26) Concurrent weak references (Young) 0.024ms [2025-11-28T09:15:08.494Z] [3.422s][info][gc] GC(26) Concurrent weak roots (Young) 0.107ms [2025-11-28T09:15:08.494Z] [3.422s][info][gc] GC(26) Concurrent cleanup (Young) 753M->753M(1024M) 0.062ms [2025-11-28T09:15:08.494Z] [3.423s][info][gc] GC(26) Concurrent strong roots 0.158ms [2025-11-28T09:15:08.494Z] [3.423s][info][gc] GC(26) Concurrent evacuation 0.150ms [2025-11-28T09:15:08.494Z] [3.423s][info][gc] GC(26) Concurrent Init Update Refs (Young) 0.049ms [2025-11-28T09:15:08.494Z] [3.425s][info][gc] GC(26) Verify Before Updating References, Level 4 (21 reachable, 27 marked) [2025-11-28T09:15:08.494Z] [3.425s][info][gc] GC(26) Pause Init Update Refs 2.010ms [2025-11-28T09:15:08.494Z] [3.432s][info][gc] GC(26) Concurrent update references 3.356ms [2025-11-28T09:15:08.494Z] Allocated: 722 Mb [2025-11-28T09:15:08.494Z] [3.436s][info][gc] GC(26) Concurrent update thread roots 4.296ms [2025-11-28T09:15:08.494Z] [3.448s][info][gc] GC(26) Verify After Updating References, Level 4 (20 reachable, 24 marked) [2025-11-28T09:15:08.494Z] [3.448s][info][gc] GC(26) Pause Final Update Refs 3.863ms [2025-11-28T09:15:08.494Z] Allocated: 706 Mb [2025-11-28T09:15:08.494Z] [3.449s][info][gc] Failed to allocate Shared, 60005K [2025-11-28T09:15:08.494Z] [3.449s][info][gc] GC(26) Concurrent cleanup (Young) 849M->849M(1024M) 0.038ms [2025-11-28T09:15:08.494Z] [3.453s][info][gc] GC(26) Concurrent reset after collect (Young) 3.828ms [2025-11-28T09:15:08.494Z] [3.453s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.494Z] [3.461s][info][gc] GC(27) Verify Before Full GC, Level 4 (34342 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.656s][info][gc] GC(27) Verify After Full GC, Level 4 (34238 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.656s][info][gc] GC(27) Pause Full 849M->683M(1024M) 203.004ms [2025-11-28T09:15:08.494Z] Allocated: 736 Mb [2025-11-28T09:15:08.494Z] Allocated: 679 Mb [2025-11-28T09:15:08.494Z] Allocated: 704 Mb [2025-11-28T09:15:08.494Z] [3.671s][info][gc] Trigger (Young): Average GC time (64.90 ms) is above the time for average allocation rate (1579 MB/s) to deplete free headroom (97331K) (margin of error = 1.83) [2025-11-28T09:15:08.494Z] [3.671s][info][gc] GC(28) Concurrent reset (Young) 0.461ms [2025-11-28T09:15:08.494Z] [3.682s][info][gc] GC(28) Verify Before Mark, Level 4 (21 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.682s][info][gc] GC(28) Pause Init Mark (Young) 3.499ms [2025-11-28T09:15:08.494Z] Allocated: 733 Mb [2025-11-28T09:15:08.494Z] [3.684s][info][gc] GC(28) Concurrent remembered set scanning 0.243ms [2025-11-28T09:15:08.494Z] [3.684s][info][gc] GC(28) Concurrent marking roots 0.226ms [2025-11-28T09:15:08.494Z] [3.693s][info][gc] GC(28) Concurrent marking (Young) 9.179ms [2025-11-28T09:15:08.494Z] [3.700s][info][gc] GC(28) Verify Before Evacuation, Level 4 (22 reachable, 7 marked) [2025-11-28T09:15:08.494Z] [3.700s][info][gc] GC(28) Pause Final Mark (Young) 6.625ms [2025-11-28T09:15:08.494Z] Allocated: 736 Mb [2025-11-28T09:15:08.494Z] [3.701s][info][gc] GC(28) Concurrent thread roots 0.077ms [2025-11-28T09:15:08.494Z] [3.701s][info][gc] GC(28) Concurrent weak references (Young) 0.013ms [2025-11-28T09:15:08.494Z] [3.701s][info][gc] GC(28) Concurrent weak roots (Young) 0.078ms [2025-11-28T09:15:08.494Z] [3.701s][info][gc] GC(28) Concurrent cleanup (Young) 744M->744M(1024M) 0.062ms [2025-11-28T09:15:08.494Z] [3.701s][info][gc] GC(28) Concurrent strong roots 0.137ms [2025-11-28T09:15:08.494Z] [3.701s][info][gc] GC(28) Concurrent evacuation 0.153ms [2025-11-28T09:15:08.494Z] [3.701s][info][gc] GC(28) Concurrent Init Update Refs (Young) 0.047ms [2025-11-28T09:15:08.494Z] [3.704s][info][gc] GC(28) Verify Before Updating References, Level 4 (22 reachable, 20 marked) [2025-11-28T09:15:08.494Z] [3.704s][info][gc] GC(28) Pause Init Update Refs 2.440ms [2025-11-28T09:15:08.494Z] [3.709s][info][gc] GC(28) Concurrent update references 2.219ms [2025-11-28T09:15:08.494Z] Allocated: 712 Mb [2025-11-28T09:15:08.494Z] [3.712s][info][gc] GC(28) Concurrent update thread roots 2.130ms [2025-11-28T09:15:08.494Z] [3.718s][info][gc] GC(28) Verify After Updating References, Level 4 (21 reachable, 19 marked) [2025-11-28T09:15:08.494Z] [3.718s][info][gc] GC(28) Pause Final Update Refs 3.403ms [2025-11-28T09:15:08.494Z] [3.718s][info][gc] GC(28) Concurrent cleanup (Young) 795M->795M(1024M) 0.016ms [2025-11-28T09:15:08.494Z] [3.722s][info][gc] GC(28) Concurrent reset after collect (Young) 3.680ms [2025-11-28T09:15:08.494Z] Allocated: 709 Mb [2025-11-28T09:15:08.494Z] Allocated: 672 Mb [2025-11-28T09:15:08.494Z] [3.727s][info][gc] Trigger (Young): Average GC time (66.59 ms) is above the time for average allocation rate (3843 MB/s) to deplete free headroom (90419K) (margin of error = 1.82) [2025-11-28T09:15:08.494Z] [3.728s][info][gc] GC(29) Concurrent reset (Young) 0.403ms [2025-11-28T09:15:08.494Z] [3.732s][info][gc] GC(29) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.494Z] [3.732s][info][gc] GC(29) Pause Init Mark (Young) 3.190ms [2025-11-28T09:15:08.494Z] Allocated: 695 Mb [2025-11-28T09:15:08.494Z] [3.732s][info][gc] GC(29) Concurrent remembered set scanning 0.177ms [2025-11-28T09:15:08.494Z] [3.733s][info][gc] GC(29) Concurrent marking roots 0.201ms [2025-11-28T09:15:08.494Z] [3.733s][info][gc] GC(29) Concurrent marking (Young) 0.210ms [2025-11-28T09:15:08.494Z] [3.737s][info][gc] GC(29) Verify Before Evacuation, Level 4 (23 reachable, 5 marked) [2025-11-28T09:15:08.494Z] [3.737s][info][gc] GC(29) Pause Final Mark (Young) 4.119ms [2025-11-28T09:15:08.494Z] Allocated: 699 Mb [2025-11-28T09:15:08.494Z] [3.738s][info][gc] GC(29) Concurrent thread roots 0.062ms [2025-11-28T09:15:08.494Z] [3.738s][info][gc] GC(29) Concurrent weak references (Young) 0.013ms [2025-11-28T09:15:08.495Z] [3.738s][info][gc] GC(29) Concurrent weak roots (Young) 0.167ms [2025-11-28T09:15:08.495Z] [3.738s][info][gc] GC(29) Concurrent cleanup (Young) 734M->734M(1024M) 0.037ms [2025-11-28T09:15:08.495Z] [3.738s][info][gc] GC(29) Concurrent strong roots 0.127ms [2025-11-28T09:15:08.495Z] [3.739s][info][gc] GC(29) Concurrent evacuation 0.058ms [2025-11-28T09:15:08.495Z] Allocated: 726 Mb [2025-11-28T09:15:08.495Z] [3.744s][info][gc] GC(29) Concurrent Init Update Refs (Young) 5.378ms [2025-11-28T09:15:08.495Z] [3.754s][info][gc] GC(29) Verify Before Updating References, Level 4 (23 reachable, 19 marked) [2025-11-28T09:15:08.495Z] [3.754s][info][gc] GC(29) Pause Init Update Refs 3.315ms [2025-11-28T09:15:08.495Z] Allocated: 713 Mb [2025-11-28T09:15:08.495Z] Allocated: 697 Mb [2025-11-28T09:15:08.495Z] [3.758s][info][gc] GC(29) Concurrent update references 3.590ms [2025-11-28T09:15:08.495Z] [3.758s][info][gc] GC(29) Concurrent update thread roots 0.176ms [2025-11-28T09:15:08.495Z] [3.760s][info][gc] GC(29) Verify After Updating References, Level 4 (24 reachable, 26 marked) [2025-11-28T09:15:08.495Z] [3.761s][info][gc] GC(29) Pause Final Up [2025-11-28T09:15:08.495Z] [2025-11-28T09:15:08.495Z] ... [2025-11-28T09:15:08.495Z] Output overflow: [2025-11-28T09:15:08.495Z] JT Harness has limited the test output to the text [2025-11-28T09:15:08.495Z] at the beginning and the end, so that you can see how the [2025-11-28T09:15:08.495Z] test began, and how it completed. [2025-11-28T09:15:08.495Z] [2025-11-28T09:15:08.495Z] If you need to see more of the output from the test, [2025-11-28T09:15:08.495Z] set the system property javatest.maxOutputSize to a higher [2025-11-28T09:15:08.495Z] value. The current value is 100000 [2025-11-28T09:15:08.495Z] ... [2025-11-28T09:15:08.495Z] [2025-11-28T09:15:08.495Z] 6.951s][info][gc] GC(142) Pause Full 790M->690M(1024M) 234.850ms [2025-11-28T09:15:08.495Z] Allocated: 726 Mb [2025-11-28T09:15:08.495Z] Allocated: 733 Mb [2025-11-28T09:15:08.495Z] Allocated: 719 Mb [2025-11-28T09:15:08.495Z] Allocated: 707 Mb [2025-11-28T09:15:08.495Z] [16.976s][info][gc] Trigger (Young): Average GC time (57.58 ms) is above the time for average allocation rate (1381 MB/s) to deplete free headroom (71731K) (margin of error = 1.89) [2025-11-28T09:15:08.495Z] [16.977s][info][gc] GC(143) Concurrent reset (Young) 0.473ms [2025-11-28T09:15:08.495Z] [16.982s][info][gc] GC(143) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T09:15:08.495Z] [16.983s][info][gc] GC(143) Pause Init Mark (Young) 3.589ms [2025-11-28T09:15:08.495Z] Allocated: 703 Mb [2025-11-28T09:15:08.495Z] [16.984s][info][gc] GC(143) Concurrent remembered set scanning 0.216ms [2025-11-28T09:15:08.495Z] [16.984s][info][gc] GC(143) Concurrent marking roots 0.251ms [2025-11-28T09:15:08.495Z] Allocated: 734 Mb [2025-11-28T09:15:08.495Z] [16.991s][info][gc] GC(143) Concurrent marking (Young) 6.531ms [2025-11-28T09:15:08.495Z] [16.996s][info][gc] GC(143) Verify Before Evacuation, Level 4 (24 reachable, 9 marked) [2025-11-28T09:15:08.495Z] [16.996s][info][gc] GC(143) Pause Final Mark (Young) 4.840ms [2025-11-28T09:15:08.495Z] Allocated: 675 Mb [2025-11-28T09:15:08.495Z] [16.997s][info][gc] GC(143) Concurrent thread roots 0.068ms [2025-11-28T09:15:08.495Z] [16.997s][info][gc] GC(143) Concurrent weak references (Young) 0.011ms [2025-11-28T09:15:08.495Z] [16.997s][info][gc] GC(143) Concurrent weak roots (Young) 0.062ms [2025-11-28T09:15:08.495Z] [16.997s][info][gc] GC(143) Concurrent cleanup (Young) 748M->748M(1024M) 0.048ms [2025-11-28T09:15:08.495Z] [16.997s][info][gc] GC(143) Concurrent strong roots 0.177ms [2025-11-28T09:15:08.495Z] [16.997s][info][gc] GC(143) Concurrent evacuation 0.135ms [2025-11-28T09:15:08.495Z] [16.997s][info][gc] GC(143) Concurrent Init Update Refs (Young) 0.032ms [2025-11-28T09:15:08.495Z] [17.000s][info][gc] GC(143) Verify Before Updating References, Level 4 (24 reachable, 26 marked) [2025-11-28T09:15:08.495Z] [17.000s][info][gc] GC(143) Pause Init Update Refs 2.663ms [2025-11-28T09:15:08.495Z] [17.005s][info][gc] GC(143) Concurrent update references 2.193ms [2025-11-28T09:15:08.495Z] Allocated: 700 Mb [2025-11-28T09:15:08.495Z] [17.008s][info][gc] GC(143) Concurrent update thread roots 2.160ms [2025-11-28T09:15:08.495Z] [17.018s][info][gc] GC(143) Verify After Updating References, Level 4 (24 reachable, 22 marked) [2025-11-28T09:15:08.495Z] [17.018s][info][gc] GC(143) Pause Final Update Refs 3.789ms [2025-11-28T09:15:08.495Z] [17.018s][info][gc] GC(143) Concurrent cleanup (Young) 810M->810M(1024M) 0.013ms [2025-11-28T09:15:08.495Z] Allocated: 724 Mb [2025-11-28T09:15:08.495Z] [17.025s][info][gc] GC(143) Concurrent reset after collect (Young) 7.267ms [2025-11-28T09:15:08.495Z] [17.026s][info][gc] Trigger (Young): Average GC time (55.83 ms) is above the time for average allocation rate (3178 MB/s) to deplete free headroom (66611K) (margin of error = 1.89) [2025-11-28T09:15:08.495Z] [17.027s][info][gc] GC(144) Concurrent reset (Young) 0.422ms [2025-11-28T09:15:08.495Z] [17.035s][info][gc] GC(144) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T09:15:08.495Z] [17.035s][info][gc] GC(144) Pause Init Mark (Young) 3.475ms [2025-11-28T09:15:08.495Z] Allocated: 728 Mb [2025-11-28T09:15:08.495Z] [17.035s][info][gc] GC(144) Concurrent remembered set scanning 0.171ms [2025-11-28T09:15:08.495Z] [17.035s][info][gc] GC(144) Concurrent marking roots 0.211ms [2025-11-28T09:15:08.495Z] [17.036s][info][gc] GC(144) Concurrent marking (Young) 0.218ms [2025-11-28T09:15:08.495Z] [17.039s][info][gc] GC(144) Verify Before Evacuation, Level 4 (25 reachable, 5 marked) [2025-11-28T09:15:08.495Z] [17.039s][info][gc] GC(144) Pause Final Mark (Young) 3.005ms [2025-11-28T09:15:08.495Z] [17.039s][info][gc] GC(144) Concurrent thread roots 0.089ms [2025-11-28T09:15:08.495Z] [17.039s][info][gc] GC(144) Concurrent weak references (Young) 0.279ms [2025-11-28T09:15:08.495Z] [17.040s][info][gc] GC(144) Concurrent weak roots (Young) 0.985ms [2025-11-28T09:15:08.495Z] [17.040s][info][gc] GC(144) Concurrent cleanup (Young) 742M->742M(1024M) 0.043ms [2025-11-28T09:15:08.495Z] [17.040s][info][gc] GC(144) Concurrent strong roots 0.170ms [2025-11-28T09:15:08.495Z] [17.040s][info][gc] GC(144) Concurrent evacuation 0.058ms [2025-11-28T09:15:08.495Z] Allocated: 704 Mb [2025-11-28T09:15:08.495Z] [17.041s][info][gc] GC(144) Concurrent Init Update Refs (Young) 0.131ms [2025-11-28T09:15:08.495Z] [17.043s][info][gc] GC(144) Verify Before Updating References, Level 4 (25 reachable, 13 marked) [2025-11-28T09:15:08.495Z] [17.043s][info][gc] GC(144) Pause Init Update Refs 2.889ms [2025-11-28T09:15:08.495Z] Allocated: 671 Mb [2025-11-28T09:15:08.495Z] [17.049s][info][gc] GC(144) Concurrent update references 4.435ms [2025-11-28T09:15:08.495Z] Allocated: 702 Mb [2025-11-28T09:15:08.495Z] [17.053s][info][gc] Failed to allocate Shared, 64848K [2025-11-28T09:15:08.495Z] [17.054s][info][gc] GC(144) Concurrent update thread roots 4.321ms [2025-11-28T09:15:08.495Z] [17.054s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.495Z] [17.062s][info][gc] GC(145) Verify Before Full GC, Level 4 (34347 reachable, 0 marked) [2025-11-28T09:15:08.495Z] [17.208s][info][gc] GC(145) Verify After Full GC, Level 4 (34243 reachable, 0 marked) [2025-11-28T09:15:08.495Z] [17.208s][info][gc] GC(145) Pause Full 782M->659M(1024M) 154.179ms [2025-11-28T09:15:08.495Z] Allocated: 714 Mb [2025-11-28T09:15:08.495Z] Allocated: 695 Mb [2025-11-28T09:15:08.495Z] Allocated: 729 Mb [2025-11-28T09:15:08.495Z] Allocated: 695 Mb [2025-11-28T09:15:08.495Z] Allocated: 710 Mb [2025-11-28T09:15:08.495Z] Allocated: 683 Mb [2025-11-28T09:15:08.495Z] Allocated: 701 Mb [2025-11-28T09:15:08.500Z] Allocated: 682 Mb [2025-11-28T09:15:08.500Z] [17.239s][info][gc] Trigger (Young): Free (88320K) is below minimum threshold (102M) [2025-11-28T09:15:08.500Z] [17.239s][info][gc] GC(146) Concurrent reset (Young) 0.520ms [2025-11-28T09:15:08.500Z] [17.250s][info][gc] GC(146) Verify Before Mark, Level 4 (29 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [17.250s][info][gc] GC(146) Pause Init Mark (Young) 3.605ms [2025-11-28T09:15:08.500Z] Allocated: 723 Mb [2025-11-28T09:15:08.500Z] [17.251s][info][gc] GC(146) Concurrent remembered set scanning 0.169ms [2025-11-28T09:15:08.500Z] [17.251s][info][gc] GC(146) Concurrent marking roots 0.187ms [2025-11-28T09:15:08.500Z] [17.251s][info][gc] GC(146) Concurrent marking (Young) 0.231ms [2025-11-28T09:15:08.500Z] [17.256s][info][gc] GC(146) Verify Before Evacuation, Level 4 (30 reachable, 5 marked) [2025-11-28T09:15:08.500Z] [17.256s][info][gc] GC(146) Pause Final Mark (Young) 4.659ms [2025-11-28T09:15:08.500Z] [17.256s][info][gc] GC(146) Concurrent thread roots 0.084ms [2025-11-28T09:15:08.500Z] [17.257s][info][gc] GC(146) Concurrent weak references (Young) 0.944ms [2025-11-28T09:15:08.500Z] [17.267s][info][gc] GC(146) Concurrent weak roots (Young) 9.711ms [2025-11-28T09:15:08.500Z] [17.267s][info][gc] GC(146) Concurrent cleanup (Young) 796M->796M(1024M) 0.099ms [2025-11-28T09:15:08.500Z] [17.267s][info][gc] GC(146) Concurrent strong roots 0.241ms [2025-11-28T09:15:08.500Z] [17.268s][info][gc] GC(146) Concurrent evacuation 0.206ms [2025-11-28T09:15:08.500Z] Allocated: 714 Mb [2025-11-28T09:15:08.500Z] [17.268s][info][gc] GC(146) Concurrent Init Update Refs (Young) 0.139ms [2025-11-28T09:15:08.500Z] [17.271s][info][gc] GC(146) Verify Before Updating References, Level 4 (29 reachable, 19 marked) [2025-11-28T09:15:08.500Z] [17.271s][info][gc] GC(146) Pause Init Update Refs 3.364ms [2025-11-28T09:15:08.500Z] [17.278s][info][gc] GC(146) Concurrent update references 4.468ms [2025-11-28T09:15:08.500Z] Allocated: 742 Mb [2025-11-28T09:15:08.500Z] [17.281s][info][gc] GC(146) Concurrent update thread roots 3.191ms [2025-11-28T09:15:08.500Z] [17.291s][info][gc] GC(146) Verify After Updating References, Level 4 (28 reachable, 20 marked) [2025-11-28T09:15:08.500Z] [17.291s][info][gc] GC(146) Pause Final Update Refs 3.903ms [2025-11-28T09:15:08.500Z] Allocated: 726 Mb [2025-11-28T09:15:08.500Z] [17.291s][info][gc] GC(146) Concurrent cleanup (Young) 869M->869M(1024M) 0.021ms [2025-11-28T09:15:08.500Z] Allocated: 713 Mb [2025-11-28T09:15:08.500Z] [17.298s][info][gc] GC(146) Concurrent reset after collect (Young) 7.338ms [2025-11-28T09:15:08.500Z] [17.300s][info][gc] Trigger (Young): Free (73984K) is below minimum threshold (102M) [2025-11-28T09:15:08.500Z] [17.301s][info][gc] GC(147) Concurrent reset (Young) 0.434ms [2025-11-28T09:15:08.500Z] [17.305s][info][gc] GC(147) Verify Before Mark, Level 4 (28 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [17.305s][info][gc] GC(147) Pause Init Mark (Young) 3.467ms [2025-11-28T09:15:08.500Z] Allocated: 709 Mb [2025-11-28T09:15:08.500Z] [17.305s][info][gc] GC(147) Concurrent remembered set scanning 0.164ms [2025-11-28T09:15:08.500Z] [17.305s][info][gc] GC(147) Concurrent marking roots 0.208ms [2025-11-28T09:15:08.500Z] Allocated: 688 Mb [2025-11-28T09:15:08.500Z] [17.310s][info][gc] Failed to allocate Shared, 62539K [2025-11-28T09:15:08.500Z] [17.310s][info][gc] GC(147) Concurrent marking (Young) 4.899ms [2025-11-28T09:15:08.500Z] [17.310s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.500Z] [17.318s][info][gc] GC(148) Verify Before Full GC, Level 4 (34351 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [17.318s][info][gc] GC(148) Cancel marking: Young [2025-11-28T09:15:08.500Z] [17.318s][info][gc] GC(148) Cancel marking: Old [2025-11-28T09:15:08.500Z] [17.318s][info][gc] GC(148) Cancel marking: Global [2025-11-28T09:15:08.500Z] [17.513s][info][gc] GC(148) Verify After Full GC, Level 4 (34247 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [17.513s][info][gc] GC(148) Pause Full 923M->697M(1024M) 202.292ms [2025-11-28T09:15:08.500Z] Allocated: 749 Mb [2025-11-28T09:15:08.500Z] Allocated: 743 Mb [2025-11-28T09:15:08.500Z] [17.532s][info][gc] Trigger (Young): Free (95999K) is below minimum threshold (102M) [2025-11-28T09:15:08.500Z] [17.533s][info][gc] GC(149) Concurrent reset (Young) 0.467ms [2025-11-28T09:15:08.500Z] [17.547s][info][gc] GC(149) Verify Before Mark, Level 4 (28 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [17.547s][info][gc] GC(149) Pause Init Mark (Young) 3.645ms [2025-11-28T09:15:08.500Z] Allocated: 748 Mb [2025-11-28T09:15:08.500Z] [17.548s][info][gc] GC(149) Concurrent remembered set scanning 0.190ms [2025-11-28T09:15:08.500Z] [17.548s][info][gc] GC(149) Concurrent marking roots 0.195ms [2025-11-28T09:15:08.500Z] [17.548s][info][gc] GC(149) Concurrent marking (Young) 0.210ms [2025-11-28T09:15:08.500Z] [17.551s][info][gc] GC(149) Verify Before Evacuation, Level 4 (29 reachable, 5 marked) [2025-11-28T09:15:08.500Z] [17.551s][info][gc] GC(149) Pause Final Mark (Young) 3.408ms [2025-11-28T09:15:08.500Z] [17.555s][info][gc] GC(149) Concurrent thread roots 0.084ms [2025-11-28T09:15:08.500Z] [17.555s][info][gc] GC(149) Concurrent weak references (Young) 0.066ms [2025-11-28T09:15:08.500Z] Allocated: 738 Mb [2025-11-28T09:15:08.500Z] Allocated: 699 Mb [2025-11-28T09:15:08.500Z] [17.560s][info][gc] GC(149) Concurrent weak roots (Young) 4.346ms [2025-11-28T09:15:08.500Z] [17.560s][info][gc] GC(149) Concurrent cleanup (Young) 823M->823M(1024M) 0.071ms [2025-11-28T09:15:08.500Z] [17.560s][info][gc] GC(149) Concurrent strong roots 0.191ms [2025-11-28T09:15:08.500Z] [17.560s][info][gc] GC(149) Concurrent evacuation 0.200ms [2025-11-28T09:15:08.500Z] Allocated: 719 Mb [2025-11-28T09:15:08.500Z] [17.565s][info][gc] GC(149) Concurrent Init Update Refs (Young) 4.262ms [2025-11-28T09:15:08.500Z] [17.578s][info][gc] GC(149) Verify Before Updating References, Level 4 (24 reachable, 34 marked) [2025-11-28T09:15:08.500Z] [17.578s][info][gc] GC(149) Pause Init Update Refs 3.587ms [2025-11-28T09:15:08.500Z] Allocated: 728 Mb [2025-11-28T09:15:08.500Z] [17.581s][info][gc] GC(149) Concurrent update references 3.491ms [2025-11-28T09:15:08.500Z] Allocated: 697 Mb [2025-11-28T09:15:08.500Z] [17.590s][info][gc] GC(149) Concurrent update thread roots 8.080ms [2025-11-28T09:15:08.500Z] [17.599s][info][gc] GC(149) Verify After Updating References, Level 4 (24 reachable, 38 marked) [2025-11-28T09:15:08.500Z] [17.599s][info][gc] GC(149) Pause Final Update Refs 3.955ms [2025-11-28T09:15:08.500Z] Allocated: 733 Mb [2025-11-28T09:15:08.500Z] [17.599s][info][gc] GC(149) Concurrent cleanup (Young) 952M->952M(1024M) 0.027ms [2025-11-28T09:15:08.500Z] [17.601s][info][gc] Failed to allocate Shared, 58120K [2025-11-28T09:15:08.500Z] [17.604s][info][gc] GC(149) Concurrent reset after collect (Young) 4.542ms [2025-11-28T09:15:08.500Z] [17.604s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.500Z] [17.612s][info][gc] GC(150) Verify Before Full GC, Level 4 (34345 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [17.836s][info][gc] GC(150) Verify After Full GC, Level 4 (34241 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [17.836s][info][gc] GC(150) Pause Full 952M->647M(1024M) 232.458ms [2025-11-28T09:15:08.500Z] Allocated: 697 Mb [2025-11-28T09:15:08.500Z] Allocated: 755 Mb [2025-11-28T09:15:08.500Z] [17.856s][info][gc] Trigger (Young): Average GC time (81.38 ms) is above the time for average allocation rate (1814 MB/s) to deplete free headroom (104M) (margin of error = 1.89) [2025-11-28T09:15:08.500Z] [17.857s][info][gc] GC(151) Concurrent reset (Young) 0.753ms [2025-11-28T09:15:08.500Z] [17.869s][info][gc] GC(151) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [17.869s][info][gc] GC(151) Pause Init Mark (Young) 3.678ms [2025-11-28T09:15:08.500Z] [17.869s][info][gc] GC(151) Concurrent remembered set scanning 0.177ms [2025-11-28T09:15:08.500Z] [17.869s][info][gc] GC(151) Concurrent marking roots 0.206ms [2025-11-28T09:15:08.500Z] Allocated: 752 Mb [2025-11-28T09:15:08.500Z] Allocated: 680 Mb [2025-11-28T09:15:08.500Z] [17.872s][info][gc] GC(151) Concurrent marking (Young) 2.314ms [2025-11-28T09:15:08.500Z] [17.887s][info][gc] GC(151) Verify Before Evacuation, Level 4 (22 reachable, 13 marked) [2025-11-28T09:15:08.500Z] [17.887s][info][gc] GC(151) Pause Final Mark (Young) 7.649ms [2025-11-28T09:15:08.500Z] [17.887s][info][gc] GC(151) Concurrent thread roots 0.088ms [2025-11-28T09:15:08.500Z] [17.887s][info][gc] GC(151) Concurrent weak references (Young) 0.028ms [2025-11-28T09:15:08.500Z] Allocated: 719 Mb [2025-11-28T09:15:08.500Z] Allocated: 696 Mb [2025-11-28T09:15:08.500Z] Allocated: 720 Mb [2025-11-28T09:15:08.500Z] [17.895s][info][gc] GC(151) Concurrent weak roots (Young) 7.712ms [2025-11-28T09:15:08.500Z] [17.895s][info][gc] GC(151) Concurrent cleanup (Young) 883M->883M(1024M) 0.143ms [2025-11-28T09:15:08.500Z] [17.895s][info][gc] GC(151) Concurrent strong roots 0.219ms [2025-11-28T09:15:08.500Z] [17.895s][info][gc] GC(151) Concurrent evacuation 0.213ms [2025-11-28T09:15:08.500Z] Allocated: 701 Mb [2025-11-28T09:15:08.500Z] [17.902s][info][gc] GC(151) Concurrent Init Update Refs (Young) 6.441ms [2025-11-28T09:15:08.500Z] [17.908s][info][gc] GC(151) Verify Before Updating References, Level 4 (22 reachable, 45 marked) [2025-11-28T09:15:08.500Z] [17.908s][info][gc] GC(151) Pause Init Update Refs 3.524ms [2025-11-28T09:15:08.500Z] [17.911s][info][gc] GC(151) Concurrent update references 2.391ms [2025-11-28T09:15:08.500Z] Allocated: 697 Mb [2025-11-28T09:15:08.500Z] [17.914s][info][gc] GC(151) Concurrent update thread roots 3.373ms [2025-11-28T09:15:08.500Z] [17.921s][info][gc] GC(151) Verify After Updating References, Level 4 (23 reachable, 39 marked) [2025-11-28T09:15:08.500Z] [17.921s][info][gc] GC(151) Pause Final Update Refs 3.797ms [2025-11-28T09:15:08.500Z] [17.921s][info][gc] GC(151) Concurrent cleanup (Young) 940M->940M(1024M) 0.024ms [2025-11-28T09:15:08.500Z] Allocated: 734 Mb [2025-11-28T09:15:08.500Z] [17.921s][info][gc] Failed to allocate Shared, 50721K [2025-11-28T09:15:08.500Z] [17.926s][info][gc] GC(151) Concurrent reset after collect (Young) 4.407ms [2025-11-28T09:15:08.500Z] [17.926s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.500Z] [17.934s][info][gc] GC(152) Verify Before Full GC, Level 4 (34344 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [18.147s][info][gc] GC(152) Verify After Full GC, Level 4 (34240 reachable, 0 marked) [2025-11-28T09:15:08.500Z] [18.147s][info][gc] GC(152) Pause Full 940M->696M(1024M) 221.218ms [2025-11-28T09:15:08.500Z] Allocated: 740 Mb [2025-11-28T09:15:08.500Z] Allocated: 691 Mb [2025-11-28T09:15:08.501Z] [18.158s][info][gc] Trigger (Young): Average GC time (81.45 ms) is above the time for average allocation rate (1669 MB/s) to deplete free headroom (134M) (margin of error = 1.90) [2025-11-28T09:15:08.501Z] [18.159s][info][gc] GC(153) Concurrent reset (Young) 0.827ms [2025-11-28T09:15:08.501Z] [18.167s][info][gc] GC(153) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.167s][info][gc] GC(153) Pause Init Mark (Young) 3.615ms [2025-11-28T09:15:08.501Z] Allocated: 720 Mb [2025-11-28T09:15:08.501Z] [18.167s][info][gc] GC(153) Concurrent remembered set scanning 0.193ms [2025-11-28T09:15:08.501Z] [18.168s][info][gc] GC(153) Concurrent marking roots 0.203ms [2025-11-28T09:15:08.501Z] [18.168s][info][gc] GC(153) Concurrent marking (Young) 0.213ms [2025-11-28T09:15:08.501Z] [18.172s][info][gc] GC(153) Verify Before Evacuation, Level 4 (23 reachable, 5 marked) [2025-11-28T09:15:08.501Z] [18.172s][info][gc] GC(153) Pause Final Mark (Young) 4.254ms [2025-11-28T09:15:08.501Z] [18.173s][info][gc] GC(153) Concurrent thread roots 1.115ms [2025-11-28T09:15:08.501Z] [18.173s][info][gc] GC(153) Concurrent weak references (Young) 0.016ms [2025-11-28T09:15:08.501Z] Allocated: 697 Mb [2025-11-28T09:15:08.501Z] [18.177s][info][gc] GC(153) Concurrent weak roots (Young) 3.251ms [2025-11-28T09:15:08.501Z] [18.177s][info][gc] GC(153) Concurrent cleanup (Young) 761M->761M(1024M) 0.030ms [2025-11-28T09:15:08.501Z] [18.177s][info][gc] GC(153) Concurrent strong roots 0.192ms [2025-11-28T09:15:08.501Z] [18.177s][info][gc] GC(153) Concurrent evacuation 0.135ms [2025-11-28T09:15:08.501Z] Allocated: 713 Mb [2025-11-28T09:15:08.501Z] [18.180s][info][gc] GC(153) Concurrent Init Update Refs (Young) 3.172ms [2025-11-28T09:15:08.501Z] [18.190s][info][gc] GC(153) Verify Before Updating References, Level 4 (22 reachable, 25 marked) [2025-11-28T09:15:08.501Z] [18.190s][info][gc] GC(153) Pause Init Update Refs 3.488ms [2025-11-28T09:15:08.501Z] Allocated: 689 Mb [2025-11-28T09:15:08.501Z] Allocated: 695 Mb [2025-11-28T09:15:08.501Z] [18.194s][info][gc] GC(153) Concurrent update references 4.033ms [2025-11-28T09:15:08.501Z] Allocated: 712 Mb [2025-11-28T09:15:08.501Z] [18.198s][info][gc] GC(153) Concurrent update thread roots 3.305ms [2025-11-28T09:15:08.501Z] [18.205s][info][gc] GC(153) Verify After Updating References, Level 4 (23 reachable, 33 marked) [2025-11-28T09:15:08.501Z] [18.205s][info][gc] GC(153) Pause Final Update Refs 3.803ms [2025-11-28T09:15:08.501Z] Allocated: 675 Mb [2025-11-28T09:15:08.501Z] [18.205s][info][gc] GC(153) Concurrent cleanup (Young) 834M->834M(1024M) 0.022ms [2025-11-28T09:15:08.501Z] Allocated: 678 Mb [2025-11-28T09:15:08.501Z] [18.213s][info][gc] GC(153) Concurrent reset after collect (Young) 8.607ms [2025-11-28T09:15:08.501Z] [18.214s][info][gc] Trigger (Young): Average GC time (75.86 ms) is above the time for average allocation rate (3195 MB/s) to deplete free headroom (53811K) (margin of error = 1.90) [2025-11-28T09:15:08.501Z] [18.215s][info][gc] GC(154) Concurrent reset (Young) 0.409ms [2025-11-28T09:15:08.501Z] [18.220s][info][gc] GC(154) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.220s][info][gc] GC(154) Pause Init Mark (Young) 3.397ms [2025-11-28T09:15:08.501Z] Allocated: 709 Mb [2025-11-28T09:15:08.501Z] [18.224s][info][gc] GC(154) Concurrent remembered set scanning 3.793ms [2025-11-28T09:15:08.501Z] [18.225s][info][gc] GC(154) Concurrent marking roots 0.250ms [2025-11-28T09:15:08.501Z] Allocated: 713 Mb [2025-11-28T09:15:08.501Z] Allocated: 700 Mb [2025-11-28T09:15:08.501Z] [18.237s][info][gc] GC(154) Concurrent marking (Young) 11.853ms [2025-11-28T09:15:08.501Z] [18.243s][info][gc] GC(154) Verify Before Evacuation, Level 4 (25 reachable, 16 marked) [2025-11-28T09:15:08.501Z] [18.243s][info][gc] GC(154) Pause Final Mark (Young) 6.079ms [2025-11-28T09:15:08.501Z] Allocated: 647 Mb [2025-11-28T09:15:08.501Z] [18.243s][info][gc] GC(154) Concurrent thread roots 0.072ms [2025-11-28T09:15:08.501Z] [18.243s][info][gc] GC(154) Concurrent weak references (Young) 0.022ms [2025-11-28T09:15:08.501Z] [18.243s][info][gc] GC(154) Concurrent weak roots (Young) 0.080ms [2025-11-28T09:15:08.501Z] [18.243s][info][gc] GC(154) Concurrent cleanup (Young) 775M->775M(1024M) 0.063ms [2025-11-28T09:15:08.501Z] [18.244s][info][gc] GC(154) Concurrent strong roots 0.169ms [2025-11-28T09:15:08.501Z] [18.244s][info][gc] GC(154) Concurrent evacuation 0.082ms [2025-11-28T09:15:08.501Z] [18.244s][info][gc] GC(154) Concurrent Init Update Refs (Young) 0.029ms [2025-11-28T09:15:08.501Z] [18.246s][info][gc] GC(154) Verify Before Updating References, Level 4 (26 reachable, 22 marked) [2025-11-28T09:15:08.501Z] [18.246s][info][gc] GC(154) Pause Init Update Refs 2.507ms [2025-11-28T09:15:08.501Z] [18.254s][info][gc] GC(154) Concurrent update references 3.424ms [2025-11-28T09:15:08.501Z] Allocated: 674 Mb [2025-11-28T09:15:08.501Z] [18.256s][info][gc] Failed to allocate Shared, 64258K [2025-11-28T09:15:08.501Z] [18.256s][info][gc] GC(154) Concurrent update thread roots 2.154ms [2025-11-28T09:15:08.501Z] [18.256s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.501Z] [18.265s][info][gc] GC(155) Verify Before Full GC, Level 4 (34349 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.462s][info][gc] GC(155) Verify After Full GC, Level 4 (34245 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.462s][info][gc] GC(155) Pause Full 802M->682M(1024M) 205.794ms [2025-11-28T09:15:08.501Z] Allocated: 736 Mb [2025-11-28T09:15:08.501Z] Allocated: 697 Mb [2025-11-28T09:15:08.501Z] [18.485s][info][gc] Trigger (Young): Average GC time (75.86 ms) is above the time for average allocation rate (1391 MB/s) to deplete free headroom (104M) (margin of error = 1.90) [2025-11-28T09:15:08.501Z] [18.486s][info][gc] GC(156) Concurrent reset (Young) 0.492ms [2025-11-28T09:15:08.501Z] [18.498s][info][gc] GC(156) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.498s][info][gc] GC(156) Pause Init Mark (Young) 3.678ms [2025-11-28T09:15:08.501Z] [18.498s][info][gc] GC(156) Concurrent remembered set scanning 0.164ms [2025-11-28T09:15:08.501Z] [18.498s][info][gc] GC(156) Concurrent marking roots 0.235ms [2025-11-28T09:15:08.501Z] Allocated: 744 Mb [2025-11-28T09:15:08.501Z] Allocated: 721 Mb [2025-11-28T09:15:08.501Z] [18.504s][info][gc] GC(156) Concurrent marking (Young) 6.020ms [2025-11-28T09:15:08.501Z] [18.514s][info][gc] GC(156) Verify Before Evacuation, Level 4 (27 reachable, 12 marked) [2025-11-28T09:15:08.501Z] [18.514s][info][gc] GC(156) Pause Final Mark (Young) 5.643ms [2025-11-28T09:15:08.501Z] Allocated: 695 Mb [2025-11-28T09:15:08.501Z] [18.518s][info][gc] GC(156) Concurrent thread roots 0.101ms [2025-11-28T09:15:08.501Z] [18.518s][info][gc] GC(156) Concurrent weak references (Young) 0.044ms [2025-11-28T09:15:08.501Z] Allocated: 745 Mb [2025-11-28T09:15:08.501Z] [18.524s][info][gc] GC(156) Concurrent weak roots (Young) 5.391ms [2025-11-28T09:15:08.501Z] [18.524s][info][gc] GC(156) Concurrent cleanup (Young) 900M->900M(1024M) 0.093ms [2025-11-28T09:15:08.501Z] [18.524s][info][gc] GC(156) Concurrent strong roots 0.265ms [2025-11-28T09:15:08.501Z] [18.524s][info][gc] GC(156) Concurrent evacuation 0.264ms [2025-11-28T09:15:08.501Z] Allocated: 736 Mb [2025-11-28T09:15:08.501Z] [18.532s][info][gc] GC(156) Concurrent Init Update Refs (Young) 7.553ms [2025-11-28T09:15:08.501Z] [18.540s][info][gc] GC(156) Verify Before Updating References, Level 4 (26 reachable, 40 marked) [2025-11-28T09:15:08.501Z] [18.540s][info][gc] GC(156) Pause Init Update Refs 3.617ms [2025-11-28T09:15:08.501Z] Allocated: 698 Mb [2025-11-28T09:15:08.501Z] [18.543s][info][gc] GC(156) Concurrent update references 2.433ms [2025-11-28T09:15:08.501Z] Allocated: 717 Mb [2025-11-28T09:15:08.501Z] [18.547s][info][gc] GC(156) Concurrent update thread roots 4.275ms [2025-11-28T09:15:08.501Z] [18.551s][info][gc] GC(156) Verify After Updating References, Level 4 (26 reachable, 40 marked) [2025-11-28T09:15:08.501Z] [18.551s][info][gc] GC(156) Pause Final Update Refs 3.729ms [2025-11-28T09:15:08.501Z] [18.551s][info][gc] GC(156) Concurrent cleanup (Young) 950M->950M(1024M) 0.014ms [2025-11-28T09:15:08.501Z] Allocated: 684 Mb [2025-11-28T09:15:08.501Z] [18.554s][info][gc] Failed to allocate Shared, 42956K [2025-11-28T09:15:08.501Z] [18.556s][info][gc] GC(156) Concurrent reset after collect (Young) 4.550ms [2025-11-28T09:15:08.501Z] [18.556s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.501Z] [18.564s][info][gc] GC(157) Verify Before Full GC, Level 4 (34349 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.760s][info][gc] GC(157) Verify After Full GC, Level 4 (34245 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.760s][info][gc] GC(157) Pause Full 950M->691M(1024M) 204.040ms [2025-11-28T09:15:08.501Z] Allocated: 726 Mb [2025-11-28T09:15:08.501Z] Allocated: 702 Mb [2025-11-28T09:15:08.501Z] Allocated: 739 Mb [2025-11-28T09:15:08.501Z] [18.777s][info][gc] Trigger (Young): Average GC time (79.93 ms) is above the time for average allocation rate (1325 MB/s) to deplete free headroom (84019K) (margin of error = 1.92) [2025-11-28T09:15:08.501Z] [18.778s][info][gc] GC(158) Concurrent reset (Young) 0.815ms [2025-11-28T09:15:08.501Z] [18.788s][info][gc] GC(158) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.788s][info][gc] GC(158) Pause Init Mark (Young) 3.658ms [2025-11-28T09:15:08.501Z] Allocated: 744 Mb [2025-11-28T09:15:08.501Z] [18.789s][info][gc] GC(158) Concurrent remembered set scanning 0.197ms [2025-11-28T09:15:08.501Z] [18.789s][info][gc] GC(158) Concurrent marking roots 0.200ms [2025-11-28T09:15:08.501Z] Allocated: 718 Mb [2025-11-28T09:15:08.501Z] [18.797s][info][gc] GC(158) Concurrent marking (Young) 7.490ms [2025-11-28T09:15:08.501Z] [18.811s][info][gc] GC(158) Verify After Mark, Level 4 (26 reachable, 13 marked) [2025-11-28T09:15:08.501Z] [18.811s][info][gc] GC(158) Pause Final Mark (Young) 6.067ms [2025-11-28T09:15:08.501Z] [18.811s][info][gc] GC(158) Concurrent weak references (Young) 0.017ms [2025-11-28T09:15:08.501Z] Allocated: 743 Mb [2025-11-28T09:15:08.501Z] [18.812s][info][gc] GC(158) Concurrent weak roots (Young) 0.265ms [2025-11-28T09:15:08.501Z] [18.812s][info][gc] GC(158) Concurrent cleanup (Young) 838M->838M(1024M) 0.051ms [2025-11-28T09:15:08.501Z] Allocated: 684 Mb [2025-11-28T09:15:08.501Z] [18.814s][info][gc] GC(158) Concurrent Final Roots (Young) 2.470ms [2025-11-28T09:15:08.501Z] Allocated: 689 Mb [2025-11-28T09:15:08.501Z] [18.822s][info][gc] GC(158) Concurrent reset after collect (Young) 7.508ms [2025-11-28T09:15:08.501Z] Allocated: 702 Mb [2025-11-28T09:15:08.501Z] [18.822s][info][gc] Trigger (Young): Free (100M) is below minimum threshold (102M) [2025-11-28T09:15:08.501Z] [18.823s][info][gc] GC(159) Concurrent reset (Young) 0.432ms [2025-11-28T09:15:08.501Z] [18.826s][info][gc] GC(159) Verify Before Mark, Level 4 (28 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.826s][info][gc] GC(159) Pause Init Mark (Young) 3.331ms [2025-11-28T09:15:08.501Z] [18.826s][info][gc] GC(159) Concurrent remembered set scanning 0.205ms [2025-11-28T09:15:08.501Z] [18.827s][info][gc] GC(159) Concurrent marking roots 0.268ms [2025-11-28T09:15:08.501Z] [18.827s][info][gc] Failed to allocate Shared, 58029K [2025-11-28T09:15:08.501Z] [18.827s][info][gc] GC(159) Concurrent marking (Young) 0.297ms [2025-11-28T09:15:08.501Z] [18.827s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.501Z] [18.834s][info][gc] GC(160) Verify Before Full GC, Level 4 (34349 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [18.834s][info][gc] GC(160) Cancel marking: Young [2025-11-28T09:15:08.501Z] [18.834s][info][gc] GC(160) Cancel marking: Old [2025-11-28T09:15:08.501Z] [18.834s][info][gc] GC(160) Cancel marking: Global [2025-11-28T09:15:08.501Z] [19.071s][info][gc] GC(160) Verify After Full GC, Level 4 (34245 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.071s][info][gc] GC(160) Pause Full 871M->698M(1024M) 243.717ms [2025-11-28T09:15:08.501Z] Allocated: 746 Mb [2025-11-28T09:15:08.501Z] Allocated: 681 Mb [2025-11-28T09:15:08.501Z] Allocated: 743 Mb [2025-11-28T09:15:08.501Z] Allocated: 680 Mb [2025-11-28T09:15:08.501Z] [19.095s][info][gc] Trigger (Young): Free (100095K) is below minimum threshold (102M) [2025-11-28T09:15:08.501Z] [19.096s][info][gc] GC(161) Concurrent reset (Young) 0.452ms [2025-11-28T09:15:08.501Z] [19.103s][info][gc] GC(161) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.104s][info][gc] GC(161) Pause Init Mark (Young) 3.399ms [2025-11-28T09:15:08.501Z] Allocated: 714 Mb [2025-11-28T09:15:08.501Z] [19.104s][info][gc] GC(161) Concurrent remembered set scanning 0.235ms [2025-11-28T09:15:08.501Z] [19.104s][info][gc] GC(161) Concurrent marking roots 0.311ms [2025-11-28T09:15:08.501Z] Allocated: 719 Mb [2025-11-28T09:15:08.501Z] Allocated: 712 Mb [2025-11-28T09:15:08.501Z] [19.115s][info][gc] GC(161) Concurrent marking (Young) 9.764ms [2025-11-28T09:15:08.501Z] [19.115s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.501Z] [19.115s][info][gc] Failed to allocate Shared, 58415K [2025-11-28T09:15:08.501Z] [19.124s][info][gc] GC(162) Verify Before Full GC, Level 4 (34349 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.124s][info][gc] GC(162) Cancel marking: Young [2025-11-28T09:15:08.501Z] [19.124s][info][gc] GC(162) Cancel marking: Old [2025-11-28T09:15:08.501Z] [19.124s][info][gc] GC(162) Cancel marking: Global [2025-11-28T09:15:08.501Z] [19.308s][info][gc] GC(162) Verify After Full GC, Level 4 (34245 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.308s][info][gc] GC(162) Pause Full 935M->673M(1024M) 193.364ms [2025-11-28T09:15:08.501Z] Allocated: 722 Mb [2025-11-28T09:15:08.501Z] Allocated: 741 Mb [2025-11-28T09:15:08.501Z] [19.327s][info][gc] Trigger (Young): Average GC time (77.88 ms) is above the time for average allocation rate (1561 MB/s) to deplete free headroom (84019K) (margin of error = 1.92) [2025-11-28T09:15:08.501Z] [19.328s][info][gc] GC(163) Concurrent reset (Young) 0.444ms [2025-11-28T09:15:08.501Z] [19.338s][info][gc] GC(163) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.339s][info][gc] GC(163) Pause Init Mark (Young) 3.372ms [2025-11-28T09:15:08.501Z] Allocated: 713 Mb [2025-11-28T09:15:08.501Z] [19.340s][info][gc] GC(163) Concurrent remembered set scanning 0.226ms [2025-11-28T09:15:08.501Z] [19.340s][info][gc] GC(163) Concurrent marking roots 0.316ms [2025-11-28T09:15:08.501Z] Allocated: 737 Mb [2025-11-28T09:15:08.501Z] Allocated: 698 Mb [2025-11-28T09:15:08.501Z] [19.350s][info][gc] GC(163) Concurrent marking (Young) 9.109ms [2025-11-28T09:15:08.501Z] [19.359s][info][gc] GC(163) Verify After Mark, Level 4 (25 reachable, 16 marked) [2025-11-28T09:15:08.501Z] [19.359s][info][gc] GC(163) Pause Final Mark (Young) 3.869ms [2025-11-28T09:15:08.501Z] Allocated: 731 Mb [2025-11-28T09:15:08.501Z] [19.359s][info][gc] Failed to allocate Shared, 65082K [2025-11-28T09:15:08.501Z] [19.359s][info][gc] GC(163) Concurrent weak references (Young) 0.028ms [2025-11-28T09:15:08.501Z] [19.359s][info][gc] GC(163) Concurrent weak roots (Young) 0.083ms [2025-11-28T09:15:08.501Z] [19.359s][info][gc] GC(163) Concurrent cleanup (Young) 794M->794M(1024M) 0.059ms [2025-11-28T09:15:08.501Z] [19.359s][info][gc] GC(163) Concurrent Final Roots (Young) 0.093ms [2025-11-28T09:15:08.501Z] [19.363s][info][gc] GC(163) Concurrent reset after collect (Young) 3.794ms [2025-11-28T09:15:08.501Z] [19.363s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.501Z] [19.372s][info][gc] GC(164) Verify Before Full GC, Level 4 (34347 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.591s][info][gc] GC(164) Verify After Full GC, Level 4 (34243 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.591s][info][gc] GC(164) Pause Full 794M->705M(1024M) 227.771ms [2025-11-28T09:15:08.501Z] Allocated: 760 Mb [2025-11-28T09:15:08.501Z] [19.602s][info][gc] Trigger (Young): Average GC time (69.73 ms) is above the time for average allocation rate (1770 MB/s) to deplete free headroom (92211K) (margin of error = 1.90) [2025-11-28T09:15:08.501Z] [19.603s][info][gc] GC(165) Concurrent reset (Young) 0.828ms [2025-11-28T09:15:08.501Z] [19.615s][info][gc] GC(165) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.615s][info][gc] GC(165) Pause Init Mark (Young) 3.518ms [2025-11-28T09:15:08.501Z] [19.615s][info][gc] GC(165) Concurrent remembered set scanning 0.168ms [2025-11-28T09:15:08.501Z] [19.615s][info][gc] GC(165) Concurrent marking roots 0.253ms [2025-11-28T09:15:08.501Z] Allocated: 755 Mb [2025-11-28T09:15:08.501Z] Allocated: 739 Mb [2025-11-28T09:15:08.501Z] [19.622s][info][gc] GC(165) Concurrent marking (Young) 6.911ms [2025-11-28T09:15:08.501Z] [19.630s][info][gc] GC(165) Verify After Mark, Level 4 (21 reachable, 13 marked) [2025-11-28T09:15:08.501Z] [19.630s][info][gc] GC(165) Pause Final Mark (Young) 3.857ms [2025-11-28T09:15:08.501Z] Allocated: 688 Mb [2025-11-28T09:15:08.501Z] [19.631s][info][gc] GC(165) Concurrent weak references (Young) 0.015ms [2025-11-28T09:15:08.501Z] [19.631s][info][gc] GC(165) Concurrent weak roots (Young) 0.088ms [2025-11-28T09:15:08.501Z] [19.631s][info][gc] GC(165) Concurrent cleanup (Young) 831M->831M(1024M) 0.046ms [2025-11-28T09:15:08.501Z] [19.632s][info][gc] GC(165) Concurrent Final Roots (Young) 0.868ms [2025-11-28T09:15:08.501Z] Allocated: 710 Mb [2025-11-28T09:15:08.501Z] [19.638s][info][gc] Failed to allocate Shared, 39334K [2025-11-28T09:15:08.501Z] [19.638s][info][gc] GC(165) Concurrent reset after collect (Young) 6.858ms [2025-11-28T09:15:08.501Z] [19.639s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.501Z] [19.647s][info][gc] GC(166) Verify Before Full GC, Level 4 (34344 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.834s][info][gc] GC(166) Verify After Full GC, Level 4 (34240 reachable, 0 marked) [2025-11-28T09:15:08.501Z] [19.834s][info][gc] GC(166) Pause Full 853M->690M(1024M) 195.343ms [2025-11-28T09:15:08.501Z] Allocated: 720 Mb [2025-11-28T09:15:08.501Z] Allocated: 650 Mb [2025-11-28T09:15:08.501Z] Allocated: 694 Mb [2025-11-28T09:15:08.501Z] Allocated: 714 Mb [2025-11-28T09:15:08.502Z] [19.852s][info][gc] Trigger (Young): Average GC time (60.10 ms) is above the time for average allocation rate (1673 MB/s) to deplete free headroom (92723K) (margin of error = 1.90) [2025-11-28T09:15:08.502Z] [19.853s][info][gc] GC(167) Concurrent reset (Young) 0.458ms [2025-11-28T09:15:08.502Z] [19.861s][info][gc] GC(167) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [19.862s][info][gc] GC(167) Pause Init Mark (Young) 3.544ms [2025-11-28T09:15:08.502Z] Allocated: 703 Mb [2025-11-28T09:15:08.502Z] [19.863s][info][gc] GC(167) Concurrent remembered set scanning 1.249ms [2025-11-28T09:15:08.502Z] [19.863s][info][gc] GC(167) Concurrent marking roots 0.242ms [2025-11-28T09:15:08.502Z] Allocated: 695 Mb [2025-11-28T09:15:08.502Z] [19.869s][info][gc] GC(167) Concurrent marking (Young) 5.692ms [2025-11-28T09:15:08.502Z] [19.872s][info][gc] GC(167) Verify After Mark, Level 4 (23 reachable, 9 marked) [2025-11-28T09:15:08.502Z] [19.873s][info][gc] GC(167) Pause Final Mark (Young) 3.558ms [2025-11-28T09:15:08.502Z] [19.876s][info][gc] GC(167) Concurrent weak references (Young) 0.022ms [2025-11-28T09:15:08.502Z] Allocated: 751 Mb [2025-11-28T09:15:08.502Z] [19.882s][info][gc] GC(167) Concurrent weak roots (Young) 5.425ms [2025-11-28T09:15:08.502Z] [19.882s][info][gc] GC(167) Concurrent cleanup (Young) 760M->760M(1024M) 0.090ms [2025-11-28T09:15:08.502Z] [19.882s][info][gc] GC(167) Concurrent Final Roots (Young) 0.107ms [2025-11-28T09:15:08.502Z] [19.888s][info][gc] GC(167) Concurrent reset after collect (Young) 5.625ms [2025-11-28T09:15:08.502Z] [19.888s][info][gc] Trigger (Young): Average GC time (52.61 ms) is above the time for average allocation rate (3825 MB/s) to deplete free headroom (123M) (margin of error = 1.88) [2025-11-28T09:15:08.502Z] [19.889s][info][gc] GC(168) Concurrent reset (Young) 0.402ms [2025-11-28T09:15:08.502Z] [19.896s][info][gc] GC(168) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [19.896s][info][gc] GC(168) Pause Init Mark (Young) 3.575ms [2025-11-28T09:15:08.502Z] Allocated: 736 Mb [2025-11-28T09:15:08.502Z] Allocated: 701 Mb [2025-11-28T09:15:08.502Z] [19.898s][info][gc] GC(168) Concurrent remembered set scanning 0.239ms [2025-11-28T09:15:08.502Z] [19.898s][info][gc] GC(168) Concurrent marking roots 0.277ms [2025-11-28T09:15:08.502Z] [19.898s][info][gc] GC(168) Concurrent marking (Young) 0.226ms [2025-11-28T09:15:08.502Z] [19.902s][info][gc] GC(168) Verify After Mark, Level 4 (23 reachable, 9 marked) [2025-11-28T09:15:08.502Z] [19.902s][info][gc] GC(168) Pause Final Mark (Young) 3.726ms [2025-11-28T09:15:08.502Z] [19.905s][info][gc] GC(168) Concurrent weak references (Young) 3.374ms [2025-11-28T09:15:08.502Z] Allocated: 675 Mb [2025-11-28T09:15:08.502Z] [19.909s][info][gc] GC(168) Concurrent weak roots (Young) 3.248ms [2025-11-28T09:15:08.502Z] [19.909s][info][gc] GC(168) Concurrent cleanup (Young) 763M->763M(1024M) 0.065ms [2025-11-28T09:15:08.502Z] Allocated: 692 Mb [2025-11-28T09:15:08.502Z] [19.911s][info][gc] GC(168) Concurrent Final Roots (Young) 2.649ms [2025-11-28T09:15:08.502Z] [19.915s][info][gc] GC(168) Concurrent reset after collect (Young) 3.796ms [2025-11-28T09:15:08.502Z] Allocated: 715 Mb [2025-11-28T09:15:08.502Z] Allocated: 702 Mb [2025-11-28T09:15:08.502Z] [19.920s][info][gc] Failed to allocate Shared, 53157K [2025-11-28T09:15:08.502Z] [19.920s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.502Z] [19.920s][info][gc] Trigger (Young): Average GC time (46.75 ms) is above the time for average allocation rate (3767 MB/s) to deplete free headroom (125M) (margin of error = 1.87) [2025-11-28T09:15:08.502Z] [19.926s][info][gc] GC(169) Verify Before Full GC, Level 4 (34346 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.187s][info][gc] GC(169) Verify After Full GC, Level 4 (34242 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.187s][info][gc] GC(169) Pause Full 796M->678M(1024M) 266.619ms [2025-11-28T09:15:08.502Z] [20.188s][info][gc] Trigger (Young): GC start is already pending [2025-11-28T09:15:08.502Z] [20.191s][info][gc] GC(170) Concurrent reset (Young) 0.428ms [2025-11-28T09:15:08.502Z] [20.199s][info][gc] GC(170) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.199s][info][gc] GC(170) Pause Init Mark (Young) 3.371ms [2025-11-28T09:15:08.502Z] Allocated: 721 Mb [2025-11-28T09:15:08.502Z] [20.200s][info][gc] GC(170) Concurrent remembered set scanning 0.172ms [2025-11-28T09:15:08.502Z] [20.200s][info][gc] GC(170) Concurrent marking roots 0.244ms [2025-11-28T09:15:08.502Z] [20.200s][info][gc] GC(170) Concurrent marking (Young) 0.225ms [2025-11-28T09:15:08.502Z] [20.205s][info][gc] GC(170) Verify After Mark, Level 4 (25 reachable, 5 marked) [2025-11-28T09:15:08.502Z] [20.205s][info][gc] GC(170) Pause Final Mark (Young) 4.877ms [2025-11-28T09:15:08.502Z] [20.205s][info][gc] GC(170) Concurrent weak references (Young) 0.018ms [2025-11-28T09:15:08.502Z] [20.206s][info][gc] GC(170) Concurrent weak roots (Young) 0.167ms [2025-11-28T09:15:08.502Z] [20.206s][info][gc] GC(170) Concurrent cleanup (Young) 754M->754M(1024M) 0.016ms [2025-11-28T09:15:08.502Z] Allocated: 697 Mb [2025-11-28T09:15:08.502Z] [20.210s][info][gc] GC(170) Concurrent Final Roots (Young) 4.275ms [2025-11-28T09:15:08.502Z] Allocated: 713 Mb [2025-11-28T09:15:08.502Z] [20.217s][info][gc] GC(170) Concurrent reset after collect (Young) 7.462ms [2025-11-28T09:15:08.502Z] Allocated: 725 Mb [2025-11-28T09:15:08.502Z] Allocated: 714 Mb [2025-11-28T09:15:08.502Z] [20.228s][info][gc] Trigger (Young): Free (100M) is below minimum threshold (102M) [2025-11-28T09:15:08.502Z] [20.228s][info][gc] GC(171) Concurrent reset (Young) 0.419ms [2025-11-28T09:15:08.502Z] [20.237s][info][gc] GC(171) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.237s][info][gc] GC(171) Pause Init Mark (Young) 3.455ms [2025-11-28T09:15:08.502Z] Allocated: 703 Mb [2025-11-28T09:15:08.502Z] [20.238s][info][gc] GC(171) Concurrent remembered set scanning 0.208ms [2025-11-28T09:15:08.502Z] [20.239s][info][gc] GC(171) Concurrent marking roots 1.037ms [2025-11-28T09:15:08.502Z] Allocated: 689 Mb [2025-11-28T09:15:08.502Z] Allocated: 727 Mb [2025-11-28T09:15:08.502Z] [20.249s][info][gc] Failed to allocate Shared, 61147K [2025-11-28T09:15:08.502Z] [20.249s][info][gc] GC(171) Concurrent marking (Young) 10.832ms [2025-11-28T09:15:08.502Z] [20.250s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.502Z] [20.258s][info][gc] GC(172) Verify Before Full GC, Level 4 (34347 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.258s][info][gc] GC(172) Cancel marking: Young [2025-11-28T09:15:08.502Z] [20.258s][info][gc] GC(172) Cancel marking: Old [2025-11-28T09:15:08.502Z] [20.258s][info][gc] GC(172) Cancel marking: Global [2025-11-28T09:15:08.502Z] [20.514s][info][gc] GC(172) Verify After Full GC, Level 4 (34243 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.514s][info][gc] GC(172) Pause Full 937M->683M(1024M) 264.453ms [2025-11-28T09:15:08.502Z] Allocated: 734 Mb [2025-11-28T09:15:08.502Z] Allocated: 659 Mb [2025-11-28T09:15:08.502Z] Allocated: 675 Mb [2025-11-28T09:15:08.502Z] Allocated: 687 Mb [2025-11-28T09:15:08.502Z] Allocated: 705 Mb [2025-11-28T09:15:08.502Z] Allocated: 687 Mb [2025-11-28T09:15:08.502Z] Allocated: 698 Mb [2025-11-28T09:15:08.502Z] Allocated: 714 Mb [2025-11-28T09:15:08.502Z] [20.539s][info][gc] Trigger (Young): Free (77055K) is below minimum threshold (102M) [2025-11-28T09:15:08.502Z] [20.540s][info][gc] GC(173) Concurrent reset (Young) 0.449ms [2025-11-28T09:15:08.502Z] [20.551s][info][gc] GC(173) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.551s][info][gc] GC(173) Pause Init Mark (Young) 3.549ms [2025-11-28T09:15:08.502Z] Allocated: 726 Mb [2025-11-28T09:15:08.502Z] [20.552s][info][gc] GC(173) Concurrent remembered set scanning 0.192ms [2025-11-28T09:15:08.502Z] [20.552s][info][gc] GC(173) Concurrent marking roots 0.242ms [2025-11-28T09:15:08.502Z] [20.552s][info][gc] GC(173) Concurrent marking (Young) 0.226ms [2025-11-28T09:15:08.502Z] [20.556s][info][gc] GC(173) Verify After Mark, Level 4 (28 reachable, 5 marked) [2025-11-28T09:15:08.502Z] [20.556s][info][gc] GC(173) Pause Final Mark (Young) 4.244ms [2025-11-28T09:15:08.502Z] Allocated: 674 Mb [2025-11-28T09:15:08.502Z] [20.558s][info][gc] GC(173) Concurrent weak references (Young) 0.021ms [2025-11-28T09:15:08.502Z] [20.558s][info][gc] GC(173) Concurrent weak roots (Young) 0.082ms [2025-11-28T09:15:08.502Z] [20.558s][info][gc] GC(173) Concurrent cleanup (Young) 744M->744M(1024M) 0.043ms [2025-11-28T09:15:08.502Z] [20.558s][info][gc] GC(173) Concurrent Final Roots (Young) 0.046ms [2025-11-28T09:15:08.502Z] Allocated: 678 Mb [2025-11-28T09:15:08.502Z] [20.565s][info][gc] GC(173) Concurrent reset after collect (Young) 6.717ms [2025-11-28T09:15:08.502Z] [20.565s][info][gc] Trigger (Young): Average GC time (37.68 ms) is above the time for average allocation rate (4280 MB/s) to deplete free headroom (113M) (margin of error = 1.86) [2025-11-28T09:15:08.502Z] [20.566s][info][gc] GC(174) Concurrent reset (Young) 0.393ms [2025-11-28T09:15:08.502Z] [20.576s][info][gc] GC(174) Verify Before Mark, Level 4 (29 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.576s][info][gc] GC(174) Pause Init Mark (Young) 3.331ms [2025-11-28T09:15:08.502Z] Allocated: 737 Mb [2025-11-28T09:15:08.502Z] [20.577s][info][gc] GC(174) Concurrent remembered set scanning 0.186ms [2025-11-28T09:15:08.502Z] [20.577s][info][gc] GC(174) Concurrent marking roots 0.225ms [2025-11-28T09:15:08.502Z] [20.578s][info][gc] GC(174) Concurrent marking (Young) 0.597ms [2025-11-28T09:15:08.502Z] [20.585s][info][gc] GC(174) Verify Before Evacuation, Level 4 (28 reachable, 8 marked) [2025-11-28T09:15:08.502Z] [20.585s][info][gc] GC(174) Pause Final Mark (Young) 4.978ms [2025-11-28T09:15:08.502Z] Allocated: 695 Mb [2025-11-28T09:15:08.502Z] [20.585s][info][gc] GC(174) Concurrent thread roots 0.065ms [2025-11-28T09:15:08.502Z] [20.585s][info][gc] GC(174) Concurrent weak references (Young) 0.017ms [2025-11-28T09:15:08.502Z] [20.585s][info][gc] GC(174) Concurrent weak roots (Young) 0.082ms [2025-11-28T09:15:08.502Z] [20.585s][info][gc] GC(174) Concurrent cleanup (Young) 761M->761M(1024M) 0.028ms [2025-11-28T09:15:08.502Z] [20.586s][info][gc] GC(174) Concurrent strong roots 0.792ms [2025-11-28T09:15:08.502Z] [20.586s][info][gc] GC(174) Concurrent evacuation 0.065ms [2025-11-28T09:15:08.502Z] Allocated: 755 Mb [2025-11-28T09:15:08.502Z] [20.596s][info][gc] GC(174) Concurrent Init Update Refs (Young) 9.682ms [2025-11-28T09:15:08.502Z] [20.608s][info][gc] GC(174) Verify Before Updating References, Level 4 (28 reachable, 21 marked) [2025-11-28T09:15:08.502Z] [20.608s][info][gc] GC(174) Pause Init Update Refs 3.510ms [2025-11-28T09:15:08.502Z] Allocated: 747 Mb [2025-11-28T09:15:08.502Z] Allocated: 671 Mb [2025-11-28T09:15:08.502Z] [20.612s][info][gc] GC(174) Concurrent update references 4.376ms [2025-11-28T09:15:08.502Z] [20.613s][info][gc] GC(174) Concurrent update thread roots 0.196ms [2025-11-28T09:15:08.502Z] [20.616s][info][gc] GC(174) Verify After Updating References, Level 4 (27 reachable, 30 marked) [2025-11-28T09:15:08.502Z] [20.616s][info][gc] GC(174) Pause Final Update Refs 3.130ms [2025-11-28T09:15:08.502Z] [20.616s][info][gc] GC(174) Concurrent cleanup (Young) 885M->885M(1024M) 0.012ms [2025-11-28T09:15:08.502Z] [20.618s][info][gc] Failed to allocate Shared, 32809K [2025-11-28T09:15:08.502Z] [20.620s][info][gc] GC(174) Concurrent reset after collect (Young) 4.110ms [2025-11-28T09:15:08.502Z] [20.620s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.502Z] [20.628s][info][gc] GC(175) Verify Before Full GC, Level 4 (34349 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.828s][info][gc] GC(175) Verify After Full GC, Level 4 (34245 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.828s][info][gc] GC(175) Pause Full 885M->680M(1024M) 207.516ms [2025-11-28T09:15:08.502Z] Allocated: 703 Mb [2025-11-28T09:15:08.502Z] Allocated: 711 Mb [2025-11-28T09:15:08.502Z] Allocated: 673 Mb [2025-11-28T09:15:08.502Z] Allocated: 714 Mb [2025-11-28T09:15:08.502Z] [20.845s][info][gc] Trigger (Young): Average GC time (67.22 ms) is above the time for average allocation rate (1560 MB/s) to deplete free headroom (100915K) (margin of error = 1.86) [2025-11-28T09:15:08.502Z] [20.846s][info][gc] GC(176) Concurrent reset (Young) 0.493ms [2025-11-28T09:15:08.502Z] [20.855s][info][gc] GC(176) Verify Before Mark, Level 4 (28 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.855s][info][gc] GC(176) Pause Init Mark (Young) 3.578ms [2025-11-28T09:15:08.502Z] Allocated: 728 Mb [2025-11-28T09:15:08.502Z] [20.855s][info][gc] GC(176) Concurrent remembered set scanning 0.180ms [2025-11-28T09:15:08.502Z] [20.855s][info][gc] GC(176) Concurrent marking roots 0.252ms [2025-11-28T09:15:08.502Z] [20.856s][info][gc] GC(176) Concurrent marking (Young) 0.238ms [2025-11-28T09:15:08.502Z] [20.859s][info][gc] GC(176) Verify After Mark, Level 4 (29 reachable, 5 marked) [2025-11-28T09:15:08.502Z] [20.859s][info][gc] GC(176) Pause Final Mark (Young) 3.193ms [2025-11-28T09:15:08.502Z] [20.859s][info][gc] GC(176) Concurrent weak references (Young) 0.021ms [2025-11-28T09:15:08.502Z] [20.860s][info][gc] GC(176) Concurrent weak roots (Young) 0.990ms [2025-11-28T09:15:08.502Z] [20.860s][info][gc] GC(176) Concurrent cleanup (Young) 795M->795M(1024M) 0.034ms [2025-11-28T09:15:08.502Z] Allocated: 729 Mb [2025-11-28T09:15:08.502Z] [20.869s][info][gc] GC(176) Concurrent Final Roots (Young) 8.612ms [2025-11-28T09:15:08.502Z] Allocated: 694 Mb [2025-11-28T09:15:08.502Z] [20.877s][info][gc] GC(176) Concurrent reset after collect (Young) 8.361ms [2025-11-28T09:15:08.502Z] [20.878s][info][gc] Trigger (Young): Average GC time (57.00 ms) is above the time for average allocation rate (4754 MB/s) to deplete free headroom (67379K) (margin of error = 1.86) [2025-11-28T09:15:08.502Z] [20.879s][info][gc] GC(177) Concurrent reset (Young) 0.455ms [2025-11-28T09:15:08.502Z] [20.888s][info][gc] GC(177) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.888s][info][gc] GC(177) Pause Init Mark (Young) 3.466ms [2025-11-28T09:15:08.502Z] Allocated: 739 Mb [2025-11-28T09:15:08.502Z] [20.888s][info][gc] GC(177) Concurrent remembered set scanning 0.214ms [2025-11-28T09:15:08.502Z] [20.889s][info][gc] GC(177) Concurrent marking roots 0.226ms [2025-11-28T09:15:08.502Z] Allocated: 727 Mb [2025-11-28T09:15:08.502Z] [20.894s][info][gc] Failed to allocate Shared, 49837K [2025-11-28T09:15:08.502Z] [20.894s][info][gc] GC(177) Concurrent marking (Young) 5.879ms [2025-11-28T09:15:08.502Z] [20.895s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.502Z] [20.903s][info][gc] GC(178) Verify Before Full GC, Level 4 (34346 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [20.903s][info][gc] GC(178) Cancel marking: Young [2025-11-28T09:15:08.502Z] [20.903s][info][gc] GC(178) Cancel marking: Old [2025-11-28T09:15:08.502Z] [20.903s][info][gc] GC(178) Cancel marking: Global [2025-11-28T09:15:08.502Z] [21.117s][info][gc] GC(178) Verify After Full GC, Level 4 (34242 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [21.117s][info][gc] GC(178) Pause Full 889M->699M(1024M) 222.394ms [2025-11-28T09:15:08.502Z] Allocated: 741 Mb [2025-11-28T09:15:08.502Z] Allocated: 697 Mb [2025-11-28T09:15:08.502Z] Allocated: 747 Mb [2025-11-28T09:15:08.502Z] [21.134s][info][gc] Trigger (Young): Free (101M) is below minimum threshold (102M) [2025-11-28T09:15:08.502Z] [21.135s][info][gc] GC(179) Concurrent reset (Young) 0.469ms [2025-11-28T09:15:08.502Z] [21.148s][info][gc] GC(179) Verify Before Mark, Level 4 (23 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [21.148s][info][gc] GC(179) Pause Init Mark (Young) 3.700ms [2025-11-28T09:15:08.502Z] Allocated: 742 Mb [2025-11-28T09:15:08.502Z] [21.149s][info][gc] GC(179) Concurrent remembered set scanning 0.286ms [2025-11-28T09:15:08.502Z] [21.150s][info][gc] GC(179) Concurrent marking roots 0.982ms [2025-11-28T09:15:08.502Z] Allocated: 718 Mb [2025-11-28T09:15:08.502Z] Allocated: 726 Mb [2025-11-28T09:15:08.502Z] [21.161s][info][gc] Failed to allocate Shared, 48433K [2025-11-28T09:15:08.502Z] [21.162s][info][gc] GC(179) Concurrent marking (Young) 11.977ms [2025-11-28T09:15:08.502Z] [21.162s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.502Z] [21.171s][info][gc] GC(180) Verify Before Full GC, Level 4 (34344 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [21.171s][info][gc] GC(180) Cancel marking: Young [2025-11-28T09:15:08.502Z] [21.171s][info][gc] GC(180) Cancel marking: Old [2025-11-28T09:15:08.502Z] [21.171s][info][gc] GC(180) Cancel marking: Global [2025-11-28T09:15:08.502Z] [21.403s][info][gc] GC(180) Verify After Full GC, Level 4 (34240 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [21.404s][info][gc] GC(180) Pause Full 946M->687M(1024M) 241.714ms [2025-11-28T09:15:08.502Z] Allocated: 727 Mb [2025-11-28T09:15:08.502Z] Allocated: 720 Mb [2025-11-28T09:15:08.502Z] [21.419s][info][gc] Trigger (Young): Average GC time (57.00 ms) is above the time for average allocation rate (2020 MB/s) to deplete free headroom (103M) (margin of error = 1.86) [2025-11-28T09:15:08.502Z] [21.419s][info][gc] GC(181) Concurrent reset (Young) 0.444ms [2025-11-28T09:15:08.502Z] [21.428s][info][gc] GC(181) Verify Before Mark, Level 4 (21 reachable, 0 marked) [2025-11-28T09:15:08.502Z] [21.428s][info][gc] GC(181) Pause Init Mark (Young) 3.507ms [2025-11-28T09:15:08.502Z] Allocated: 711 Mb [2025-11-28T09:15:08.502Z] [21.428s][info][gc] GC(181) Concurrent remembered set scanning 0.195ms [2025-11-28T09:15:08.502Z] [21.429s][info][gc] GC(181) Concurrent marking roots 0.238ms [2025-11-28T09:15:08.502Z] [21.429s][info][gc] GC(181) Concurrent marking (Young) 0.211ms [2025-11-28T09:15:08.502Z] [21.433s][info][gc] GC(181) Verify After Mark, Level 4 (22 reachable, 5 marked) [2025-11-28T09:15:08.502Z] [21.433s][info][gc] GC(181) Pause Final Mark (Young) 4.182ms [2025-11-28T09:15:08.502Z] [21.433s][info][gc] GC(181) Concurrent weak references (Young) 0.019ms [2025-11-28T09:15:08.502Z] [21.433s][info][gc] GC(181) Concurrent weak roots (Young) 0.179ms [2025-11-28T09:15:08.502Z] [21.433s][info][gc] GC(181) Concurrent cleanup (Young) 725M->725M(1024M) 0.036ms [2025-11-28T09:15:08.502Z] Allocated: 664 Mb [2025-11-28T09:15:08.502Z] [21.436s][info][gc] GC(181) Concurrent Final Roots (Young) 2.133ms [2025-11-28T09:15:08.502Z] [21.443s][info][gc] GC(181) Concurrent reset after collect (Young) 7.103ms [2025-11-28T09:15:08.503Z] [21.445s][info][gc] Trigger (Young): Average GC time (48.83 ms) is above the time for average allocation rate (4034 MB/s) to deplete free headroom (147M) (margin of error = 1.85) [2025-11-28T09:15:08.503Z] [21.445s][info][gc] GC(182) Concurrent reset (Young) 0.427ms [2025-11-28T09:15:08.503Z] [21.450s][info][gc] GC(182) Verify Before Mark, Level 4 (22 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [21.450s][info][gc] GC(182) Pause Init Mark (Young) 3.279ms [2025-11-28T09:15:08.503Z] Allocated: 711 Mb [2025-11-28T09:15:08.503Z] [21.451s][info][gc] GC(182) Concurrent remembered set scanning 0.186ms [2025-11-28T09:15:08.503Z] [21.451s][info][gc] GC(182) Concurrent marking roots 0.238ms [2025-11-28T09:15:08.503Z] Allocated: 663 Mb [2025-11-28T09:15:08.503Z] [21.454s][info][gc] GC(182) Concurrent marking (Young) 2.762ms [2025-11-28T09:15:08.503Z] [21.466s][info][gc] GC(182) Verify Before Evacuation, Level 4 (23 reachable, 11 marked) [2025-11-28T09:15:08.503Z] [21.466s][info][gc] GC(182) Pause Final Mark (Young) 3.961ms [2025-11-28T09:15:08.503Z] Allocated: 715 Mb [2025-11-28T09:15:08.503Z] [21.467s][info][gc] GC(182) Concurrent thread roots 0.068ms [2025-11-28T09:15:08.503Z] [21.467s][info][gc] GC(182) Concurrent weak references (Young) 0.016ms [2025-11-28T09:15:08.503Z] [21.467s][info][gc] GC(182) Concurrent weak roots (Young) 0.070ms [2025-11-28T09:15:08.503Z] [21.467s][info][gc] GC(182) Concurrent cleanup (Young) 785M->785M(1024M) 0.042ms [2025-11-28T09:15:08.503Z] [21.467s][info][gc] GC(182) Concurrent strong roots 0.164ms [2025-11-28T09:15:08.503Z] [21.467s][info][gc] GC(182) Concurrent evacuation 0.086ms [2025-11-28T09:15:08.503Z] [21.467s][info][gc] GC(182) Concurrent Init Update Refs (Young) 0.031ms [2025-11-28T09:15:08.503Z] [21.469s][info][gc] GC(182) Verify Before Updating References, Level 4 (24 reachable, 17 marked) [2025-11-28T09:15:08.503Z] [21.469s][info][gc] GC(182) Pause Init Update Refs 1.692ms [2025-11-28T09:15:08.503Z] [21.475s][info][gc] GC(182) Concurrent update references 2.325ms [2025-11-28T09:15:08.503Z] Allocated: 713 Mb [2025-11-28T09:15:08.503Z] [21.478s][info][gc] GC(182) Concurrent update thread roots 3.219ms [2025-11-28T09:15:08.503Z] [21.489s][info][gc] GC(182) Verify After Updating References, Level 4 (23 reachable, 23 marked) [2025-11-28T09:15:08.503Z] [21.489s][info][gc] GC(182) Pause Final Update Refs 3.773ms [2025-11-28T09:15:08.503Z] [21.489s][info][gc] GC(182) Concurrent cleanup (Young) 869M->869M(1024M) 0.013ms [2025-11-28T09:15:08.503Z] Allocated: 711 Mb [2025-11-28T09:15:08.503Z] [21.492s][info][gc] Failed to allocate Shared, 47103K [2025-11-28T09:15:08.503Z] [21.493s][info][gc] GC(182) Concurrent reset after collect (Young) 4.055ms [2025-11-28T09:15:08.503Z] [21.493s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.503Z] [21.502s][info][gc] GC(183) Verify Before Full GC, Level 4 (34345 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [21.689s][info][gc] GC(183) Verify After Full GC, Level 4 (34241 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [21.689s][info][gc] GC(183) Pause Full 869M->686M(1024M) 195.546ms [2025-11-28T09:15:08.503Z] Allocated: 723 Mb [2025-11-28T09:15:08.503Z] Allocated: 724 Mb [2025-11-28T09:15:08.503Z] Allocated: 682 Mb [2025-11-28T09:15:08.503Z] Allocated: 713 Mb [2025-11-28T09:15:08.503Z] Allocated: 669 Mb [2025-11-28T09:15:08.503Z] [21.709s][info][gc] Trigger (Young): Average GC time (60.74 ms) is above the time for average allocation rate (1549 MB/s) to deplete free headroom (62515K) (margin of error = 1.86) [2025-11-28T09:15:08.503Z] [21.710s][info][gc] GC(184) Concurrent reset (Young) 0.449ms [2025-11-28T09:15:08.503Z] [21.721s][info][gc] GC(184) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [21.721s][info][gc] GC(184) Pause Init Mark (Young) 3.572ms [2025-11-28T09:15:08.503Z] Allocated: 717 Mb [2025-11-28T09:15:08.503Z] [21.722s][info][gc] GC(184) Concurrent remembered set scanning 0.229ms [2025-11-28T09:15:08.503Z] [21.723s][info][gc] GC(184) Concurrent marking roots 0.245ms [2025-11-28T09:15:08.503Z] Allocated: 714 Mb [2025-11-28T09:15:08.503Z] Allocated: 663 Mb [2025-11-28T09:15:08.503Z] [21.729s][info][gc] GC(184) Concurrent marking (Young) 6.460ms [2025-11-28T09:15:08.503Z] [21.741s][info][gc] GC(184) Verify Before Evacuation, Level 4 (26 reachable, 15 marked) [2025-11-28T09:15:08.503Z] [21.741s][info][gc] GC(184) Pause Final Mark (Young) 4.056ms [2025-11-28T09:15:08.503Z] Allocated: 714 Mb [2025-11-28T09:15:08.503Z] [21.741s][info][gc] GC(184) Concurrent thread roots 0.069ms [2025-11-28T09:15:08.503Z] [21.741s][info][gc] GC(184) Concurrent weak references (Young) 0.015ms [2025-11-28T09:15:08.503Z] [21.741s][info][gc] GC(184) Concurrent weak roots (Young) 0.081ms [2025-11-28T09:15:08.503Z] [21.741s][info][gc] GC(184) Concurrent cleanup (Young) 784M->784M(1024M) 0.052ms [2025-11-28T09:15:08.503Z] [21.741s][info][gc] GC(184) Concurrent strong roots 0.181ms [2025-11-28T09:15:08.503Z] [21.742s][info][gc] GC(184) Concurrent evacuation 0.150ms [2025-11-28T09:15:08.503Z] [21.742s][info][gc] GC(184) Concurrent Init Update Refs (Young) 0.038ms [2025-11-28T09:15:08.503Z] [21.743s][info][gc] GC(184) Verify Before Updating References, Level 4 (27 reachable, 34 marked) [2025-11-28T09:15:08.503Z] [21.743s][info][gc] GC(184) Pause Init Update Refs 1.816ms [2025-11-28T09:15:08.503Z] [21.750s][info][gc] GC(184) Concurrent update references 2.263ms [2025-11-28T09:15:08.503Z] Allocated: 695 Mb [2025-11-28T09:15:08.503Z] [21.753s][info][gc] GC(184) Concurrent update thread roots 3.261ms [2025-11-28T09:15:08.503Z] [21.762s][info][gc] GC(184) Verify After Updating References, Level 4 (25 reachable, 28 marked) [2025-11-28T09:15:08.503Z] [21.762s][info][gc] GC(184) Pause Final Update Refs 3.570ms [2025-11-28T09:15:08.503Z] [21.762s][info][gc] GC(184) Concurrent cleanup (Young) 855M->855M(1024M) 0.013ms [2025-11-28T09:15:08.503Z] Allocated: 727 Mb [2025-11-28T09:15:08.503Z] Allocated: 692 Mb [2025-11-28T09:15:08.503Z] Allocated: 704 Mb [2025-11-28T09:15:08.503Z] Allocated: 654 Mb [2025-11-28T09:15:08.503Z] [21.770s][info][gc] GC(184) Concurrent reset after collect (Young) 7.696ms [2025-11-28T09:15:08.503Z] [21.770s][info][gc] Trigger (Young): Free (76800K) is below minimum threshold (102M) [2025-11-28T09:15:08.503Z] [21.771s][info][gc] GC(185) Concurrent reset (Young) 0.400ms [2025-11-28T09:15:08.503Z] [21.777s][info][gc] GC(185) Verify Before Mark, Level 4 (24 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [21.777s][info][gc] GC(185) Pause Init Mark (Young) 3.211ms [2025-11-28T09:15:08.503Z] Allocated: 678 Mb [2025-11-28T09:15:08.503Z] [21.778s][info][gc] GC(185) Concurrent remembered set scanning 0.164ms [2025-11-28T09:15:08.503Z] [21.778s][info][gc] GC(185) Concurrent marking roots 0.237ms [2025-11-28T09:15:08.503Z] [21.779s][info][gc] GC(185) Concurrent marking (Young) 0.610ms [2025-11-28T09:15:08.503Z] [21.789s][info][gc] GC(185) Verify After Mark, Level 4 (25 reachable, 6 marked) [2025-11-28T09:15:08.503Z] [21.789s][info][gc] GC(185) Pause Final Mark (Young) 5.265ms [2025-11-28T09:15:08.503Z] Allocated: 711 Mb [2025-11-28T09:15:08.503Z] [21.789s][info][gc] GC(185) Concurrent weak references (Young) 0.032ms [2025-11-28T09:15:08.503Z] [21.789s][info][gc] Failed to allocate Shared, 33014K [2025-11-28T09:15:08.503Z] [21.790s][info][gc] GC(185) Concurrent weak roots (Young) 0.239ms [2025-11-28T09:15:08.503Z] [21.790s][info][gc] GC(185) Concurrent cleanup (Young) 719M->719M(1024M) 0.057ms [2025-11-28T09:15:08.503Z] [21.790s][info][gc] GC(185) Concurrent Final Roots (Young) 0.055ms [2025-11-28T09:15:08.503Z] [21.794s][info][gc] GC(185) Concurrent reset after collect (Young) 3.841ms [2025-11-28T09:15:08.503Z] [21.794s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.503Z] [21.802s][info][gc] GC(186) Verify Before Full GC, Level 4 (34345 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.015s][info][gc] GC(186) Verify After Full GC, Level 4 (34241 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.015s][info][gc] GC(186) Pause Full 719M->668M(1024M) 221.568ms [2025-11-28T09:15:08.503Z] Allocated: 694 Mb [2025-11-28T09:15:08.503Z] Allocated: 723 Mb [2025-11-28T09:15:08.503Z] Allocated: 725 Mb [2025-11-28T09:15:08.503Z] Allocated: 685 Mb [2025-11-28T09:15:08.503Z] Allocated: 732 Mb [2025-11-28T09:15:08.503Z] [22.041s][info][gc] Trigger (Young): Average GC time (67.21 ms) is above the time for average allocation rate (1323 MB/s) to deplete free headroom (69171K) (margin of error = 1.88) [2025-11-28T09:15:08.503Z] [22.041s][info][gc] GC(187) Concurrent reset (Young) 0.480ms [2025-11-28T09:15:08.503Z] [22.049s][info][gc] GC(187) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.049s][info][gc] GC(187) Pause Init Mark (Young) 3.516ms [2025-11-28T09:15:08.503Z] Allocated: 706 Mb [2025-11-28T09:15:08.503Z] [22.050s][info][gc] GC(187) Concurrent remembered set scanning 0.191ms [2025-11-28T09:15:08.503Z] [22.050s][info][gc] GC(187) Concurrent marking roots 0.246ms [2025-11-28T09:15:08.503Z] Allocated: 731 Mb [2025-11-28T09:15:08.503Z] [22.057s][info][gc] GC(187) Concurrent marking (Young) 6.927ms [2025-11-28T09:15:08.503Z] [22.063s][info][gc] GC(187) Verify After Mark, Level 4 (25 reachable, 12 marked) [2025-11-28T09:15:08.503Z] [22.063s][info][gc] GC(187) Pause Final Mark (Young) 3.963ms [2025-11-28T09:15:08.503Z] Allocated: 700 Mb [2025-11-28T09:15:08.503Z] [22.063s][info][gc] GC(187) Concurrent weak references (Young) 0.029ms [2025-11-28T09:15:08.503Z] [22.067s][info][gc] GC(187) Concurrent weak roots (Young) 3.991ms [2025-11-28T09:15:08.503Z] [22.067s][info][gc] GC(187) Concurrent cleanup (Young) 801M->801M(1024M) 0.058ms [2025-11-28T09:15:08.503Z] Allocated: 715 Mb [2025-11-28T09:15:08.503Z] [22.071s][info][gc] GC(187) Concurrent Final Roots (Young) 3.211ms [2025-11-28T09:15:08.503Z] Allocated: 709 Mb [2025-11-28T09:15:08.503Z] [22.078s][info][gc] GC(187) Concurrent reset after collect (Young) 7.243ms [2025-11-28T09:15:08.503Z] [22.079s][info][gc] Trigger (Young): Free (100864K) is below minimum threshold (102M) [2025-11-28T09:15:08.503Z] [22.079s][info][gc] GC(188) Concurrent reset (Young) 0.422ms [2025-11-28T09:15:08.503Z] [22.090s][info][gc] GC(188) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.090s][info][gc] GC(188) Pause Init Mark (Young) 3.436ms [2025-11-28T09:15:08.503Z] Allocated: 709 Mb [2025-11-28T09:15:08.503Z] [22.092s][info][gc] GC(188) Concurrent remembered set scanning 0.213ms [2025-11-28T09:15:08.503Z] [22.092s][info][gc] GC(188) Concurrent marking roots 0.246ms [2025-11-28T09:15:08.503Z] Allocated: 687 Mb [2025-11-28T09:15:08.503Z] Allocated: 706 Mb [2025-11-28T09:15:08.503Z] [22.099s][info][gc] Failed to allocate Shared, 51627K [2025-11-28T09:15:08.503Z] [22.100s][info][gc] GC(188) Concurrent marking (Young) 7.568ms [2025-11-28T09:15:08.503Z] [22.100s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.503Z] [22.109s][info][gc] GC(189) Verify Before Full GC, Level 4 (34348 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.109s][info][gc] GC(189) Cancel marking: Young [2025-11-28T09:15:08.503Z] [22.109s][info][gc] GC(189) Cancel marking: Old [2025-11-28T09:15:08.503Z] [22.109s][info][gc] GC(189) Cancel marking: Global [2025-11-28T09:15:08.503Z] [22.355s][info][gc] GC(189) Verify After Full GC, Level 4 (34244 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.355s][info][gc] GC(189) Pause Full 917M->695M(1024M) 254.689ms [2025-11-28T09:15:08.503Z] Allocated: 737 Mb [2025-11-28T09:15:08.503Z] Allocated: 685 Mb [2025-11-28T09:15:08.503Z] Allocated: 742 Mb [2025-11-28T09:15:08.503Z] [22.373s][info][gc] Trigger (Young): Average GC time (58.41 ms) is above the time for average allocation rate (1579 MB/s) to deplete free headroom (91187K) (margin of error = 1.88) [2025-11-28T09:15:08.503Z] [22.373s][info][gc] GC(190) Concurrent reset (Young) 0.471ms [2025-11-28T09:15:08.503Z] [22.381s][info][gc] GC(190) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.381s][info][gc] GC(190) Pause Init Mark (Young) 3.529ms [2025-11-28T09:15:08.503Z] Allocated: 722 Mb [2025-11-28T09:15:08.503Z] [22.382s][info][gc] GC(190) Concurrent remembered set scanning 0.204ms [2025-11-28T09:15:08.503Z] [22.382s][info][gc] GC(190) Concurrent marking roots 0.244ms [2025-11-28T09:15:08.503Z] [22.382s][info][gc] GC(190) Concurrent marking (Young) 0.232ms [2025-11-28T09:15:08.503Z] [22.386s][info][gc] GC(190) Verify After Mark, Level 4 (26 reachable, 5 marked) [2025-11-28T09:15:08.503Z] [22.386s][info][gc] GC(190) Pause Final Mark (Young) 4.097ms [2025-11-28T09:15:08.503Z] Allocated: 717 Mb [2025-11-28T09:15:08.503Z] [22.390s][info][gc] GC(190) Concurrent weak references (Young) 0.024ms [2025-11-28T09:15:08.503Z] Allocated: 701 Mb [2025-11-28T09:15:08.503Z] Allocated: 689 Mb [2025-11-28T09:15:08.503Z] [22.392s][info][gc] GC(190) Concurrent weak roots (Young) 2.161ms [2025-11-28T09:15:08.503Z] [22.393s][info][gc] GC(190) Concurrent cleanup (Young) 779M->779M(1024M) 0.043ms [2025-11-28T09:15:08.503Z] Allocated: 704 Mb [2025-11-28T09:15:08.503Z] [22.396s][info][gc] GC(190) Concurrent Final Roots (Young) 3.256ms [2025-11-28T09:15:08.503Z] Allocated: 683 Mb [2025-11-28T09:15:08.503Z] [22.403s][info][gc] GC(190) Concurrent reset after collect (Young) 7.129ms [2025-11-28T09:15:08.503Z] Allocated: 692 Mb [2025-11-28T09:15:08.503Z] [22.404s][info][gc] Trigger (Young): Average GC time (49.98 ms) is above the time for average allocation rate (3341 MB/s) to deplete free headroom (100659K) (margin of error = 1.88) [2025-11-28T09:15:08.503Z] [22.404s][info][gc] GC(191) Concurrent reset (Young) 0.406ms [2025-11-28T09:15:08.503Z] [22.410s][info][gc] GC(191) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.410s][info][gc] GC(191) Pause Init Mark (Young) 3.291ms [2025-11-28T09:15:08.503Z] Allocated: 706 Mb [2025-11-28T09:15:08.503Z] [22.410s][info][gc] Failed to allocate Shared, 56242K [2025-11-28T09:15:08.503Z] [22.410s][info][gc] GC(191) Concurrent remembered set scanning 0.208ms [2025-11-28T09:15:08.503Z] [22.411s][info][gc] GC(191) Concurrent marking roots 0.253ms [2025-11-28T09:15:08.503Z] [22.411s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.503Z] [22.418s][info][gc] GC(192) Verify Before Full GC, Level 4 (34348 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.419s][info][gc] GC(192) Cancel marking: Young [2025-11-28T09:15:08.503Z] [22.419s][info][gc] GC(192) Cancel marking: Old [2025-11-28T09:15:08.503Z] [22.419s][info][gc] GC(192) Cancel marking: Global [2025-11-28T09:15:08.503Z] [22.629s][info][gc] GC(192) Verify After Full GC, Level 4 (34244 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.629s][info][gc] GC(192) Pause Full 823M->705M(1024M) 218.314ms [2025-11-28T09:15:08.503Z] Allocated: 752 Mb [2025-11-28T09:15:08.503Z] Allocated: 706 Mb [2025-11-28T09:15:08.503Z] Allocated: 693 Mb [2025-11-28T09:15:08.503Z] Allocated: 702 Mb [2025-11-28T09:15:08.503Z] Allocated: 698 Mb [2025-11-28T09:15:08.503Z] Allocated: 710 Mb [2025-11-28T09:15:08.503Z] Allocated: 712 Mb [2025-11-28T09:15:08.503Z] [22.651s][info][gc] Trigger (Young): Free (101M) is below minimum threshold (102M) [2025-11-28T09:15:08.503Z] [22.651s][info][gc] GC(193) Concurrent reset (Young) 0.450ms [2025-11-28T09:15:08.503Z] [22.660s][info][gc] GC(193) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.660s][info][gc] GC(193) Pause Init Mark (Young) 3.536ms [2025-11-28T09:15:08.503Z] Allocated: 703 Mb [2025-11-28T09:15:08.503Z] [22.660s][info][gc] GC(193) Concurrent remembered set scanning 0.227ms [2025-11-28T09:15:08.503Z] [22.661s][info][gc] GC(193) Concurrent marking roots 0.271ms [2025-11-28T09:15:08.503Z] [22.661s][info][gc] GC(193) Concurrent marking (Young) 0.229ms [2025-11-28T09:15:08.503Z] [22.666s][info][gc] GC(193) Verify After Mark, Level 4 (27 reachable, 5 marked) [2025-11-28T09:15:08.503Z] [22.666s][info][gc] GC(193) Pause Final Mark (Young) 5.424ms [2025-11-28T09:15:08.503Z] [22.667s][info][gc] GC(193) Concurrent weak references (Young) 0.020ms [2025-11-28T09:15:08.503Z] [22.668s][info][gc] GC(193) Concurrent weak roots (Young) 1.028ms [2025-11-28T09:15:08.503Z] [22.668s][info][gc] GC(193) Concurrent cleanup (Young) 742M->742M(1024M) 0.047ms [2025-11-28T09:15:08.503Z] Allocated: 719 Mb [2025-11-28T09:15:08.503Z] [22.673s][info][gc] GC(193) Concurrent Final Roots (Young) 5.399ms [2025-11-28T09:15:08.503Z] [22.681s][info][gc] GC(193) Concurrent reset after collect (Young) 8.172ms [2025-11-28T09:15:08.503Z] Allocated: 678 Mb [2025-11-28T09:15:08.503Z] [22.682s][info][gc] Trigger (Young): Average GC time (44.07 ms) is above the time for average allocation rate (3228 MB/s) to deplete free headroom (83763K) (margin of error = 1.88) [2025-11-28T09:15:08.503Z] [22.683s][info][gc] GC(194) Concurrent reset (Young) 0.421ms [2025-11-28T09:15:08.503Z] [22.697s][info][gc] GC(194) Verify Before Mark, Level 4 (25 reachable, 0 marked) [2025-11-28T09:15:08.503Z] [22.697s][info][gc] GC(194) Pause Init Mark (Young) 3.590ms [2025-11-28T09:15:08.503Z] Allocated: 741 Mb [2025-11-28T09:15:08.503Z] Allocated: 695 Mb [2025-11-28T09:15:08.503Z] [22.698s][info][gc] GC(194) Concurrent remembered set scanning 0.854ms [2025-11-28T09:15:08.505Z] [22.699s][info][gc] GC(194) Concurrent marking roots 0.254ms [2025-11-28T09:15:08.505Z] [22.699s][info][gc] GC(194) Concurrent marking (Young) 0.288ms [2025-11-28T09:15:08.505Z] [22.703s][info][gc] GC(194) Verify Before Evacuation, Level 4 (24 reachable, 12 marked) [2025-11-28T09:15:08.505Z] [22.703s][info][gc] GC(194) Pause Final Mark (Young) 3.587ms [2025-11-28T09:15:08.505Z] Allocated: 698 Mb [2025-11-28T09:15:08.505Z] [22.703s][info][gc] GC(194) Concurrent thread roots 0.767ms [2025-11-28T09:15:08.505Z] [22.703s][info][gc] GC(194) Concurrent weak references (Young) 0.024ms [2025-11-28T09:15:08.505Z] [22.704s][info][gc] GC(194) Concurrent weak roots (Young) 0.079ms [2025-11-28T09:15:08.505Z] [22.704s][info][gc] GC(194) Concurrent cleanup (Young) 757M->757M(1024M) 0.035ms [2025-11-28T09:15:08.505Z] [22.704s][info][gc] GC(194) Concurrent strong roots 0.156ms [2025-11-28T09:15:08.505Z] [22.704s][info][gc] GC(194) Concurrent evacuation 0.064ms [2025-11-28T09:15:08.505Z] [22.704s][info][gc] GC(194) Concurrent Init Update Refs (Young) 0.031ms [2025-11-28T09:15:08.505Z] [22.707s][info][gc] GC(194) Verify Before Updating References, Level 4 (25 reachable, 19 marked) [2025-11-28T09:15:08.505Z] [22.707s][info][gc] GC(194) Pause Init Update Refs 2.979ms [2025-11-28T09:15:08.505Z] Allocated: 702 Mb [2025-11-28T09:15:08.505Z] [22.710s][info][gc] GC(194) Concurrent update references 2.410ms [2025-11-28T09:15:08.505Z] [22.710s][info][gc] GC(194) Concurrent update thread roots 0.089ms [2025-11-28T09:15:08.505Z] [22.713s][info][gc] GC(194) Verify After Updating References, Level 4 (26 reachable, 22 marked) [2025-11-28T09:15:08.505Z] [22.713s][info][gc] GC(194) Pause Final Update Refs 3.318ms [2025-11-28T09:15:08.505Z] [22.713s][info][gc] GC(194) Concurrent cleanup (Young) 760M->760M(1024M) 0.013ms [2025-11-28T09:15:08.505Z] [22.721s][info][gc] GC(194) Concurrent reset after collect (Young) 7.735ms [2025-11-28T09:15:08.505Z] Allocated: 681 Mb [2025-11-28T09:15:08.505Z] [22.722s][info][gc] Trigger (Young): Average GC time (45.98 ms) is above the time for average allocation rate (3652 MB/s) to deplete free headroom (122M) (margin of error = 1.88) [2025-11-28T09:15:08.505Z] [22.723s][info][gc] GC(195) Concurrent reset (Young) 0.396ms [2025-11-28T09:15:08.505Z] [22.728s][info][gc] GC(195) Verify Before Mark, Level 4 (26 reachable, 0 marked) [2025-11-28T09:15:08.505Z] [22.728s][info][gc] GC(195) Pause Init Mark (Young) 3.312ms [2025-11-28T09:15:08.505Z] Allocated: 692 Mb [2025-11-28T09:15:08.505Z] [22.729s][info][gc] GC(195) Concurrent remembered set scanning 0.174ms [2025-11-28T09:15:08.505Z] [22.729s][info][gc] GC(195) Concurrent marking roots 0.254ms [2025-11-28T09:15:08.505Z] [22.729s][info][gc] GC(195) Concurrent marking (Young) 0.237ms [2025-11-28T09:15:08.505Z] [22.734s][info][gc] GC(195) Verify Before Evacuation, Level 4 (27 reachable, 5 marked) [2025-11-28T09:15:08.505Z] [22.734s][info][gc] GC(195) Pause Final Mark (Young) 4.635ms [2025-11-28T09:15:08.505Z] [22.737s][info][gc] GC(195) Concurrent thread roots 0.086ms [2025-11-28T09:15:08.505Z] [22.737s][info][gc] GC(195) Concurrent weak references (Young) 0.042ms [2025-11-28T09:15:08.505Z] Allocated: 731 Mb [2025-11-28T09:15:08.505Z] [22.741s][info][gc] Failed to allocate Shared, 57085K [2025-11-28T09:15:08.505Z] [22.742s][info][gc] GC(195) Concurrent weak roots (Young) 4.307ms [2025-11-28T09:15:08.505Z] [22.742s][info][gc] GC(195) Concurrent cleanup (Young) 740M->740M(1024M) 0.095ms [2025-11-28T09:15:08.505Z] [22.742s][info][gc] GC(195) Concurrent strong roots 0.212ms [2025-11-28T09:15:08.505Z] [22.742s][info][gc] GC(195) Concurrent evacuation 0.016ms [2025-11-28T09:15:08.505Z] [22.742s][info][gc] Trigger (Young): Handle Allocation Failure [2025-11-28T09:15:08.505Z] [22.751s][info][gc] GC(196) Verify Before Full GC, Level 4 (34348 reachable, 0 marked) [2025-11-28T09:15:08.505Z] [22.996s][info][gc] GC(196) Verify After Full GC, Level 4 (34244 reachable, 0 marked) [2025-11-28T09:15:08.505Z] [22.996s][info][gc] GC(196) Pause Full 740M->685M(1024M) 253.707ms [2025-11-28T09:15:08.505Z] Allocated: 733 Mb [2025-11-28T09:15:08.505Z] Allocated: 714 Mb [2025-11-28T09:15:08.505Z] Allocated: 687 Mb [2025-11-28T09:15:08.505Z] [23.015s][info][gc] Trigger (Young): Average GC time (45.98 ms) is above the time for average allocation rate (1184 MB/s) to deplete free headroom (55347K) (margin of error = 1.88) [2025-11-28T09:15:08.505Z] [23.016s][info][gc] GC(197) Concurrent reset (Young) 0.449ms [2025-11-28T09:15:08.505Z] [23.029s][info][gc] GC(197) Verify Before Mark, Level 4 (27 reachable, 0 marked) [2025-11-28T09:15:08.505Z] [23.029s][info][gc] GC(197) Pause Init Mark (Young) 3.784ms [2025-11-28T09:15:08.505Z] Allocated: 747 Mb [2025-11-28T09:15:08.505Z] [23.030s][info][gc] GC(197) Concurrent remembered set scanning 0.306ms [2025-11-28T09:15:08.505Z] [23.030s][info][gc] GC(197) Concurrent marking roots 0.280ms [2025-11-28T09:15:08.505Z] Allocated: 714 Mb [2025-11-28T09:15:08.505Z] Allocated: 702 Mb [2025-11-28T09:15:08.505Z] Allocated: 680 Mb [2025-11-28T09:15:08.505Z] [23.042s][info][gc] GC(197) Concurrent marking (Young) 12.047ms [2025-11-28T09:15:08.505Z] # [2025-11-28T09:15:08.505Z] # A fatal error has been detected by the Java Runtime Environment: [2025-11-28T09:15:08.505Z] # [2025-11-28T09:15:08.505Z] # Internal Error (shenandoahVerifier.cpp:105), pid=2007366, tid=2007370 [2025-11-28T09:15:08.505Z] # Error: After Mark, Roots; Object end should be within the region [2025-11-28T09:15:08.505Z] [2025-11-28T09:15:08.505Z] Referenced from: [2025-11-28T09:15:08.505Z] interior location: 0x00000000c0244bb0 [2025-11-28T09:15:08.505Z] inside Java heap [2025-11-28T09:15:08.505Z] not in collection set [2025-11-28T09:15:08.505Z] region: | 4|R |O|BTE c0200000, c0244c10, c0280000|TAMS c0200000|UWM c0244c10|U 275K|T 0B|G 0B|P 0B|S 275K|L 275K|CP 0 [2025-11-28T09:15:08.505Z] [2025-11-28T09:15:08.505Z] Object: [2025-11-28T09:15:08.505Z] 0x00000000da980000 - safe print, no details [2025-11-28T09:15:08.505Z] region: | 851|TR |Y|BTE da980000, daa00000, daa00000|TAMS daa00000|UWM daa00000|U 512K|T 0B|G 0B|P 0B|S 512K|L 0B|CP 0 [2025-11-28T09:15:08.505Z] [2025-11-28T09:15:08.505Z] Raw heap memory: [2025-11-28T09:15:08.505Z] 0x00000000da980000: 00000001 00000000 00175a00 0072cfa2 .........Z....r. [2025-11-28T09:15:08.505Z] 0x00000000da980010: 00000000 00000000 00000000 00000000 ................ [2025-11-28T09:15:08.505Z] 0x00000000da980020: 00000000 00000000 00000000 00000000 ................ [2025-11-28T09:15:08.505Z] 0x00000000da980030: 00000000 00000000 00000000 00000000 ................ [2025-11-28T09:15:08.505Z] 0x00000000da980040: 00000000 00000000 00000000 00000000 ................ [2025-11-28T09:15:08.505Z] 0x00000000da980050: 00000000 00000000 00000000 00000000 ................ [2025-11-28T09:15:08.505Z] 0x00000000da980060: 00000000 00000000 00000000 00000000 ................ [2025-11-28T09:15:08.505Z] 0x00000000da980070: 00000000 00000000 00000000 00000000 ................ [2025-11-28T09:15:08.505Z] [2025-11-28T09:15:08.505Z] [2025-11-28T09:15:08.505Z] # [2025-11-28T09:15:08.505Z] # JRE version: OpenJDK Runtime Environment Temurin-26+26-202511272135 (26.0+26) (build 26-beta+26-ea) [2025-11-28T09:15:08.505Z] # Java VM: OpenJDK 64-Bit Server VM Temurin-26+26-202511272135 (26-beta+26-ea, mixed mode, sharing, tiered, compressed oops, compressed class ptrs, shenandoah gc, linux-amd64) [2025-11-28T09:15:08.505Z] # 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_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/scratch/1/core.2007366) [2025-11-28T09:15:08.505Z] # [2025-11-28T09:15:08.505Z] # An error report file with more information is saved as: [2025-11-28T09:15:08.505Z] # /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/scratch/1/hs_err_pid2007366.log [2025-11-28T09:15:08.505Z] [thread 2007371 also had an error] [2025-11-28T09:15:08.505Z] # [2025-11-28T09:15:08.505Z] # If you would like to submit a bug report, please visit: [2025-11-28T09:15:08.505Z] # https://github.com/adoptium/adoptium-support/issues [2025-11-28T09:15:08.505Z] # [2025-11-28T09:15:08.505Z] STDERR: [2025-11-28T09:15:08.505Z] rerun: [2025-11-28T09:15:08.505Z] cd /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/scratch/1 && \ [2025-11-28T09:15:08.505Z] DISPLAY=:0 \ [2025-11-28T09:15:08.505Z] HOME=/home/jenkins \ [2025-11-28T09:15:08.505Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-11-28T09:15:08.506Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d/test/lib:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-11-28T09:15:08.506Z] /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image/bin/java \ [2025-11-28T09:15:08.506Z] -Dtest.vm.opts='-ea -esa -Xmx512m -Djava.awt.headless=true' \ [2025-11-28T09:15:08.506Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-Djava.awt.headless=true' \ [2025-11-28T09:15:08.506Z] -Dtest.compiler.opts= \ [2025-11-28T09:15:08.506Z] -Dtest.java.opts= \ [2025-11-28T09:15:08.506Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image \ [2025-11-28T09:15:08.506Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/j2sdk-image \ [2025-11-28T09:15:08.506Z] -Dtest.timeout.factor=8.0 \ [2025-11-28T09:15:08.506Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-11-28T09:15:08.506Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-11-28T09:15:08.506Z] -Dtest.name=gc/TestAllocHumongousFragment.java#generational \ [2025-11-28T09:15:08.506Z] -Dtest.verbose=Verbose[p=NONE,f=FULL,e=FULL,t=true,m=false] \ [2025-11-28T09:15:08.506Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/TestAllocHumongousFragment.java \ [2025-11-28T09:15:08.506Z] -Dtest.main.class=TestAllocHumongousFragment \ [2025-11-28T09:15:08.506Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc \ [2025-11-28T09:15:08.506Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-11-28T09:15:08.506Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d \ [2025-11-28T09:15:08.506Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T09:15:08.506Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc:/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/classes/1/gc/TestAllocHumongousFragment_generational.d/test/lib \ [2025-11-28T09:15:08.506Z] -ea \ [2025-11-28T09:15:08.506Z] -esa \ [2025-11-28T09:15:08.506Z] -Xmx512m \ [2025-11-28T09:15:08.506Z] -Djava.awt.headless=true \ [2025-11-28T09:15:08.506Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-11-28T09:15:08.506Z] -Xmx1g \ [2025-11-28T09:15:08.506Z] -Xms1g \ [2025-11-28T09:15:08.506Z] -Xlog:gc \ [2025-11-28T09:15:08.506Z] -XX:+UnlockDiagnosticVMOptions \ [2025-11-28T09:15:08.506Z] -XX:+UnlockExperimentalVMOptions \ [2025-11-28T09:15:08.506Z] -XX:ShenandoahTargetNumRegions=2048 \ [2025-11-28T09:15:08.506Z] -XX:+UseShenandoahGC \ [2025-11-28T09:15:08.506Z] -XX:ShenandoahGCHeuristics=adaptive \ [2025-11-28T09:15:08.506Z] -XX:ShenandoahGCMode=generational \ [2025-11-28T09:15:08.506Z] -XX:+ShenandoahVerify \ [2025-11-28T09:15:08.506Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work/gc/TestAllocHumongousFragment_generational.d/main.0.jta [2025-11-28T09:15:08.506Z] [2025-11-28T09:15:08.506Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 134] [2025-11-28T09:15:08.506Z] -------------------------------------------------- [2025-11-28T09:20:02.133Z] Test results: passed: 548; failed: 1; skipped: 4; excluded: 11; did not meet platform requirements: 24 [2025-11-28T09:20:02.133Z] Report written to /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/report/html/report.html [2025-11-28T09:20:02.133Z] Results written to /home/jenkins/workspace/Test_openjdk26_hs_extended.openjdk_x86-64_alpine-linux_rerun/aqa-tests/TKG/output_17643171125740/hotspot_gc_0/work [2025-11-28T09:20:02.133Z] Error: Some tests failed or other problems occurred. [2025-11-28T09:20:02.133Z] ----------------------------------- [2025-11-28T09:20:02.133Z] hotspot_gc_0_FAILED [2025-11-28T09:20:02.133Z] ----------------------------------- [2025-11-28T09:20:02.133Z] [2025-11-28T09:20:02.133Z] TEST TEARDOWN: [2025-11-28T09:20:02.133Z] Nothing to be done for teardown. [2025-11-28T09:20:02.133Z] hotspot_gc_0 Finish Time: Fri Nov 28 09:19:59 2025 Epoch Time (ms): 1764321599651