hotspot_tier1_gc_0
[2025-05-22T07:09:50.315Z] Running test hotspot_tier1_gc_0 ...
[2025-05-22T07:09:50.315Z] ===============================================
[2025-05-22T07:09:50.315Z] hotspot_tier1_gc_0 Start Time: Thu May 22 07:09:49 2025 Epoch Time (ms): 1747897789821
[2025-05-22T07:09:50.315Z] variation: NoOptions
[2025-05-22T07:09:50.315Z] JVM_OPTIONS:
[2025-05-22T07:09:50.315Z] { \
[2025-05-22T07:09:50.315Z] echo ""; echo "TEST SETUP:"; \
[2025-05-22T07:09:50.315Z] echo "Nothing to be done for setup."; \
[2025-05-22T07:09:50.315Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17478977896435/hotspot_tier1_gc_0"; \
[2025-05-22T07:09:50.315Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17478977896435/hotspot_tier1_gc_0"; \
[2025-05-22T07:09:50.315Z] echo ""; echo "TESTING:"; \
[2025-05-22T07:09:50.315Z] "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \
[2025-05-22T07:09:50.315Z] -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_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \
[2025-05-22T07:09:50.315Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17478977896435/hotspot_tier1_gc_0"/work" \
[2025-05-22T07:09:50.315Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17478977896435/hotspot_tier1_gc_0"/report" \
[2025-05-22T07:09:50.315Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image" \
[2025-05-22T07:09:50.315Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \
[2025-05-22T07:09:50.315Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \
[2025-05-22T07:09:50.315Z] \
[2025-05-22T07:09:50.315Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \
[2025-05-22T07:09:50.315Z] "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:tier1_gc"; \
[2025-05-22T07:09:50.315Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_tier1_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_tier1_gc_0""_FAILED"; echo "-----------------------------------"; fi; \
[2025-05-22T07:09:50.315Z] echo ""; echo "TEST TEARDOWN:"; \
[2025-05-22T07:09:50.315Z] echo "Nothing to be done for teardown."; \
[2025-05-22T07:09:50.315Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17478977896435/TestTargetResult";
[2025-05-22T07:09:50.315Z]
[2025-05-22T07:09:50.315Z] TEST SETUP:
[2025-05-22T07:09:50.315Z] Nothing to be done for setup.
[2025-05-22T07:09:50.315Z]
[2025-05-22T07:09:50.315Z] TESTING:
[2025-05-22T07:09:51.087Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17478977896435/hotspot_tier1_gc_0/work" not found: creating
[2025-05-22T07:09:51.087Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17478977896435/hotspot_tier1_gc_0/report" not found: creating
[2025-05-22T07:09:56.705Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work
[2025-05-22T07:13:18.998Z] --------------------------------------------------
[2025-05-22T07:13:18.998Z] TEST: gc/g1/TestSkipRebuildRemsetPhase.java
[2025-05-22T07:13:18.998Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] ACTION: build -- Passed. All files up to date
[2025-05-22T07:13:18.998Z] REASON: User specified action: run build jdk.test.whitebox.WhiteBox
[2025-05-22T07:13:18.998Z] TIME: 0.0 seconds
[2025-05-22T07:13:18.998Z] messages:
[2025-05-22T07:13:18.998Z] command: build jdk.test.whitebox.WhiteBox
[2025-05-22T07:13:18.998Z] reason: User specified action: run build jdk.test.whitebox.WhiteBox
[2025-05-22T07:13:18.998Z] started: Thu May 22 07:13:08 GMT 2025
[2025-05-22T07:13:18.998Z] finished: Thu May 22 07:13:08 GMT 2025
[2025-05-22T07:13:18.998Z] elapsed time (seconds): 0.0
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] ACTION: build -- Passed. All files up to date
[2025-05-22T07:13:18.998Z] REASON: Named class compiled on demand
[2025-05-22T07:13:18.998Z] TIME: 0.0 seconds
[2025-05-22T07:13:18.998Z] messages:
[2025-05-22T07:13:18.998Z] command: build jdk.test.lib.helpers.ClassFileInstaller
[2025-05-22T07:13:18.998Z] reason: Named class compiled on demand
[2025-05-22T07:13:18.998Z] started: Thu May 22 07:13:08 GMT 2025
[2025-05-22T07:13:18.998Z] finished: Thu May 22 07:13:08 GMT 2025
[2025-05-22T07:13:18.998Z] elapsed time (seconds): 0.0
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] ACTION: driver -- Passed. Execution successful
[2025-05-22T07:13:18.998Z] REASON: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox
[2025-05-22T07:13:18.998Z] TIME: 0.221 seconds
[2025-05-22T07:13:18.998Z] messages:
[2025-05-22T07:13:18.998Z] command: driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox
[2025-05-22T07:13:18.998Z] reason: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox
[2025-05-22T07:13:18.998Z] started: Thu May 22 07:13:08 GMT 2025
[2025-05-22T07:13:18.998Z] Mode: agentvm
[2025-05-22T07:13:18.998Z] Agent id: 13
[2025-05-22T07:13:18.998Z] finished: Thu May 22 07:13:08 GMT 2025
[2025-05-22T07:13:18.998Z] elapsed time (seconds): 0.221
[2025-05-22T07:13:18.998Z] configuration:
[2025-05-22T07:13:18.998Z] Boot Layer
[2025-05-22T07:13:18.998Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar
[2025-05-22T07:13:18.998Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/patches/java.base
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] Test Layer
[2025-05-22T07:13:18.998Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] rerun:
[2025-05-22T07:13:18.998Z] cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/scratch && \
[2025-05-22T07:13:18.998Z] DISPLAY=unix:0 \
[2025-05-22T07:13:18.998Z] HOME=/home/jenkins \
[2025-05-22T07:13:18.998Z] LANG=C \
[2025-05-22T07:13:18.998Z] PATH=/bin:/usr/bin:/usr/sbin \
[2025-05-22T07:13:18.998Z] TZ=UTC0 \
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/java \
[2025-05-22T07:13:18.998Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \
[2025-05-22T07:13:18.998Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \
[2025-05-22T07:13:18.998Z] -Dtest.compiler.opts= \
[2025-05-22T07:13:18.998Z] -Dtest.java.opts= \
[2025-05-22T07:13:18.998Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \
[2025-05-22T07:13:18.998Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \
[2025-05-22T07:13:18.998Z] -Dtest.timeout.factor=8.0 \
[2025-05-22T07:13:18.998Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
[2025-05-22T07:13:18.998Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \
[2025-05-22T07:13:18.998Z] -Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \
[2025-05-22T07:13:18.998Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \
[2025-05-22T07:13:18.998Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \
[2025-05-22T07:13:18.998Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \
[2025-05-22T07:13:18.998Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \
[2025-05-22T07:13:18.998Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib \
[2025-05-22T07:13:18.998Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib \
[2025-05-22T07:13:18.998Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar \
[2025-05-22T07:13:18.998Z] jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox
[2025-05-22T07:13:18.998Z] STDERR:
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] JavaTest Message: Test complete.
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] ACTION: build -- Passed. Build successful
[2025-05-22T07:13:18.998Z] REASON: Named class compiled on demand
[2025-05-22T07:13:18.998Z] TIME: 0.227 seconds
[2025-05-22T07:13:18.998Z] messages:
[2025-05-22T07:13:18.998Z] command: build gc.g1.TestSkipRebuildRemsetPhase
[2025-05-22T07:13:18.998Z] reason: Named class compiled on demand
[2025-05-22T07:13:18.998Z] started: Thu May 22 07:13:08 GMT 2025
[2025-05-22T07:13:18.998Z] Test directory:
[2025-05-22T07:13:18.998Z] compile: gc.g1.TestSkipRebuildRemsetPhase
[2025-05-22T07:13:18.998Z] finished: Thu May 22 07:13:09 GMT 2025
[2025-05-22T07:13:18.998Z] elapsed time (seconds): 0.227
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] ACTION: compile -- Passed. Compilation successful
[2025-05-22T07:13:18.998Z] REASON: .class file out of date or does not exist
[2025-05-22T07:13:18.998Z] TIME: 0.226 seconds
[2025-05-22T07:13:18.998Z] messages:
[2025-05-22T07:13:18.998Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java
[2025-05-22T07:13:18.998Z] reason: .class file out of date or does not exist
[2025-05-22T07:13:18.998Z] started: Thu May 22 07:13:08 GMT 2025
[2025-05-22T07:13:18.998Z] Mode: agentvm
[2025-05-22T07:13:18.998Z] Agent id: 1
[2025-05-22T07:13:18.998Z] finished: Thu May 22 07:13:09 GMT 2025
[2025-05-22T07:13:18.998Z] elapsed time (seconds): 0.226
[2025-05-22T07:13:18.998Z] configuration:
[2025-05-22T07:13:18.998Z] Boot Layer (javac runtime environment)
[2025-05-22T07:13:18.998Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar
[2025-05-22T07:13:18.998Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/patches/java.base
[2025-05-22T07:13:18.998Z]
[2025-05-22T07:13:18.998Z] javac compilation environment
[2025-05-22T07:13:18.998Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1
[2025-05-22T07:13:18.998Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib
[2025-05-22T07:13:18.998Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib
[2025-05-22T07:13:18.999Z]
[2025-05-22T07:13:18.999Z] rerun:
[2025-05-22T07:13:18.999Z] cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/scratch && \
[2025-05-22T07:13:18.999Z] DISPLAY=unix:0 \
[2025-05-22T07:13:18.999Z] HOME=/home/jenkins \
[2025-05-22T07:13:18.999Z] LANG=C \
[2025-05-22T07:13:18.999Z] PATH=/bin:/usr/bin:/usr/sbin \
[2025-05-22T07:13:18.999Z] TZ=UTC0 \
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/javac \
[2025-05-22T07:13:18.999Z] -J-ea \
[2025-05-22T07:13:18.999Z] -J-esa \
[2025-05-22T07:13:18.999Z] -J-Xmx512m \
[2025-05-22T07:13:18.999Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \
[2025-05-22T07:13:18.999Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \
[2025-05-22T07:13:18.999Z] -J-Dtest.compiler.opts= \
[2025-05-22T07:13:18.999Z] -J-Dtest.java.opts= \
[2025-05-22T07:13:18.999Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \
[2025-05-22T07:13:18.999Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \
[2025-05-22T07:13:18.999Z] -J-Dtest.timeout.factor=8.0 \
[2025-05-22T07:13:18.999Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
[2025-05-22T07:13:18.999Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \
[2025-05-22T07:13:18.999Z] -J-Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \
[2025-05-22T07:13:18.999Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \
[2025-05-22T07:13:18.999Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \
[2025-05-22T07:13:18.999Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \
[2025-05-22T07:13:18.999Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \
[2025-05-22T07:13:18.999Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib \
[2025-05-22T07:13:18.999Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib \
[2025-05-22T07:13:18.999Z] -d /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \
[2025-05-22T07:13:18.999Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \
[2025-05-22T07:13:18.999Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java
[2025-05-22T07:13:18.999Z]
[2025-05-22T07:13:18.999Z] ACTION: driver -- Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr
[2025-05-22T07:13:18.999Z] REASON: User specified action: run driver gc.g1.TestSkipRebuildRemsetPhase
[2025-05-22T07:13:18.999Z] TIME: 1.378 seconds
[2025-05-22T07:13:18.999Z] messages:
[2025-05-22T07:13:18.999Z] command: driver gc.g1.TestSkipRebuildRemsetPhase
[2025-05-22T07:13:18.999Z] reason: User specified action: run driver gc.g1.TestSkipRebuildRemsetPhase
[2025-05-22T07:13:18.999Z] started: Thu May 22 07:13:09 GMT 2025
[2025-05-22T07:13:18.999Z] Mode: agentvm
[2025-05-22T07:13:18.999Z] Agent id: 13
[2025-05-22T07:13:18.999Z] finished: Thu May 22 07:13:10 GMT 2025
[2025-05-22T07:13:18.999Z] elapsed time (seconds): 1.378
[2025-05-22T07:13:18.999Z] configuration:
[2025-05-22T07:13:18.999Z] Boot Layer
[2025-05-22T07:13:18.999Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar
[2025-05-22T07:13:18.999Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/patches/java.base
[2025-05-22T07:13:18.999Z]
[2025-05-22T07:13:18.999Z] Test Layer
[2025-05-22T07:13:18.999Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib
[2025-05-22T07:13:18.999Z]
[2025-05-22T07:13:18.999Z] rerun:
[2025-05-22T07:13:18.999Z] cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/scratch && \
[2025-05-22T07:13:18.999Z] DISPLAY=unix:0 \
[2025-05-22T07:13:18.999Z] HOME=/home/jenkins \
[2025-05-22T07:13:18.999Z] LANG=C \
[2025-05-22T07:13:18.999Z] PATH=/bin:/usr/bin:/usr/sbin \
[2025-05-22T07:13:18.999Z] TZ=UTC0 \
[2025-05-22T07:13:18.999Z] /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/java \
[2025-05-22T07:13:18.999Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \
[2025-05-22T07:13:18.999Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \
[2025-05-22T07:13:18.999Z] -Dtest.compiler.opts= \
[2025-05-22T07:13:18.999Z] -Dtest.java.opts= \
[2025-05-22T07:13:18.999Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \
[2025-05-22T07:13:18.999Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \
[2025-05-22T07:13:18.999Z] -Dtest.timeout.factor=8.0 \
[2025-05-22T07:13:18.999Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
[2025-05-22T07:13:18.999Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \
[2025-05-22T07:13:18.999Z] -Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \
[2025-05-22T07:13:18.999Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \
[2025-05-22T07:13:18.999Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \
[2025-05-22T07:13:18.999Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \
[2025-05-22T07:13:18.999Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \
[2025-05-22T07:13:18.999Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib \
[2025-05-22T07:13:18.999Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib \
[2025-05-22T07:13:19.000Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar \
[2025-05-22T07:13:19.000Z] gc.g1.TestSkipRebuildRemsetPhase
[2025-05-22T07:13:19.000Z] STDOUT:
[2025-05-22T07:13:19.000Z] Command line: [/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/java -cp /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/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-22T07:13:19.000Z] [2025-05-22T07:13:09.280482Z] Gathering output for process 21496146
[2025-05-22T07:13:19.000Z] [2025-05-22T07:13:10.412863Z] Waiting for completion for process 21496146
[2025-05-22T07:13:19.000Z] [2025-05-22T07:13:10.414717Z] Waiting for completion finished for process 21496146
[2025-05-22T07:13:19.000Z] STDERR:
[2025-05-22T07:13:19.000Z] stdout: [[0.118s][debug][gc,phases] GC(0) Phase 1: Reference Processing 0.077ms
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.3, Max: 1, Diff: 1, Sum: 1, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 728.0, Max: 1344, Diff: 1344, Sum: 2184, Workers: 3
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [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-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Phase 1: Weak Processing 0.360ms
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) ClassLoaderData 0.001ms
[2025-05-22T07:13:19.000Z] [0.118s][debug][gc,phases] GC(0) Trigger cleanups 0.001ms
[2025-05-22T07:13:19.000Z] [0.119s][debug][gc,phases] GC(0) Phase 1: Class Unloading and Cleanup 0.101ms
[2025-05-22T07:13:19.000Z] [0.119s][debug][gc,phases] GC(0) Report Object Count 0.001ms
[2025-05-22T07:13:19.000Z] [0.119s][info ][gc,phases] GC(0) Phase 1: Mark live objects 1.938ms
[2025-05-22T07:13:19.000Z] [0.119s][info ][gc,phases] GC(0) Phase 2: Prepare for compaction 0.231ms
[2025-05-22T07:13:19.000Z] [0.119s][info ][gc,phases] GC(0) Phase 3: Adjust pointers 0.587ms
[2025-05-22T07:13:19.000Z] [0.120s][info ][gc,phases] GC(0) Phase 4: Compact heap 0.407ms
[2025-05-22T07:13:19.000Z] [0.121s][info ][gc,phases] GC(1) Pre Evacuate Collection Set: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Prepare TLABs: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Concatenate Dirty Card Logs: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Choose Collection Set: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Region Register: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Prepare Heap Roots: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Clear Claimed Marks: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][info ][gc,phases] GC(1) Merge Heap Roots: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Prepare Merge Heap Roots: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Eager Reclaim (ms): skipped
[2025-05-22T07:13:19.000Z] [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-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Merged Sparse: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Merged Fine: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Merged Coarse: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [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-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [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-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [0.121s][info ][gc,phases] GC(1) Evacuate Collection Set: 0.2ms
[2025-05-22T07:13:19.000Z] [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-22T07:13:19.000Z] [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-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Scanned Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Scanned Blocks: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Claimed Chunks: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.000Z] [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-22T07:13:19.000Z] [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-22T07:13:19.000Z] [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-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 2, Workers: 2
[2025-05-22T07:13:19.000Z] [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-22T07:13:19.000Z] [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-22T07:13:19.000Z] [0.121s][info ][gc,phases] GC(1) Post Evacuate Collection Set: 0.2ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Code Roots Fixup: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Reference Processing: 0.0ms
[2025-05-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Weak Processing: 0.1ms
[2025-05-22T07:13:19.000Z] [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-22T07:13:19.000Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 728.0, Max: 1224, Diff: 1224, Sum: 2184, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.121s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Post Evacuate Cleanup 1: 0.0ms
[2025-05-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Copied Bytes Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) LAB Waste Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) LAB Undo Waste Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Post Evacuate Cleanup 2: 0.0ms
[2025-05-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Redirtied Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2
[2025-05-22T07:13:19.001Z] [0.122s][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-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Rebuild Free List: 0.0ms
[2025-05-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Start New Collection Set: 0.0ms
[2025-05-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Resize TLABs: 0.0ms
[2025-05-22T07:13:19.001Z] [0.122s][debug][gc,phases] GC(1) Expand Heap After Collection: 0.0ms
[2025-05-22T07:13:19.001Z] [0.122s][info ][gc,phases] GC(1) Other: 0.1ms
[2025-05-22T07:13:19.001Z] [0.122s][info ][gc,marking] GC(2) Concurrent Clear Claimed Marks
[2025-05-22T07:13:19.001Z] [0.122s][info ][gc,marking] GC(2) Concurrent Clear Claimed Marks 0.013ms
[2025-05-22T07:13:19.001Z] [0.122s][info ][gc,marking] GC(2) Concurrent Scan Root Regions
[2025-05-22T07:13:19.001Z] [0.122s][info ][gc,marking] GC(2) Concurrent Scan Root Regions 0.005ms
[2025-05-22T07:13:19.001Z] [0.122s][info ][gc,marking] GC(2) Concurrent Mark
[2025-05-22T07:13:19.001Z] [0.122s][info ][gc,marking] GC(2) Concurrent Mark From Roots
[2025-05-22T07:13:19.001Z] [0.124s][info ][gc,marking] GC(2) Concurrent Mark From Roots 1.897ms
[2025-05-22T07:13:19.001Z] [0.124s][info ][gc,marking] GC(2) Concurrent Preclean
[2025-05-22T07:13:19.001Z] [0.124s][info ][gc,marking] GC(2) Concurrent Preclean 0.299ms
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Finalize Marking 0.044ms
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Reference Processing 0.015ms
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 728.0, Max: 1224, Diff: 1224, Sum: 2184, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [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-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.001Z] [0.124s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3
[2025-05-22T07:13:19.002Z] [0.124s][debug][gc,phases ] GC(2) Weak Processing 0.280ms
[2025-05-22T07:13:19.002Z] [0.124s][debug][gc,phases ] GC(2) ClassLoaderData 0.001ms
[2025-05-22T07:13:19.002Z] [0.124s][debug][gc,phases ] GC(2) Trigger cleanups 0.000ms
[2025-05-22T07:13:19.002Z] [0.124s][debug][gc,phases ] GC(2) Class Unloading 0.148ms
[2025-05-22T07:13:19.002Z] [0.124s][debug][gc,phases ] GC(2) Flush Task Caches 0.020ms
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) Before rebuild region 0 (ntams: 0x00000000f00cf080) total_live_bytes 848000 selected false (live_bytes 848000 next_marked 0 marked 0 type OLD)
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) Before rebuild region 1 (ntams: 0x00000000f01175a0) total_live_bytes 95648 selected true (live_bytes 95648 next_marked 0 marked 0 type OLD)
[2025-05-22T07:13:19.002Z] [0.125s][debug][gc,remset,tracking] GC(2) Remembered Set Tracking update regions total 32, selected 1
[2025-05-22T07:13:19.002Z] [0.125s][debug][gc,phases ] GC(2) Update Remembered Set Tracking Before Rebuild 0.090ms
[2025-05-22T07:13:19.002Z] [0.125s][debug][gc,phases ] GC(2) Reclaim Empty Regions 0.019ms
[2025-05-22T07:13:19.002Z] [0.125s][debug][gc,phases ] GC(2) Purge Metaspace 0.001ms
[2025-05-22T07:13:19.002Z] [0.125s][debug][gc,phases ] GC(2) Report Object Count 0.001ms
[2025-05-22T07:13:19.002Z] [0.125s][info ][gc,marking ] GC(2) Concurrent Mark 2.964ms
[2025-05-22T07:13:19.002Z] [0.125s][info ][gc,marking ] GC(2) Concurrent Rebuild Remembered Sets
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 848000 time 0.002ms marked bytes 349544 bot 0x00000000f0000000 TAMS 0x00000000f00cf080 TARS 0x00000000f00cf080
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) Rebuilt region 1 live 95648 time 0.122ms marked bytes 95648 bot 0x00000000f0100000 TAMS 0x00000000f01175a0 TARS 0x00000000f01175a0
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 848000 time 0.156ms marked bytes 183696 bot 0x00000000f0000000 TAMS 0x00000000f00cf080 TARS 0x00000000f00cf080
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 848000 time 0.301ms marked bytes 253192 bot 0x00000000f0000000 TAMS 0x00000000f00cf080 TARS 0x00000000f00cf080
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 848000 time 0.079ms marked bytes 61568 bot 0x00000000f0000000 TAMS 0x00000000f00cf080 TARS 0x00000000f00cf080
[2025-05-22T07:13:19.002Z] [0.125s][info ][gc,marking ] GC(2) Concurrent Rebuild Remembered Sets 0.595ms
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) After rebuild region 0 (ntams 0x00000000f0000000 liveness 848000 next_marked_bytes 0 remset occ 0 size 3104)
[2025-05-22T07:13:19.002Z] [0.125s][trace][gc,remset,tracking] GC(2) After rebuild region 1 (ntams 0x00000000f0100000 liveness 95648 next_marked_bytes 0 remset occ 34 size 3264)
[2025-05-22T07:13:19.002Z] [0.125s][debug][gc,phases ] GC(2) Update Remembered Set Tracking After Rebuild 0.015ms
[2025-05-22T07:13:19.002Z] [0.125s][debug][gc,phases ] GC(2) Finalize Concurrent Mark Cleanup 0.156ms
[2025-05-22T07:13:19.002Z] [0.125s][info ][gc,marking ] GC(2) Concurrent Cleanup for Next Mark
[2025-05-22T07:13:19.002Z] [0.126s][info ][gc,marking ] GC(2) Concurrent Cleanup for Next Mark 0.145ms
[2025-05-22T07:13:19.002Z] [B@2437c6dc
[2025-05-22T07:13:19.002Z] ];
[2025-05-22T07:13:19.002Z] stderr: []
[2025-05-22T07:13:19.002Z] exitValue = 0
[2025-05-22T07:13:19.002Z]
[2025-05-22T07:13:19.002Z] java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr
[2025-05-22T07:13:19.002Z]
[2025-05-22T07:13:19.002Z] at jdk.test.lib.process.OutputAnalyzer.shouldContain(OutputAnalyzer.java:236)
[2025-05-22T07:13:19.002Z] at gc.g1.TestSkipRebuildRemsetPhase.main(TestSkipRebuildRemsetPhase.java:54)
[2025-05-22T07:13:19.002Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2025-05-22T07:13:19.002Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
[2025-05-22T07:13:19.002Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2025-05-22T07:13:19.002Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569)
[2025-05-22T07:13:19.002Z] at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333)
[2025-05-22T07:13:19.002Z] at java.base/java.lang.Thread.run(Thread.java:840)
[2025-05-22T07:13:19.002Z]
[2025-05-22T07:13:19.002Z] JavaTest Message: Test threw exception: java.lang.RuntimeException
[2025-05-22T07:13:19.002Z] JavaTest Message: shutting down test
[2025-05-22T07:13:19.002Z]
[2025-05-22T07:13:19.002Z]
[2025-05-22T07:13:19.002Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr
[2025-05-22T07:13:19.002Z] --------------------------------------------------
[2025-05-22T07:29:22.991Z] Test results: passed: 201; failed: 1
[2025-05-22T07:29:24.588Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/report/html/report.html
[2025-05-22T07:29:24.588Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_sanity.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17478977896435/hotspot_tier1_gc_0/work
[2025-05-22T07:29:24.588Z] Error: Some tests failed or other problems occurred.
[2025-05-22T07:29:24.588Z] -----------------------------------
[2025-05-22T07:29:24.588Z] hotspot_tier1_gc_0_FAILED
[2025-05-22T07:29:24.588Z] -----------------------------------
[2025-05-22T07:29:24.588Z]
[2025-05-22T07:29:24.588Z] TEST TEARDOWN:
[2025-05-22T07:29:24.588Z] Nothing to be done for teardown.
[2025-05-22T07:29:24.588Z] hotspot_tier1_gc_0 Finish Time: Thu May 22 07:29:24 2025 Epoch Time (ms): 1747898964168