[2025-05-16T22:14:50.945Z] Running test hotspot_tier1_gc_0 ... [2025-05-16T22:14:50.945Z] =============================================== [2025-05-16T22:14:50.945Z] hotspot_tier1_gc_0 Start Time: Fri May 16 17:14:49 2025 Epoch Time (ms): 1747433689330 [2025-05-16T22:14:50.945Z] variation: NoOptions [2025-05-16T22:14:50.945Z] JVM_OPTIONS: [2025-05-16T22:14:50.945Z] { \ [2025-05-16T22:14:50.945Z] echo ""; echo "TEST SETUP:"; \ [2025-05-16T22:14:50.945Z] echo "Nothing to be done for setup."; \ [2025-05-16T22:14:50.945Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17474330621237/hotspot_tier1_gc_0"; \ [2025-05-16T22:14:50.945Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17474330621237/hotspot_tier1_gc_0"; \ [2025-05-16T22:14:50.945Z] echo ""; echo "TESTING:"; \ [2025-05-16T22:14:50.945Z] "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-05-16T22:14:50.945Z] -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_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2025-05-16T22:14:50.945Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17474330621237/hotspot_tier1_gc_0"/work" \ [2025-05-16T22:14:50.945Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17474330621237/hotspot_tier1_gc_0"/report" \ [2025-05-16T22:14:50.945Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image" \ [2025-05-16T22:14:50.945Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2025-05-16T22:14:50.946Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2025-05-16T22:14:50.946Z] \ [2025-05-16T22:14:50.946Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2025-05-16T22:14:50.946Z] "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:tier1_gc"; \ [2025-05-16T22:14:50.946Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_tier1_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_tier1_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-16T22:14:50.946Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-16T22:14:50.946Z] echo "Nothing to be done for teardown."; \ [2025-05-16T22:14:50.946Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17474330621237/TestTargetResult"; [2025-05-16T22:14:50.946Z] [2025-05-16T22:14:50.946Z] TEST SETUP: [2025-05-16T22:14:50.946Z] Nothing to be done for setup. [2025-05-16T22:14:50.946Z] [2025-05-16T22:14:50.946Z] TESTING: [2025-05-16T22:14:50.946Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17474330621237/hotspot_tier1_gc_0/work" not found: creating [2025-05-16T22:14:50.946Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17474330621237/hotspot_tier1_gc_0/report" not found: creating [2025-05-16T22:14:57.288Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work [2025-05-16T22:40:09.247Z] -------------------------------------------------- [2025-05-16T22:40:09.247Z] TEST: gc/z/TestUncommit.java [2025-05-16T22:40:09.247Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image [2025-05-16T22:40:09.247Z] [2025-05-16T22:40:09.247Z] ACTION: build -- Passed. Build successful [2025-05-16T22:40:09.247Z] REASON: Named class compiled on demand [2025-05-16T22:40:09.247Z] TIME: 0.148 seconds [2025-05-16T22:40:09.247Z] messages: [2025-05-16T22:40:09.247Z] command: build gc.z.TestUncommit [2025-05-16T22:40:09.247Z] reason: Named class compiled on demand [2025-05-16T22:40:09.247Z] started: Fri May 16 17:24:05 CDT 2025 [2025-05-16T22:40:09.247Z] Test directory: [2025-05-16T22:40:09.247Z] compile: gc.z.TestUncommit [2025-05-16T22:40:09.247Z] finished: Fri May 16 17:24:05 CDT 2025 [2025-05-16T22:40:09.247Z] elapsed time (seconds): 0.148 [2025-05-16T22:40:09.247Z] [2025-05-16T22:40:09.247Z] ACTION: compile -- Passed. Compilation successful [2025-05-16T22:40:09.247Z] REASON: .class file out of date or does not exist [2025-05-16T22:40:09.247Z] TIME: 0.147 seconds [2025-05-16T22:40:09.247Z] messages: [2025-05-16T22:40:09.247Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java [2025-05-16T22:40:09.247Z] reason: .class file out of date or does not exist [2025-05-16T22:40:09.247Z] started: Fri May 16 17:24:05 CDT 2025 [2025-05-16T22:40:09.247Z] Mode: agentvm [2025-05-16T22:40:09.247Z] Agent id: 3 [2025-05-16T22:40:09.247Z] finished: Fri May 16 17:24:05 CDT 2025 [2025-05-16T22:40:09.247Z] elapsed time (seconds): 0.147 [2025-05-16T22:40:09.247Z] configuration: [2025-05-16T22:40:09.247Z] Boot Layer (javac runtime environment) [2025-05-16T22:40:09.247Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-05-16T22:40:09.247Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-05-16T22:40:09.247Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/patches/java.base [2025-05-16T22:40:09.247Z] [2025-05-16T22:40:09.247Z] javac compilation environment [2025-05-16T22:40:09.248Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z [2025-05-16T22:40:09.248Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-05-16T22:40:09.248Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z [2025-05-16T22:40:09.248Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d [2025-05-16T22:40:09.248Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/test/lib [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] rerun: [2025-05-16T22:40:09.248Z] cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/scratch/2 && \ [2025-05-16T22:40:09.248Z] DISPLAY=:0 \ [2025-05-16T22:40:09.248Z] HOME=/home/jenkins \ [2025-05-16T22:40:09.248Z] LANG=en_US.UTF-8 \ [2025-05-16T22:40:09.248Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-05-16T22:40:09.248Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/javac \ [2025-05-16T22:40:09.248Z] -J-ea \ [2025-05-16T22:40:09.248Z] -J-esa \ [2025-05-16T22:40:09.248Z] -J-Xmx512m \ [2025-05-16T22:40:09.248Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-05-16T22:40:09.248Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-05-16T22:40:09.248Z] -J-Dtest.compiler.opts= \ [2025-05-16T22:40:09.248Z] -J-Dtest.java.opts= \ [2025-05-16T22:40:09.248Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image \ [2025-05-16T22:40:09.248Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image \ [2025-05-16T22:40:09.248Z] -J-Dtest.timeout.factor=8.0 \ [2025-05-16T22:40:09.248Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-05-16T22:40:09.248Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-05-16T22:40:09.248Z] -J-Dtest.name=gc/z/TestUncommit.java \ [2025-05-16T22:40:09.248Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java \ [2025-05-16T22:40:09.248Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \ [2025-05-16T22:40:09.248Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-05-16T22:40:09.248Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d \ [2025-05-16T22:40:09.248Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/test/lib \ [2025-05-16T22:40:09.248Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/test/lib \ [2025-05-16T22:40:09.248Z] -d /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d \ [2025-05-16T22:40:09.248Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-05-16T22:40:09.248Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/test/lib /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] ACTION: main -- Error. Program `/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960412ms). [2025-05-16T22:40:09.248Z] 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-05-16T22:40:09.248Z] TIME: 960.43 seconds [2025-05-16T22:40:09.248Z] messages: [2025-05-16T22:40:09.248Z] command: main -XX:+UseZGC -Xlog:gc*,gc+heap=debug,gc+stats=off -Xms128M -Xmx512M -XX:ZUncommitDelay=10 gc.z.TestUncommit [2025-05-16T22:40:09.248Z] 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-05-16T22:40:09.248Z] started: Fri May 16 17:24:05 CDT 2025 [2025-05-16T22:40:09.248Z] Mode: othervm [/othervm specified] [2025-05-16T22:40:09.248Z] Timeout information: [2025-05-16T22:40:09.248Z] Running jstack on process 19237 [2025-05-16T22:40:09.248Z] 2025-05-16 17:40:05 [2025-05-16T22:40:09.248Z] Full thread dump OpenJDK 64-Bit Server VM (17.0.16-beta+3-ea mixed mode, sharing): [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] Threads class SMR info: [2025-05-16T22:40:09.248Z] _java_thread_list=0x00007fe8bc001dd0, length=13, elements={ [2025-05-16T22:40:09.248Z] 0x00007feb6802ea40, 0x00007feb68090840, 0x00007feb68091e80, 0x00007feb68098da0, [2025-05-16T22:40:09.248Z] 0x00007feb6809a2c0, 0x00007feb6809b8e0, 0x00007feb6809d4e0, 0x00007feb6809ec40, [2025-05-16T22:40:09.248Z] 0x00007feb680a82e0, 0x00007feb680f4880, 0x00007feb680f87d0, 0x00007feb68126ef0, [2025-05-16T22:40:09.248Z] 0x00007fe8bc000bd0 [2025-05-16T22:40:09.248Z] } [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "main" #1 prio=5 os_prio=0 cpu=312.30ms elapsed=960.35s tid=0x00007feb6802ea40 nid=0x4b28 in Object.wait() [0x00007feb6e4c2000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: WAITING (on object monitor) [2025-05-16T22:40:09.248Z] at java.lang.Object.wait(java.base@17.0.16-beta/Native Method) [2025-05-16T22:40:09.248Z] - waiting on <0x000010000ce00000> (a java.lang.Thread) [2025-05-16T22:40:09.248Z] at java.lang.Thread.join(java.base@17.0.16-beta/Thread.java:1313) [2025-05-16T22:40:09.248Z] - locked <0x000010000ce00000> (a java.lang.Thread) [2025-05-16T22:40:09.248Z] at java.lang.Thread.join(java.base@17.0.16-beta/Thread.java:1381) [2025-05-16T22:40:09.248Z] at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:85) [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Reference Handler" #2 daemon prio=10 os_prio=0 cpu=1.19ms elapsed=960.10s tid=0x00007feb68090840 nid=0x4b36 waiting on condition [0x00007feb50248000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] at java.lang.ref.Reference.waitForReferencePendingList(java.base@17.0.16-beta/Native Method) [2025-05-16T22:40:09.248Z] at java.lang.ref.Reference.processPendingReferences(java.base@17.0.16-beta/Reference.java:253) [2025-05-16T22:40:09.248Z] at java.lang.ref.Reference$ReferenceHandler.run(java.base@17.0.16-beta/Reference.java:215) [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Finalizer" #3 daemon prio=8 os_prio=0 cpu=1.64ms elapsed=960.10s tid=0x00007feb68091e80 nid=0x4b37 in Object.wait() [0x00007feb50147000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: WAITING (on object monitor) [2025-05-16T22:40:09.248Z] at java.lang.Object.wait(java.base@17.0.16-beta/Native Method) [2025-05-16T22:40:09.248Z] - waiting on <0x000010000d013208> (a java.lang.ref.ReferenceQueue$Lock) [2025-05-16T22:40:09.248Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.16-beta/ReferenceQueue.java:155) [2025-05-16T22:40:09.248Z] - locked <0x000010000d013208> (a java.lang.ref.ReferenceQueue$Lock) [2025-05-16T22:40:09.248Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.16-beta/ReferenceQueue.java:176) [2025-05-16T22:40:09.248Z] at java.lang.ref.Finalizer$FinalizerThread.run(java.base@17.0.16-beta/Finalizer.java:172) [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Signal Dispatcher" #4 daemon prio=9 os_prio=0 cpu=1.80ms elapsed=960.09s tid=0x00007feb68098da0 nid=0x4b38 waiting on condition [0x0000000000000000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Service Thread" #5 daemon prio=9 os_prio=0 cpu=0.87ms elapsed=960.09s tid=0x00007feb6809a2c0 nid=0x4b39 runnable [0x0000000000000000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Monitor Deflation Thread" #6 daemon prio=9 os_prio=0 cpu=322.89ms elapsed=960.09s tid=0x00007feb6809b8e0 nid=0x4b3a runnable [0x0000000000000000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "C2 CompilerThread0" #7 daemon prio=9 os_prio=0 cpu=60.35ms elapsed=960.09s tid=0x00007feb6809d4e0 nid=0x4b3b waiting on condition [0x0000000000000000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] No compile task [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "C1 CompilerThread0" #10 daemon prio=9 os_prio=0 cpu=95.62ms elapsed=960.09s tid=0x00007feb6809ec40 nid=0x4b3c waiting on condition [0x0000000000000000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] No compile task [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Sweeper thread" #11 daemon prio=9 os_prio=0 cpu=0.63ms elapsed=960.09s tid=0x00007feb680a82e0 nid=0x4b3d runnable [0x0000000000000000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Notification Thread" #12 daemon prio=9 os_prio=0 cpu=0.26ms elapsed=960.06s tid=0x00007feb680f4880 nid=0x4b3e runnable [0x0000000000000000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Common-Cleaner" #13 daemon prio=8 os_prio=0 cpu=2.55ms elapsed=960.06s tid=0x00007feb680f87d0 nid=0x4b40 in Object.wait() [0x00007feb417f6000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: TIMED_WAITING (on object monitor) [2025-05-16T22:40:09.248Z] at java.lang.Object.wait(java.base@17.0.16-beta/Native Method) [2025-05-16T22:40:09.248Z] - waiting on <0x000010000d099598> (a java.lang.ref.ReferenceQueue$Lock) [2025-05-16T22:40:09.248Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.16-beta/ReferenceQueue.java:155) [2025-05-16T22:40:09.248Z] - locked <0x000010000d099598> (a java.lang.ref.ReferenceQueue$Lock) [2025-05-16T22:40:09.248Z] at jdk.internal.ref.CleanerImpl.run(java.base@17.0.16-beta/CleanerImpl.java:140) [2025-05-16T22:40:09.248Z] at java.lang.Thread.run(java.base@17.0.16-beta/Thread.java:840) [2025-05-16T22:40:09.248Z] at jdk.internal.misc.InnocuousThread.run(java.base@17.0.16-beta/InnocuousThread.java:162) [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "MainThread" #14 prio=5 os_prio=0 cpu=669.67ms elapsed=960.03s tid=0x00007feb68126ef0 nid=0x4b41 waiting on condition [0x00007feb416d7000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: TIMED_WAITING (sleeping) [2025-05-16T22:40:09.248Z] at java.lang.Thread.sleep(java.base@17.0.16-beta/Native Method) [2025-05-16T22:40:09.248Z] at gc.z.TestUncommit.test(TestUncommit.java:85) [2025-05-16T22:40:09.248Z] at gc.z.TestUncommit.main(TestUncommit.java:131) [2025-05-16T22:40:09.248Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@17.0.16-beta/Native Method) [2025-05-16T22:40:09.248Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@17.0.16-beta/NativeMethodAccessorImpl.java:77) [2025-05-16T22:40:09.248Z] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@17.0.16-beta/DelegatingMethodAccessorImpl.java:43) [2025-05-16T22:40:09.248Z] at java.lang.reflect.Method.invoke(java.base@17.0.16-beta/Method.java:569) [2025-05-16T22:40:09.248Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2025-05-16T22:40:09.248Z] at java.lang.Thread.run(java.base@17.0.16-beta/Thread.java:840) [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "Attach Listener" #15 daemon prio=9 os_prio=0 cpu=1.08ms elapsed=0.10s tid=0x00007fe8bc000bd0 nid=0x5173 waiting on condition [0x0000000000000000] [2025-05-16T22:40:09.248Z] java.lang.Thread.State: RUNNABLE [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "VM Periodic Task Thread" os_prio=0 cpu=2016.65ms elapsed=960.06s tid=0x00007feb680f6430 nid=0x4b3f waiting on condition [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "VM Thread" os_prio=0 cpu=85.20ms elapsed=960.11s tid=0x00007feb6808bf00 nid=0x4b35 runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "RuntimeWorker#4" os_prio=0 cpu=0.38ms elapsed=960.14s tid=0x00007feb68060470 nid=0x4b34 runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "RuntimeWorker#3" os_prio=0 cpu=0.37ms elapsed=960.14s tid=0x00007feb6805f500 nid=0x4b33 runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "RuntimeWorker#2" os_prio=0 cpu=0.46ms elapsed=960.14s tid=0x00007feb6805e590 nid=0x4b32 runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "RuntimeWorker#1" os_prio=0 cpu=0.39ms elapsed=960.14s tid=0x00007feb6805d620 nid=0x4b31 runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "RuntimeWorker#0" os_prio=0 cpu=0.53ms elapsed=960.14s tid=0x00007feb6805c6b0 nid=0x4b30 runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "ZStat" os_prio=0 cpu=113.83ms elapsed=960.14s tid=0x00007feb6805b5e0 nid=0x4b2f runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "ZDirector" os_prio=0 cpu=964.50ms elapsed=960.14s tid=0x00007feb6805a6b0 nid=0x4b2e runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "ZDriver" os_prio=0 cpu=43.24ms elapsed=960.14s tid=0x00007feb680571d0 nid=0x4b2d runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "ZUncommitter" os_prio=0 cpu=0.41ms elapsed=960.35s tid=0x00007feb68059050 nid=0x4b2c runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "ZUnmapper" os_prio=0 cpu=0.40ms elapsed=960.35s tid=0x00007feb68055010 nid=0x4b2b runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "ZWorker#1" os_prio=0 cpu=283.86ms elapsed=960.35s tid=0x00007feb6804bd20 nid=0x4b2a runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] "ZWorker#0" os_prio=0 cpu=287.98ms elapsed=960.35s tid=0x00007feb6804b210 nid=0x4b29 runnable [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] JNI global refs: 9, weak refs: 0 [2025-05-16T22:40:09.248Z] [2025-05-16T22:40:09.248Z] --- Timeout information end. [2025-05-16T22:40:09.248Z] finished: Fri May 16 17:40:05 CDT 2025 [2025-05-16T22:40:09.248Z] elapsed time (seconds): 960.43 [2025-05-16T22:40:09.248Z] configuration: [2025-05-16T22:40:09.248Z] STDOUT: [2025-05-16T22:40:09.248Z] [0.009s][debug][gc,heap] Minimum heap 134217728 Initial heap 134217728 Maximum heap 536870912 [2025-05-16T22:40:09.248Z] [0.010s][info ][gc,init] Initializing The Z Garbage Collector [2025-05-16T22:40:09.248Z] [0.010s][info ][gc,init] Version: 17.0.16-beta+3-ea (release) [2025-05-16T22:40:09.248Z] [0.010s][info ][gc,init] NUMA Support: Disabled [2025-05-16T22:40:09.248Z] [0.010s][info ][gc,init] CPUs: 8 total, 8 available [2025-05-16T22:40:09.248Z] [0.010s][info ][gc,init] Memory: 7839M [2025-05-16T22:40:09.248Z] [0.010s][info ][gc,init] Large Page Support: Disabled [2025-05-16T22:40:09.248Z] [0.010s][info ][gc,init] GC Workers: 2 (dynamic) [2025-05-16T22:40:09.248Z] [0.011s][info ][gc,init] Address Space Type: Contiguous/Unrestricted/Complete [2025-05-16T22:40:09.248Z] [0.011s][info ][gc,init] Address Space Size: 8192M x 3 = 24576M [2025-05-16T22:40:09.248Z] [0.012s][info ][gc,init] Heap Backing File: /dev/shm/java_heap.19237 [2025-05-16T22:40:09.248Z] [0.012s][info ][gc,init] Heap Backing Filesystem: tmpfs (0x1021994) [2025-05-16T22:40:09.248Z] [0.013s][info ][gc,init] Min Capacity: 128M [2025-05-16T22:40:09.248Z] [0.013s][info ][gc,init] Initial Capacity: 128M [2025-05-16T22:40:09.248Z] [0.013s][info ][gc,init] Max Capacity: 512M [2025-05-16T22:40:09.248Z] [0.013s][info ][gc,init] Medium Page Size: 16M [2025-05-16T22:40:09.248Z] [0.013s][info ][gc,init] Pre-touch: Disabled [2025-05-16T22:40:09.248Z] [0.013s][info ][gc,init] Available space on backing filesystem: 3461M [2025-05-16T22:40:09.248Z] [0.016s][error][gc ] Failed to uncommit memory (Not supported) [2025-05-16T22:40:09.248Z] [0.016s][info ][gc,init] Uncommit: Implicitly Disabled (Not supported by operating system) [2025-05-16T22:40:09.248Z] [0.220s][info ][gc,init] Runtime Workers: 5 [2025-05-16T22:40:09.248Z] [0.222s][info ][gc ] Using The Z Garbage Collector [2025-05-16T22:40:09.248Z] [0.240s][info ][gc,metaspace] CDS archive(s) mapped at: [0x00007fe8d7000000-0x00007fe8d7ba2000-0x00007fe8d7ba2000), size 12197888, SharedBaseAddress: 0x00007fe8d7000000, ArchiveRelocationMode: 1. [2025-05-16T22:40:09.248Z] [0.240s][info ][gc,metaspace] Compressed class space mapped at: 0x00007fe8d8000000-0x00007fe918000000, reserved size: 1073741824 [2025-05-16T22:40:09.248Z] [0.241s][info ][gc,metaspace] Narrow klass base: 0x00007fe8d7000000, Narrow klass shift: 0, Narrow klass range: 0x100000000 [2025-05-16T22:40:09.248Z] [0.038s] (MainThread) Iteration 0 [2025-05-16T22:40:09.248Z] [0.074s] (MainThread) Allocating [2025-05-16T22:40:09.248Z] [0.568s][debug][gc,heap ] GC(0) Heap before GC invocations=0 (full 0): [2025-05-16T22:40:09.248Z] [0.568s][debug][gc,heap ] GC(0) ZHeap used 112M, capacity 128M, max capacity 512M [2025-05-16T22:40:09.248Z] [0.568s][debug][gc,heap ] GC(0) Metaspace used 559K, committed 704K, reserved 1114112K [2025-05-16T22:40:09.248Z] [0.568s][debug][gc,heap ] GC(0) class space used 42K, committed 128K, reserved 1048576K [2025-05-16T22:40:09.248Z] [0.568s][info ][gc,start ] GC(0) Garbage Collection (Warmup) [2025-05-16T22:40:09.248Z] [0.568s][info ][gc,task ] GC(0) Using 2 workers [2025-05-16T22:40:09.248Z] [0.569s][info ][gc,phases ] GC(0) Pause Mark Start 0.142ms [2025-05-16T22:40:09.248Z] [0.683s][info ][gc,phases ] GC(0) Concurrent Mark 113.274ms [2025-05-16T22:40:09.248Z] [0.683s][info ][gc,phases ] GC(0) Pause Mark End 0.050ms [2025-05-16T22:40:09.248Z] [0.683s][info ][gc,phases ] GC(0) Concurrent Mark Free 0.009ms [2025-05-16T22:40:09.248Z] [0.684s][info ][gc,phases ] GC(0) Concurrent Process Non-Strong References 0.679ms [2025-05-16T22:40:09.248Z] [0.684s][info ][gc,phases ] GC(0) Concurrent Reset Relocation Set 0.004ms [2025-05-16T22:40:09.248Z] [0.704s][info ][gc,phases ] GC(0) Concurrent Select Relocation Set 20.148ms [2025-05-16T22:40:09.248Z] [0.706s][info ][gc,phases ] GC(0) Pause Relocate Start 0.037ms [2025-05-16T22:40:09.248Z] [0.714s][info ][gc,phases ] GC(0) Concurrent Relocate 7.923ms [2025-05-16T22:40:09.248Z] [0.715s][debug][gc,heap ] GC(0) Heap after GC invocations=1 (full 1): [2025-05-16T22:40:09.248Z] [0.715s][debug][gc,heap ] GC(0) ZHeap used 174M, capacity 178M, max capacity 512M [2025-05-16T22:40:09.248Z] [0.715s][debug][gc,heap ] GC(0) Metaspace used 559K, committed 704K, reserved 1114112K [2025-05-16T22:40:09.248Z] [0.715s][debug][gc,heap ] GC(0) class space used 42K, committed 128K, reserved 1048576K [2025-05-16T22:40:09.248Z] [0.715s][info ][gc,load ] GC(0) Load: 2.11/2.60/3.13 [2025-05-16T22:40:09.248Z] [0.715s][info ][gc,mmu ] GC(0) MMU: 2ms/92.9%, 5ms/97.2%, 10ms/98.6%, 20ms/99.3%, 50ms/99.7%, 100ms/99.9% [2025-05-16T22:40:09.248Z] [0.716s][info ][gc,marking ] GC(0) Mark: 2 stripe(s), 5 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-05-16T22:40:09.248Z] [0.716s][info ][gc,marking ] GC(0) Mark Stack Usage: 32M [2025-05-16T22:40:09.248Z] [0.716s][info ][gc,nmethod ] GC(0) NMethods: 277 registered, 0 unregistered [2025-05-16T22:40:09.248Z] [0.716s][info ][gc,metaspace] GC(0) Metaspace: 0M used, 0M committed, 1088M reserved [2025-05-16T22:40:09.248Z] [0.716s][info ][gc,ref ] GC(0) Soft: 98 encountered, 0 discovered, 0 enqueued [2025-05-16T22:40:09.248Z] [0.716s][info ][gc,ref ] GC(0) Weak: 263 encountered, 53 discovered, 50 enqueued [2025-05-16T22:40:09.248Z] [0.716s][info ][gc,ref ] GC(0) Final: 0 encountered, 0 discovered, 0 enqueued [2025-05-16T22:40:09.248Z] [0.717s][info ][gc,ref ] GC(0) Phantom: 14 encountered, 11 discovered, 6 enqueued [2025-05-16T22:40:09.248Z] [0.717s][info ][gc,reloc ] GC(0) Small Pages: 56 / 112M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-05-16T22:40:09.248Z] [0.717s][info ][gc,reloc ] GC(0) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-05-16T22:40:09.248Z] [0.717s][info ][gc,reloc ] GC(0) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-05-16T22:40:09.248Z] [0.717s][info ][gc,reloc ] GC(0) Forwarding Usage: 0M [2025-05-16T22:40:09.248Z] [0.717s][info ][gc,heap ] GC(0) Min Capacity: 128M(25%) [2025-05-16T22:40:09.248Z] [0.717s][info ][gc,heap ] GC(0) Max Capacity: 512M(100%) [2025-05-16T22:40:09.248Z] [0.718s][info ][gc,heap ] GC(0) Soft Max Capacity: 512M(100%) [2025-05-16T22:40:09.248Z] [0.718s][info ][gc,heap ] GC(0) Mark Start Mark End Relocate Start Relocate End High Low [2025-05-16T22:40:09.248Z] [0.718s][info ][gc,heap ] GC(0) Capacity: 128M (25%) 164M (32%) 172M (34%) 178M (35%) 178M (35%) 128M (25%) [2025-05-16T22:40:09.248Z] [0.718s][info ][gc,heap ] GC(0) Free: 400M (78%) 348M (68%) 340M (66%) 338M (66%) 400M (78%) 334M (65%) [2025-05-16T22:40:09.248Z] [0.718s][info ][gc,heap ] GC(0) Used: 112M (22%) 164M (32%) 172M (34%) 174M (34%) 178M (35%) 112M (22%) [2025-05-16T22:40:09.248Z] [0.718s][info ][gc,heap ] GC(0) Live: - 106M (21%) 106M (21%) 106M (21%) - - [2025-05-16T22:40:09.248Z] [0.718s][info ][gc,heap ] GC(0) Allocated: - 52M (10%) 60M (12%) 62M (12%) - - [2025-05-16T22:40:09.248Z] [0.719s][info ][gc,heap ] GC(0) Garbage: - 5M (1%) 5M (1%) 5M (1%) - - [2025-05-16T22:40:09.248Z] [0.719s][info ][gc,heap ] GC(0) Reclaimed: - - 0M (0%) 0M (0%) - - [2025-05-16T22:40:09.248Z] [0.719s][info ][gc ] GC(0) Garbage Collection (Warmup) 112M(22%)->174M(34%) [2025-05-16T22:40:09.248Z] [0.768s][debug][gc,heap ] GC(1) Heap before GC invocations=1 (full 1): [2025-05-16T22:40:09.248Z] [0.768s][debug][gc,heap ] GC(1) ZHeap used 194M, capacity 194M, max capacity 512M [2025-05-16T22:40:09.248Z] [0.768s][debug][gc,heap ] GC(1) Metaspace used 559K, committed 704K, reserved 1114112K [2025-05-16T22:40:09.248Z] [0.769s][debug][gc,heap ] GC(1) class space used 42K, committed 128K, reserved 1048576K [2025-05-16T22:40:09.248Z] [0.769s][info ][gc,start ] GC(1) Garbage Collection (Warmup) [2025-05-16T22:40:09.248Z] [0.769s][info ][gc,task ] GC(1) Using 2 workers [2025-05-16T22:40:09.248Z] [0.769s][info ][gc,phases ] GC(1) Pause Mark Start 0.108ms [2025-05-16T22:40:09.248Z] [0.575s] (MainThread) Reclaiming [2025-05-16T22:40:09.248Z] [0.931s][info ][gc,phases ] GC(1) Concurrent Mark 161.703ms [2025-05-16T22:40:09.248Z] [0.932s][info ][gc,phases ] GC(1) Pause Mark End 0.043ms [2025-05-16T22:40:09.248Z] [0.932s][info ][gc,phases ] GC(1) Concurrent Mark Free 0.013ms [2025-05-16T22:40:09.248Z] [0.933s][info ][gc,phases ] GC(1) Concurrent Process Non-Strong References 1.160ms [2025-05-16T22:40:09.248Z] [0.933s][info ][gc,phases ] GC(1) Concurrent Reset Relocation Set 0.011ms [2025-05-16T22:40:09.248Z] [0.939s][info ][gc,phases ] GC(1) Concurrent Select Relocation Set 6.103ms [2025-05-16T22:40:09.248Z] [0.940s][info ][gc,phases ] GC(1) Pause Relocate Start 0.037ms [2025-05-16T22:40:09.248Z] [0.950s][info ][gc,phases ] GC(1) Concurrent Relocate 9.835ms [2025-05-16T22:40:09.248Z] [0.950s][debug][gc,heap ] GC(1) Heap after GC invocations=2 (full 2): [2025-05-16T22:40:09.248Z] [0.950s][debug][gc,heap ] GC(1) ZHeap used 242M, capacity 248M, max capacity 512M [2025-05-16T22:40:09.248Z] [0.950s][debug][gc,heap ] GC(1) Metaspace used 559K, committed 704K, reserved 1114112K [2025-05-16T22:40:09.248Z] [0.950s][debug][gc,heap ] GC(1) class space used 42K, committed 128K, reserved 1048576K [2025-05-16T22:40:09.248Z] [0.950s][info ][gc,load ] GC(1) Load: 2.11/2.60/3.13 [2025-05-16T22:40:09.248Z] [0.951s][info ][gc,mmu ] GC(1) MMU: 2ms/92.9%, 5ms/97.2%, 10ms/98.6%, 20ms/99.3%, 50ms/99.7%, 100ms/99.8% [2025-05-16T22:40:09.248Z] [0.951s][info ][gc,marking ] GC(1) Mark: 2 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-05-16T22:40:09.248Z] [0.951s][info ][gc,marking ] GC(1) Mark Stack Usage: 32M [2025-05-16T22:40:09.248Z] [0.951s][info ][gc,nmethod ] GC(1) NMethods: 277 registered, 0 unregistered [2025-05-16T22:40:09.248Z] [0.951s][info ][gc,metaspace] GC(1) Metaspace: 0M used, 0M committed, 1088M reserved [2025-05-16T22:40:09.248Z] [0.951s][info ][gc,ref ] GC(1) Soft: 98 encountered, 0 discovered, 0 enqueued [2025-05-16T22:40:09.248Z] [0.951s][info ][gc,ref ] GC(1) Weak: 213 encountered, 17 discovered, 0 enqueued [2025-05-16T22:40:09.248Z] [0.952s][info ][gc,ref ] GC(1) Final: 0 encountered, 0 discovered, 0 enqueued [2025-05-16T22:40:09.248Z] [0.952s][info ][gc,ref ] GC(1) Phantom: 8 encountered, 5 discovered, 0 enqueued [2025-05-16T22:40:09.248Z] [0.952s][info ][gc,reloc ] GC(1) Small Pages: 89 / 178M, Empty: 0M, Relocated: 3M, In-Place: 0 [2025-05-16T22:40:09.248Z] [0.952s][info ][gc,reloc ] GC(1) Medium Pages: 1 / 16M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-05-16T22:40:09.248Z] [0.952s][info ][gc,reloc ] GC(1) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-05-16T22:40:09.248Z] [0.952s][info ][gc,reloc ] GC(1) Forwarding Usage: 0M [2025-05-16T22:40:09.248Z] [0.952s][info ][gc,heap ] GC(1) Min Capacity: 128M(25%) [2025-05-16T22:40:09.248Z] [0.953s][info ][gc,heap ] GC(1) Max Capacity: 512M(100%) [2025-05-16T22:40:09.248Z] [0.953s][info ][gc,heap ] GC(1) Soft Max Capacity: 512M(100%) [2025-05-16T22:40:09.248Z] [0.953s][info ][gc,heap ] GC(1) Mark Start Mark End Relocate Start Relocate End High Low [2025-05-16T22:40:09.248Z] [0.953s][info ][gc,heap ] GC(1) Capacity: 194M (38%) 244M (48%) 244M (48%) 248M (48%) 248M (48%) 194M (38%) [2025-05-16T22:40:09.248Z] [0.953s][info ][gc,heap ] GC(1) Free: 318M (62%) 268M (52%) 268M (52%) 270M (53%) 318M (62%) 264M (52%) [2025-05-16T22:40:09.248Z] [0.953s][info ][gc,heap ] GC(1) Used: 194M (38%) 244M (48%) 244M (48%) 242M (47%) 248M (48%) 194M (38%) [2025-05-16T22:40:09.248Z] [0.953s][info ][gc,heap ] GC(1) Live: - 170M (33%) 170M (33%) 170M (33%) - - [2025-05-16T22:40:09.248Z] [0.954s][info ][gc,heap ] GC(1) Allocated: - 50M (10%) 50M (10%) 50M (10%) - - [2025-05-16T22:40:09.248Z] [0.954s][info ][gc,heap ] GC(1) Garbage: - 23M (5%) 23M (5%) 21M (4%) - - [2025-05-16T22:40:09.249Z] [0.954s][info ][gc,heap ] GC(1) Reclaimed: - - 0M (0%) 2M (0%) - - [2025-05-16T22:40:09.249Z] [0.954s][info ][gc ] GC(1) Garbage Collection (Warmup) 194M(38%)->242M(47%) [2025-05-16T22:40:09.249Z] [0.954s][debug][gc,heap ] GC(2) Heap before GC invocations=2 (full 2): [2025-05-16T22:40:09.249Z] [0.954s][debug][gc,heap ] GC(2) ZHeap used 242M, capacity 248M, max capacity 512M [2025-05-16T22:40:09.249Z] [0.954s][debug][gc,heap ] GC(2) Metaspace used 559K, committed 704K, reserved 1114112K [2025-05-16T22:40:09.249Z] [0.955s][debug][gc,heap ] GC(2) class space used 42K, committed 128K, reserved 1048576K [2025-05-16T22:40:09.249Z] [0.955s][info ][gc,start ] GC(2) Garbage Collection (System.gc()) [2025-05-16T22:40:09.249Z] [0.955s][info ][gc,task ] GC(2) Using 2 workers [2025-05-16T22:40:09.249Z] [0.955s][info ][gc,phases ] GC(2) Pause Mark Start 0.049ms [2025-05-16T22:40:09.249Z] [0.960s][info ][gc,phases ] GC(2) Concurrent Mark 4.995ms [2025-05-16T22:40:09.249Z] [0.961s][info ][gc,phases ] GC(2) Pause Mark End 0.048ms [2025-05-16T22:40:09.249Z] [0.961s][info ][gc,phases ] GC(2) Concurrent Mark Free 0.011ms [2025-05-16T22:40:09.249Z] [0.962s][info ][gc,phases ] GC(2) Concurrent Process Non-Strong References 1.132ms [2025-05-16T22:40:09.249Z] [0.962s][info ][gc,phases ] GC(2) Concurrent Reset Relocation Set 0.012ms [2025-05-16T22:40:09.249Z] [0.968s][info ][gc,phases ] GC(2) Concurrent Select Relocation Set 5.622ms [2025-05-16T22:40:09.249Z] [0.968s][info ][gc,phases ] GC(2) Pause Relocate Start 0.055ms [2025-05-16T22:40:09.249Z] [0.972s][info ][gc,phases ] GC(2) Concurrent Relocate 3.302ms [2025-05-16T22:40:09.249Z] [0.972s][debug][gc,heap ] GC(2) Heap after GC invocations=3 (full 3): [2025-05-16T22:40:09.249Z] [0.972s][debug][gc,heap ] GC(2) ZHeap used 4M, capacity 248M, max capacity 512M [2025-05-16T22:40:09.249Z] [0.972s][debug][gc,heap ] GC(2) Metaspace used 559K, committed 704K, reserved 1114112K [2025-05-16T22:40:09.249Z] [0.972s][debug][gc,heap ] GC(2) class space used 42K, committed 128K, reserved 1048576K [2025-05-16T22:40:09.249Z] [0.972s][info ][gc,load ] GC(2) Load: 2.11/2.60/3.13 [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,mmu ] GC(2) MMU: 2ms/92.9%, 5ms/97.2%, 10ms/98.6%, 20ms/99.2%, 50ms/99.5%, 100ms/99.8% [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,marking ] GC(2) Mark: 2 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,marking ] GC(2) Mark Stack Usage: 32M [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,nmethod ] GC(2) NMethods: 277 registered, 0 unregistered [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,metaspace] GC(2) Metaspace: 0M used, 0M committed, 1088M reserved [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,ref ] GC(2) Soft: 98 encountered, 0 discovered, 0 enqueued [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,ref ] GC(2) Weak: 213 encountered, 0 discovered, 0 enqueued [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,ref ] GC(2) Final: 0 encountered, 0 discovered, 0 enqueued [2025-05-16T22:40:09.249Z] [0.973s][info ][gc,ref ] GC(2) Phantom: 8 encountered, 5 discovered, 0 enqueued [2025-05-16T22:40:09.249Z] [0.974s][info ][gc,reloc ] GC(2) Small Pages: 105 / 210M, Empty: 200M, Relocated: 1M, In-Place: 0 [2025-05-16T22:40:09.249Z] [0.974s][info ][gc,reloc ] GC(2) Medium Pages: 2 / 32M, Empty: 32M, Relocated: 0M, In-Place: 0 [2025-05-16T22:40:09.249Z] [0.974s][info ][gc,reloc ] GC(2) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-05-16T22:40:09.249Z] [0.974s][info ][gc,reloc ] GC(2) Forwarding Usage: 0M [2025-05-16T22:40:09.249Z] [0.974s][info ][gc,heap ] GC(2) Min Capacity: 128M(25%) [2025-05-16T22:40:09.249Z] [0.974s][info ][gc,heap ] GC(2) Max Capacity: 512M(100%) [2025-05-16T22:40:09.249Z] [0.974s][info ][gc,heap ] GC(2) Soft Max Capacity: 512M(100%) [2025-05-16T22:40:09.249Z] [0.974s][info ][gc,heap ] GC(2) Mark Start Mark End Relocate Start Relocate End High Low [2025-05-16T22:40:09.249Z] [0.975s][info ][gc,heap ] GC(2) Capacity: 248M (48%) 248M (48%) 248M (48%) 248M (48%) 248M (48%) 248M (48%) [2025-05-16T22:40:09.249Z] [0.975s][info ][gc,heap ] GC(2) Free: 270M (53%) 270M (53%) 502M (98%) 508M (99%) 508M (99%) 270M (53%) [2025-05-16T22:40:09.249Z] [0.975s][info ][gc,heap ] GC(2) Used: 242M (47%) 242M (47%) 10M (2%) 4M (1%) 242M (47%) 4M (1%) [2025-05-16T22:40:09.249Z] [0.975s][info ][gc,heap ] GC(2) Live: - 1M (0%) 1M (0%) 1M (0%) - - [2025-05-16T22:40:09.249Z] [0.975s][info ][gc,heap ] GC(2) Allocated: - 0M (0%) 0M (0%) 0M (0%) - - [2025-05-16T22:40:09.249Z] [0.975s][info ][gc,heap ] GC(2) Garbage: - 240M (47%) 8M (2%) 2M (1%) - - [2025-05-16T22:40:09.249Z] [0.975s][info ][gc,heap ] GC(2) Reclaimed: - - 232M (45%) 238M (46%) - - [2025-05-16T22:40:09.249Z] [0.976s][info ][gc ] GC(2) Garbage Collection (System.gc()) 242M(47%)->4M(1%) [2025-05-16T22:40:09.249Z] [0.650s] (MainThread) Waiting for uncommit to start [2025-05-16T22:40:09.249Z] Timeout signalled after 960 seconds [2025-05-16T22:40:09.249Z] STDERR: [2025-05-16T22:40:09.249Z] rerun: [2025-05-16T22:40:09.249Z] cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/scratch/2 && \ [2025-05-16T22:40:09.249Z] DISPLAY=:0 \ [2025-05-16T22:40:09.249Z] HOME=/home/jenkins \ [2025-05-16T22:40:09.249Z] LANG=en_US.UTF-8 \ [2025-05-16T22:40:09.249Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-05-16T22:40:09.249Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-05-16T22:40:09.249Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/java \ [2025-05-16T22:40:09.249Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-05-16T22:40:09.249Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-05-16T22:40:09.249Z] -Dtest.compiler.opts= \ [2025-05-16T22:40:09.249Z] -Dtest.java.opts= \ [2025-05-16T22:40:09.249Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image \ [2025-05-16T22:40:09.249Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image \ [2025-05-16T22:40:09.249Z] -Dtest.timeout.factor=8.0 \ [2025-05-16T22:40:09.249Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-05-16T22:40:09.249Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-05-16T22:40:09.249Z] -Dtest.name=gc/z/TestUncommit.java \ [2025-05-16T22:40:09.249Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java \ [2025-05-16T22:40:09.249Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \ [2025-05-16T22:40:09.249Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-05-16T22:40:09.249Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d \ [2025-05-16T22:40:09.249Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/test/lib \ [2025-05-16T22:40:09.249Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/classes/2/test/lib \ [2025-05-16T22:40:09.249Z] -ea \ [2025-05-16T22:40:09.249Z] -esa \ [2025-05-16T22:40:09.249Z] -Xmx512m \ [2025-05-16T22:40:09.249Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-05-16T22:40:09.249Z] -XX:+UseZGC \ [2025-05-16T22:40:09.249Z] -Xlog:gc*,gc+heap=debug,gc+stats=off \ [2025-05-16T22:40:09.249Z] -Xms128M \ [2025-05-16T22:40:09.249Z] -Xmx512M \ [2025-05-16T22:40:09.249Z] -XX:ZUncommitDelay=10 \ [2025-05-16T22:40:09.249Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work/gc/z/TestUncommit.d/main.0.jta [2025-05-16T22:40:09.249Z] [2025-05-16T22:40:09.249Z] TEST RESULT: Error. Program `/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960412ms). [2025-05-16T22:40:09.249Z] -------------------------------------------------- [2025-05-16T22:40:09.249Z] Test results: passed: 312; error: 1 [2025-05-16T22:40:13.395Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/report/html/report.html [2025-05-16T22:40:13.395Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17474330621237/hotspot_tier1_gc_0/work [2025-05-16T22:40:13.395Z] Error: Some tests failed or other problems occurred. [2025-05-16T22:40:13.395Z] ----------------------------------- [2025-05-16T22:40:13.395Z] hotspot_tier1_gc_0_FAILED [2025-05-16T22:40:13.395Z] ----------------------------------- [2025-05-16T22:40:13.395Z] [2025-05-16T22:40:13.395Z] TEST TEARDOWN: [2025-05-16T22:40:13.395Z] Nothing to be done for teardown. [2025-05-16T22:40:13.395Z] hotspot_tier1_gc_0 Finish Time: Fri May 16 17:40:12 2025 Epoch Time (ms): 1747435212355