Test Results Summary Service

hotspot_gc_0

[2024-11-08T16:27:23.213Z] Running test hotspot_gc_0 ... [2024-11-08T16:27:23.213Z] =============================================== [2024-11-08T16:27:23.213Z] hotspot_gc_0 Start Time: Fri Nov 8 10:27:23 2024 Epoch Time (ms): 1731083243050 [2024-11-08T16:27:23.213Z] variation: NoOptions [2024-11-08T16:27:23.213Z] JVM_OPTIONS: [2024-11-08T16:27:23.213Z] { \ [2024-11-08T16:27:23.213Z] echo ""; echo "TEST SETUP:"; \ [2024-11-08T16:27:23.213Z] echo "Nothing to be done for setup."; \ [2024-11-08T16:27:23.213Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17310832427050/hotspot_gc_0"; \ [2024-11-08T16:27:23.213Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17310832427050/hotspot_gc_0"; \ [2024-11-08T16:27:23.213Z] echo ""; echo "TESTING:"; \ [2024-11-08T16:27:23.213Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/..//bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2024-11-08T16:27:23.213Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:3 -nativepath:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2024-11-08T16:27:23.213Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17310832427050/hotspot_gc_0"/work" \ [2024-11-08T16:27:23.213Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17310832427050/hotspot_gc_0"/report" \ [2024-11-08T16:27:23.213Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/../" \ [2024-11-08T16:27:23.213Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2024-11-08T16:27:23.213Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2024-11-08T16:27:23.213Z] \ [2024-11-08T16:27:23.213Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2024-11-08T16:27:23.213Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \ [2024-11-08T16:27:23.213Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-08T16:27:23.213Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-08T16:27:23.213Z] echo "Nothing to be done for teardown."; \ [2024-11-08T16:27:23.213Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17310832427050/TestTargetResult"; [2024-11-08T16:27:23.213Z] [2024-11-08T16:27:23.213Z] TEST SETUP: [2024-11-08T16:27:23.213Z] Nothing to be done for setup. [2024-11-08T16:27:23.213Z] [2024-11-08T16:27:23.213Z] TESTING: [2024-11-08T16:27:23.897Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17310832427050/hotspot_gc_0/work" not found: creating [2024-11-08T16:27:23.897Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17310832427050/hotspot_gc_0/report" not found: creating [2024-11-08T16:27:28.035Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work [2024-11-08T18:04:23.475Z] -------------------------------------------------- [2024-11-08T18:04:23.475Z] TEST: gc/z/TestUncommit.java [2024-11-08T18:04:23.475Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/.. [2024-11-08T18:04:23.475Z] [2024-11-08T18:04:23.475Z] ACTION: build -- Passed. Build successful [2024-11-08T18:04:23.475Z] REASON: Named class compiled on demand [2024-11-08T18:04:23.475Z] TIME: 0.118 seconds [2024-11-08T18:04:23.475Z] messages: [2024-11-08T18:04:23.475Z] command: build gc.z.TestUncommit [2024-11-08T18:04:23.475Z] reason: Named class compiled on demand [2024-11-08T18:04:23.475Z] started: Fri Nov 08 11:48:16 CST 2024 [2024-11-08T18:04:23.475Z] Test directory: [2024-11-08T18:04:23.475Z] compile: gc.z.TestUncommit [2024-11-08T18:04:23.475Z] finished: Fri Nov 08 11:48:16 CST 2024 [2024-11-08T18:04:23.475Z] elapsed time (seconds): 0.118 [2024-11-08T18:04:23.475Z] [2024-11-08T18:04:23.475Z] ACTION: compile -- Passed. Compilation successful [2024-11-08T18:04:23.475Z] REASON: .class file out of date or does not exist [2024-11-08T18:04:23.475Z] TIME: 0.118 seconds [2024-11-08T18:04:23.475Z] messages: [2024-11-08T18:04:23.475Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java [2024-11-08T18:04:23.475Z] reason: .class file out of date or does not exist [2024-11-08T18:04:23.475Z] started: Fri Nov 08 11:48:16 CST 2024 [2024-11-08T18:04:23.475Z] Mode: agentvm [2024-11-08T18:04:23.476Z] Agent id: 2 [2024-11-08T18:04:23.476Z] finished: Fri Nov 08 11:48:16 CST 2024 [2024-11-08T18:04:23.476Z] elapsed time (seconds): 0.118 [2024-11-08T18:04:23.476Z] configuration: [2024-11-08T18:04:23.476Z] Boot Layer (javac runtime environment) [2024-11-08T18:04:23.476Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-11-08T18:04:23.476Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-11-08T18:04:23.476Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/patches/java.base [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] javac compilation environment [2024-11-08T18:04:23.476Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z [2024-11-08T18:04:23.476Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-11-08T18:04:23.476Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z [2024-11-08T18:04:23.476Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d [2024-11-08T18:04:23.476Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/test/lib [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] rerun: [2024-11-08T18:04:23.476Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/scratch/0 && \ [2024-11-08T18:04:23.476Z] DISPLAY=:0 \ [2024-11-08T18:04:23.476Z] HOME=/home/jenkins \ [2024-11-08T18:04:23.476Z] LANG=en_US.UTF-8 \ [2024-11-08T18:04:23.476Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-11-08T18:04:23.476Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/../bin/javac \ [2024-11-08T18:04:23.476Z] -J-ea \ [2024-11-08T18:04:23.476Z] -J-esa \ [2024-11-08T18:04:23.476Z] -J-Xmx512m \ [2024-11-08T18:04:23.476Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2024-11-08T18:04:23.476Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2024-11-08T18:04:23.476Z] -J-Dtest.compiler.opts= \ [2024-11-08T18:04:23.476Z] -J-Dtest.java.opts= \ [2024-11-08T18:04:23.476Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/.. \ [2024-11-08T18:04:23.476Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/.. \ [2024-11-08T18:04:23.476Z] -J-Dtest.timeout.factor=8.0 \ [2024-11-08T18:04:23.476Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-11-08T18:04:23.476Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2024-11-08T18:04:23.476Z] -J-Dtest.name=gc/z/TestUncommit.java \ [2024-11-08T18:04:23.476Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java \ [2024-11-08T18:04:23.476Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \ [2024-11-08T18:04:23.476Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-11-08T18:04:23.476Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d \ [2024-11-08T18:04:23.476Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/test/lib \ [2024-11-08T18:04:23.476Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/test/lib \ [2024-11-08T18:04:23.476Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d \ [2024-11-08T18:04:23.476Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-11-08T18:04:23.476Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/test/lib /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] ACTION: main -- Error. Program `/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/../bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960519ms). [2024-11-08T18:04:23.476Z] 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 [2024-11-08T18:04:23.476Z] TIME: 960.528 seconds [2024-11-08T18:04:23.476Z] messages: [2024-11-08T18:04:23.476Z] command: main -XX:+UseZGC -Xlog:gc*,gc+heap=debug,gc+stats=off -Xms128M -Xmx512M -XX:ZUncommitDelay=10 gc.z.TestUncommit [2024-11-08T18:04:23.476Z] 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 [2024-11-08T18:04:23.476Z] started: Fri Nov 08 11:48:16 CST 2024 [2024-11-08T18:04:23.476Z] Mode: othervm [/othervm specified] [2024-11-08T18:04:23.476Z] Timeout information: [2024-11-08T18:04:23.476Z] Running jstack on process 7917 [2024-11-08T18:04:23.476Z] 2024-11-08 12:04:17 [2024-11-08T18:04:23.476Z] Full thread dump OpenJDK 64-Bit Server VM (17.0.14-beta+2-ea mixed mode, sharing): [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] Threads class SMR info: [2024-11-08T18:04:23.476Z] _java_thread_list=0x00007f0b70001de0, length=13, elements={ [2024-11-08T18:04:23.476Z] 0x00007f0e1c02eb20, 0x00007f0e1c0909c0, 0x00007f0e1c092040, 0x00007f0e1c098f50, [2024-11-08T18:04:23.476Z] 0x00007f0e1c09a480, 0x00007f0e1c09bab0, 0x00007f0e1c09d6c0, 0x00007f0e1c09ee30, [2024-11-08T18:04:23.476Z] 0x00007f0e1c0a84e0, 0x00007f0e1c0fbe60, 0x00007f0e1c0ffd10, 0x00007f0e1c15e530, [2024-11-08T18:04:23.476Z] 0x00007f0b70000bd0 [2024-11-08T18:04:23.476Z] } [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "main" #1 prio=5 os_prio=0 cpu=317.23ms elapsed=960.49s tid=0x00007f0e1c02eb20 nid=0x1ef0 in Object.wait() [0x00007f0e23f2a000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: WAITING (on object monitor) [2024-11-08T18:04:23.476Z] at java.lang.Object.wait(java.base@17.0.14-beta/Native Method) [2024-11-08T18:04:23.476Z] - waiting on <0x0000100006c00178> (a java.lang.Thread) [2024-11-08T18:04:23.476Z] at java.lang.Thread.join(java.base@17.0.14-beta/Thread.java:1313) [2024-11-08T18:04:23.476Z] - locked <0x0000100006c00178> (a java.lang.Thread) [2024-11-08T18:04:23.476Z] at java.lang.Thread.join(java.base@17.0.14-beta/Thread.java:1381) [2024-11-08T18:04:23.476Z] at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:85) [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Reference Handler" #2 daemon prio=10 os_prio=0 cpu=0.94ms elapsed=960.18s tid=0x00007f0e1c0909c0 nid=0x1efe waiting on condition [0x00007f0bd7caa000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] at java.lang.ref.Reference.waitForReferencePendingList(java.base@17.0.14-beta/Native Method) [2024-11-08T18:04:23.476Z] at java.lang.ref.Reference.processPendingReferences(java.base@17.0.14-beta/Reference.java:253) [2024-11-08T18:04:23.476Z] at java.lang.ref.Reference$ReferenceHandler.run(java.base@17.0.14-beta/Reference.java:215) [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Finalizer" #3 daemon prio=8 os_prio=0 cpu=0.79ms elapsed=960.18s tid=0x00007f0e1c092040 nid=0x1eff in Object.wait() [0x00007f0bd7ba9000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: WAITING (on object monitor) [2024-11-08T18:04:23.476Z] at java.lang.Object.wait(java.base@17.0.14-beta/Native Method) [2024-11-08T18:04:23.476Z] - waiting on <0x0000100006c13500> (a java.lang.ref.ReferenceQueue$Lock) [2024-11-08T18:04:23.476Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.14-beta/ReferenceQueue.java:155) [2024-11-08T18:04:23.476Z] - locked <0x0000100006c13500> (a java.lang.ref.ReferenceQueue$Lock) [2024-11-08T18:04:23.476Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.14-beta/ReferenceQueue.java:176) [2024-11-08T18:04:23.476Z] at java.lang.ref.Finalizer$FinalizerThread.run(java.base@17.0.14-beta/Finalizer.java:172) [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Signal Dispatcher" #4 daemon prio=9 os_prio=0 cpu=0.77ms elapsed=960.17s tid=0x00007f0e1c098f50 nid=0x1f00 waiting on condition [0x0000000000000000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Service Thread" #5 daemon prio=9 os_prio=0 cpu=2.04ms elapsed=960.17s tid=0x00007f0e1c09a480 nid=0x1f01 runnable [0x0000000000000000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Monitor Deflation Thread" #6 daemon prio=9 os_prio=0 cpu=228.22ms elapsed=960.17s tid=0x00007f0e1c09bab0 nid=0x1f02 runnable [0x0000000000000000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "C2 CompilerThread0" #7 daemon prio=9 os_prio=0 cpu=59.97ms elapsed=960.17s tid=0x00007f0e1c09d6c0 nid=0x1f03 waiting on condition [0x0000000000000000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] No compile task [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "C1 CompilerThread0" #10 daemon prio=9 os_prio=0 cpu=92.29ms elapsed=960.17s tid=0x00007f0e1c09ee30 nid=0x1f04 waiting on condition [0x0000000000000000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] No compile task [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Sweeper thread" #11 daemon prio=9 os_prio=0 cpu=0.31ms elapsed=960.17s tid=0x00007f0e1c0a84e0 nid=0x1f05 runnable [0x0000000000000000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Notification Thread" #12 daemon prio=9 os_prio=0 cpu=0.18ms elapsed=960.09s tid=0x00007f0e1c0fbe60 nid=0x1f06 runnable [0x0000000000000000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Common-Cleaner" #13 daemon prio=8 os_prio=0 cpu=2.40ms elapsed=960.09s tid=0x00007f0e1c0ffd10 nid=0x1f08 in Object.wait() [0x00007f0bd72a0000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-11-08T18:04:23.476Z] at java.lang.Object.wait(java.base@17.0.14-beta/Native Method) [2024-11-08T18:04:23.476Z] - waiting on <0x0000100006c999b0> (a java.lang.ref.ReferenceQueue$Lock) [2024-11-08T18:04:23.476Z] at java.lang.ref.ReferenceQueue.remove(java.base@17.0.14-beta/ReferenceQueue.java:155) [2024-11-08T18:04:23.476Z] - locked <0x0000100006c999b0> (a java.lang.ref.ReferenceQueue$Lock) [2024-11-08T18:04:23.476Z] at jdk.internal.ref.CleanerImpl.run(java.base@17.0.14-beta/CleanerImpl.java:140) [2024-11-08T18:04:23.476Z] at java.lang.Thread.run(java.base@17.0.14-beta/Thread.java:840) [2024-11-08T18:04:23.476Z] at jdk.internal.misc.InnocuousThread.run(java.base@17.0.14-beta/InnocuousThread.java:162) [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "MainThread" #14 prio=5 os_prio=0 cpu=620.12ms elapsed=960.07s tid=0x00007f0e1c15e530 nid=0x1f09 waiting on condition [0x00007f0bd719f000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-11-08T18:04:23.476Z] at java.lang.Thread.sleep(java.base@17.0.14-beta/Native Method) [2024-11-08T18:04:23.476Z] at gc.z.TestUncommit.test(TestUncommit.java:85) [2024-11-08T18:04:23.476Z] at gc.z.TestUncommit.main(TestUncommit.java:131) [2024-11-08T18:04:23.476Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@17.0.14-beta/Native Method) [2024-11-08T18:04:23.476Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@17.0.14-beta/NativeMethodAccessorImpl.java:77) [2024-11-08T18:04:23.476Z] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@17.0.14-beta/DelegatingMethodAccessorImpl.java:43) [2024-11-08T18:04:23.476Z] at java.lang.reflect.Method.invoke(java.base@17.0.14-beta/Method.java:569) [2024-11-08T18:04:23.476Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2024-11-08T18:04:23.476Z] at java.lang.Thread.run(java.base@17.0.14-beta/Thread.java:840) [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "Attach Listener" #15 daemon prio=9 os_prio=0 cpu=0.58ms elapsed=0.10s tid=0x00007f0b70000bd0 nid=0x25b1 waiting on condition [0x0000000000000000] [2024-11-08T18:04:23.476Z] java.lang.Thread.State: RUNNABLE [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "VM Periodic Task Thread" os_prio=0 cpu=1483.76ms elapsed=960.09s tid=0x00007f0e1c0fda20 nid=0x1f07 waiting on condition [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "VM Thread" os_prio=0 cpu=74.67ms elapsed=960.20s tid=0x00007f0e1c08c050 nid=0x1efd runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "RuntimeWorker#4" os_prio=0 cpu=0.29ms elapsed=960.24s tid=0x00007f0e1c0605c0 nid=0x1efc runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "RuntimeWorker#3" os_prio=0 cpu=0.33ms elapsed=960.24s tid=0x00007f0e1c05f650 nid=0x1efb runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "RuntimeWorker#2" os_prio=0 cpu=0.32ms elapsed=960.24s tid=0x00007f0e1c05e6e0 nid=0x1efa runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "RuntimeWorker#1" os_prio=0 cpu=0.31ms elapsed=960.24s tid=0x00007f0e1c05d770 nid=0x1ef9 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "RuntimeWorker#0" os_prio=0 cpu=0.51ms elapsed=960.24s tid=0x00007f0e1c05c800 nid=0x1ef8 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "ZStat" os_prio=0 cpu=95.82ms elapsed=960.24s tid=0x00007f0e1c05b730 nid=0x1ef7 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "ZDirector" os_prio=0 cpu=844.44ms elapsed=960.24s tid=0x00007f0e1c05a720 nid=0x1ef6 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "ZDriver" os_prio=0 cpu=56.08ms elapsed=960.25s tid=0x00007f0e1c0573e0 nid=0x1ef5 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "ZUncommitter" os_prio=0 cpu=0.21ms elapsed=960.48s tid=0x00007f0e1c059050 nid=0x1ef4 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "ZUnmapper" os_prio=0 cpu=0.28ms elapsed=960.48s tid=0x00007f0e1c055010 nid=0x1ef3 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "ZWorker#1" os_prio=0 cpu=255.32ms elapsed=960.48s tid=0x00007f0e1c04c010 nid=0x1ef2 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] "ZWorker#0" os_prio=0 cpu=229.14ms elapsed=960.48s tid=0x00007f0e1c0493a0 nid=0x1ef1 runnable [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] JNI global refs: 9, weak refs: 0 [2024-11-08T18:04:23.476Z] [2024-11-08T18:04:23.476Z] --- Timeout information end. [2024-11-08T18:04:23.476Z] finished: Fri Nov 08 12:04:17 CST 2024 [2024-11-08T18:04:23.476Z] elapsed time (seconds): 960.528 [2024-11-08T18:04:23.476Z] configuration: [2024-11-08T18:04:23.476Z] STDOUT: [2024-11-08T18:04:23.476Z] [0.008s][debug][gc,heap] Minimum heap 134217728 Initial heap 134217728 Maximum heap 536870912 [2024-11-08T18:04:23.476Z] [0.008s][info ][gc,init] Initializing The Z Garbage Collector [2024-11-08T18:04:23.476Z] [0.008s][info ][gc,init] Version: 17.0.14-beta+2-ea (release) [2024-11-08T18:04:23.476Z] [0.008s][info ][gc,init] NUMA Support: Disabled [2024-11-08T18:04:23.476Z] [0.008s][info ][gc,init] CPUs: 8 total, 8 available [2024-11-08T18:04:23.476Z] [0.009s][info ][gc,init] Memory: 7839M [2024-11-08T18:04:23.476Z] [0.009s][info ][gc,init] Large Page Support: Disabled [2024-11-08T18:04:23.476Z] [0.009s][info ][gc,init] GC Workers: 2 (dynamic) [2024-11-08T18:04:23.476Z] [0.013s][info ][gc,init] Address Space Type: Contiguous/Unrestricted/Complete [2024-11-08T18:04:23.476Z] [0.013s][info ][gc,init] Address Space Size: 8192M x 3 = 24576M [2024-11-08T18:04:23.476Z] [0.013s][info ][gc,init] Heap Backing File: /dev/shm/java_heap.7917 [2024-11-08T18:04:23.476Z] [0.013s][info ][gc,init] Heap Backing Filesystem: tmpfs (0x1021994) [2024-11-08T18:04:23.476Z] [0.013s][info ][gc,init] Min Capacity: 128M [2024-11-08T18:04:23.476Z] [0.013s][info ][gc,init] Initial Capacity: 128M [2024-11-08T18:04:23.476Z] [0.013s][info ][gc,init] Max Capacity: 512M [2024-11-08T18:04:23.476Z] [0.014s][info ][gc,init] Medium Page Size: 16M [2024-11-08T18:04:23.476Z] [0.014s][info ][gc,init] Pre-touch: Disabled [2024-11-08T18:04:23.476Z] [0.014s][info ][gc,init] Available space on backing filesystem: 3919M [2024-11-08T18:04:23.476Z] [0.018s][error][gc ] Failed to uncommit memory (Not supported) [2024-11-08T18:04:23.476Z] [0.018s][info ][gc,init] Uncommit: Implicitly Disabled (Not supported by operating system) [2024-11-08T18:04:23.476Z] [0.250s][info ][gc,init] Runtime Workers: 5 [2024-11-08T18:04:23.476Z] [0.253s][info ][gc ] Using The Z Garbage Collector [2024-11-08T18:04:23.476Z] [0.278s][info ][gc,metaspace] CDS archive(s) mapped at: [0x00007f0b8f000000-0x00007f0b8fba0000-0x00007f0b8fba0000), size 12189696, SharedBaseAddress: 0x00007f0b8f000000, ArchiveRelocationMode: 1. [2024-11-08T18:04:23.476Z] [0.278s][info ][gc,metaspace] Compressed class space mapped at: 0x00007f0b90000000-0x00007f0bd0000000, reserved size: 1073741824 [2024-11-08T18:04:23.476Z] [0.278s][info ][gc,metaspace] Narrow klass base: 0x00007f0b8f000000, Narrow klass shift: 0, Narrow klass range: 0x100000000 [2024-11-08T18:04:23.476Z] [0.026s] (MainThread) Iteration 0 [2024-11-08T18:04:23.476Z] [0.067s] (MainThread) Allocating [2024-11-08T18:04:23.476Z] [0.623s][debug][gc,heap ] GC(0) Heap before GC invocations=0 (full 0): [2024-11-08T18:04:23.476Z] [0.623s][debug][gc,heap ] GC(0) ZHeap used 74M, capacity 128M, max capacity 512M [2024-11-08T18:04:23.476Z] [0.623s][debug][gc,heap ] GC(0) Metaspace used 571K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.476Z] [0.623s][debug][gc,heap ] GC(0) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.476Z] [0.623s][info ][gc,start ] GC(0) Garbage Collection (Warmup) [2024-11-08T18:04:23.476Z] [0.623s][info ][gc,task ] GC(0) Using 2 workers [2024-11-08T18:04:23.476Z] [0.624s][info ][gc,phases ] GC(0) Pause Mark Start 0.110ms [2024-11-08T18:04:23.476Z] [0.678s][info ][gc,phases ] GC(0) Concurrent Mark 54.145ms [2024-11-08T18:04:23.476Z] [0.678s][info ][gc,phases ] GC(0) Pause Mark End 0.104ms [2024-11-08T18:04:23.476Z] [0.679s][info ][gc,phases ] GC(0) Concurrent Mark Free 0.006ms [2024-11-08T18:04:23.476Z] [0.680s][info ][gc,phases ] GC(0) Concurrent Process Non-Strong References 1.555ms [2024-11-08T18:04:23.476Z] [0.680s][info ][gc,phases ] GC(0) Concurrent Reset Relocation Set 0.003ms [2024-11-08T18:04:23.476Z] [0.692s][info ][gc,phases ] GC(0) Concurrent Select Relocation Set 12.274ms [2024-11-08T18:04:23.477Z] [0.693s][info ][gc,phases ] GC(0) Pause Relocate Start 0.126ms [2024-11-08T18:04:23.477Z] [0.697s][info ][gc,phases ] GC(0) Concurrent Relocate 4.486ms [2024-11-08T18:04:23.477Z] [0.697s][debug][gc,heap ] GC(0) Heap after GC invocations=1 (full 1): [2024-11-08T18:04:23.477Z] [0.698s][debug][gc,heap ] GC(0) ZHeap used 110M, capacity 128M, max capacity 512M [2024-11-08T18:04:23.477Z] [0.698s][debug][gc,heap ] GC(0) Metaspace used 572K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.477Z] [0.698s][debug][gc,heap ] GC(0) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,load ] GC(0) Load: 2.34/3.16/4.08 [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,mmu ] GC(0) MMU: 2ms/93.7%, 5ms/97.5%, 10ms/98.7%, 20ms/98.9%, 50ms/99.5%, 100ms/99.7% [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,marking ] GC(0) Mark: 2 stripe(s), 4 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,marking ] GC(0) Mark Stack Usage: 32M [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,nmethod ] GC(0) NMethods: 294 registered, 0 unregistered [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,metaspace] GC(0) Metaspace: 0M used, 0M committed, 1088M reserved [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,ref ] GC(0) Soft: 98 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,ref ] GC(0) Weak: 263 encountered, 50 discovered, 50 enqueued [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,ref ] GC(0) Final: 0 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,ref ] GC(0) Phantom: 14 encountered, 10 discovered, 6 enqueued [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,reloc ] GC(0) Small Pages: 37 / 74M, Empty: 0M, Relocated: 3M, In-Place: 0 [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,reloc ] GC(0) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,reloc ] GC(0) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,reloc ] GC(0) Forwarding Usage: 0M [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,heap ] GC(0) Min Capacity: 128M(25%) [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,heap ] GC(0) Max Capacity: 512M(100%) [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,heap ] GC(0) Soft Max Capacity: 512M(100%) [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,heap ] GC(0) Mark Start Mark End Relocate Start Relocate End High Low [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,heap ] GC(0) Capacity: 128M (25%) 128M (25%) 128M (25%) 128M (25%) 128M (25%) 128M (25%) [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,heap ] GC(0) Free: 438M (86%) 410M (80%) 404M (79%) 402M (79%) 438M (86%) 400M (78%) [2024-11-08T18:04:23.477Z] [0.698s][info ][gc,heap ] GC(0) Used: 74M (14%) 102M (20%) 108M (21%) 110M (21%) 112M (22%) 74M (14%) [2024-11-08T18:04:23.477Z] [0.699s][info ][gc,heap ] GC(0) Live: - 67M (13%) 67M (13%) 67M (13%) - - [2024-11-08T18:04:23.477Z] [0.699s][info ][gc,heap ] GC(0) Allocated: - 28M (5%) 34M (7%) 37M (7%) - - [2024-11-08T18:04:23.477Z] [0.699s][info ][gc,heap ] GC(0) Garbage: - 6M (1%) 6M (1%) 4M (1%) - - [2024-11-08T18:04:23.477Z] [0.699s][info ][gc,heap ] GC(0) Reclaimed: - - 0M (0%) 1M (0%) - - [2024-11-08T18:04:23.477Z] [0.699s][info ][gc ] GC(0) Garbage Collection (Warmup) 74M(14%)->110M(21%) [2024-11-08T18:04:23.477Z] [0.723s][debug][gc,heap ] GC(1) Heap before GC invocations=1 (full 1): [2024-11-08T18:04:23.477Z] [0.723s][debug][gc,heap ] GC(1) ZHeap used 126M, capacity 128M, max capacity 512M [2024-11-08T18:04:23.477Z] [0.723s][debug][gc,heap ] GC(1) Metaspace used 572K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.477Z] [0.723s][debug][gc,heap ] GC(1) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.477Z] [0.723s][info ][gc,start ] GC(1) Garbage Collection (Warmup) [2024-11-08T18:04:23.477Z] [0.723s][info ][gc,task ] GC(1) Using 2 workers [2024-11-08T18:04:23.477Z] [0.724s][info ][gc,phases ] GC(1) Pause Mark Start 0.048ms [2024-11-08T18:04:23.477Z] [0.852s][info ][gc,phases ] GC(1) Concurrent Mark 128.512ms [2024-11-08T18:04:23.477Z] [0.855s][info ][gc,phases ] GC(1) Pause Mark End 0.079ms [2024-11-08T18:04:23.477Z] [0.855s][info ][gc,phases ] GC(1) Concurrent Mark Free 0.009ms [2024-11-08T18:04:23.477Z] [0.857s][info ][gc,phases ] GC(1) Concurrent Process Non-Strong References 1.739ms [2024-11-08T18:04:23.477Z] [0.857s][info ][gc,phases ] GC(1) Concurrent Reset Relocation Set 0.011ms [2024-11-08T18:04:23.477Z] [0.864s][info ][gc,phases ] GC(1) Concurrent Select Relocation Set 6.243ms [2024-11-08T18:04:23.477Z] [0.866s][info ][gc,phases ] GC(1) Pause Relocate Start 0.086ms [2024-11-08T18:04:23.477Z] [0.880s][info ][gc,phases ] GC(1) Concurrent Relocate 13.571ms [2024-11-08T18:04:23.477Z] [0.880s][debug][gc,heap ] GC(1) Heap after GC invocations=2 (full 2): [2024-11-08T18:04:23.477Z] [0.880s][debug][gc,heap ] GC(1) ZHeap used 168M, capacity 172M, max capacity 512M [2024-11-08T18:04:23.477Z] [0.880s][debug][gc,heap ] GC(1) Metaspace used 572K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.477Z] [0.881s][debug][gc,heap ] GC(1) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.477Z] [0.881s][info ][gc,load ] GC(1) Load: 2.34/3.16/4.08 [2024-11-08T18:04:23.477Z] [0.881s][info ][gc,mmu ] GC(1) MMU: 2ms/93.7%, 5ms/97.5%, 10ms/98.7%, 20ms/98.9%, 50ms/99.4%, 100ms/99.7% [2024-11-08T18:04:23.477Z] [0.881s][info ][gc,marking ] GC(1) Mark: 2 stripe(s), 3 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2024-11-08T18:04:23.477Z] [0.881s][info ][gc,marking ] GC(1) Mark Stack Usage: 32M [2024-11-08T18:04:23.477Z] [0.881s][info ][gc,nmethod ] GC(1) NMethods: 294 registered, 0 unregistered [2024-11-08T18:04:23.477Z] [0.881s][info ][gc,metaspace] GC(1) Metaspace: 0M used, 0M committed, 1088M reserved [2024-11-08T18:04:23.477Z] [0.881s][info ][gc,ref ] GC(1) Soft: 98 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [0.882s][info ][gc,ref ] GC(1) Weak: 213 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [0.882s][info ][gc,ref ] GC(1) Final: 0 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [0.882s][info ][gc,ref ] GC(1) Phantom: 8 encountered, 6 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [0.882s][info ][gc,reloc ] GC(1) Small Pages: 63 / 126M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [0.882s][info ][gc,reloc ] GC(1) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [0.882s][info ][gc,reloc ] GC(1) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [0.882s][info ][gc,reloc ] GC(1) Forwarding Usage: 0M [2024-11-08T18:04:23.477Z] [0.882s][info ][gc,heap ] GC(1) Min Capacity: 128M(25%) [2024-11-08T18:04:23.477Z] [0.883s][info ][gc,heap ] GC(1) Max Capacity: 512M(100%) [2024-11-08T18:04:23.477Z] [0.883s][info ][gc,heap ] GC(1) Soft Max Capacity: 512M(100%) [2024-11-08T18:04:23.477Z] [0.883s][info ][gc,heap ] GC(1) Mark Start Mark End Relocate Start Relocate End High Low [2024-11-08T18:04:23.477Z] [0.883s][info ][gc,heap ] GC(1) Capacity: 128M (25%) 162M (32%) 166M (32%) 172M (34%) 172M (34%) 128M (25%) [2024-11-08T18:04:23.477Z] [0.883s][info ][gc,heap ] GC(1) Free: 386M (75%) 350M (68%) 346M (68%) 344M (67%) 386M (75%) 340M (66%) [2024-11-08T18:04:23.477Z] [0.883s][info ][gc,heap ] GC(1) Used: 126M (25%) 162M (32%) 166M (32%) 168M (33%) 172M (34%) 126M (25%) [2024-11-08T18:04:23.477Z] [0.883s][info ][gc,heap ] GC(1) Live: - 119M (23%) 119M (23%) 119M (23%) - - [2024-11-08T18:04:23.477Z] [0.884s][info ][gc,heap ] GC(1) Allocated: - 36M (7%) 40M (8%) 42M (8%) - - [2024-11-08T18:04:23.477Z] [0.884s][info ][gc,heap ] GC(1) Garbage: - 6M (1%) 6M (1%) 6M (1%) - - [2024-11-08T18:04:23.477Z] [0.884s][info ][gc,heap ] GC(1) Reclaimed: - - 0M (0%) 0M (0%) - - [2024-11-08T18:04:23.477Z] [0.884s][info ][gc ] GC(1) Garbage Collection (Warmup) 126M(25%)->168M(33%) [2024-11-08T18:04:23.477Z] [0.923s][debug][gc,heap ] GC(2) Heap before GC invocations=2 (full 2): [2024-11-08T18:04:23.477Z] [0.923s][debug][gc,heap ] GC(2) ZHeap used 186M, capacity 186M, max capacity 512M [2024-11-08T18:04:23.477Z] [0.924s][debug][gc,heap ] GC(2) Metaspace used 572K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.477Z] [0.924s][debug][gc,heap ] GC(2) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.477Z] [0.924s][info ][gc,start ] GC(2) Garbage Collection (Warmup) [2024-11-08T18:04:23.477Z] [0.924s][info ][gc,task ] GC(2) Using 2 workers [2024-11-08T18:04:23.477Z] [0.928s][info ][gc,phases ] GC(2) Pause Mark Start 0.086ms [2024-11-08T18:04:23.477Z] [0.989s][info ][gc,phases ] GC(2) Concurrent Mark 60.992ms [2024-11-08T18:04:23.477Z] [0.989s][info ][gc,phases ] GC(2) Pause Mark End 0.037ms [2024-11-08T18:04:23.477Z] [0.990s][info ][gc,phases ] GC(2) Concurrent Mark Free 0.012ms [2024-11-08T18:04:23.477Z] [0.994s][info ][gc,phases ] GC(2) Concurrent Process Non-Strong References 4.399ms [2024-11-08T18:04:23.477Z] [0.994s][info ][gc,phases ] GC(2) Concurrent Reset Relocation Set 0.010ms [2024-11-08T18:04:23.477Z] [1.000s][info ][gc,phases ] GC(2) Concurrent Select Relocation Set 5.837ms [2024-11-08T18:04:23.477Z] [1.001s][info ][gc,phases ] GC(2) Pause Relocate Start 0.036ms [2024-11-08T18:04:23.477Z] [1.011s][info ][gc,phases ] GC(2) Concurrent Relocate 10.479ms [2024-11-08T18:04:23.477Z] [1.011s][debug][gc,heap ] GC(2) Heap after GC invocations=3 (full 3): [2024-11-08T18:04:23.477Z] [1.011s][debug][gc,heap ] GC(2) ZHeap used 228M, capacity 234M, max capacity 512M [2024-11-08T18:04:23.477Z] [1.011s][debug][gc,heap ] GC(2) Metaspace used 572K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.477Z] [1.011s][debug][gc,heap ] GC(2) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,load ] GC(2) Load: 2.34/3.16/4.08 [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,mmu ] GC(2) MMU: 2ms/93.7%, 5ms/97.5%, 10ms/98.7%, 20ms/98.9%, 50ms/99.4%, 100ms/99.7% [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,marking ] GC(2) Mark: 2 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,marking ] GC(2) Mark Stack Usage: 32M [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,nmethod ] GC(2) NMethods: 294 registered, 0 unregistered [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,metaspace] GC(2) Metaspace: 0M used, 0M committed, 1088M reserved [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,ref ] GC(2) Soft: 98 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,ref ] GC(2) Weak: 213 encountered, 70 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,ref ] GC(2) Final: 0 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,ref ] GC(2) Phantom: 8 encountered, 6 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,reloc ] GC(2) Small Pages: 86 / 172M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,reloc ] GC(2) Medium Pages: 1 / 16M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,reloc ] GC(2) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,reloc ] GC(2) Forwarding Usage: 0M [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Min Capacity: 128M(25%) [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Max Capacity: 512M(100%) [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Soft Max Capacity: 512M(100%) [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Mark Start Mark End Relocate Start Relocate End High Low [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Capacity: 188M (37%) 208M (41%) 212M (41%) 218M (43%) 218M (43%) 188M (37%) [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Free: 324M (63%) 304M (59%) 300M (59%) 300M (59%) 324M (63%) 294M (57%) [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Used: 188M (37%) 208M (41%) 212M (41%) 212M (41%) 218M (43%) 188M (37%) [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Live: - 163M (32%) 163M (32%) 163M (32%) - - [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Allocated: - 20M (4%) 24M (5%) 26M (5%) - - [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Garbage: - 24M (5%) 24M (5%) 22M (4%) - - [2024-11-08T18:04:23.477Z] [1.012s][info ][gc,heap ] GC(2) Reclaimed: - - 0M (0%) 2M (0%) - - [2024-11-08T18:04:23.477Z] [1.012s][info ][gc ] GC(2) Garbage Collection (Warmup) 188M(37%)->212M(41%) [2024-11-08T18:04:23.477Z] [0.637s] (MainThread) Reclaiming [2024-11-08T18:04:23.477Z] [1.066s][debug][gc,heap ] GC(3) Heap before GC invocations=3 (full 3): [2024-11-08T18:04:23.477Z] [1.066s][debug][gc,heap ] GC(3) ZHeap used 242M, capacity 242M, max capacity 512M [2024-11-08T18:04:23.477Z] [1.066s][debug][gc,heap ] GC(3) Metaspace used 572K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.477Z] [1.066s][debug][gc,heap ] GC(3) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.477Z] [1.066s][info ][gc,start ] GC(3) Garbage Collection (System.gc()) [2024-11-08T18:04:23.477Z] [1.066s][info ][gc,task ] GC(3) Using 2 workers [2024-11-08T18:04:23.477Z] [1.067s][info ][gc,phases ] GC(3) Pause Mark Start 0.132ms [2024-11-08T18:04:23.477Z] [1.072s][info ][gc,phases ] GC(3) Concurrent Mark 5.107ms [2024-11-08T18:04:23.477Z] [1.072s][info ][gc,phases ] GC(3) Pause Mark End 0.083ms [2024-11-08T18:04:23.477Z] [1.073s][info ][gc,phases ] GC(3) Concurrent Mark Free 0.012ms [2024-11-08T18:04:23.477Z] [1.074s][info ][gc,phases ] GC(3) Concurrent Process Non-Strong References 1.691ms [2024-11-08T18:04:23.477Z] [1.074s][info ][gc,phases ] GC(3) Concurrent Reset Relocation Set 0.010ms [2024-11-08T18:04:23.477Z] [1.082s][info ][gc,phases ] GC(3) Concurrent Select Relocation Set 8.053ms [2024-11-08T18:04:23.477Z] [1.083s][info ][gc,phases ] GC(3) Pause Relocate Start 0.120ms [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,phases ] GC(3) Concurrent Relocate 1.927ms [2024-11-08T18:04:23.477Z] [1.085s][debug][gc,heap ] GC(3) Heap after GC invocations=4 (full 4): [2024-11-08T18:04:23.477Z] [1.085s][debug][gc,heap ] GC(3) ZHeap used 4M, capacity 242M, max capacity 512M [2024-11-08T18:04:23.477Z] [1.085s][debug][gc,heap ] GC(3) Metaspace used 572K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.477Z] [1.085s][debug][gc,heap ] GC(3) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,load ] GC(3) Load: 2.34/3.16/4.08 [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,mmu ] GC(3) MMU: 2ms/93.4%, 5ms/97.4%, 10ms/97.9%, 20ms/98.3%, 50ms/99.3%, 100ms/99.6% [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,marking ] GC(3) Mark: 2 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,marking ] GC(3) Mark Stack Usage: 32M [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,nmethod ] GC(3) NMethods: 294 registered, 0 unregistered [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,metaspace] GC(3) Metaspace: 0M used, 0M committed, 1088M reserved [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,ref ] GC(3) Soft: 98 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,ref ] GC(3) Weak: 213 encountered, 177 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,ref ] GC(3) Final: 0 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,ref ] GC(3) Phantom: 8 encountered, 5 discovered, 0 enqueued [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,reloc ] GC(3) Small Pages: 105 / 210M, Empty: 200M, Relocated: 1M, In-Place: 0 [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,reloc ] GC(3) Medium Pages: 2 / 32M, Empty: 32M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,reloc ] GC(3) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,reloc ] GC(3) Forwarding Usage: 0M [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,heap ] GC(3) Min Capacity: 128M(25%) [2024-11-08T18:04:23.477Z] [1.085s][info ][gc,heap ] GC(3) Max Capacity: 512M(100%) [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Soft Max Capacity: 512M(100%) [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Mark Start Mark End Relocate Start Relocate End High Low [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Capacity: 242M (47%) 242M (47%) 242M (47%) 242M (47%) 242M (47%) 242M (47%) [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Free: 270M (53%) 270M (53%) 502M (98%) 508M (99%) 508M (99%) 270M (53%) [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Used: 242M (47%) 242M (47%) 10M (2%) 4M (1%) 242M (47%) 4M (1%) [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Live: - 1M (0%) 1M (0%) 1M (0%) - - [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Allocated: - 0M (0%) 0M (0%) 0M (0%) - - [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Garbage: - 240M (47%) 8M (2%) 2M (1%) - - [2024-11-08T18:04:23.477Z] [1.086s][info ][gc,heap ] GC(3) Reclaimed: - - 232M (45%) 238M (46%) - - [2024-11-08T18:04:23.477Z] [1.086s][info ][gc ] GC(3) Garbage Collection (System.gc()) 242M(47%)->4M(1%) [2024-11-08T18:04:23.477Z] [0.657s] (MainThread) Waiting for uncommit to start [2024-11-08T18:04:23.477Z] [301.123s][debug][gc,heap ] GC(4) Heap before GC invocations=4 (full 4): [2024-11-08T18:04:23.477Z] [301.123s][debug][gc,heap ] GC(4) ZHeap used 6M, capacity 242M, max capacity 512M [2024-11-08T18:04:23.477Z] [301.123s][debug][gc,heap ] GC(4) Metaspace used 573K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.477Z] [301.123s][debug][gc,heap ] GC(4) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.477Z] [301.123s][info ][gc,start ] GC(4) Garbage Collection (Proactive) [2024-11-08T18:04:23.477Z] [301.123s][info ][gc,task ] GC(4) Using 2 workers [2024-11-08T18:04:23.477Z] [301.123s][info ][gc,phases ] GC(4) Pause Mark Start 0.064ms [2024-11-08T18:04:23.477Z] [301.128s][info ][gc,phases ] GC(4) Concurrent Mark 5.121ms [2024-11-08T18:04:23.477Z] [301.128s][info ][gc,phases ] GC(4) Pause Mark End 0.033ms [2024-11-08T18:04:23.477Z] [301.129s][info ][gc,phases ] GC(4) Concurrent Mark Free 0.009ms [2024-11-08T18:04:23.477Z] [301.130s][info ][gc,phases ] GC(4) Concurrent Process Non-Strong References 0.912ms [2024-11-08T18:04:23.477Z] [301.130s][info ][gc,phases ] GC(4) Concurrent Reset Relocation Set 0.005ms [2024-11-08T18:04:23.477Z] [301.132s][info ][gc,phases ] GC(4) Concurrent Select Relocation Set 2.438ms [2024-11-08T18:04:23.477Z] [301.132s][info ][gc,phases ] GC(4) Pause Relocate Start 0.037ms [2024-11-08T18:04:23.477Z] [301.134s][info ][gc,phases ] GC(4) Concurrent Relocate 1.667ms [2024-11-08T18:04:23.477Z] [301.134s][debug][gc,heap ] GC(4) Heap after GC invocations=5 (full 5): [2024-11-08T18:04:23.478Z] [301.134s][debug][gc,heap ] GC(4) ZHeap used 4M, capacity 242M, max capacity 512M [2024-11-08T18:04:23.478Z] [301.134s][debug][gc,heap ] GC(4) Metaspace used 573K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.478Z] [301.134s][debug][gc,heap ] GC(4) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,load ] GC(4) Load: 2.04/2.43/3.50 [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,mmu ] GC(4) MMU: 2ms/93.4%, 5ms/97.4%, 10ms/97.9%, 20ms/98.3%, 50ms/99.3%, 100ms/99.6% [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,marking ] GC(4) Mark: 2 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,marking ] GC(4) Mark Stack Usage: 32M [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,nmethod ] GC(4) NMethods: 298 registered, 0 unregistered [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,metaspace] GC(4) Metaspace: 0M used, 0M committed, 1088M reserved [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,ref ] GC(4) Soft: 98 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,ref ] GC(4) Weak: 213 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,ref ] GC(4) Final: 0 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,ref ] GC(4) Phantom: 8 encountered, 5 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,reloc ] GC(4) Small Pages: 3 / 6M, Empty: 0M, Relocated: 1M, In-Place: 0 [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,reloc ] GC(4) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,reloc ] GC(4) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,reloc ] GC(4) Forwarding Usage: 0M [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Min Capacity: 128M(25%) [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Max Capacity: 512M(100%) [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Soft Max Capacity: 512M(100%) [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Mark Start Mark End Relocate Start Relocate End High Low [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Capacity: 242M (47%) 242M (47%) 242M (47%) 242M (47%) 242M (47%) 242M (47%) [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Free: 506M (99%) 506M (99%) 506M (99%) 508M (99%) 508M (99%) 504M (98%) [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Used: 6M (1%) 6M (1%) 6M (1%) 4M (1%) 8M (2%) 4M (1%) [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Live: - 1M (0%) 1M (0%) 1M (0%) - - [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Allocated: - 0M (0%) 0M (0%) 0M (0%) - - [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Garbage: - 4M (1%) 4M (1%) 2M (1%) - - [2024-11-08T18:04:23.478Z] [301.134s][info ][gc,heap ] GC(4) Reclaimed: - - 0M (0%) 2M (0%) - - [2024-11-08T18:04:23.478Z] [301.134s][info ][gc ] GC(4) Garbage Collection (Proactive) 6M(1%)->4M(1%) [2024-11-08T18:04:23.478Z] [601.223s][debug][gc,heap ] GC(5) Heap before GC invocations=5 (full 5): [2024-11-08T18:04:23.478Z] [601.223s][debug][gc,heap ] GC(5) ZHeap used 4M, capacity 242M, max capacity 512M [2024-11-08T18:04:23.478Z] [601.223s][debug][gc,heap ] GC(5) Metaspace used 573K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.478Z] [601.223s][debug][gc,heap ] GC(5) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.478Z] [601.223s][info ][gc,start ] GC(5) Garbage Collection (Proactive) [2024-11-08T18:04:23.478Z] [601.223s][info ][gc,task ] GC(5) Using 2 workers [2024-11-08T18:04:23.478Z] [601.223s][info ][gc,phases ] GC(5) Pause Mark Start 0.111ms [2024-11-08T18:04:23.478Z] [601.228s][info ][gc,phases ] GC(5) Concurrent Mark 5.021ms [2024-11-08T18:04:23.478Z] [601.229s][info ][gc,phases ] GC(5) Pause Mark End 0.186ms [2024-11-08T18:04:23.478Z] [601.229s][info ][gc,phases ] GC(5) Concurrent Mark Free 0.009ms [2024-11-08T18:04:23.478Z] [601.230s][info ][gc,phases ] GC(5) Concurrent Process Non-Strong References 1.093ms [2024-11-08T18:04:23.478Z] [601.230s][info ][gc,phases ] GC(5) Concurrent Reset Relocation Set 0.005ms [2024-11-08T18:04:23.478Z] [601.233s][info ][gc,phases ] GC(5) Concurrent Select Relocation Set 2.396ms [2024-11-08T18:04:23.478Z] [601.233s][info ][gc,phases ] GC(5) Pause Relocate Start 0.096ms [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,phases ] GC(5) Concurrent Relocate 1.753ms [2024-11-08T18:04:23.478Z] [601.235s][debug][gc,heap ] GC(5) Heap after GC invocations=6 (full 6): [2024-11-08T18:04:23.478Z] [601.235s][debug][gc,heap ] GC(5) ZHeap used 4M, capacity 242M, max capacity 512M [2024-11-08T18:04:23.478Z] [601.235s][debug][gc,heap ] GC(5) Metaspace used 573K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.478Z] [601.235s][debug][gc,heap ] GC(5) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,load ] GC(5) Load: 2.00/2.15/3.07 [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,mmu ] GC(5) MMU: 2ms/90.7%, 5ms/94.4%, 10ms/96.1%, 20ms/98.0%, 50ms/99.2%, 100ms/99.6% [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,marking ] GC(5) Mark: 2 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,marking ] GC(5) Mark Stack Usage: 32M [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,nmethod ] GC(5) NMethods: 298 registered, 0 unregistered [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,metaspace] GC(5) Metaspace: 0M used, 0M committed, 1088M reserved [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,ref ] GC(5) Soft: 98 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,ref ] GC(5) Weak: 213 encountered, 45 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,ref ] GC(5) Final: 0 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,ref ] GC(5) Phantom: 8 encountered, 6 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,reloc ] GC(5) Small Pages: 2 / 4M, Empty: 0M, Relocated: 1M, In-Place: 0 [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,reloc ] GC(5) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,reloc ] GC(5) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,reloc ] GC(5) Forwarding Usage: 0M [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Min Capacity: 128M(25%) [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Max Capacity: 512M(100%) [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Soft Max Capacity: 512M(100%) [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Mark Start Mark End Relocate Start Relocate End High Low [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Capacity: 242M (47%) 242M (47%) 242M (47%) 242M (47%) 242M (47%) 242M (47%) [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Free: 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) 504M (98%) [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) 8M (2%) 4M (1%) [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Live: - 1M (0%) 1M (0%) 1M (0%) - - [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Allocated: - 0M (0%) 0M (0%) 0M (0%) - - [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Garbage: - 2M (1%) 2M (1%) 2M (1%) - - [2024-11-08T18:04:23.478Z] [601.235s][info ][gc,heap ] GC(5) Reclaimed: - - 0M (0%) 0M (0%) - - [2024-11-08T18:04:23.478Z] [601.235s][info ][gc ] GC(5) Garbage Collection (Proactive) 4M(1%)->4M(1%) [2024-11-08T18:04:23.478Z] [901.322s][debug][gc,heap ] GC(6) Heap before GC invocations=6 (full 6): [2024-11-08T18:04:23.478Z] [901.322s][debug][gc,heap ] GC(6) ZHeap used 4M, capacity 242M, max capacity 512M [2024-11-08T18:04:23.478Z] [901.323s][debug][gc,heap ] GC(6) Metaspace used 573K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.478Z] [901.323s][debug][gc,heap ] GC(6) class space used 42K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.478Z] [901.323s][info ][gc,start ] GC(6) Garbage Collection (Proactive) [2024-11-08T18:04:23.478Z] [901.323s][info ][gc,task ] GC(6) Using 2 workers [2024-11-08T18:04:23.478Z] [901.323s][info ][gc,phases ] GC(6) Pause Mark Start 0.192ms [2024-11-08T18:04:23.478Z] [901.329s][info ][gc,phases ] GC(6) Concurrent Mark 5.980ms [2024-11-08T18:04:23.478Z] [901.329s][info ][gc,phases ] GC(6) Pause Mark End 0.085ms [2024-11-08T18:04:23.478Z] [901.330s][info ][gc,phases ] GC(6) Concurrent Mark Free 0.003ms [2024-11-08T18:04:23.478Z] [901.332s][info ][gc,phases ] GC(6) Concurrent Process Non-Strong References 2.107ms [2024-11-08T18:04:23.478Z] [901.332s][info ][gc,phases ] GC(6) Concurrent Reset Relocation Set 0.006ms [2024-11-08T18:04:23.478Z] [901.333s][info ][gc,phases ] GC(6) Concurrent Select Relocation Set 1.578ms [2024-11-08T18:04:23.478Z] [901.334s][info ][gc,phases ] GC(6) Pause Relocate Start 0.069ms [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,phases ] GC(6) Concurrent Relocate 2.279ms [2024-11-08T18:04:23.478Z] [901.336s][debug][gc,heap ] GC(6) Heap after GC invocations=7 (full 7): [2024-11-08T18:04:23.478Z] [901.336s][debug][gc,heap ] GC(6) ZHeap used 4M, capacity 242M, max capacity 512M [2024-11-08T18:04:23.478Z] [901.336s][debug][gc,heap ] GC(6) Metaspace used 551K, committed 704K, reserved 1114112K [2024-11-08T18:04:23.478Z] [901.336s][debug][gc,heap ] GC(6) class space used 36K, committed 128K, reserved 1048576K [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,load ] GC(6) Load: 2.00/2.06/2.77 [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,mmu ] GC(6) MMU: 2ms/90.4%, 5ms/94.4%, 10ms/96.1%, 20ms/98.0%, 50ms/99.2%, 100ms/99.6% [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,marking ] GC(6) Mark: 2 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,marking ] GC(6) Mark Stack Usage: 32M [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,nmethod ] GC(6) NMethods: 298 registered, 0 unregistered [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,metaspace] GC(6) Metaspace: 0M used, 0M committed, 1088M reserved [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,ref ] GC(6) Soft: 85 encountered, 54 discovered, 13 enqueued [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,ref ] GC(6) Weak: 213 encountered, 6 discovered, 6 enqueued [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,ref ] GC(6) Final: 0 encountered, 0 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,ref ] GC(6) Phantom: 8 encountered, 6 discovered, 0 enqueued [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,reloc ] GC(6) Small Pages: 2 / 4M, Empty: 0M, Relocated: 1M, In-Place: 0 [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,reloc ] GC(6) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,reloc ] GC(6) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,reloc ] GC(6) Forwarding Usage: 0M [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Min Capacity: 128M(25%) [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Max Capacity: 512M(100%) [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Soft Max Capacity: 512M(100%) [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Mark Start Mark End Relocate Start Relocate End High Low [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Capacity: 242M (47%) 242M (47%) 242M (47%) 242M (47%) 242M (47%) 242M (47%) [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Free: 508M (99%) 508M (99%) 508M (99%) 508M (99%) 508M (99%) 504M (98%) [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Used: 4M (1%) 4M (1%) 4M (1%) 4M (1%) 8M (2%) 4M (1%) [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Live: - 1M (0%) 1M (0%) 1M (0%) - - [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Allocated: - 0M (0%) 0M (0%) 0M (0%) - - [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Garbage: - 2M (1%) 2M (1%) 2M (1%) - - [2024-11-08T18:04:23.478Z] [901.336s][info ][gc,heap ] GC(6) Reclaimed: - - 0M (0%) 0M (0%) - - [2024-11-08T18:04:23.478Z] [901.336s][info ][gc ] GC(6) Garbage Collection (Proactive) 4M(1%)->4M(1%) [2024-11-08T18:04:23.478Z] Timeout signalled after 960 seconds [2024-11-08T18:04:23.478Z] STDERR: [2024-11-08T18:04:23.478Z] rerun: [2024-11-08T18:04:23.478Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/scratch/0 && \ [2024-11-08T18:04:23.478Z] DISPLAY=:0 \ [2024-11-08T18:04:23.478Z] HOME=/home/jenkins \ [2024-11-08T18:04:23.478Z] LANG=en_US.UTF-8 \ [2024-11-08T18:04:23.478Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-11-08T18:04:23.478Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2024-11-08T18:04:23.478Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/../bin/java \ [2024-11-08T18:04:23.478Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2024-11-08T18:04:23.478Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2024-11-08T18:04:23.478Z] -Dtest.compiler.opts= \ [2024-11-08T18:04:23.478Z] -Dtest.java.opts= \ [2024-11-08T18:04:23.478Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/.. \ [2024-11-08T18:04:23.478Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/.. \ [2024-11-08T18:04:23.478Z] -Dtest.timeout.factor=8.0 \ [2024-11-08T18:04:23.478Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-11-08T18:04:23.478Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2024-11-08T18:04:23.478Z] -Dtest.name=gc/z/TestUncommit.java \ [2024-11-08T18:04:23.478Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestUncommit.java \ [2024-11-08T18:04:23.478Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \ [2024-11-08T18:04:23.478Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-11-08T18:04:23.478Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d \ [2024-11-08T18:04:23.478Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/test/lib \ [2024-11-08T18:04:23.478Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/gc/z/TestUncommit.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/classes/1/test/lib \ [2024-11-08T18:04:23.478Z] -ea \ [2024-11-08T18:04:23.478Z] -esa \ [2024-11-08T18:04:23.478Z] -Xmx512m \ [2024-11-08T18:04:23.478Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-11-08T18:04:23.478Z] -XX:+UseZGC \ [2024-11-08T18:04:23.478Z] -Xlog:gc*,gc+heap=debug,gc+stats=off \ [2024-11-08T18:04:23.478Z] -Xms128M \ [2024-11-08T18:04:23.480Z] -Xmx512M \ [2024-11-08T18:04:23.480Z] -XX:ZUncommitDelay=10 \ [2024-11-08T18:04:23.480Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work/gc/z/TestUncommit.d/main.0.jta [2024-11-08T18:04:23.480Z] [2024-11-08T18:04:23.480Z] TEST RESULT: Error. Program `/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.14+2/bin/../bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960519ms). [2024-11-08T18:04:23.480Z] -------------------------------------------------- [2024-11-08T18:11:34.669Z] Test results: passed: 498; error: 1 [2024-11-08T18:11:49.161Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/report/html/report.html [2024-11-08T18:11:49.161Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_linux_testList_0/aqa-tests/TKG/output_17310832427050/hotspot_gc_0/work [2024-11-08T18:11:49.161Z] Error: Some tests failed or other problems occurred. [2024-11-08T18:11:49.161Z] ----------------------------------- [2024-11-08T18:11:49.161Z] hotspot_gc_0_FAILED [2024-11-08T18:11:49.161Z] ----------------------------------- [2024-11-08T18:11:49.161Z] [2024-11-08T18:11:49.161Z] TEST TEARDOWN: [2024-11-08T18:11:49.161Z] Nothing to be done for teardown. [2024-11-08T18:11:49.161Z] hotspot_gc_0 Finish Time: Fri Nov 8 12:11:49 2024 Epoch Time (ms): 1731089509046