[2025-01-10T04:36:56.310Z] Running test hotspot_gc_0 ... [2025-01-10T04:36:56.310Z] =============================================== [2025-01-10T04:36:56.310Z] hotspot_gc_0 Start Time: Thu Jan 9 20:36:55 2025 Epoch Time (ms): 1736483815726 [2025-01-10T04:36:56.310Z] variation: NoOptions [2025-01-10T04:36:56.310Z] JVM_OPTIONS: [2025-01-10T04:36:56.310Z] { \ [2025-01-10T04:36:56.310Z] echo ""; echo "TEST SETUP:"; \ [2025-01-10T04:36:56.310Z] echo "Nothing to be done for setup."; \ [2025-01-10T04:36:56.310Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17364838142363/hotspot_gc_0"; \ [2025-01-10T04:36:56.310Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17364838142363/hotspot_gc_0"; \ [2025-01-10T04:36:56.310Z] echo ""; echo "TESTING:"; \ [2025-01-10T04:36:56.311Z] "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/..//bin/java" -Xmx512m -jar "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-01-10T04:36:56.311Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:2 -nativepath:"/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2025-01-10T04:36:56.311Z] -w ""/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17364838142363/hotspot_gc_0"/work" \ [2025-01-10T04:36:56.311Z] -r ""/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17364838142363/hotspot_gc_0"/report" \ [2025-01-10T04:36:56.311Z] -jdk:"/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../" \ [2025-01-10T04:36:56.311Z] -exclude:"/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2025-01-10T04:36:56.311Z] -exclude:"/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk25.txt" \ [2025-01-10T04:36:56.311Z] \ [2025-01-10T04:36:56.311Z] \ [2025-01-10T04:36:56.311Z] "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \ [2025-01-10T04:36:56.311Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-10T04:36:56.311Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-10T04:36:56.311Z] echo "Nothing to be done for teardown."; \ [2025-01-10T04:36:56.311Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17364838142363/TestTargetResult"; [2025-01-10T04:36:56.311Z] [2025-01-10T04:36:56.311Z] TEST SETUP: [2025-01-10T04:36:56.311Z] Nothing to be done for setup. [2025-01-10T04:36:56.311Z] [2025-01-10T04:36:56.311Z] TESTING: [2025-01-10T04:36:57.219Z] Directory "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17364838142363/hotspot_gc_0/work" not found: creating [2025-01-10T04:36:57.219Z] Directory "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17364838142363/hotspot_gc_0/report" not found: creating [2025-01-10T04:37:02.775Z] XML output with verification to /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work [2025-01-10T05:06:28.241Z] -------------------------------------------------- [2025-01-10T05:06:28.241Z] TEST: gc/shenandoah/TestReferenceRefersToShenandoah.java#generational [2025-01-10T05:06:28.241Z] TEST JDK: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. [2025-01-10T05:06:28.241Z] [2025-01-10T05:06:28.241Z] ACTION: build -- Passed. All files up to date [2025-01-10T05:06:28.241Z] REASON: User specified action: run build jdk.test.whitebox.WhiteBox [2025-01-10T05:06:28.241Z] TIME: 0.0 seconds [2025-01-10T05:06:28.241Z] messages: [2025-01-10T05:06:28.241Z] command: build jdk.test.whitebox.WhiteBox [2025-01-10T05:06:28.241Z] reason: User specified action: run build jdk.test.whitebox.WhiteBox [2025-01-10T05:06:28.241Z] started: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.241Z] finished: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.241Z] elapsed time (seconds): 0.0 [2025-01-10T05:06:28.241Z] [2025-01-10T05:06:28.241Z] ACTION: build -- Passed. All files up to date [2025-01-10T05:06:28.241Z] REASON: Named class compiled on demand [2025-01-10T05:06:28.241Z] TIME: 0.0 seconds [2025-01-10T05:06:28.241Z] messages: [2025-01-10T05:06:28.241Z] command: build jdk.test.lib.helpers.ClassFileInstaller [2025-01-10T05:06:28.241Z] reason: Named class compiled on demand [2025-01-10T05:06:28.241Z] started: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.241Z] finished: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.241Z] elapsed time (seconds): 0.0 [2025-01-10T05:06:28.241Z] [2025-01-10T05:06:28.241Z] ACTION: driver -- Passed. Execution successful [2025-01-10T05:06:28.241Z] REASON: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-01-10T05:06:28.241Z] TIME: 0.01 seconds [2025-01-10T05:06:28.241Z] messages: [2025-01-10T05:06:28.241Z] command: driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-01-10T05:06:28.241Z] reason: User specified action: run driver jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-01-10T05:06:28.241Z] started: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.241Z] Mode: agentvm [2025-01-10T05:06:28.241Z] Agent id: 22 [2025-01-10T05:06:28.241Z] finished: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.241Z] elapsed time (seconds): 0.01 [2025-01-10T05:06:28.241Z] configuration: [2025-01-10T05:06:28.241Z] Boot Layer [2025-01-10T05:06:28.241Z] class path: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-01-10T05:06:28.241Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-01-10T05:06:28.241Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.10.2.jar [2025-01-10T05:06:28.241Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2025-01-10T05:06:28.241Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2025-01-10T05:06:28.241Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2025-01-10T05:06:28.241Z] patch: java.base /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/patches/java.base [2025-01-10T05:06:28.241Z] [2025-01-10T05:06:28.241Z] Test Layer [2025-01-10T05:06:28.241Z] class path: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah [2025-01-10T05:06:28.241Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib [2025-01-10T05:06:28.241Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-01-10T05:06:28.241Z] [2025-01-10T05:06:28.241Z] rerun: [2025-01-10T05:06:28.241Z] cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/scratch/1 && \ [2025-01-10T05:06:28.241Z] HOME=/Users/admin \ [2025-01-10T05:06:28.241Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-01-10T05:06:28.241Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/java \ [2025-01-10T05:06:28.241Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-01-10T05:06:28.241Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-01-10T05:06:28.241Z] -Dtest.compiler.opts= \ [2025-01-10T05:06:28.241Z] -Dtest.java.opts= \ [2025-01-10T05:06:28.241Z] -Dtest.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:06:28.241Z] -Dcompile.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:06:28.241Z] -Dtest.timeout.factor=8.0 \ [2025-01-10T05:06:28.241Z] -Dtest.nativepath=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-01-10T05:06:28.241Z] -Dtest.root=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-01-10T05:06:28.241Z] -Dtest.name=gc/shenandoah/TestReferenceRefersToShenandoah.java#generational \ [2025-01-10T05:06:28.241Z] -Dtest.file=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah/TestReferenceRefersToShenandoah.java \ [2025-01-10T05:06:28.241Z] -Dtest.src=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah \ [2025-01-10T05:06:28.241Z] -Dtest.src.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-01-10T05:06:28.241Z] -Dtest.classes=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d \ [2025-01-10T05:06:28.242Z] -Dtest.class.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib \ [2025-01-10T05:06:28.242Z] -Dtest.class.path.prefix=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib \ [2025-01-10T05:06:28.242Z] -classpath /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-01-10T05:06:28.242Z] jdk.test.lib.helpers.ClassFileInstaller jdk.test.whitebox.WhiteBox [2025-01-10T05:06:28.242Z] STDERR: [2025-01-10T05:06:28.242Z] [2025-01-10T05:06:28.242Z] JavaTest Message: Test complete. [2025-01-10T05:06:28.242Z] [2025-01-10T05:06:28.242Z] [2025-01-10T05:06:28.242Z] ACTION: build -- Passed. Build successful [2025-01-10T05:06:28.242Z] REASON: Named class compiled on demand [2025-01-10T05:06:28.242Z] TIME: 0.017 seconds [2025-01-10T05:06:28.242Z] messages: [2025-01-10T05:06:28.242Z] command: build gc.shenandoah.TestReferenceRefersToShenandoah [2025-01-10T05:06:28.242Z] reason: Named class compiled on demand [2025-01-10T05:06:28.242Z] started: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.242Z] Test directory: [2025-01-10T05:06:28.242Z] compile: gc.shenandoah.TestReferenceRefersToShenandoah [2025-01-10T05:06:28.242Z] finished: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.242Z] elapsed time (seconds): 0.017 [2025-01-10T05:06:28.242Z] [2025-01-10T05:06:28.242Z] ACTION: compile -- Passed. Compilation successful [2025-01-10T05:06:28.242Z] REASON: .class file out of date or does not exist [2025-01-10T05:06:28.242Z] TIME: 0.017 seconds [2025-01-10T05:06:28.242Z] messages: [2025-01-10T05:06:28.242Z] command: compile /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah/TestReferenceRefersToShenandoah.java [2025-01-10T05:06:28.242Z] reason: .class file out of date or does not exist [2025-01-10T05:06:28.242Z] started: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.242Z] Mode: agentvm [2025-01-10T05:06:28.242Z] Agent id: 2 [2025-01-10T05:06:28.242Z] finished: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.242Z] elapsed time (seconds): 0.017 [2025-01-10T05:06:28.242Z] configuration: [2025-01-10T05:06:28.242Z] Boot Layer (javac runtime environment) [2025-01-10T05:06:28.242Z] class path: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-01-10T05:06:28.242Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-01-10T05:06:28.242Z] patch: java.base /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/patches/java.base [2025-01-10T05:06:28.242Z] [2025-01-10T05:06:28.242Z] javac compilation environment [2025-01-10T05:06:28.242Z] source path: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah [2025-01-10T05:06:28.242Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-01-10T05:06:28.242Z] class path: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah [2025-01-10T05:06:28.242Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d [2025-01-10T05:06:28.242Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib [2025-01-10T05:06:28.242Z] [2025-01-10T05:06:28.242Z] rerun: [2025-01-10T05:06:28.242Z] cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/scratch/1 && \ [2025-01-10T05:06:28.242Z] HOME=/Users/admin \ [2025-01-10T05:06:28.242Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-01-10T05:06:28.242Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/javac \ [2025-01-10T05:06:28.242Z] -J-ea \ [2025-01-10T05:06:28.242Z] -J-esa \ [2025-01-10T05:06:28.242Z] -J-Xmx512m \ [2025-01-10T05:06:28.242Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-01-10T05:06:28.242Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-01-10T05:06:28.242Z] -J-Dtest.compiler.opts= \ [2025-01-10T05:06:28.242Z] -J-Dtest.java.opts= \ [2025-01-10T05:06:28.242Z] -J-Dtest.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:06:28.242Z] -J-Dcompile.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:06:28.242Z] -J-Dtest.timeout.factor=8.0 \ [2025-01-10T05:06:28.242Z] -J-Dtest.nativepath=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-01-10T05:06:28.242Z] -J-Dtest.root=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-01-10T05:06:28.242Z] -J-Dtest.name=gc/shenandoah/TestReferenceRefersToShenandoah.java#generational \ [2025-01-10T05:06:28.242Z] -J-Dtest.file=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah/TestReferenceRefersToShenandoah.java \ [2025-01-10T05:06:28.242Z] -J-Dtest.src=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah \ [2025-01-10T05:06:28.242Z] -J-Dtest.src.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-01-10T05:06:28.242Z] -J-Dtest.classes=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d \ [2025-01-10T05:06:28.243Z] -J-Dtest.class.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib \ [2025-01-10T05:06:28.243Z] -J-Dtest.class.path.prefix=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib \ [2025-01-10T05:06:28.243Z] -d /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d \ [2025-01-10T05:06:28.243Z] -sourcepath /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-01-10T05:06:28.243Z] -classpath /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah/TestReferenceRefersToShenandoah.java [2025-01-10T05:06:28.243Z] [2025-01-10T05:06:28.243Z] ACTION: main -- Failed. Unexpected exit from test [exit code: 134] [2025-01-10T05:06:28.243Z] REASON: User specified action: run main/othervm -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational gc.shenandoah.TestReferenceRefersToShenandoah [2025-01-10T05:06:28.243Z] TIME: 0.526 seconds [2025-01-10T05:06:28.243Z] messages: [2025-01-10T05:06:28.243Z] command: main -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational gc.shenandoah.TestReferenceRefersToShenandoah [2025-01-10T05:06:28.243Z] reason: User specified action: run main/othervm -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational gc.shenandoah.TestReferenceRefersToShenandoah [2025-01-10T05:06:28.243Z] started: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.243Z] Mode: othervm [/othervm specified] [2025-01-10T05:06:28.243Z] finished: Thu Jan 09 21:06:18 PST 2025 [2025-01-10T05:06:28.243Z] elapsed time (seconds): 0.526 [2025-01-10T05:06:28.243Z] configuration: [2025-01-10T05:06:28.243Z] STDOUT: [2025-01-10T05:06:28.243Z] setup concurrent collection test [2025-01-10T05:06:28.243Z] gcUntilOld [2025-01-10T05:06:28.243Z] Warning: object not promoted by full gc [2025-01-10T05:06:28.243Z] Warning: object not promoted by full gc [2025-01-10T05:06:28.243Z] Warning: object not promoted by full gc [2025-01-10T05:06:28.243Z] # [2025-01-10T05:06:28.243Z] # A fatal error has been detected by the Java Runtime Environment: [2025-01-10T05:06:28.243Z] # [2025-01-10T05:06:28.243Z] # SIGSEGV (0xb) at pc=0x000000010d609c45, pid=43054, tid=13827 [2025-01-10T05:06:28.243Z] # [2025-01-10T05:06:28.243Z] # JRE version: OpenJDK Runtime Environment Temurin-25+5-202501092134 (25.0+5) (build 25-beta+5-ea) [2025-01-10T05:06:28.243Z] # Java VM: OpenJDK 64-Bit Server VM Temurin-25+5-202501092134 (25-beta+5-ea, mixed mode, tiered, compressed oops, compressed class ptrs, shenandoah gc, bsd-amd64) [2025-01-10T05:06:28.243Z] # Problematic frame: [2025-01-10T05:06:28.243Z] # V [libjvm.dylib+0xae3c45] ShenandoahCardCluster::coalesce_objects(HeapWordImpl**, unsigned long)+0x115 [2025-01-10T05:06:28.243Z] # [2025-01-10T05:06:28.243Z] # No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again [2025-01-10T05:06:28.243Z] # [2025-01-10T05:06:28.243Z] Unsupported internal testing APIs have been used. [2025-01-10T05:06:28.243Z] [2025-01-10T05:06:28.243Z] # An error report file with more information is saved as: [2025-01-10T05:06:28.243Z] # /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/scratch/1/hs_err_pid43054.log [2025-01-10T05:06:28.243Z] # [2025-01-10T05:06:28.243Z] # If you would like to submit a bug report, please visit: [2025-01-10T05:06:28.243Z] # https://github.com/adoptium/adoptium-support/issues [2025-01-10T05:06:28.243Z] # [2025-01-10T05:06:28.243Z] STDERR: [2025-01-10T05:06:28.243Z] rerun: [2025-01-10T05:06:28.243Z] cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/scratch/1 && \ [2025-01-10T05:06:28.243Z] HOME=/Users/admin \ [2025-01-10T05:06:28.243Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-01-10T05:06:28.243Z] CLASSPATH=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-01-10T05:06:28.243Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/java \ [2025-01-10T05:06:28.243Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-01-10T05:06:28.243Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-01-10T05:06:28.243Z] -Dtest.compiler.opts= \ [2025-01-10T05:06:28.243Z] -Dtest.java.opts= \ [2025-01-10T05:06:28.243Z] -Dtest.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:06:28.243Z] -Dcompile.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:06:28.243Z] -Dtest.timeout.factor=8.0 \ [2025-01-10T05:06:28.243Z] -Dtest.nativepath=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-01-10T05:06:28.243Z] -Dtest.root=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-01-10T05:06:28.243Z] -Dtest.name=gc/shenandoah/TestReferenceRefersToShenandoah.java#generational \ [2025-01-10T05:06:28.243Z] -Dtest.file=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah/TestReferenceRefersToShenandoah.java \ [2025-01-10T05:06:28.243Z] -Dtest.src=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah \ [2025-01-10T05:06:28.243Z] -Dtest.src.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2025-01-10T05:06:28.243Z] -Dtest.classes=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d \ [2025-01-10T05:06:28.243Z] -Dtest.class.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib \ [2025-01-10T05:06:28.243Z] -Dtest.class.path.prefix=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/shenandoah:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/1/test/lib \ [2025-01-10T05:06:28.243Z] -ea \ [2025-01-10T05:06:28.243Z] -esa \ [2025-01-10T05:06:28.243Z] -Xmx512m \ [2025-01-10T05:06:28.243Z] -Djava.library.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-01-10T05:06:28.243Z] -Xbootclasspath/a:. \ [2025-01-10T05:06:28.243Z] -XX:+UnlockDiagnosticVMOptions \ [2025-01-10T05:06:28.243Z] -XX:+WhiteBoxAPI \ [2025-01-10T05:06:28.243Z] -XX:+UnlockExperimentalVMOptions \ [2025-01-10T05:06:28.243Z] -XX:+UseShenandoahGC \ [2025-01-10T05:06:28.243Z] -XX:ShenandoahGCMode=generational \ [2025-01-10T05:06:28.244Z] com.sun.javatest.regtest.agent.MainWrapper /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/gc/shenandoah/TestReferenceRefersToShenandoah_generational.d/main.0.jta [2025-01-10T05:06:28.244Z] [2025-01-10T05:06:28.244Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 134] [2025-01-10T05:06:28.244Z] -------------------------------------------------- [2025-01-10T05:44:43.968Z] TEST: gc/stress/systemgc/TestSystemGCWithShenandoah.java#generational [2025-01-10T05:44:43.968Z] TEST JDK: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. [2025-01-10T05:44:43.968Z] [2025-01-10T05:44:43.968Z] ACTION: build -- Passed. Build successful [2025-01-10T05:44:43.968Z] REASON: Named class compiled on demand [2025-01-10T05:44:43.968Z] TIME: 0.022 seconds [2025-01-10T05:44:43.968Z] messages: [2025-01-10T05:44:43.968Z] command: build gc.stress.systemgc.TestSystemGCWithShenandoah [2025-01-10T05:44:43.968Z] reason: Named class compiled on demand [2025-01-10T05:44:43.968Z] started: Thu Jan 09 21:43:53 PST 2025 [2025-01-10T05:44:43.968Z] Test directory: [2025-01-10T05:44:43.968Z] compile: gc.stress.systemgc.TestSystemGCWithShenandoah [2025-01-10T05:44:43.968Z] finished: Thu Jan 09 21:43:53 PST 2025 [2025-01-10T05:44:43.968Z] elapsed time (seconds): 0.022 [2025-01-10T05:44:43.968Z] [2025-01-10T05:44:43.968Z] ACTION: compile -- Passed. Compilation successful [2025-01-10T05:44:43.968Z] REASON: .class file out of date or does not exist [2025-01-10T05:44:43.968Z] TIME: 0.021 seconds [2025-01-10T05:44:43.968Z] messages: [2025-01-10T05:44:43.968Z] command: compile /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc/TestSystemGCWithShenandoah.java [2025-01-10T05:44:43.968Z] reason: .class file out of date or does not exist [2025-01-10T05:44:43.968Z] started: Thu Jan 09 21:43:53 PST 2025 [2025-01-10T05:44:43.968Z] Mode: agentvm [2025-01-10T05:44:43.968Z] Agent id: 1 [2025-01-10T05:44:43.968Z] finished: Thu Jan 09 21:43:53 PST 2025 [2025-01-10T05:44:43.968Z] elapsed time (seconds): 0.021 [2025-01-10T05:44:43.968Z] configuration: [2025-01-10T05:44:43.968Z] Boot Layer (javac runtime environment) [2025-01-10T05:44:43.968Z] class path: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-01-10T05:44:43.968Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-01-10T05:44:43.968Z] patch: java.base /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/patches/java.base [2025-01-10T05:44:43.968Z] [2025-01-10T05:44:43.968Z] javac compilation environment [2025-01-10T05:44:43.968Z] source path: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc [2025-01-10T05:44:43.968Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg [2025-01-10T05:44:43.968Z] class path: /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc [2025-01-10T05:44:43.968Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d [2025-01-10T05:44:43.968Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0 [2025-01-10T05:44:43.968Z] [2025-01-10T05:44:43.968Z] rerun: [2025-01-10T05:44:43.968Z] cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/scratch/0 && \ [2025-01-10T05:44:43.968Z] HOME=/Users/admin \ [2025-01-10T05:44:43.968Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-01-10T05:44:43.968Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/javac \ [2025-01-10T05:44:43.968Z] -J-ea \ [2025-01-10T05:44:43.968Z] -J-esa \ [2025-01-10T05:44:43.968Z] -J-Xmx512m \ [2025-01-10T05:44:43.968Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-01-10T05:44:43.968Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-01-10T05:44:43.968Z] -J-Dtest.compiler.opts= \ [2025-01-10T05:44:43.968Z] -J-Dtest.java.opts= \ [2025-01-10T05:44:43.968Z] -J-Dtest.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:44:43.968Z] -J-Dcompile.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:44:43.968Z] -J-Dtest.timeout.factor=8.0 \ [2025-01-10T05:44:43.968Z] -J-Dtest.nativepath=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-01-10T05:44:43.968Z] -J-Dtest.root=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-01-10T05:44:43.968Z] -J-Dtest.name=gc/stress/systemgc/TestSystemGCWithShenandoah.java#generational \ [2025-01-10T05:44:43.968Z] -J-Dtest.file=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc/TestSystemGCWithShenandoah.java \ [2025-01-10T05:44:43.968Z] -J-Dtest.src=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc \ [2025-01-10T05:44:43.968Z] -J-Dtest.src.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-01-10T05:44:43.968Z] -J-Dtest.classes=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d \ [2025-01-10T05:44:43.968Z] -J-Dtest.class.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0 \ [2025-01-10T05:44:43.969Z] -J-Dtest.class.path.prefix=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0 \ [2025-01-10T05:44:43.969Z] -d /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d \ [2025-01-10T05:44:43.969Z] -sourcepath /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-01-10T05:44:43.969Z] -classpath /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0 /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc/TestSystemGCWithShenandoah.java [2025-01-10T05:44:43.969Z] [2025-01-10T05:44:43.969Z] ACTION: main -- Failed. Unexpected exit from test [exit code: 134] [2025-01-10T05:44:43.969Z] REASON: User specified action: run main/othervm/timeout=300 -Xlog:gc*=info -Xmx512m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify gc.stress.systemgc.TestSystemGCWithShenandoah 270 [2025-01-10T05:44:43.969Z] TIME: 47.063 seconds [2025-01-10T05:44:43.969Z] messages: [2025-01-10T05:44:43.969Z] command: main -Xlog:gc*=info -Xmx512m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify gc.stress.systemgc.TestSystemGCWithShenandoah 270 [2025-01-10T05:44:43.969Z] reason: User specified action: run main/othervm/timeout=300 -Xlog:gc*=info -Xmx512m -XX:+UnlockExperimentalVMOptions -XX:+UnlockDiagnosticVMOptions -XX:+UseShenandoahGC -XX:ShenandoahGCMode=generational -XX:+ShenandoahVerify gc.stress.systemgc.TestSystemGCWithShenandoah 270 [2025-01-10T05:44:43.969Z] started: Thu Jan 09 21:43:53 PST 2025 [2025-01-10T05:44:43.969Z] Mode: othervm [/othervm specified] [2025-01-10T05:44:43.969Z] finished: Thu Jan 09 21:44:40 PST 2025 [2025-01-10T05:44:43.969Z] elapsed time (seconds): 47.063 [2025-01-10T05:44:43.969Z] configuration: [2025-01-10T05:44:43.969Z] STDOUT: [2025-01-10T05:44:43.969Z] [0.013s][info][gc,init] CardTable entry size: 512 [2025-01-10T05:44:43.969Z] [0.013s][info][gc ] Heuristics ergonomically sets -XX:GCTimeRatio=70 [2025-01-10T05:44:43.969Z] [0.013s][info][gc ] Heuristics ergonomically sets -XX:+ExplicitGCInvokesConcurrent [2025-01-10T05:44:43.969Z] [0.013s][info][gc ] Heuristics ergonomically sets -XX:+ShenandoahImplicitGCInvokesConcurrent [2025-01-10T05:44:43.969Z] [0.013s][info][gc ] Heuristics ergonomically sets -XX:+UseCondCardMark [2025-01-10T05:44:43.969Z] [0.013s][info][gc ] Using Shenandoah [2025-01-10T05:44:43.969Z] [0.014s][info][gc,ref ] Clearing All SoftReferences [2025-01-10T05:44:43.969Z] [0.020s][info][gc,free] Free: 486M, Max: 256K regular, 486M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1945 Collector Reserve: 26368K, Max: 256K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.969Z] [0.020s][info][gc,ergo] Pacer for Idle. Initial: 10485K, Alloc Tax Rate: 1.0x [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Version: 25-beta+5-ea (release) [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] CPUs: 3 total, 3 available [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Memory: 14415M [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Large Page Support: Disabled [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] NUMA Support: Disabled [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Compressed Oops: Enabled (Zero based) [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Heap Min Capacity: 6656K [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Heap Initial Capacity: 230656K [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Heap Max Capacity: 512M [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Pre-touch: Disabled [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Heap Region Count: 2048 [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Heap Region Size: 256K [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] TLAB Size Max: 256K [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Young Generation Soft Size: 512M [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Young Generation Max: 512M [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Old Generation Soft Size: 0B [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Old Generation Max: 0B [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Parallel Workers: 1 [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Concurrent Workers: 1 [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Mode: Generational [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Heuristics: Adaptive [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Young Heuristics: Adaptive [2025-01-10T05:44:43.969Z] [0.021s][info][gc,init] Old Heuristics: Old [2025-01-10T05:44:43.969Z] [0.021s][info][gc,metaspace] CDS archive(s) not mapped [2025-01-10T05:44:43.969Z] [0.021s][info][gc,metaspace] Compressed class space mapped at: 0x0000000120000000-0x0000000160000000, reserved size: 1073741824 [2025-01-10T05:44:43.969Z] [0.021s][info][gc,metaspace] UseCompressedClassPointers 1, UseCompactObjectHeaders 0 [2025-01-10T05:44:43.969Z] [0.021s][info][gc,metaspace] Narrow klass pointer bits 32, Max shift 3 [2025-01-10T05:44:43.969Z] [0.021s][info][gc,metaspace] Narrow klass base: 0x0000000000000000, Narrow klass shift: 3 [2025-01-10T05:44:43.969Z] [0.021s][info][gc,metaspace] Encoding Range: [0x0000000000000000 - 0x0000000800000000), (34359738368 bytes) [2025-01-10T05:44:43.969Z] [0.021s][info][gc,metaspace] Klass Range: [0x0000000120000000 - 0x0000000160000000), (1073741824 bytes) [2025-01-10T05:44:43.969Z] [0.021s][info][gc,metaspace] Klass ID Range: [603979776 - 738197504) (134217728) [2025-01-10T05:44:43.969Z] Running with timeout of 270 seconds [2025-01-10T05:44:43.969Z] [0.682s][info][gc ] Trigger (Young): Learning 1 of 5. Free (358M) is below initial threshold (358M) [2025-01-10T05:44:43.969Z] [0.682s][info][gc,free ] Free: 358M, Max: 256K regular, 358M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1432 Collector Reserve: 26368K, Max: 256K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.969Z] [0.682s][info][gc,ergo ] GC(0) Start GC cycle (Young) [2025-01-10T05:44:43.969Z] [0.683s][info][gc,start ] GC(0) Concurrent reset (Young) [2025-01-10T05:44:43.969Z] [0.683s][info][gc,task ] GC(0) Using 1 of 1 workers for Concurrent reset (Young) [2025-01-10T05:44:43.969Z] [0.683s][info][gc,ergo ] GC(0) Pacer for Reset. Non-Taxable: 512M [2025-01-10T05:44:43.969Z] [0.683s][info][gc ] GC(0) Concurrent reset (Young) 0.184ms [2025-01-10T05:44:43.969Z] [0.683s][info][gc,start ] GC(0) Pause Init Mark (Young) [2025-01-10T05:44:43.969Z] [0.683s][info][gc,task ] GC(0) Using 1 of 1 workers for init marking [2025-01-10T05:44:43.969Z] [0.683s][info][gc,start ] GC(0) Verify Before Mark, Level 4 [2025-01-10T05:44:43.969Z] [0.696s][info][gc,remset ] GC(0) Scan remembered set using bitmap: true [2025-01-10T05:44:43.969Z] [0.868s][info][gc ] GC(0) Verify Before Mark, Level 4 (4142785 reachable, 0 marked) [2025-01-10T05:44:43.969Z] [0.868s][info][gc,ergo ] GC(0) Pacer for Mark. Expected Live: 52428K, Free: 358M, Non-Taxable: 36659K, Alloc Tax Rate: 0.2x [2025-01-10T05:44:43.969Z] [0.868s][info][gc ] GC(0) Pause Init Mark (Young) 185.582ms [2025-01-10T05:44:43.969Z] [0.869s][info][gc,start ] GC(0) Concurrent remembered set scanning [2025-01-10T05:44:43.969Z] [0.869s][info][gc,task ] GC(0) Using 1 of 1 workers for Concurrent remembered set scanning [2025-01-10T05:44:43.969Z] [0.869s][info][gc,remset ] GC(0) Scan remembered set using bitmap: true [2025-01-10T05:44:43.969Z] [0.869s][info][gc ] GC(0) Concurrent remembered set scanning 0.186ms [2025-01-10T05:44:43.969Z] [0.869s][info][gc,start ] GC(0) Concurrent marking roots [2025-01-10T05:44:43.969Z] [0.869s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent marking roots [2025-01-10T05:44:43.969Z] [0.869s][info][gc ] GC(0) Concurrent marking roots 0.318ms [2025-01-10T05:44:43.969Z] [0.869s][info][gc,start ] GC(0) Concurrent marking (Young) [2025-01-10T05:44:43.969Z] [0.869s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent marking [2025-01-10T05:44:43.969Z] [1.033s][info][gc ] GC(0) Concurrent marking (Young) 163.425ms [2025-01-10T05:44:43.969Z] [1.033s][info][gc,start ] GC(0) Pause Final Mark (Young) [2025-01-10T05:44:43.969Z] [1.033s][info][gc,task ] GC(0) Using 1 of 1 workers for final marking [2025-01-10T05:44:43.969Z] [1.033s][info][gc,remset ] GC(0) Scan remembered set using bitmap: true [2025-01-10T05:44:43.969Z] [1.033s][info][gc,age ] GC(0) ---------------------------------------------------------------------------- [2025-01-10T05:44:43.969Z] [1.033s][info][gc,ergo ] GC(0) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-01-10T05:44:43.969Z] [1.033s][info][gc,ergo ] GC(0) Adaptive CSet Selection for YOUNG. Max Evacuation: 21845K, Actual Free: 0B. [2025-01-10T05:44:43.969Z] [1.033s][info][gc,ergo ] GC(0) Chosen CSet evacuates young: 1199K (of which at least: 0B are to be promoted), old: 0B [2025-01-10T05:44:43.969Z] [1.033s][info][gc,ergo ] GC(0) Collectable Garbage: 1104K (78%), Immediate: 0B (0%), 0 regions, CSet: 1104K (78%), 9 regions [2025-01-10T05:44:43.969Z] [1.033s][info][gc,ergo ] GC(0) Evacuation Targets: YOUNG: 1199K, PROMOTE: 0B, OLD: 0B, TOTAL: 1199K [2025-01-10T05:44:43.969Z] [1.033s][info][gc,free ] Free: 355M, Max: 256K regular, 331M humongous, Frag: 7% external, 0% internal; Used: 0B, Mutator Free: 1422 Collector Reserve: 1536K, Max: 256K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.969Z] [1.033s][info][gc,start ] GC(0) Verify Before Evacuation, Level 4 [2025-01-10T05:44:43.969Z] [1.034s][info][gc,remset ] GC(0) Scan remembered set using bitmap: true [2025-01-10T05:44:43.970Z] [1.319s][info][gc ] GC(0) Verify Before Evacuation, Level 4 (5014376 reachable, 330 marked) [2025-01-10T05:44:43.970Z] [1.319s][info][gc,ergo ] GC(0) Pacer for Evacuation. Used CSet: 2304K, Free: 355M, Non-Taxable: 36403K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.970Z] [1.319s][info][gc ] GC(0) Pause Final Mark (Young) 286.637ms [2025-01-10T05:44:43.970Z] [1.319s][info][gc,start ] GC(0) Concurrent thread roots [2025-01-10T05:44:43.970Z] [1.319s][info][gc,task ] GC(0) Using 1 of 1 workers for Concurrent thread roots [2025-01-10T05:44:43.970Z] [1.320s][info][gc ] GC(0) Concurrent thread roots 0.270ms [2025-01-10T05:44:43.970Z] [1.320s][info][gc,start ] GC(0) Concurrent weak references (Young) [2025-01-10T05:44:43.970Z] [1.320s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent weak references [2025-01-10T05:44:43.970Z] [1.320s][info][gc,ref ] GC(0) Encountered references: Soft: 33, Weak: 78, Final: 0, Phantom: 11 [2025-01-10T05:44:43.970Z] [1.320s][info][gc,ref ] GC(0) Discovered references: Soft: 0, Weak: 10, Final: 0, Phantom: 11 [2025-01-10T05:44:43.970Z] [1.320s][info][gc,ref ] GC(0) Enqueued references: Soft: 0, Weak: 1, Final: 0, Phantom: 3 [2025-01-10T05:44:43.970Z] [1.320s][info][gc ] GC(0) Concurrent weak references (Young) 0.134ms [2025-01-10T05:44:43.970Z] [1.320s][info][gc,start ] GC(0) Concurrent weak roots (Young) [2025-01-10T05:44:43.970Z] [1.320s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent weak root [2025-01-10T05:44:43.970Z] [1.320s][info][gc ] GC(0) Concurrent weak roots (Young) 0.482ms [2025-01-10T05:44:43.970Z] [1.320s][info][gc,start ] GC(0) Concurrent cleanup (Young) [2025-01-10T05:44:43.970Z] [1.320s][info][gc,task ] GC(0) Using 1 of 1 workers for cleanup early. [2025-01-10T05:44:43.970Z] [1.320s][info][gc ] GC(0) Concurrent cleanup (Young) 155M->155M(380M) 0.063ms [2025-01-10T05:44:43.970Z] [1.320s][info][gc,free ] Free: 355M, Max: 256K regular, 331M humongous, Frag: 7% external, 0% internal; Used: 0B, Mutator Free: 1422 Collector Reserve: 1388K, Max: 256K; Used: 147K Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.970Z] [1.320s][info][gc,start ] GC(0) Concurrent strong roots [2025-01-10T05:44:43.970Z] [1.320s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent strong root [2025-01-10T05:44:43.970Z] [1.321s][info][gc ] GC(0) Concurrent strong roots 0.561ms [2025-01-10T05:44:43.970Z] [1.321s][info][gc,start ] GC(0) Concurrent evacuation [2025-01-10T05:44:43.970Z] [1.321s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent evacuation [2025-01-10T05:44:43.970Z] [1.324s][info][gc ] GC(0) Concurrent evacuation 3.453ms [2025-01-10T05:44:43.970Z] [1.324s][info][gc,start ] GC(0) Pause Init Update Refs [2025-01-10T05:44:43.970Z] [1.324s][info][gc,start ] GC(0) Verify Before Updating References, Level 4 [2025-01-10T05:44:43.970Z] [1.325s][info][gc,remset ] GC(0) Scan remembered set using bitmap: true [2025-01-10T05:44:43.970Z] [1.586s][info][gc ] GC(0) Verify Before Updating References, Level 4 (5014857 reachable, 23195 marked) [2025-01-10T05:44:43.970Z] [1.586s][info][gc,ergo ] GC(0) Pacer for Update Refs. Used: 156M, Free: 355M, Non-Taxable: 36403K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.970Z] [1.586s][info][gc ] GC(0) Pause Init Update Refs 261.397ms [2025-01-10T05:44:43.970Z] [1.586s][info][gc,start ] GC(0) Concurrent update references [2025-01-10T05:44:43.970Z] [1.586s][info][gc,task ] GC(0) Using 1 of 1 workers for concurrent reference update [2025-01-10T05:44:43.970Z] [1.586s][info][gc,ergo ] GC(0) At start of update refs, moving 0B to Mutator free set from Collector Reserve (0B) and from Old Collector Reserve (0B) [2025-01-10T05:44:43.970Z] [1.658s][info][gc ] GC(0) Concurrent update references 72.223ms [2025-01-10T05:44:43.970Z] [1.658s][info][gc,start ] GC(0) Concurrent update thread roots [2025-01-10T05:44:43.970Z] [1.659s][info][gc ] GC(0) Concurrent update thread roots 0.195ms [2025-01-10T05:44:43.970Z] [1.659s][info][gc,start ] GC(0) Pause Final Update Refs [2025-01-10T05:44:43.970Z] [1.659s][info][gc,task ] GC(0) Using 1 of 1 workers for final reference update [2025-01-10T05:44:43.970Z] [1.659s][info][gc,remset ] GC(0) Scan remembered set using bitmap: true [2025-01-10T05:44:43.970Z] [1.659s][info][gc,start ] GC(0) Verify After Updating References, Level 4 [2025-01-10T05:44:43.970Z] [1.660s][info][gc,remset ] GC(0) Scan remembered set using bitmap: true [2025-01-10T05:44:43.970Z] [1.917s][info][gc ] GC(0) Verify After Updating References, Level 4 (5014534 reachable, 182 marked) [2025-01-10T05:44:43.970Z] [1.917s][info][gc,ergo ] GC(0) Transfer 5 region(s) from Young to Old, yielding increased size: 1280K [2025-01-10T05:44:43.970Z] [1.917s][info][gc,free ] Free: 330M, Max: 256K regular, 330M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1323 Collector Reserve: 26368K, Max: 256K; Used: 2304K Old Collector Reserve: 1280K, Max: 256K; Used: 0B [2025-01-10T05:44:43.970Z] [1.917s][info][gc ] GC(0) Pause Final Update Refs 258.221ms [2025-01-10T05:44:43.970Z] [1.917s][info][gc,start ] GC(0) Concurrent cleanup (Young) [2025-01-10T05:44:43.970Z] [1.917s][info][gc,task ] GC(0) Using 1 of 1 workers for cleanup complete. [2025-01-10T05:44:43.970Z] [1.917s][info][gc ] GC(0) Concurrent cleanup (Young) 156M->154M(381M) 0.043ms [2025-01-10T05:44:43.970Z] [1.917s][info][gc,ergo ] GC(0) After Concurrent GC, successfully transferred 0 regions to none to prepare for next gc, old available: 1280K, young_available: 330M [2025-01-10T05:44:43.970Z] [1.917s][info][gc,ergo ] GC(0) At end of Concurrent Young GC: GCU: 21.4%, MU: 17.6% during period of 1.917s [2025-01-10T05:44:43.970Z] [1.917s][info][gc,ergo ] GC(0) At end of Concurrent Young GC: Young generation used: 154M, used regions: 154M, humongous waste: 0B, soft capacity: 512M, max capacity: 510M, available: 330M [2025-01-10T05:44:43.970Z] [1.917s][info][gc,ergo ] GC(0) At end of Concurrent Young GC: Old generation used: 0B, used regions: 0B, humongous waste: 0B, soft capacity: 0B, max capacity: 1280K, available: 1280K [2025-01-10T05:44:43.970Z] [1.917s][info][gc,free ] Free: 330M, Max: 256K regular, 330M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1323 Collector Reserve: 26368K, Max: 256K; Used: 0B Old Collector Reserve: 1280K, Max: 256K; Used: 0B [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Concurrent Reset 197 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Pause Init Mark (G) 185809 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Pause Init Mark (N) 185591 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Swap Remembered Set 12 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Update Region States 41 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Concurrent Scan Remembered Set 195 us, parallelism: 0.49x [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] RS: <total> 96 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] RS: Scan Clusters 96 us, workers (us): 96, [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Concurrent Mark Roots 328 us, parallelism: 0.75x [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] CMR: <total> 244 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] CMR: Thread Roots 64 us, workers (us): 64, [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] CMR: Code Cache Roots 65 us, workers (us): 65, [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] CMR: VM Strong Roots 33 us, workers (us): 33, [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] CMR: CLDG Roots 82 us, workers (us): 82, [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Concurrent Marking 163447 us, parallelism: 1.00x [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] CM: <total> 163102 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] CM: Parallel Mark 163102 us, workers (us): 163102, [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Flush SATB 160 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Pause Final Mark (G) 286823 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Pause Final Mark (N) 286646 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Finish Mark 77 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Update Region States 34 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Choose Collection Set 100 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Rebuild Free Set 44 us [2025-01-10T05:44:43.970Z] [1.917s][info][gc,stats ] Concurrent Thread Roots 288 us, parallelism: 0.55x [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CTR: <total> 158 us [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CTR: Thread Roots 158 us, workers (us): 158, [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] Concurrent Weak References 143 us, parallelism: 0.04x [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CWRF: <total> 6 us [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CWRF: Weak References 6 us, workers (us): 6, [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] Concurrent Weak Roots 499 us [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] Roots 351 us, parallelism: 0.74x [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CWR: <total> 261 us [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CWR: VM Weak Roots 261 us, workers (us): 261, [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] Rendezvous 108 us [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] Concurrent Cleanup 72 us [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] Concurrent Strong Roots 577 us, parallelism: 0.78x [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CSR: <total> 452 us [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CSR: Code Cache Roots 96 us, workers (us): 96, [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CSR: VM Strong Roots 15 us, workers (us): 15, [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] CSR: CLDG Roots 341 us, workers (us): 341, [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] Concurrent Evacuation 3466 us [2025-01-10T05:44:43.971Z] [1.917s][info][gc,stats ] Pause Init Update Refs (G) 261742 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Pause Init Update Refs (N) 261407 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Manage GCLABs 2 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Concurrent Update Refs 72246 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Concurrent Update Thread Roots 209 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Pause Final Update Refs (G) 258331 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Pause Final Update Refs (N) 258231 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Update Region States 13 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Trash Collection Set 2 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Rebuild Free Set 95 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Concurrent Cleanup 53 us [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Workers: [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Mutators: [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Young regions: Age table: [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] - age 1: 131819536 bytes, 131819536 total [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] Allocation pacing accrued: [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] 0 of 1898 ms ( 0.0%): <total> [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] 0 of 1898 ms ( 0.0%): <average total> [2025-01-10T05:44:43.971Z] [1.918s][info][gc,stats ] [2025-01-10T05:44:43.971Z] [1.918s][info][gc,metaspace] Metaspace: 6869K(6976K)->6878K(7040K) NonClass: 6411K(6464K)->6418K(6528K) Class: 458K(512K)->459K(512K) [2025-01-10T05:44:43.971Z] [1.918s][info][gc,ergo ] Pacer for Idle. Initial: 10485K, Alloc Tax Rate: 1.0x [2025-01-10T05:44:43.971Z] [1.929s][info][gc ] Trigger (Young): Learning 2 of 5. Free (330M) is below initial threshold (358M) [2025-01-10T05:44:43.971Z] [1.929s][info][gc,free ] Free: 330M, Max: 256K regular, 330M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1323 Collector Reserve: 26368K, Max: 256K; Used: 0B Old Collector Reserve: 1280K, Max: 256K; Used: 0B [2025-01-10T05:44:43.971Z] [1.929s][info][gc,ergo ] GC(1) Start GC cycle (Young) [2025-01-10T05:44:43.971Z] [1.929s][info][gc,start ] GC(1) Concurrent reset (Young) [2025-01-10T05:44:43.971Z] [1.929s][info][gc,task ] GC(1) Using 1 of 1 workers for Concurrent reset (Young) [2025-01-10T05:44:43.971Z] [1.929s][info][gc,ergo ] GC(1) Pacer for Reset. Non-Taxable: 512M [2025-01-10T05:44:43.971Z] [1.930s][info][gc ] GC(1) Concurrent reset (Young) 0.516ms [2025-01-10T05:44:43.971Z] [1.930s][info][gc,start ] GC(1) Pause Init Mark (Young) [2025-01-10T05:44:43.971Z] [1.930s][info][gc,task ] GC(1) Using 1 of 1 workers for init marking [2025-01-10T05:44:43.971Z] [1.930s][info][gc,start ] GC(1) Verify Before Mark, Level 4 [2025-01-10T05:44:43.971Z] [1.931s][info][gc,remset ] GC(1) Scan remembered set using bitmap: true [2025-01-10T05:44:43.971Z] [2.139s][info][gc ] GC(1) Verify Before Mark, Level 4 (5014370 reachable, 0 marked) [2025-01-10T05:44:43.971Z] [2.139s][info][gc,ergo ] GC(1) Pacer for Mark. Expected Live: 126M, Free: 330M, Non-Taxable: 33868K, Alloc Tax Rate: 0.5x [2025-01-10T05:44:43.971Z] [2.139s][info][gc ] GC(1) Pause Init Mark (Young) 208.174ms [2025-01-10T05:44:43.971Z] [2.139s][info][gc,start ] GC(1) Concurrent remembered set scanning [2025-01-10T05:44:43.971Z] [2.139s][info][gc,task ] GC(1) Using 1 of 1 workers for Concurrent remembered set scanning [2025-01-10T05:44:43.971Z] [2.139s][info][gc,remset ] GC(1) Scan remembered set using bitmap: true [2025-01-10T05:44:43.971Z] [2.139s][info][gc ] GC(1) Concurrent remembered set scanning 0.236ms [2025-01-10T05:44:43.971Z] [2.139s][info][gc,start ] GC(1) Concurrent marking roots [2025-01-10T05:44:43.971Z] [2.139s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent marking roots [2025-01-10T05:44:43.971Z] [2.140s][info][gc ] GC(1) Concurrent marking roots 0.242ms [2025-01-10T05:44:43.971Z] [2.140s][info][gc,start ] GC(1) Concurrent marking (Young) [2025-01-10T05:44:43.971Z] [2.140s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent marking [2025-01-10T05:44:43.971Z] [2.329s][info][gc ] GC(1) Concurrent marking (Young) 188.918ms [2025-01-10T05:44:43.971Z] [2.329s][info][gc,start ] GC(1) Pause Final Mark (Young) [2025-01-10T05:44:43.971Z] [2.329s][info][gc,task ] GC(1) Using 1 of 1 workers for final marking [2025-01-10T05:44:43.971Z] [2.329s][info][gc,remset ] GC(1) Scan remembered set using bitmap: true [2025-01-10T05:44:43.971Z] [2.329s][info][gc,age ] GC(1) - age 1: prev 161097072 bytes, curr 133047184 bytes, mortality 0.17 [2025-01-10T05:44:43.971Z] [2.329s][info][gc,age ] GC(1) ---------------------------------------------------------------------------- [2025-01-10T05:44:43.971Z] [2.329s][info][gc,ergo ] GC(1) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-01-10T05:44:43.971Z] [2.329s][info][gc,ergo ] GC(1) Adaptive CSet Selection for YOUNG. Max Evacuation: 21791K, Actual Free: 0B. [2025-01-10T05:44:43.971Z] [2.329s][info][gc,ergo ] GC(1) Chosen CSet evacuates young: 348K (of which at least: 0B are to be promoted), old: 0B [2025-01-10T05:44:43.971Z] [2.329s][info][gc,ergo ] GC(1) Collectable Garbage: 419K (53%), Immediate: 0B (0%), 0 regions, CSet: 419K (53%), 3 regions [2025-01-10T05:44:43.971Z] [2.329s][info][gc,ergo ] GC(1) Evacuation Targets: YOUNG: 348K, PROMOTE: 0B, OLD: 0B, TOTAL: 348K [2025-01-10T05:44:43.971Z] [2.329s][info][gc,ergo ] GC(1) Transfer 5 region(s) from Old to Young, yielding increased size: 512M [2025-01-10T05:44:43.971Z] [2.329s][info][gc,free ] Free: 357M, Max: 256K regular, 331M humongous, Frag: 8% external, 0% internal; Used: 0B, Mutator Free: 1429 Collector Reserve: 512K, Max: 256K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.971Z] [2.329s][info][gc,start ] GC(1) Verify Before Evacuation, Level 4 [2025-01-10T05:44:43.971Z] [2.330s][info][gc,remset ] GC(1) Scan remembered set using bitmap: true [2025-01-10T05:44:43.971Z] [2.604s][info][gc ] GC(1) Verify Before Evacuation, Level 4 (5014370 reachable, 173 marked) [2025-01-10T05:44:43.971Z] [2.604s][info][gc,ergo ] GC(1) Pacer for Evacuation. Used CSet: 768K, Free: 357M, Non-Taxable: 36582K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.971Z] [2.604s][info][gc ] GC(1) Pause Final Mark (Young) 275.795ms [2025-01-10T05:44:43.971Z] [2.605s][info][gc,start ] GC(1) Concurrent thread roots [2025-01-10T05:44:43.971Z] [2.605s][info][gc,task ] GC(1) Using 1 of 1 workers for Concurrent thread roots [2025-01-10T05:44:43.971Z] [2.605s][info][gc ] GC(1) Concurrent thread roots 0.176ms [2025-01-10T05:44:43.971Z] [2.605s][info][gc,start ] GC(1) Concurrent weak references (Young) [2025-01-10T05:44:43.971Z] [2.605s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent weak references [2025-01-10T05:44:43.971Z] [2.605s][info][gc,ref ] GC(1) Encountered references: Soft: 33, Weak: 77, Final: 0, Phantom: 8 [2025-01-10T05:44:43.971Z] [2.605s][info][gc,ref ] GC(1) Discovered references: Soft: 0, Weak: 9, Final: 0, Phantom: 8 [2025-01-10T05:44:43.971Z] [2.605s][info][gc,ref ] GC(1) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-01-10T05:44:43.971Z] [2.605s][info][gc ] GC(1) Concurrent weak references (Young) 0.103ms [2025-01-10T05:44:43.971Z] [2.605s][info][gc,start ] GC(1) Concurrent weak roots (Young) [2025-01-10T05:44:43.971Z] [2.605s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent weak root [2025-01-10T05:44:43.971Z] [2.605s][info][gc ] GC(1) Concurrent weak roots (Young) 0.335ms [2025-01-10T05:44:43.971Z] [2.605s][info][gc,start ] GC(1) Concurrent cleanup (Young) [2025-01-10T05:44:43.971Z] [2.605s][info][gc,task ] GC(1) Using 1 of 1 workers for cleanup early. [2025-01-10T05:44:43.971Z] [2.605s][info][gc ] GC(1) Concurrent cleanup (Young) 154M->154M(382M) 0.044ms [2025-01-10T05:44:43.971Z] [2.605s][info][gc,free ] Free: 357M, Max: 256K regular, 331M humongous, Frag: 8% external, 0% internal; Used: 0B, Mutator Free: 1429 Collector Reserve: 509K, Max: 256K; Used: 2064B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.972Z] [2.605s][info][gc,start ] GC(1) Concurrent strong roots [2025-01-10T05:44:43.972Z] [2.605s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent strong root [2025-01-10T05:44:43.972Z] [2.605s][info][gc ] GC(1) Concurrent strong roots 0.167ms [2025-01-10T05:44:43.972Z] [2.605s][info][gc,start ] GC(1) Concurrent evacuation [2025-01-10T05:44:43.972Z] [2.605s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent evacuation [2025-01-10T05:44:43.972Z] [2.606s][info][gc ] GC(1) Concurrent evacuation 1.002ms [2025-01-10T05:44:43.972Z] [2.606s][info][gc,start ] GC(1) Pause Init Update Refs [2025-01-10T05:44:43.972Z] [2.606s][info][gc,start ] GC(1) Verify Before Updating References, Level 4 [2025-01-10T05:44:43.972Z] [2.607s][info][gc,remset ] GC(1) Scan remembered set using bitmap: true [2025-01-10T05:44:43.972Z] [2.866s][info][gc ] GC(1) Verify Before Updating References, Level 4 (5014535 reachable, 9838 marked) [2025-01-10T05:44:43.972Z] [2.866s][info][gc,ergo ] GC(1) Pacer for Update Refs. Used: 154M, Free: 357M, Non-Taxable: 36582K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.972Z] [2.866s][info][gc ] GC(1) Pause Init Update Refs 259.193ms [2025-01-10T05:44:43.972Z] [2.866s][info][gc,start ] GC(1) Concurrent update references [2025-01-10T05:44:43.972Z] [2.866s][info][gc,task ] GC(1) Using 1 of 1 workers for concurrent reference update [2025-01-10T05:44:43.972Z] [2.866s][info][gc,ergo ] GC(1) At start of update refs, moving 0B to Mutator free set from Collector Reserve (0B) and from Old Collector Reserve (0B) [2025-01-10T05:44:43.972Z] [2.942s][info][gc ] GC(1) Concurrent update references 76.483ms [2025-01-10T05:44:43.972Z] [2.942s][info][gc,start ] GC(1) Concurrent update thread roots [2025-01-10T05:44:43.972Z] [2.942s][info][gc ] GC(1) Concurrent update thread roots 0.120ms [2025-01-10T05:44:43.972Z] [2.942s][info][gc,start ] GC(1) Pause Final Update Refs [2025-01-10T05:44:43.972Z] [2.942s][info][gc,task ] GC(1) Using 1 of 1 workers for final reference update [2025-01-10T05:44:43.972Z] [2.943s][info][gc,remset ] GC(1) Scan remembered set using bitmap: true [2025-01-10T05:44:43.972Z] [2.943s][info][gc,start ] GC(1) Verify After Updating References, Level 4 [2025-01-10T05:44:43.972Z] [2.943s][info][gc,remset ] GC(1) Scan remembered set using bitmap: true [2025-01-10T05:44:43.972Z] [3.206s][info][gc ] GC(1) Verify After Updating References, Level 4 (5014534 reachable, 17 marked) [2025-01-10T05:44:43.972Z] [3.206s][info][gc,free ] Free: 332M, Max: 256K regular, 331M humongous, Frag: 1% external, 0% internal; Used: 256K, Mutator Free: 1329 Collector Reserve: 26368K, Max: 256K; Used: 512K Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.972Z] [3.206s][info][gc ] GC(1) Pause Final Update Refs 263.308ms [2025-01-10T05:44:43.972Z] [3.206s][info][gc,start ] GC(1) Concurrent cleanup (Young) [2025-01-10T05:44:43.972Z] [3.206s][info][gc,task ] GC(1) Using 1 of 1 workers for cleanup complete. [2025-01-10T05:44:43.972Z] [3.206s][info][gc ] GC(1) Concurrent cleanup (Young) 154M->153M(382M) 0.029ms [2025-01-10T05:44:43.972Z] [3.206s][info][gc,ergo ] GC(1) After Concurrent GC, successfully transferred 0 regions to none to prepare for next gc, old available: 0B, young_available: 332M [2025-01-10T05:44:43.972Z] [3.206s][info][gc,ergo ] GC(1) At end of Concurrent Young GC: GCU: 33.1%, MU: 0.3% during period of 1.289s [2025-01-10T05:44:43.972Z] [3.206s][info][gc,ergo ] GC(1) At end of Concurrent Young GC: Young generation used: 153M, used regions: 154M, humongous waste: 0B, soft capacity: 512M, max capacity: 512M, available: 332M [2025-01-10T05:44:43.972Z] [3.206s][info][gc,ergo ] GC(1) At end of Concurrent Young GC: Old generation used: 0B, used regions: 0B, humongous waste: 0B, soft capacity: 0B, max capacity: 0B, available: 0B [2025-01-10T05:44:43.972Z] [3.206s][info][gc,free ] Free: 332M, Max: 256K regular, 331M humongous, Frag: 1% external, 0% internal; Used: 0B, Mutator Free: 1329 Collector Reserve: 26368K, Max: 256K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Reset 800 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Pause Init Mark (G) 208882 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Pause Init Mark (N) 208568 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Swap Remembered Set 8 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Update Region States 30 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Scan Remembered Set 251 us, parallelism: 0.46x [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] RS: <total> 115 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] RS: Scan Clusters 115 us, workers (us): 115, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Mark Roots 256 us, parallelism: 0.52x [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CMR: <total> 132 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CMR: Thread Roots 30 us, workers (us): 30, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CMR: Code Cache Roots 65 us, workers (us): 65, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CMR: VM Strong Roots 3 us, workers (us): 3, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CMR: CLDG Roots 34 us, workers (us): 34, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Marking 188943 us, parallelism: 1.00x [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CM: <total> 188652 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CM: Parallel Mark 188652 us, workers (us): 188652, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Flush SATB 126 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Pause Final Mark (G) 275909 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Pause Final Mark (N) 275804 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Finish Mark 71 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Update Region States 27 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Choose Collection Set 99 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Rebuild Free Set 35 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Thread Roots 194 us, parallelism: 0.36x [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CTR: <total> 70 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CTR: Thread Roots 70 us, workers (us): 70, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Weak References 110 us, parallelism: 0.02x [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CWRF: <total> 3 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CWRF: Weak References 3 us, workers (us): 3, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Weak Roots 351 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Roots 171 us, parallelism: 0.50x [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CWR: <total> 85 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CWR: VM Weak Roots 85 us, workers (us): 85, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Rendezvous 142 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Cleanup 52 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Strong Roots 180 us, parallelism: 0.53x [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CSR: <total> 95 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CSR: Code Cache Roots 85 us, workers (us): 85, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CSR: VM Strong Roots 2 us, workers (us): 2, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] CSR: CLDG Roots 8 us, workers (us): 8, [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Evacuation 1015 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Pause Init Update Refs (G) 259275 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Pause Init Update Refs (N) 259202 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Manage GCLABs 2 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Update Refs 76509 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Update Thread Roots 137 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Pause Final Update Refs (G) 263423 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Pause Final Update Refs (N) 263317 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Update Region States 13 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Trash Collection Set 2 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Rebuild Free Set 76 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Concurrent Cleanup 38 us [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Workers: [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Mutators: [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Young regions: Age table: [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] - age 1: 28763344 bytes, 28763344 total [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] - age 2: 131819536 bytes, 160582880 total [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] Allocation pacing accrued: [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] 0 of 1289 ms ( 0.0%): <total> [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] 0 of 1289 ms ( 0.0%): <average total> [2025-01-10T05:44:43.972Z] [3.206s][info][gc,stats ] [2025-01-10T05:44:43.972Z] [3.207s][info][gc,metaspace] Metaspace: 6878K(7040K)->6878K(7040K) NonClass: 6418K(6528K)->6419K(6528K) Class: 459K(512K)->459K(512K) [2025-01-10T05:44:43.972Z] [3.207s][info][gc,ergo ] Pacer for Idle. Initial: 10485K, Alloc Tax Rate: 1.0x [2025-01-10T05:44:43.972Z] [3.207s][info][gc ] Trigger (Global): GC request (System.gc()) [2025-01-10T05:44:43.973Z] [3.207s][info][gc,free ] Free: 332M, Max: 256K regular, 331M humongous, Frag: 1% external, 0% internal; Used: 0B, Mutator Free: 1329 Collector Reserve: 26368K, Max: 256K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.973Z] [3.207s][info][gc,ergo ] GC(2) Start GC cycle (Global) [2025-01-10T05:44:43.973Z] [3.207s][info][gc,start ] GC(2) Concurrent reset (Global) (unload classes) [2025-01-10T05:44:43.973Z] [3.207s][info][gc,task ] GC(2) Using 1 of 1 workers for Concurrent reset (Global) (unload classes) [2025-01-10T05:44:43.973Z] [3.207s][info][gc,ergo ] GC(2) Pacer for Reset. Non-Taxable: 512M [2025-01-10T05:44:43.973Z] [3.207s][info][gc ] GC(2) Concurrent reset (Global) (unload classes) 0.522ms [2025-01-10T05:44:43.973Z] [3.207s][info][gc,start ] GC(2) Pause Init Mark (Global) (unload classes) [2025-01-10T05:44:43.973Z] [3.207s][info][gc,task ] GC(2) Using 1 of 1 workers for init marking [2025-01-10T05:44:43.973Z] [3.207s][info][gc,start ] GC(2) Verify Before Mark, Level 4 [2025-01-10T05:44:43.973Z] [3.412s][info][gc ] GC(2) Verify Before Mark, Level 4 (5014212 reachable, 0 marked) [2025-01-10T05:44:43.973Z] [3.412s][info][gc,ref ] GC(2) Clearing All SoftReferences [2025-01-10T05:44:43.973Z] [3.412s][info][gc,ergo ] GC(2) Pacer for Mark. Expected Live: 140M, Free: 332M, Non-Taxable: 34022K, Alloc Tax Rate: 0.5x [2025-01-10T05:44:43.973Z] [3.412s][info][gc ] GC(2) Pause Init Mark (Global) (unload classes) 205.228ms [2025-01-10T05:44:43.973Z] [3.412s][info][gc,start ] GC(2) Concurrent marking roots [2025-01-10T05:44:43.973Z] [3.412s][info][gc,task ] GC(2) Using 1 of 1 workers for concurrent marking roots [2025-01-10T05:44:43.973Z] [3.413s][info][gc ] GC(2) Concurrent marking roots 0.494ms [2025-01-10T05:44:43.973Z] [3.413s][info][gc,start ] GC(2) Concurrent marking (Global) (unload classes) [2025-01-10T05:44:43.973Z] [3.413s][info][gc,task ] GC(2) Using 1 of 1 workers for concurrent marking [2025-01-10T05:44:43.973Z] [3.588s][info][gc ] GC(2) Concurrent marking (Global) (unload classes) 174.923ms [2025-01-10T05:44:43.973Z] [3.588s][info][gc,start ] GC(2) Pause Final Mark (Global) (unload classes) [2025-01-10T05:44:43.973Z] [3.588s][info][gc,task ] GC(2) Using 1 of 1 workers for final marking [2025-01-10T05:44:43.973Z] [3.588s][info][gc,age ] GC(2) - age 1: prev 27892144 bytes, curr 27892144 bytes, mortality 0.00 [2025-01-10T05:44:43.973Z] [3.588s][info][gc,age ] GC(2) ---------------------------------------------------------------------------- [2025-01-10T05:44:43.973Z] [3.588s][info][gc,age ] GC(2) - age 2: prev 133047184 bytes, curr 133040344 bytes, mortality 0.00 [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Planning to promote in place 0 humongous regions and 614 regular regions, spanning a total of 160956416 used bytes [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Adaptive CSet Selection for GLOBAL. Max Young Evacuation: 87380B, Max Old Evacuation: 0B, Actual Free: 358M. [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Chosen CSet evacuates young: 93408B (of which at least: 0B are to be promoted), old: 0B [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Collectable Garbage: 164K (30%), Immediate: 0B (0%), 0 regions, CSet: 164K (30%), 1 regions [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Evacuation Targets: YOUNG: 93408B, PROMOTE: 0B, OLD: 0B, TOTAL: 93408B [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Old-Gen Collectable Garbage: 0B consolidated with free: 0B, over 0 regions [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Old-Gen Immediate Garbage: 0B over 0 regions [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Old regions selected for defragmentation: 0 [2025-01-10T05:44:43.973Z] [3.588s][info][gc,ergo ] GC(2) Old regions not selected: [2025-01-10T05:44:43.973Z] [2025-01-10T05:44:43.973Z] ... [2025-01-10T05:44:43.973Z] Output overflow: [2025-01-10T05:44:43.973Z] JT Harness has limited the test output to the text [2025-01-10T05:44:43.973Z] at the beginning and the end, so that you can see how the [2025-01-10T05:44:43.973Z] test began, and how it completed. [2025-01-10T05:44:43.973Z] [2025-01-10T05:44:43.973Z] If you need to see more of the output from the test, [2025-01-10T05:44:43.973Z] set the system property javatest.maxOutputSize to a higher [2025-01-10T05:44:43.973Z] value. The current value is 100000 [2025-01-10T05:44:43.973Z] ... [2025-01-10T05:44:43.973Z] [2025-01-10T05:44:43.973Z] ] GC(52) Concurrent reset (Global) (unload classes) 0.499ms [2025-01-10T05:44:43.973Z] [37.943s][info][gc,start ] GC(52) Pause Init Mark (Global) (unload classes) [2025-01-10T05:44:43.973Z] [37.943s][info][gc,task ] GC(52) Using 1 of 1 workers for init marking [2025-01-10T05:44:43.973Z] [37.943s][info][gc,start ] GC(52) Verify Before Mark, Level 4 [2025-01-10T05:44:43.973Z] [38.147s][info][gc ] GC(52) Verify Before Mark, Level 4 (5014208 reachable, 0 marked) [2025-01-10T05:44:43.973Z] [38.147s][info][gc,ref ] GC(52) Clearing All SoftReferences [2025-01-10T05:44:43.973Z] [38.147s][info][gc,ergo ] GC(52) Pacer for Mark. Expected Live: 31432K, Free: 340M, Non-Taxable: 34816K, Alloc Tax Rate: 0.1x [2025-01-10T05:44:43.973Z] [38.147s][info][gc ] GC(52) Pause Init Mark (Global) (unload classes) 204.416ms [2025-01-10T05:44:43.973Z] [38.147s][info][gc,start ] GC(52) Concurrent marking roots [2025-01-10T05:44:43.973Z] [38.147s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent marking roots [2025-01-10T05:44:43.973Z] [38.147s][info][gc ] GC(52) Concurrent marking roots 0.206ms [2025-01-10T05:44:43.973Z] [38.148s][info][gc,start ] GC(52) Concurrent marking (Global) (unload classes) [2025-01-10T05:44:43.973Z] [38.148s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent marking [2025-01-10T05:44:43.973Z] [38.314s][info][gc ] GC(52) Concurrent marking (Global) (unload classes) 166.349ms [2025-01-10T05:44:43.973Z] [38.314s][info][gc,start ] GC(52) Pause Final Mark (Global) (unload classes) [2025-01-10T05:44:43.973Z] [38.314s][info][gc,task ] GC(52) Using 1 of 1 workers for final marking [2025-01-10T05:44:43.973Z] [38.314s][info][gc,age ] GC(52) ---------------------------------------------------------------------------- [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Adaptive CSet Selection for GLOBAL. Max Young Evacuation: 170K, Max Old Evacuation: 0B, Actual Free: 358M. [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Forcing transfer of 2 region(s) from Young to Old, yielding increased size: 154M [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Chosen CSet evacuates young: 0B (of which at least: 0B are to be promoted), old: 305K [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Collectable Garbage: 206K (38%), Immediate: 0B (0%), 0 regions, CSet: 206K (38%), 2 regions [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Evacuation Targets: YOUNG: 0B, PROMOTE: 0B, OLD: 305K, TOTAL: 305K [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Old-Gen Collectable Garbage: 0B consolidated with free: 0B, over 0 regions [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Old-Gen Immediate Garbage: 0B over 0 regions [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Old regions selected for defragmentation: 0 [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) Old regions not selected: 614 [2025-01-10T05:44:43.973Z] [38.315s][info][gc,ergo ] GC(52) After choosing global collection set, mixed candidates: 0, coalescing candidates: 614 [2025-01-10T05:44:43.973Z] [38.315s][info][gc,free ] Free: 357M, Max: 256K regular, 350M humongous, Frag: 2% external, 0% internal; Used: 0B, Mutator Free: 1430 Collector Reserve: 0B, Max: 0B; Used: 0B Old Collector Reserve: 512K, Max: 256K; Used: 0B [2025-01-10T05:44:43.973Z] [38.315s][info][gc,start ] GC(52) Verify Before Evacuation, Level 4 [2025-01-10T05:44:43.973Z] [38.608s][info][gc ] GC(52) Verify Before Evacuation, Level 4 (5014208 reachable, 155 marked) [2025-01-10T05:44:43.973Z] [38.608s][info][gc,ergo ] GC(52) Pacer for Evacuation. Used CSet: 512K, Free: 357M, Non-Taxable: 36608K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.973Z] [38.608s][info][gc ] GC(52) Pause Final Mark (Global) (unload classes) 294.010ms [2025-01-10T05:44:43.973Z] [38.608s][info][gc,start ] GC(52) Concurrent thread roots [2025-01-10T05:44:43.973Z] [38.608s][info][gc,task ] GC(52) Using 1 of 1 workers for Concurrent thread roots [2025-01-10T05:44:43.973Z] [38.609s][info][gc ] GC(52) Concurrent thread roots 0.155ms [2025-01-10T05:44:43.973Z] [38.609s][info][gc,start ] GC(52) Concurrent weak references (Global) (unload classes) [2025-01-10T05:44:43.973Z] [38.609s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent weak references [2025-01-10T05:44:43.973Z] [38.609s][info][gc,ref ] GC(52) Encountered references: Soft: 18, Weak: 76, Final: 0, Phantom: 8 [2025-01-10T05:44:43.973Z] [38.609s][info][gc,ref ] GC(52) Discovered references: Soft: 12, Weak: 8, Final: 0, Phantom: 8 [2025-01-10T05:44:43.973Z] [38.609s][info][gc,ref ] GC(52) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-01-10T05:44:43.973Z] [38.609s][info][gc ] GC(52) Concurrent weak references (Global) (unload classes) 0.204ms [2025-01-10T05:44:43.973Z] [38.609s][info][gc,start ] GC(52) Concurrent weak roots (Global) (unload classes) [2025-01-10T05:44:43.973Z] [38.609s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent weak root [2025-01-10T05:44:43.973Z] [38.609s][info][gc ] GC(52) Concurrent weak roots (Global) (unload classes) 0.315ms [2025-01-10T05:44:43.973Z] [38.609s][info][gc,start ] GC(52) Concurrent cleanup (Global) (unload classes) [2025-01-10T05:44:43.973Z] [38.609s][info][gc,task ] GC(52) Using 1 of 1 workers for cleanup early. [2025-01-10T05:44:43.973Z] [38.609s][info][gc ] GC(52) Concurrent cleanup (Global) (unload classes) 154M->154M(173M) 0.042ms [2025-01-10T05:44:43.973Z] [38.609s][info][gc,free ] Free: 357M, Max: 256K regular, 350M humongous, Frag: 2% external, 0% internal; Used: 0B, Mutator Free: 1430 Collector Reserve: 0B, Max: 0B; Used: 0B Old Collector Reserve: 509K, Max: 256K; Used: 2560B [2025-01-10T05:44:43.973Z] [38.609s][info][gc,start ] GC(52) Concurrent class unloading [2025-01-10T05:44:43.974Z] [38.609s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent class unloading [2025-01-10T05:44:43.974Z] [38.609s][info][gc ] GC(52) Concurrent class unloading 0.300ms [2025-01-10T05:44:43.974Z] [38.609s][info][gc,start ] GC(52) Concurrent strong roots [2025-01-10T05:44:43.974Z] [38.609s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent strong root [2025-01-10T05:44:43.974Z] [38.610s][info][gc ] GC(52) Concurrent strong roots 0.067ms [2025-01-10T05:44:43.974Z] [38.610s][info][gc,start ] GC(52) Concurrent evacuation [2025-01-10T05:44:43.974Z] [38.610s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent evacuation [2025-01-10T05:44:43.974Z] [38.611s][info][gc ] GC(52) Concurrent evacuation 1.042ms [2025-01-10T05:44:43.974Z] [38.611s][info][gc,start ] GC(52) Pause Init Update Refs [2025-01-10T05:44:43.974Z] [38.611s][info][gc,start ] GC(52) Verify Before Updating References, Level 4 [2025-01-10T05:44:43.974Z] [39.388s][info][gc ] GC(52) Verify Before Updating References, Level 4 (5014253 reachable, 9455 marked) [2025-01-10T05:44:43.974Z] [39.388s][info][gc,ergo ] GC(52) Pacer for Update Refs. Used: 154M, Free: 357M, Non-Taxable: 36608K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.974Z] [39.388s][info][gc ] GC(52) Pause Init Update Refs 777.655ms [2025-01-10T05:44:43.974Z] [39.388s][info][gc,start ] GC(52) Concurrent update references [2025-01-10T05:44:43.974Z] [39.388s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent reference update [2025-01-10T05:44:43.974Z] [39.388s][info][gc,ergo ] GC(52) At start of update refs, moving 0B to Mutator free set from Collector Reserve (0B) and from Old Collector Reserve (0B) [2025-01-10T05:44:43.974Z] [39.464s][info][gc ] GC(52) Concurrent update references 75.595ms [2025-01-10T05:44:43.974Z] [39.464s][info][gc,start ] GC(52) Concurrent update thread roots [2025-01-10T05:44:43.974Z] [39.464s][info][gc ] GC(52) Concurrent update thread roots 0.168ms [2025-01-10T05:44:43.974Z] [39.464s][info][gc,start ] GC(52) Pause Final Update Refs [2025-01-10T05:44:43.974Z] [39.464s][info][gc,task ] GC(52) Using 1 of 1 workers for final reference update [2025-01-10T05:44:43.974Z] [39.464s][info][gc,start ] GC(52) Verify After Updating References, Level 4 [2025-01-10T05:44:43.974Z] [39.724s][info][gc ] GC(52) Verify After Updating References, Level 4 (5014253 reachable, 118 marked) [2025-01-10T05:44:43.974Z] [39.724s][info][gc,ergo ] GC(52) Transfer 2 region(s) from Old to Young, yielding increased size: 358M [2025-01-10T05:44:43.974Z] [39.724s][info][gc,free ] Free: 340M, Max: 256K regular, 340M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1360 Collector Reserve: 18432K, Max: 256K; Used: 512K Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.974Z] [39.724s][info][gc ] GC(52) Pause Final Update Refs 260.175ms [2025-01-10T05:44:43.974Z] [39.724s][info][gc,start ] GC(52) Concurrent cleanup (Global) (unload classes) [2025-01-10T05:44:43.974Z] [39.725s][info][gc,task ] GC(52) Using 1 of 1 workers for cleanup complete. [2025-01-10T05:44:43.974Z] [39.725s][info][gc ] GC(52) Concurrent cleanup (Global) (unload classes) 154M->153M(173M) 0.047ms [2025-01-10T05:44:43.974Z] [39.725s][info][gc,start ] GC(52) Coalescing and filling old regions [2025-01-10T05:44:43.974Z] [39.725s][info][gc,task ] GC(52) Using 1 of 1 workers for concurrent coalesce and fill [2025-01-10T05:44:43.974Z] [39.757s][info][gc ] GC(52) Coalescing and filling old regions 32.903ms [2025-01-10T05:44:43.974Z] [39.757s][info][gc,ergo ] GC(52) After Concurrent GC, successfully transferred 0 regions to none to prepare for next gc, old available: 16B, young_available: 340M [2025-01-10T05:44:43.974Z] [39.758s][info][gc,ergo ] GC(52) At end of Concurrent Global GC: GCU: 23.7%, MU: 9.6% during period of 1.816s [2025-01-10T05:44:43.974Z] [39.758s][info][gc,ergo ] GC(52) At end of Concurrent Global GC: Young generation used: 0B, used regions: 0B, humongous waste: 0B, soft capacity: 512M, max capacity: 358M, available: 340M [2025-01-10T05:44:43.974Z] [39.758s][info][gc,ergo ] GC(52) At end of Concurrent Global GC: Old generation used: 153M, used regions: 154M, humongous waste: 0B, soft capacity: 0B, max capacity: 154M, available: 16B [2025-01-10T05:44:43.976Z] [39.758s][info][gc,free ] Free: 340M, Max: 256K regular, 340M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1360 Collector Reserve: 18432K, Max: 256K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Concurrent Reset 516 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Pause Init Mark (G) 204521 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Pause Init Mark (N) 204426 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Update Region States 26 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Concurrent Mark Roots 224 us, parallelism: 0.30x [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] CMR: <total> 68 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] CMR: Thread Roots 44 us, workers (us): 44, [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] CMR: VM Strong Roots 3 us, workers (us): 3, [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] CMR: CLDG Roots 20 us, workers (us): 20, [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Concurrent Marking 166379 us, parallelism: 1.00x [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] CM: <total> 165890 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] CM: Parallel Mark 165890 us, workers (us): 165890, [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Flush SATB 129 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Pause Final Mark (G) 294450 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Pause Final Mark (N) 294019 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Finish Mark 60 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Update Region States 28 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Choose Collection Set 207 us [2025-01-10T05:44:43.976Z] [39.758s][info][gc,stats ] Rebuild Free Set 38 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Thread Roots 172 us, parallelism: 0.24x [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CTR: <total> 42 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CTR: Thread Roots 42 us, workers (us): 42, [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Weak References 211 us, parallelism: 0.20x [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CWRF: <total> 41 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CWRF: Weak References 41 us, workers (us): 41, [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Weak Roots 329 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Roots 194 us, parallelism: 0.55x [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CWR: <total> 106 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CWR: Code Cache Roots 58 us, workers (us): 58, [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CWR: VM Weak Roots 48 us, workers (us): 48, [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CWR: CLDG Roots 0 us, workers (us): 0, [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Rendezvous 97 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Cleanup 53 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Class Unloading 314 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Unlink Stale 187 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] System Dictionary 2 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Weak Class Links 0 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Code Roots 178 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Rendezvous 79 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Purge Unlinked 10 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Code Roots 1 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CLDG 9 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Exception Caches 0 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Strong Roots 75 us, parallelism: 0.13x [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CSR: <total> 10 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CSR: VM Strong Roots 2 us, workers (us): 2, [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CSR: CLDG Roots 8 us, workers (us): 8, [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Evacuation 1058 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Pause Init Update Refs (G) 777780 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Pause Init Update Refs (N) 777664 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Manage GCLABs 2 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Update Refs 75619 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Update Thread Roots 185 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Pause Final Update Refs (G) 260295 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Pause Final Update Refs (N) 260184 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Update Region States 70 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Trash Collection Set 1 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Rebuild Free Set 124 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Cleanup 58 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Concurrent Coalesce and Fill 32930 us, parallelism: 1.00x [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CC&F: <total> 32782 us [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] CC&F: Scan Clusters 32782 us, workers (us): 32782, [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Workers: [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Mutators: [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Young regions: Age table: [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] Allocation pacing accrued: [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] 0 of 1816 ms ( 0.0%): <total> [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] 0 of 1816 ms ( 0.0%): <average total> [2025-01-10T05:44:43.977Z] [39.758s][info][gc,stats ] [2025-01-10T05:44:43.977Z] [39.758s][info][gc,metaspace] Metaspace: 6875K(7040K)->6875K(7040K) NonClass: 6416K(6528K)->6416K(6528K) Class: 458K(512K)->458K(512K) [2025-01-10T05:44:43.977Z] [39.758s][info][gc,ergo ] Pacer for Idle. Initial: 10485K, Alloc Tax Rate: 1.0x [2025-01-10T05:44:43.977Z] [39.758s][info][gc,start ] Concurrent uncommit [2025-01-10T05:44:43.977Z] [39.761s][info][gc ] Concurrent uncommit 19968K (154M) 2.294ms [2025-01-10T05:44:43.977Z] [40.786s][info][gc ] Trigger (Global): GC request (System.gc()) [2025-01-10T05:44:43.977Z] [40.787s][info][gc,free ] Free: 339M, Max: 256K regular, 339M humongous, Frag: 0% external, 0% internal; Used: 188K, Mutator Free: 1360 Collector Reserve: 18432K, Max: 256K; Used: 0B Old Collector Reserve: 0B, Max: 0B; Used: 0B [2025-01-10T05:44:43.977Z] [40.787s][info][gc,ergo ] GC(53) Start GC cycle (Global) [2025-01-10T05:44:43.977Z] [40.787s][info][gc,start ] GC(53) Concurrent reset (Global) (unload classes) [2025-01-10T05:44:43.977Z] [40.787s][info][gc,task ] GC(53) Using 1 of 1 workers for Concurrent reset (Global) (unload classes) [2025-01-10T05:44:43.977Z] [40.787s][info][gc,ergo ] GC(53) Pacer for Reset. Non-Taxable: 512M [2025-01-10T05:44:43.977Z] [40.787s][info][gc ] GC(53) Concurrent reset (Global) (unload classes) 0.522ms [2025-01-10T05:44:43.977Z] [40.787s][info][gc,start ] GC(53) Pause Init Mark (Global) (unload classes) [2025-01-10T05:44:43.977Z] [40.787s][info][gc,task ] GC(53) Using 1 of 1 workers for init marking [2025-01-10T05:44:43.977Z] [40.787s][info][gc,start ] GC(53) Verify Before Mark, Level 4 [2025-01-10T05:44:43.977Z] [40.995s][info][gc ] GC(53) Verify Before Mark, Level 4 (5014208 reachable, 0 marked) [2025-01-10T05:44:43.977Z] [40.995s][info][gc,ref ] GC(53) Clearing All SoftReferences [2025-01-10T05:44:43.977Z] [40.995s][info][gc,ergo ] GC(53) Pacer for Mark. Expected Live: 62864K, Free: 339M, Non-Taxable: 34797K, Alloc Tax Rate: 0.2x [2025-01-10T05:44:43.977Z] [40.995s][info][gc ] GC(53) Pause Init Mark (Global) (unload classes) 207.425ms [2025-01-10T05:44:43.977Z] [40.995s][info][gc,start ] GC(53) Concurrent marking roots [2025-01-10T05:44:43.977Z] [40.995s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent marking roots [2025-01-10T05:44:43.977Z] [40.995s][info][gc ] GC(53) Concurrent marking roots 0.174ms [2025-01-10T05:44:43.977Z] [40.995s][info][gc,start ] GC(53) Concurrent marking (Global) (unload classes) [2025-01-10T05:44:43.977Z] [40.995s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent marking [2025-01-10T05:44:43.977Z] [41.171s][info][gc ] GC(53) Concurrent marking (Global) (unload classes) 176.250ms [2025-01-10T05:44:43.977Z] [41.171s][info][gc,start ] GC(53) Pause Final Mark (Global) (unload classes) [2025-01-10T05:44:43.977Z] [41.171s][info][gc,task ] GC(53) Using 1 of 1 workers for final marking [2025-01-10T05:44:43.978Z] [41.171s][info][gc,age ] GC(53) ---------------------------------------------------------------------------- [2025-01-10T05:44:43.978Z] [41.171s][info][gc,ergo ] GC(53) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-01-10T05:44:43.978Z] [41.171s][info][gc,ergo ] GC(53) Adaptive CSet Selection for GLOBAL. Max Young Evacuation: 170K, Max Old Evacuation: 0B, Actual Free: 357M. [2025-01-10T05:44:43.978Z] [41.171s][info][gc,ergo ] GC(53) Forcing transfer of 1 region(s) from Young to Old, yielding increased size: 154M [2025-01-10T05:44:43.978Z] [41.171s][info][gc,ergo ] GC(53) Chosen CSet evacuates young: 0B (of which at least: 0B are to be promoted), old: 50696B [2025-01-10T05:44:43.978Z] [41.171s][info][gc,ergo ] GC(53) Collectable Garbage: 394K (54%), Immediate: 188K (26%), 1 regions, CSet: 206K (28%), 1 regions [2025-01-10T05:44:43.978Z] [41.171s][info][gc,ergo ] GC(53) Evacuation Targets: YOUNG: 0B, PROMOTE: 0B, OLD: 50696B, TOTAL: 50696B [2025-01-10T05:44:43.978Z] [41.172s][info][gc,ergo ] GC(53) Old-Gen Collectable Garbage: 0B consolidated with free: 0B, over 0 regions [2025-01-10T05:44:43.978Z] [41.172s][info][gc,ergo ] GC(53) Old-Gen Immediate Garbage: 0B over 0 regions [2025-01-10T05:44:43.978Z] [41.172s][info][gc,ergo ] GC(53) Old regions selected for defragmentation: 0 [2025-01-10T05:44:43.978Z] [41.172s][info][gc,ergo ] GC(53) Old regions not selected: 615 [2025-01-10T05:44:43.978Z] [41.172s][info][gc,ergo ] GC(53) After choosing global collection set, mixed candidates: 0, coalescing candidates: 615 [2025-01-10T05:44:43.978Z] [41.172s][info][gc,free ] Free: 357M, Max: 256K regular, 339M humongous, Frag: 5% external, 0% internal; Used: 188K, Mutator Free: 1431 Collector Reserve: 0B, Max: 0B; Used: 0B Old Collector Reserve: 256K, Max: 256K; Used: 0B [2025-01-10T05:44:43.978Z] [41.172s][info][gc,start ] GC(53) Verify Before Evacuation, Level 4 [2025-01-10T05:44:43.978Z] [41.452s][info][gc ] GC(53) Verify Before Evacuation, Level 4 (5014208 reachable, 155 marked) [2025-01-10T05:44:43.978Z] [41.452s][info][gc,ergo ] GC(53) Pacer for Evacuation. Used CSet: 256K, Free: 357M, Non-Taxable: 36633K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.978Z] [41.452s][info][gc ] GC(53) Pause Final Mark (Global) (unload classes) 280.983ms [2025-01-10T05:44:43.978Z] [41.452s][info][gc,start ] GC(53) Concurrent thread roots [2025-01-10T05:44:43.978Z] [41.452s][info][gc,task ] GC(53) Using 1 of 1 workers for Concurrent thread roots [2025-01-10T05:44:43.978Z] [41.452s][info][gc ] GC(53) Concurrent thread roots 0.116ms [2025-01-10T05:44:43.978Z] [41.452s][info][gc,start ] GC(53) Concurrent weak references (Global) (unload classes) [2025-01-10T05:44:43.978Z] [41.452s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent weak references [2025-01-10T05:44:43.978Z] [41.452s][info][gc,ref ] GC(53) Encountered references: Soft: 18, Weak: 76, Final: 0, Phantom: 8 [2025-01-10T05:44:43.978Z] [41.452s][info][gc,ref ] GC(53) Discovered references: Soft: 12, Weak: 8, Final: 0, Phantom: 8 [2025-01-10T05:44:43.978Z] [41.452s][info][gc,ref ] GC(53) Enqueued references: Soft: 0, Weak: 0, Final: 0, Phantom: 0 [2025-01-10T05:44:43.978Z] [41.452s][info][gc ] GC(53) Concurrent weak references (Global) (unload classes) 0.109ms [2025-01-10T05:44:43.978Z] [41.452s][info][gc,start ] GC(53) Concurrent weak roots (Global) (unload classes) [2025-01-10T05:44:43.978Z] [41.452s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent weak root [2025-01-10T05:44:43.978Z] [41.453s][info][gc ] GC(53) Concurrent weak roots (Global) (unload classes) 0.356ms [2025-01-10T05:44:43.978Z] [41.453s][info][gc,start ] GC(53) Concurrent cleanup (Global) (unload classes) [2025-01-10T05:44:43.978Z] [41.453s][info][gc,task ] GC(53) Using 1 of 1 workers for cleanup early. [2025-01-10T05:44:43.978Z] [41.453s][info][gc ] GC(53) Concurrent cleanup (Global) (unload classes) 154M->153M(154M) 0.041ms [2025-01-10T05:44:43.978Z] [41.453s][info][gc,free ] Free: 357M, Max: 256K regular, 350M humongous, Frag: 3% external, 0% internal; Used: 0B, Mutator Free: 1431 Collector Reserve: 0B, Max: 0B; Used: 0B Old Collector Reserve: 256K, Max: 256K; Used: 0B [2025-01-10T05:44:43.978Z] [41.453s][info][gc,start ] GC(53) Concurrent class unloading [2025-01-10T05:44:43.978Z] [41.453s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent class unloading [2025-01-10T05:44:43.978Z] [41.453s][info][gc ] GC(53) Concurrent class unloading 0.314ms [2025-01-10T05:44:43.978Z] [41.453s][info][gc,start ] GC(53) Concurrent strong roots [2025-01-10T05:44:43.978Z] [41.453s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent strong root [2025-01-10T05:44:43.978Z] [41.453s][info][gc ] GC(53) Concurrent strong roots 0.132ms [2025-01-10T05:44:43.978Z] [41.453s][info][gc,start ] GC(53) Concurrent evacuation [2025-01-10T05:44:43.978Z] [41.453s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent evacuation [2025-01-10T05:44:43.978Z] [41.454s][info][gc ] GC(53) Concurrent evacuation 0.304ms [2025-01-10T05:44:43.978Z] [41.454s][info][gc,start ] GC(53) Pause Init Update Refs [2025-01-10T05:44:43.978Z] [41.454s][info][gc,start ] GC(53) Verify Before Updating References, Level 4 [2025-01-10T05:44:43.978Z] [42.233s][info][gc ] GC(53) Verify Before Updating References, Level 4 (5014253 reachable, 1699 marked) [2025-01-10T05:44:43.978Z] [42.233s][info][gc,ergo ] GC(53) Pacer for Update Refs. Used: 154M, Free: 357M, Non-Taxable: 36633K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.978Z] [42.233s][info][gc ] GC(53) Pause Init Update Refs 779.578ms [2025-01-10T05:44:43.978Z] [42.233s][info][gc,start ] GC(53) Concurrent update references [2025-01-10T05:44:43.978Z] [42.233s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent reference update [2025-01-10T05:44:43.978Z] [42.233s][info][gc,ergo ] GC(53) At start of update refs, moving 0B to Mutator free set from Collector Reserve (0B) and from Old Collector Reserve (0B) [2025-01-10T05:44:43.978Z] [42.311s][info][gc ] GC(53) Concurrent update references 77.648ms [2025-01-10T05:44:43.978Z] [42.311s][info][gc,start ] GC(53) Concurrent update thread roots [2025-01-10T05:44:43.978Z] [42.311s][info][gc ] GC(53) Concurrent update thread roots 0.110ms [2025-01-10T05:44:43.978Z] [42.311s][info][gc,start ] GC(53) Pause Final Update Refs [2025-01-10T05:44:43.978Z] [42.311s][info][gc,task ] GC(53) Using 1 of 1 workers for final reference update [2025-01-10T05:44:43.978Z] [42.311s][info][gc,start ] GC(53) Verify After Updating References, Level 4 [2025-01-10T05:44:43.978Z] [42.569s][info][gc ] GC(53) Verify After Updating References, Level 4 (5014253 reachable, 115 marked) [2025-01-10T05:44:43.978Z] [42.569s][info][gc,free ] Free: 339M, Max: 256K regular, 339M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1359 Collector Reserve: 18432K, Max: 256K; Used: 0B Old Collector Reserve: 434K, Max: 256K; Used: 333K [2025-01-10T05:44:43.978Z] [42.569s][info][gc ] GC(53) Pause Final Update Refs 258.071ms [2025-01-10T05:44:43.978Z] [42.569s][info][gc,start ] GC(53) Concurrent cleanup (Global) (unload classes) [2025-01-10T05:44:43.978Z] [42.569s][info][gc,task ] GC(53) Using 1 of 1 workers for cleanup complete. [2025-01-10T05:44:43.978Z] [42.569s][info][gc ] GC(53) Concurrent cleanup (Global) (unload classes) 154M->153M(154M) 0.047ms [2025-01-10T05:44:43.978Z] [42.569s][info][gc,start ] GC(53) Coalescing and filling old regions [2025-01-10T05:44:43.978Z] [42.569s][info][gc,task ] GC(53) Using 1 of 1 workers for concurrent coalesce and fill [2025-01-10T05:44:43.978Z] [42.603s][info][gc ] GC(53) Coalescing and filling old regions 33.818ms [2025-01-10T05:44:43.978Z] [42.603s][info][gc,ergo ] GC(53) After Concurrent GC, successfully transferred 0 regions to none to prepare for next gc, old available: 434K, young_available: 339M [2025-01-10T05:44:43.978Z] [42.603s][info][gc,ergo ] GC(53) At end of Concurrent Global GC: GCU: 15.4%, MU: 6.2% during period of 2.846s [2025-01-10T05:44:43.978Z] [42.603s][info][gc,ergo ] GC(53) At end of Concurrent Global GC: Young generation used: 0B, used regions: 0B, humongous waste: 0B, soft capacity: 512M, max capacity: 357M, available: 339M [2025-01-10T05:44:43.978Z] [42.603s][info][gc,ergo ] GC(53) At end of Concurrent Global GC: Old generation used: 153M, used regions: 154M, humongous waste: 0B, soft capacity: 0B, max capacity: 154M, available: 434K [2025-01-10T05:44:43.978Z] [42.603s][info][gc,free ] Free: 339M, Max: 256K regular, 339M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1359 Collector Reserve: 18432K, Max: 256K; Used: 0B Old Collector Reserve: 434K, Max: 256K; Used: 79360B [2025-01-10T05:44:43.978Z] [42.603s][info][gc,stats ] [2025-01-10T05:44:43.978Z] [42.603s][info][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over [2025-01-10T05:44:43.978Z] [42.603s][info][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism. [2025-01-10T05:44:43.978Z] [42.603s][info][gc,stats ] [2025-01-10T05:44:43.978Z] [42.603s][info][gc,stats ] Concurrent Reset 539 us [2025-01-10T05:44:43.978Z] [42.603s][info][gc,stats ] Pause Init Mark (G) 207510 us [2025-01-10T05:44:43.978Z] [42.603s][info][gc,stats ] Pause Init Mark (N) 207434 us [2025-01-10T05:44:43.978Z] [42.603s][info][gc,stats ] Update Region States 27 us [2025-01-10T05:44:43.978Z] [42.604s][info][gc,stats ] Concurrent Mark Roots 191 us, parallelism: 0.32x [2025-01-10T05:44:43.978Z] [42.604s][info][gc,stats ] CMR: <total> 61 us [2025-01-10T05:44:43.978Z] [42.604s][info][gc,stats ] CMR: Thread Roots 39 us, workers (us): 39, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CMR: VM Strong Roots 2 us, workers (us): 2, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CMR: CLDG Roots 20 us, workers (us): 20, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Marking 176272 us, parallelism: 1.00x [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CM: <total> 176077 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CM: Parallel Mark 176077 us, workers (us): 176077, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Flush SATB 78 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Pause Final Mark (G) 281058 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Pause Final Mark (N) 280992 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Finish Mark 47 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Update Region States 27 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Choose Collection Set 195 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Rebuild Free Set 34 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Thread Roots 131 us, parallelism: 0.34x [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CTR: <total> 45 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CTR: Thread Roots 45 us, workers (us): 45, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Weak References 116 us, parallelism: 0.03x [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CWRF: <total> 4 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CWRF: Weak References 4 us, workers (us): 4, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Weak Roots 369 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Roots 188 us, parallelism: 0.55x [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CWR: <total> 103 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CWR: Code Cache Roots 56 us, workers (us): 56, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CWR: VM Weak Roots 46 us, workers (us): 46, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CWR: CLDG Roots 0 us, workers (us): 0, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Rendezvous 145 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Cleanup 52 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Class Unloading 326 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Unlink Stale 209 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] System Dictionary 2 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Weak Class Links 0 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Code Roots 201 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Rendezvous 72 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Purge Unlinked 10 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Code Roots 1 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CLDG 9 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Exception Caches 0 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Strong Roots 142 us, parallelism: 0.06x [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CSR: <total> 9 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CSR: VM Strong Roots 1 us, workers (us): 1, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CSR: CLDG Roots 7 us, workers (us): 7, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Evacuation 317 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Pause Init Update Refs (G) 779698 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Pause Init Update Refs (N) 779587 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Manage GCLABs 2 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Update Refs 77667 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Update Thread Roots 128 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Pause Final Update Refs (G) 258167 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Pause Final Update Refs (N) 258080 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Update Region States 38 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Trash Collection Set 1 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Rebuild Free Set 97 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Cleanup 59 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Concurrent Coalesce and Fill 33843 us, parallelism: 1.00x [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CC&F: <total> 33711 us [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] CC&F: Scan Clusters 33711 us, workers (us): 33711, [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Workers: [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Mutators: [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Young regions: Age table: [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] Allocation pacing accrued: [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] 0 of 2846 ms ( 0.0%): <total> [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] 0 of 2846 ms ( 0.0%): <average total> [2025-01-10T05:44:43.979Z] [42.604s][info][gc,stats ] [2025-01-10T05:44:43.979Z] [42.604s][info][gc,metaspace] Metaspace: 6875K(7040K)->6875K(7040K) NonClass: 6416K(6528K)->6416K(6528K) Class: 458K(512K)->458K(512K) [2025-01-10T05:44:43.979Z] [42.604s][info][gc,ergo ] Pacer for Idle. Initial: 10485K, Alloc Tax Rate: 1.0x [2025-01-10T05:44:43.979Z] [42.604s][info][gc,start ] Concurrent uncommit [2025-01-10T05:44:43.979Z] [42.604s][info][gc ] Concurrent uncommit 512K (154M) 0.113ms [2025-01-10T05:44:43.979Z] [44.637s][info][gc ] Trigger (Global): GC request (System.gc()) [2025-01-10T05:44:43.979Z] [44.637s][info][gc,free ] Free: 339M, Max: 256K regular, 339M humongous, Frag: 0% external, 0% internal; Used: 188K, Mutator Free: 1359 Collector Reserve: 18432K, Max: 256K; Used: 0B Old Collector Reserve: 434K, Max: 256K; Used: 79360B [2025-01-10T05:44:43.979Z] [44.637s][info][gc,ergo ] GC(54) Start GC cycle (Global) [2025-01-10T05:44:43.979Z] [44.637s][info][gc,start ] GC(54) Concurrent reset (Global) (unload classes) [2025-01-10T05:44:43.979Z] [44.637s][info][gc,task ] GC(54) Using 1 of 1 workers for Concurrent reset (Global) (unload classes) [2025-01-10T05:44:43.979Z] [44.637s][info][gc,ergo ] GC(54) Pacer for Reset. Non-Taxable: 512M [2025-01-10T05:44:43.979Z] [44.639s][info][gc ] GC(54) Concurrent reset (Global) (unload classes) 2.395ms [2025-01-10T05:44:43.979Z] [44.640s][info][gc,start ] GC(54) Pause Init Mark (Global) (unload classes) [2025-01-10T05:44:43.979Z] [44.640s][info][gc,task ] GC(54) Using 1 of 1 workers for init marking [2025-01-10T05:44:43.979Z] [44.640s][info][gc,start ] GC(54) Verify Before Mark, Level 4 [2025-01-10T05:44:43.979Z] [44.845s][info][gc ] GC(54) Verify Before Mark, Level 4 (5015239 reachable, 0 marked) [2025-01-10T05:44:43.979Z] [44.845s][info][gc,ref ] GC(54) Clearing All SoftReferences [2025-01-10T05:44:43.979Z] [44.845s][info][gc,ergo ] GC(54) Pacer for Mark. Expected Live: 94296K, Free: 339M, Non-Taxable: 34739K, Alloc Tax Rate: 0.3x [2025-01-10T05:44:43.979Z] [44.845s][info][gc ] GC(54) Pause Init Mark (Global) (unload classes) 205.151ms [2025-01-10T05:44:43.979Z] [44.845s][info][gc,start ] GC(54) Concurrent marking roots [2025-01-10T05:44:43.979Z] [44.845s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent marking roots [2025-01-10T05:44:43.979Z] [44.845s][info][gc ] GC(54) Concurrent marking roots 0.179ms [2025-01-10T05:44:43.979Z] [44.845s][info][gc,start ] GC(54) Concurrent marking (Global) (unload classes) [2025-01-10T05:44:43.979Z] [44.845s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent marking [2025-01-10T05:44:43.979Z] [45.041s][info][gc ] GC(54) Concurrent marking (Global) (unload classes) 196.076ms [2025-01-10T05:44:43.979Z] [45.041s][info][gc,start ] GC(54) Pause Final Mark (Global) (unload classes) [2025-01-10T05:44:43.979Z] [45.041s][info][gc,task ] GC(54) Using 1 of 1 workers for final marking [2025-01-10T05:44:43.979Z] [45.042s][info][gc,age ] GC(54) ---------------------------------------------------------------------------- [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Planning to promote in place 0 humongous regions and 0 regular regions, spanning a total of 0 used bytes [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Adaptive CSet Selection for GLOBAL. Max Young Evacuation: 159K, Max Old Evacuation: 182K, Actual Free: 322M. [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Chosen CSet evacuates young: 33544B (of which at least: 0B are to be promoted), old: 0B [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Collectable Garbage: 479K (57%), Immediate: 0B (0%), 0 regions, CSet: 479K (57%), 2 regions [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Evacuation Targets: YOUNG: 33544B, PROMOTE: 0B, OLD: 0B, TOTAL: 33544B [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Transfer 1 region(s) from Old to Young, yielding increased size: 358M [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Old-Gen Collectable Garbage: 28664B consolidated with free: 178K, over 1 regions [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Old-Gen Immediate Garbage: 0B over 0 regions [2025-01-10T05:44:43.979Z] [45.042s][info][gc,ergo ] GC(54) Old regions selected for defragmentation: 0 [2025-01-10T05:44:43.980Z] [45.042s][info][gc,ergo ] GC(54) Old regions not selected: 615 [2025-01-10T05:44:43.980Z] [45.042s][info][gc,ergo ] GC(54) After choosing global collection set, mixed candidates: 1, coalescing candidates: 616 [2025-01-10T05:44:43.980Z] [45.042s][info][gc,ergo ] GC(54) Transfer 1 region(s) from Young to Old, yielding increased size: 154M [2025-01-10T05:44:43.980Z] [45.042s][info][gc,free ] Free: 321M, Max: 256K regular, 304M humongous, Frag: 6% external, 0% internal; Used: 0B, Mutator Free: 1287 Collector Reserve: 256K, Max: 256K; Used: 0B Old Collector Reserve: 434K, Max: 256K; Used: 79360B [2025-01-10T05:44:43.980Z] [45.042s][info][gc,start ] GC(54) Verify Before Evacuation, Level 4 [2025-01-10T05:44:43.980Z] [45.381s][info][gc ] GC(54) Verify Before Evacuation, Level 4 (5015235 reachable, 1317417 marked) [2025-01-10T05:44:43.980Z] [45.381s][info][gc,ergo ] GC(54) Pacer for Evacuation. Used CSet: 512K, Free: 321M, Non-Taxable: 32947K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.980Z] [45.381s][info][gc ] GC(54) Pause Final Mark (Global) (unload classes) 339.405ms [2025-01-10T05:44:43.980Z] [45.381s][info][gc,start ] GC(54) Concurrent thread roots [2025-01-10T05:44:43.980Z] [45.381s][info][gc,task ] GC(54) Using 1 of 1 workers for Concurrent thread roots [2025-01-10T05:44:43.980Z] [45.381s][info][gc ] GC(54) Concurrent thread roots 0.125ms [2025-01-10T05:44:43.980Z] [45.381s][info][gc,start ] GC(54) Concurrent weak references (Global) (unload classes) [2025-01-10T05:44:43.980Z] [45.381s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent weak references [2025-01-10T05:44:43.980Z] [45.381s][info][gc,ref ] GC(54) Encountered references: Soft: 20, Weak: 77, Final: 0, Phantom: 10 [2025-01-10T05:44:43.980Z] [45.381s][info][gc,ref ] GC(54) Discovered references: Soft: 14, Weak: 9, Final: 0, Phantom: 10 [2025-01-10T05:44:43.980Z] [45.381s][info][gc,ref ] GC(54) Enqueued references: Soft: 2, Weak: 1, Final: 0, Phantom: 2 [2025-01-10T05:44:43.980Z] [45.381s][info][gc ] GC(54) Concurrent weak references (Global) (unload classes) 0.135ms [2025-01-10T05:44:43.980Z] [45.381s][info][gc,start ] GC(54) Concurrent weak roots (Global) (unload classes) [2025-01-10T05:44:43.980Z] [45.381s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent weak root [2025-01-10T05:44:43.980Z] [45.382s][info][gc ] GC(54) Concurrent weak roots (Global) (unload classes) 0.454ms [2025-01-10T05:44:43.980Z] [45.382s][info][gc,start ] GC(54) Concurrent cleanup (Global) (unload classes) [2025-01-10T05:44:43.980Z] [45.382s][info][gc,task ] GC(54) Using 1 of 1 workers for cleanup early. [2025-01-10T05:44:43.980Z] [45.382s][info][gc ] GC(54) Concurrent cleanup (Global) (unload classes) 189M->189M(190M) 0.046ms [2025-01-10T05:44:43.980Z] [45.382s][info][gc,free ] Free: 321M, Max: 256K regular, 304M humongous, Frag: 6% external, 0% internal; Used: 0B, Mutator Free: 1286 Collector Reserve: 221K, Max: 221K; Used: 35088B Old Collector Reserve: 434K, Max: 256K; Used: 79360B [2025-01-10T05:44:43.980Z] [45.382s][info][gc,start ] GC(54) Concurrent class unloading [2025-01-10T05:44:43.980Z] [45.382s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent class unloading [2025-01-10T05:44:43.980Z] [45.382s][info][gc ] GC(54) Concurrent class unloading 0.372ms [2025-01-10T05:44:43.980Z] [45.382s][info][gc,start ] GC(54) Concurrent strong roots [2025-01-10T05:44:43.980Z] [45.382s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent strong root [2025-01-10T05:44:43.980Z] [45.382s][info][gc ] GC(54) Concurrent strong roots 0.067ms [2025-01-10T05:44:43.980Z] [45.382s][info][gc,start ] GC(54) Concurrent evacuation [2025-01-10T05:44:43.980Z] [45.382s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent evacuation [2025-01-10T05:44:43.980Z] [45.382s][info][gc ] GC(54) Concurrent evacuation 0.158ms [2025-01-10T05:44:43.980Z] [45.382s][info][gc,start ] GC(54) Pause Init Update Refs [2025-01-10T05:44:43.980Z] [45.382s][info][gc,start ] GC(54) Verify Before Updating References, Level 4 [2025-01-10T05:44:43.980Z] [46.199s][info][gc ] GC(54) Verify Before Updating References, Level 4 (5015276 reachable, 1334462 marked) [2025-01-10T05:44:43.980Z] [46.199s][info][gc,ergo ] GC(54) Pacer for Update Refs. Used: 190M, Free: 321M, Non-Taxable: 32897K, Alloc Tax Rate: 1.1x [2025-01-10T05:44:43.980Z] [46.199s][info][gc ] GC(54) Pause Init Update Refs 816.988ms [2025-01-10T05:44:43.980Z] [46.199s][info][gc,start ] GC(54) Concurrent update references [2025-01-10T05:44:43.980Z] [46.200s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent reference update [2025-01-10T05:44:43.980Z] [46.200s][info][gc,ergo ] GC(54) Transfer 1 region(s) from Old to Young, yielding increased size: 358M [2025-01-10T05:44:43.980Z] [46.200s][info][gc,ergo ] GC(54) At start of update refs, moving 465K to Mutator free set from Collector Reserve (209K) and from Old Collector Reserve (256K) [2025-01-10T05:44:43.980Z] [46.286s][info][gc ] GC(54) Concurrent update references 86.717ms [2025-01-10T05:44:43.980Z] [46.286s][info][gc,start ] GC(54) Concurrent update thread roots [2025-01-10T05:44:43.980Z] [46.286s][info][gc ] GC(54) Concurrent update thread roots 0.149ms [2025-01-10T05:44:43.980Z] [46.286s][info][gc,start ] GC(54) Pause Final Update Refs [2025-01-10T05:44:43.980Z] [46.286s][info][gc,task ] GC(54) Using 1 of 1 workers for final reference update [2025-01-10T05:44:43.980Z] [46.287s][info][gc,start ] GC(54) Verify After Updating References, Level 4 [2025-01-10T05:44:43.980Z] [46.634s][info][gc ] GC(54) Verify After Updating References, Level 4 (5015276 reachable, 2857958 marked) [2025-01-10T05:44:43.980Z] [46.634s][info][gc,ergo ] GC(54) Transfer 2 region(s) from Young to Old, yielding increased size: 154M [2025-01-10T05:44:43.980Z] [46.634s][info][gc,free ] Free: 262M, Max: 256K regular, 262M humongous, Frag: 0% external, 0% internal; Used: 0B, Mutator Free: 1051 Collector Reserve: 18445K, Max: 256K; Used: 242K Old Collector Reserve: 690K, Max: 256K; Used: 589K [2025-01-10T05:44:43.980Z] [46.634s][info][gc ] GC(54) Pause Final Update Refs 347.941ms [2025-01-10T05:44:43.980Z] [46.635s][info][gc,start ] GC(54) Concurrent cleanup (Global) (unload classes) [2025-01-10T05:44:43.980Z] [46.635s][info][gc,task ] GC(54) Using 1 of 1 workers for cleanup complete. [2025-01-10T05:44:43.980Z] [46.635s][info][gc ] GC(54) Concurrent cleanup (Global) (unload classes) 231M->230M(231M) 0.045ms [2025-01-10T05:44:43.980Z] [46.635s][info][gc,start ] GC(54) Coalescing and filling old regions [2025-01-10T05:44:43.980Z] [46.635s][info][gc,task ] GC(54) Using 1 of 1 workers for concurrent coalesce and fill [2025-01-10T05:44:43.980Z] # [2025-01-10T05:44:43.980Z] # A fatal error has been detected by the Java Runtime Environment: [2025-01-10T05:44:43.980Z] # [2025-01-10T05:44:43.980Z] # SIGSEGV (0xb) at pc=0x0000000103f09c45, pid=45137, tid=14339 [2025-01-10T05:44:43.980Z] # [2025-01-10T05:44:43.980Z] # JRE version: OpenJDK Runtime Environment Temurin-25+5-202501092134 (25.0+5) (build 25-beta+5-ea) [2025-01-10T05:44:43.980Z] # Java VM: OpenJDK 64-Bit Server VM Temurin-25+5-202501092134 (25-beta+5-ea, mixed mode, tiered, compressed oops, compressed class ptrs, shenandoah gc, bsd-amd64) [2025-01-10T05:44:43.980Z] # Problematic frame: [2025-01-10T05:44:43.980Z] # V [libjvm.dylib+0xae3c45] ShenandoahCardCluster::coalesce_objects(HeapWordImpl**, unsigned long)+0x115 [2025-01-10T05:44:43.980Z] # [2025-01-10T05:44:43.980Z] # No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again [2025-01-10T05:44:43.980Z] # [2025-01-10T05:44:43.980Z] # An error report file with more information is saved as: [2025-01-10T05:44:43.980Z] # /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/scratch/0/hs_err_pid45137.log [2025-01-10T05:44:43.980Z] # [2025-01-10T05:44:43.980Z] # If you would like to submit a bug report, please visit: [2025-01-10T05:44:43.980Z] # https://github.com/adoptium/adoptium-support/issues [2025-01-10T05:44:43.980Z] # [2025-01-10T05:44:43.980Z] STDERR: [2025-01-10T05:44:43.980Z] rerun: [2025-01-10T05:44:43.980Z] cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/scratch/0 && \ [2025-01-10T05:44:43.980Z] HOME=/Users/admin \ [2025-01-10T05:44:43.980Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-01-10T05:44:43.980Z] CLASSPATH=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-01-10T05:44:43.980Z] /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/java \ [2025-01-10T05:44:43.980Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-01-10T05:44:43.980Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-01-10T05:44:43.980Z] -Dtest.compiler.opts= \ [2025-01-10T05:44:43.980Z] -Dtest.java.opts= \ [2025-01-10T05:44:43.980Z] -Dtest.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:44:43.980Z] -Dcompile.jdk=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-01-10T05:44:43.980Z] -Dtest.timeout.factor=8.0 \ [2025-01-10T05:44:43.980Z] -Dtest.nativepath=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-01-10T05:44:43.980Z] -Dtest.root=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-01-10T05:44:43.981Z] -Dtest.name=gc/stress/systemgc/TestSystemGCWithShenandoah.java#generational \ [2025-01-10T05:44:43.981Z] -Dtest.file=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc/TestSystemGCWithShenandoah.java \ [2025-01-10T05:44:43.981Z] -Dtest.src=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc \ [2025-01-10T05:44:43.981Z] -Dtest.src.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-01-10T05:44:43.981Z] -Dtest.classes=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d \ [2025-01-10T05:44:43.981Z] -Dtest.class.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0 \ [2025-01-10T05:44:43.981Z] -Dtest.class.path.prefix=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/systemgc:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/classes/0 \ [2025-01-10T05:44:43.981Z] -ea \ [2025-01-10T05:44:43.981Z] -esa \ [2025-01-10T05:44:43.981Z] -Xmx512m \ [2025-01-10T05:44:43.981Z] -Djava.library.path=/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-01-10T05:44:43.981Z] -Xlog:gc*=info \ [2025-01-10T05:44:43.981Z] -Xmx512m \ [2025-01-10T05:44:43.981Z] -XX:+UnlockExperimentalVMOptions \ [2025-01-10T05:44:43.981Z] -XX:+UnlockDiagnosticVMOptions \ [2025-01-10T05:44:43.981Z] -XX:+UseShenandoahGC \ [2025-01-10T05:44:43.981Z] -XX:ShenandoahGCMode=generational \ [2025-01-10T05:44:43.981Z] -XX:+ShenandoahVerify \ [2025-01-10T05:44:43.981Z] com.sun.javatest.regtest.agent.MainWrapper /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work/gc/stress/systemgc/TestSystemGCWithShenandoah_generational.d/main.0.jta 270 [2025-01-10T05:44:43.981Z] [2025-01-10T05:44:43.981Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 134] [2025-01-10T05:44:43.981Z] -------------------------------------------------- [2025-01-10T06:36:07.210Z] Test results: passed: 522; failed: 2 [2025-01-10T06:36:14.972Z] Report written to /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/report/html/report.html [2025-01-10T06:36:14.972Z] Results written to /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.openjdk_x86-64_mac_testList_0/aqa-tests/TKG/output_17364838142363/hotspot_gc_0/work [2025-01-10T06:36:14.972Z] Error: Some tests failed or other problems occurred. [2025-01-10T06:36:14.972Z] ----------------------------------- [2025-01-10T06:36:14.972Z] hotspot_gc_0_FAILED [2025-01-10T06:36:14.972Z] ----------------------------------- [2025-01-10T06:36:14.972Z] [2025-01-10T06:36:14.972Z] TEST TEARDOWN: [2025-01-10T06:36:14.972Z] Nothing to be done for teardown. [2025-01-10T06:36:14.972Z] hotspot_gc_0 Finish Time: Thu Jan 9 22:36:13 2025 Epoch Time (ms): 1736490973402