[2025-04-16T08:04:32.271Z] Running test hotspot_gc_0 ... [2025-04-16T08:04:32.271Z] =============================================== [2025-04-16T08:04:32.271Z] hotspot_gc_0 Start Time: Wed Apr 16 08:04:32 2025 Epoch Time (ms): 1744790672076 [2025-04-16T08:04:32.271Z] variation: NoOptions [2025-04-16T08:04:32.271Z] JVM_OPTIONS: [2025-04-16T08:04:32.271Z] { \ [2025-04-16T08:04:32.271Z] echo ""; echo "TEST SETUP:"; \ [2025-04-16T08:04:32.271Z] echo "Nothing to be done for setup."; \ [2025-04-16T08:04:32.271Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../TKG/output_17447862283989/hotspot_gc_0"; \ [2025-04-16T08:04:32.271Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../TKG/output_17447862283989/hotspot_gc_0"; \ [2025-04-16T08:04:32.271Z] echo ""; echo "TESTING:"; \ [2025-04-16T08:04:32.271Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-04-16T08:04:32.271Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:1 -nativepath:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2025-04-16T08:04:32.271Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../TKG/output_17447862283989/hotspot_gc_0"/work" \ [2025-04-16T08:04:32.271Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../TKG/output_17447862283989/hotspot_gc_0"/report" \ [2025-04-16T08:04:32.271Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image" \ [2025-04-16T08:04:32.271Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2025-04-16T08:04:32.271Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2025-04-16T08:04:32.271Z] \ [2025-04-16T08:04:32.271Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2025-04-16T08:04:32.271Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \ [2025-04-16T08:04:32.271Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-16T08:04:32.271Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-16T08:04:32.271Z] echo "Nothing to be done for teardown."; \ [2025-04-16T08:04:32.272Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../TKG/output_17447862283989/TestTargetResult"; [2025-04-16T08:04:32.272Z] [2025-04-16T08:04:32.272Z] TEST SETUP: [2025-04-16T08:04:32.272Z] Nothing to be done for setup. [2025-04-16T08:04:32.272Z] [2025-04-16T08:04:32.272Z] TESTING: [2025-04-16T08:04:33.054Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../TKG/output_17447862283989/hotspot_gc_0/work" not found: creating [2025-04-16T08:04:33.054Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/../TKG/output_17447862283989/hotspot_gc_0/report" not found: creating [2025-04-16T08:04:37.555Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work [2025-04-16T08:20:58.032Z] -------------------------------------------------- [2025-04-16T08:20:58.032Z] TEST: gc/g1/TestSkipRebuildRemsetPhase.java [2025-04-16T08:20:58.032Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image [2025-04-16T08:20:58.032Z] [2025-04-16T08:20:58.032Z] ACTION: build -- Passed. All files up to date [2025-04-16T08:20:58.032Z] REASON: User specified action: run build jdk.test.whitebox.WhiteBox [2025-04-16T08:20:58.032Z] TIME: 0.0 seconds [2025-04-16T08:20:58.032Z] messages: [2025-04-16T08:20:58.032Z] command: build jdk.test.whitebox.WhiteBox [2025-04-16T08:20:58.032Z] reason: User specified action: run build jdk.test.whitebox.WhiteBox [2025-04-16T08:20:58.032Z] started: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.032Z] finished: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.032Z] elapsed time (seconds): 0.0 [2025-04-16T08:20:58.032Z] [2025-04-16T08:20:58.032Z] ACTION: build -- Passed. All files up to date [2025-04-16T08:20:58.032Z] REASON: Named class compiled on demand [2025-04-16T08:20:58.032Z] TIME: 0.001 seconds [2025-04-16T08:20:58.032Z] messages: [2025-04-16T08:20:58.032Z] command: build jdk.test.lib.helpers.ClassFileInstaller [2025-04-16T08:20:58.032Z] reason: Named class compiled on demand [2025-04-16T08:20:58.032Z] started: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.032Z] finished: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.032Z] elapsed time (seconds): 0.001 [2025-04-16T08:20:58.032Z] [2025-04-16T08:20:58.032Z] ACTION: driver -- Passed. Execution successful [2025-04-16T08:20:58.032Z] REASON: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-04-16T08:20:58.032Z] TIME: 0.205 seconds [2025-04-16T08:20:58.032Z] messages: [2025-04-16T08:20:58.032Z] command: driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-04-16T08:20:58.032Z] reason: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-04-16T08:20:58.032Z] started: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.032Z] Mode: agentvm [2025-04-16T08:20:58.032Z] Agent id: 21 [2025-04-16T08:20:58.032Z] finished: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.032Z] elapsed time (seconds): 0.205 [2025-04-16T08:20:58.032Z] configuration: [2025-04-16T08:20:58.032Z] Boot Layer [2025-04-16T08:20:58.032Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-04-16T08:20:58.032Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-04-16T08:20:58.032Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2025-04-16T08:20:58.033Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/patches/java.base [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] Test Layer [2025-04-16T08:20:58.033Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] rerun: [2025-04-16T08:20:58.033Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/scratch && \ [2025-04-16T08:20:58.033Z] DISPLAY=unix:0 \ [2025-04-16T08:20:58.033Z] HOME=/home/jenkins \ [2025-04-16T08:20:58.033Z] LANG=en_US \ [2025-04-16T08:20:58.033Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-04-16T08:20:58.033Z] TZ=Etc/UTC \ [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image/bin/java \ [2025-04-16T08:20:58.033Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-04-16T08:20:58.033Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-04-16T08:20:58.033Z] -Dtest.compiler.opts= \ [2025-04-16T08:20:58.033Z] -Dtest.java.opts= \ [2025-04-16T08:20:58.033Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image \ [2025-04-16T08:20:58.033Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image \ [2025-04-16T08:20:58.033Z] -Dtest.timeout.factor=8.0 \ [2025-04-16T08:20:58.033Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-04-16T08:20:58.033Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-04-16T08:20:58.033Z] -Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-04-16T08:20:58.033Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-04-16T08:20:58.033Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2025-04-16T08:20:58.033Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-04-16T08:20:58.033Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2025-04-16T08:20:58.033Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib \ [2025-04-16T08:20:58.033Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib \ [2025-04-16T08:20:58.033Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-04-16T08:20:58.033Z] jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-04-16T08:20:58.033Z] STDERR: [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] JavaTest Message: Test complete. [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] ACTION: build -- Passed. Build successful [2025-04-16T08:20:58.033Z] REASON: Named class compiled on demand [2025-04-16T08:20:58.033Z] TIME: 0.22 seconds [2025-04-16T08:20:58.033Z] messages: [2025-04-16T08:20:58.033Z] command: build gc.g1.TestSkipRebuildRemsetPhase [2025-04-16T08:20:58.033Z] reason: Named class compiled on demand [2025-04-16T08:20:58.033Z] started: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.033Z] Test directory: [2025-04-16T08:20:58.033Z] compile: gc.g1.TestSkipRebuildRemsetPhase [2025-04-16T08:20:58.033Z] finished: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.033Z] elapsed time (seconds): 0.22 [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] ACTION: compile -- Passed. Compilation successful [2025-04-16T08:20:58.033Z] REASON: .class file out of date or does not exist [2025-04-16T08:20:58.033Z] TIME: 0.22 seconds [2025-04-16T08:20:58.033Z] messages: [2025-04-16T08:20:58.033Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java [2025-04-16T08:20:58.033Z] reason: .class file out of date or does not exist [2025-04-16T08:20:58.033Z] started: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.033Z] Mode: agentvm [2025-04-16T08:20:58.033Z] Agent id: 1 [2025-04-16T08:20:58.033Z] finished: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.033Z] elapsed time (seconds): 0.22 [2025-04-16T08:20:58.033Z] configuration: [2025-04-16T08:20:58.033Z] Boot Layer (javac runtime environment) [2025-04-16T08:20:58.033Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-04-16T08:20:58.033Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/patches/java.base [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] javac compilation environment [2025-04-16T08:20:58.033Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-04-16T08:20:58.033Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] rerun: [2025-04-16T08:20:58.033Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/scratch && \ [2025-04-16T08:20:58.033Z] DISPLAY=unix:0 \ [2025-04-16T08:20:58.033Z] HOME=/home/jenkins \ [2025-04-16T08:20:58.033Z] LANG=en_US \ [2025-04-16T08:20:58.033Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-04-16T08:20:58.033Z] TZ=Etc/UTC \ [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image/bin/javac \ [2025-04-16T08:20:58.033Z] -J-ea \ [2025-04-16T08:20:58.033Z] -J-esa \ [2025-04-16T08:20:58.033Z] -J-Xmx512m \ [2025-04-16T08:20:58.033Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-04-16T08:20:58.033Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-04-16T08:20:58.033Z] -J-Dtest.compiler.opts= \ [2025-04-16T08:20:58.033Z] -J-Dtest.java.opts= \ [2025-04-16T08:20:58.033Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image \ [2025-04-16T08:20:58.033Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image \ [2025-04-16T08:20:58.033Z] -J-Dtest.timeout.factor=8.0 \ [2025-04-16T08:20:58.033Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-04-16T08:20:58.033Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-04-16T08:20:58.033Z] -J-Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-04-16T08:20:58.033Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-04-16T08:20:58.033Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2025-04-16T08:20:58.033Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-04-16T08:20:58.033Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2025-04-16T08:20:58.033Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib \ [2025-04-16T08:20:58.033Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib \ [2025-04-16T08:20:58.033Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2025-04-16T08:20:58.033Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-04-16T08:20:58.033Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] ACTION: driver -- Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2025-04-16T08:20:58.033Z] REASON: User specified action: run driver gc.g1.TestSkipRebuildRemsetPhase [2025-04-16T08:20:58.033Z] TIME: 1.439 seconds [2025-04-16T08:20:58.033Z] messages: [2025-04-16T08:20:58.033Z] command: driver gc.g1.TestSkipRebuildRemsetPhase [2025-04-16T08:20:58.033Z] reason: User specified action: run driver gc.g1.TestSkipRebuildRemsetPhase [2025-04-16T08:20:58.033Z] started: Wed Apr 16 08:20:52 UTC 2025 [2025-04-16T08:20:58.033Z] Mode: agentvm [2025-04-16T08:20:58.033Z] Agent id: 21 [2025-04-16T08:20:58.033Z] finished: Wed Apr 16 08:20:54 UTC 2025 [2025-04-16T08:20:58.033Z] elapsed time (seconds): 1.439 [2025-04-16T08:20:58.033Z] configuration: [2025-04-16T08:20:58.033Z] Boot Layer [2025-04-16T08:20:58.033Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2025-04-16T08:20:58.033Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/patches/java.base [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] Test Layer [2025-04-16T08:20:58.033Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:58.033Z] rerun: [2025-04-16T08:20:58.033Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/scratch && \ [2025-04-16T08:20:58.033Z] DISPLAY=unix:0 \ [2025-04-16T08:20:58.033Z] HOME=/home/jenkins \ [2025-04-16T08:20:58.033Z] LANG=en_US \ [2025-04-16T08:20:58.033Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-04-16T08:20:58.033Z] TZ=Etc/UTC \ [2025-04-16T08:20:58.033Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image/bin/java \ [2025-04-16T08:20:58.033Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-04-16T08:20:58.033Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-04-16T08:20:58.033Z] -Dtest.compiler.opts= \ [2025-04-16T08:20:58.033Z] -Dtest.java.opts= \ [2025-04-16T08:20:58.033Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image \ [2025-04-16T08:20:58.033Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image \ [2025-04-16T08:20:58.033Z] -Dtest.timeout.factor=8.0 \ [2025-04-16T08:20:58.033Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-04-16T08:20:58.033Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-04-16T08:20:58.033Z] -Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-04-16T08:20:58.033Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-04-16T08:20:58.033Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2025-04-16T08:20:58.033Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-04-16T08:20:58.033Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2025-04-16T08:20:58.033Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib \ [2025-04-16T08:20:58.033Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib \ [2025-04-16T08:20:58.033Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-04-16T08:20:58.033Z] gc.g1.TestSkipRebuildRemsetPhase [2025-04-16T08:20:58.033Z] STDOUT: [2025-04-16T08:20:58.033Z] Command line: [/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jdkbinary/j2sdk-image/bin/java -cp /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jtreg.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar -Xbootclasspath/a:. -XX:+UseG1GC -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI -XX:G1MixedGCLiveThresholdPercent=20 -Xlog:gc+marking=debug,gc+phases=debug,gc+remset+tracking=trace -Xms10M -Xmx10M gc.g1.TestSkipRebuildRemsetPhase$GCTest ] [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:52.886223Z] Gathering output for process 19071418 [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:54.021935Z] Waiting for completion for process 19071418 [2025-04-16T08:20:58.033Z] [2025-04-16T08:20:54.022644Z] Waiting for completion finished for process 19071418 [2025-04-16T08:20:58.033Z] STDERR: [2025-04-16T08:20:58.033Z] stdout: [[0.118s][debug][gc,phases] GC(0) Phase 1: Reference Processing 0.113ms [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) VM Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) ObjectSynchronizer Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) JVMTI Tag Weak OopStorage Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) StringTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.3, Max: 1, Diff: 1, Sum: 1, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 131, Avg: 726.3, Max: 1344, Diff: 1213, Sum: 2179, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) ResolvedMethodTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) JNI Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) StringDedup Table Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) StringDedup Requests0 Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) StringDedup Requests1 Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Phase 1: Weak Processing 0.336ms [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) ClassLoaderData 0.001ms [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Trigger cleanups 0.000ms [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Phase 1: Class Unloading and Cleanup 0.168ms [2025-04-16T08:20:58.033Z] [0.118s][debug][gc,phases] GC(0) Report Object Count 0.001ms [2025-04-16T08:20:58.033Z] [0.118s][info ][gc,phases] GC(0) Phase 1: Mark live objects 1.892ms [2025-04-16T08:20:58.033Z] [0.118s][info ][gc,phases] GC(0) Phase 2: Prepare for compaction 0.233ms [2025-04-16T08:20:58.033Z] [0.119s][info ][gc,phases] GC(0) Phase 3: Adjust pointers 0.585ms [2025-04-16T08:20:58.033Z] [0.119s][info ][gc,phases] GC(0) Phase 4: Compact heap 0.430ms [2025-04-16T08:20:58.033Z] [0.120s][info ][gc,phases] GC(1) Pre Evacuate Collection Set: 0.0ms [2025-04-16T08:20:58.033Z] [0.120s][debug][gc,phases] GC(1) Prepare TLABs: 0.0ms [2025-04-16T08:20:58.033Z] [0.120s][debug][gc,phases] GC(1) Concatenate Dirty Card Logs: 0.0ms [2025-04-16T08:20:58.033Z] [0.120s][debug][gc,phases] GC(1) Choose Collection Set: 0.0ms [2025-04-16T08:20:58.033Z] [0.120s][debug][gc,phases] GC(1) Region Register: 0.0ms [2025-04-16T08:20:58.033Z] [0.120s][debug][gc,phases] GC(1) Prepare Heap Roots: 0.0ms [2025-04-16T08:20:58.033Z] [0.120s][debug][gc,phases] GC(1) Clear Claimed Marks: 0.0ms [2025-04-16T08:20:58.033Z] [0.120s][info ][gc,phases] GC(1) Merge Heap Roots: 0.0ms [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Prepare Merge Heap Roots: 0.0ms [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Eager Reclaim (ms): skipped [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Remembered Sets (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Merged Sparse: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Merged Fine: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Merged Coarse: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Hot Card Cache (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Log Buffers (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][info ][gc,phases] GC(1) Evacuate Collection Set: 0.2ms [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 0.2, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Scan Heap Roots (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Scanned Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Scanned Blocks: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Claimed Chunks: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Code Root Scan (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 2, Workers: 2 [2025-04-16T08:20:58.034Z] [0.120s][debug][gc,phases] GC(1) GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) GC Worker Total (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.2, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][info ][gc,phases] GC(1) Post Evacuate Collection Set: 0.2ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Code Roots Fixup: 0.0ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Reference Processing: 0.0ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Weak Processing: 0.1ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) VM Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) ObjectSynchronizer Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) JVMTI Tag Weak OopStorage Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) StringTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 726.3, Max: 1283, Diff: 1283, Sum: 2179, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) ResolvedMethodTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) JNI Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) StringDedup Table Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) StringDedup Requests0 Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) StringDedup Requests1 Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Post Evacuate Cleanup 1: 0.0ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Merge Per-Thread State (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 1 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Copied Bytes Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) LAB Waste Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) LAB Undo Waste Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Clear Logged Cards (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Recalculate Used Memory (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 1 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Post Evacuate Cleanup 2: 0.0ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Reset Hot Card Cache (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 1 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Purge Code Roots (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 1 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Update Derived Pointers (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 1 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Redirty Logged Cards (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Redirtied Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Free Collection Set (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 2 [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Rebuild Free List: 0.0ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Start New Collection Set: 0.0ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Resize TLABs: 0.0ms [2025-04-16T08:20:58.034Z] [0.121s][debug][gc,phases] GC(1) Expand Heap After Collection: 0.0ms [2025-04-16T08:20:58.034Z] [0.121s][info ][gc,phases] GC(1) Other: 0.1ms [2025-04-16T08:20:58.034Z] [0.121s][info ][gc,marking] GC(2) Concurrent Clear Claimed Marks [2025-04-16T08:20:58.034Z] [0.121s][info ][gc,marking] GC(2) Concurrent Clear Claimed Marks 0.014ms [2025-04-16T08:20:58.034Z] [0.121s][info ][gc,marking] GC(2) Concurrent Scan Root Regions [2025-04-16T08:20:58.034Z] [0.121s][info ][gc,marking] GC(2) Concurrent Scan Root Regions 0.005ms [2025-04-16T08:20:58.034Z] [0.121s][info ][gc,marking] GC(2) Concurrent Mark [2025-04-16T08:20:58.034Z] [0.121s][info ][gc,marking] GC(2) Concurrent Mark From Roots [2025-04-16T08:20:58.034Z] [0.122s][info ][gc,marking] GC(2) Concurrent Mark From Roots 1.531ms [2025-04-16T08:20:58.034Z] [0.122s][info ][gc,marking] GC(2) Concurrent Preclean [2025-04-16T08:20:58.034Z] [0.123s][info ][gc,marking] GC(2) Concurrent Preclean 0.310ms [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Finalize Marking 0.052ms [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Reference Processing 0.014ms [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) VM Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) ObjectSynchronizer Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) JVMTI Tag Weak OopStorage Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) StringTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 726.3, Max: 1219, Diff: 1219, Sum: 2179, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) ResolvedMethodTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) JNI Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) StringDedup Table Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) StringDedup Requests0 Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) StringDedup Requests1 Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Weak Processing 0.264ms [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) ClassLoaderData 0.001ms [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Trigger cleanups 0.000ms [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Class Unloading 0.209ms [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Flush Task Caches 0.022ms [2025-04-16T08:20:58.034Z] [0.123s][trace][gc,remset,tracking] GC(2) Before rebuild region 0 (ntams: 0x00000000f00cf0a8) total_live_bytes 848040 selected false (live_bytes 848040 next_marked 0 marked 0 type OLD) [2025-04-16T08:20:58.034Z] [0.123s][trace][gc,remset,tracking] GC(2) Before rebuild region 1 (ntams: 0x00000000f01174e0) total_live_bytes 95456 selected true (live_bytes 95456 next_marked 0 marked 0 type OLD) [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,remset,tracking] GC(2) Remembered Set Tracking update regions total 32, selected 1 [2025-04-16T08:20:58.034Z] [0.123s][debug][gc,phases ] GC(2) Update Remembered Set Tracking Before Rebuild 0.066ms [2025-04-16T08:20:58.034Z] [0.124s][debug][gc,phases ] GC(2) Reclaim Empty Regions 0.019ms [2025-04-16T08:20:58.034Z] [0.124s][debug][gc,phases ] GC(2) Purge Metaspace 0.001ms [2025-04-16T08:20:58.034Z] [0.124s][debug][gc,phases ] GC(2) Report Object Count 0.001ms [2025-04-16T08:20:58.034Z] [0.124s][info ][gc,marking ] GC(2) Concurrent Mark 2.632ms [2025-04-16T08:20:58.034Z] [0.124s][info ][gc,marking ] GC(2) Concurrent Rebuild Remembered Sets [2025-04-16T08:20:58.034Z] [0.124s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 848040 time 0.002ms marked bytes 349544 bot 0x00000000f0000000 TAMS 0x00000000f00cf0a8 TARS 0x00000000f00cf0a8 [2025-04-16T08:20:58.034Z] [0.124s][trace][gc,remset,tracking] GC(2) Rebuilt region 1 live 95456 time 0.122ms marked bytes 95456 bot 0x00000000f0100000 TAMS 0x00000000f01174e0 TARS 0x00000000f01174e0 [2025-04-16T08:20:58.034Z] [0.124s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 848040 time 0.155ms marked bytes 183800 bot 0x00000000f0000000 TAMS 0x00000000f00cf0a8 TARS 0x00000000f00cf0a8 [2025-04-16T08:20:58.034Z] [0.124s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 848040 time 0.303ms marked bytes 253112 bot 0x00000000f0000000 TAMS 0x00000000f00cf0a8 TARS 0x00000000f00cf0a8 [2025-04-16T08:20:58.034Z] [0.124s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 848040 time 0.078ms marked bytes 61584 bot 0x00000000f0000000 TAMS 0x00000000f00cf0a8 TARS 0x00000000f00cf0a8 [2025-04-16T08:20:58.034Z] [0.124s][info ][gc,marking ] GC(2) Concurrent Rebuild Remembered Sets 0.598ms [2025-04-16T08:20:58.034Z] [0.124s][trace][gc,remset,tracking] GC(2) After rebuild region 0 (ntams 0x00000000f0000000 liveness 848040 next_marked_bytes 0 remset occ 0 size 3128) [2025-04-16T08:20:58.034Z] [0.124s][trace][gc,remset,tracking] GC(2) After rebuild region 1 (ntams 0x00000000f0100000 liveness 95456 next_marked_bytes 0 remset occ 32 size 3264) [2025-04-16T08:20:58.034Z] [0.124s][debug][gc,phases ] GC(2) Update Remembered Set Tracking After Rebuild 0.016ms [2025-04-16T08:20:58.034Z] [0.124s][debug][gc,phases ] GC(2) Finalize Concurrent Mark Cleanup 0.026ms [2025-04-16T08:20:58.034Z] [0.124s][info ][gc,marking ] GC(2) Concurrent Cleanup for Next Mark [2025-04-16T08:20:58.034Z] [0.124s][info ][gc,marking ] GC(2) Concurrent Cleanup for Next Mark 0.108ms [2025-04-16T08:20:58.034Z] [B@2437c6dc [2025-04-16T08:20:58.034Z] ]; [2025-04-16T08:20:58.034Z] stderr: [] [2025-04-16T08:20:58.034Z] exitValue = 0 [2025-04-16T08:20:58.034Z] [2025-04-16T08:20:58.034Z] java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2025-04-16T08:20:58.034Z] [2025-04-16T08:20:58.034Z] at jdk.test.lib.process.OutputAnalyzer.shouldContain(OutputAnalyzer.java:221) [2025-04-16T08:20:58.034Z] at gc.g1.TestSkipRebuildRemsetPhase.main(TestSkipRebuildRemsetPhase.java:54) [2025-04-16T08:20:58.034Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2025-04-16T08:20:58.034Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2025-04-16T08:20:58.035Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2025-04-16T08:20:58.035Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2025-04-16T08:20:58.035Z] at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333) [2025-04-16T08:20:58.035Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-04-16T08:20:58.035Z] [2025-04-16T08:20:58.035Z] JavaTest Message: Test threw exception: java.lang.RuntimeException [2025-04-16T08:20:58.035Z] JavaTest Message: shutting down test [2025-04-16T08:20:58.035Z] [2025-04-16T08:20:58.035Z] [2025-04-16T08:20:58.035Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2025-04-16T08:20:58.035Z] -------------------------------------------------- [2025-04-16T09:26:17.893Z] Test results: passed: 227; failed: 1 [2025-04-16T09:26:20.400Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/report/html/report.html [2025-04-16T09:26:20.400Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_rerun/aqa-tests/TKG/output_17447862283989/hotspot_gc_0/work [2025-04-16T09:26:20.400Z] Error: Some tests failed or other problems occurred. [2025-04-16T09:26:20.400Z] ----------------------------------- [2025-04-16T09:26:20.401Z] hotspot_gc_0_FAILED [2025-04-16T09:26:20.401Z] ----------------------------------- [2025-04-16T09:26:20.401Z] [2025-04-16T09:26:20.401Z] TEST TEARDOWN: [2025-04-16T09:26:20.401Z] Nothing to be done for teardown. [2025-04-16T09:26:20.401Z] hotspot_gc_0 Finish Time: Wed Apr 16 09:26:19 2025 Epoch Time (ms): 1744795579996