[2025-06-26T17:07:48.073Z] Running test hotspot_tier1_gc_0 ... [2025-06-26T17:07:48.073Z] =============================================== [2025-06-26T17:07:48.073Z] hotspot_tier1_gc_0 Start Time: Thu Jun 26 17:07:47 2025 Epoch Time (ms): 1750957667759 [2025-06-26T17:07:48.073Z] variation: NoOptions [2025-06-26T17:07:48.073Z] JVM_OPTIONS: [2025-06-26T17:07:48.073Z] { \ [2025-06-26T17:07:48.073Z] echo ""; echo "TEST SETUP:"; \ [2025-06-26T17:07:48.073Z] echo "Nothing to be done for setup."; \ [2025-06-26T17:07:48.073Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../TKG/output_17509576675065/hotspot_tier1_gc_0"; \ [2025-06-26T17:07:48.073Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../TKG/output_17509576675065/hotspot_tier1_gc_0"; \ [2025-06-26T17:07:48.073Z] echo ""; echo "TESTING:"; \ [2025-06-26T17:07:48.073Z] "/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-06-26T17:07:48.073Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:12 -xml:verify -concurrency:3 -nativepath:"/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2025-06-26T17:07:48.073Z] -w ""/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../TKG/output_17509576675065/hotspot_tier1_gc_0"/work" \ [2025-06-26T17:07:48.073Z] -r ""/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../TKG/output_17509576675065/hotspot_tier1_gc_0"/report" \ [2025-06-26T17:07:48.073Z] -jdk:"/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image" \ [2025-06-26T17:07:48.073Z] -exclude:"/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2025-06-26T17:07:48.073Z] -exclude:"/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk21.txt" \ [2025-06-26T17:07:48.073Z] \ [2025-06-26T17:07:48.073Z] -exclude:"/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk21.txt" \ [2025-06-26T17:07:48.073Z] "/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:tier1_gc"; \ [2025-06-26T17:07:48.073Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_tier1_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_tier1_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-26T17:07:48.073Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-26T17:07:48.073Z] echo "Nothing to be done for teardown."; \ [2025-06-26T17:07:48.073Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../TKG/output_17509576675065/TestTargetResult"; [2025-06-26T17:07:48.073Z] [2025-06-26T17:07:48.073Z] TEST SETUP: [2025-06-26T17:07:48.073Z] Nothing to be done for setup. [2025-06-26T17:07:48.073Z] [2025-06-26T17:07:48.073Z] TESTING: [2025-06-26T17:07:49.660Z] Directory "/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../TKG/output_17509576675065/hotspot_tier1_gc_0/work" not found: creating [2025-06-26T17:07:49.660Z] Directory "/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/../TKG/output_17509576675065/hotspot_tier1_gc_0/report" not found: creating [2025-06-26T17:08:08.746Z] XML output with verification to /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work Calling Pipeline was cancelled [2025-06-26T17:40:07.463Z] Sending interrupt signal to process [2025-06-26T17:40:21.037Z] -------------------------------------------------- [2025-06-26T17:40:21.037Z] TEST: gc/z/TestUncommit.java [2025-06-26T17:40:21.037Z] TEST JDK: /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image [2025-06-26T17:40:21.037Z] [2025-06-26T17:40:21.037Z] ACTION: build -- Passed. Build successful [2025-06-26T17:40:21.037Z] REASON: Named class compiled on demand [2025-06-26T17:40:21.037Z] TIME: 0.404 seconds [2025-06-26T17:40:21.037Z] messages: [2025-06-26T17:40:21.037Z] command: build gc.z.TestUncommit [2025-06-26T17:40:21.037Z] reason: Named class compiled on demand [2025-06-26T17:40:21.037Z] started: Thu Jun 26 17:39:00 GMT 2025 [2025-06-26T17:40:21.037Z] Test directory: [2025-06-26T17:40:21.037Z] compile: gc.z.TestUncommit [2025-06-26T17:40:21.037Z] finished: Thu Jun 26 17:39:01 GMT 2025 [2025-06-26T17:40:21.037Z] elapsed time (seconds): 0.404 [2025-06-26T17:40:21.037Z] [2025-06-26T17:40:21.037Z] ACTION: compile -- Passed. Compilation successful [2025-06-26T17:40:21.037Z] REASON: .class file out of date or does not exist [2025-06-26T17:40:21.037Z] TIME: 0.404 seconds [2025-06-26T17:40:21.037Z] messages: [2025-06-26T17:40:21.037Z] command: compile /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java [2025-06-26T17:40:21.037Z] reason: .class file out of date or does not exist [2025-06-26T17:40:21.037Z] started: Thu Jun 26 17:39:00 GMT 2025 [2025-06-26T17:40:21.037Z] Mode: agentvm [2025-06-26T17:40:21.037Z] Agent id: 1 [2025-06-26T17:40:21.037Z] finished: Thu Jun 26 17:39:01 GMT 2025 [2025-06-26T17:40:21.037Z] elapsed time (seconds): 0.404 [2025-06-26T17:40:21.037Z] configuration: [2025-06-26T17:40:21.037Z] Boot Layer (javac runtime environment) [2025-06-26T17:40:21.037Z] class path: /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-06-26T17:40:21.037Z] /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-06-26T17:40:21.037Z] patch: java.base /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/patches/java.base [2025-06-26T17:40:21.037Z] [2025-06-26T17:40:21.037Z] javac compilation environment [2025-06-26T17:40:21.037Z] source path: /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z [2025-06-26T17:40:21.037Z] /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-06-26T17:40:21.037Z] class path: /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z [2025-06-26T17:40:21.037Z] /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d [2025-06-26T17:40:21.037Z] /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/test/lib [2025-06-26T17:40:21.037Z] [2025-06-26T17:40:21.037Z] rerun: [2025-06-26T17:40:21.037Z] cd /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/scratch/2 && \ [2025-06-26T17:40:21.037Z] DISPLAY=:0 \ [2025-06-26T17:40:21.037Z] HOME=/home/jenkins \ [2025-06-26T17:40:21.037Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-06-26T17:40:21.037Z] /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image/bin/javac \ [2025-06-26T17:40:21.037Z] -J-ea \ [2025-06-26T17:40:21.037Z] -J-esa \ [2025-06-26T17:40:21.037Z] -J-Xmx512m \ [2025-06-26T17:40:21.037Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-06-26T17:40:21.037Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-06-26T17:40:21.037Z] -J-Dtest.compiler.opts= \ [2025-06-26T17:40:21.037Z] -J-Dtest.java.opts= \ [2025-06-26T17:40:21.037Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image \ [2025-06-26T17:40:21.037Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image \ [2025-06-26T17:40:21.037Z] -J-Dtest.timeout.factor=12.0 \ [2025-06-26T17:40:21.037Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-06-26T17:40:21.037Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-06-26T17:40:21.037Z] -J-Dtest.name=gc/z/TestUncommit.java \ [2025-06-26T17:40:21.037Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java \ [2025-06-26T17:40:21.037Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \ [2025-06-26T17:40:21.037Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-06-26T17:40:21.037Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d \ [2025-06-26T17:40:21.037Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/test/lib \ [2025-06-26T17:40:21.037Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/test/lib \ [2025-06-26T17:40:21.037Z] -d /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d \ [2025-06-26T17:40:21.037Z] -sourcepath /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-06-26T17:40:21.037Z] -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/test/lib /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java [2025-06-26T17:40:21.037Z] direct: [2025-06-26T17:40:21.037Z] Note: /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib/jdk/test/lib/Asserts.java uses unchecked or unsafe operations. [2025-06-26T17:40:21.037Z] Note: Recompile with -Xlint:unchecked for details. [2025-06-26T17:40:21.037Z] [2025-06-26T17:40:21.037Z] ACTION: main -- Failed. Unexpected exit from test [exit code: 143] [2025-06-26T17:40:21.037Z] REASON: User specified action: run main/othervm -XX:+UseZGC -XX:+ZGenerational -Xlog:gc*,gc+heap=debug,gc+stats=off -Xms128M -Xmx512M -XX:ZUncommitDelay=10 gc.z.TestUncommit [2025-06-26T17:40:21.037Z] TIME: 66.537 seconds [2025-06-26T17:40:21.037Z] messages: [2025-06-26T17:40:21.037Z] command: main -XX:+UseZGC -XX:+ZGenerational -Xlog:gc*,gc+heap=debug,gc+stats=off -Xms128M -Xmx512M -XX:ZUncommitDelay=10 gc.z.TestUncommit [2025-06-26T17:40:21.037Z] reason: User specified action: run main/othervm -XX:+UseZGC -XX:+ZGenerational -Xlog:gc*,gc+heap=debug,gc+stats=off -Xms128M -Xmx512M -XX:ZUncommitDelay=10 gc.z.TestUncommit [2025-06-26T17:40:21.037Z] started: Thu Jun 26 17:39:01 GMT 2025 [2025-06-26T17:40:21.037Z] Mode: othervm [/othervm specified] [2025-06-26T17:40:21.037Z] finished: Thu Jun 26 17:40:07 GMT 2025 [2025-06-26T17:40:21.037Z] elapsed time (seconds): 66.537 [2025-06-26T17:40:21.037Z] configuration: [2025-06-26T17:40:21.037Z] STDOUT: [2025-06-26T17:40:21.037Z] [0.027s][debug][gc,heap] Minimum heap 134217728 Initial heap 134217728 Maximum heap 536870912 [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Initializing The Z Garbage Collector [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Version: 21.0.8-beta+7-ea (release) [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Probing address space for the highest valid bit: 46 [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] NUMA Support: Disabled [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] CPUs: 160 total, 4 available [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Memory: 6144M [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Large Page Support: Disabled [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Address Space Type: Contiguous/Unrestricted/Complete [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Address Space Size: 8192M [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Heap Backing File: /memfd:java_heap [2025-06-26T17:40:21.037Z] [0.027s][info ][gc,init] Heap Backing Filesystem: tmpfs (0x1021994) [2025-06-26T17:40:21.037Z] [0.033s][info ][gc,init] Min Capacity: 128M [2025-06-26T17:40:21.037Z] [0.033s][info ][gc,init] Initial Capacity: 128M [2025-06-26T17:40:21.037Z] [0.033s][info ][gc,init] Max Capacity: 512M [2025-06-26T17:40:21.037Z] [0.033s][info ][gc,init] Soft Max Capacity: 512M [2025-06-26T17:40:21.037Z] [0.033s][info ][gc,init] Medium Page Size: 16M [2025-06-26T17:40:21.037Z] [0.033s][info ][gc,init] Pre-touch: Disabled [2025-06-26T17:40:21.037Z] [0.033s][info ][gc,init] Available space on backing filesystem: N/A [2025-06-26T17:40:21.037Z] [0.034s][info ][gc,init] Uncommit: Enabled [2025-06-26T17:40:21.037Z] [0.034s][info ][gc,init] Uncommit Delay: 10s [2025-06-26T17:40:21.037Z] [0.034s][info ][gc,init] GC Workers for Old Generation: 1 (dynamic) [2025-06-26T17:40:21.037Z] [0.041s][info ][gc,init] GC Workers for Young Generation: 1 (dynamic) [2025-06-26T17:40:21.037Z] [0.176s][info ][gc,init] GC Workers Max: 1 (dynamic) [2025-06-26T17:40:21.037Z] [0.226s][info ][gc,init] Runtime Workers: 3 [2025-06-26T17:40:21.037Z] [0.292s][info ][gc ] Using The Z Garbage Collector [2025-06-26T17:40:21.037Z] [0.355s][info ][gc,metaspace] CDS archive(s) mapped at: [0x0000004000000000-0x0000004000cb0000-0x0000004000cb0000), size 13303808, SharedBaseAddress: 0x0000004000000000, ArchiveRelocationMode: 1. [2025-06-26T17:40:21.037Z] [0.355s][info ][gc,metaspace] Compressed class space mapped at: 0x0000004001000000-0x0000004041000000, reserved size: 1073741824 [2025-06-26T17:40:21.037Z] [0.355s][info ][gc,metaspace] Narrow klass base: 0x0000004000000000, Narrow klass shift: 0, Narrow klass range: 0x100000000 [2025-06-26T17:40:21.037Z] [0.451s][debug][gc,heap ] Uncommit Timeout: 11s [2025-06-26T17:40:21.037Z] [0.107s] (MainThread) Iteration 0 [2025-06-26T17:40:21.037Z] [0.312s] (MainThread) Allocating [2025-06-26T17:40:21.037Z] [1.168s][info ][gc ] GC(0) Major Collection (Warmup) [2025-06-26T17:40:21.037Z] [1.168s][info ][gc,task ] GC(0) Using 1 Workers for Young Generation [2025-06-26T17:40:21.037Z] [1.168s][info ][gc,task ] GC(0) Using 1 Workers for Old Generation [2025-06-26T17:40:21.037Z] [1.168s][debug][gc,heap ] GC(0) Y: Heap before GC invocations=0 (full 0): [2025-06-26T17:40:21.037Z] [1.168s][debug][gc,heap ] GC(0) Y: ZHeap used 58M, capacity 128M, max capacity 512M [2025-06-26T17:40:21.037Z] [1.168s][debug][gc,heap ] GC(0) Y: Metaspace used 646K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.037Z] [1.168s][debug][gc,heap ] GC(0) Y: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.037Z] [1.168s][info ][gc,phases ] GC(0) Y: Young Generation [2025-06-26T17:40:21.037Z] [1.171s][info ][gc,phases ] GC(0) Y: Pause Mark Start (Major) 0.038ms [2025-06-26T17:40:21.037Z] [1.213s][info ][gc,phases ] GC(0) Y: Concurrent Mark 42.528ms [2025-06-26T17:40:21.037Z] [1.234s][info ][gc,phases ] GC(0) Y: Pause Mark End 0.018ms [2025-06-26T17:40:21.037Z] [1.234s][info ][gc,phases ] GC(0) Y: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.037Z] [1.234s][info ][gc,phases ] GC(0) Y: Concurrent Reset Relocation Set 0.001ms [2025-06-26T17:40:21.037Z] [1.268s][info ][gc,reloc ] GC(0) Y: Using tenuring threshold: 1 (Computed) [2025-06-26T17:40:21.037Z] [1.268s][info ][gc,phases ] GC(0) Y: Concurrent Select Relocation Set 33.725ms [2025-06-26T17:40:21.037Z] [1.273s][info ][gc,phases ] GC(0) Y: Pause Relocate Start 0.013ms [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,phases ] GC(0) Y: Concurrent Relocate 3.716ms [2025-06-26T17:40:21.037Z] [1.277s][debug][gc,heap ] GC(0) Y: Heap after GC invocations=1 (full 1): [2025-06-26T17:40:21.037Z] [1.277s][debug][gc,heap ] GC(0) Y: ZHeap used 148M, capacity 152M, max capacity 512M [2025-06-26T17:40:21.037Z] [1.277s][debug][gc,heap ] GC(0) Y: Metaspace used 646K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.037Z] [1.277s][debug][gc,heap ] GC(0) Y: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,alloc ] GC(0) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,alloc ] GC(0) Y: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,load ] GC(0) Y: Load: 52.96 (33%) / 59.16 (37%) / 50.70 (32%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,mmu ] GC(0) Y: MMU: 2ms/98.1%, 5ms/99.2%, 10ms/99.6%, 20ms/99.8%, 50ms/99.9%, 100ms/99.9% [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,marking ] GC(0) Y: Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,marking ] GC(0) Y: Mark Stack Usage: 32M [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,nmethod ] GC(0) Y: NMethods: 299 registered, 0 unregistered [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,metaspace] GC(0) Y: Metaspace: 0M used, 0M committed, 1088M reserved [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,reloc ] GC(0) Y: Candidates Selected In-Place Size Empty Relocated [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,reloc ] GC(0) Y: Small Pages: 29 1 0 58M 0M 1M [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,reloc ] GC(0) Y: Medium Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,reloc ] GC(0) Y: Large Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,reloc ] GC(0) Y: Forwarding Usage: 0M [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,reloc ] GC(0) Y: Age Table: [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,reloc ] GC(0) Y: Live Garbage Small Medium Large [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,reloc ] GC(0) Y: Eden 54M (11%) 3M (1%) 29 / 1 0 / 0 0 / 0 [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Min Capacity: 128M(25%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Max Capacity: 512M(100%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Heap Statistics: [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Capacity: 128M (25%) 128M (25%) 148M (29%) 152M (30%) 152M (30%) 128M (25%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Free: 454M (89%) 396M (77%) 364M (71%) 364M (71%) 454M (89%) 360M (70%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Used: 58M (11%) 116M (23%) 148M (29%) 148M (29%) 152M (30%) 58M (11%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Young Generation Statistics: [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Used: 58M (11%) 116M (23%) 148M (29%) 148M (29%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Live: - 54M (11%) 54M (11%) 54M (11%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Garbage: - 3M (1%) 3M (1%) 0M (0%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Allocated: - 58M (11%) 90M (18%) 92M (18%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Reclaimed: - - 0M (0%) 2M (1%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Promoted: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,heap ] GC(0) Y: Compacted: - - - 3M (1%) [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,phases ] GC(0) Y: Young Generation 58M(11%)->148M(29%) 0.109s [2025-06-26T17:40:21.037Z] [1.277s][debug][gc,heap ] GC(0) O: Heap before GC invocations=1 (full 1): [2025-06-26T17:40:21.037Z] [1.277s][debug][gc,heap ] GC(0) O: ZHeap used 150M, capacity 152M, max capacity 512M [2025-06-26T17:40:21.037Z] [1.277s][debug][gc,heap ] GC(0) O: Metaspace used 646K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.037Z] [1.277s][debug][gc,heap ] GC(0) O: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.037Z] [1.277s][info ][gc,phases ] GC(0) O: Old Generation [2025-06-26T17:40:21.037Z] [1.291s][info ][gc,phases ] GC(0) O: Concurrent Mark 13.959ms [2025-06-26T17:40:21.037Z] [1.309s][info ][gc,phases ] GC(0) O: Pause Mark End 0.018ms [2025-06-26T17:40:21.037Z] [1.309s][info ][gc,phases ] GC(0) O: Concurrent Mark Free 0.051ms [2025-06-26T17:40:21.037Z] [1.313s][info ][gc,phases ] GC(0) O: Concurrent Process Non-Strong 4.522ms [2025-06-26T17:40:21.037Z] [1.313s][info ][gc,phases ] GC(0) O: Concurrent Reset Relocation Set 0.001ms [2025-06-26T17:40:21.037Z] [1.317s][info ][gc,phases ] GC(0) O: Concurrent Select Relocation Set 3.461ms [2025-06-26T17:40:21.037Z] [1.317s][info ][gc,task ] GC(0) O: Using 1 Workers for Old Generation [2025-06-26T17:40:21.038Z] [1.391s][info ][gc,task ] GC(0) O: Using 1 Workers for Old Generation [2025-06-26T17:40:21.038Z] [1.391s][info ][gc,phases ] GC(0) O: Concurrent Remap Roots 74.294ms [2025-06-26T17:40:21.038Z] [1.402s][info ][gc,phases ] GC(0) O: Pause Relocate Start 0.014ms [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,phases ] GC(0) O: Concurrent Relocate 3.619ms [2025-06-26T17:40:21.038Z] [1.405s][debug][gc,heap ] GC(0) O: Heap after GC invocations=1 (full 1): [2025-06-26T17:40:21.038Z] [1.405s][debug][gc,heap ] GC(0) O: ZHeap used 222M, capacity 222M, max capacity 512M [2025-06-26T17:40:21.038Z] [1.405s][debug][gc,heap ] GC(0) O: Metaspace used 646K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.038Z] [1.405s][debug][gc,heap ] GC(0) O: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,alloc ] GC(0) O: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,alloc ] GC(0) O: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,load ] GC(0) O: Load: 52.96 (33%) / 59.16 (37%) / 50.70 (32%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,mmu ] GC(0) O: MMU: 2ms/98.1%, 5ms/99.2%, 10ms/99.6%, 20ms/99.8%, 50ms/99.9%, 100ms/99.9% [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,marking ] GC(0) O: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,marking ] GC(0) O: Mark Stack Usage: 0M [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,nmethod ] GC(0) O: NMethods: 282 registered, 17 unregistered [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,metaspace] GC(0) O: Metaspace: 0M used, 0M committed, 1088M reserved [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,ref ] GC(0) O: Encountered Discovered Enqueued [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,ref ] GC(0) O: Soft References: 160 0 0 [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,ref ] GC(0) O: Weak References: 295 0 0 [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,ref ] GC(0) O: Final References: 0 0 0 [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,ref ] GC(0) O: Phantom References: 18 0 0 [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Min Capacity: 128M(25%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Max Capacity: 512M(100%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Heap Statistics: [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Capacity: 128M (25%) 166M (32%) 218M (43%) 222M (43%) 222M (43%) 128M (25%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Free: 454M (89%) 346M (68%) 294M (57%) 290M (57%) 454M (89%) 290M (57%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Used: 58M (11%) 166M (32%) 218M (43%) 222M (43%) 222M (43%) 58M (11%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Old Generation Statistics: [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Used: 0M (0%) 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Live: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Garbage: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Reclaimed: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,heap ] GC(0) O: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.038Z] [1.405s][info ][gc,phases ] GC(0) O: Old Generation 150M(29%)->222M(43%) 0.128s [2025-06-26T17:40:21.038Z] [1.405s][info ][gc ] GC(0) Major Collection (Warmup) 58M(11%)->222M(43%) 0.238s [2025-06-26T17:40:21.038Z] [1.406s][info ][gc ] GC(1) Major Collection (Warmup) [2025-06-26T17:40:21.038Z] [1.406s][info ][gc,task ] GC(1) Using 1 Workers for Young Generation [2025-06-26T17:40:21.038Z] [1.406s][info ][gc,task ] GC(1) Using 1 Workers for Old Generation [2025-06-26T17:40:21.038Z] [1.406s][debug][gc,heap ] GC(1) Y: Heap before GC invocations=1 (full 1): [2025-06-26T17:40:21.038Z] [1.406s][debug][gc,heap ] GC(1) Y: ZHeap used 224M, capacity 224M, max capacity 512M [2025-06-26T17:40:21.038Z] [1.406s][debug][gc,heap ] GC(1) Y: Metaspace used 646K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.038Z] [1.406s][debug][gc,heap ] GC(1) Y: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.038Z] [1.406s][info ][gc,phases ] GC(1) Y: Young Generation [2025-06-26T17:40:21.038Z] [1.417s][info ][gc,phases ] GC(1) Y: Pause Mark Start (Major) 0.038ms [2025-06-26T17:40:21.038Z] [0.636s] (MainThread) Reclaiming [2025-06-26T17:40:21.038Z] [1.504s][info ][gc,phases ] GC(1) Y: Concurrent Mark 87.330ms [2025-06-26T17:40:21.038Z] [1.504s][info ][gc,phases ] GC(1) Y: Pause Mark End 0.015ms [2025-06-26T17:40:21.038Z] [1.504s][info ][gc,phases ] GC(1) Y: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.038Z] [1.504s][info ][gc,phases ] GC(1) Y: Concurrent Reset Relocation Set 0.001ms [2025-06-26T17:40:21.038Z] [1.506s][info ][gc,reloc ] GC(1) Y: Using tenuring threshold: 1 (Computed) [2025-06-26T17:40:21.038Z] [1.510s][info ][gc,phases ] GC(1) Y: Concurrent Select Relocation Set 5.299ms [2025-06-26T17:40:21.038Z] [1.510s][info ][gc,phases ] GC(1) Y: Pause Relocate Start 0.009ms [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,phases ] GC(1) Y: Concurrent Relocate 4.599ms [2025-06-26T17:40:21.038Z] [1.517s][debug][gc,heap ] GC(1) Y: Heap after GC invocations=2 (full 2): [2025-06-26T17:40:21.038Z] [1.517s][debug][gc,heap ] GC(1) Y: ZHeap used 226M, capacity 228M, max capacity 512M [2025-06-26T17:40:21.038Z] [1.517s][debug][gc,heap ] GC(1) Y: Metaspace used 647K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.038Z] [1.517s][debug][gc,heap ] GC(1) Y: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,alloc ] GC(1) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,alloc ] GC(1) Y: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,load ] GC(1) Y: Load: 52.96 (33%) / 59.16 (37%) / 50.70 (32%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,mmu ] GC(1) Y: MMU: 2ms/98.1%, 5ms/99.2%, 10ms/99.6%, 20ms/99.7%, 50ms/99.9%, 100ms/99.9% [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,marking ] GC(1) Y: Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,marking ] GC(1) Y: Mark Stack Usage: 32M [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,nmethod ] GC(1) Y: NMethods: 283 registered, 17 unregistered [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,metaspace] GC(1) Y: Metaspace: 0M used, 0M committed, 1088M reserved [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Candidates Selected In-Place Size Empty Relocated [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Small Pages: 104 1 0 208M 0M 1M [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Medium Pages: 1 0 0 16M 0M 0M [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Large Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Forwarding Usage: 0M [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Age Table: [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Live Garbage Small Medium Large [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Eden 148M (29%) 19M (4%) 76 / 0 1 / 0 0 / 0 [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,reloc ] GC(1) Y: Survivor 1 54M (11%) 1M (0%) 28 / 1 0 / 0 0 / 0 [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Min Capacity: 128M(25%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Max Capacity: 512M(100%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Heap Statistics: [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Capacity: 224M (44%) 226M (44%) 226M (44%) 228M (45%) 228M (45%) 224M (44%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Free: 288M (56%) 286M (56%) 286M (56%) 286M (56%) 288M (56%) 284M (55%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Used: 224M (44%) 226M (44%) 226M (44%) 226M (44%) 228M (45%) 224M (44%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Young Generation Statistics: [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Used: 224M (44%) 226M (44%) 172M (34%) 170M (33%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Live: - 202M (40%) 149M (29%) 148M (29%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Garbage: - 21M (4%) 20M (4%) 17M (3%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Allocated: - 2M (0%) 2M (0%) 3M (1%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Reclaimed: - - 1M (0%) 3M (1%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Promoted: - - 52M (10%) 54M (11%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,heap ] GC(1) Y: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,phases ] GC(1) Y: Young Generation 224M(44%)->226M(44%) 0.111s [2025-06-26T17:40:21.038Z] [1.517s][debug][gc,heap ] GC(1) O: Heap before GC invocations=2 (full 2): [2025-06-26T17:40:21.038Z] [1.517s][debug][gc,heap ] GC(1) O: ZHeap used 226M, capacity 228M, max capacity 512M [2025-06-26T17:40:21.038Z] [1.517s][debug][gc,heap ] GC(1) O: Metaspace used 647K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.038Z] [1.517s][debug][gc,heap ] GC(1) O: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.038Z] [1.517s][info ][gc,phases ] GC(1) O: Old Generation [2025-06-26T17:40:21.038Z] [1.524s][info ][gc,phases ] GC(1) O: Concurrent Mark 6.338ms [2025-06-26T17:40:21.038Z] [1.524s][info ][gc,phases ] GC(1) O: Pause Mark End 0.014ms [2025-06-26T17:40:21.038Z] [1.529s][info ][gc,phases ] GC(1) O: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.038Z] [1.531s][info ][gc,phases ] GC(1) O: Concurrent Process Non-Strong 1.953ms [2025-06-26T17:40:21.038Z] [1.531s][info ][gc,phases ] GC(1) O: Concurrent Reset Relocation Set 0.001ms [2025-06-26T17:40:21.038Z] [1.537s][info ][gc,phases ] GC(1) O: Concurrent Select Relocation Set 5.979ms [2025-06-26T17:40:21.038Z] [1.537s][info ][gc,task ] GC(1) O: Using 1 Workers for Old Generation [2025-06-26T17:40:21.038Z] [1.599s][info ][gc,task ] GC(1) O: Using 1 Workers for Old Generation [2025-06-26T17:40:21.038Z] [1.599s][info ][gc,phases ] GC(1) O: Concurrent Remap Roots 62.388ms [2025-06-26T17:40:21.038Z] [1.599s][info ][gc,phases ] GC(1) O: Pause Relocate Start 0.013ms [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,phases ] GC(1) O: Concurrent Relocate 10.942ms [2025-06-26T17:40:21.038Z] [1.610s][debug][gc,heap ] GC(1) O: Heap after GC invocations=2 (full 2): [2025-06-26T17:40:21.038Z] [1.610s][debug][gc,heap ] GC(1) O: ZHeap used 226M, capacity 228M, max capacity 512M [2025-06-26T17:40:21.038Z] [1.610s][debug][gc,heap ] GC(1) O: Metaspace used 647K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.038Z] [1.610s][debug][gc,heap ] GC(1) O: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,alloc ] GC(1) O: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,alloc ] GC(1) O: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,load ] GC(1) O: Load: 52.96 (33%) / 59.16 (37%) / 50.70 (32%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,mmu ] GC(1) O: MMU: 2ms/98.1%, 5ms/99.2%, 10ms/99.6%, 20ms/99.7%, 50ms/99.9%, 100ms/99.9% [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,marking ] GC(1) O: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,marking ] GC(1) O: Mark Stack Usage: 0M [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,nmethod ] GC(1) O: NMethods: 283 registered, 17 unregistered [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,metaspace] GC(1) O: Metaspace: 0M used, 0M committed, 1088M reserved [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,ref ] GC(1) O: Encountered Discovered Enqueued [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,ref ] GC(1) O: Soft References: 160 0 0 [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,ref ] GC(1) O: Weak References: 295 0 0 [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,ref ] GC(1) O: Final References: 0 0 0 [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,ref ] GC(1) O: Phantom References: 18 0 0 [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Min Capacity: 128M(25%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Max Capacity: 512M(100%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Heap Statistics: [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Capacity: 224M (44%) 228M (45%) 228M (45%) 228M (45%) 228M (45%) 224M (44%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Free: 288M (56%) 286M (56%) 286M (56%) 286M (56%) 288M (56%) 284M (55%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Used: 224M (44%) 226M (44%) 226M (44%) 226M (44%) 228M (45%) 224M (44%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Old Generation Statistics: [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Used: 0M (0%) 56M (11%) 56M (11%) 56M (11%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Live: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Garbage: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Allocated: - 56M (11%) 56M (11%) 56M (11%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Reclaimed: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,heap ] GC(1) O: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,phases ] GC(1) O: Old Generation 226M(44%)->226M(44%) 0.093s [2025-06-26T17:40:21.038Z] [1.610s][info ][gc ] GC(1) Major Collection (Warmup) 224M(44%)->226M(44%) 0.204s [2025-06-26T17:40:21.038Z] [1.610s][info ][gc ] GC(2) Major Collection (System.gc()) [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,task ] GC(2) Using 1 Workers for Young Generation [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,task ] GC(2) Using 1 Workers for Old Generation [2025-06-26T17:40:21.038Z] [1.610s][debug][gc,heap ] GC(2) Y: Heap before GC invocations=2 (full 2): [2025-06-26T17:40:21.038Z] [1.610s][debug][gc,heap ] GC(2) Y: ZHeap used 226M, capacity 228M, max capacity 512M [2025-06-26T17:40:21.038Z] [1.610s][debug][gc,heap ] GC(2) Y: Metaspace used 647K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.038Z] [1.610s][debug][gc,heap ] GC(2) Y: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.038Z] [1.610s][info ][gc,phases ] GC(2) Y: Young Generation (Promote All) [2025-06-26T17:40:21.038Z] [1.611s][info ][gc,phases ] GC(2) Y: Pause Mark Start 0.033ms [2025-06-26T17:40:21.038Z] [1.640s][info ][gc,phases ] GC(2) Y: Concurrent Mark 29.921ms [2025-06-26T17:40:21.038Z] [1.641s][info ][gc,phases ] GC(2) Y: Pause Mark End 0.015ms [2025-06-26T17:40:21.038Z] [1.641s][info ][gc,phases ] GC(2) Y: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.038Z] [1.641s][info ][gc,phases ] GC(2) Y: Concurrent Reset Relocation Set 0.018ms [2025-06-26T17:40:21.038Z] [1.642s][info ][gc,reloc ] GC(2) Y: Using tenuring threshold: 0 (Promote All) [2025-06-26T17:40:21.038Z] [1.665s][info ][gc,phases ] GC(2) Y: Concurrent Select Relocation Set 24.862ms [2025-06-26T17:40:21.038Z] [1.666s][info ][gc,phases ] GC(2) Y: Pause Relocate Start 0.013ms [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,phases ] GC(2) Y: Concurrent Relocate 5.265ms [2025-06-26T17:40:21.038Z] [1.679s][debug][gc,heap ] GC(2) Y: Heap after GC invocations=3 (full 2): [2025-06-26T17:40:21.038Z] [1.679s][debug][gc,heap ] GC(2) Y: ZHeap used 224M, capacity 228M, max capacity 512M [2025-06-26T17:40:21.038Z] [1.679s][debug][gc,heap ] GC(2) Y: Metaspace used 647K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.038Z] [1.679s][debug][gc,heap ] GC(2) Y: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,alloc ] GC(2) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,alloc ] GC(2) Y: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,load ] GC(2) Y: Load: 52.96 (33%) / 59.16 (37%) / 50.70 (32%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,mmu ] GC(2) Y: MMU: 2ms/98.1%, 5ms/99.2%, 10ms/99.6%, 20ms/99.7%, 50ms/99.9%, 100ms/99.9% [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,marking ] GC(2) Y: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,marking ] GC(2) Y: Mark Stack Usage: 32M [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,nmethod ] GC(2) Y: NMethods: 283 registered, 17 unregistered [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,metaspace] GC(2) Y: Metaspace: 0M used, 0M committed, 1088M reserved [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Candidates Selected In-Place Size Empty Relocated [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Small Pages: 77 1 0 154M 0M 0M [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Medium Pages: 1 0 0 16M 0M 0M [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Large Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Forwarding Usage: 0M [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Age Table: [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Live Garbage Small Medium Large [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Eden 0M (0%) 1M (0%) 1 / 1 0 / 0 0 / 0 [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,reloc ] GC(2) Y: Survivor 1 148M (29%) 19M (4%) 76 / 0 1 / 0 0 / 0 [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Min Capacity: 128M(25%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Max Capacity: 512M(100%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Heap Statistics: [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Capacity: 228M (45%) 228M (45%) 228M (45%) 228M (45%) 228M (45%) 228M (45%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Free: 286M (56%) 286M (56%) 286M (56%) 288M (56%) 288M (56%) 284M (55%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Used: 226M (44%) 226M (44%) 226M (44%) 224M (44%) 228M (45%) 224M (44%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Young Generation Statistics: [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Used: 170M (33%) 170M (33%) 4M (1%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Live: - 148M (29%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Garbage: - 21M (4%) 3M (1%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.038Z] [1.679s][info ][gc,heap ] GC(2) Y: Reclaimed: - - 17M (3%) 21M (4%) [2025-06-26T17:40:21.039Z] [1.679s][info ][gc,heap ] GC(2) Y: Promoted: - - 148M (29%) 148M (29%) [2025-06-26T17:40:21.039Z] [1.679s][info ][gc,heap ] GC(2) Y: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.039Z] [1.679s][info ][gc,phases ] GC(2) Y: Young Generation (Promote All) 226M(44%)->224M(44%) 0.069s [2025-06-26T17:40:21.039Z] [1.679s][debug][gc,heap ] GC(2) Y: Heap before GC invocations=3 (full 2): [2025-06-26T17:40:21.039Z] [1.679s][debug][gc,heap ] GC(2) Y: ZHeap used 224M, capacity 228M, max capacity 512M [2025-06-26T17:40:21.039Z] [1.679s][debug][gc,heap ] GC(2) Y: Metaspace used 647K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.039Z] [1.679s][debug][gc,heap ] GC(2) Y: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.039Z] [1.680s][info ][gc,phases ] GC(2) Y: Young Generation (Collect Roots) [2025-06-26T17:40:21.039Z] [1.680s][info ][gc,phases ] GC(2) Y: Pause Mark Start (Major) 0.038ms [2025-06-26T17:40:21.039Z] [1.701s][info ][gc,phases ] GC(2) Y: Concurrent Mark 11.966ms [2025-06-26T17:40:21.039Z] [1.701s][info ][gc,phases ] GC(2) Y: Pause Mark End 0.014ms [2025-06-26T17:40:21.039Z] [1.701s][info ][gc,phases ] GC(2) Y: Concurrent Mark Free 0.065ms [2025-06-26T17:40:21.039Z] [1.701s][info ][gc,phases ] GC(2) Y: Concurrent Reset Relocation Set 0.041ms [2025-06-26T17:40:21.039Z] [1.702s][info ][gc,reloc ] GC(2) Y: Using tenuring threshold: 0 (Computed) [2025-06-26T17:40:21.039Z] [1.703s][info ][gc,phases ] GC(2) Y: Concurrent Select Relocation Set 1.585ms [2025-06-26T17:40:21.039Z] [1.703s][info ][gc,phases ] GC(2) Y: Pause Relocate Start 0.007ms [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,phases ] GC(2) Y: Concurrent Relocate 0.027ms [2025-06-26T17:40:21.039Z] [1.704s][debug][gc,heap ] GC(2) Y: Heap after GC invocations=4 (full 3): [2025-06-26T17:40:21.039Z] [1.704s][debug][gc,heap ] GC(2) Y: ZHeap used 224M, capacity 228M, max capacity 512M [2025-06-26T17:40:21.039Z] [1.704s][debug][gc,heap ] GC(2) Y: Metaspace used 647K, committed 832K, reserved 1114112K [2025-06-26T17:40:21.039Z] [1.704s][debug][gc,heap ] GC(2) Y: class space used 55K, committed 128K, reserved 1048576K [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,alloc ] GC(2) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,alloc ] GC(2) Y: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,load ] GC(2) Y: Load: 52.96 (33%) / 59.16 (37%) / 50.70 (32%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,mmu ] GC(2) Y: MMU: 2ms/98.1%, 5ms/99.2%, 10ms/99.6%, 20ms/99.7%, 50ms/99.9%, 100ms/99.9% [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,marking ] GC(2) Y: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,marking ] GC(2) Y: Mark Stack Usage: 0M [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,nmethod ] GC(2) Y: NMethods: 283 registered, 17 unregistered [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,metaspace] GC(2) Y: Metaspace: 0M used, 0M committed, 1088M reserved [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Min Capacity: 128M(25%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Max Capacity: 512M(100%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Heap Statistics: [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Capacity: 228M (45%) 228M (45%) 228M (45%) 228M (45%) 228M (45%) 228M (45%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Free: 288M (56%) 288M (56%) 288M (56%) 288M (56%) 288M (56%) 288M (56%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Used: 224M (44%) 224M (44%) 224M (44%) 224M (44%) 224M (44%) 224M (44%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Young Generation Statistics: [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Used: 0M (0%) 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Live: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Garbage: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Reclaimed: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Promoted: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,heap ] GC(2) Y: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.039Z] [1.704s][info ][gc,phases ] GC(2) Y: Young Generation (Collect Roots) 224M(44%)->224M(44%) 0.025s [2025-06-26T17:40:21.039Z] [1.704s][debug][gc,heap ] GC(2) O: Heap before GC invoc [2025-06-26T17:40:21.039Z] [2025-06-26T17:40:21.039Z] ... [2025-06-26T17:40:21.039Z] Output overflow: [2025-06-26T17:40:21.039Z] JT Harness has limited the test output to the text [2025-06-26T17:40:21.039Z] at the beginning and the end, so that you can see how the [2025-06-26T17:40:21.039Z] test began, and how it completed. [2025-06-26T17:40:21.039Z] [2025-06-26T17:40:21.039Z] If you need to see more of the output from the test, [2025-06-26T17:40:21.039Z] set the system property javatest.maxOutputSize to a higher [2025-06-26T17:40:21.039Z] value. The current value is 100000 [2025-06-26T17:40:21.039Z] ... [2025-06-26T17:40:21.039Z] [2025-06-26T17:40:21.039Z] 6.470s][info ][gc,heap ] GC(6) O: Allocated: - 0M (0%) 0M (0%) 1M (0%) [2025-06-26T17:40:21.039Z] [36.470s][info ][gc,heap ] GC(6) O: Reclaimed: - - 0M (0%) 3M (1%) [2025-06-26T17:40:21.039Z] [36.470s][info ][gc,heap ] GC(6) O: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.039Z] [36.470s][info ][gc,phases ] GC(6) O: Old Generation 6M(1%)->4M(1%) 0.104s [2025-06-26T17:40:21.039Z] [36.470s][info ][gc ] GC(6) Major Collection (System.gc()) 226M(44%)->4M(1%) 0.138s [2025-06-26T17:40:21.039Z] [35.688s] (MainThread) Waiting for uncommit to start [2025-06-26T17:40:21.039Z] [45.633s][debug][gc,heap ] Uncommit Timeout: 2s [2025-06-26T17:40:21.039Z] [47.674s][info ][gc,heap ] Uncommitted: 98M(19%) [2025-06-26T17:40:21.039Z] [47.674s][debug][gc,heap ] Uncommit Timeout: 10s [2025-06-26T17:40:21.039Z] [47.821s] (MainThread) Uncommit started [2025-06-26T17:40:21.039Z] [47.821s] (MainThread) Waiting for uncommit to complete [2025-06-26T17:40:21.039Z] [47.821s] (MainThread) Uncommit completed [2025-06-26T17:40:21.039Z] [47.821s] (MainThread) Uncommit Delay: 10000 [2025-06-26T17:40:21.039Z] [47.821s] (MainThread) Object Size: 4096 [2025-06-26T17:40:21.039Z] [47.822s] (MainThread) Alloc Size: 209715200 [2025-06-26T17:40:21.039Z] [47.822s] (MainThread) Before Alloc: 134217728 [2025-06-26T17:40:21.039Z] [47.822s] (MainThread) After Alloc: 236978176 [2025-06-26T17:40:21.039Z] [47.822s] (MainThread) After Uncommit: 134217728 [2025-06-26T17:40:21.039Z] [47.822s] (MainThread) Actual Uncommit Delay: 12499 [2025-06-26T17:40:21.039Z] [47.822s] (MainThread) Success [2025-06-26T17:40:21.039Z] [47.822s] (MainThread) Allocating [2025-06-26T17:40:21.039Z] [48.985s][debug][gc,heap ] Page Cache Flushed: 4M [2025-06-26T17:40:21.039Z] [48.989s][debug][gc,heap ] Page Cache Flushed: 4M [2025-06-26T17:40:21.039Z] [49.001s][debug][gc,heap ] Page Cache Flushed: 4M [2025-06-26T17:40:21.039Z] [49.002s][debug][gc,heap ] Page Cache Flushed: 4M [2025-06-26T17:40:21.039Z] [48.221s] (MainThread) Reclaiming [2025-06-26T17:40:21.039Z] [49.017s][info ][gc ] GC(7) Major Collection (System.gc()) [2025-06-26T17:40:21.039Z] [49.017s][info ][gc,task ] GC(7) Using 1 Workers for Young Generation [2025-06-26T17:40:21.039Z] [49.017s][info ][gc,task ] GC(7) Using 1 Workers for Old Generation [2025-06-26T17:40:21.039Z] [49.017s][debug][gc,heap ] GC(7) Y: Heap before GC invocations=11 (full 7): [2025-06-26T17:40:21.039Z] [49.017s][debug][gc,heap ] GC(7) Y: ZHeap used 406M, capacity 512M, max capacity 512M [2025-06-26T17:40:21.039Z] [49.018s][debug][gc,heap ] GC(7) Y: Metaspace used 1159K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.039Z] [49.018s][debug][gc,heap ] GC(7) Y: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.039Z] [49.018s][info ][gc,phases ] GC(7) Y: Young Generation (Promote All) [2025-06-26T17:40:21.039Z] [49.018s][info ][gc,phases ] GC(7) Y: Pause Mark Start 0.033ms [2025-06-26T17:40:21.039Z] [49.025s][info ][gc,phases ] GC(7) Y: Concurrent Mark 4.100ms [2025-06-26T17:40:21.039Z] [49.025s][info ][gc,phases ] GC(7) Y: Pause Mark End 0.012ms [2025-06-26T17:40:21.039Z] [49.025s][info ][gc,phases ] GC(7) Y: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.039Z] [49.025s][info ][gc,phases ] GC(7) Y: Concurrent Reset Relocation Set 0.000ms [2025-06-26T17:40:21.039Z] [49.027s][info ][gc,reloc ] GC(7) Y: Using tenuring threshold: 0 (Promote All) [2025-06-26T17:40:21.039Z] [49.027s][info ][gc,phases ] GC(7) Y: Concurrent Select Relocation Set 1.639ms [2025-06-26T17:40:21.039Z] [49.027s][info ][gc,phases ] GC(7) Y: Pause Relocate Start 0.007ms [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,phases ] GC(7) Y: Concurrent Relocate 0.145ms [2025-06-26T17:40:21.039Z] [49.033s][debug][gc,heap ] GC(7) Y: Heap after GC invocations=12 (full 7): [2025-06-26T17:40:21.039Z] [49.033s][debug][gc,heap ] GC(7) Y: ZHeap used 4M, capacity 512M, max capacity 512M [2025-06-26T17:40:21.039Z] [49.033s][debug][gc,heap ] GC(7) Y: Metaspace used 1159K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.039Z] [49.033s][debug][gc,heap ] GC(7) Y: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,alloc ] GC(7) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,alloc ] GC(7) Y: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,load ] GC(7) Y: Load: 49.31 (31%) / 57.27 (36%) / 50.50 (32%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,mmu ] GC(7) Y: MMU: 2ms/97.8%, 5ms/98.8%, 10ms/99.4%, 20ms/99.6%, 50ms/99.8%, 100ms/99.9% [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,marking ] GC(7) Y: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,marking ] GC(7) Y: Mark Stack Usage: 0M [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,nmethod ] GC(7) Y: NMethods: 392 registered, 30 unregistered [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,metaspace] GC(7) Y: Metaspace: 1M used, 1M committed, 1088M reserved [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,reloc ] GC(7) Y: Candidates Selected In-Place Size Empty Relocated [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,reloc ] GC(7) Y: Small Pages: 1 0 0 2M 2M 0M [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,reloc ] GC(7) Y: Medium Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,reloc ] GC(7) Y: Large Pages: 100 0 0 400M 400M 0M [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,reloc ] GC(7) Y: Forwarding Usage: 0M [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,reloc ] GC(7) Y: Age Table: [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,reloc ] GC(7) Y: Live Garbage Small Medium Large [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,reloc ] GC(7) Y: Eden - 402M (79%) 1 / 0 0 / 0 100 / 0 [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Min Capacity: 128M(25%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Max Capacity: 512M(100%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Heap Statistics: [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Capacity: 512M (100%) 512M (100%) 512M (100%) 512M (100%) 512M (100%) 512M (100%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Free: 106M (21%) 106M (21%) 508M (99%) 508M (99%) 508M (99%) 106M (21%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Used: 406M (79%) 406M (79%) 4M (1%) 4M (1%) 406M (79%) 4M (1%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Young Generation Statistics: [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Used: 402M (79%) 402M (79%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Live: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Garbage: - 402M (79%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Reclaimed: - - 402M (79%) 402M (79%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Promoted: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,heap ] GC(7) Y: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,phases ] GC(7) Y: Young Generation (Promote All) 406M(79%)->4M(1%) 0.016s [2025-06-26T17:40:21.039Z] [49.033s][debug][gc,heap ] GC(7) Y: Heap before GC invocations=12 (full 7): [2025-06-26T17:40:21.039Z] [49.033s][debug][gc,heap ] GC(7) Y: ZHeap used 4M, capacity 512M, max capacity 512M [2025-06-26T17:40:21.039Z] [49.033s][debug][gc,heap ] GC(7) Y: Metaspace used 1159K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.039Z] [49.033s][debug][gc,heap ] GC(7) Y: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.039Z] [49.033s][info ][gc,phases ] GC(7) Y: Young Generation (Collect Roots) [2025-06-26T17:40:21.039Z] [49.034s][info ][gc,phases ] GC(7) Y: Pause Mark Start (Major) 0.033ms [2025-06-26T17:40:21.039Z] [49.042s][info ][gc,phases ] GC(7) Y: Concurrent Mark 7.953ms [2025-06-26T17:40:21.039Z] [49.042s][info ][gc,phases ] GC(7) Y: Pause Mark End 0.012ms [2025-06-26T17:40:21.039Z] [49.042s][info ][gc,phases ] GC(7) Y: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.039Z] [49.042s][info ][gc,phases ] GC(7) Y: Concurrent Reset Relocation Set 0.000ms [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,reloc ] GC(7) Y: Using tenuring threshold: 0 (Computed) [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,phases ] GC(7) Y: Concurrent Select Relocation Set 1.594ms [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,phases ] GC(7) Y: Pause Relocate Start 0.004ms [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,phases ] GC(7) Y: Concurrent Relocate 0.022ms [2025-06-26T17:40:21.039Z] [49.044s][debug][gc,heap ] GC(7) Y: Heap after GC invocations=13 (full 8): [2025-06-26T17:40:21.039Z] [49.044s][debug][gc,heap ] GC(7) Y: ZHeap used 4M, capacity 512M, max capacity 512M [2025-06-26T17:40:21.039Z] [49.044s][debug][gc,heap ] GC(7) Y: Metaspace used 1159K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.039Z] [49.044s][debug][gc,heap ] GC(7) Y: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,alloc ] GC(7) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,alloc ] GC(7) Y: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,load ] GC(7) Y: Load: 49.31 (31%) / 57.27 (36%) / 50.50 (32%) [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,mmu ] GC(7) Y: MMU: 2ms/97.8%, 5ms/98.8%, 10ms/99.4%, 20ms/99.6%, 50ms/99.8%, 100ms/99.9% [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,marking ] GC(7) Y: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,marking ] GC(7) Y: Mark Stack Usage: 0M [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,nmethod ] GC(7) Y: NMethods: 392 registered, 30 unregistered [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,metaspace] GC(7) Y: Metaspace: 1M used, 1M committed, 1088M reserved [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,heap ] GC(7) Y: Min Capacity: 128M(25%) [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,heap ] GC(7) Y: Max Capacity: 512M(100%) [2025-06-26T17:40:21.039Z] [49.044s][info ][gc,heap ] GC(7) Y: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Heap Statistics: [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Capacity: 512M (100%) 512M (100%) 512M (100%) 512M (100%) 512M (100%) 512M (100%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Free: 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) 4M (1%) 4M (1%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Young Generation Statistics: [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Used: 0M (0%) 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Live: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Garbage: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Reclaimed: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Promoted: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,heap ] GC(7) Y: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,phases ] GC(7) Y: Young Generation (Collect Roots) 4M(1%)->4M(1%) 0.011s [2025-06-26T17:40:21.040Z] [49.044s][debug][gc,heap ] GC(7) O: Heap before GC invocations=13 (full 8): [2025-06-26T17:40:21.040Z] [49.044s][debug][gc,heap ] GC(7) O: ZHeap used 4M, capacity 512M, max capacity 512M [2025-06-26T17:40:21.040Z] [49.044s][debug][gc,heap ] GC(7) O: Metaspace used 1159K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.040Z] [49.044s][debug][gc,heap ] GC(7) O: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.040Z] [49.044s][info ][gc,phases ] GC(7) O: Old Generation [2025-06-26T17:40:21.040Z] [49.053s][info ][gc,phases ] GC(7) O: Concurrent Mark 8.947ms [2025-06-26T17:40:21.040Z] [49.053s][info ][gc,phases ] GC(7) O: Pause Mark End 0.013ms [2025-06-26T17:40:21.040Z] [49.053s][info ][gc,phases ] GC(7) O: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.040Z] [49.056s][info ][gc,phases ] GC(7) O: Concurrent Process Non-Strong 2.909ms [2025-06-26T17:40:21.040Z] [49.056s][info ][gc,phases ] GC(7) O: Concurrent Reset Relocation Set 0.001ms [2025-06-26T17:40:21.040Z] [49.061s][info ][gc,phases ] GC(7) O: Concurrent Select Relocation Set 4.774ms [2025-06-26T17:40:21.040Z] [49.061s][info ][gc,task ] GC(7) O: Using 1 Workers for Old Generation [2025-06-26T17:40:21.040Z] [49.122s][info ][gc,task ] GC(7) O: Using 1 Workers for Old Generation [2025-06-26T17:40:21.040Z] [49.122s][info ][gc,phases ] GC(7) O: Concurrent Remap Roots 61.521ms [2025-06-26T17:40:21.040Z] [49.122s][info ][gc,phases ] GC(7) O: Pause Relocate Start 0.013ms [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,phases ] GC(7) O: Concurrent Relocate 5.942ms [2025-06-26T17:40:21.040Z] [49.128s][debug][gc,heap ] GC(7) O: Heap after GC invocations=13 (full 8): [2025-06-26T17:40:21.040Z] [49.128s][debug][gc,heap ] GC(7) O: ZHeap used 4M, capacity 512M, max capacity 512M [2025-06-26T17:40:21.040Z] [49.128s][debug][gc,heap ] GC(7) O: Metaspace used 1159K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.040Z] [49.128s][debug][gc,heap ] GC(7) O: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,alloc ] GC(7) O: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,alloc ] GC(7) O: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,load ] GC(7) O: Load: 49.31 (31%) / 57.27 (36%) / 50.50 (32%) [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,mmu ] GC(7) O: MMU: 2ms/97.8%, 5ms/98.8%, 10ms/99.4%, 20ms/99.6%, 50ms/99.8%, 100ms/99.9% [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,marking ] GC(7) O: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,marking ] GC(7) O: Mark Stack Usage: 32M [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,nmethod ] GC(7) O: NMethods: 392 registered, 30 unregistered [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,metaspace] GC(7) O: Metaspace: 1M used, 1M committed, 1088M reserved [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,ref ] GC(7) O: Encountered Discovered Enqueued [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,ref ] GC(7) O: Soft References: 189 0 0 [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,ref ] GC(7) O: Weak References: 306 224 0 [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,ref ] GC(7) O: Final References: 0 0 0 [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,ref ] GC(7) O: Phantom References: 10 7 0 [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,reloc ] GC(7) O: Candidates Selected In-Place Size Empty Relocated [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,reloc ] GC(7) O: Small Pages: 2 0 0 4M 0M 0M [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,reloc ] GC(7) O: Medium Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.040Z] [49.128s][info ][gc,reloc ] GC(7) O: Large Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,reloc ] GC(7) O: Forwarding Usage: 0M [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Min Capacity: 128M(25%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Max Capacity: 512M(100%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Heap Statistics: [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Capacity: 512M (100%) 512M (100%) 512M (100%) 512M (100%) 512M (100%) 512M (100%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Free: 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) 4M (1%) 4M (1%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Old Generation Statistics: [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Live: - 2M (0%) 2M (0%) 2M (0%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Garbage: - 1M (0%) 1M (0%) 1M (0%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Reclaimed: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,heap ] GC(7) O: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.040Z] [49.129s][info ][gc,phases ] GC(7) O: Old Generation 4M(1%)->4M(1%) 0.084s [2025-06-26T17:40:21.040Z] [49.129s][info ][gc ] GC(7) Major Collection (System.gc()) 406M(79%)->4M(1%) 0.111s [2025-06-26T17:40:21.040Z] [48.347s] (MainThread) Waiting for uncommit to start [2025-06-26T17:40:21.040Z] [57.674s][debug][gc,heap ] Uncommit Timeout: 2s [2025-06-26T17:40:21.040Z] [59.750s][info ][gc,heap ] Uncommitted: 106M(21%) [2025-06-26T17:40:21.040Z] [59.750s][debug][gc,heap ] Uncommit Timeout: 1s [2025-06-26T17:40:21.040Z] [59.349s] (MainThread) Uncommit started [2025-06-26T17:40:21.040Z] [59.349s] (MainThread) Waiting for uncommit to complete [2025-06-26T17:40:21.040Z] [60.852s][info ][gc,heap ] Uncommitted: 278M(54%) [2025-06-26T17:40:21.040Z] [60.852s][debug][gc,heap ] Uncommit Timeout: 10s [2025-06-26T17:40:21.040Z] [60.349s] (MainThread) Uncommit completed [2025-06-26T17:40:21.040Z] [60.349s] (MainThread) Uncommit Delay: 10000 [2025-06-26T17:40:21.040Z] [60.350s] (MainThread) Object Size: 2097152 [2025-06-26T17:40:21.040Z] [60.350s] (MainThread) Alloc Size: 209715200 [2025-06-26T17:40:21.040Z] [60.350s] (MainThread) Before Alloc: 134217728 [2025-06-26T17:40:21.040Z] [60.350s] (MainThread) After Alloc: 536870912 [2025-06-26T17:40:21.040Z] [60.350s] (MainThread) After Uncommit: 134217728 [2025-06-26T17:40:21.040Z] [60.350s] (MainThread) Actual Uncommit Delay: 11527 [2025-06-26T17:40:21.040Z] [60.350s] (MainThread) Success [2025-06-26T17:40:21.040Z] [60.350s] (MainThread) Allocating [2025-06-26T17:40:21.040Z] [60.777s] (MainThread) Reclaiming [2025-06-26T17:40:21.040Z] [61.559s][info ][gc ] GC(8) Major Collection (System.gc()) [2025-06-26T17:40:21.040Z] [61.560s][info ][gc,task ] GC(8) Using 1 Workers for Young Generation [2025-06-26T17:40:21.040Z] [61.560s][info ][gc,task ] GC(8) Using 1 Workers for Old Generation [2025-06-26T17:40:21.040Z] [61.560s][debug][gc,heap ] GC(8) Y: Heap before GC invocations=13 (full 8): [2025-06-26T17:40:21.040Z] [61.560s][debug][gc,heap ] GC(8) Y: ZHeap used 208M, capacity 330M, max capacity 512M [2025-06-26T17:40:21.040Z] [61.560s][debug][gc,heap ] GC(8) Y: Metaspace used 1164K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.040Z] [61.560s][debug][gc,heap ] GC(8) Y: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.040Z] [61.560s][info ][gc,phases ] GC(8) Y: Young Generation (Promote All) [2025-06-26T17:40:21.040Z] [61.577s][info ][gc,phases ] GC(8) Y: Pause Mark Start 0.302ms [2025-06-26T17:40:21.040Z] [61.583s][info ][gc,phases ] GC(8) Y: Concurrent Mark 5.337ms [2025-06-26T17:40:21.040Z] [61.583s][info ][gc,phases ] GC(8) Y: Pause Mark End 0.017ms [2025-06-26T17:40:21.040Z] [61.583s][info ][gc,phases ] GC(8) Y: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.040Z] [61.583s][info ][gc,phases ] GC(8) Y: Concurrent Reset Relocation Set 0.001ms [2025-06-26T17:40:21.040Z] [61.584s][info ][gc,reloc ] GC(8) Y: Using tenuring threshold: 0 (Promote All) [2025-06-26T17:40:21.040Z] [61.584s][info ][gc,phases ] GC(8) Y: Concurrent Select Relocation Set 1.652ms [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,phases ] GC(8) Y: Pause Relocate Start 0.007ms [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,phases ] GC(8) Y: Concurrent Relocate 0.022ms [2025-06-26T17:40:21.040Z] [61.585s][debug][gc,heap ] GC(8) Y: Heap after GC invocations=14 (full 8): [2025-06-26T17:40:21.040Z] [61.585s][debug][gc,heap ] GC(8) Y: ZHeap used 4M, capacity 330M, max capacity 512M [2025-06-26T17:40:21.040Z] [61.585s][debug][gc,heap ] GC(8) Y: Metaspace used 1164K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.040Z] [61.585s][debug][gc,heap ] GC(8) Y: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,alloc ] GC(8) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,alloc ] GC(8) Y: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,load ] GC(8) Y: Load: 48.61 (30%) / 56.88 (36%) / 50.44 (32%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,mmu ] GC(8) Y: MMU: 2ms/84.9%, 5ms/94.0%, 10ms/96.7%, 20ms/98.4%, 50ms/99.3%, 100ms/99.7% [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,marking ] GC(8) Y: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,marking ] GC(8) Y: Mark Stack Usage: 0M [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,nmethod ] GC(8) Y: NMethods: 399 registered, 30 unregistered [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,metaspace] GC(8) Y: Metaspace: 1M used, 1M committed, 1088M reserved [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,reloc ] GC(8) Y: Candidates Selected In-Place Size Empty Relocated [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,reloc ] GC(8) Y: Small Pages: 1 0 0 2M 2M 0M [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,reloc ] GC(8) Y: Medium Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,reloc ] GC(8) Y: Large Pages: 1 0 0 202M 202M 0M [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,reloc ] GC(8) Y: Forwarding Usage: 0M [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,reloc ] GC(8) Y: Age Table: [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,reloc ] GC(8) Y: Live Garbage Small Medium Large [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,reloc ] GC(8) Y: Eden - 204M (40%) 1 / 0 0 / 0 1 / 0 [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Min Capacity: 128M(25%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Max Capacity: 512M(100%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Heap Statistics: [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Capacity: 330M (64%) 330M (64%) 330M (64%) 330M (64%) 330M (64%) 330M (64%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Free: 304M (59%) 304M (59%) 508M (99%) 508M (99%) 508M (99%) 304M (59%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Used: 208M (41%) 208M (41%) 4M (1%) 4M (1%) 208M (41%) 4M (1%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Young Generation Statistics: [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Used: 204M (40%) 204M (40%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Live: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Garbage: - 204M (40%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Reclaimed: - - 204M (40%) 204M (40%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Promoted: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,heap ] GC(8) Y: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,phases ] GC(8) Y: Young Generation (Promote All) 208M(41%)->4M(1%) 0.025s [2025-06-26T17:40:21.040Z] [61.585s][debug][gc,heap ] GC(8) Y: Heap before GC invocations=14 (full 8): [2025-06-26T17:40:21.040Z] [61.585s][debug][gc,heap ] GC(8) Y: ZHeap used 4M, capacity 330M, max capacity 512M [2025-06-26T17:40:21.040Z] [61.585s][debug][gc,heap ] GC(8) Y: Metaspace used 1164K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.040Z] [61.585s][debug][gc,heap ] GC(8) Y: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,phases ] GC(8) Y: Young Generation (Collect Roots) [2025-06-26T17:40:21.040Z] [61.585s][info ][gc,phases ] GC(8) Y: Pause Mark Start (Major) 0.029ms [2025-06-26T17:40:21.040Z] [61.597s][info ][gc,phases ] GC(8) Y: Concurrent Mark 11.948ms [2025-06-26T17:40:21.040Z] [61.597s][info ][gc,phases ] GC(8) Y: Pause Mark End 0.011ms [2025-06-26T17:40:21.040Z] [61.597s][info ][gc,phases ] GC(8) Y: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.040Z] [61.597s][info ][gc,phases ] GC(8) Y: Concurrent Reset Relocation Set 0.000ms [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,reloc ] GC(8) Y: Using tenuring threshold: 0 (Computed) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,phases ] GC(8) Y: Concurrent Select Relocation Set 1.584ms [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,phases ] GC(8) Y: Pause Relocate Start 0.004ms [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,phases ] GC(8) Y: Concurrent Relocate 0.021ms [2025-06-26T17:40:21.040Z] [61.599s][debug][gc,heap ] GC(8) Y: Heap after GC invocations=15 (full 9): [2025-06-26T17:40:21.040Z] [61.599s][debug][gc,heap ] GC(8) Y: ZHeap used 4M, capacity 330M, max capacity 512M [2025-06-26T17:40:21.040Z] [61.599s][debug][gc,heap ] GC(8) Y: Metaspace used 1164K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.040Z] [61.599s][debug][gc,heap ] GC(8) Y: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,alloc ] GC(8) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,alloc ] GC(8) Y: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,load ] GC(8) Y: Load: 48.61 (30%) / 56.88 (36%) / 50.44 (32%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,mmu ] GC(8) Y: MMU: 2ms/84.9%, 5ms/94.0%, 10ms/96.4%, 20ms/98.2%, 50ms/99.3%, 100ms/99.6% [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,marking ] GC(8) Y: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,marking ] GC(8) Y: Mark Stack Usage: 0M [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,nmethod ] GC(8) Y: NMethods: 399 registered, 30 unregistered [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,metaspace] GC(8) Y: Metaspace: 1M used, 1M committed, 1088M reserved [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Min Capacity: 128M(25%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Max Capacity: 512M(100%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Heap Statistics: [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Capacity: 330M (64%) 330M (64%) 330M (64%) 330M (64%) 330M (64%) 330M (64%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Free: 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) 4M (1%) 4M (1%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Young Generation Statistics: [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Used: 0M (0%) 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Live: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Garbage: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Reclaimed: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Promoted: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,heap ] GC(8) Y: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,phases ] GC(8) Y: Young Generation (Collect Roots) 4M(1%)->4M(1%) 0.014s [2025-06-26T17:40:21.040Z] [61.599s][debug][gc,heap ] GC(8) O: Heap before GC invocations=15 (full 9): [2025-06-26T17:40:21.040Z] [61.599s][debug][gc,heap ] GC(8) O: ZHeap used 4M, capacity 330M, max capacity 512M [2025-06-26T17:40:21.040Z] [61.599s][debug][gc,heap ] GC(8) O: Metaspace used 1164K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.040Z] [61.599s][debug][gc,heap ] GC(8) O: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.040Z] [61.599s][info ][gc,phases ] GC(8) O: Old Generation [2025-06-26T17:40:21.040Z] [61.606s][info ][gc,phases ] GC(8) O: Concurrent Mark 6.780ms [2025-06-26T17:40:21.040Z] [61.606s][info ][gc,phases ] GC(8) O: Pause Mark End 0.011ms [2025-06-26T17:40:21.040Z] [61.606s][info ][gc,phases ] GC(8) O: Concurrent Mark Free 0.001ms [2025-06-26T17:40:21.040Z] [61.608s][info ][gc,phases ] GC(8) O: Concurrent Process Non-Strong 1.653ms [2025-06-26T17:40:21.040Z] [61.608s][info ][gc,phases ] GC(8) O: Concurrent Reset Relocation Set 0.000ms [2025-06-26T17:40:21.040Z] [61.609s][info ][gc,phases ] GC(8) O: Concurrent Select Relocation Set 1.634ms [2025-06-26T17:40:21.040Z] [61.609s][info ][gc,task ] GC(8) O: Using 1 Workers for Old Generation [2025-06-26T17:40:21.040Z] [61.670s][info ][gc,task ] GC(8) O: Using 1 Workers for Old Generation [2025-06-26T17:40:21.040Z] [61.670s][info ][gc,phases ] GC(8) O: Concurrent Remap Roots 60.717ms [2025-06-26T17:40:21.041Z] [61.670s][info ][gc,phases ] GC(8) O: Pause Relocate Start 0.013ms [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,phases ] GC(8) O: Concurrent Relocate 10.970ms [2025-06-26T17:40:21.041Z] [61.681s][debug][gc,heap ] GC(8) O: Heap after GC invocations=15 (full 9): [2025-06-26T17:40:21.041Z] [61.681s][debug][gc,heap ] GC(8) O: ZHeap used 4M, capacity 330M, max capacity 512M [2025-06-26T17:40:21.041Z] [61.681s][debug][gc,heap ] GC(8) O: Metaspace used 1164K, committed 1344K, reserved 1114112K [2025-06-26T17:40:21.041Z] [61.681s][debug][gc,heap ] GC(8) O: class space used 112K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,alloc ] GC(8) O: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,alloc ] GC(8) O: Allocation Stalls: 0 0 0 0 [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,load ] GC(8) O: Load: 48.61 (30%) / 56.88 (36%) / 50.44 (32%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,mmu ] GC(8) O: MMU: 2ms/84.9%, 5ms/94.0%, 10ms/96.4%, 20ms/98.2%, 50ms/99.2%, 100ms/99.6% [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,marking ] GC(8) O: Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,marking ] GC(8) O: Mark Stack Usage: 32M [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,nmethod ] GC(8) O: NMethods: 399 registered, 30 unregistered [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,metaspace] GC(8) O: Metaspace: 1M used, 1M committed, 1088M reserved [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,ref ] GC(8) O: Encountered Discovered Enqueued [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,ref ] GC(8) O: Soft References: 189 0 0 [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,ref ] GC(8) O: Weak References: 306 224 0 [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,ref ] GC(8) O: Final References: 0 0 0 [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,ref ] GC(8) O: Phantom References: 10 7 0 [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,reloc ] GC(8) O: Candidates Selected In-Place Size Empty Relocated [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,reloc ] GC(8) O: Small Pages: 2 0 0 4M 0M 0M [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,reloc ] GC(8) O: Medium Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,reloc ] GC(8) O: Large Pages: 0 0 0 0M 0M 0M [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,reloc ] GC(8) O: Forwarding Usage: 0M [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Min Capacity: 128M(25%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Max Capacity: 512M(100%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Soft Max Capacity: 512M(100%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Heap Statistics: [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Mark Start Mark End Relocate Start Relocate End High Low [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Capacity: 330M (64%) 330M (64%) 330M (64%) 330M (64%) 330M (64%) 330M (64%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Free: 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) 4M (1%) 4M (1%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Old Generation Statistics: [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Mark Start Mark End Relocate Start Relocate End [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Live: - 2M (0%) 2M (0%) 2M (0%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Garbage: - 1M (0%) 1M (0%) 1M (0%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Allocated: - 0M (0%) 0M (0%) 0M (0%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Reclaimed: - - 0M (0%) 0M (0%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,heap ] GC(8) O: Compacted: - - - 0M (0%) [2025-06-26T17:40:21.041Z] [61.681s][info ][gc,phases ] GC(8) O: Old Generation 4M(1%)->4M(1%) 0.082s [2025-06-26T17:40:21.041Z] [61.681s][info ][gc ] GC(8) Major Collection (System.gc()) 208M(41%)->4M(1%) 0.122s [2025-06-26T17:40:21.041Z] [60.899s] (MainThread) Waiting for uncommit to start [2025-06-26T17:40:21.041Z] [66.327s][info ][gc,exit ] Stopping ZGC [2025-06-26T17:40:21.041Z] [66.339s][info ][gc,heap,exit] Heap [2025-06-26T17:40:21.041Z] [66.339s][info ][gc,heap,exit] ZHeap used 6M, capacity 330M, max capacity 512M [2025-06-26T17:40:21.041Z] [66.340s][info ][gc,heap,exit] Metaspace used 1258K, committed 1472K, reserved 1114112K [2025-06-26T17:40:21.041Z] [66.340s][info ][gc,heap,exit] class space used 124K, committed 192K, reserved 1048576K [2025-06-26T17:40:21.041Z] STDERR: [2025-06-26T17:40:21.041Z] rerun: [2025-06-26T17:40:21.041Z] cd /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/scratch/2 && \ [2025-06-26T17:40:21.041Z] DISPLAY=:0 \ [2025-06-26T17:40:21.041Z] HOME=/home/jenkins \ [2025-06-26T17:40:21.041Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-06-26T17:40:21.041Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-06-26T17:40:21.041Z] /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image/bin/java \ [2025-06-26T17:40:21.041Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-06-26T17:40:21.041Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-06-26T17:40:21.041Z] -Dtest.compiler.opts= \ [2025-06-26T17:40:21.041Z] -Dtest.java.opts= \ [2025-06-26T17:40:21.041Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image \ [2025-06-26T17:40:21.041Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/j2sdk-image \ [2025-06-26T17:40:21.041Z] -Dtest.timeout.factor=12.0 \ [2025-06-26T17:40:21.041Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-06-26T17:40:21.041Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-06-26T17:40:21.041Z] -Dtest.name=gc/z/TestUncommit.java \ [2025-06-26T17:40:21.041Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java \ [2025-06-26T17:40:21.041Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \ [2025-06-26T17:40:21.041Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-06-26T17:40:21.041Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d \ [2025-06-26T17:40:21.041Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/test/lib \ [2025-06-26T17:40:21.041Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/classes/1/test/lib \ [2025-06-26T17:40:21.041Z] -ea \ [2025-06-26T17:40:21.041Z] -esa \ [2025-06-26T17:40:21.041Z] -Xmx512m \ [2025-06-26T17:40:21.041Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-06-26T17:40:21.041Z] -XX:+UseZGC \ [2025-06-26T17:40:21.041Z] -XX:+ZGenerational \ [2025-06-26T17:40:21.041Z] -Xlog:gc*,gc+heap=debug,gc+stats=off \ [2025-06-26T17:40:21.041Z] -Xms128M \ [2025-06-26T17:40:21.041Z] -Xmx512M \ [2025-06-26T17:40:21.041Z] -XX:ZUncommitDelay=10 \ [2025-06-26T17:40:21.041Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk21_hs_sanity.openjdk_aarch64_linux_testList_2/aqa-tests/TKG/output_17509576675065/hotspot_tier1_gc_0/work/gc/z/TestUncommit.d/main.0.jta [2025-06-26T17:40:21.041Z] [2025-06-26T17:40:21.041Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 143] [2025-06-26T17:40:21.041Z] -------------------------------------------------- [2025-06-26T17:40:21.041Z] Test results: passed: 360; failed: 1 [2025-06-26T17:40:21.041Z] ----------------------------------- [2025-06-26T17:40:21.041Z] hotspot_tier1_gc_0_FAILED [2025-06-26T17:40:21.041Z] ----------------------------------- [2025-06-26T17:40:21.041Z] [2025-06-26T17:40:21.041Z] TEST TEARDOWN: [2025-06-26T17:40:21.041Z] Nothing to be done for teardown. [2025-06-26T17:40:21.041Z] hotspot_tier1_gc_0 Finish Time: Thu Jun 26 17:40:08 2025 Epoch Time (ms): 1750959608442