[2025-09-04T08:21:43.261Z] Running test jdk_jfr_1 ... [2025-09-04T08:21:43.261Z] =============================================== [2025-09-04T08:21:43.261Z] jdk_jfr_1 Start Time: Thu Sep 4 03:21:37 2025 Epoch Time (ms): 1756974097886 [2025-09-04T08:21:43.261Z] variation: Mode650 [2025-09-04T08:21:43.261Z] JVM_OPTIONS: -XX:-UseCompressedOops [2025-09-04T08:21:43.261Z] { \ [2025-09-04T08:21:43.261Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T08:21:43.261Z] echo "Nothing to be done for setup."; \ [2025-09-04T08:21:43.261Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../TKG/output_17569669589948/jdk_jfr_1"; \ [2025-09-04T08:21:43.261Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../TKG/output_17569669589948/jdk_jfr_1"; \ [2025-09-04T08:21:43.261Z] echo ""; echo "TESTING:"; \ [2025-09-04T08:21:43.261Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-09-04T08:21:43.261Z] -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_rerun/jdkbinary/openjdk-test-image/jdk/jtreg/native" -vmoptions:"-Xmx768m -XX:-UseCompressedOops " \ [2025-09-04T08:21:43.261Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../TKG/output_17569669589948/jdk_jfr_1"/work" \ [2025-09-04T08:21:43.261Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../TKG/output_17569669589948/jdk_jfr_1"/report" \ [2025-09-04T08:21:43.261Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image" \ [2025-09-04T08:21:43.261Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../openjdk/openjdk-jdk/test/jdk/ProblemList.txt" \ [2025-09-04T08:21:43.261Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2025-09-04T08:21:43.261Z] \ [2025-09-04T08:21:43.261Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2025-09-04T08:21:43.261Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../openjdk/openjdk-jdk/test/jdk:jdk_jfr"; \ [2025-09-04T08:21:43.261Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "jdk_jfr_1""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "jdk_jfr_1""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T08:21:43.261Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T08:21:43.261Z] echo "Nothing to be done for teardown."; \ [2025-09-04T08:21:43.261Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../TKG/output_17569669589948/TestTargetResult"; [2025-09-04T08:21:43.261Z] [2025-09-04T08:21:43.261Z] TEST SETUP: [2025-09-04T08:21:43.261Z] Nothing to be done for setup. [2025-09-04T08:21:43.261Z] [2025-09-04T08:21:43.261Z] TESTING: [2025-09-04T08:21:43.926Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../TKG/output_17569669589948/jdk_jfr_1/work" not found: creating [2025-09-04T08:21:43.926Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/../TKG/output_17569669589948/jdk_jfr_1/report" not found: creating [2025-09-04T08:21:48.903Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work [2025-09-04T08:41:37.394Z] -------------------------------------------------- [2025-09-04T08:41:37.394Z] TEST: jdk/jfr/event/gc/detailed/TestZUncommitEvent.java [2025-09-04T08:41:37.395Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image [2025-09-04T08:41:37.395Z] [2025-09-04T08:41:37.395Z] ACTION: build -- Passed. Build successful [2025-09-04T08:41:37.395Z] REASON: Named class compiled on demand [2025-09-04T08:41:37.395Z] TIME: 0.077 seconds [2025-09-04T08:41:37.395Z] messages: [2025-09-04T08:41:37.395Z] command: build jdk.jfr.event.gc.detailed.TestZUncommitEvent [2025-09-04T08:41:37.395Z] reason: Named class compiled on demand [2025-09-04T08:41:37.395Z] started: Thu Sep 04 03:25:24 CDT 2025 [2025-09-04T08:41:37.395Z] Test directory: [2025-09-04T08:41:37.395Z] compile: jdk.jfr.event.gc.detailed.TestZUncommitEvent [2025-09-04T08:41:37.395Z] finished: Thu Sep 04 03:25:24 CDT 2025 [2025-09-04T08:41:37.395Z] elapsed time (seconds): 0.077 [2025-09-04T08:41:37.395Z] [2025-09-04T08:41:37.395Z] ACTION: compile -- Passed. Compilation successful [2025-09-04T08:41:37.395Z] REASON: .class file out of date or does not exist [2025-09-04T08:41:37.395Z] TIME: 0.077 seconds [2025-09-04T08:41:37.395Z] messages: [2025-09-04T08:41:37.395Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed/TestZUncommitEvent.java [2025-09-04T08:41:37.395Z] reason: .class file out of date or does not exist [2025-09-04T08:41:37.395Z] started: Thu Sep 04 03:25:24 CDT 2025 [2025-09-04T08:41:37.395Z] Additional options from @modules: --add-modules jdk.jfr,java.management [2025-09-04T08:41:37.395Z] Mode: agentvm [2025-09-04T08:41:37.395Z] Agent id: 3 [2025-09-04T08:41:37.395Z] finished: Thu Sep 04 03:25:24 CDT 2025 [2025-09-04T08:41:37.395Z] elapsed time (seconds): 0.077 [2025-09-04T08:41:37.395Z] configuration: [2025-09-04T08:41:37.395Z] Boot Layer (javac runtime environment) [2025-09-04T08:41:37.395Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-09-04T08:41:37.395Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/patches/java.base [2025-09-04T08:41:37.395Z] [2025-09-04T08:41:37.395Z] javac compilation environment [2025-09-04T08:41:37.395Z] add modules: jdk.jfr java.management [2025-09-04T08:41:37.395Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg [2025-09-04T08:41:37.395Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/lib [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/jdk [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/hotspot/jtreg [2025-09-04T08:41:37.395Z] [2025-09-04T08:41:37.395Z] rerun: [2025-09-04T08:41:37.395Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/scratch/1 && \ [2025-09-04T08:41:37.395Z] DISPLAY=:0 \ [2025-09-04T08:41:37.395Z] HOME=/home/jenkins \ [2025-09-04T08:41:37.395Z] LANG=en_US.UTF-8 \ [2025-09-04T08:41:37.395Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-09-04T08:41:37.395Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image/bin/javac \ [2025-09-04T08:41:37.395Z] -J-ea \ [2025-09-04T08:41:37.395Z] -J-esa \ [2025-09-04T08:41:37.395Z] -J-Xmx768m \ [2025-09-04T08:41:37.395Z] -J-XX:-UseCompressedOops \ [2025-09-04T08:41:37.395Z] -J-Dtest.vm.opts='-ea -esa -Xmx768m -XX:-UseCompressedOops' \ [2025-09-04T08:41:37.395Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx768m -J-XX:-UseCompressedOops' \ [2025-09-04T08:41:37.395Z] -J-Dtest.compiler.opts= \ [2025-09-04T08:41:37.395Z] -J-Dtest.java.opts= \ [2025-09-04T08:41:37.395Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image \ [2025-09-04T08:41:37.395Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image \ [2025-09-04T08:41:37.395Z] -J-Dtest.timeout.factor=8.0 \ [2025-09-04T08:41:37.395Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-09-04T08:41:37.395Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2025-09-04T08:41:37.395Z] -J-Dtest.name=jdk/jfr/event/gc/detailed/TestZUncommitEvent.java \ [2025-09-04T08:41:37.395Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed/TestZUncommitEvent.java \ [2025-09-04T08:41:37.395Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed \ [2025-09-04T08:41:37.395Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-09-04T08:41:37.395Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d \ [2025-09-04T08:41:37.395Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/hotspot/jtreg \ [2025-09-04T08:41:37.396Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/hotspot/jtreg \ [2025-09-04T08:41:37.396Z] -J-Dtest.modules='jdk.jfr java.management' \ [2025-09-04T08:41:37.396Z] --add-modules jdk.jfr,java.management \ [2025-09-04T08:41:37.396Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d \ [2025-09-04T08:41:37.396Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-09-04T08:41:37.396Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/hotspot/jtreg /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed/TestZUncommitEvent.java [2025-09-04T08:41:37.396Z] direct: [2025-09-04T08:41:37.396Z] Note: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib/jdk/test/lib/Asserts.java uses unchecked or unsafe operations. [2025-09-04T08:41:37.396Z] Note: Recompile with -Xlint:unchecked for details. [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] ACTION: main -- Error. Program `/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960413ms). [2025-09-04T08:41:37.396Z] REASON: User specified action: run main/othervm -XX:+UseZGC -Xms32M -Xmx128M -Xlog:gc,gc+heap -XX:+ZUncommit -XX:ZUncommitDelay=1 jdk.jfr.event.gc.detailed.TestZUncommitEvent [2025-09-04T08:41:37.396Z] TIME: 960.427 seconds [2025-09-04T08:41:37.396Z] messages: [2025-09-04T08:41:37.396Z] command: main -XX:+UseZGC -Xms32M -Xmx128M -Xlog:gc,gc+heap -XX:+ZUncommit -XX:ZUncommitDelay=1 jdk.jfr.event.gc.detailed.TestZUncommitEvent [2025-09-04T08:41:37.396Z] reason: User specified action: run main/othervm -XX:+UseZGC -Xms32M -Xmx128M -Xlog:gc,gc+heap -XX:+ZUncommit -XX:ZUncommitDelay=1 jdk.jfr.event.gc.detailed.TestZUncommitEvent [2025-09-04T08:41:37.396Z] started: Thu Sep 04 03:25:24 CDT 2025 [2025-09-04T08:41:37.396Z] Mode: othervm [/othervm specified] [2025-09-04T08:41:37.396Z] Additional options from @modules: --add-modules jdk.jfr,java.management [2025-09-04T08:41:37.396Z] Timeout information: [2025-09-04T08:41:37.396Z] Running jstack on process 29540 [2025-09-04T08:41:37.396Z] 2025-09-04 03:41:25 [2025-09-04T08:41:37.396Z] Full thread dump OpenJDK 64-Bit Server VM (17.0.17-beta+6-ea mixed mode, sharing): [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] Threads class SMR info: [2025-09-04T08:41:37.396Z] _java_thread_list=0x00007f13e40015b0, length=16, elements={ [2025-09-04T08:41:37.396Z] 0x00007f168002eaa0, 0x00007f168008b030, 0x00007f168008c790, 0x00007f1680093640, [2025-09-04T08:41:37.396Z] 0x00007f1680094bd0, 0x00007f16800961f0, 0x00007f1680097df0, 0x00007f1680099550, [2025-09-04T08:41:37.396Z] 0x00007f16800a2bf0, 0x00007f16800c2670, 0x00007f168010d400, 0x00007f1680182050, [2025-09-04T08:41:37.396Z] 0x00007f13bce654a0, 0x00007f13bcfb13f0, 0x00007f13bcf41e10, 0x00007f13e4000a90 [2025-09-04T08:41:37.396Z] } [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "main" #1 prio=5 os_prio=0 cpu=222.37ms elapsed=960.36s tid=0x00007f168002eaa0 nid=0x7367 in Object.wait() [0x00007f16862a1000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: WAITING (on object monitor) [2025-09-04T08:41:37.396Z] at java.lang.Object.wait(java.base@17.0.17-beta/Native Method) [2025-09-04T08:41:37.396Z] - waiting on <0x0000100000a02600> (a java.lang.Thread) [2025-09-04T08:41:37.396Z] at java.lang.Thread.join(java.base@17.0.17-beta/Thread.java:1313) [2025-09-04T08:41:37.396Z] - locked <0x0000100000a02600> (a java.lang.Thread) [2025-09-04T08:41:37.396Z] at java.lang.Thread.join(java.base@17.0.17-beta/Thread.java:1381) [2025-09-04T08:41:37.396Z] at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:85) [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "Reference Handler" #2 daemon prio=10 os_prio=0 cpu=1.20ms elapsed=960.25s tid=0x00007f168008b030 nid=0x7370 waiting on condition [0x00007f1668629000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.396Z] at java.lang.ref.Reference.waitForReferencePendingList(java.base@17.0.17-beta/Native Method) [2025-09-04T08:41:37.396Z] at java.lang.ref.Reference.processPendingReferences(java.base@17.0.17-beta/Reference.java:253) [2025-09-04T08:41:37.396Z] at java.lang.ref.Reference$ReferenceHandler.run(java.base@17.0.17-beta/Reference.java:215) [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "Finalizer" #3 daemon prio=8 os_prio=0 cpu=0.69ms elapsed=960.25s tid=0x00007f168008c790 nid=0x7371 in Object.wait() [0x00007f1668528000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: WAITING (on object monitor) [2025-09-04T08:41:37.396Z] at java.lang.Object.wait(java.base@17.0.17-beta/Native Method) [2025-09-04T08:41:37.396Z] - waiting on <0x00001000003a7388> (a java.lang.ref.ReferenceQueue$Lock) [2025-09-04T08:41:37.396Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.17-beta/ReferenceQueue.java:155) [2025-09-04T08:41:37.396Z] - locked <0x00001000003a7388> (a java.lang.ref.ReferenceQueue$Lock) [2025-09-04T08:41:37.396Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.17-beta/ReferenceQueue.java:176) [2025-09-04T08:41:37.396Z] at java.lang.ref.Finalizer$FinalizerThread.run(java.base@17.0.17-beta/Finalizer.java:172) [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "Signal Dispatcher" #4 daemon prio=9 os_prio=0 cpu=1.13ms elapsed=960.24s tid=0x00007f1680093640 nid=0x7372 waiting on condition [0x0000000000000000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "Service Thread" #5 daemon prio=9 os_prio=0 cpu=2.30ms elapsed=960.24s tid=0x00007f1680094bd0 nid=0x7373 runnable [0x0000000000000000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "Monitor Deflation Thread" #6 daemon prio=9 os_prio=0 cpu=279.84ms elapsed=960.24s tid=0x00007f16800961f0 nid=0x7374 runnable [0x0000000000000000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "C2 CompilerThread0" #7 daemon prio=9 os_prio=0 cpu=1207.66ms elapsed=960.24s tid=0x00007f1680097df0 nid=0x7375 waiting on condition [0x0000000000000000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.396Z] No compile task [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "C1 CompilerThread0" #10 daemon prio=9 os_prio=0 cpu=646.30ms elapsed=960.24s tid=0x00007f1680099550 nid=0x7376 waiting on condition [0x0000000000000000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.396Z] No compile task [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "Sweeper thread" #11 daemon prio=9 os_prio=0 cpu=1.82ms elapsed=960.24s tid=0x00007f16800a2bf0 nid=0x7377 runnable [0x0000000000000000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "Common-Cleaner" #12 daemon prio=8 os_prio=0 cpu=3.54ms elapsed=960.19s tid=0x00007f16800c2670 nid=0x7378 in Object.wait() [0x00007f1459dfc000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: TIMED_WAITING (on object monitor) [2025-09-04T08:41:37.396Z] at java.lang.Object.wait(java.base@17.0.17-beta/Native Method) [2025-09-04T08:41:37.396Z] - waiting on <no object reference available> [2025-09-04T08:41:37.396Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.17-beta/ReferenceQueue.java:155) [2025-09-04T08:41:37.396Z] - locked <0x0000100000aafdb8> (a java.lang.ref.ReferenceQueue$Lock) [2025-09-04T08:41:37.396Z] at jdk.internal.ref.CleanerImpl.run(java.base@17.0.17-beta/CleanerImpl.java:140) [2025-09-04T08:41:37.396Z] at java.lang.Thread.run(java.base@17.0.17-beta/Thread.java:840) [2025-09-04T08:41:37.396Z] at jdk.internal.misc.InnocuousThread.run(java.base@17.0.17-beta/InnocuousThread.java:162) [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "Notification Thread" #13 daemon prio=9 os_prio=0 cpu=0.26ms elapsed=960.15s tid=0x00007f168010d400 nid=0x7379 runnable [0x0000000000000000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.396Z] [2025-09-04T08:41:37.396Z] "MainThread" #14 prio=5 os_prio=0 cpu=707.21ms elapsed=960.10s tid=0x00007f1680182050 nid=0x737b in Object.wait() [0x00007f1459af8000] [2025-09-04T08:41:37.396Z] java.lang.Thread.State: WAITING (on object monitor) [2025-09-04T08:41:37.396Z] at java.lang.Object.wait(java.base@17.0.17-beta/Native Method) [2025-09-04T08:41:37.396Z] - waiting on <0x0000100000a6a848> (a java.lang.Object) [2025-09-04T08:41:37.396Z] at jdk.jfr.internal.consumer.AbstractEventStream.awaitTermination(jdk.jfr@17.0.17-beta/AbstractEventStream.java:199) [2025-09-04T08:41:37.396Z] - locked <0x0000100000a6a848> (a java.lang.Object) [2025-09-04T08:41:37.396Z] at jdk.jfr.internal.consumer.AbstractEventStream.awaitTermination(jdk.jfr@17.0.17-beta/AbstractEventStream.java:176) [2025-09-04T08:41:37.397Z] at jdk.jfr.consumer.RecordingStream.awaitTermination(jdk.jfr@17.0.17-beta/RecordingStream.java:452) [2025-09-04T08:41:37.397Z] at jdk.jfr.event.gc.detailed.TestZUncommitEvent.main(TestZUncommitEvent.java:64) [2025-09-04T08:41:37.397Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@17.0.17-beta/Native Method) [2025-09-04T08:41:37.397Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@17.0.17-beta/NativeMethodAccessorImpl.java:77) [2025-09-04T08:41:37.397Z] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@17.0.17-beta/DelegatingMethodAccessorImpl.java:43) [2025-09-04T08:41:37.397Z] at java.lang.reflect.Method.invoke(java.base@17.0.17-beta/Method.java:569) [2025-09-04T08:41:37.397Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2025-09-04T08:41:37.397Z] at java.lang.Thread.run(java.base@17.0.17-beta/Thread.java:840) [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "JFR Recorder Thread" #15 daemon prio=5 os_prio=0 cpu=315.16ms elapsed=960.03s tid=0x00007f13bce654a0 nid=0x7380 waiting on condition [0x0000000000000000] [2025-09-04T08:41:37.397Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "JFR Periodic Tasks" #16 daemon prio=5 os_prio=0 cpu=213.22ms elapsed=959.48s tid=0x00007f13bcfb13f0 nid=0x73b0 in Object.wait() [0x00007f14595f4000] [2025-09-04T08:41:37.397Z] java.lang.Thread.State: TIMED_WAITING (on object monitor) [2025-09-04T08:41:37.397Z] at java.lang.Object.wait(java.base@17.0.17-beta/Native Method) [2025-09-04T08:41:37.397Z] - waiting on <no object reference available> [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.PlatformRecorder.takeNap(jdk.jfr@17.0.17-beta/PlatformRecorder.java:544) [2025-09-04T08:41:37.397Z] - locked <0x0000100000b49a60> (a jdk.jfr.internal.JVM$ChunkRotationMonitor) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.PlatformRecorder.periodicTask(jdk.jfr@17.0.17-beta/PlatformRecorder.java:524) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.PlatformRecorder.lambda$startDiskMonitor$1(jdk.jfr@17.0.17-beta/PlatformRecorder.java:449) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.PlatformRecorder$$Lambda$63/0x00007f14000575c0.run(jdk.jfr@17.0.17-beta/Unknown Source) [2025-09-04T08:41:37.397Z] at java.lang.Thread.run(java.base@17.0.17-beta/Thread.java:840) [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "JFR Event Stream 1" #18 prio=5 os_prio=0 cpu=1408.91ms elapsed=959.27s tid=0x00007f13bcf41e10 nid=0x73b5 in Object.wait() [0x00007f14594f3000] [2025-09-04T08:41:37.397Z] java.lang.Thread.State: TIMED_WAITING (on object monitor) [2025-09-04T08:41:37.397Z] at java.lang.Object.wait(java.base@17.0.17-beta/Native Method) [2025-09-04T08:41:37.397Z] - waiting on <no object reference available> [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.Utils.waitFlush(jdk.jfr@17.0.17-beta/Utils.java:832) [2025-09-04T08:41:37.397Z] - locked <0x0000100000b3d2e8> (a java.lang.Object) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.ChunkParser.awaitUpdatedHeader(jdk.jfr@17.0.17-beta/ChunkParser.java:296) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.ChunkParser.readStreamingEvent(jdk.jfr@17.0.17-beta/ChunkParser.java:204) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.EventDirectoryStream.processOrdered(jdk.jfr@17.0.17-beta/EventDirectoryStream.java:226) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.EventDirectoryStream.processRecursionSafe(jdk.jfr@17.0.17-beta/EventDirectoryStream.java:169) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.EventDirectoryStream.process(jdk.jfr@17.0.17-beta/EventDirectoryStream.java:126) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.AbstractEventStream.execute(jdk.jfr@17.0.17-beta/AbstractEventStream.java:266) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.AbstractEventStream$1.run(jdk.jfr@17.0.17-beta/AbstractEventStream.java:289) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.AbstractEventStream$1.run(jdk.jfr@17.0.17-beta/AbstractEventStream.java:286) [2025-09-04T08:41:37.397Z] at java.security.AccessController.executePrivileged(java.base@17.0.17-beta/AccessController.java:776) [2025-09-04T08:41:37.397Z] at java.security.AccessController.doPrivileged(java.base@17.0.17-beta/AccessController.java:399) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.AbstractEventStream.run(jdk.jfr@17.0.17-beta/AbstractEventStream.java:286) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.AbstractEventStream.lambda$startAsync$1(jdk.jfr@17.0.17-beta/AbstractEventStream.java:228) [2025-09-04T08:41:37.397Z] at jdk.jfr.internal.consumer.AbstractEventStream$$Lambda$82/0x00007f1400038d50.run(jdk.jfr@17.0.17-beta/Unknown Source) [2025-09-04T08:41:37.397Z] at java.lang.Thread.run(java.base@17.0.17-beta/Thread.java:840) [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "Attach Listener" #19 daemon prio=9 os_prio=0 cpu=0.91ms elapsed=0.10s tid=0x00007f13e4000a90 nid=0x371f waiting on condition [0x0000000000000000] [2025-09-04T08:41:37.397Z] java.lang.Thread.State: RUNNABLE [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "VM Periodic Task Thread" os_prio=0 cpu=1752.77ms elapsed=960.15s tid=0x00007f168010efc0 nid=0x737a waiting on condition [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "VM Thread" os_prio=0 cpu=97.53ms elapsed=960.26s tid=0x00007f1680086800 nid=0x736f runnable [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "RuntimeWorker#0" os_prio=0 cpu=0.76ms elapsed=960.29s tid=0x00007f168005a910 nid=0x736e runnable [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "ZStat" os_prio=0 cpu=112.43ms elapsed=960.30s tid=0x00007f1680059850 nid=0x736d runnable [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "ZDirector" os_prio=0 cpu=924.97ms elapsed=960.30s tid=0x00007f16800589b0 nid=0x736c runnable [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "ZDriver" os_prio=0 cpu=45.67ms elapsed=960.30s tid=0x00007f1680054010 nid=0x736b runnable [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "ZUncommitter" os_prio=0 cpu=0.21ms elapsed=960.35s tid=0x00007f168004b010 nid=0x736a runnable [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "ZUnmapper" os_prio=0 cpu=0.19ms elapsed=960.35s tid=0x00007f1680056010 nid=0x7369 runnable [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] "ZWorker#0" os_prio=0 cpu=233.44ms elapsed=960.36s tid=0x00007f1680049450 nid=0x7368 runnable [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] JNI global refs: 25, weak refs: 4 [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] --- Timeout information end. [2025-09-04T08:41:37.397Z] finished: Thu Sep 04 03:41:25 CDT 2025 [2025-09-04T08:41:37.397Z] elapsed time (seconds): 960.427 [2025-09-04T08:41:37.397Z] configuration: [2025-09-04T08:41:37.397Z] Boot Layer [2025-09-04T08:41:37.397Z] add modules: jdk.jfr java.management [2025-09-04T08:41:37.397Z] [2025-09-04T08:41:37.397Z] STDOUT: [2025-09-04T08:41:37.397Z] [0.014s][error][gc] Failed to uncommit memory (Not supported) [2025-09-04T08:41:37.397Z] [0.069s][info ][gc] Using The Z Garbage Collector [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Min Capacity: 32M(25%) [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Max Capacity: 128M(100%) [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Soft Max Capacity: 128M(100%) [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Mark Start Mark End Relocate Start Relocate End High Low [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Capacity: 32M (25%) 32M (25%) 32M (25%) 32M (25%) 32M (25%) 32M (25%) [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Free: 114M (89%) 112M (88%) 110M (86%) 118M (92%) 118M (92%) 106M (83%) [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Used: 14M (11%) 16M (12%) 18M (14%) 10M (8%) 22M (17%) 10M (8%) [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Live: - 3M (3%) 3M (3%) 3M (3%) - - [2025-09-04T08:41:37.397Z] [0.849s][info ][gc,heap] GC(0) Allocated: - 2M (2%) 4M (3%) 5M (5%) - - [2025-09-04T08:41:37.398Z] [0.849s][info ][gc,heap] GC(0) Garbage: - 10M (8%) 10M (8%) 0M (0%) - - [2025-09-04T08:41:37.398Z] [0.849s][info ][gc,heap] GC(0) Reclaimed: - - 0M (0%) 9M (8%) - - [2025-09-04T08:41:37.398Z] [0.849s][info ][gc ] GC(0) Garbage Collection (Warmup) 14M(11%)->10M(8%) [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Min Capacity: 32M(25%) [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Soft Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Mark Start Mark End Relocate Start Relocate End High Low [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Capacity: 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Free: 78M (61%) 78M (61%) 78M (61%) 88M (69%) 88M (69%) 74M (58%) [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Used: 50M (39%) 50M (39%) 50M (39%) 40M (31%) 54M (42%) 40M (31%) [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Live: - 37M (29%) 37M (29%) 37M (29%) - - [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Allocated: - 0M (0%) 0M (0%) 1M (2%) - - [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Garbage: - 12M (10%) 12M (10%) 0M (1%) - - [2025-09-04T08:41:37.398Z] [1.207s][info ][gc,heap] GC(1) Reclaimed: - - 0M (0%) 11M (9%) - - [2025-09-04T08:41:37.398Z] [1.207s][info ][gc ] GC(1) Garbage Collection (Warmup) 50M(39%)->40M(31%) [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Min Capacity: 32M(25%) [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Soft Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Mark Start Mark End Relocate Start Relocate End High Low [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Capacity: 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Free: 88M (69%) 86M (67%) 86M (67%) 88M (69%) 88M (69%) 84M (66%) [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Used: 40M (31%) 42M (33%) 42M (33%) 40M (31%) 44M (34%) 40M (31%) [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Live: - 37M (29%) 37M (29%) 37M (29%) - - [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Allocated: - 2M (2%) 2M (2%) 1M (2%) - - [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Garbage: - 2M (2%) 2M (2%) 0M (1%) - - [2025-09-04T08:41:37.398Z] [1.249s][info ][gc,heap] GC(2) Reclaimed: - - 0M (0%) 1M (2%) - - [2025-09-04T08:41:37.398Z] [1.249s][info ][gc ] GC(2) Garbage Collection (Warmup) 40M(31%)->40M(31%) [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Min Capacity: 32M(25%) [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Soft Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Mark Start Mark End Relocate Start Relocate End High Low [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Capacity: 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Free: 88M (69%) 88M (69%) 122M (95%) 122M (95%) 122M (95%) 88M (69%) [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Used: 40M (31%) 40M (31%) 6M (5%) 6M (5%) 40M (31%) 6M (5%) [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Live: - 3M (3%) 3M (3%) 3M (3%) - - [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Allocated: - 0M (0%) 0M (0%) 1M (2%) - - [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Garbage: - 36M (29%) 2M (2%) 0M (1%) - - [2025-09-04T08:41:37.398Z] [1.272s][info ][gc,heap] GC(3) Reclaimed: - - 34M (27%) 35M (28%) - - [2025-09-04T08:41:37.398Z] [1.272s][info ][gc ] GC(3) Garbage Collection (System.gc()) 40M(31%)->6M(5%) [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Min Capacity: 32M(25%) [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Soft Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Mark Start Mark End Relocate Start Relocate End High Low [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Capacity: 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Free: 114M (89%) 112M (88%) 112M (88%) 116M (91%) 116M (91%) 110M (86%) [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Used: 14M (11%) 16M (12%) 16M (12%) 12M (9%) 18M (14%) 12M (9%) [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Live: - 7M (6%) 7M (6%) 7M (6%) - - [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Allocated: - 2M (2%) 2M (2%) 2M (2%) - - [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Garbage: - 6M (5%) 6M (5%) 2M (2%) - - [2025-09-04T08:41:37.398Z] [301.367s][info ][gc,heap] GC(4) Reclaimed: - - 0M (0%) 4M (3%) - - [2025-09-04T08:41:37.398Z] [301.367s][info ][gc ] GC(4) Garbage Collection (Proactive) 14M(11%)->12M(9%) [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Min Capacity: 32M(25%) [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Soft Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Mark Start Mark End Relocate Start Relocate End High Low [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Capacity: 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Free: 116M (91%) 116M (91%) 116M (91%) 120M (94%) 120M (94%) 114M (89%) [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Used: 12M (9%) 12M (9%) 12M (9%) 8M (6%) 14M (11%) 8M (6%) [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Live: - 7M (6%) 7M (6%) 7M (6%) - - [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Allocated: - 0M (0%) 0M (0%) 0M (0%) - - [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Garbage: - 4M (4%) 4M (4%) 0M (1%) - - [2025-09-04T08:41:37.398Z] [601.472s][info ][gc,heap] GC(5) Reclaimed: - - 0M (0%) 4M (3%) - - [2025-09-04T08:41:37.398Z] [601.472s][info ][gc ] GC(5) Garbage Collection (Proactive) 12M(9%)->8M(6%) [2025-09-04T08:41:37.398Z] [901.561s][info ][gc,heap] GC(6) Min Capacity: 32M(25%) [2025-09-04T08:41:37.398Z] [901.561s][info ][gc,heap] GC(6) Max Capacity: 128M(100%) [2025-09-04T08:41:37.398Z] [901.561s][info ][gc,heap] GC(6) Soft Max Capacity: 128M(100%) [2025-09-04T08:41:37.399Z] [901.561s][info ][gc,heap] GC(6) Mark Start Mark End Relocate Start Relocate End High Low [2025-09-04T08:41:37.399Z] [901.561s][info ][gc,heap] GC(6) Capacity: 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) 66M (52%) [2025-09-04T08:41:37.399Z] [901.561s][info ][gc,heap] GC(6) Free: 118M (92%) 118M (92%) 118M (92%) 120M (94%) 120M (94%) 116M (91%) [2025-09-04T08:41:37.399Z] [901.561s][info ][gc,heap] GC(6) Used: 10M (8%) 10M (8%) 10M (8%) 8M (6%) 12M (9%) 8M (6%) [2025-09-04T08:41:37.399Z] [901.561s][info ][gc,heap] GC(6) Live: - 7M (6%) 7M (6%) 7M (6%) - - [2025-09-04T08:41:37.399Z] [901.561s][info ][gc,heap] GC(6) Allocated: - 0M (0%) 0M (0%) 0M (0%) - - [2025-09-04T08:41:37.399Z] [901.561s][info ][gc,heap] GC(6) Garbage: - 2M (2%) 2M (2%) 0M (1%) - - [2025-09-04T08:41:37.399Z] [901.561s][info ][gc,heap] GC(6) Reclaimed: - - 0M (0%) 2M (2%) - - [2025-09-04T08:41:37.399Z] [901.561s][info ][gc ] GC(6) Garbage Collection (Proactive) 10M(8%)->8M(6%) [2025-09-04T08:41:37.399Z] Timeout signalled after 960 seconds [2025-09-04T08:41:37.399Z] STDERR: [2025-09-04T08:41:37.399Z] rerun: [2025-09-04T08:41:37.399Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/scratch/1 && \ [2025-09-04T08:41:37.399Z] DISPLAY=:0 \ [2025-09-04T08:41:37.399Z] HOME=/home/jenkins \ [2025-09-04T08:41:37.399Z] LANG=en_US.UTF-8 \ [2025-09-04T08:41:37.399Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-09-04T08:41:37.399Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/hotspot/jtreg:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-09-04T08:41:37.399Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image/bin/java \ [2025-09-04T08:41:37.399Z] -Dtest.vm.opts='-ea -esa -Xmx768m -XX:-UseCompressedOops' \ [2025-09-04T08:41:37.399Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx768m -J-XX:-UseCompressedOops' \ [2025-09-04T08:41:37.399Z] -Dtest.compiler.opts= \ [2025-09-04T08:41:37.399Z] -Dtest.java.opts= \ [2025-09-04T08:41:37.399Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image \ [2025-09-04T08:41:37.399Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image \ [2025-09-04T08:41:37.399Z] -Dtest.timeout.factor=8.0 \ [2025-09-04T08:41:37.399Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-09-04T08:41:37.399Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2025-09-04T08:41:37.399Z] -Dtest.name=jdk/jfr/event/gc/detailed/TestZUncommitEvent.java \ [2025-09-04T08:41:37.399Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed/TestZUncommitEvent.java \ [2025-09-04T08:41:37.399Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed \ [2025-09-04T08:41:37.399Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-09-04T08:41:37.399Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d \ [2025-09-04T08:41:37.399Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/hotspot/jtreg \ [2025-09-04T08:41:37.399Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/openjdk/openjdk-jdk/test/jdk/jdk/jfr/event/gc/detailed:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/jdk:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/classes/0/test/hotspot/jtreg \ [2025-09-04T08:41:37.399Z] -Dtest.modules='jdk.jfr java.management' \ [2025-09-04T08:41:37.399Z] --add-modules jdk.jfr,java.management \ [2025-09-04T08:41:37.399Z] -ea \ [2025-09-04T08:41:37.399Z] -esa \ [2025-09-04T08:41:37.399Z] -Xmx768m \ [2025-09-04T08:41:37.399Z] -XX:-UseCompressedOops \ [2025-09-04T08:41:37.399Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-09-04T08:41:37.399Z] -XX:+UseZGC \ [2025-09-04T08:41:37.399Z] -Xms32M \ [2025-09-04T08:41:37.399Z] -Xmx128M \ [2025-09-04T08:41:37.399Z] -Xlog:gc,gc+heap \ [2025-09-04T08:41:37.399Z] -XX:+ZUncommit \ [2025-09-04T08:41:37.399Z] -XX:ZUncommitDelay=1 \ [2025-09-04T08:41:37.399Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work/jdk/jfr/event/gc/detailed/TestZUncommitEvent.d/main.0.jta [2025-09-04T08:41:37.399Z] [2025-09-04T08:41:37.399Z] TEST RESULT: Error. Program `/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960413ms). [2025-09-04T08:41:37.399Z] -------------------------------------------------- [2025-09-04T08:41:37.399Z] Test results: passed: 537; error: 1 [2025-09-04T08:41:41.351Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/report/html/report.html [2025-09-04T08:41:41.352Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_rerun/aqa-tests/TKG/output_17569669589948/jdk_jfr_1/work [2025-09-04T08:41:41.352Z] Error: Some tests failed or other problems occurred. [2025-09-04T08:41:41.352Z] ----------------------------------- [2025-09-04T08:41:41.352Z] jdk_jfr_1_FAILED [2025-09-04T08:41:41.352Z] ----------------------------------- [2025-09-04T08:41:41.352Z] [2025-09-04T08:41:41.352Z] TEST TEARDOWN: [2025-09-04T08:41:41.352Z] Nothing to be done for teardown. [2025-09-04T08:41:41.352Z] jdk_jfr_1 Finish Time: Thu Sep 4 03:41:36 2025 Epoch Time (ms): 1756975296053