Test Results Summary Service

hotspot_gc_0

[2024-10-02T20:56:11.567Z] Running test hotspot_gc_0 ... [2024-10-02T20:56:11.567Z] =============================================== [2024-10-02T20:56:11.567Z] hotspot_gc_0 Start Time: Wed Oct 2 20:56:11 2024 Epoch Time (ms): 1727902571281 [2024-10-02T20:56:11.567Z] variation: NoOptions [2024-10-02T20:56:11.567Z] JVM_OPTIONS: [2024-10-02T20:56:11.567Z] { \ [2024-10-02T20:56:11.567Z] echo ""; echo "TEST SETUP:"; \ [2024-10-02T20:56:11.567Z] echo "Nothing to be done for setup."; \ [2024-10-02T20:56:11.567Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17279025709856/hotspot_gc_0"; \ [2024-10-02T20:56:11.567Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17279025709856/hotspot_gc_0"; \ [2024-10-02T20:56:11.567Z] echo ""; echo "TESTING:"; \ [2024-10-02T20:56:11.567Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2024-10-02T20:56:11.567Z] -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_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2024-10-02T20:56:11.567Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17279025709856/hotspot_gc_0"/work" \ [2024-10-02T20:56:11.567Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17279025709856/hotspot_gc_0"/report" \ [2024-10-02T20:56:11.567Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image" \ [2024-10-02T20:56:11.567Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2024-10-02T20:56:11.567Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2024-10-02T20:56:11.567Z] \ [2024-10-02T20:56:11.567Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2024-10-02T20:56:11.567Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \ [2024-10-02T20:56:11.567Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-02T20:56:11.567Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-02T20:56:11.567Z] echo "Nothing to be done for teardown."; \ [2024-10-02T20:56:11.567Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17279025709856/TestTargetResult"; [2024-10-02T20:56:11.567Z] [2024-10-02T20:56:11.567Z] TEST SETUP: [2024-10-02T20:56:11.567Z] Nothing to be done for setup. [2024-10-02T20:56:11.567Z] [2024-10-02T20:56:11.567Z] TESTING: [2024-10-02T20:56:14.955Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17279025709856/hotspot_gc_0/work" not found: creating [2024-10-02T20:56:14.955Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/../TKG/output_17279025709856/hotspot_gc_0/report" not found: creating [2024-10-02T20:56:23.232Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work [2024-10-02T21:14:14.624Z] -------------------------------------------------- [2024-10-02T21:14:14.624Z] TEST: gc/g1/TestSkipRebuildRemsetPhase.java [2024-10-02T21:14:14.624Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image [2024-10-02T21:14:14.624Z] [2024-10-02T21:14:14.624Z] ACTION: build -- Passed. All files up to date [2024-10-02T21:14:14.624Z] REASON: User specified action: run build jdk.test.whitebox.WhiteBox [2024-10-02T21:14:14.624Z] TIME: 0.001 seconds [2024-10-02T21:14:14.624Z] messages: [2024-10-02T21:14:14.624Z] command: build jdk.test.whitebox.WhiteBox [2024-10-02T21:14:14.624Z] reason: User specified action: run build jdk.test.whitebox.WhiteBox [2024-10-02T21:14:14.624Z] started: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.624Z] finished: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.624Z] elapsed time (seconds): 0.001 [2024-10-02T21:14:14.624Z] [2024-10-02T21:14:14.624Z] ACTION: build -- Passed. All files up to date [2024-10-02T21:14:14.624Z] REASON: Named class compiled on demand [2024-10-02T21:14:14.624Z] TIME: 0.0 seconds [2024-10-02T21:14:14.624Z] messages: [2024-10-02T21:14:14.624Z] command: build jdk.test.lib.helpers.ClassFileInstaller [2024-10-02T21:14:14.624Z] reason: Named class compiled on demand [2024-10-02T21:14:14.624Z] started: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.624Z] finished: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.624Z] elapsed time (seconds): 0.0 [2024-10-02T21:14:14.624Z] [2024-10-02T21:14:14.624Z] ACTION: driver -- Passed. Execution successful [2024-10-02T21:14:14.624Z] REASON: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2024-10-02T21:14:14.624Z] TIME: 0.217 seconds [2024-10-02T21:14:14.624Z] messages: [2024-10-02T21:14:14.624Z] command: driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2024-10-02T21:14:14.624Z] reason: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2024-10-02T21:14:14.624Z] started: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.624Z] Mode: agentvm [2024-10-02T21:14:14.624Z] Agent id: 21 [2024-10-02T21:14:14.624Z] finished: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.624Z] elapsed time (seconds): 0.217 [2024-10-02T21:14:14.624Z] configuration: [2024-10-02T21:14:14.624Z] Boot Layer [2024-10-02T21:14:14.624Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-10-02T21:14:14.624Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-10-02T21:14:14.624Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar [2024-10-02T21:14:14.624Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2024-10-02T21:14:14.624Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2024-10-02T21:14:14.624Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2024-10-02T21:14:14.624Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/patches/java.base [2024-10-02T21:14:14.624Z] [2024-10-02T21:14:14.624Z] Test Layer [2024-10-02T21:14:14.624Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2024-10-02T21:14:14.624Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib [2024-10-02T21:14:14.624Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-10-02T21:14:14.624Z] [2024-10-02T21:14:14.624Z] rerun: [2024-10-02T21:14:14.624Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/scratch && \ [2024-10-02T21:14:14.624Z] DISPLAY=unix:0 \ [2024-10-02T21:14:14.624Z] HOME=/home/jenkins \ [2024-10-02T21:14:14.624Z] LANG=en_US \ [2024-10-02T21:14:14.624Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-10-02T21:14:14.624Z] TZ=Etc/UTC \ [2024-10-02T21:14:14.624Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/java \ [2024-10-02T21:14:14.624Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2024-10-02T21:14:14.624Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2024-10-02T21:14:14.624Z] -Dtest.compiler.opts= \ [2024-10-02T21:14:14.624Z] -Dtest.java.opts= \ [2024-10-02T21:14:14.624Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \ [2024-10-02T21:14:14.624Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \ [2024-10-02T21:14:14.624Z] -Dtest.timeout.factor=8.0 \ [2024-10-02T21:14:14.624Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-10-02T21:14:14.624Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2024-10-02T21:14:14.625Z] -Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2024-10-02T21:14:14.625Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2024-10-02T21:14:14.625Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2024-10-02T21:14:14.625Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-10-02T21:14:14.625Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2024-10-02T21:14:14.625Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib \ [2024-10-02T21:14:14.625Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib \ [2024-10-02T21:14:14.625Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2024-10-02T21:14:14.625Z] jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2024-10-02T21:14:14.625Z] STDERR: [2024-10-02T21:14:14.625Z] [2024-10-02T21:14:14.625Z] JavaTest Message: Test complete. [2024-10-02T21:14:14.625Z] [2024-10-02T21:14:14.625Z] [2024-10-02T21:14:14.625Z] ACTION: build -- Passed. Build successful [2024-10-02T21:14:14.625Z] REASON: Named class compiled on demand [2024-10-02T21:14:14.625Z] TIME: 0.221 seconds [2024-10-02T21:14:14.625Z] messages: [2024-10-02T21:14:14.625Z] command: build gc.g1.TestSkipRebuildRemsetPhase [2024-10-02T21:14:14.625Z] reason: Named class compiled on demand [2024-10-02T21:14:14.625Z] started: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.625Z] Test directory: [2024-10-02T21:14:14.625Z] compile: gc.g1.TestSkipRebuildRemsetPhase [2024-10-02T21:14:14.625Z] finished: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.625Z] elapsed time (seconds): 0.221 [2024-10-02T21:14:14.625Z] [2024-10-02T21:14:14.625Z] ACTION: compile -- Passed. Compilation successful [2024-10-02T21:14:14.625Z] REASON: .class file out of date or does not exist [2024-10-02T21:14:14.625Z] TIME: 0.221 seconds [2024-10-02T21:14:14.625Z] messages: [2024-10-02T21:14:14.625Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java [2024-10-02T21:14:14.625Z] reason: .class file out of date or does not exist [2024-10-02T21:14:14.625Z] started: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.625Z] Mode: agentvm [2024-10-02T21:14:14.625Z] Agent id: 1 [2024-10-02T21:14:14.625Z] finished: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.625Z] elapsed time (seconds): 0.221 [2024-10-02T21:14:14.625Z] configuration: [2024-10-02T21:14:14.625Z] Boot Layer (javac runtime environment) [2024-10-02T21:14:14.625Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-10-02T21:14:14.625Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-10-02T21:14:14.625Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/patches/java.base [2024-10-02T21:14:14.625Z] [2024-10-02T21:14:14.625Z] javac compilation environment [2024-10-02T21:14:14.625Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2024-10-02T21:14:14.625Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-10-02T21:14:14.625Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2024-10-02T21:14:14.625Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d [2024-10-02T21:14:14.625Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib [2024-10-02T21:14:14.625Z] [2024-10-02T21:14:14.625Z] rerun: [2024-10-02T21:14:14.625Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/scratch && \ [2024-10-02T21:14:14.625Z] DISPLAY=unix:0 \ [2024-10-02T21:14:14.625Z] HOME=/home/jenkins \ [2024-10-02T21:14:14.625Z] LANG=en_US \ [2024-10-02T21:14:14.625Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-10-02T21:14:14.625Z] TZ=Etc/UTC \ [2024-10-02T21:14:14.625Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/javac \ [2024-10-02T21:14:14.625Z] -J-ea \ [2024-10-02T21:14:14.625Z] -J-esa \ [2024-10-02T21:14:14.625Z] -J-Xmx512m \ [2024-10-02T21:14:14.625Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2024-10-02T21:14:14.625Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2024-10-02T21:14:14.625Z] -J-Dtest.compiler.opts= \ [2024-10-02T21:14:14.625Z] -J-Dtest.java.opts= \ [2024-10-02T21:14:14.625Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \ [2024-10-02T21:14:14.625Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \ [2024-10-02T21:14:14.625Z] -J-Dtest.timeout.factor=8.0 \ [2024-10-02T21:14:14.625Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-10-02T21:14:14.625Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2024-10-02T21:14:14.625Z] -J-Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2024-10-02T21:14:14.625Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2024-10-02T21:14:14.625Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2024-10-02T21:14:14.625Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-10-02T21:14:14.625Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2024-10-02T21:14:14.625Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib \ [2024-10-02T21:14:14.625Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib \ [2024-10-02T21:14:14.625Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2024-10-02T21:14:14.625Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-10-02T21:14:14.625Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java [2024-10-02T21:14:14.625Z] [2024-10-02T21:14:14.625Z] ACTION: driver -- Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2024-10-02T21:14:14.625Z] REASON: User specified action: run driver gc.g1.TestSkipRebuildRemsetPhase [2024-10-02T21:14:14.625Z] TIME: 1.579 seconds [2024-10-02T21:14:14.625Z] messages: [2024-10-02T21:14:14.625Z] command: driver gc.g1.TestSkipRebuildRemsetPhase [2024-10-02T21:14:14.625Z] reason: User specified action: run driver gc.g1.TestSkipRebuildRemsetPhase [2024-10-02T21:14:14.625Z] started: Wed Oct 02 21:13:57 UTC 2024 [2024-10-02T21:14:14.625Z] Mode: agentvm [2024-10-02T21:14:14.626Z] Agent id: 21 [2024-10-02T21:14:14.626Z] finished: Wed Oct 02 21:13:59 UTC 2024 [2024-10-02T21:14:14.626Z] elapsed time (seconds): 1.579 [2024-10-02T21:14:14.626Z] configuration: [2024-10-02T21:14:14.626Z] Boot Layer [2024-10-02T21:14:14.626Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2024-10-02T21:14:14.626Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/patches/java.base [2024-10-02T21:14:14.626Z] [2024-10-02T21:14:14.626Z] Test Layer [2024-10-02T21:14:14.626Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-10-02T21:14:14.626Z] [2024-10-02T21:14:14.626Z] rerun: [2024-10-02T21:14:14.626Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/scratch && \ [2024-10-02T21:14:14.626Z] DISPLAY=unix:0 \ [2024-10-02T21:14:14.626Z] HOME=/home/jenkins \ [2024-10-02T21:14:14.626Z] LANG=en_US \ [2024-10-02T21:14:14.626Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-10-02T21:14:14.626Z] TZ=Etc/UTC \ [2024-10-02T21:14:14.626Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/java \ [2024-10-02T21:14:14.626Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2024-10-02T21:14:14.626Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2024-10-02T21:14:14.626Z] -Dtest.compiler.opts= \ [2024-10-02T21:14:14.626Z] -Dtest.java.opts= \ [2024-10-02T21:14:14.626Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \ [2024-10-02T21:14:14.626Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image \ [2024-10-02T21:14:14.626Z] -Dtest.timeout.factor=8.0 \ [2024-10-02T21:14:14.626Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-10-02T21:14:14.626Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2024-10-02T21:14:14.626Z] -Dtest.name=gc/g1/TestSkipRebuildRemsetPhase.java \ [2024-10-02T21:14:14.626Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1/TestSkipRebuildRemsetPhase.java \ [2024-10-02T21:14:14.626Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1 \ [2024-10-02T21:14:14.626Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-10-02T21:14:14.626Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d \ [2024-10-02T21:14:14.626Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib \ [2024-10-02T21:14:14.626Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib \ [2024-10-02T21:14:14.626Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2024-10-02T21:14:14.626Z] gc.g1.TestSkipRebuildRemsetPhase [2024-10-02T21:14:14.626Z] STDOUT: [2024-10-02T21:14:14.626Z] Command line: [/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jdkbinary/j2sdk-image/bin/java -cp /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/gc/g1/TestSkipRebuildRemsetPhase.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/g1:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work/classes/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.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 ] [2024-10-02T21:14:14.626Z] [2024-10-02T21:13:57.698478Z] Gathering output for process 10355054 [2024-10-02T21:14:14.626Z] [2024-10-02T21:13:58.853858Z] Waiting for completion for process 10355054 [2024-10-02T21:14:14.626Z] [2024-10-02T21:13:58.854189Z] Waiting for completion finished for process 10355054 [2024-10-02T21:14:14.626Z] STDERR: [2024-10-02T21:14:14.626Z] stdout: [[0.131s][debug][gc,phases] GC(0) Phase 1: Reference Processing 0.083ms [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) VM Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) ObjectSynchronizer Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][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 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) StringTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.3, Max: 1, Diff: 1, Sum: 1, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 727.3, Max: 1990, Diff: 1990, Sum: 2182, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) ResolvedMethodTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) JNI Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][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 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.626Z] [0.132s][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 [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.132s][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 [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) Phase 1: Weak Processing 0.359ms [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) ClassLoaderData 0.001ms [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) Trigger cleanups 0.000ms [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) Phase 1: Class Unloading and Cleanup 0.143ms [2024-10-02T21:14:14.627Z] [0.132s][debug][gc,phases] GC(0) Report Object Count 0.001ms [2024-10-02T21:14:14.627Z] [0.132s][info ][gc,phases] GC(0) Phase 1: Mark live objects 1.774ms [2024-10-02T21:14:14.627Z] [0.132s][info ][gc,phases] GC(0) Phase 2: Prepare for compaction 0.248ms [2024-10-02T21:14:14.627Z] [0.133s][info ][gc,phases] GC(0) Phase 3: Adjust pointers 0.600ms [2024-10-02T21:14:14.627Z] [0.133s][info ][gc,phases] GC(0) Phase 4: Compact heap 0.464ms [2024-10-02T21:14:14.627Z] [0.134s][info ][gc,phases] GC(1) Pre Evacuate Collection Set: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Prepare TLABs: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Concatenate Dirty Card Logs: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Choose Collection Set: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Region Register: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Prepare Heap Roots: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Clear Claimed Marks: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][info ][gc,phases] GC(1) Merge Heap Roots: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Prepare Merge Heap Roots: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Eager Reclaim (ms): skipped [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Merged Sparse: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Merged Fine: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Merged Coarse: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dirty Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][info ][gc,phases] GC(1) Evacuate Collection Set: 0.1ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.1, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Scanned Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Scanned Blocks: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Claimed Chunks: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 2, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) GC Worker Total (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.2, Workers: 2 [2024-10-02T21:14:14.627Z] [0.134s][info ][gc,phases] GC(1) Post Evacuate Collection Set: 0.1ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Code Roots Fixup: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Reference Processing: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Weak Processing: 0.1ms [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) VM Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) ObjectSynchronizer Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) StringTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 727.3, Max: 1344, Diff: 1344, Sum: 2182, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) ResolvedMethodTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) JNI Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.627Z] [0.134s][debug][gc,phases] GC(1) Post Evacuate Cleanup 1: 0.0ms [2024-10-02T21:14:14.627Z] [0.134s][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 [2024-10-02T21:14:14.628Z] [0.134s][debug][gc,phases] GC(1) Copied Bytes Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.628Z] [0.134s][debug][gc,phases] GC(1) LAB Waste Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.628Z] [0.134s][debug][gc,phases] GC(1) LAB Undo Waste Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.628Z] [0.134s][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 [2024-10-02T21:14:14.628Z] [0.134s][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 [2024-10-02T21:14:14.628Z] [0.134s][debug][gc,phases] GC(1) Post Evacuate Cleanup 2: 0.0ms [2024-10-02T21:14:14.628Z] [0.134s][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 [2024-10-02T21:14:14.628Z] [0.134s][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 [2024-10-02T21:14:14.628Z] [0.134s][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 [2024-10-02T21:14:14.628Z] [0.134s][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 [2024-10-02T21:14:14.628Z] [0.134s][debug][gc,phases] GC(1) Redirtied Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 2 [2024-10-02T21:14:14.628Z] [0.135s][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 [2024-10-02T21:14:14.628Z] [0.135s][debug][gc,phases] GC(1) Rebuild Free List: 0.0ms [2024-10-02T21:14:14.628Z] [0.135s][debug][gc,phases] GC(1) Start New Collection Set: 0.0ms [2024-10-02T21:14:14.628Z] [0.135s][debug][gc,phases] GC(1) Resize TLABs: 0.0ms [2024-10-02T21:14:14.628Z] [0.135s][debug][gc,phases] GC(1) Expand Heap After Collection: 0.0ms [2024-10-02T21:14:14.628Z] [0.135s][info ][gc,phases] GC(1) Other: 0.1ms [2024-10-02T21:14:14.628Z] [0.135s][info ][gc,marking] GC(2) Concurrent Clear Claimed Marks [2024-10-02T21:14:14.628Z] [0.135s][info ][gc,marking] GC(2) Concurrent Clear Claimed Marks 0.011ms [2024-10-02T21:14:14.628Z] [0.135s][info ][gc,marking] GC(2) Concurrent Scan Root Regions [2024-10-02T21:14:14.628Z] [0.135s][info ][gc,marking] GC(2) Concurrent Scan Root Regions 0.005ms [2024-10-02T21:14:14.628Z] [0.135s][info ][gc,marking] GC(2) Concurrent Mark [2024-10-02T21:14:14.628Z] [0.135s][info ][gc,marking] GC(2) Concurrent Mark From Roots [2024-10-02T21:14:14.628Z] [0.136s][info ][gc,marking] GC(2) Concurrent Mark From Roots 1.555ms [2024-10-02T21:14:14.628Z] [0.136s][info ][gc,marking] GC(2) Concurrent Preclean [2024-10-02T21:14:14.628Z] [0.137s][info ][gc,marking] GC(2) Concurrent Preclean 0.235ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Finalize Marking 0.038ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Reference Processing 0.013ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) VM Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 3.7, Max: 11, Diff: 11, Sum: 11, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) ObjectSynchronizer Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 1.0, Max: 3, Diff: 3, Sum: 3, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][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 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) StringTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 727.3, Max: 1094, Diff: 1094, Sum: 2182, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) ResolvedMethodTable Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 16.7, Max: 50, Diff: 50, Sum: 50, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) JNI Weak Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][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 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][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 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][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 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Dead Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Total Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 3 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Weak Processing 0.255ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) ClassLoaderData 0.001ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Trigger cleanups 0.000ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Class Unloading 0.287ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Flush Task Caches 0.020ms [2024-10-02T21:14:14.628Z] [0.137s][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) [2024-10-02T21:14:14.628Z] [0.137s][trace][gc,remset,tracking] GC(2) Before rebuild region 1 (ntams: 0x00000000f01cf188) total_live_bytes 848264 selected false (live_bytes 848264 next_marked 0 marked 0 type OLD) [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,remset,tracking] GC(2) Remembered Set Tracking update regions total 32, selected 1 [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Update Remembered Set Tracking Before Rebuild 0.053ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Reclaim Empty Regions 0.017ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Purge Metaspace 0.001ms [2024-10-02T21:14:14.628Z] [0.137s][debug][gc,phases ] GC(2) Report Object Count 0.001ms [2024-10-02T21:14:14.628Z] [0.137s][info ][gc,marking ] GC(2) Concurrent Mark 2.620ms [2024-10-02T21:14:14.628Z] [0.137s][info ][gc,marking ] GC(2) Concurrent Rebuild Remembered Sets [2024-10-02T21:14:14.628Z] [0.138s][trace][gc,remset,tracking] GC(2) Rebuilt region 1 live 848264 time 0.001ms marked bytes 349544 bot 0x00000000f0100000 TAMS 0x00000000f01cf188 TARS 0x00000000f01cf188 [2024-10-02T21:14:14.628Z] [0.138s][trace][gc,remset,tracking] GC(2) Rebuilt region 0 live 95568 time 0.134ms marked bytes 95568 bot 0x00000000f0000000 TAMS 0x00000000f0017550 TARS 0x00000000f0017550 [2024-10-02T21:14:14.628Z] [0.138s][trace][gc,remset,tracking] GC(2) Rebuilt region 1 live 848264 time 0.171ms marked bytes 184040 bot 0x00000000f0100000 TAMS 0x00000000f01cf188 TARS 0x00000000f01cf188 [2024-10-02T21:14:14.628Z] [0.138s][trace][gc,remset,tracking] GC(2) Rebuilt region 1 live 848264 time 0.335ms marked bytes 252872 bot 0x00000000f0100000 TAMS 0x00000000f01cf188 TARS 0x00000000f01cf188 [2024-10-02T21:14:14.628Z] [0.138s][trace][gc,remset,tracking] GC(2) Rebuilt region 1 live 848264 time 0.089ms marked bytes 61808 bot 0x00000000f0100000 TAMS 0x00000000f01cf188 TARS 0x00000000f01cf188 [2024-10-02T21:14:14.628Z] [0.138s][info ][gc,marking ] GC(2) Concurrent Rebuild Remembered Sets 0.686ms [2024-10-02T21:14:14.628Z] [0.138s][trace][gc,remset,tracking] GC(2) After rebuild region 0 (ntams 0x00000000f0000000 liveness 95568 next_marked_bytes 0 remset occ 33 size 3240) [2024-10-02T21:14:14.628Z] [0.138s][trace][gc,remset,tracking] GC(2) After rebuild region 1 (ntams 0x00000000f0100000 liveness 848264 next_marked_bytes 0 remset occ 0 size 3152) [2024-10-02T21:14:14.628Z] [0.138s][debug][gc,phases ] GC(2) Update Remembered Set Tracking After Rebuild 0.016ms [2024-10-02T21:14:14.628Z] [0.138s][debug][gc,phases ] GC(2) Finalize Concurrent Mark Cleanup 0.043ms [2024-10-02T21:14:14.628Z] [0.138s][info ][gc,marking ] GC(2) Concurrent Cleanup for Next Mark [2024-10-02T21:14:14.628Z] [0.138s][info ][gc,marking ] GC(2) Concurrent Cleanup for Next Mark 0.126ms [2024-10-02T21:14:14.628Z] [B@2437c6dc [2024-10-02T21:14:14.628Z] ]; [2024-10-02T21:14:14.628Z] stderr: [] [2024-10-02T21:14:14.629Z] exitValue = 0 [2024-10-02T21:14:14.629Z] [2024-10-02T21:14:14.629Z] java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2024-10-02T21:14:14.629Z] [2024-10-02T21:14:14.629Z] at jdk.test.lib.process.OutputAnalyzer.shouldContain(OutputAnalyzer.java:221) [2024-10-02T21:14:14.629Z] at gc.g1.TestSkipRebuildRemsetPhase.main(TestSkipRebuildRemsetPhase.java:54) [2024-10-02T21:14:14.629Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2024-10-02T21:14:14.629Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2024-10-02T21:14:14.629Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2024-10-02T21:14:14.629Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2024-10-02T21:14:14.629Z] at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333) [2024-10-02T21:14:14.629Z] at java.base/java.lang.Thread.run(Thread.java:840) [2024-10-02T21:14:14.629Z] [2024-10-02T21:14:14.629Z] JavaTest Message: Test threw exception: java.lang.RuntimeException [2024-10-02T21:14:14.629Z] JavaTest Message: shutting down test [2024-10-02T21:14:14.629Z] [2024-10-02T21:14:14.629Z] [2024-10-02T21:14:14.629Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Skipping Remembered Set Rebuild.' missing from stdout/stderr [2024-10-02T21:14:14.629Z] -------------------------------------------------- [2024-10-02T22:18:24.565Z] Test results: passed: 225; failed: 1 [2024-10-02T22:18:27.969Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/report/html/report.html [2024-10-02T22:18:27.969Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_2/aqa-tests/TKG/output_17279025709856/hotspot_gc_0/work [2024-10-02T22:18:27.969Z] Error: Some tests failed or other problems occurred. [2024-10-02T22:18:27.969Z] ----------------------------------- [2024-10-02T22:18:27.969Z] hotspot_gc_0_FAILED [2024-10-02T22:18:27.969Z] ----------------------------------- [2024-10-02T22:18:27.969Z] [2024-10-02T22:18:27.969Z] TEST TEARDOWN: [2024-10-02T22:18:27.969Z] Nothing to be done for teardown. [2024-10-02T22:18:27.969Z] hotspot_gc_0 Finish Time: Wed Oct 2 22:18:27 2024 Epoch Time (ms): 1727907507816