No Possible Issues Found via Git Search
hotspot_gc_0
[2025-12-17T21:55:46.202Z] Running test hotspot_gc_0 ...
[2025-12-17T21:55:46.202Z] ===============================================
[2025-12-17T21:55:46.871Z] hotspot_gc_0 Start Time: Wed Dec 17 15:54:53 2025 Epoch Time (ms): 1766008493024
[2025-12-17T21:55:46.871Z] variation: NoOptions
[2025-12-17T21:55:46.871Z] JVM_OPTIONS:
[2025-12-17T21:55:46.871Z] { \
[2025-12-17T21:55:46.871Z] echo ""; echo "TEST SETUP:"; \
[2025-12-17T21:55:46.871Z] echo "Nothing to be done for setup."; \
[2025-12-17T21:55:46.871Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17660084918440/hotspot_gc_0"; \
[2025-12-17T21:55:46.871Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17660084918440/hotspot_gc_0"; \
[2025-12-17T21:55:46.871Z] echo ""; echo "TESTING:"; \
[2025-12-17T21:55:46.871Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \
[2025-12-17T21:55:46.871Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:3 -nativepath:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \
[2025-12-17T21:55:46.871Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17660084918440/hotspot_gc_0"/work" \
[2025-12-17T21:55:46.871Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17660084918440/hotspot_gc_0"/report" \
[2025-12-17T21:55:46.871Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image" \
[2025-12-17T21:55:46.871Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \
[2025-12-17T21:55:46.871Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \
[2025-12-17T21:55:46.871Z] \
[2025-12-17T21:55:46.871Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \
[2025-12-17T21:55:46.871Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \
[2025-12-17T21:55:46.871Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \
[2025-12-17T21:55:46.871Z] echo ""; echo "TEST TEARDOWN:"; \
[2025-12-17T21:55:46.871Z] echo "Nothing to be done for teardown."; \
[2025-12-17T21:55:46.871Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17660084918440/TestTargetResult";
[2025-12-17T21:55:46.872Z]
[2025-12-17T21:55:46.872Z] TEST SETUP:
[2025-12-17T21:55:46.872Z] Nothing to be done for setup.
[2025-12-17T21:55:46.872Z]
[2025-12-17T21:55:46.872Z] TESTING:
[2025-12-17T21:55:47.553Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17660084918440/hotspot_gc_0/work" not found: creating
[2025-12-17T21:55:47.553Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17660084918440/hotspot_gc_0/report" not found: creating
[2025-12-17T21:55:52.553Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work
[2025-12-17T23:33:57.498Z] --------------------------------------------------
[2025-12-17T23:33:57.498Z] TEST: gc/z/TestUncommit.java
[2025-12-17T23:33:57.498Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image
[2025-12-17T23:33:57.498Z]
[2025-12-17T23:33:57.498Z] ACTION: build -- Passed. Build successful
[2025-12-17T23:33:57.498Z] REASON: Named class compiled on demand
[2025-12-17T23:33:57.498Z] TIME: 0.145 seconds
[2025-12-17T23:33:57.498Z] messages:
[2025-12-17T23:33:57.498Z] command: build gc.z.TestUncommit
[2025-12-17T23:33:57.498Z] reason: Named class compiled on demand
[2025-12-17T23:33:57.498Z] started: Wed Dec 17 17:16:55 CST 2025
[2025-12-17T23:33:57.498Z] Test directory:
[2025-12-17T23:33:57.498Z] compile: gc.z.TestUncommit
[2025-12-17T23:33:57.498Z] finished: Wed Dec 17 17:16:55 CST 2025
[2025-12-17T23:33:57.498Z] elapsed time (seconds): 0.145
[2025-12-17T23:33:57.498Z]
[2025-12-17T23:33:57.498Z] ACTION: compile -- Passed. Compilation successful
[2025-12-17T23:33:57.498Z] REASON: .class file out of date or does not exist
[2025-12-17T23:33:57.498Z] TIME: 0.144 seconds
[2025-12-17T23:33:57.498Z] messages:
[2025-12-17T23:33:57.498Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java
[2025-12-17T23:33:57.498Z] reason: .class file out of date or does not exist
[2025-12-17T23:33:57.499Z] started: Wed Dec 17 17:16:55 CST 2025
[2025-12-17T23:33:57.499Z] Mode: agentvm
[2025-12-17T23:33:57.499Z] Agent id: 3
[2025-12-17T23:33:57.499Z] finished: Wed Dec 17 17:16:55 CST 2025
[2025-12-17T23:33:57.499Z] elapsed time (seconds): 0.144
[2025-12-17T23:33:57.499Z] configuration:
[2025-12-17T23:33:57.499Z] Boot Layer (javac runtime environment)
[2025-12-17T23:33:57.499Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar
[2025-12-17T23:33:57.499Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar
[2025-12-17T23:33:57.499Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/patches/java.base
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] javac compilation environment
[2025-12-17T23:33:57.499Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z
[2025-12-17T23:33:57.499Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib
[2025-12-17T23:33:57.499Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z
[2025-12-17T23:33:57.499Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d
[2025-12-17T23:33:57.499Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/test/lib
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] rerun:
[2025-12-17T23:33:57.499Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/scratch/2 && \
[2025-12-17T23:33:57.499Z] DISPLAY=:0 \
[2025-12-17T23:33:57.499Z] HOME=/home/jenkins \
[2025-12-17T23:33:57.499Z] LANG=en_US.UTF-8 \
[2025-12-17T23:33:57.499Z] PATH=/bin:/usr/bin:/usr/sbin \
[2025-12-17T23:33:57.499Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/javac \
[2025-12-17T23:33:57.499Z] -J-ea \
[2025-12-17T23:33:57.499Z] -J-esa \
[2025-12-17T23:33:57.499Z] -J-Xmx512m \
[2025-12-17T23:33:57.499Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \
[2025-12-17T23:33:57.499Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \
[2025-12-17T23:33:57.499Z] -J-Dtest.compiler.opts= \
[2025-12-17T23:33:57.499Z] -J-Dtest.java.opts= \
[2025-12-17T23:33:57.499Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image \
[2025-12-17T23:33:57.499Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image \
[2025-12-17T23:33:57.499Z] -J-Dtest.timeout.factor=8.0 \
[2025-12-17T23:33:57.499Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
[2025-12-17T23:33:57.499Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \
[2025-12-17T23:33:57.499Z] -J-Dtest.name=gc/z/TestUncommit.java \
[2025-12-17T23:33:57.499Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java \
[2025-12-17T23:33:57.499Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \
[2025-12-17T23:33:57.499Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \
[2025-12-17T23:33:57.499Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d \
[2025-12-17T23:33:57.499Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/test/lib \
[2025-12-17T23:33:57.499Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/test/lib \
[2025-12-17T23:33:57.499Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d \
[2025-12-17T23:33:57.499Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \
[2025-12-17T23:33:57.499Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/test/lib /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java
[2025-12-17T23:33:57.499Z] direct:
[2025-12-17T23:33:57.499Z] Note: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib/jdk/test/lib/Asserts.java uses unchecked or unsafe operations.
[2025-12-17T23:33:57.499Z] Note: Recompile with -Xlint:unchecked for details.
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] ACTION: main -- Error. Program `/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960418ms).
[2025-12-17T23:33:57.499Z] REASON: User specified action: run main/othervm -XX:+UseZGC -Xlog:gc*,gc+heap=debug,gc+stats=off -Xms128M -Xmx512M -XX:ZUncommitDelay=10 gc.z.TestUncommit
[2025-12-17T23:33:57.499Z] TIME: 960.433 seconds
[2025-12-17T23:33:57.499Z] messages:
[2025-12-17T23:33:57.499Z] command: main -XX:+UseZGC -Xlog:gc*,gc+heap=debug,gc+stats=off -Xms128M -Xmx512M -XX:ZUncommitDelay=10 gc.z.TestUncommit
[2025-12-17T23:33:57.499Z] reason: User specified action: run main/othervm -XX:+UseZGC -Xlog:gc*,gc+heap=debug,gc+stats=off -Xms128M -Xmx512M -XX:ZUncommitDelay=10 gc.z.TestUncommit
[2025-12-17T23:33:57.499Z] started: Wed Dec 17 17:16:55 CST 2025
[2025-12-17T23:33:57.499Z] Mode: othervm [/othervm specified]
[2025-12-17T23:33:57.499Z] Timeout information:
[2025-12-17T23:33:57.499Z] Running jstack on process 30713
[2025-12-17T23:33:57.499Z] 2025-12-17 17:32:56
[2025-12-17T23:33:57.499Z] Full thread dump OpenJDK 64-Bit Server VM (17.0.18-beta+6-ea mixed mode, sharing):
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] Threads class SMR info:
[2025-12-17T23:33:57.499Z] _java_thread_list=0x00007fd1ac001dd0, length=13, elements={
[2025-12-17T23:33:57.499Z] 0x00007fd45402ea50, 0x00007fd454090eb0, 0x00007fd4540924f0, 0x00007fd4540993a0,
[2025-12-17T23:33:57.499Z] 0x00007fd45409a930, 0x00007fd45409bf50, 0x00007fd45409db50, 0x00007fd45409f2b0,
[2025-12-17T23:33:57.499Z] 0x00007fd4540a8950, 0x00007fd4540fcbd0, 0x00007fd454100aa0, 0x00007fd454122c00,
[2025-12-17T23:33:57.499Z] 0x00007fd1ac000bd0
[2025-12-17T23:33:57.499Z] }
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] "main" #1 prio=5 os_prio=0 cpu=411.03ms elapsed=960.36s tid=0x00007fd45402ea50 nid=0x77fc in Object.wait() [0x00007fd45d378000]
[2025-12-17T23:33:57.499Z] java.lang.Thread.State: WAITING (on object monitor)
[2025-12-17T23:33:57.499Z] at java.lang.Object.wait(java.base@17.0.18-beta/Native Method)
[2025-12-17T23:33:57.499Z] - waiting on <0x000010000a400000> (a java.lang.Thread)
[2025-12-17T23:33:57.499Z] at java.lang.Thread.join(java.base@17.0.18-beta/Thread.java:1313)
[2025-12-17T23:33:57.499Z] - locked <0x000010000a400000> (a java.lang.Thread)
[2025-12-17T23:33:57.499Z] at java.lang.Thread.join(java.base@17.0.18-beta/Thread.java:1381)
[2025-12-17T23:33:57.499Z] at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:85)
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] "Reference Handler" #2 daemon prio=10 os_prio=0 cpu=0.73ms elapsed=960.04s tid=0x00007fd454090eb0 nid=0x780a waiting on condition [0x00007fd43d10b000]
[2025-12-17T23:33:57.499Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.499Z] at java.lang.ref.Reference.waitForReferencePendingList(java.base@17.0.18-beta/Native Method)
[2025-12-17T23:33:57.499Z] at java.lang.ref.Reference.processPendingReferences(java.base@17.0.18-beta/Reference.java:253)
[2025-12-17T23:33:57.499Z] at java.lang.ref.Reference$ReferenceHandler.run(java.base@17.0.18-beta/Reference.java:215)
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] "Finalizer" #3 daemon prio=8 os_prio=0 cpu=0.78ms elapsed=960.04s tid=0x00007fd4540924f0 nid=0x780b in Object.wait() [0x00007fd43d00a000]
[2025-12-17T23:33:57.499Z] java.lang.Thread.State: WAITING (on object monitor)
[2025-12-17T23:33:57.499Z] at java.lang.Object.wait(java.base@17.0.18-beta/Native Method)
[2025-12-17T23:33:57.499Z] - waiting on <0x000010000d013250> (a java.lang.ref.ReferenceQueue$Lock)
[2025-12-17T23:33:57.499Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.18-beta/ReferenceQueue.java:155)
[2025-12-17T23:33:57.499Z] - locked <0x000010000d013250> (a java.lang.ref.ReferenceQueue$Lock)
[2025-12-17T23:33:57.499Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.18-beta/ReferenceQueue.java:176)
[2025-12-17T23:33:57.499Z] at java.lang.ref.Finalizer$FinalizerThread.run(java.base@17.0.18-beta/Finalizer.java:172)
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] "Signal Dispatcher" #4 daemon prio=9 os_prio=0 cpu=1.42ms elapsed=960.03s tid=0x00007fd4540993a0 nid=0x780c waiting on condition [0x0000000000000000]
[2025-12-17T23:33:57.499Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] "Service Thread" #5 daemon prio=9 os_prio=0 cpu=1.25ms elapsed=960.02s tid=0x00007fd45409a930 nid=0x780d runnable [0x0000000000000000]
[2025-12-17T23:33:57.499Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.499Z] "Monitor Deflation Thread" #6 daemon prio=9 os_prio=0 cpu=301.14ms elapsed=960.02s tid=0x00007fd45409bf50 nid=0x780e runnable [0x0000000000000000]
[2025-12-17T23:33:57.499Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.499Z]
[2025-12-17T23:33:57.500Z] "C2 CompilerThread0" #7 daemon prio=9 os_prio=0 cpu=45.03ms elapsed=960.02s tid=0x00007fd45409db50 nid=0x780f waiting on condition [0x0000000000000000]
[2025-12-17T23:33:57.500Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.500Z] No compile task
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "C1 CompilerThread0" #10 daemon prio=9 os_prio=0 cpu=119.98ms elapsed=960.02s tid=0x00007fd45409f2b0 nid=0x7810 waiting on condition [0x0000000000000000]
[2025-12-17T23:33:57.500Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.500Z] No compile task
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "Sweeper thread" #11 daemon prio=9 os_prio=0 cpu=0.70ms elapsed=960.02s tid=0x00007fd4540a8950 nid=0x7811 runnable [0x0000000000000000]
[2025-12-17T23:33:57.500Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "Notification Thread" #12 daemon prio=9 os_prio=0 cpu=0.26ms elapsed=959.99s tid=0x00007fd4540fcbd0 nid=0x7812 runnable [0x0000000000000000]
[2025-12-17T23:33:57.500Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "Common-Cleaner" #13 daemon prio=8 os_prio=0 cpu=2.21ms elapsed=959.98s tid=0x00007fd454100aa0 nid=0x7814 in Object.wait() [0x00007fd43c6dd000]
[2025-12-17T23:33:57.500Z] java.lang.Thread.State: TIMED_WAITING (on object monitor)
[2025-12-17T23:33:57.500Z] at java.lang.Object.wait(java.base@17.0.18-beta/Native Method)
[2025-12-17T23:33:57.500Z] - waiting on <0x000010000d0997e0> (a java.lang.ref.ReferenceQueue$Lock)
[2025-12-17T23:33:57.500Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.18-beta/ReferenceQueue.java:155)
[2025-12-17T23:33:57.500Z] - locked <0x000010000d0997e0> (a java.lang.ref.ReferenceQueue$Lock)
[2025-12-17T23:33:57.500Z] at jdk.internal.ref.CleanerImpl.run(java.base@17.0.18-beta/CleanerImpl.java:140)
[2025-12-17T23:33:57.500Z] at java.lang.Thread.run(java.base@17.0.18-beta/Thread.java:840)
[2025-12-17T23:33:57.500Z] at jdk.internal.misc.InnocuousThread.run(java.base@17.0.18-beta/InnocuousThread.java:162)
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "MainThread" #14 prio=5 os_prio=0 cpu=836.85ms elapsed=959.92s tid=0x00007fd454122c00 nid=0x7815 waiting on condition [0x00007fd43c58d000]
[2025-12-17T23:33:57.500Z] java.lang.Thread.State: TIMED_WAITING (sleeping)
[2025-12-17T23:33:57.500Z] at java.lang.Thread.sleep(java.base@17.0.18-beta/Native Method)
[2025-12-17T23:33:57.500Z] at gc.z.TestUncommit.test(TestUncommit.java:85)
[2025-12-17T23:33:57.500Z] at gc.z.TestUncommit.main(TestUncommit.java:131)
[2025-12-17T23:33:57.500Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@17.0.18-beta/Native Method)
[2025-12-17T23:33:57.500Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@17.0.18-beta/NativeMethodAccessorImpl.java:77)
[2025-12-17T23:33:57.500Z] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@17.0.18-beta/DelegatingMethodAccessorImpl.java:43)
[2025-12-17T23:33:57.500Z] at java.lang.reflect.Method.invoke(java.base@17.0.18-beta/Method.java:569)
[2025-12-17T23:33:57.500Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138)
[2025-12-17T23:33:57.500Z] at java.lang.Thread.run(java.base@17.0.18-beta/Thread.java:840)
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "Attach Listener" #15 daemon prio=9 os_prio=0 cpu=0.69ms elapsed=0.10s tid=0x00007fd1ac000bd0 nid=0x7e15 waiting on condition [0x0000000000000000]
[2025-12-17T23:33:57.500Z] java.lang.Thread.State: RUNNABLE
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "VM Periodic Task Thread" os_prio=0 cpu=1817.55ms elapsed=959.99s tid=0x00007fd4540fe780 nid=0x7813 waiting on condition
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "VM Thread" os_prio=0 cpu=84.96ms elapsed=960.05s tid=0x00007fd45408c560 nid=0x7809 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "RuntimeWorker#4" os_prio=0 cpu=0.23ms elapsed=960.09s tid=0x00007fd454060650 nid=0x7808 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "RuntimeWorker#3" os_prio=0 cpu=0.22ms elapsed=960.09s tid=0x00007fd45405f6e0 nid=0x7807 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "RuntimeWorker#2" os_prio=0 cpu=0.22ms elapsed=960.09s tid=0x00007fd45405e770 nid=0x7806 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "RuntimeWorker#1" os_prio=0 cpu=0.24ms elapsed=960.09s tid=0x00007fd45405d800 nid=0x7805 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "RuntimeWorker#0" os_prio=0 cpu=0.31ms elapsed=960.09s tid=0x00007fd45405c890 nid=0x7804 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "ZStat" os_prio=0 cpu=80.75ms elapsed=960.09s tid=0x00007fd45405b7c0 nid=0x7803 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "ZDirector" os_prio=0 cpu=1317.78ms elapsed=960.09s tid=0x00007fd45405a7b0 nid=0x7802 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "ZDriver" os_prio=0 cpu=64.67ms elapsed=960.09s tid=0x00007fd4540573e0 nid=0x7801 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "ZUncommitter" os_prio=0 cpu=0.22ms elapsed=960.36s tid=0x00007fd454059050 nid=0x7800 runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "ZUnmapper" os_prio=0 cpu=0.25ms elapsed=960.36s tid=0x00007fd454055010 nid=0x77ff runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "ZWorker#1" os_prio=0 cpu=367.38ms elapsed=960.36s tid=0x00007fd45404c010 nid=0x77fe runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] "ZWorker#0" os_prio=0 cpu=323.04ms elapsed=960.36s tid=0x00007fd4540493d0 nid=0x77fd runnable
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] JNI global refs: 9, weak refs: 0
[2025-12-17T23:33:57.500Z]
[2025-12-17T23:33:57.500Z] --- Timeout information end.
[2025-12-17T23:33:57.500Z] finished: Wed Dec 17 17:32:56 CST 2025
[2025-12-17T23:33:57.500Z] elapsed time (seconds): 960.433
[2025-12-17T23:33:57.500Z] configuration:
[2025-12-17T23:33:57.500Z] STDOUT:
[2025-12-17T23:33:57.500Z] [0.011s][debug][gc,heap] Minimum heap 134217728 Initial heap 134217728 Maximum heap 536870912
[2025-12-17T23:33:57.500Z] [0.011s][info ][gc,init] Initializing The Z Garbage Collector
[2025-12-17T23:33:57.500Z] [0.011s][info ][gc,init] Version: 17.0.18-beta+6-ea (release)
[2025-12-17T23:33:57.500Z] [0.011s][info ][gc,init] NUMA Support: Disabled
[2025-12-17T23:33:57.500Z] [0.011s][info ][gc,init] CPUs: 8 total, 8 available
[2025-12-17T23:33:57.500Z] [0.011s][info ][gc,init] Memory: 7839M
[2025-12-17T23:33:57.500Z] [0.011s][info ][gc,init] Large Page Support: Disabled
[2025-12-17T23:33:57.500Z] [0.012s][info ][gc,init] GC Workers: 2 (dynamic)
[2025-12-17T23:33:57.500Z] [0.013s][info ][gc,init] Address Space Type: Contiguous/Unrestricted/Complete
[2025-12-17T23:33:57.500Z] [0.013s][info ][gc,init] Address Space Size: 8192M x 3 = 24576M
[2025-12-17T23:33:57.500Z] [0.013s][info ][gc,init] Heap Backing File: /dev/shm/java_heap.30713
[2025-12-17T23:33:57.500Z] [0.013s][info ][gc,init] Heap Backing Filesystem: tmpfs (0x1021994)
[2025-12-17T23:33:57.500Z] [0.014s][info ][gc,init] Min Capacity: 128M
[2025-12-17T23:33:57.500Z] [0.014s][info ][gc,init] Initial Capacity: 128M
[2025-12-17T23:33:57.500Z] [0.014s][info ][gc,init] Max Capacity: 512M
[2025-12-17T23:33:57.500Z] [0.014s][info ][gc,init] Medium Page Size: 16M
[2025-12-17T23:33:57.500Z] [0.014s][info ][gc,init] Pre-touch: Disabled
[2025-12-17T23:33:57.500Z] [0.014s][info ][gc,init] Available space on backing filesystem: 3919M
[2025-12-17T23:33:57.500Z] [0.018s][error][gc ] Failed to uncommit memory (Not supported)
[2025-12-17T23:33:57.500Z] [0.018s][info ][gc,init] Uncommit: Implicitly Disabled (Not supported by operating system)
[2025-12-17T23:33:57.500Z] [0.280s][info ][gc,init] Runtime Workers: 5
[2025-12-17T23:33:57.500Z] [0.282s][info ][gc ] Using The Z Garbage Collector
[2025-12-17T23:33:57.500Z] [0.307s][info ][gc,metaspace] CDS archive(s) mapped at: [0x00007fd1c7000000-0x00007fd1c7ba2000-0x00007fd1c7ba2000), size 12197888, SharedBaseAddress: 0x00007fd1c7000000, ArchiveRelocationMode: 1.
[2025-12-17T23:33:57.500Z] [0.307s][info ][gc,metaspace] Compressed class space mapped at: 0x00007fd1c8000000-0x00007fd208000000, reserved size: 1073741824
[2025-12-17T23:33:57.500Z] [0.307s][info ][gc,metaspace] Narrow klass base: 0x00007fd1c7000000, Narrow klass shift: 0, Narrow klass range: 0x100000000
[2025-12-17T23:33:57.500Z] [0.039s] (MainThread) Iteration 0
[2025-12-17T23:33:57.500Z] [0.073s] (MainThread) Allocating
[2025-12-17T23:33:57.500Z] [0.642s][debug][gc,heap ] GC(0) Heap before GC invocations=0 (full 0):
[2025-12-17T23:33:57.500Z] [0.642s][debug][gc,heap ] GC(0) ZHeap used 72M, capacity 128M, max capacity 512M
[2025-12-17T23:33:57.500Z] [0.642s][debug][gc,heap ] GC(0) Metaspace used 557K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.500Z] [0.642s][debug][gc,heap ] GC(0) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.500Z] [0.642s][info ][gc,start ] GC(0) Garbage Collection (Warmup)
[2025-12-17T23:33:57.500Z] [0.642s][info ][gc,task ] GC(0) Using 2 workers
[2025-12-17T23:33:57.500Z] [0.643s][info ][gc,phases ] GC(0) Pause Mark Start 0.075ms
[2025-12-17T23:33:57.500Z] [0.779s][info ][gc,phases ] GC(0) Concurrent Mark 136.140ms
[2025-12-17T23:33:57.500Z] [0.780s][info ][gc,phases ] GC(0) Pause Mark End 0.098ms
[2025-12-17T23:33:57.500Z] [0.780s][info ][gc,phases ] GC(0) Concurrent Mark Free 0.012ms
[2025-12-17T23:33:57.500Z] [0.781s][info ][gc,phases ] GC(0) Concurrent Process Non-Strong References 0.889ms
[2025-12-17T23:33:57.500Z] [0.781s][info ][gc,phases ] GC(0) Concurrent Reset Relocation Set 0.006ms
[2025-12-17T23:33:57.500Z] [0.803s][info ][gc,phases ] GC(0) Concurrent Select Relocation Set 22.133ms
[2025-12-17T23:33:57.500Z] [0.803s][info ][gc,phases ] GC(0) Pause Relocate Start 0.059ms
[2025-12-17T23:33:57.500Z] [0.811s][info ][gc,phases ] GC(0) Concurrent Relocate 7.786ms
[2025-12-17T23:33:57.500Z] [0.811s][debug][gc,heap ] GC(0) Heap after GC invocations=1 (full 1):
[2025-12-17T23:33:57.500Z] [0.811s][debug][gc,heap ] GC(0) ZHeap used 116M, capacity 128M, max capacity 512M
[2025-12-17T23:33:57.500Z] [0.811s][debug][gc,heap ] GC(0) Metaspace used 558K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.500Z] [0.811s][debug][gc,heap ] GC(0) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.500Z] [0.811s][info ][gc,load ] GC(0) Load: 2.39/3.00/3.91
[2025-12-17T23:33:57.500Z] [0.811s][info ][gc,mmu ] GC(0) MMU: 2ms/95.1%, 5ms/98.0%, 10ms/99.0%, 20ms/99.5%, 50ms/99.7%, 100ms/99.8%
[2025-12-17T23:33:57.500Z] [0.812s][info ][gc,marking ] GC(0) Mark: 2 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s)
[2025-12-17T23:33:57.500Z] [0.812s][info ][gc,marking ] GC(0) Mark Stack Usage: 32M
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,nmethod ] GC(0) NMethods: 274 registered, 0 unregistered
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,metaspace] GC(0) Metaspace: 0M used, 0M committed, 1088M reserved
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,ref ] GC(0) Soft: 98 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,ref ] GC(0) Weak: 263 encountered, 98 discovered, 50 enqueued
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,ref ] GC(0) Final: 0 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,ref ] GC(0) Phantom: 14 encountered, 11 discovered, 6 enqueued
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,reloc ] GC(0) Small Pages: 36 / 72M, Empty: 0M, Relocated: 3M, In-Place: 0
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,reloc ] GC(0) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,reloc ] GC(0) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,reloc ] GC(0) Forwarding Usage: 0M
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,heap ] GC(0) Min Capacity: 128M(25%)
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,heap ] GC(0) Max Capacity: 512M(100%)
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,heap ] GC(0) Soft Max Capacity: 512M(100%)
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,heap ] GC(0) Mark Start Mark End Relocate Start Relocate End High Low
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,heap ] GC(0) Capacity: 128M (25%) 128M (25%) 128M (25%) 128M (25%) 128M (25%) 128M (25%)
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,heap ] GC(0) Free: 440M (86%) 404M (79%) 396M (77%) 396M (77%) 440M (86%) 392M (77%)
[2025-12-17T23:33:57.501Z] [0.812s][info ][gc,heap ] GC(0) Used: 72M (14%) 108M (21%) 116M (23%) 116M (23%) 120M (23%) 72M (14%)
[2025-12-17T23:33:57.501Z] [0.813s][info ][gc,heap ] GC(0) Live: - 65M (13%) 65M (13%) 65M (13%) - -
[2025-12-17T23:33:57.501Z] [0.813s][info ][gc,heap ] GC(0) Allocated: - 36M (7%) 44M (9%) 47M (9%) - -
[2025-12-17T23:33:57.501Z] [0.813s][info ][gc,heap ] GC(0) Garbage: - 6M (1%) 6M (1%) 2M (0%) - -
[2025-12-17T23:33:57.501Z] [0.813s][info ][gc,heap ] GC(0) Reclaimed: - - 0M (0%) 3M (1%) - -
[2025-12-17T23:33:57.501Z] [0.813s][info ][gc ] GC(0) Garbage Collection (Warmup) 72M(14%)->116M(23%)
[2025-12-17T23:33:57.501Z] [0.842s][debug][gc,heap ] GC(1) Heap before GC invocations=1 (full 1):
[2025-12-17T23:33:57.501Z] [0.843s][debug][gc,heap ] GC(1) ZHeap used 146M, capacity 146M, max capacity 512M
[2025-12-17T23:33:57.501Z] [0.843s][debug][gc,heap ] GC(1) Metaspace used 558K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.501Z] [0.843s][debug][gc,heap ] GC(1) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.501Z] [0.843s][info ][gc,start ] GC(1) Garbage Collection (Warmup)
[2025-12-17T23:33:57.501Z] [0.843s][info ][gc,task ] GC(1) Using 2 workers
[2025-12-17T23:33:57.501Z] [0.881s][info ][gc,phases ] GC(1) Pause Mark Start 0.060ms
[2025-12-17T23:33:57.501Z] [1.020s][info ][gc,phases ] GC(1) Concurrent Mark 139.389ms
[2025-12-17T23:33:57.501Z] [1.021s][info ][gc,phases ] GC(1) Pause Mark End 0.066ms
[2025-12-17T23:33:57.501Z] [1.021s][info ][gc,phases ] GC(1) Concurrent Mark Free 0.014ms
[2025-12-17T23:33:57.501Z] [1.026s][info ][gc,phases ] GC(1) Concurrent Process Non-Strong References 4.828ms
[2025-12-17T23:33:57.501Z] [1.026s][info ][gc,phases ] GC(1) Concurrent Reset Relocation Set 0.015ms
[2025-12-17T23:33:57.501Z] [1.030s][info ][gc,phases ] GC(1) Concurrent Select Relocation Set 3.539ms
[2025-12-17T23:33:57.501Z] [1.032s][info ][gc,phases ] GC(1) Pause Relocate Start 0.058ms
[2025-12-17T23:33:57.501Z] [1.043s][info ][gc,phases ] GC(1) Concurrent Relocate 11.099ms
[2025-12-17T23:33:57.501Z] [1.044s][debug][gc,heap ] GC(1) Heap after GC invocations=2 (full 2):
[2025-12-17T23:33:57.501Z] [1.044s][debug][gc,heap ] GC(1) ZHeap used 180M, capacity 184M, max capacity 512M
[2025-12-17T23:33:57.501Z] [1.044s][debug][gc,heap ] GC(1) Metaspace used 558K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.501Z] [1.044s][debug][gc,heap ] GC(1) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.501Z] [1.044s][info ][gc,load ] GC(1) Load: 2.39/3.00/3.91
[2025-12-17T23:33:57.501Z] [1.044s][info ][gc,mmu ] GC(1) MMU: 2ms/95.1%, 5ms/98.0%, 10ms/99.0%, 20ms/99.4%, 50ms/99.7%, 100ms/99.8%
[2025-12-17T23:33:57.501Z] [1.045s][info ][gc,marking ] GC(1) Mark: 2 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s)
[2025-12-17T23:33:57.501Z] [1.045s][info ][gc,marking ] GC(1) Mark Stack Usage: 32M
[2025-12-17T23:33:57.501Z] [1.045s][info ][gc,nmethod ] GC(1) NMethods: 274 registered, 0 unregistered
[2025-12-17T23:33:57.501Z] [1.045s][info ][gc,metaspace] GC(1) Metaspace: 0M used, 0M committed, 1088M reserved
[2025-12-17T23:33:57.501Z] [1.045s][info ][gc,ref ] GC(1) Soft: 98 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.501Z] [1.046s][info ][gc,ref ] GC(1) Weak: 213 encountered, 3 discovered, 0 enqueued
[2025-12-17T23:33:57.501Z] [1.046s][info ][gc,ref ] GC(1) Final: 0 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.501Z] [1.046s][info ][gc,ref ] GC(1) Phantom: 8 encountered, 5 discovered, 0 enqueued
[2025-12-17T23:33:57.501Z] [1.046s][info ][gc,reloc ] GC(1) Small Pages: 65 / 130M, Empty: 0M, Relocated: 1M, In-Place: 0
[2025-12-17T23:33:57.501Z] [1.046s][info ][gc,reloc ] GC(1) Medium Pages: 1 / 16M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.501Z] [1.046s][info ][gc,reloc ] GC(1) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.501Z] [1.047s][info ][gc,reloc ] GC(1) Forwarding Usage: 0M
[2025-12-17T23:33:57.501Z] [1.047s][info ][gc,heap ] GC(1) Min Capacity: 128M(25%)
[2025-12-17T23:33:57.501Z] [1.047s][info ][gc,heap ] GC(1) Max Capacity: 512M(100%)
[2025-12-17T23:33:57.501Z] [1.047s][info ][gc,heap ] GC(1) Soft Max Capacity: 512M(100%)
[2025-12-17T23:33:57.501Z] [1.047s][info ][gc,heap ] GC(1) Mark Start Mark End Relocate Start Relocate End High Low
[2025-12-17T23:33:57.501Z] [1.047s][info ][gc,heap ] GC(1) Capacity: 146M (29%) 174M (34%) 178M (35%) 184M (36%) 184M (36%) 146M (29%)
[2025-12-17T23:33:57.501Z] [1.047s][info ][gc,heap ] GC(1) Free: 366M (71%) 338M (66%) 334M (65%) 332M (65%) 366M (71%) 328M (64%)
[2025-12-17T23:33:57.501Z] [1.048s][info ][gc,heap ] GC(1) Used: 146M (29%) 174M (34%) 178M (35%) 180M (35%) 184M (36%) 146M (29%)
[2025-12-17T23:33:57.501Z] [1.048s][info ][gc,heap ] GC(1) Live: - 125M (25%) 125M (25%) 125M (25%) - -
[2025-12-17T23:33:57.501Z] [1.048s][info ][gc,heap ] GC(1) Allocated: - 28M (5%) 32M (6%) 34M (7%) - -
[2025-12-17T23:33:57.501Z] [1.048s][info ][gc,heap ] GC(1) Garbage: - 20M (4%) 20M (4%) 20M (4%) - -
[2025-12-17T23:33:57.501Z] [1.048s][info ][gc,heap ] GC(1) Reclaimed: - - 0M (0%) 0M (0%) - -
[2025-12-17T23:33:57.501Z] [1.048s][info ][gc ] GC(1) Garbage Collection (Warmup) 146M(29%)->180M(35%)
[2025-12-17T23:33:57.501Z] [1.143s][debug][gc,heap ] GC(2) Heap before GC invocations=2 (full 2):
[2025-12-17T23:33:57.501Z] [1.143s][debug][gc,heap ] GC(2) ZHeap used 226M, capacity 226M, max capacity 512M
[2025-12-17T23:33:57.501Z] [1.143s][debug][gc,heap ] GC(2) Metaspace used 558K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.501Z] [1.143s][debug][gc,heap ] GC(2) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.501Z] [1.144s][info ][gc,start ] GC(2) Garbage Collection (Warmup)
[2025-12-17T23:33:57.501Z] [1.144s][info ][gc,task ] GC(2) Using 2 workers
[2025-12-17T23:33:57.501Z] [1.165s][info ][gc,phases ] GC(2) Pause Mark Start 0.062ms
[2025-12-17T23:33:57.501Z] [0.779s] (MainThread) Reclaiming
[2025-12-17T23:33:57.501Z] [1.251s][info ][gc,phases ] GC(2) Concurrent Mark 86.384ms
[2025-12-17T23:33:57.501Z] [1.252s][info ][gc,phases ] GC(2) Pause Mark End 0.068ms
[2025-12-17T23:33:57.501Z] [1.252s][info ][gc,phases ] GC(2) Concurrent Mark Free 0.013ms
[2025-12-17T23:33:57.501Z] [1.253s][info ][gc,phases ] GC(2) Concurrent Process Non-Strong References 1.271ms
[2025-12-17T23:33:57.501Z] [1.254s][info ][gc,phases ] GC(2) Concurrent Reset Relocation Set 0.014ms
[2025-12-17T23:33:57.501Z] [1.257s][info ][gc,phases ] GC(2) Concurrent Select Relocation Set 3.502ms
[2025-12-17T23:33:57.501Z] [1.258s][info ][gc,phases ] GC(2) Pause Relocate Start 0.058ms
[2025-12-17T23:33:57.501Z] [1.289s][info ][gc,phases ] GC(2) Concurrent Relocate 30.963ms
[2025-12-17T23:33:57.501Z] [1.289s][debug][gc,heap ] GC(2) Heap after GC invocations=3 (full 3):
[2025-12-17T23:33:57.502Z] [1.289s][debug][gc,heap ] GC(2) ZHeap used 224M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.502Z] [1.289s][debug][gc,heap ] GC(2) Metaspace used 558K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.502Z] [1.289s][debug][gc,heap ] GC(2) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.502Z] [1.290s][info ][gc,load ] GC(2) Load: 2.39/3.00/3.91
[2025-12-17T23:33:57.502Z] [1.290s][info ][gc,mmu ] GC(2) MMU: 2ms/95.1%, 5ms/98.0%, 10ms/98.7%, 20ms/99.4%, 50ms/99.7%, 100ms/99.8%
[2025-12-17T23:33:57.502Z] [1.290s][info ][gc,marking ] GC(2) Mark: 2 stripe(s), 4 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s)
[2025-12-17T23:33:57.502Z] [1.290s][info ][gc,marking ] GC(2) Mark Stack Usage: 32M
[2025-12-17T23:33:57.502Z] [1.290s][info ][gc,nmethod ] GC(2) NMethods: 274 registered, 0 unregistered
[2025-12-17T23:33:57.502Z] [1.290s][info ][gc,metaspace] GC(2) Metaspace: 0M used, 0M committed, 1088M reserved
[2025-12-17T23:33:57.502Z] [1.291s][info ][gc,ref ] GC(2) Soft: 98 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.502Z] [1.291s][info ][gc,ref ] GC(2) Weak: 213 encountered, 4 discovered, 0 enqueued
[2025-12-17T23:33:57.502Z] [1.291s][info ][gc,ref ] GC(2) Final: 0 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.502Z] [1.291s][info ][gc,ref ] GC(2) Phantom: 8 encountered, 5 discovered, 0 enqueued
[2025-12-17T23:33:57.502Z] [1.291s][info ][gc,reloc ] GC(2) Small Pages: 97 / 194M, Empty: 0M, Relocated: 1M, In-Place: 0
[2025-12-17T23:33:57.502Z] [1.291s][info ][gc,reloc ] GC(2) Medium Pages: 2 / 32M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.502Z] [1.292s][info ][gc,reloc ] GC(2) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.502Z] [1.292s][info ][gc,reloc ] GC(2) Forwarding Usage: 0M
[2025-12-17T23:33:57.502Z] [1.292s][info ][gc,heap ] GC(2) Min Capacity: 128M(25%)
[2025-12-17T23:33:57.502Z] [1.292s][info ][gc,heap ] GC(2) Max Capacity: 512M(100%)
[2025-12-17T23:33:57.502Z] [1.292s][info ][gc,heap ] GC(2) Soft Max Capacity: 512M(100%)
[2025-12-17T23:33:57.502Z] [1.292s][info ][gc,heap ] GC(2) Mark Start Mark End Relocate Start Relocate End High Low
[2025-12-17T23:33:57.502Z] [1.292s][info ][gc,heap ] GC(2) Capacity: 226M (44%) 242M (47%) 242M (47%) 258M (50%) 258M (50%) 226M (44%)
[2025-12-17T23:33:57.502Z] [1.293s][info ][gc,heap ] GC(2) Free: 286M (56%) 270M (53%) 270M (53%) 288M (56%) 288M (56%) 254M (50%)
[2025-12-17T23:33:57.502Z] [1.293s][info ][gc,heap ] GC(2) Used: 226M (44%) 242M (47%) 242M (47%) 224M (44%) 258M (50%) 224M (44%)
[2025-12-17T23:33:57.502Z] [1.293s][info ][gc,heap ] GC(2) Live: - 188M (37%) 188M (37%) 188M (37%) - -
[2025-12-17T23:33:57.502Z] [1.293s][info ][gc,heap ] GC(2) Allocated: - 16M (3%) 16M (3%) 16M (3%) - -
[2025-12-17T23:33:57.502Z] [1.293s][info ][gc,heap ] GC(2) Garbage: - 37M (7%) 37M (7%) 19M (4%) - -
[2025-12-17T23:33:57.502Z] [1.293s][info ][gc,heap ] GC(2) Reclaimed: - - 0M (0%) 18M (4%) - -
[2025-12-17T23:33:57.502Z] [1.294s][info ][gc ] GC(2) Garbage Collection (Warmup) 226M(44%)->224M(44%)
[2025-12-17T23:33:57.502Z] [1.294s][debug][gc,heap ] GC(3) Heap before GC invocations=3 (full 3):
[2025-12-17T23:33:57.502Z] [1.294s][debug][gc,heap ] GC(3) ZHeap used 224M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.502Z] [1.294s][debug][gc,heap ] GC(3) Metaspace used 558K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.502Z] [1.294s][debug][gc,heap ] GC(3) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.502Z] [1.294s][info ][gc,start ] GC(3) Garbage Collection (System.gc())
[2025-12-17T23:33:57.502Z] [1.295s][info ][gc,task ] GC(3) Using 2 workers
[2025-12-17T23:33:57.502Z] [1.295s][info ][gc,phases ] GC(3) Pause Mark Start 0.061ms
[2025-12-17T23:33:57.502Z] [1.299s][info ][gc,phases ] GC(3) Concurrent Mark 3.413ms
[2025-12-17T23:33:57.502Z] [1.300s][info ][gc,phases ] GC(3) Pause Mark End 0.061ms
[2025-12-17T23:33:57.502Z] [1.300s][info ][gc,phases ] GC(3) Concurrent Mark Free 0.015ms
[2025-12-17T23:33:57.502Z] [1.301s][info ][gc,phases ] GC(3) Concurrent Process Non-Strong References 1.185ms
[2025-12-17T23:33:57.502Z] [1.301s][info ][gc,phases ] GC(3) Concurrent Reset Relocation Set 0.012ms
[2025-12-17T23:33:57.502Z] [1.306s][info ][gc,phases ] GC(3) Concurrent Select Relocation Set 4.474ms
[2025-12-17T23:33:57.502Z] [1.306s][info ][gc,phases ] GC(3) Pause Relocate Start 0.058ms
[2025-12-17T23:33:57.502Z] [1.308s][info ][gc,phases ] GC(3) Concurrent Relocate 1.476ms
[2025-12-17T23:33:57.502Z] [1.308s][debug][gc,heap ] GC(3) Heap after GC invocations=4 (full 4):
[2025-12-17T23:33:57.502Z] [1.308s][debug][gc,heap ] GC(3) ZHeap used 4M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.502Z] [1.308s][debug][gc,heap ] GC(3) Metaspace used 558K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.502Z] [1.308s][debug][gc,heap ] GC(3) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.502Z] [1.308s][info ][gc,load ] GC(3) Load: 2.39/3.00/3.91
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,mmu ] GC(3) MMU: 2ms/95.1%, 5ms/97.6%, 10ms/98.7%, 20ms/99.1%, 50ms/99.5%, 100ms/99.7%
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,marking ] GC(3) Mark: 2 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s)
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,marking ] GC(3) Mark Stack Usage: 32M
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,nmethod ] GC(3) NMethods: 274 registered, 0 unregistered
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,metaspace] GC(3) Metaspace: 0M used, 0M committed, 1088M reserved
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,ref ] GC(3) Soft: 98 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,ref ] GC(3) Weak: 213 encountered, 15 discovered, 0 enqueued
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,ref ] GC(3) Final: 0 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.502Z] [1.309s][info ][gc,ref ] GC(3) Phantom: 8 encountered, 5 discovered, 0 enqueued
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,reloc ] GC(3) Small Pages: 104 / 208M, Empty: 198M, Relocated: 1M, In-Place: 0
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,reloc ] GC(3) Medium Pages: 1 / 16M, Empty: 16M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,reloc ] GC(3) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,reloc ] GC(3) Forwarding Usage: 0M
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,heap ] GC(3) Min Capacity: 128M(25%)
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,heap ] GC(3) Max Capacity: 512M(100%)
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,heap ] GC(3) Soft Max Capacity: 512M(100%)
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,heap ] GC(3) Mark Start Mark End Relocate Start Relocate End High Low
[2025-12-17T23:33:57.502Z] [1.310s][info ][gc,heap ] GC(3) Capacity: 258M (50%) 258M (50%) 258M (50%) 258M (50%) 258M (50%) 258M (50%)
[2025-12-17T23:33:57.502Z] [1.311s][info ][gc,heap ] GC(3) Free: 288M (56%) 288M (56%) 502M (98%) 508M (99%) 508M (99%) 288M (56%)
[2025-12-17T23:33:57.502Z] [1.311s][info ][gc,heap ] GC(3) Used: 224M (44%) 224M (44%) 10M (2%) 4M (1%) 224M (44%) 4M (1%)
[2025-12-17T23:33:57.502Z] [1.311s][info ][gc,heap ] GC(3) Live: - 1M (0%) 1M (0%) 1M (0%) - -
[2025-12-17T23:33:57.502Z] [1.311s][info ][gc,heap ] GC(3) Allocated: - 0M (0%) 0M (0%) 0M (0%) - -
[2025-12-17T23:33:57.502Z] [1.311s][info ][gc,heap ] GC(3) Garbage: - 222M (44%) 8M (2%) 2M (1%) - -
[2025-12-17T23:33:57.503Z] [1.311s][info ][gc,heap ] GC(3) Reclaimed: - - 214M (42%) 220M (43%) - -
[2025-12-17T23:33:57.503Z] [1.311s][info ][gc ] GC(3) Garbage Collection (System.gc()) 224M(44%)->4M(1%)
[2025-12-17T23:33:57.503Z] [0.855s] (MainThread) Waiting for uncommit to start
[2025-12-17T23:33:57.503Z] [301.342s][debug][gc,heap ] GC(4) Heap before GC invocations=4 (full 4):
[2025-12-17T23:33:57.503Z] [301.342s][debug][gc,heap ] GC(4) ZHeap used 6M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.503Z] [301.342s][debug][gc,heap ] GC(4) Metaspace used 559K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.503Z] [301.342s][debug][gc,heap ] GC(4) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.503Z] [301.342s][info ][gc,start ] GC(4) Garbage Collection (Proactive)
[2025-12-17T23:33:57.503Z] [301.343s][info ][gc,task ] GC(4) Using 2 workers
[2025-12-17T23:33:57.503Z] [301.343s][info ][gc,phases ] GC(4) Pause Mark Start 0.118ms
[2025-12-17T23:33:57.503Z] [301.347s][info ][gc,phases ] GC(4) Concurrent Mark 4.201ms
[2025-12-17T23:33:57.503Z] [301.347s][info ][gc,phases ] GC(4) Pause Mark End 0.060ms
[2025-12-17T23:33:57.503Z] [301.347s][info ][gc,phases ] GC(4) Concurrent Mark Free 0.006ms
[2025-12-17T23:33:57.503Z] [301.348s][info ][gc,phases ] GC(4) Concurrent Process Non-Strong References 0.942ms
[2025-12-17T23:33:57.503Z] [301.348s][info ][gc,phases ] GC(4) Concurrent Reset Relocation Set 0.007ms
[2025-12-17T23:33:57.503Z] [301.350s][info ][gc,phases ] GC(4) Concurrent Select Relocation Set 1.183ms
[2025-12-17T23:33:57.503Z] [301.350s][info ][gc,phases ] GC(4) Pause Relocate Start 0.073ms
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,phases ] GC(4) Concurrent Relocate 2.312ms
[2025-12-17T23:33:57.503Z] [301.352s][debug][gc,heap ] GC(4) Heap after GC invocations=5 (full 5):
[2025-12-17T23:33:57.503Z] [301.352s][debug][gc,heap ] GC(4) ZHeap used 4M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.503Z] [301.352s][debug][gc,heap ] GC(4) Metaspace used 559K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.503Z] [301.352s][debug][gc,heap ] GC(4) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,load ] GC(4) Load: 2.00/2.37/3.37
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,mmu ] GC(4) MMU: 2ms/94.1%, 5ms/96.4%, 10ms/97.5%, 20ms/98.7%, 50ms/99.5%, 100ms/99.7%
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,marking ] GC(4) Mark: 2 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s)
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,marking ] GC(4) Mark Stack Usage: 32M
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,nmethod ] GC(4) NMethods: 278 registered, 0 unregistered
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,metaspace] GC(4) Metaspace: 0M used, 0M committed, 1088M reserved
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,ref ] GC(4) Soft: 98 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.503Z] [301.352s][info ][gc,ref ] GC(4) Weak: 213 encountered, 69 discovered, 0 enqueued
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,ref ] GC(4) Final: 0 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,ref ] GC(4) Phantom: 8 encountered, 5 discovered, 0 enqueued
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,reloc ] GC(4) Small Pages: 3 / 6M, Empty: 0M, Relocated: 1M, In-Place: 0
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,reloc ] GC(4) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,reloc ] GC(4) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,reloc ] GC(4) Forwarding Usage: 0M
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Min Capacity: 128M(25%)
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Max Capacity: 512M(100%)
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Soft Max Capacity: 512M(100%)
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Mark Start Mark End Relocate Start Relocate End High Low
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Capacity: 258M (50%) 258M (50%) 258M (50%) 258M (50%) 258M (50%) 258M (50%)
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Free: 506M (99%) 506M (99%) 506M (99%) 508M (99%) 508M (99%) 502M (98%)
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Used: 6M (1%) 6M (1%) 6M (1%) 4M (1%) 10M (2%) 4M (1%)
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Live: - 1M (0%) 1M (0%) 1M (0%) - -
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Allocated: - 0M (0%) 0M (0%) 0M (0%) - -
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Garbage: - 4M (1%) 4M (1%) 2M (1%) - -
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc,heap ] GC(4) Reclaimed: - - 0M (0%) 2M (0%) - -
[2025-12-17T23:33:57.503Z] [301.353s][info ][gc ] GC(4) Garbage Collection (Proactive) 6M(1%)->4M(1%)
[2025-12-17T23:33:57.503Z] [601.442s][debug][gc,heap ] GC(5) Heap before GC invocations=5 (full 5):
[2025-12-17T23:33:57.503Z] [601.443s][debug][gc,heap ] GC(5) ZHeap used 4M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.503Z] [601.443s][debug][gc,heap ] GC(5) Metaspace used 559K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.503Z] [601.443s][debug][gc,heap ] GC(5) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.503Z] [601.443s][info ][gc,start ] GC(5) Garbage Collection (Proactive)
[2025-12-17T23:33:57.503Z] [601.443s][info ][gc,task ] GC(5) Using 2 workers
[2025-12-17T23:33:57.503Z] [601.443s][info ][gc,phases ] GC(5) Pause Mark Start 0.227ms
[2025-12-17T23:33:57.503Z] [601.450s][info ][gc,phases ] GC(5) Concurrent Mark 6.019ms
[2025-12-17T23:33:57.503Z] [601.450s][info ][gc,phases ] GC(5) Pause Mark End 0.136ms
[2025-12-17T23:33:57.503Z] [601.450s][info ][gc,phases ] GC(5) Concurrent Mark Free 0.021ms
[2025-12-17T23:33:57.503Z] [601.452s][info ][gc,phases ] GC(5) Concurrent Process Non-Strong References 1.552ms
[2025-12-17T23:33:57.503Z] [601.452s][info ][gc,phases ] GC(5) Concurrent Reset Relocation Set 0.017ms
[2025-12-17T23:33:57.503Z] [601.456s][info ][gc,phases ] GC(5) Concurrent Select Relocation Set 3.577ms
[2025-12-17T23:33:57.503Z] [601.456s][info ][gc,phases ] GC(5) Pause Relocate Start 0.126ms
[2025-12-17T23:33:57.503Z] [601.458s][info ][gc,phases ] GC(5) Concurrent Relocate 1.810ms
[2025-12-17T23:33:57.503Z] [601.458s][debug][gc,heap ] GC(5) Heap after GC invocations=6 (full 6):
[2025-12-17T23:33:57.503Z] [601.459s][debug][gc,heap ] GC(5) ZHeap used 4M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.503Z] [601.459s][debug][gc,heap ] GC(5) Metaspace used 559K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.503Z] [601.459s][debug][gc,heap ] GC(5) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.503Z] [601.459s][info ][gc,load ] GC(5) Load: 2.03/2.14/2.99
[2025-12-17T23:33:57.503Z] [601.459s][info ][gc,mmu ] GC(5) MMU: 2ms/88.6%, 5ms/95.5%, 10ms/96.4%, 20ms/97.6%, 50ms/99.0%, 100ms/99.5%
[2025-12-17T23:33:57.503Z] [601.460s][info ][gc,marking ] GC(5) Mark: 2 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s)
[2025-12-17T23:33:57.503Z] [601.460s][info ][gc,marking ] GC(5) Mark Stack Usage: 32M
[2025-12-17T23:33:57.503Z] [601.460s][info ][gc,nmethod ] GC(5) NMethods: 278 registered, 0 unregistered
[2025-12-17T23:33:57.503Z] [601.460s][info ][gc,metaspace] GC(5) Metaspace: 0M used, 0M committed, 1088M reserved
[2025-12-17T23:33:57.503Z] [601.460s][info ][gc,ref ] GC(5) Soft: 98 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.503Z] [601.460s][info ][gc,ref ] GC(5) Weak: 213 encountered, 165 discovered, 0 enqueued
[2025-12-17T23:33:57.503Z] [601.461s][info ][gc,ref ] GC(5) Final: 0 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.503Z] [601.461s][info ][gc,ref ] GC(5) Phantom: 8 encountered, 5 discovered, 0 enqueued
[2025-12-17T23:33:57.503Z] [601.461s][info ][gc,reloc ] GC(5) Small Pages: 2 / 4M, Empty: 0M, Relocated: 1M, In-Place: 0
[2025-12-17T23:33:57.503Z] [601.461s][info ][gc,reloc ] GC(5) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.503Z] [601.461s][info ][gc,reloc ] GC(5) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.503Z] [601.461s][info ][gc,reloc ] GC(5) Forwarding Usage: 0M
[2025-12-17T23:33:57.503Z] [601.462s][info ][gc,heap ] GC(5) Min Capacity: 128M(25%)
[2025-12-17T23:33:57.503Z] [601.462s][info ][gc,heap ] GC(5) Max Capacity: 512M(100%)
[2025-12-17T23:33:57.503Z] [601.462s][info ][gc,heap ] GC(5) Soft Max Capacity: 512M(100%)
[2025-12-17T23:33:57.503Z] [601.462s][info ][gc,heap ] GC(5) Mark Start Mark End Relocate Start Relocate End High Low
[2025-12-17T23:33:57.503Z] [601.462s][info ][gc,heap ] GC(5) Capacity: 258M (50%) 258M (50%) 258M (50%) 258M (50%) 258M (50%) 258M (50%)
[2025-12-17T23:33:57.504Z] [601.463s][info ][gc,heap ] GC(5) Free: 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) 504M (98%)
[2025-12-17T23:33:57.504Z] [601.463s][info ][gc,heap ] GC(5) Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) 8M (2%) 4M (1%)
[2025-12-17T23:33:57.504Z] [601.463s][info ][gc,heap ] GC(5) Live: - 1M (0%) 1M (0%) 1M (0%) - -
[2025-12-17T23:33:57.504Z] [601.463s][info ][gc,heap ] GC(5) Allocated: - 0M (0%) 0M (0%) 0M (0%) - -
[2025-12-17T23:33:57.504Z] [601.463s][info ][gc,heap ] GC(5) Garbage: - 2M (1%) 2M (1%) 2M (1%) - -
[2025-12-17T23:33:57.504Z] [601.463s][info ][gc,heap ] GC(5) Reclaimed: - - 0M (0%) 0M (0%) - -
[2025-12-17T23:33:57.504Z] [601.464s][info ][gc ] GC(5) Garbage Collection (Proactive) 4M(1%)->4M(1%)
[2025-12-17T23:33:57.504Z] [901.543s][debug][gc,heap ] GC(6) Heap before GC invocations=6 (full 6):
[2025-12-17T23:33:57.504Z] [901.543s][debug][gc,heap ] GC(6) ZHeap used 4M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.504Z] [901.543s][debug][gc,heap ] GC(6) Metaspace used 559K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.504Z] [901.543s][debug][gc,heap ] GC(6) class space used 42K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.504Z] [901.543s][info ][gc,start ] GC(6) Garbage Collection (Proactive)
[2025-12-17T23:33:57.504Z] [901.543s][info ][gc,task ] GC(6) Using 2 workers
[2025-12-17T23:33:57.504Z] [901.543s][info ][gc,phases ] GC(6) Pause Mark Start 0.203ms
[2025-12-17T23:33:57.504Z] [901.549s][info ][gc,phases ] GC(6) Concurrent Mark 4.895ms
[2025-12-17T23:33:57.504Z] [901.549s][info ][gc,phases ] GC(6) Pause Mark End 0.141ms
[2025-12-17T23:33:57.504Z] [901.549s][info ][gc,phases ] GC(6) Concurrent Mark Free 0.007ms
[2025-12-17T23:33:57.504Z] [901.551s][info ][gc,phases ] GC(6) Concurrent Process Non-Strong References 2.164ms
[2025-12-17T23:33:57.504Z] [901.551s][info ][gc,phases ] GC(6) Concurrent Reset Relocation Set 0.006ms
[2025-12-17T23:33:57.504Z] [901.553s][info ][gc,phases ] GC(6) Concurrent Select Relocation Set 1.993ms
[2025-12-17T23:33:57.504Z] [901.554s][info ][gc,phases ] GC(6) Pause Relocate Start 0.166ms
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,phases ] GC(6) Concurrent Relocate 4.651ms
[2025-12-17T23:33:57.504Z] [901.559s][debug][gc,heap ] GC(6) Heap after GC invocations=7 (full 7):
[2025-12-17T23:33:57.504Z] [901.559s][debug][gc,heap ] GC(6) ZHeap used 4M, capacity 258M, max capacity 512M
[2025-12-17T23:33:57.504Z] [901.559s][debug][gc,heap ] GC(6) Metaspace used 537K, committed 704K, reserved 1114112K
[2025-12-17T23:33:57.504Z] [901.559s][debug][gc,heap ] GC(6) class space used 36K, committed 128K, reserved 1048576K
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,load ] GC(6) Load: 2.00/2.04/2.70
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,mmu ] GC(6) MMU: 2ms/88.6%, 5ms/93.9%, 10ms/96.4%, 20ms/97.4%, 50ms/99.0%, 100ms/99.5%
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,marking ] GC(6) Mark: 2 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s)
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,marking ] GC(6) Mark Stack Usage: 32M
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,nmethod ] GC(6) NMethods: 278 registered, 0 unregistered
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,metaspace] GC(6) Metaspace: 0M used, 0M committed, 1088M reserved
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,ref ] GC(6) Soft: 85 encountered, 53 discovered, 13 enqueued
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,ref ] GC(6) Weak: 213 encountered, 71 discovered, 6 enqueued
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,ref ] GC(6) Final: 0 encountered, 0 discovered, 0 enqueued
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,ref ] GC(6) Phantom: 8 encountered, 5 discovered, 0 enqueued
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,reloc ] GC(6) Small Pages: 2 / 4M, Empty: 0M, Relocated: 1M, In-Place: 0
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,reloc ] GC(6) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,reloc ] GC(6) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,reloc ] GC(6) Forwarding Usage: 0M
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Min Capacity: 128M(25%)
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Max Capacity: 512M(100%)
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Soft Max Capacity: 512M(100%)
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Mark Start Mark End Relocate Start Relocate End High Low
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Capacity: 258M (50%) 258M (50%) 258M (50%) 258M (50%) 258M (50%) 258M (50%)
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Free: 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) 504M (98%)
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) 8M (2%) 4M (1%)
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Live: - 1M (0%) 1M (0%) 1M (0%) - -
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Allocated: - 0M (0%) 0M (0%) 0M (0%) - -
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Garbage: - 2M (1%) 2M (1%) 2M (1%) - -
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc,heap ] GC(6) Reclaimed: - - 0M (0%) 0M (0%) - -
[2025-12-17T23:33:57.504Z] [901.559s][info ][gc ] GC(6) Garbage Collection (Proactive) 4M(1%)->4M(1%)
[2025-12-17T23:33:57.504Z] Timeout signalled after 960 seconds
[2025-12-17T23:33:57.504Z] STDERR:
[2025-12-17T23:33:57.504Z] rerun:
[2025-12-17T23:33:57.504Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/scratch/2 && \
[2025-12-17T23:33:57.504Z] DISPLAY=:0 \
[2025-12-17T23:33:57.504Z] HOME=/home/jenkins \
[2025-12-17T23:33:57.504Z] LANG=en_US.UTF-8 \
[2025-12-17T23:33:57.504Z] PATH=/bin:/usr/bin:/usr/sbin \
[2025-12-17T23:33:57.504Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar \
[2025-12-17T23:33:57.504Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/java \
[2025-12-17T23:33:57.504Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \
[2025-12-17T23:33:57.504Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \
[2025-12-17T23:33:57.504Z] -Dtest.compiler.opts= \
[2025-12-17T23:33:57.504Z] -Dtest.java.opts= \
[2025-12-17T23:33:57.504Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image \
[2025-12-17T23:33:57.504Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image \
[2025-12-17T23:33:57.504Z] -Dtest.timeout.factor=8.0 \
[2025-12-17T23:33:57.504Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
[2025-12-17T23:33:57.504Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \
[2025-12-17T23:33:57.504Z] -Dtest.name=gc/z/TestUncommit.java \
[2025-12-17T23:33:57.504Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java \
[2025-12-17T23:33:57.504Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \
[2025-12-17T23:33:57.505Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \
[2025-12-17T23:33:57.505Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d \
[2025-12-17T23:33:57.505Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/test/lib \
[2025-12-17T23:33:57.505Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/classes/2/test/lib \
[2025-12-17T23:33:57.505Z] -ea \
[2025-12-17T23:33:57.505Z] -esa \
[2025-12-17T23:33:57.505Z] -Xmx512m \
[2025-12-17T23:33:57.505Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
[2025-12-17T23:33:57.507Z] -XX:+UseZGC \
[2025-12-17T23:33:57.507Z] -Xlog:gc*,gc+heap=debug,gc+stats=off \
[2025-12-17T23:33:57.507Z] -Xms128M \
[2025-12-17T23:33:57.507Z] -Xmx512M \
[2025-12-17T23:33:57.507Z] -XX:ZUncommitDelay=10 \
[2025-12-17T23:33:57.507Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work/gc/z/TestUncommit.d/main.0.jta
[2025-12-17T23:33:57.507Z]
[2025-12-17T23:33:57.507Z] TEST RESULT: Error. Program `/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960418ms).
[2025-12-17T23:33:57.507Z] --------------------------------------------------
[2025-12-17T23:40:37.244Z] Test results: passed: 491; error: 1
[2025-12-17T23:40:43.414Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/report/html/report.html
[2025-12-17T23:40:43.414Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17660084918440/hotspot_gc_0/work
[2025-12-17T23:40:43.414Z] Error: Some tests failed or other problems occurred.
[2025-12-17T23:40:43.414Z] -----------------------------------
[2025-12-17T23:40:43.414Z] hotspot_gc_0_FAILED
[2025-12-17T23:40:43.414Z] -----------------------------------
[2025-12-17T23:40:43.414Z]
[2025-12-17T23:40:43.414Z] TEST TEARDOWN:
[2025-12-17T23:40:43.414Z] Nothing to be done for teardown.
[2025-12-17T23:40:43.414Z] hotspot_gc_0 Finish Time: Wed Dec 17 17:39:49 2025 Epoch Time (ms): 1766014789643