[2025-05-29T02:56:09.657Z] Running test hotspot_gc_0 ... [2025-05-29T02:56:09.657Z] =============================================== [2025-05-29T02:56:09.657Z] hotspot_gc_0 Start Time: Thu May 29 02:56:09 2025 Epoch Time (ms): 1748487369441 [2025-05-29T02:56:09.657Z] variation: NoOptions [2025-05-29T02:56:09.657Z] JVM_OPTIONS: [2025-05-29T02:56:09.657Z] { \ [2025-05-29T02:56:09.657Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T02:56:09.657Z] echo "Nothing to be done for setup."; \ [2025-05-29T02:56:09.657Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../TKG/output_17484873688889/hotspot_gc_0"; \ [2025-05-29T02:56:09.657Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../TKG/output_17484873688889/hotspot_gc_0"; \ [2025-05-29T02:56:09.657Z] echo ""; echo "TESTING:"; \ [2025-05-29T02:56:09.657Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-05-29T02:56:09.657Z] -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_testList_3/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2025-05-29T02:56:09.657Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../TKG/output_17484873688889/hotspot_gc_0"/work" \ [2025-05-29T02:56:09.657Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../TKG/output_17484873688889/hotspot_gc_0"/report" \ [2025-05-29T02:56:09.657Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image" \ [2025-05-29T02:56:09.657Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2025-05-29T02:56:09.657Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2025-05-29T02:56:09.657Z] \ [2025-05-29T02:56:09.657Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2025-05-29T02:56:09.657Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \ [2025-05-29T02:56:09.657Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T02:56:09.657Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T02:56:09.657Z] echo "Nothing to be done for teardown."; \ [2025-05-29T02:56:09.657Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../TKG/output_17484873688889/TestTargetResult"; [2025-05-29T02:56:09.657Z] [2025-05-29T02:56:09.657Z] TEST SETUP: [2025-05-29T02:56:09.657Z] Nothing to be done for setup. [2025-05-29T02:56:09.657Z] [2025-05-29T02:56:09.657Z] TESTING: [2025-05-29T02:56:10.436Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../TKG/output_17484873688889/hotspot_gc_0/work" not found: creating [2025-05-29T02:56:10.436Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/../TKG/output_17484873688889/hotspot_gc_0/report" not found: creating [2025-05-29T02:56:16.122Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work [2025-05-29T03:12:35.362Z] -------------------------------------------------- [2025-05-29T03:12:35.362Z] TEST: gc/g1/TestSkipRebuildRemsetPhase.java [2025-05-29T03:12:35.362Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image [2025-05-29T03:12:35.362Z] [2025-05-29T03:12:35.362Z] ACTION: build -- Passed. All files up to date [2025-05-29T03:12:35.362Z] REASON: User specified action: run build jdk.test.whitebox.WhiteBox [2025-05-29T03:12:35.362Z] TIME: 0.0 seconds [2025-05-29T03:12:35.362Z] messages: [2025-05-29T03:12:35.362Z] command: build jdk.test.whitebox.WhiteBox [2025-05-29T03:12:35.362Z] reason: User specified action: run build jdk.test.whitebox.WhiteBox [2025-05-29T03:12:35.362Z] started: Thu May 29 03:12:30 UTC 2025 [2025-05-29T03:12:35.362Z] finished: Thu May 29 03:12:30 UTC 2025 [2025-05-29T03:12:35.362Z] elapsed time (seconds): 0.0 [2025-05-29T03:12:35.362Z] [2025-05-29T03:12:35.362Z] ACTION: build -- Passed. All files up to date [2025-05-29T03:12:35.362Z] REASON: Named class compiled on demand [2025-05-29T03:12:35.362Z] TIME: 0.0 seconds [2025-05-29T03:12:35.362Z] messages: [2025-05-29T03:12:35.362Z] command: build jdk.test.lib.helpers.ClassFileInstaller [2025-05-29T03:12:35.362Z] reason: Named class compiled on demand [2025-05-29T03:12:35.362Z] started: Thu May 29 03:12:30 UTC 2025 [2025-05-29T03:12:35.362Z] finished: Thu May 29 03:12:30 UTC 2025 [2025-05-29T03:12:35.362Z] elapsed time (seconds): 0.0 [2025-05-29T03:12:35.362Z] [2025-05-29T03:12:35.362Z] ACTION: driver -- Passed. Execution successful [2025-05-29T03:12:35.362Z] REASON: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-05-29T03:12:35.362Z] TIME: 0.222 seconds [2025-05-29T03:12:35.362Z] messages: [2025-05-29T03:12:35.362Z] command: driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-05-29T03:12:35.362Z] reason: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-05-29T03:12:35.362Z] started: Thu May 29 03:12:30 UTC 2025 [2025-05-29T03:12:35.362Z] Mode: agentvm [2025-05-29T03:12:35.362Z] Agent id: 21 [2025-05-29T03:12:35.362Z] finished: Thu May 29 03:12:30 UTC 2025 [2025-05-29T03:12:35.362Z] elapsed time (seconds): 0.222 [2025-05-29T03:12:35.362Z] configuration: [2025-05-29T03:12:35.362Z] Boot Layer [2025-05-29T03:12:35.362Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-05-29T03:12:35.362Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-05-29T03:12:35.362Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar [2025-05-29T03:12:35.362Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2025-05-29T03:12:35.362Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2025-05-29T03:12:35.362Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2025-05-29T03:12:35.362Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/patches/java.base [2025-05-29T03:12:35.363Z] [2025-05-29T03:12:35.363Z] Test Layer [2025-05-29T03:12:35.363Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2025-05-29T03:12:35.363Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib [2025-05-29T03:12:35.363Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-05-29T03:12:35.363Z] [2025-05-29T03:12:35.363Z] rerun: [2025-05-29T03:12:35.363Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/scratch && \ [2025-05-29T03:12:35.363Z] DISPLAY=unix:0 \ [2025-05-29T03:12:35.363Z] HOME=/home/jenkins \ [2025-05-29T03:12:35.363Z] LANG=en_US \ [2025-05-29T03:12:35.363Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-05-29T03:12:35.363Z] TZ=Etc/UTC \ [2025-05-29T03:12:35.363Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image/bin/java \ [2025-05-29T03:12:35.363Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-05-29T03:12:35.363Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-05-29T03:12:35.363Z] -Dtest.compiler.opts= \ [2025-05-29T03:12:35.363Z] -Dtest.java.opts= \ [2025-05-29T03:12:35.363Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image \ [2025-05-29T03:12:35.363Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image \ [2025-05-29T03:12:35.363Z] -Dtest.timeout.factor=8.0 \ [2025-05-29T03:12:35.363Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-05-29T03:12:35.363Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-05-29T03:12:35.363Z] -Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-05-29T03:12:35.363Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-05-29T03:12:35.363Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2025-05-29T03:12:35.363Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-05-29T03:12:35.363Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2025-05-29T03:12:35.363Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib \ [2025-05-29T03:12:35.363Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib \ [2025-05-29T03:12:35.363Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-05-29T03:12:35.363Z] jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-05-29T03:12:35.363Z] STDERR: [2025-05-29T03:12:35.363Z] [2025-05-29T03:12:35.363Z] JavaTest Message: Test complete. [2025-05-29T03:12:35.363Z] [2025-05-29T03:12:35.363Z] [2025-05-29T03:12:35.363Z] ACTION: build -- Passed. Build successful [2025-05-29T03:12:35.363Z] REASON: Named class compiled on demand [2025-05-29T03:12:35.363Z] TIME: 0.23 seconds [2025-05-29T03:12:35.363Z] messages: [2025-05-29T03:12:35.363Z] command: build gc.g1.TestSkipRebuildRemsetPhase [2025-05-29T03:12:35.363Z] reason: Named class compiled on demand [2025-05-29T03:12:35.363Z] started: Thu May 29 03:12:30 UTC 2025 [2025-05-29T03:12:35.363Z] Test directory: [2025-05-29T03:12:35.363Z] compile: gc.g1.TestSkipRebuildRemsetPhase [2025-05-29T03:12:35.363Z] finished: Thu May 29 03:12:31 UTC 2025 [2025-05-29T03:12:35.363Z] elapsed time (seconds): 0.23 [2025-05-29T03:12:35.363Z] [2025-05-29T03:12:35.363Z] ACTION: compile -- Passed. Compilation successful [2025-05-29T03:12:35.363Z] REASON: .class file out of date or does not exist [2025-05-29T03:12:35.363Z] TIME: 0.23 seconds [2025-05-29T03:12:35.363Z] messages: [2025-05-29T03:12:35.363Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java [2025-05-29T03:12:35.363Z] reason: .class file out of date or does not exist [2025-05-29T03:12:35.363Z] started: Thu May 29 03:12:30 UTC 2025 [2025-05-29T03:12:35.363Z] Mode: agentvm [2025-05-29T03:12:35.363Z] Agent id: 1 [2025-05-29T03:12:35.363Z] finished: Thu May 29 03:12:31 UTC 2025 [2025-05-29T03:12:35.363Z] elapsed time (seconds): 0.23 [2025-05-29T03:12:35.363Z] configuration: [2025-05-29T03:12:35.363Z] Boot Layer (javac runtime environment) [2025-05-29T03:12:35.363Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-05-29T03:12:35.363Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-05-29T03:12:35.363Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/patches/java.base [2025-05-29T03:12:35.363Z] [2025-05-29T03:12:35.363Z] javac compilation environment [2025-05-29T03:12:35.363Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2025-05-29T03:12:35.363Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-05-29T03:12:35.363Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2025-05-29T03:12:35.363Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d [2025-05-29T03:12:35.363Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib [2025-05-29T03:12:35.363Z] [2025-05-29T03:12:35.363Z] rerun: [2025-05-29T03:12:35.363Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/scratch && \ [2025-05-29T03:12:35.363Z] DISPLAY=unix:0 \ [2025-05-29T03:12:35.363Z] HOME=/home/jenkins \ [2025-05-29T03:12:35.363Z] LANG=en_US \ [2025-05-29T03:12:35.363Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-05-29T03:12:35.363Z] TZ=Etc/UTC \ [2025-05-29T03:12:35.363Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image/bin/javac \ [2025-05-29T03:12:35.363Z] -J-ea \ [2025-05-29T03:12:35.363Z] -J-esa \ [2025-05-29T03:12:35.363Z] -J-Xmx512m \ [2025-05-29T03:12:35.363Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-05-29T03:12:35.363Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-05-29T03:12:35.363Z] -J-Dtest.compiler.opts= \ [2025-05-29T03:12:35.363Z] -J-Dtest.java.opts= \ [2025-05-29T03:12:35.363Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image \ [2025-05-29T03:12:35.363Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image \ [2025-05-29T03:12:35.363Z] -J-Dtest.timeout.factor=8.0 \ [2025-05-29T03:12:35.363Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-05-29T03:12:35.363Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-05-29T03:12:35.363Z] -J-Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-05-29T03:12:35.363Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-05-29T03:12:35.363Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2025-05-29T03:12:35.363Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-05-29T03:12:35.363Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2025-05-29T03:12:35.363Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib \ [2025-05-29T03:12:35.364Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib \ [2025-05-29T03:12:35.364Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2025-05-29T03:12:35.364Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-05-29T03:12:35.364Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java [2025-05-29T03:12:35.364Z] [2025-05-29T03:12:35.364Z] ACTION: driver -- Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2025-05-29T03:12:35.364Z] REASON: User specified action: run driver gc.g1.TestSkipRebuildRemsetPhase [2025-05-29T03:12:35.364Z] TIME: 1.43 seconds [2025-05-29T03:12:35.364Z] messages: [2025-05-29T03:12:35.364Z] command: driver gc.g1.TestSkipRebuildRemsetPhase [2025-05-29T03:12:35.364Z] reason: User specified action: run driver gc.g1.TestSkipRebuildRemsetPhase [2025-05-29T03:12:35.364Z] started: Thu May 29 03:12:31 UTC 2025 [2025-05-29T03:12:35.364Z] Mode: agentvm [2025-05-29T03:12:35.364Z] Agent id: 21 [2025-05-29T03:12:35.364Z] finished: Thu May 29 03:12:32 UTC 2025 [2025-05-29T03:12:35.364Z] elapsed time (seconds): 1.43 [2025-05-29T03:12:35.364Z] configuration: [2025-05-29T03:12:35.364Z] Boot Layer [2025-05-29T03:12:35.364Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2025-05-29T03:12:35.364Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/patches/java.base [2025-05-29T03:12:35.364Z] [2025-05-29T03:12:35.364Z] Test Layer [2025-05-29T03:12:35.364Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-05-29T03:12:35.364Z] [2025-05-29T03:12:35.364Z] rerun: [2025-05-29T03:12:35.364Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/scratch && \ [2025-05-29T03:12:35.364Z] DISPLAY=unix:0 \ [2025-05-29T03:12:35.364Z] HOME=/home/jenkins \ [2025-05-29T03:12:35.364Z] LANG=en_US \ [2025-05-29T03:12:35.364Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-05-29T03:12:35.364Z] TZ=Etc/UTC \ [2025-05-29T03:12:35.364Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image/bin/java \ [2025-05-29T03:12:35.364Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-05-29T03:12:35.364Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-05-29T03:12:35.364Z] -Dtest.compiler.opts= \ [2025-05-29T03:12:35.364Z] -Dtest.java.opts= \ [2025-05-29T03:12:35.364Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image \ [2025-05-29T03:12:35.364Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image \ [2025-05-29T03:12:35.364Z] -Dtest.timeout.factor=8.0 \ [2025-05-29T03:12:35.364Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-05-29T03:12:35.364Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-05-29T03:12:35.364Z] -Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-05-29T03:12:35.364Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2025-05-29T03:12:35.364Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2025-05-29T03:12:35.364Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-05-29T03:12:35.364Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2025-05-29T03:12:35.364Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib \ [2025-05-29T03:12:35.364Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib \ [2025-05-29T03:12:35.364Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-05-29T03:12:35.364Z] gc.g1.TestSkipRebuildRemsetPhase [2025-05-29T03:12:35.364Z] STDOUT: [2025-05-29T03:12:35.364Z] Command line: [/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jdkbinary/j2sdk-image/bin/java -cp /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/jtreg.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/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-05-29T03:12:35.364Z] [2025-05-29T03:12:31.324735Z] Gathering output for process 27656456 [2025-05-29T03:12:35.364Z] [2025-05-29T03:12:32.459991Z] Waiting for completion for process 27656456 [2025-05-29T03:12:35.364Z] [2025-05-29T03:12:32.460711Z] Waiting for completion finished for process 27656456 [2025-05-29T03:12:35.364Z] STDERR: [2025-05-29T03:12:35.364Z] stdout: [[0.118s][debug][gc,phases] GC(0) Phase 1: Reference Processing 0.110ms [2025-05-29T03:12:35.364Z] [0.119s][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-05-29T03:12:35.364Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.364Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2025-05-29T03:12:35.364Z] [0.119s][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-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][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-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][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-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.3, Max: 1, Diff: 1, Sum: 1, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 727.3, Max: 1222, Diff: 1222, Sum: 2182, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][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-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][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-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][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-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][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-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][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-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Phase 1: Weak Processing 0.365ms [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) ClassLoaderData 0.002ms [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Trigger cleanups 0.000ms [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Phase 1: Class Unloading and Cleanup 0.114ms [2025-05-29T03:12:35.365Z] [0.119s][debug][gc,phases] GC(0) Report Object Count 0.001ms [2025-05-29T03:12:35.365Z] [0.119s][info ][gc,phases] GC(0) Phase 1: Mark live objects 2.522ms [2025-05-29T03:12:35.365Z] [0.119s][info ][gc,phases] GC(0) Phase 2: Prepare for compaction 0.220ms [2025-05-29T03:12:35.365Z] [0.120s][info ][gc,phases] GC(0) Phase 3: Adjust pointers 0.591ms [2025-05-29T03:12:35.365Z] [0.120s][info ][gc,phases] GC(0) Phase 4: Compact heap 0.501ms [2025-05-29T03:12:35.365Z] [0.121s][info ][gc,phases] GC(1) Pre Evacuate Collection Set: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Prepare TLABs: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Concatenate Dirty Card Logs: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Choose Collection Set: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Region Register: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Prepare Heap Roots: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Clear Claimed Marks: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][info ][gc,phases] GC(1) Merge Heap Roots: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Prepare Merge Heap Roots: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Eager Reclaim (ms): skipped [2025-05-29T03:12:35.365Z] [0.121s][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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Merged Sparse: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Merged Fine: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Merged Coarse: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][info ][gc,phases] GC(1) Evacuate Collection Set: 0.2ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.2, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Scanned Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Scanned Blocks: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Claimed Chunks: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][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-05-29T03:12:35.365Z] [0.121s][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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.1, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 2, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) GC Worker Total (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.3, Workers: 2 [2025-05-29T03:12:35.365Z] [0.121s][info ][gc,phases] GC(1) Post Evacuate Collection Set: 0.2ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Code Roots Fixup: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Reference Processing: 0.0ms [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Weak Processing: 0.1ms [2025-05-29T03:12:35.365Z] [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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2025-05-29T03:12:35.365Z] [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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2025-05-29T03:12:35.365Z] [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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 727.3, Max: 1286, Diff: 1286, Sum: 2182, Workers: 3 [2025-05-29T03:12:35.365Z] [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-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.365Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2025-05-29T03:12:35.365Z] [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-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Post Evacuate Cleanup 1: 0.0ms [2025-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Copied Bytes Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) LAB Waste Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) LAB Undo Waste Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Post Evacuate Cleanup 2: 0.0ms [2025-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Redirtied Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2025-05-29T03:12:35.366Z] [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-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Rebuild Free List: 0.0ms [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Start New Collection Set: 0.0ms [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Resize TLABs: 0.0ms [2025-05-29T03:12:35.366Z] [0.121s][debug][gc,phases] GC(1) Expand Heap After Collection: 0.0ms [2025-05-29T03:12:35.366Z] [0.121s][info ][gc,phases] GC(1) Other: 0.1ms [2025-05-29T03:12:35.366Z] [0.121s][info ][gc,marking] GC(2) Concurrent Clear Claimed Marks [2025-05-29T03:12:35.366Z] [0.121s][info ][gc,marking] GC(2) Concurrent Clear Claimed Marks 0.008ms [2025-05-29T03:12:35.366Z] [0.122s][info ][gc,marking] GC(2) Concurrent Scan Root Regions [2025-05-29T03:12:35.366Z] [0.122s][info ][gc,marking] GC(2) Concurrent Scan Root Regions 0.004ms [2025-05-29T03:12:35.366Z] [0.122s][info ][gc,marking] GC(2) Concurrent Mark [2025-05-29T03:12:35.366Z] [0.122s][info ][gc,marking] GC(2) Concurrent Mark From Roots [2025-05-29T03:12:35.366Z] [0.123s][info ][gc,marking] GC(2) Concurrent Mark From Roots 1.520ms [2025-05-29T03:12:35.366Z] [0.123s][info ][gc,marking] GC(2) Concurrent Preclean [2025-05-29T03:12:35.366Z] [0.123s][info ][gc,marking] GC(2) Concurrent Preclean 0.305ms [2025-05-29T03:12:35.366Z] [0.123s][debug][gc,phases ] GC(2) Finalize Marking 0.039ms [2025-05-29T03:12:35.366Z] [0.123s][debug][gc,phases ] GC(2) Reference Processing 0.014ms [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 727.3, Max: 1286, Diff: 1286, Sum: 2182, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][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-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Weak Processing 0.258ms [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) ClassLoaderData 0.001ms [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Trigger cleanups 0.001ms [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Class Unloading 0.150ms [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Flush Task Caches 0.022ms [2025-05-29T03:12:35.366Z] [0.124s][trace][gc,remset,tracking] GC(2) Before rebuild region 0 (ntams: 0x00000000f0017550) total_live_bytes 95568 selected true (live_bytes 95568 next_marked 0 marked 0 type OLD) [2025-05-29T03:12:35.366Z] [0.124s][trace][gc,remset,tracking] GC(2) Before rebuild region 21 (ntams: 0x00000000f15cf1b0) total_live_bytes 848304 selected false (live_bytes 848304 next_marked 0 marked 0 type OLD) [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,remset,tracking] GC(2) Remembered Set Tracking update regions total 32, selected 1 [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Update Remembered Set Tracking Before Rebuild 0.081ms [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Reclaim Empty Regions 0.020ms [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Purge Metaspace 0.001ms [2025-05-29T03:12:35.366Z] [0.124s][debug][gc,phases ] GC(2) Report Object Count 0.001ms [2025-05-29T03:12:35.366Z] [0.124s][info ][gc,marking ] GC(2) Concurrent Mark 2.537ms [2025-05-29T03:12:35.366Z] [0.124s][info ][gc,marking ] GC(2) Concurrent Rebuild Remembered Sets [2025-05-29T03:12:35.366Z] [0.124s][trace][gc,remset,tracking] GC(2) Rebuilt region 21 live 848304 time 0.001ms marked bytes 349544 bot 0x00000000f1500000 TAMS 0x00000000f15cf1b0 TARS 0x00000000f15cf1b0 [2025-05-29T03:12:35.366Z] [0.124s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 95568 time 0.122ms marked bytes 95568 bot 0x00000000f0000000 TAMS 0x00000000f0017550 TARS 0x00000000f0017550 [2025-05-29T03:12:35.366Z] [0.124s][trace][gc,remset,tracking] GC(2) Rebuilt region 21 live 848304 time 0.157ms marked bytes 184080 bot 0x00000000f1500000 TAMS 0x00000000f15cf1b0 TARS 0x00000000f15cf1b0 [2025-05-29T03:12:35.366Z] [0.125s][trace][gc,remset,tracking] GC(2) Rebuilt region 21 live 848304 time 0.302ms marked bytes 252808 bot 0x00000000f1500000 TAMS 0x00000000f15cf1b0 TARS 0x00000000f15cf1b0 [2025-05-29T03:12:35.367Z] [0.125s][trace][gc,remset,tracking] GC(2) Rebuilt region 21 live 848304 time 0.080ms marked bytes 61872 bot 0x00000000f1500000 TAMS 0x00000000f15cf1b0 TARS 0x00000000f15cf1b0 [2025-05-29T03:12:35.367Z] [0.125s][info ][gc,marking ] GC(2) Concurrent Rebuild Remembered Sets 0.642ms [2025-05-29T03:12:35.367Z] [0.125s][trace][gc,remset,tracking] GC(2) After rebuild region 0 (ntams 0x00000000f0000000 liveness 95568 next_marked_bytes 0 remset occ 34 size 3264) [2025-05-29T03:12:35.367Z] [0.125s][trace][gc,remset,tracking] GC(2) After rebuild region 21 (ntams 0x00000000f1500000 liveness 848304 next_marked_bytes 0 remset occ 0 size 3104) [2025-05-29T03:12:35.367Z] [0.125s][debug][gc,phases ] GC(2) Update Remembered Set Tracking After Rebuild 0.017ms [2025-05-29T03:12:35.367Z] [0.125s][debug][gc,phases ] GC(2) Finalize Concurrent Mark Cleanup 0.026ms [2025-05-29T03:12:35.367Z] [0.125s][info ][gc,marking ] GC(2) Concurrent Cleanup for Next Mark [2025-05-29T03:12:35.367Z] [0.125s][info ][gc,marking ] GC(2) Concurrent Cleanup for Next Mark 0.109ms [2025-05-29T03:12:35.367Z] [B@2437c6dc [2025-05-29T03:12:35.367Z] ]; [2025-05-29T03:12:35.367Z] stderr: [] [2025-05-29T03:12:35.367Z] exitValue = 0 [2025-05-29T03:12:35.367Z] [2025-05-29T03:12:35.367Z] java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2025-05-29T03:12:35.367Z] [2025-05-29T03:12:35.367Z] at jdk.test.lib.process.OutputAnalyzer.shouldContain(OutputAnalyzer.java:236) [2025-05-29T03:12:35.367Z] at gc.g1.TestSkipRebuildRemsetPhase.main(TestSkipRebuildRemsetPhase.java:54) [2025-05-29T03:12:35.367Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2025-05-29T03:12:35.367Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2025-05-29T03:12:35.367Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2025-05-29T03:12:35.367Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2025-05-29T03:12:35.367Z] at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333) [2025-05-29T03:12:35.367Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-05-29T03:12:35.367Z] [2025-05-29T03:12:35.367Z] JavaTest Message: Test threw exception: java.lang.RuntimeException [2025-05-29T03:12:35.367Z] JavaTest Message: shutting down test [2025-05-29T03:12:35.367Z] [2025-05-29T03:12:35.367Z] [2025-05-29T03:12:35.367Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2025-05-29T03:12:35.367Z] -------------------------------------------------- [2025-05-29T04:17:10.766Z] Test results: passed: 227; failed: 1 [2025-05-29T04:17:17.803Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/report/html/report.html [2025-05-29T04:17:17.803Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_3/aqa-tests/TKG/output_17484873688889/hotspot_gc_0/work [2025-05-29T04:17:17.803Z] Error: Some tests failed or other problems occurred. [2025-05-29T04:17:17.803Z] ----------------------------------- [2025-05-29T04:17:17.803Z] hotspot_gc_0_FAILED [2025-05-29T04:17:17.803Z] ----------------------------------- [2025-05-29T04:17:17.803Z] [2025-05-29T04:17:17.803Z] TEST TEARDOWN: [2025-05-29T04:17:17.803Z] Nothing to be done for teardown. [2025-05-29T04:17:17.803Z] hotspot_gc_0 Finish Time: Thu May 29 04:17:17 2025 Epoch Time (ms): 1748492237027