[2025-07-02T13:33:12.888Z] Running test hotspot_gc_0 ... [2025-07-02T13:33:12.888Z] =============================================== [2025-07-02T13:33:12.888Z] hotspot_gc_0 Start Time: Wed Jul 2 09:33:12 2025 Epoch Time (ms): 1751463192759 [2025-07-02T13:33:12.888Z] variation: NoOptions [2025-07-02T13:33:12.888Z] JVM_OPTIONS: [2025-07-02T13:33:12.888Z] { \ [2025-07-02T13:33:12.888Z] echo ""; echo "TEST SETUP:"; \ [2025-07-02T13:33:12.888Z] echo "Nothing to be done for setup."; \ [2025-07-02T13:33:12.888Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17514631926861/hotspot_gc_0"; \ [2025-07-02T13:33:12.888Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17514631926861/hotspot_gc_0"; \ [2025-07-02T13:33:12.888Z] echo ""; echo "TESTING:"; \ [2025-07-02T13:33:12.888Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/..//bin/java" -Xmx512m -jar "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-07-02T13:33:12.888Z] -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_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2025-07-02T13:33:12.888Z] -w ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17514631926861/hotspot_gc_0"/work" \ [2025-07-02T13:33:12.888Z] -r ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17514631926861/hotspot_gc_0"/report" \ [2025-07-02T13:33:12.888Z] -jdk:"/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../" \ [2025-07-02T13:33:12.888Z] -exclude:"/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2025-07-02T13:33:12.888Z] -exclude:"/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2025-07-02T13:33:12.888Z] \ [2025-07-02T13:33:12.888Z] -exclude:"/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2025-07-02T13:33:12.888Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \ [2025-07-02T13:33:12.888Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-02T13:33:12.888Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-02T13:33:12.888Z] echo "Nothing to be done for teardown."; \ [2025-07-02T13:33:12.888Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17514631926861/TestTargetResult"; [2025-07-02T13:33:12.888Z] [2025-07-02T13:33:12.888Z] TEST SETUP: [2025-07-02T13:33:12.888Z] Nothing to be done for setup. [2025-07-02T13:33:12.888Z] [2025-07-02T13:33:12.888Z] TESTING: [2025-07-02T13:33:13.245Z] Directory "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17514631926861/hotspot_gc_0/work" not found: creating [2025-07-02T13:33:13.245Z] Directory "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17514631926861/hotspot_gc_0/report" not found: creating [2025-07-02T13:33:15.648Z] XML output with verification to /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work Calling Pipeline was cancelled [2025-07-02T14:35:08.624Z] Sending interrupt signal to process Calling Pipeline was cancelled [2025-07-02T14:35:08.817Z] Sending interrupt signal to process [2025-07-02T14:35:22.254Z] -------------------------------------------------- [2025-07-02T14:35:22.254Z] TEST: gc/stress/gcbasher/TestGCBasherWithZ.java#id0 [2025-07-02T14:35:22.254Z] TEST JDK: /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. [2025-07-02T14:35:22.254Z] [2025-07-02T14:35:22.254Z] ACTION: build -- Passed. Build successful [2025-07-02T14:35:22.254Z] REASON: Named class compiled on demand [2025-07-02T14:35:22.254Z] TIME: 0.072 seconds [2025-07-02T14:35:22.254Z] messages: [2025-07-02T14:35:22.254Z] command: build gc.stress.gcbasher.TestGCBasherWithZ [2025-07-02T14:35:22.254Z] reason: Named class compiled on demand [2025-07-02T14:35:22.254Z] started: Wed Jul 02 10:33:46 EDT 2025 [2025-07-02T14:35:22.254Z] Test directory: [2025-07-02T14:35:22.254Z] compile: gc.stress.gcbasher.TestGCBasherWithZ [2025-07-02T14:35:22.254Z] finished: Wed Jul 02 10:33:46 EDT 2025 [2025-07-02T14:35:22.254Z] elapsed time (seconds): 0.072 [2025-07-02T14:35:22.254Z] [2025-07-02T14:35:22.254Z] ACTION: compile -- Passed. Compilation successful [2025-07-02T14:35:22.254Z] REASON: .class file out of date or does not exist [2025-07-02T14:35:22.254Z] TIME: 0.072 seconds [2025-07-02T14:35:22.254Z] messages: [2025-07-02T14:35:22.254Z] command: compile /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher/TestGCBasherWithZ.java [2025-07-02T14:35:22.254Z] reason: .class file out of date or does not exist [2025-07-02T14:35:22.254Z] started: Wed Jul 02 10:33:46 EDT 2025 [2025-07-02T14:35:22.254Z] Mode: agentvm [2025-07-02T14:35:22.254Z] Agent id: 1 [2025-07-02T14:35:22.254Z] finished: Wed Jul 02 10:33:46 EDT 2025 [2025-07-02T14:35:22.254Z] elapsed time (seconds): 0.072 [2025-07-02T14:35:22.254Z] configuration: [2025-07-02T14:35:22.254Z] Boot Layer (javac runtime environment) [2025-07-02T14:35:22.254Z] class path: /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-07-02T14:35:22.254Z] /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-07-02T14:35:22.254Z] patch: java.base /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/patches/java.base [2025-07-02T14:35:22.254Z] [2025-07-02T14:35:22.254Z] javac compilation environment [2025-07-02T14:35:22.254Z] source path: /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher [2025-07-02T14:35:22.254Z] /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg [2025-07-02T14:35:22.254Z] class path: /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher [2025-07-02T14:35:22.254Z] /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d [2025-07-02T14:35:22.254Z] /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0 [2025-07-02T14:35:22.254Z] [2025-07-02T14:35:22.254Z] rerun: [2025-07-02T14:35:22.254Z] cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/scratch/1 && \ [2025-07-02T14:35:22.254Z] HOME=/Users/admin \ [2025-07-02T14:35:22.254Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-07-02T14:35:22.254Z] /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/javac \ [2025-07-02T14:35:22.254Z] -J-ea \ [2025-07-02T14:35:22.254Z] -J-esa \ [2025-07-02T14:35:22.254Z] -J-Xmx512m \ [2025-07-02T14:35:22.254Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-07-02T14:35:22.254Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-07-02T14:35:22.254Z] -J-Dtest.compiler.opts= \ [2025-07-02T14:35:22.254Z] -J-Dtest.java.opts= \ [2025-07-02T14:35:22.254Z] -J-Dtest.jdk=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-07-02T14:35:22.254Z] -J-Dcompile.jdk=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-07-02T14:35:22.254Z] -J-Dtest.timeout.factor=8.0 \ [2025-07-02T14:35:22.254Z] -J-Dtest.nativepath=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-07-02T14:35:22.254Z] -J-Dtest.root=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-07-02T14:35:22.254Z] -J-Dtest.name=gc/stress/gcbasher/TestGCBasherWithZ.java#id0 \ [2025-07-02T14:35:22.254Z] -J-Dtest.file=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher/TestGCBasherWithZ.java \ [2025-07-02T14:35:22.254Z] -J-Dtest.src=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher \ [2025-07-02T14:35:22.254Z] -J-Dtest.src.path=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-07-02T14:35:22.254Z] -J-Dtest.classes=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d \ [2025-07-02T14:35:22.254Z] -J-Dtest.class.path=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0 \ [2025-07-02T14:35:22.254Z] -J-Dtest.class.path.prefix=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0 \ [2025-07-02T14:35:22.254Z] -d /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d \ [2025-07-02T14:35:22.254Z] -sourcepath /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-07-02T14:35:22.254Z] -classpath /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0 /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher/TestGCBasherWithZ.java [2025-07-02T14:35:22.254Z] [2025-07-02T14:35:22.254Z] ACTION: main -- Failed. Unexpected exit from test [exit code: 143] [2025-07-02T14:35:22.254Z] REASON: User specified action: run main/othervm/timeout=200 -Xlog:gc*=info -Xmx384m -server -XX:+UseZGC gc.stress.gcbasher.TestGCBasherWithZ 120000 [2025-07-02T14:35:22.254Z] TIME: 81.724 seconds [2025-07-02T14:35:22.254Z] messages: [2025-07-02T14:35:22.254Z] command: main -Xlog:gc*=info -Xmx384m -server -XX:+UseZGC gc.stress.gcbasher.TestGCBasherWithZ 120000 [2025-07-02T14:35:22.254Z] reason: User specified action: run main/othervm/timeout=200 -Xlog:gc*=info -Xmx384m -server -XX:+UseZGC gc.stress.gcbasher.TestGCBasherWithZ 120000 [2025-07-02T14:35:22.254Z] started: Wed Jul 02 10:33:46 EDT 2025 [2025-07-02T14:35:22.254Z] Mode: othervm [/othervm specified] [2025-07-02T14:35:22.254Z] finished: Wed Jul 02 10:35:08 EDT 2025 [2025-07-02T14:35:22.254Z] elapsed time (seconds): 81.724 [2025-07-02T14:35:22.254Z] configuration: [2025-07-02T14:35:22.254Z] STDOUT: [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] Initializing The Z Garbage Collector [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] Version: 17.0.16-beta+7-ea (release) [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] NUMA Support: Disabled [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] CPUs: 3 total, 3 available [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] Memory: 5120M [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] Large Page Support: Disabled [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] GC Workers: 1 (dynamic) [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] Address Space Type: Contiguous/Unrestricted/Complete [2025-07-02T14:35:22.254Z] [0.004s][info][gc,init] Address Space Size: 6144M x 3 = 18432M [2025-07-02T14:35:22.254Z] [0.005s][info][gc,init] Min Capacity: 8M [2025-07-02T14:35:22.254Z] [0.005s][info][gc,init] Initial Capacity: 80M [2025-07-02T14:35:22.254Z] [0.005s][info][gc,init] Max Capacity: 384M [2025-07-02T14:35:22.254Z] [0.005s][info][gc,init] Medium Page Size: 8M [2025-07-02T14:35:22.254Z] [0.005s][info][gc,init] Pre-touch: Disabled [2025-07-02T14:35:22.254Z] [0.005s][info][gc,init] Uncommit: Enabled [2025-07-02T14:35:22.254Z] [0.005s][info][gc,init] Uncommit Delay: 300s [2025-07-02T14:35:22.254Z] [0.005s][info][gc,init] Runtime Workers: 2 [2025-07-02T14:35:22.254Z] [0.005s][info][gc ] Using The Z Garbage Collector [2025-07-02T14:35:22.254Z] [0.089s][info][gc,metaspace] CDS archive(s) mapped at: [0x0000008800000000-0x0000008800b98000-0x0000008800b98000), size 12156928, SharedBaseAddress: 0x0000008800000000, ArchiveRelocationMode: 1. [2025-07-02T14:35:22.254Z] [0.089s][info][gc,metaspace] Compressed class space mapped at: 0x0000008801000000-0x0000008841000000, reserved size: 1073741824 [2025-07-02T14:35:22.254Z] [0.089s][info][gc,metaspace] Narrow klass base: 0x0000008800000000, Narrow klass shift: 0, Narrow klass range: 0x100000000 [2025-07-02T14:35:22.254Z] [0.311s][info][gc,start ] GC(0) Garbage Collection (Warmup) [2025-07-02T14:35:22.254Z] [0.311s][info][gc,task ] GC(0) Using 1 workers [2025-07-02T14:35:22.254Z] [0.312s][info][gc,phases ] GC(0) Pause Mark Start 0.007ms [2025-07-02T14:35:22.255Z] [0.326s][info][gc,phases ] GC(0) Concurrent Mark 14.787ms [2025-07-02T14:35:22.255Z] [0.326s][info][gc,phases ] GC(0) Pause Mark End 0.013ms [2025-07-02T14:35:22.255Z] [0.327s][info][gc,phases ] GC(0) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.255Z] [0.333s][info][gc,phases ] GC(0) Concurrent Process Non-Strong References 6.202ms [2025-07-02T14:35:22.255Z] [0.333s][info][gc,phases ] GC(0) Concurrent Reset Relocation Set 0.001ms [2025-07-02T14:35:22.255Z] [0.337s][info][gc,phases ] GC(0) Concurrent Select Relocation Set 4.249ms [2025-07-02T14:35:22.255Z] [0.337s][info][gc,phases ] GC(0) Pause Relocate Start 0.009ms [2025-07-02T14:35:22.255Z] [0.345s][info][gc,phases ] GC(0) Concurrent Relocate 7.930ms [2025-07-02T14:35:22.255Z] [0.345s][info][gc,load ] GC(0) Load: 3.01/4.18/4.52 [2025-07-02T14:35:22.255Z] [0.345s][info][gc,mmu ] GC(0) MMU: 2ms/99.4%, 5ms/99.7%, 10ms/99.9%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.255Z] [0.345s][info][gc,marking ] GC(0) Mark: 1 stripe(s), 3 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.255Z] [0.345s][info][gc,marking ] GC(0) Mark Stack Usage: 32M [2025-07-02T14:35:22.255Z] [0.345s][info][gc,nmethod ] GC(0) NMethods: 775 registered, 0 unregistered [2025-07-02T14:35:22.255Z] [0.345s][info][gc,metaspace] GC(0) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.255Z] [0.345s][info][gc,ref ] GC(0) Soft: 40 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.345s][info][gc,ref ] GC(0) Weak: 134 encountered, 25 discovered, 25 enqueued [2025-07-02T14:35:22.255Z] [0.345s][info][gc,ref ] GC(0) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.345s][info][gc,ref ] GC(0) Phantom: 24 encountered, 22 discovered, 12 enqueued [2025-07-02T14:35:22.255Z] [0.345s][info][gc,reloc ] GC(0) Small Pages: 27 / 54M, Empty: 0M, Relocated: 6M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.345s][info][gc,reloc ] GC(0) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.345s][info][gc,reloc ] GC(0) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.345s][info][gc,reloc ] GC(0) Forwarding Usage: 3M [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Min Capacity: 8M(2%) [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Max Capacity: 384M(100%) [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Capacity: 80M (21%) 80M (21%) 80M (21%) 80M (21%) 80M (21%) 80M (21%) [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Free: 330M (86%) 326M (85%) 314M (82%) 352M (92%) 352M (92%) 312M (81%) [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Used: 54M (14%) 58M (15%) 70M (18%) 32M (8%) 72M (19%) 32M (8%) [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Live: - 7M (2%) 7M (2%) 7M (2%) - - [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Allocated: - 4M (1%) 16M (4%) 21M (6%) - - [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Garbage: - 46M (12%) 46M (12%) 2M (1%) - - [2025-07-02T14:35:22.255Z] [0.345s][info][gc,heap ] GC(0) Reclaimed: - - 0M (0%) 43M (11%) - - [2025-07-02T14:35:22.255Z] [0.345s][info][gc ] GC(0) Garbage Collection (Warmup) 54M(14%)->32M(8%) [2025-07-02T14:35:22.255Z] [0.406s][info][gc,start ] GC(1) Garbage Collection (Warmup) [2025-07-02T14:35:22.255Z] [0.406s][info][gc,task ] GC(1) Using 1 workers [2025-07-02T14:35:22.255Z] [0.406s][info][gc,phases ] GC(1) Pause Mark Start 0.009ms [2025-07-02T14:35:22.255Z] [0.454s][info][gc,phases ] GC(1) Concurrent Mark 47.849ms [2025-07-02T14:35:22.255Z] [0.454s][info][gc,phases ] GC(1) Pause Mark End 0.012ms [2025-07-02T14:35:22.255Z] [0.454s][info][gc,phases ] GC(1) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.255Z] [0.456s][info][gc,phases ] GC(1) Concurrent Process Non-Strong References 1.786ms [2025-07-02T14:35:22.255Z] [0.456s][info][gc,phases ] GC(1) Concurrent Reset Relocation Set 0.004ms [2025-07-02T14:35:22.255Z] [0.462s][info][gc,phases ] GC(1) Concurrent Select Relocation Set 5.769ms [2025-07-02T14:35:22.255Z] [0.462s][info][gc,phases ] GC(1) Pause Relocate Start 0.012ms [2025-07-02T14:35:22.255Z] [0.472s][info][gc,phases ] GC(1) Concurrent Relocate 10.211ms [2025-07-02T14:35:22.255Z] [0.472s][info][gc,load ] GC(1) Load: 3.01/4.18/4.52 [2025-07-02T14:35:22.255Z] [0.472s][info][gc,mmu ] GC(1) MMU: 2ms/99.4%, 5ms/99.7%, 10ms/99.8%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.255Z] [0.472s][info][gc,marking ] GC(1) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.255Z] [0.472s][info][gc,marking ] GC(1) Mark Stack Usage: 32M [2025-07-02T14:35:22.255Z] [0.472s][info][gc,nmethod ] GC(1) NMethods: 801 registered, 0 unregistered [2025-07-02T14:35:22.255Z] [0.472s][info][gc,metaspace] GC(1) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.255Z] [0.472s][info][gc,ref ] GC(1) Soft: 40 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.472s][info][gc,ref ] GC(1) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.472s][info][gc,ref ] GC(1) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.472s][info][gc,ref ] GC(1) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.472s][info][gc,reloc ] GC(1) Small Pages: 46 / 92M, Empty: 0M, Relocated: 11M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.472s][info][gc,reloc ] GC(1) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.472s][info][gc,reloc ] GC(1) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.472s][info][gc,reloc ] GC(1) Forwarding Usage: 5M [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Min Capacity: 8M(2%) [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Max Capacity: 384M(100%) [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Capacity: 92M (24%) 132M (34%) 134M (35%) 136M (35%) 136M (35%) 92M (24%) [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Free: 292M (76%) 252M (66%) 250M (65%) 314M (82%) 314M (82%) 248M (65%) [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Used: 92M (24%) 132M (34%) 134M (35%) 70M (18%) 136M (35%) 70M (18%) [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Live: - 17M (5%) 17M (5%) 17M (5%) - - [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Allocated: - 40M (10%) 42M (11%) 51M (13%) - - [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Garbage: - 74M (19%) 74M (19%) 0M (0%) - - [2025-07-02T14:35:22.255Z] [0.472s][info][gc,heap ] GC(1) Reclaimed: - - 0M (0%) 73M (19%) - - [2025-07-02T14:35:22.255Z] [0.472s][info][gc ] GC(1) Garbage Collection (Warmup) 92M(24%)->70M(18%) [2025-07-02T14:35:22.255Z] [0.515s][info][gc,start ] GC(2) Garbage Collection (Warmup) [2025-07-02T14:35:22.255Z] [0.515s][info][gc,task ] GC(2) Using 1 workers [2025-07-02T14:35:22.255Z] [0.515s][info][gc,phases ] GC(2) Pause Mark Start 0.012ms [2025-07-02T14:35:22.255Z] [0.591s][info][gc,phases ] GC(2) Concurrent Mark 75.282ms [2025-07-02T14:35:22.255Z] [0.591s][info][gc,phases ] GC(2) Pause Mark End 0.008ms [2025-07-02T14:35:22.255Z] [0.591s][info][gc,phases ] GC(2) Concurrent Mark Free 0.000ms [2025-07-02T14:35:22.255Z] [0.592s][info][gc,phases ] GC(2) Concurrent Process Non-Strong References 0.962ms [2025-07-02T14:35:22.255Z] [0.592s][info][gc,phases ] GC(2) Concurrent Reset Relocation Set 0.005ms [2025-07-02T14:35:22.255Z] [0.594s][info][gc,phases ] GC(2) Concurrent Select Relocation Set 2.069ms [2025-07-02T14:35:22.255Z] [0.594s][info][gc,phases ] GC(2) Pause Relocate Start 0.009ms [2025-07-02T14:35:22.255Z] [0.605s][info][gc,phases ] GC(2) Concurrent Relocate 10.601ms [2025-07-02T14:35:22.255Z] [0.605s][info][gc,load ] GC(2) Load: 3.01/4.18/4.52 [2025-07-02T14:35:22.255Z] [0.605s][info][gc,mmu ] GC(2) MMU: 2ms/99.4%, 5ms/99.7%, 10ms/99.8%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.255Z] [0.605s][info][gc,marking ] GC(2) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.255Z] [0.605s][info][gc,marking ] GC(2) Mark Stack Usage: 32M [2025-07-02T14:35:22.255Z] [0.605s][info][gc,nmethod ] GC(2) NMethods: 807 registered, 0 unregistered [2025-07-02T14:35:22.255Z] [0.605s][info][gc,metaspace] GC(2) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.255Z] [0.605s][info][gc,ref ] GC(2) Soft: 40 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.605s][info][gc,ref ] GC(2) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.605s][info][gc,ref ] GC(2) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.605s][info][gc,ref ] GC(2) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.605s][info][gc,reloc ] GC(2) Small Pages: 54 / 108M, Empty: 0M, Relocated: 9M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.605s][info][gc,reloc ] GC(2) Medium Pages: 1 / 8M, Empty: 8M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.605s][info][gc,reloc ] GC(2) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.605s][info][gc,reloc ] GC(2) Forwarding Usage: 5M [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Min Capacity: 8M(2%) [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Max Capacity: 384M(100%) [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Capacity: 136M (35%) 166M (43%) 168M (44%) 168M (44%) 168M (44%) 136M (35%) [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Free: 268M (70%) 218M (57%) 224M (58%) 292M (76%) 292M (76%) 216M (56%) [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Used: 116M (30%) 166M (43%) 160M (42%) 92M (24%) 168M (44%) 92M (24%) [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Live: - 27M (7%) 27M (7%) 27M (7%) - - [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Allocated: - 50M (13%) 52M (14%) 63M (17%) - - [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Garbage: - 88M (23%) 80M (21%) 0M (0%) - - [2025-07-02T14:35:22.255Z] [0.605s][info][gc,heap ] GC(2) Reclaimed: - - 8M (2%) 87M (23%) - - [2025-07-02T14:35:22.255Z] [0.605s][info][gc ] GC(2) Garbage Collection (Warmup) 116M(30%)->92M(24%) [2025-07-02T14:35:22.255Z] [0.611s][info][gc,start ] GC(3) Garbage Collection (Allocation Rate) [2025-07-02T14:35:22.255Z] [0.611s][info][gc,task ] GC(3) Using 1 workers [2025-07-02T14:35:22.255Z] [0.612s][info][gc,phases ] GC(3) Pause Mark Start 0.009ms [2025-07-02T14:35:22.255Z] [0.695s][info][gc,phases ] GC(3) Concurrent Mark 83.127ms [2025-07-02T14:35:22.255Z] [0.695s][info][gc,phases ] GC(3) Pause Mark End 0.016ms [2025-07-02T14:35:22.255Z] [0.695s][info][gc,phases ] GC(3) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.255Z] [0.696s][info][gc,phases ] GC(3) Concurrent Process Non-Strong References 1.036ms [2025-07-02T14:35:22.255Z] [0.696s][info][gc,phases ] GC(3) Concurrent Reset Relocation Set 0.009ms [2025-07-02T14:35:22.255Z] [0.700s][info][gc,phases ] GC(3) Concurrent Select Relocation Set 4.275ms [2025-07-02T14:35:22.255Z] [0.700s][info][gc,phases ] GC(3) Pause Relocate Start 0.012ms [2025-07-02T14:35:22.255Z] [0.713s][info][gc,phases ] GC(3) Concurrent Relocate 12.844ms [2025-07-02T14:35:22.255Z] [0.713s][info][gc,load ] GC(3) Load: 3.01/4.18/4.52 [2025-07-02T14:35:22.255Z] [0.713s][info][gc,mmu ] GC(3) MMU: 2ms/99.2%, 5ms/99.7%, 10ms/99.7%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.255Z] [0.713s][info][gc,marking ] GC(3) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.255Z] [0.713s][info][gc,marking ] GC(3) Mark Stack Usage: 32M [2025-07-02T14:35:22.255Z] [0.713s][info][gc,nmethod ] GC(3) NMethods: 822 registered, 0 unregistered [2025-07-02T14:35:22.255Z] [0.713s][info][gc,metaspace] GC(3) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.255Z] [0.713s][info][gc,ref ] GC(3) Soft: 40 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.713s][info][gc,ref ] GC(3) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.713s][info][gc,ref ] GC(3) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.713s][info][gc,ref ] GC(3) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.255Z] [0.713s][info][gc,reloc ] GC(3) Small Pages: 49 / 98M, Empty: 0M, Relocated: 8M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.713s][info][gc,reloc ] GC(3) Medium Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.713s][info][gc,reloc ] GC(3) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.255Z] [0.713s][info][gc,reloc ] GC(3) Forwarding Usage: 4M [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Min Capacity: 8M(2%) [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Max Capacity: 384M(100%) [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Capacity: 168M (44%) 184M (48%) 184M (48%) 186M (48%) 186M (48%) 168M (44%) [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Free: 286M (74%) 200M (52%) 200M (52%) 250M (65%) 286M (74%) 198M (52%) [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Used: 98M (26%) 184M (48%) 184M (48%) 134M (35%) 186M (48%) 98M (26%) [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Live: - 35M (9%) 35M (9%) 35M (9%) - - [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Allocated: - 86M (22%) 86M (22%) 97M (25%) - - [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Garbage: - 62M (16%) 62M (16%) 0M (0%) - - [2025-07-02T14:35:22.255Z] [0.713s][info][gc,heap ] GC(3) Reclaimed: - - 0M (0%) 61M (16%) - - [2025-07-02T14:35:22.255Z] [0.713s][info][gc ] GC(3) Garbage Collection (Allocation Rate) 98M(26%)->134M(35%) [2025-07-02T14:35:22.255Z] [0.715s][info][gc,start ] GC(4) Garbage Collection (Allocation Rate) [2025-07-02T14:35:22.255Z] [0.715s][info][gc,task ] GC(4) Using 1 workers [2025-07-02T14:35:22.255Z] [0.715s][info][gc,phases ] GC(4) Pause Mark Start 0.006ms [2025-07-02T14:35:22.255Z] [0.814s][info][gc,phases ] GC(4) Concurrent Mark 98.661ms [2025-07-02T14:35:22.255Z] [0.814s][info][gc,phases ] GC(4) Pause Mark End 0.014ms [2025-07-02T14:35:22.255Z] [0.814s][info][gc,phases ] GC(4) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.255Z] [0.816s][info][gc,phases ] GC(4) Concurrent Process Non-Strong References 2.063ms [2025-07-02T14:35:22.255Z] [0.816s][info][gc,phases ] GC(4) Concurrent Reset Relocation Set 0.006ms [2025-07-02T14:35:22.256Z] [0.821s][info][gc,phases ] GC(4) Concurrent Select Relocation Set 4.309ms [2025-07-02T14:35:22.256Z] [0.821s][info][gc,phases ] GC(4) Pause Relocate Start 0.010ms [2025-07-02T14:35:22.256Z] [0.835s][info][gc,phases ] GC(4) Concurrent Relocate 13.650ms [2025-07-02T14:35:22.256Z] [0.835s][info][gc,load ] GC(4) Load: 3.01/4.18/4.52 [2025-07-02T14:35:22.256Z] [0.835s][info][gc,mmu ] GC(4) MMU: 2ms/99.2%, 5ms/99.7%, 10ms/99.7%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.256Z] [0.835s][info][gc,marking ] GC(4) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.256Z] [0.835s][info][gc,marking ] GC(4) Mark Stack Usage: 32M [2025-07-02T14:35:22.256Z] [0.835s][info][gc,nmethod ] GC(4) NMethods: 840 registered, 0 unregistered [2025-07-02T14:35:22.256Z] [0.835s][info][gc,metaspace] GC(4) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.256Z] [0.835s][info][gc,ref ] GC(4) Soft: 40 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [0.835s][info][gc,ref ] GC(4) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [0.835s][info][gc,ref ] GC(4) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [0.835s][info][gc,ref ] GC(4) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [0.835s][info][gc,reloc ] GC(4) Small Pages: 64 / 128M, Empty: 0M, Relocated: 10M, In-Place: 0 [2025-07-02T14:35:22.256Z] [0.835s][info][gc,reloc ] GC(4) Medium Pages: 1 / 8M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.256Z] [0.835s][info][gc,reloc ] GC(4) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.256Z] [0.835s][info][gc,reloc ] GC(4) Forwarding Usage: 5M [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Min Capacity: 8M(2%) [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Max Capacity: 384M(100%) [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Capacity: 186M (48%) 224M (58%) 230M (60%) 232M (60%) 232M (60%) 186M (48%) [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Free: 248M (65%) 160M (42%) 154M (40%) 224M (58%) 248M (65%) 152M (40%) [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Used: 136M (35%) 224M (58%) 230M (60%) 160M (42%) 232M (60%) 136M (35%) [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Live: - 45M (12%) 45M (12%) 45M (12%) - - [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Allocated: - 88M (23%) 94M (24%) 101M (27%) - - [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Garbage: - 90M (23%) 90M (23%) 12M (3%) - - [2025-07-02T14:35:22.256Z] [0.835s][info][gc,heap ] GC(4) Reclaimed: - - 0M (0%) 77M (20%) - - [2025-07-02T14:35:22.256Z] [0.835s][info][gc ] GC(4) Garbage Collection (Allocation Rate) 136M(35%)->160M(42%) [2025-07-02T14:35:22.256Z] [0.905s][info][gc,start ] GC(5) Garbage Collection (Allocation Rate) [2025-07-02T14:35:22.256Z] [0.905s][info][gc,task ] GC(5) Using 1 workers [2025-07-02T14:35:22.256Z] [0.906s][info][gc,phases ] GC(5) Pause Mark Start 0.010ms [2025-07-02T14:35:22.256Z] [1.064s][info][gc,phases ] GC(5) Concurrent Mark 157.865ms [2025-07-02T14:35:22.256Z] [1.064s][info][gc,phases ] GC(5) Pause Mark End 0.011ms [2025-07-02T14:35:22.256Z] [1.064s][info][gc,phases ] GC(5) Concurrent Mark Free 0.000ms [2025-07-02T14:35:22.256Z] [1.065s][info][gc,phases ] GC(5) Concurrent Process Non-Strong References 1.024ms [2025-07-02T14:35:22.256Z] [1.065s][info][gc,phases ] GC(5) Concurrent Reset Relocation Set 0.004ms [2025-07-02T14:35:22.256Z] [1.069s][info][gc,phases ] GC(5) Concurrent Select Relocation Set 4.022ms [2025-07-02T14:35:22.256Z] [1.069s][info][gc,phases ] GC(5) Pause Relocate Start 0.011ms [2025-07-02T14:35:22.256Z] [1.070s][info][gc ] Allocation Stall (MainThread) 44.300ms [2025-07-02T14:35:22.256Z] [1.099s][info][gc,phases ] GC(5) Concurrent Relocate 29.742ms [2025-07-02T14:35:22.256Z] [1.099s][info][gc,load ] GC(5) Load: 3.01/4.18/4.52 [2025-07-02T14:35:22.256Z] [1.099s][info][gc,mmu ] GC(5) MMU: 2ms/99.2%, 5ms/99.7%, 10ms/99.7%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.256Z] [1.099s][info][gc,marking ] GC(5) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.256Z] [1.099s][info][gc,marking ] GC(5) Mark Stack Usage: 32M [2025-07-02T14:35:22.256Z] [1.099s][info][gc,nmethod ] GC(5) NMethods: 852 registered, 0 unregistered [2025-07-02T14:35:22.256Z] [1.099s][info][gc,metaspace] GC(5) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.256Z] [1.099s][info][gc,ref ] GC(5) Soft: 40 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.099s][info][gc,ref ] GC(5) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.099s][info][gc,ref ] GC(5) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.099s][info][gc,ref ] GC(5) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.099s][info][gc,reloc ] GC(5) Small Pages: 122 / 244M, Empty: 0M, Relocated: 22M, In-Place: 0 [2025-07-02T14:35:22.256Z] [1.099s][info][gc,reloc ] GC(5) Medium Pages: 2 / 16M, Empty: 0M, Relocated: 0M, In-Place: 1 [2025-07-02T14:35:22.256Z] [1.099s][info][gc,reloc ] GC(5) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.256Z] [1.099s][info][gc,reloc ] GC(5) Forwarding Usage: 11M [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Min Capacity: 8M(2%) [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Max Capacity: 384M(100%) [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Capacity: 260M (68%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 260M (68%) [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Free: 124M (32%) 0M (0%) 0M (0%) 154M (40%) 156M (41%) 0M (0%) [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Used: 260M (68%) 384M (100%) 384M (100%) 230M (60%) 384M (100%) 228M (59%) [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Live: - 67M (18%) 67M (18%) 67M (18%) - - [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Allocated: - 124M (32%) 124M (32%) 151M (40%) - - [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Garbage: - 192M (50%) 192M (50%) 10M (3%) - - [2025-07-02T14:35:22.256Z] [1.099s][info][gc,heap ] GC(5) Reclaimed: - - 0M (0%) 181M (47%) - - [2025-07-02T14:35:22.256Z] [1.099s][info][gc ] GC(5) Garbage Collection (Allocation Rate) 260M(68%)->230M(60%) [2025-07-02T14:35:22.256Z] [1.108s][info][gc,start ] GC(6) Garbage Collection (Allocation Stall) [2025-07-02T14:35:22.256Z] [1.108s][info][gc,ref ] GC(6) Clearing All SoftReferences [2025-07-02T14:35:22.256Z] [1.108s][info][gc,task ] GC(6) Using 1 workers [2025-07-02T14:35:22.256Z] [1.108s][info][gc,phases ] GC(6) Pause Mark Start 0.010ms [2025-07-02T14:35:22.256Z] [1.296s][info][gc,phases ] GC(6) Concurrent Mark 187.938ms [2025-07-02T14:35:22.256Z] [1.296s][info][gc,phases ] GC(6) Pause Mark End 0.012ms [2025-07-02T14:35:22.256Z] [1.296s][info][gc,phases ] GC(6) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.256Z] [1.297s][info][gc,phases ] GC(6) Concurrent Process Non-Strong References 1.078ms [2025-07-02T14:35:22.256Z] [1.297s][info][gc,phases ] GC(6) Concurrent Reset Relocation Set 0.006ms [2025-07-02T14:35:22.256Z] [1.301s][info][gc,phases ] GC(6) Concurrent Select Relocation Set 3.631ms [2025-07-02T14:35:22.256Z] [1.301s][info][gc,phases ] GC(6) Pause Relocate Start 0.011ms [2025-07-02T14:35:22.256Z] [1.306s][info][gc ] Allocation Stall (MainThread) 75.855ms [2025-07-02T14:35:22.256Z] [1.356s][info][gc,phases ] GC(6) Concurrent Relocate 54.933ms [2025-07-02T14:35:22.256Z] [1.356s][info][gc,load ] GC(6) Load: 3.01/4.18/4.52 [2025-07-02T14:35:22.256Z] [1.356s][info][gc,mmu ] GC(6) MMU: 2ms/99.2%, 5ms/99.5%, 10ms/99.7%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.256Z] [1.356s][info][gc,marking ] GC(6) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.256Z] [1.356s][info][gc,marking ] GC(6) Mark Stack Usage: 32M [2025-07-02T14:35:22.256Z] [1.356s][info][gc,nmethod ] GC(6) NMethods: 894 registered, 0 unregistered [2025-07-02T14:35:22.256Z] [1.356s][info][gc,metaspace] GC(6) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.256Z] [1.356s][info][gc,ref ] GC(6) Soft: 40 encountered, 35 discovered, 16 enqueued [2025-07-02T14:35:22.256Z] [1.356s][info][gc,ref ] GC(6) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.356s][info][gc,ref ] GC(6) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.356s][info][gc,ref ] GC(6) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.356s][info][gc,reloc ] GC(6) Small Pages: 116 / 232M, Empty: 0M, Relocated: 18M, In-Place: 0 [2025-07-02T14:35:22.256Z] [1.356s][info][gc,reloc ] GC(6) Medium Pages: 2 / 16M, Empty: 0M, Relocated: 0M, In-Place: 1 [2025-07-02T14:35:22.256Z] [1.356s][info][gc,reloc ] GC(6) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.256Z] [1.356s][info][gc,reloc ] GC(6) Forwarding Usage: 9M [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Min Capacity: 8M(2%) [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Max Capacity: 384M(100%) [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Free: 136M (35%) 0M (0%) 0M (0%) 82M (21%) 136M (35%) 0M (0%) [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Used: 248M (65%) 384M (100%) 384M (100%) 302M (79%) 384M (100%) 248M (65%) [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Live: - 86M (22%) 86M (22%) 86M (22%) - - [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Allocated: - 136M (35%) 136M (35%) 205M (54%) - - [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Garbage: - 161M (42%) 161M (42%) 9M (3%) - - [2025-07-02T14:35:22.256Z] [1.356s][info][gc,heap ] GC(6) Reclaimed: - - 0M (0%) 151M (40%) - - [2025-07-02T14:35:22.256Z] [1.356s][info][gc ] GC(6) Garbage Collection (Allocation Stall) 248M(65%)->302M(79%) [2025-07-02T14:35:22.256Z] [1.404s][info][gc,start ] GC(7) Garbage Collection (Allocation Stall) [2025-07-02T14:35:22.256Z] [1.404s][info][gc,ref ] GC(7) Clearing All SoftReferences [2025-07-02T14:35:22.256Z] [1.404s][info][gc,task ] GC(7) Using 1 workers [2025-07-02T14:35:22.256Z] [1.404s][info][gc,phases ] GC(7) Pause Mark Start 0.005ms [2025-07-02T14:35:22.256Z] [1.601s][info][gc,phases ] GC(7) Concurrent Mark 197.446ms [2025-07-02T14:35:22.256Z] [1.601s][info][gc,phases ] GC(7) Pause Mark End 0.010ms [2025-07-02T14:35:22.256Z] [1.601s][info][gc,phases ] GC(7) Concurrent Mark Free 0.000ms [2025-07-02T14:35:22.256Z] [1.602s][info][gc,phases ] GC(7) Concurrent Process Non-Strong References 1.150ms [2025-07-02T14:35:22.256Z] [1.602s][info][gc,phases ] GC(7) Concurrent Reset Relocation Set 0.005ms [2025-07-02T14:35:22.256Z] [1.605s][info][gc,phases ] GC(7) Concurrent Select Relocation Set 2.216ms [2025-07-02T14:35:22.256Z] [1.605s][info][gc,phases ] GC(7) Pause Relocate Start 0.008ms [2025-07-02T14:35:22.256Z] [1.606s][info][gc ] Allocation Stall (MainThread) 202.380ms [2025-07-02T14:35:22.256Z] [1.637s][info][gc,phases ] GC(7) Concurrent Relocate 31.800ms [2025-07-02T14:35:22.256Z] [1.637s][info][gc,load ] GC(7) Load: 4.37/4.44/4.61 [2025-07-02T14:35:22.256Z] [1.637s][info][gc,mmu ] GC(7) MMU: 2ms/99.2%, 5ms/99.5%, 10ms/99.7%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.256Z] [1.637s][info][gc,marking ] GC(7) Mark: 1 stripe(s), 1 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.256Z] [1.637s][info][gc,marking ] GC(7) Mark Stack Usage: 32M [2025-07-02T14:35:22.256Z] [1.637s][info][gc,nmethod ] GC(7) NMethods: 942 registered, 0 unregistered [2025-07-02T14:35:22.256Z] [1.637s][info][gc,metaspace] GC(7) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.256Z] [1.637s][info][gc,ref ] GC(7) Soft: 24 encountered, 19 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.637s][info][gc,ref ] GC(7) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.637s][info][gc,ref ] GC(7) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.637s][info][gc,ref ] GC(7) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.256Z] [1.637s][info][gc,reloc ] GC(7) Small Pages: 184 / 368M, Empty: 0M, Relocated: 14M, In-Place: 0 [2025-07-02T14:35:22.256Z] [1.637s][info][gc,reloc ] GC(7) Medium Pages: 2 / 16M, Empty: 0M, Relocated: 1M, In-Place: 1 [2025-07-02T14:35:22.256Z] [1.637s][info][gc,reloc ] GC(7) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.256Z] [1.637s][info][gc,reloc ] GC(7) Forwarding Usage: 7M [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Min Capacity: 8M(2%) [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Max Capacity: 384M(100%) [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Free: 0M (0%) 0M (0%) 0M (0%) 230M (60%) 242M (63%) 0M (0%) [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Used: 384M (100%) 384M (100%) 384M (100%) 154M (40%) 384M (100%) 142M (37%) [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Live: - 100M (26%) 100M (26%) 100M (26%) - - [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Allocated: - 0M (0%) 0M (0%) 43M (11%) - - [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Garbage: - 283M (74%) 283M (74%) 9M (3%) - - [2025-07-02T14:35:22.256Z] [1.637s][info][gc,heap ] GC(7) Reclaimed: - - 0M (0%) 273M (71%) - - [2025-07-02T14:35:22.256Z] [1.637s][info][gc ] GC(7) Garbage Collection (Allocation Stall) 384M(100%)->154M(40%) [2025-07-02T14:35:22.256Z] [1.721s][info][gc,start ] GC(8) Garbage Collection (Allocation Rate) [2025-07-02T14:35:22.256Z] [1.721s][info][gc,task ] GC(8) Using 1 workers [2025-07-02T14:35:22.256Z] [1.725s][info][gc,phases ] GC(8) Pause Mark Start 0.009ms [2025-07-02T14:35:22.256Z] [1.777s][info][gc,phases ] GC(8) Concurrent Mark 49.744ms [2025-07-02T14:35:22.256Z] [1.777s][info][gc,phases ] GC(8) Pause Mark End 0.013ms [2025-07-02T14:35:22.256Z] [1.777s][info][gc,phases ] GC(8) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.256Z] [1.780s][info][gc,phases ] GC(8) Concurrent Process Non-Strong References 2.569ms [2025-07-02T14:35:22.257Z] [1.780s][info][gc,phases ] GC(8) Concurrent Reset Relocation Set 0.017ms [2025-07-02T14:35:22.257Z] [1.784s][info][gc ] Allocation Stall (MainThread) 22.348ms [2025-07-02T14:35:22.257Z] [1.787s][info][gc,phases ] GC(8) Concurrent Select Relocation Set 6.767ms [2025-07-02T14:35:22.257Z] [1.787s][info][gc,phases ] GC(8) Pause Relocate Start 0.006ms [2025-07-02T14:35:22.257Z] [1.809s][info][gc,phases ] GC(8) Concurrent Relocate 22.165ms [2025-07-02T14:35:22.257Z] [1.809s][info][gc,load ] GC(8) Load: 4.37/4.44/4.61 [2025-07-02T14:35:22.257Z] [1.809s][info][gc,mmu ] GC(8) MMU: 2ms/99.2%, 5ms/99.5%, 10ms/99.7%, 20ms/99.9%, 50ms/99.9%, 100ms/100.0% [2025-07-02T14:35:22.257Z] [1.809s][info][gc,marking ] GC(8) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.257Z] [1.809s][info][gc,marking ] GC(8) Mark Stack Usage: 32M [2025-07-02T14:35:22.257Z] [1.809s][info][gc,nmethod ] GC(8) NMethods: 960 registered, 0 unregistered [2025-07-02T14:35:22.257Z] [1.809s][info][gc,metaspace] GC(8) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.257Z] [1.809s][info][gc,ref ] GC(8) Soft: 24 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.257Z] [1.809s][info][gc,ref ] GC(8) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.257Z] [1.809s][info][gc,ref ] GC(8) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.257Z] [1.809s][info][gc,ref ] GC(8) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.257Z] [1.809s][info][gc,reloc ] GC(8) Small Pages: 153 / 306M, Empty: 10M, Relocated: 30M, In-Place: 0 [2025-07-02T14:35:22.257Z] [1.809s][info][gc,reloc ] GC(8) Medium Pages: 2 / 16M, Empty: 8M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.257Z] [1.809s][info][gc,reloc ] GC(8) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.257Z] [1.809s][info][gc,reloc ] GC(8) Forwarding Usage: 14M [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Min Capacity: 8M(2%) [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Max Capacity: 384M(100%) [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Free: 62M (16%) 0M (0%) 12M (3%) 236M (61%) 236M (61%) 0M (0%) [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Used: 322M (84%) 384M (100%) 372M (97%) 148M (39%) 384M (100%) 148M (39%) [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Live: - 32M (8%) 32M (8%) 32M (8%) - - [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Allocated: - 62M (16%) 68M (18%) 107M (28%) - - [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Garbage: - 289M (75%) 271M (71%) 8M (2%) - - [2025-07-02T14:35:22.257Z] [1.809s][info][gc,heap ] GC(8) Reclaimed: - - 18M (5%) 281M (73%) - - [2025-07-02T14:35:22.257Z] [1.809s][info][gc ] GC(8) Garbage Collection (Allocation Rate) 322M(84%)->148M(39%) [2025-07-02T14:35:22.257Z] [1.816s][info][gc,start ] GC(9) Garbage Collection (Allocation Stall) [2025-07-02T14:35:22.257Z] [1.816s][info][gc,ref ] GC(9) Clearing All SoftReferences [2025-07-02T14:35:22.257Z] [1.816s][info][gc,task ] GC(9) Using 1 workers [2025-07-02T14:35:22.257Z] [1.816s][info][gc,phases ] GC(9) Pause Mark Start 0.006ms [2025-07-02T14:35:22.257Z] [1.939s] [2025-07-02T14:35:22.257Z] [2025-07-02T14:35:22.257Z] ... [2025-07-02T14:35:22.257Z] Output overflow: [2025-07-02T14:35:22.257Z] JT Harness has limited the test output to the text [2025-07-02T14:35:22.257Z] at the beginning and the end, so that you can see how the [2025-07-02T14:35:22.257Z] test began, and how it completed. [2025-07-02T14:35:22.257Z] [2025-07-02T14:35:22.257Z] If you need to see more of the output from the test, [2025-07-02T14:35:22.257Z] set the system property javatest.maxOutputSize to a higher [2025-07-02T14:35:22.257Z] value. The current value is 100000 [2025-07-02T14:35:22.257Z] ... [2025-07-02T14:35:22.257Z] [2025-07-02T14:35:22.257Z] current Mark Free 0.001 / 0.002 0.001 / 0.013 0.001 / 0.013 0.001 / 0.013 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Phase: Concurrent Process Non-Strong References 1.887 / 3.200 2.172 / 16.742 2.172 / 16.742 2.172 / 16.742 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Phase: Concurrent Relocate 23.195 / 50.997 24.769 / 121.273 24.769 / 121.273 24.769 / 121.273 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Phase: Concurrent Reset Relocation Set 0.015 / 0.042 0.014 / 0.042 0.014 / 0.042 0.014 / 0.042 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,task ] GC(404) Using 1 workers [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Phase: Concurrent Select Relocation Set 4.442 / 10.176 4.241 / 16.020 4.241 / 16.020 4.241 / 16.020 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Phase: Pause Mark End 0.069 / 2.767 0.084 / 3.843 0.084 / 3.843 0.084 / 3.843 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Phase: Pause Mark Start 0.023 / 0.784 0.020 / 2.857 0.020 / 2.857 0.020 / 2.857 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Phase: Pause Relocate Start 0.010 / 0.017 0.036 / 3.259 0.036 / 3.259 0.036 / 3.259 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Classes Purge 0.069 / 1.489 0.093 / 8.479 0.093 / 8.479 0.093 / 8.479 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Classes Unlink 1.687 / 2.558 1.807 / 6.138 1.807 / 6.138 1.807 / 6.138 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Mark 119.877 / 212.832 116.857 / 232.320 116.857 / 232.320 116.857 / 232.320 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Mark Try Flush 0.345 / 10.071 0.297 / 11.372 0.297 / 11.372 0.297 / 11.372 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Mark Try Terminate 0.123 / 10.030 0.104 / 11.374 0.104 / 11.374 0.104 / 11.374 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent References Enqueue 0.000 / 0.001 0.000 / 0.004 0.000 / 0.004 0.000 / 0.004 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent References Process 0.026 / 0.081 0.081 / 8.034 0.081 / 8.034 0.081 / 8.034 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Roots ClassLoaderDataGraph 0.047 / 0.074 0.044 / 0.289 0.044 / 0.289 0.044 / 0.289 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Roots CodeCache 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Roots JavaThreads 0.230 / 0.348 0.241 / 5.027 0.241 / 5.027 0.241 / 5.027 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Roots OopStorageSet 0.011 / 0.016 0.011 / 0.356 0.011 / 0.356 0.011 / 0.356 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Concurrent Weak Roots OopStorageSet 0.054 / 0.125 0.063 / 1.393 0.063 / 1.393 0.063 / 1.393 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] Subphase: Pause Mark Try Complete 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] System: Java Threads 12 / 12 12 / 12 12 / 12 12 / 12 threads [2025-07-02T14:35:22.257Z] [80.118s][info][gc,stats ] ========================================================================================================================================================= [2025-07-02T14:35:22.257Z] [80.118s][info][gc,phases ] GC(404) Pause Mark Start 0.007ms [2025-07-02T14:35:22.257Z] [80.294s][info][gc,phases ] GC(404) Concurrent Mark 176.653ms [2025-07-02T14:35:22.257Z] [80.294s][info][gc,phases ] GC(404) Pause Mark End 0.007ms [2025-07-02T14:35:22.257Z] [80.294s][info][gc,phases ] GC(404) Concurrent Mark Free 0.000ms [2025-07-02T14:35:22.257Z] [80.296s][info][gc,phases ] GC(404) Concurrent Process Non-Strong References 1.286ms [2025-07-02T14:35:22.257Z] [80.296s][info][gc,phases ] GC(404) Concurrent Reset Relocation Set 0.014ms [2025-07-02T14:35:22.257Z] [80.300s][info][gc,phases ] GC(404) Concurrent Select Relocation Set 4.378ms [2025-07-02T14:35:22.257Z] [80.300s][info][gc,phases ] GC(404) Pause Relocate Start 0.008ms [2025-07-02T14:35:22.257Z] [80.300s][info][gc ] Allocation Stall (MainThread) 159.674ms [2025-07-02T14:35:22.257Z] [80.334s][info][gc,phases ] GC(404) Concurrent Relocate 33.600ms [2025-07-02T14:35:22.257Z] [80.334s][info][gc,load ] GC(404) Load: 5.15/4.57/4.64 [2025-07-02T14:35:22.257Z] [80.334s][info][gc,mmu ] GC(404) MMU: 2ms/0.0%, 5ms/23.1%, 10ms/61.5%, 20ms/80.7%, 50ms/92.3%, 100ms/96.1% [2025-07-02T14:35:22.257Z] [80.334s][info][gc,marking ] GC(404) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.257Z] [80.334s][info][gc,marking ] GC(404) Mark Stack Usage: 32M [2025-07-02T14:35:22.257Z] [80.334s][info][gc,nmethod ] GC(404) NMethods: 995 registered, 0 unregistered [2025-07-02T14:35:22.257Z] [80.334s][info][gc,metaspace] GC(404) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.257Z] [80.334s][info][gc,ref ] GC(404) Soft: 24 encountered, 19 discovered, 0 enqueued [2025-07-02T14:35:22.257Z] [80.334s][info][gc,ref ] GC(404) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.257Z] [80.334s][info][gc,ref ] GC(404) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.257Z] [80.334s][info][gc,ref ] GC(404) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.257Z] [80.334s][info][gc,reloc ] GC(404) Small Pages: 164 / 328M, Empty: 0M, Relocated: 31M, In-Place: 0 [2025-07-02T14:35:22.257Z] [80.334s][info][gc,reloc ] GC(404) Medium Pages: 2 / 16M, Empty: 0M, Relocated: 0M, In-Place: 1 [2025-07-02T14:35:22.257Z] [80.334s][info][gc,reloc ] GC(404) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.257Z] [80.334s][info][gc,reloc ] GC(404) Forwarding Usage: 16M [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Min Capacity: 8M(2%) [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Max Capacity: 384M(100%) [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Free: 40M (10%) 0M (0%) 0M (0%) 196M (51%) 196M (51%) 0M (0%) [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Used: 344M (90%) 384M (100%) 384M (100%) 188M (49%) 384M (100%) 188M (49%) [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Live: - 79M (21%) 79M (21%) 79M (21%) - - [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Allocated: - 40M (10%) 40M (10%) 97M (26%) - - [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Garbage: - 264M (69%) 264M (69%) 10M (3%) - - [2025-07-02T14:35:22.257Z] [80.334s][info][gc,heap ] GC(404) Reclaimed: - - 0M (0%) 253M (66%) - - [2025-07-02T14:35:22.257Z] [80.334s][info][gc ] GC(404) Garbage Collection (Allocation Stall) 344M(90%)->188M(49%) [2025-07-02T14:35:22.257Z] [80.415s][info][gc,start ] GC(405) Garbage Collection (Allocation Stall) [2025-07-02T14:35:22.257Z] [80.415s][info][gc,ref ] GC(405) Clearing All SoftReferences [2025-07-02T14:35:22.257Z] [80.415s][info][gc,task ] GC(405) Using 1 workers [2025-07-02T14:35:22.257Z] [80.416s][info][gc,phases ] GC(405) Pause Mark Start 0.011ms [2025-07-02T14:35:22.257Z] [80.625s][info][gc,phases ] GC(405) Concurrent Mark 203.447ms [2025-07-02T14:35:22.257Z] [80.625s][info][gc,phases ] GC(405) Pause Mark End 0.016ms [2025-07-02T14:35:22.257Z] [80.625s][info][gc,phases ] GC(405) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.257Z] [80.627s][info][gc,phases ] GC(405) Concurrent Process Non-Strong References 1.339ms [2025-07-02T14:35:22.257Z] [80.627s][info][gc,phases ] GC(405) Concurrent Reset Relocation Set 0.015ms [2025-07-02T14:35:22.257Z] [80.629s][info][gc,phases ] GC(405) Concurrent Select Relocation Set 2.248ms [2025-07-02T14:35:22.257Z] [80.629s][info][gc,phases ] GC(405) Pause Relocate Start 0.008ms [2025-07-02T14:35:22.257Z] [80.633s][info][gc ] Allocation Stall (MainThread) 201.145ms [2025-07-02T14:35:22.257Z] [80.664s][info][gc,phases ] GC(405) Concurrent Relocate 34.629ms [2025-07-02T14:35:22.257Z] [80.664s][info][gc,load ] GC(405) Load: 5.15/4.57/4.64 [2025-07-02T14:35:22.258Z] [80.664s][info][gc,mmu ] GC(405) MMU: 2ms/0.0%, 5ms/23.1%, 10ms/61.5%, 20ms/80.7%, 50ms/92.3%, 100ms/96.1% [2025-07-02T14:35:22.258Z] [80.664s][info][gc,marking ] GC(405) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.258Z] [80.664s][info][gc,marking ] GC(405) Mark Stack Usage: 32M [2025-07-02T14:35:22.258Z] [80.664s][info][gc,nmethod ] GC(405) NMethods: 995 registered, 0 unregistered [2025-07-02T14:35:22.258Z] [80.664s][info][gc,metaspace] GC(405) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.258Z] [80.664s][info][gc,ref ] GC(405) Soft: 24 encountered, 19 discovered, 0 enqueued [2025-07-02T14:35:22.258Z] [80.664s][info][gc,ref ] GC(405) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.258Z] [80.664s][info][gc,ref ] GC(405) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.258Z] [80.664s][info][gc,ref ] GC(405) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.258Z] [80.664s][info][gc,reloc ] GC(405) Small Pages: 171 / 342M, Empty: 0M, Relocated: 22M, In-Place: 0 [2025-07-02T14:35:22.258Z] [80.664s][info][gc,reloc ] GC(405) Medium Pages: 2 / 16M, Empty: 0M, Relocated: 1M, In-Place: 1 [2025-07-02T14:35:22.258Z] [80.664s][info][gc,reloc ] GC(405) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.258Z] [80.664s][info][gc,reloc ] GC(405) Forwarding Usage: 11M [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Min Capacity: 8M(2%) [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Max Capacity: 384M(100%) [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Free: 26M (7%) 0M (0%) 0M (0%) 202M (53%) 210M (55%) 0M (0%) [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Used: 358M (93%) 384M (100%) 384M (100%) 182M (47%) 384M (100%) 174M (45%) [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Live: - 100M (26%) 100M (26%) 100M (26%) - - [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Allocated: - 26M (7%) 26M (7%) 71M (19%) - - [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Garbage: - 257M (67%) 257M (67%) 9M (3%) - - [2025-07-02T14:35:22.258Z] [80.664s][info][gc,heap ] GC(405) Reclaimed: - - 0M (0%) 247M (65%) - - [2025-07-02T14:35:22.258Z] [80.664s][info][gc ] GC(405) Garbage Collection (Allocation Stall) 358M(93%)->182M(47%) [2025-07-02T14:35:22.258Z] [80.715s][info][gc,start ] GC(406) Garbage Collection (Allocation Stall) [2025-07-02T14:35:22.258Z] [80.715s][info][gc,ref ] GC(406) Clearing All SoftReferences [2025-07-02T14:35:22.258Z] [80.715s][info][gc,task ] GC(406) Using 1 workers [2025-07-02T14:35:22.258Z] [80.715s][info][gc,phases ] GC(406) Pause Mark Start 0.008ms [2025-07-02T14:35:22.258Z] [80.738s][info][gc,phases ] GC(406) Concurrent Mark 23.341ms [2025-07-02T14:35:22.258Z] [80.738s][info][gc,phases ] GC(406) Pause Mark End 0.013ms [2025-07-02T14:35:22.258Z] [80.738s][info][gc,phases ] GC(406) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.258Z] [80.740s][info][gc,phases ] GC(406) Concurrent Process Non-Strong References 1.521ms [2025-07-02T14:35:22.258Z] [80.740s][info][gc,phases ] GC(406) Concurrent Reset Relocation Set 0.035ms [2025-07-02T14:35:22.258Z] [80.746s][info][gc,phases ] GC(406) Concurrent Select Relocation Set 6.130ms [2025-07-02T14:35:22.258Z] [80.746s][info][gc,phases ] GC(406) Pause Relocate Start 0.013ms [2025-07-02T14:35:22.258Z] [80.756s][info][gc,phases ] GC(406) Concurrent Relocate 10.043ms [2025-07-02T14:35:22.258Z] [80.756s][info][gc,load ] GC(406) Load: 5.15/4.57/4.64 [2025-07-02T14:35:22.258Z] [80.756s][info][gc,mmu ] GC(406) MMU: 2ms/0.0%, 5ms/23.1%, 10ms/61.5%, 20ms/80.7%, 50ms/92.3%, 100ms/96.1% [2025-07-02T14:35:22.258Z] [80.756s][info][gc,marking ] GC(406) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.258Z] [80.756s][info][gc,marking ] GC(406) Mark Stack Usage: 32M [2025-07-02T14:35:22.258Z] [80.756s][info][gc,nmethod ] GC(406) NMethods: 995 registered, 0 unregistered [2025-07-02T14:35:22.258Z] [80.756s][info][gc,metaspace] GC(406) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.258Z] [80.756s][info][gc,ref ] GC(406) Soft: 24 encountered, 19 discovered, 0 enqueued [2025-07-02T14:35:22.258Z] [80.756s][info][gc,ref ] GC(406) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.258Z] [80.756s][info][gc,ref ] GC(406) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [80.756s][info][gc,ref ] GC(406) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [80.756s][info][gc,reloc ] GC(406) Small Pages: 134 / 268M, Empty: 190M, Relocated: 7M, In-Place: 0 [2025-07-02T14:35:22.259Z] [80.756s][info][gc,reloc ] GC(406) Medium Pages: 1 / 8M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.259Z] [80.756s][info][gc,reloc ] GC(406) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.259Z] [80.756s][info][gc,reloc ] GC(406) Forwarding Usage: 3M [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Min Capacity: 8M(2%) [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Max Capacity: 384M(100%) [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Free: 108M (28%) 64M (17%) 246M (64%) 294M (77%) 296M (77%) 62M (16%) [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Used: 276M (72%) 320M (83%) 138M (36%) 90M (23%) 322M (84%) 88M (23%) [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Live: - 19M (5%) 19M (5%) 19M (5%) - - [2025-07-02T14:35:22.259Z] [80.756s][info][gc,heap ] GC(406) Allocated: - 44M (11%) 52M (14%) 61M (16%) - - [2025-07-02T14:35:22.259Z] [80.757s][info][gc,heap ] GC(406) Garbage: - 256M (67%) 66M (17%) 8M (2%) - - [2025-07-02T14:35:22.259Z] [80.757s][info][gc,heap ] GC(406) Reclaimed: - - 190M (49%) 247M (65%) - - [2025-07-02T14:35:22.259Z] [80.757s][info][gc ] GC(406) Garbage Collection (Allocation Stall) 276M(72%)->90M(23%) [2025-07-02T14:35:22.259Z] [80.806s][info][gc,start ] GC(407) Garbage Collection (Allocation Rate) [2025-07-02T14:35:22.259Z] [80.806s][info][gc,task ] GC(407) Using 1 workers [2025-07-02T14:35:22.259Z] [80.806s][info][gc,phases ] GC(407) Pause Mark Start 0.009ms [2025-07-02T14:35:22.259Z] [80.871s][info][gc,phases ] GC(407) Concurrent Mark 64.511ms [2025-07-02T14:35:22.259Z] [80.871s][info][gc,phases ] GC(407) Pause Mark End 0.009ms [2025-07-02T14:35:22.259Z] [80.871s][info][gc,phases ] GC(407) Concurrent Mark Free 0.000ms [2025-07-02T14:35:22.259Z] [80.872s][info][gc,phases ] GC(407) Concurrent Process Non-Strong References 1.447ms [2025-07-02T14:35:22.259Z] [80.872s][info][gc,phases ] GC(407) Concurrent Reset Relocation Set 0.008ms [2025-07-02T14:35:22.259Z] [80.878s][info][gc,phases ] GC(407) Concurrent Select Relocation Set 5.938ms [2025-07-02T14:35:22.259Z] [80.878s][info][gc,phases ] GC(407) Pause Relocate Start 0.009ms [2025-07-02T14:35:22.259Z] [80.911s][info][gc,phases ] GC(407) Concurrent Relocate 32.384ms [2025-07-02T14:35:22.259Z] [80.911s][info][gc,load ] GC(407) Load: 5.15/4.57/4.64 [2025-07-02T14:35:22.259Z] [80.911s][info][gc,mmu ] GC(407) MMU: 2ms/0.0%, 5ms/23.1%, 10ms/61.5%, 20ms/80.7%, 50ms/92.3%, 100ms/96.1% [2025-07-02T14:35:22.259Z] [80.911s][info][gc,marking ] GC(407) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.259Z] [80.911s][info][gc,marking ] GC(407) Mark Stack Usage: 32M [2025-07-02T14:35:22.259Z] [80.911s][info][gc,nmethod ] GC(407) NMethods: 995 registered, 0 unregistered [2025-07-02T14:35:22.259Z] [80.911s][info][gc,metaspace] GC(407) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.259Z] [80.911s][info][gc,ref ] GC(407) Soft: 24 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [80.911s][info][gc,ref ] GC(407) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [80.911s][info][gc,ref ] GC(407) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [80.911s][info][gc,ref ] GC(407) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [80.911s][info][gc,reloc ] GC(407) Small Pages: 85 / 170M, Empty: 0M, Relocated: 16M, In-Place: 0 [2025-07-02T14:35:22.259Z] [80.911s][info][gc,reloc ] GC(407) Medium Pages: 2 / 16M, Empty: 8M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.259Z] [80.911s][info][gc,reloc ] GC(407) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.259Z] [80.911s][info][gc,reloc ] GC(407) Forwarding Usage: 8M [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Min Capacity: 8M(2%) [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Max Capacity: 384M(100%) [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Free: 198M (52%) 80M (21%) 70M (18%) 158M (41%) 198M (52%) 66M (17%) [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Used: 186M (48%) 304M (79%) 314M (82%) 226M (59%) 318M (83%) 186M (48%) [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Live: - 35M (9%) 35M (9%) 35M (9%) - - [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Allocated: - 118M (31%) 136M (35%) 179M (47%) - - [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Garbage: - 150M (39%) 142M (37%) 10M (3%) - - [2025-07-02T14:35:22.259Z] [80.911s][info][gc,heap ] GC(407) Reclaimed: - - 8M (2%) 139M (36%) - - [2025-07-02T14:35:22.259Z] [80.911s][info][gc ] GC(407) Garbage Collection (Allocation Rate) 186M(48%)->226M(59%) [2025-07-02T14:35:22.259Z] [80.916s][info][gc,start ] GC(408) Garbage Collection (Allocation Rate) [2025-07-02T14:35:22.259Z] [80.916s][info][gc,task ] GC(408) Using 1 workers [2025-07-02T14:35:22.259Z] [80.916s][info][gc,phases ] GC(408) Pause Mark Start 0.011ms [2025-07-02T14:35:22.259Z] [81.034s][info][gc,phases ] GC(408) Concurrent Mark 118.022ms [2025-07-02T14:35:22.259Z] [81.034s][info][gc,phases ] GC(408) Pause Mark End 0.011ms [2025-07-02T14:35:22.259Z] [81.034s][info][gc,phases ] GC(408) Concurrent Mark Free 0.000ms [2025-07-02T14:35:22.259Z] [81.036s][info][gc,phases ] GC(408) Concurrent Process Non-Strong References 1.312ms [2025-07-02T14:35:22.259Z] [81.036s][info][gc,phases ] GC(408) Concurrent Reset Relocation Set 0.013ms [2025-07-02T14:35:22.259Z] [81.038s][info][gc,phases ] GC(408) Concurrent Select Relocation Set 2.402ms [2025-07-02T14:35:22.259Z] [81.038s][info][gc,phases ] GC(408) Pause Relocate Start 0.024ms [2025-07-02T14:35:22.259Z] [81.038s][info][gc ] Allocation Stall (MainThread) 33.168ms [2025-07-02T14:35:22.259Z] [81.063s][info][gc,phases ] GC(408) Concurrent Relocate 24.923ms [2025-07-02T14:35:22.259Z] [81.063s][info][gc,load ] GC(408) Load: 5.15/4.57/4.64 [2025-07-02T14:35:22.259Z] [81.063s][info][gc,mmu ] GC(408) MMU: 2ms/0.0%, 5ms/23.1%, 10ms/61.5%, 20ms/80.7%, 50ms/92.3%, 100ms/96.1% [2025-07-02T14:35:22.259Z] [81.063s][info][gc,marking ] GC(408) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.259Z] [81.063s][info][gc,marking ] GC(408) Mark Stack Usage: 32M [2025-07-02T14:35:22.259Z] [81.063s][info][gc,nmethod ] GC(408) NMethods: 995 registered, 0 unregistered [2025-07-02T14:35:22.259Z] [81.063s][info][gc,metaspace] GC(408) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.259Z] [81.063s][info][gc,ref ] GC(408) Soft: 24 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [81.063s][info][gc,ref ] GC(408) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [81.063s][info][gc,ref ] GC(408) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [81.063s][info][gc,ref ] GC(408) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [81.063s][info][gc,reloc ] GC(408) Small Pages: 109 / 218M, Empty: 0M, Relocated: 21M, In-Place: 0 [2025-07-02T14:35:22.259Z] [81.063s][info][gc,reloc ] GC(408) Medium Pages: 2 / 16M, Empty: 0M, Relocated: 0M, In-Place: 1 [2025-07-02T14:35:22.259Z] [81.063s][info][gc,reloc ] GC(408) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.259Z] [81.063s][info][gc,reloc ] GC(408) Forwarding Usage: 11M [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Min Capacity: 8M(2%) [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Max Capacity: 384M(100%) [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Free: 150M (39%) 0M (0%) 0M (0%) 120M (31%) 150M (39%) 0M (0%) [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Used: 234M (61%) 384M (100%) 384M (100%) 264M (69%) 384M (100%) 234M (61%) [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Live: - 56M (15%) 56M (15%) 56M (15%) - - [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Allocated: - 150M (39%) 150M (39%) 197M (52%) - - [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Garbage: - 177M (46%) 177M (46%) 9M (2%) - - [2025-07-02T14:35:22.259Z] [81.063s][info][gc,heap ] GC(408) Reclaimed: - - 0M (0%) 167M (44%) - - [2025-07-02T14:35:22.259Z] [81.063s][info][gc ] GC(408) Garbage Collection (Allocation Rate) 234M(61%)->264M(69%) [2025-07-02T14:35:22.259Z] [81.108s][info][gc,start ] GC(409) Garbage Collection (Allocation Stall) [2025-07-02T14:35:22.259Z] [81.108s][info][gc,ref ] GC(409) Clearing All SoftReferences [2025-07-02T14:35:22.259Z] [81.108s][info][gc,task ] GC(409) Using 1 workers [2025-07-02T14:35:22.259Z] [81.109s][info][gc,phases ] GC(409) Pause Mark Start 0.008ms [2025-07-02T14:35:22.259Z] [81.267s][info][gc,phases ] GC(409) Concurrent Mark 158.227ms [2025-07-02T14:35:22.259Z] [81.267s][info][gc,phases ] GC(409) Pause Mark End 0.015ms [2025-07-02T14:35:22.259Z] [81.267s][info][gc,phases ] GC(409) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.259Z] [81.270s][info][gc,phases ] GC(409) Concurrent Process Non-Strong References 2.608ms [2025-07-02T14:35:22.259Z] [81.270s][info][gc,phases ] GC(409) Concurrent Reset Relocation Set 0.023ms [2025-07-02T14:35:22.259Z] [81.275s][info][gc,phases ] GC(409) Concurrent Select Relocation Set 5.410ms [2025-07-02T14:35:22.259Z] [81.275s][info][gc,phases ] GC(409) Pause Relocate Start 0.014ms [2025-07-02T14:35:22.259Z] [81.304s][info][gc ] Relocation Stall (MainThread) 28.327ms [2025-07-02T14:35:22.259Z] [81.304s][info][gc ] Allocation Stall (MainThread) 173.988ms [2025-07-02T14:35:22.259Z] [81.305s][info][gc,phases ] GC(409) Concurrent Relocate 30.183ms [2025-07-02T14:35:22.259Z] [81.305s][info][gc,load ] GC(409) Load: 5.15/4.57/4.64 [2025-07-02T14:35:22.259Z] [81.306s][info][gc,mmu ] GC(409) MMU: 2ms/0.0%, 5ms/23.1%, 10ms/61.5%, 20ms/80.7%, 50ms/92.3%, 100ms/96.1% [2025-07-02T14:35:22.259Z] [81.306s][info][gc,marking ] GC(409) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.259Z] [81.306s][info][gc,marking ] GC(409) Mark Stack Usage: 32M [2025-07-02T14:35:22.259Z] [81.306s][info][gc,nmethod ] GC(409) NMethods: 995 registered, 0 unregistered [2025-07-02T14:35:22.259Z] [81.306s][info][gc,metaspace] GC(409) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.259Z] [81.306s][info][gc,ref ] GC(409) Soft: 24 encountered, 19 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [81.306s][info][gc,ref ] GC(409) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [81.306s][info][gc,ref ] GC(409) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [81.306s][info][gc,ref ] GC(409) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.259Z] [81.306s][info][gc,reloc ] GC(409) Small Pages: 171 / 342M, Empty: 0M, Relocated: 31M, In-Place: 1 [2025-07-02T14:35:22.259Z] [81.306s][info][gc,reloc ] GC(409) Medium Pages: 1 / 8M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.259Z] [81.306s][info][gc,reloc ] GC(409) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.259Z] [81.306s][info][gc,reloc ] GC(409) Forwarding Usage: 16M [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Min Capacity: 8M(2%) [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Max Capacity: 384M(100%) [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Free: 34M (9%) 0M (0%) 0M (0%) 248M (65%) 250M (65%) 0M (0%) [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Used: 350M (91%) 384M (100%) 384M (100%) 136M (35%) 384M (100%) 134M (35%) [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Live: - 88M (23%) 88M (23%) 88M (23%) - - [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Allocated: - 34M (9%) 34M (9%) 38M (10%) - - [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Garbage: - 261M (68%) 261M (68%) 9M (3%) - - [2025-07-02T14:35:22.259Z] [81.306s][info][gc,heap ] GC(409) Reclaimed: - - 0M (0%) 252M (66%) - - [2025-07-02T14:35:22.259Z] [81.306s][info][gc ] GC(409) Garbage Collection (Allocation Stall) 350M(91%)->136M(35%) [2025-07-02T14:35:22.259Z] [81.324s][info][gc,start ] GC(410) Garbage Collection (Allocation Stall) [2025-07-02T14:35:22.259Z] [81.324s][info][gc,ref ] GC(410) Clearing All SoftReferences [2025-07-02T14:35:22.259Z] [81.324s][info][gc,task ] GC(410) Using 1 workers [2025-07-02T14:35:22.259Z] [81.326s][info][gc,phases ] GC(410) Pause Mark Start 0.009ms [2025-07-02T14:35:22.259Z] [81.572s][info][gc,phases ] GC(410) Concurrent Mark 246.353ms [2025-07-02T14:35:22.259Z] [81.574s][info][gc,phases ] GC(410) Pause Mark End 1.377ms [2025-07-02T14:35:22.259Z] [81.574s][info][gc,phases ] GC(410) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.259Z] [81.576s][info][gc,phases ] GC(410) Concurrent Process Non-Strong References 2.618ms [2025-07-02T14:35:22.259Z] [81.577s][info][gc,phases ] GC(410) Concurrent Reset Relocation Set 0.034ms [2025-07-02T14:35:22.259Z] [81.583s][info][gc,phases ] GC(410) Concurrent Select Relocation Set 6.063ms [2025-07-02T14:35:22.260Z] [81.583s][info][gc,phases ] GC(410) Pause Relocate Start 0.012ms [2025-07-02T14:35:22.260Z] [81.583s][info][gc ] Allocation Stall (MainThread) 116.990ms [2025-07-02T14:35:22.260Z] [81.591s][info][gc,phases ] GC(410) Concurrent Relocate 8.148ms [2025-07-02T14:35:22.260Z] [81.591s][info][gc,load ] GC(410) Load: 5.15/4.57/4.64 [2025-07-02T14:35:22.260Z] [81.591s][info][gc,mmu ] GC(410) MMU: 2ms/0.0%, 5ms/23.1%, 10ms/61.5%, 20ms/80.7%, 50ms/92.3%, 100ms/96.1% [2025-07-02T14:35:22.260Z] [81.591s][info][gc,marking ] GC(410) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.260Z] [81.591s][info][gc,marking ] GC(410) Mark Stack Usage: 32M [2025-07-02T14:35:22.260Z] [81.591s][info][gc,nmethod ] GC(410) NMethods: 995 registered, 0 unregistered [2025-07-02T14:35:22.260Z] [81.591s][info][gc,metaspace] GC(410) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.260Z] [81.591s][info][gc,ref ] GC(410) Soft: 24 encountered, 19 discovered, 0 enqueued [2025-07-02T14:35:22.260Z] [81.591s][info][gc,ref ] GC(410) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.260Z] [81.591s][info][gc,ref ] GC(410) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.260Z] [81.591s][info][gc,ref ] GC(410) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.260Z] [81.591s][info][gc,reloc ] GC(410) Small Pages: 83 / 166M, Empty: 0M, Relocated: 7M, In-Place: 1 [2025-07-02T14:35:22.260Z] [81.591s][info][gc,reloc ] GC(410) Medium Pages: 1 / 8M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.260Z] [81.591s][info][gc,reloc ] GC(410) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.260Z] [81.591s][info][gc,reloc ] GC(410) Forwarding Usage: 4M [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Min Capacity: 8M(2%) [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Max Capacity: 384M(100%) [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Free: 210M (55%) 0M (0%) 0M (0%) 58M (15%) 210M (55%) 0M (0%) [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Used: 174M (45%) 384M (100%) 384M (100%) 326M (85%) 384M (100%) 174M (45%) [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Live: - 96M (25%) 96M (25%) 96M (25%) - - [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Allocated: - 210M (55%) 210M (55%) 220M (57%) - - [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Garbage: - 77M (20%) 77M (20%) 9M (3%) - - [2025-07-02T14:35:22.260Z] [81.591s][info][gc,heap ] GC(410) Reclaimed: - - 0M (0%) 68M (18%) - - [2025-07-02T14:35:22.260Z] [81.591s][info][gc ] GC(410) Garbage Collection (Allocation Stall) 174M(45%)->326M(85%) [2025-07-02T14:35:22.260Z] [81.606s][info][gc,start ] GC(411) Garbage Collection (Allocation Stall) [2025-07-02T14:35:22.260Z] [81.606s][info][gc,ref ] GC(411) Clearing All SoftReferences [2025-07-02T14:35:22.260Z] [81.606s][info][gc,task ] GC(411) Using 1 workers [2025-07-02T14:35:22.260Z] [81.608s][info][gc,phases ] GC(411) Pause Mark Start 0.008ms [2025-07-02T14:35:22.260Z] [81.628s][info][gc,phases ] GC(411) Concurrent Mark 20.170ms [2025-07-02T14:35:22.260Z] [81.628s][info][gc,phases ] GC(411) Pause Mark End 0.015ms [2025-07-02T14:35:22.260Z] [81.628s][info][gc,phases ] GC(411) Concurrent Mark Free 0.001ms [2025-07-02T14:35:22.260Z] [81.629s][info][gc,phases ] GC(411) Concurrent Process Non-Strong References 1.324ms [2025-07-02T14:35:22.260Z] [81.629s][info][gc,phases ] GC(411) Concurrent Reset Relocation Set 0.005ms [2025-07-02T14:35:22.260Z] [81.631s][info][gc,phases ] GC(411) Concurrent Select Relocation Set 2.035ms [2025-07-02T14:35:22.260Z] [81.631s][info][gc,phases ] GC(411) Pause Relocate Start 0.009ms [2025-07-02T14:35:22.260Z] [81.631s][info][gc ] Allocation Stall (MainThread) 4.859ms [2025-07-02T14:35:22.260Z] [81.635s][info][gc,phases ] GC(411) Concurrent Relocate 3.617ms [2025-07-02T14:35:22.260Z] [81.635s][info][gc,load ] GC(411) Load: 5.15/4.57/4.64 [2025-07-02T14:35:22.260Z] [81.635s][info][gc,mmu ] GC(411) MMU: 2ms/0.0%, 5ms/23.1%, 10ms/61.5%, 20ms/80.7%, 50ms/92.3%, 100ms/96.1% [2025-07-02T14:35:22.260Z] [81.635s][info][gc,marking ] GC(411) Mark: 1 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2025-07-02T14:35:22.260Z] [81.635s][info][gc,marking ] GC(411) Mark Stack Usage: 32M [2025-07-02T14:35:22.260Z] [81.635s][info][gc,nmethod ] GC(411) NMethods: 995 registered, 0 unregistered [2025-07-02T14:35:22.260Z] [81.635s][info][gc,metaspace] GC(411) Metaspace: 1M used, 1M committed, 1088M reserved [2025-07-02T14:35:22.260Z] [81.635s][info][gc,ref ] GC(411) Soft: 24 encountered, 19 discovered, 0 enqueued [2025-07-02T14:35:22.260Z] [81.635s][info][gc,ref ] GC(411) Weak: 109 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.260Z] [81.635s][info][gc,ref ] GC(411) Final: 0 encountered, 0 discovered, 0 enqueued [2025-07-02T14:35:22.260Z] [81.635s][info][gc,ref ] GC(411) Phantom: 12 encountered, 10 discovered, 0 enqueued [2025-07-02T14:35:22.260Z] [81.635s][info][gc,reloc ] GC(411) Small Pages: 172 / 344M, Empty: 292M, Relocated: 4M, In-Place: 0 [2025-07-02T14:35:22.260Z] [81.635s][info][gc,reloc ] GC(411) Medium Pages: 2 / 16M, Empty: 8M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.260Z] [81.635s][info][gc,reloc ] GC(411) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2025-07-02T14:35:22.260Z] [81.635s][info][gc,reloc ] GC(411) Forwarding Usage: 2M [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Min Capacity: 8M(2%) [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Max Capacity: 384M(100%) [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Soft Max Capacity: 384M(100%) [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Mark Start Mark End Relocate Start Relocate End High Low [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Capacity: 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) 384M (100%) [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Free: 24M (6%) 0M (0%) 298M (78%) 326M (85%) 326M (85%) 0M (0%) [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Used: 360M (94%) 384M (100%) 86M (22%) 58M (15%) 384M (100%) 58M (15%) [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Live: - 15M (4%) 15M (4%) 15M (4%) - - [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Allocated: - 24M (6%) 26M (7%) 31M (8%) - - [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Garbage: - 344M (90%) 44M (12%) 10M (3%) - - [2025-07-02T14:35:22.260Z] [81.635s][info][gc,heap ] GC(411) Reclaimed: - - 300M (78%) 333M (87%) - - [2025-07-02T14:35:22.260Z] [81.635s][info][gc ] GC(411) Garbage Collection (Allocation Stall) 360M(94%)->58M(15%) [2025-07-02T14:35:22.260Z] [81.688s][info][gc,heap,exit] Heap [2025-07-02T14:35:22.260Z] [81.688s][info][gc,heap,exit] ZHeap used 160M, capacity 384M, max capacity 384M [2025-07-02T14:35:22.260Z] [81.688s][info][gc,heap,exit] Metaspace used 1255K, committed 1408K, reserved 1114112K [2025-07-02T14:35:22.260Z] [81.688s][info][gc,heap,exit] class space used 100K, committed 192K, reserved 1048576K [2025-07-02T14:35:22.260Z] STDERR: [2025-07-02T14:35:22.260Z] rerun: [2025-07-02T14:35:22.260Z] cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/scratch/1 && \ [2025-07-02T14:35:22.260Z] HOME=/Users/admin \ [2025-07-02T14:35:22.260Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-07-02T14:35:22.260Z] CLASSPATH=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-07-02T14:35:22.260Z] /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/java \ [2025-07-02T14:35:22.260Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2025-07-02T14:35:22.260Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2025-07-02T14:35:22.260Z] -Dtest.compiler.opts= \ [2025-07-02T14:35:22.260Z] -Dtest.java.opts= \ [2025-07-02T14:35:22.260Z] -Dtest.jdk=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-07-02T14:35:22.260Z] -Dcompile.jdk=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/.. \ [2025-07-02T14:35:22.260Z] -Dtest.timeout.factor=8.0 \ [2025-07-02T14:35:22.260Z] -Dtest.nativepath=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-07-02T14:35:22.260Z] -Dtest.root=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-07-02T14:35:22.260Z] -Dtest.name=gc/stress/gcbasher/TestGCBasherWithZ.java#id0 \ [2025-07-02T14:35:22.260Z] -Dtest.file=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher/TestGCBasherWithZ.java \ [2025-07-02T14:35:22.260Z] -Dtest.src=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher \ [2025-07-02T14:35:22.260Z] -Dtest.src.path=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2025-07-02T14:35:22.260Z] -Dtest.classes=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d \ [2025-07-02T14:35:22.260Z] -Dtest.class.path=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0 \ [2025-07-02T14:35:22.260Z] -Dtest.class.path.prefix=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0/gc/stress/gcbasher/TestGCBasherWithZ_id0.d:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/stress/gcbasher:/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/classes/0 \ [2025-07-02T14:35:22.260Z] -ea \ [2025-07-02T14:35:22.260Z] -esa \ [2025-07-02T14:35:22.260Z] -Xmx512m \ [2025-07-02T14:35:22.260Z] -Djava.library.path=/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2025-07-02T14:35:22.260Z] -Xlog:gc*=info \ [2025-07-02T14:35:22.260Z] -Xmx384m \ [2025-07-02T14:35:22.260Z] -server \ [2025-07-02T14:35:22.260Z] -XX:+UseZGC \ [2025-07-02T14:35:22.260Z] com.sun.javatest.regtest.agent.MainWrapper /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0/aqa-tests/TKG/output_17514631926861/hotspot_gc_0/work/gc/stress/gcbasher/TestGCBasherWithZ_id0.d/main.0.jta 120000 [2025-07-02T14:35:22.260Z] [2025-07-02T14:35:22.260Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 143] [2025-07-02T14:35:22.260Z] -------------------------------------------------- [2025-07-02T14:35:22.260Z] make[4]: *** [hotspot_gc_0] Terminated: 15 [2025-07-02T14:35:22.260Z] make[3]: *** [testList-openjdk] Terminated: 15 [2025-07-02T14:35:22.260Z] make[2]: *** [testList-..] Terminated: 15 [2025-07-02T14:35:22.260Z] make[1]: *** [_testList] Terminated: 15 [2025-07-02T14:35:22.260Z] make: *** [testList_0] Terminated: 15 [2025-07-02T14:35:22.260Z] /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0@tmp/durable-d3793a3c/script.sh.copy: line 1: 12402 Terminated: 15 $MAKE -f parallelList.mk testList_0 [2025-07-02T14:35:22.339Z] script returned exit code 143 [Pipeline] sh [2025-07-02T14:35:22.847Z] ++ uname [2025-07-02T14:35:22.847Z] + '[' Darwin = AIX ']' [2025-07-02T14:35:22.847Z] ++ uname [2025-07-02T14:35:22.847Z] + '[' Darwin = SunOS ']' [2025-07-02T14:35:22.847Z] ++ uname [2025-07-02T14:35:22.847Z] + '[' Darwin = '*BSD' ']' [2025-07-02T14:35:22.847Z] + MAKE=make [2025-07-02T14:35:22.847Z] + make -f ./aqa-tests/TKG/testEnv.mk testEnvTeardown [2025-07-02T14:35:22.847Z] make: Nothing to be done for `testEnvTeardown'. [Pipeline] } [2025-07-02T14:35:22.685Z] $ ssh-agent -k [2025-07-02T14:35:22.691Z] unset SSH_AUTH_SOCK; [2025-07-02T14:35:22.691Z] unset SSH_AGENT_PID; [2025-07-02T14:35:22.691Z] echo Agent pid 12362 killed; [2025-07-02T14:35:23.067Z] [ssh-agent] Stopped. [Pipeline] // sshagent [Pipeline] echo [2025-07-02T14:35:23.167Z] Could not find test result, set build result to FAILURE. [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Post) [Pipeline] step [2025-07-02T14:35:23.254Z] TAP Reports Processing: START [2025-07-02T14:35:23.255Z] Looking for TAP results report in workspace using pattern: aqa-tests/TKG/**/*.tap [2025-07-02T14:35:23.475Z] Did not find any matching files. Setting build result to FAILURE. [Pipeline] echo [2025-07-02T14:35:23.480Z] Saving aqa-tests/testenv/testenv.properties file on jenkins. [Pipeline] archiveArtifacts [2025-07-02T14:35:23.511Z] Archiving artifacts [2025-07-02T14:35:23.570Z] Recording fingerprints Click here to forcibly terminate running steps [Pipeline] echo [2025-07-02T14:35:23.609Z] Saving aqa-tests/TKG/**/*.tap file on jenkins. [Pipeline] archiveArtifacts [2025-07-02T14:35:23.641Z] Archiving artifacts [Pipeline] sh Click here to forcibly terminate running steps [2025-07-02T14:35:24.250Z] + tar -cf - ./aqa-tests/TKG/output_17514631926861 [2025-07-02T14:35:24.250Z] + pigz -9 [2025-07-02T14:35:24.250Z] + gzip -9 [Pipeline] echo [2025-07-02T14:35:26.097Z] ARTIFACTORY_SERVER is not set. Saving artifacts on jenkins. [Pipeline] archiveArtifacts [2025-07-02T14:35:26.131Z] Archiving artifacts [2025-07-02T14:35:26.502Z] Recording fingerprints [Pipeline] findFiles [Pipeline] junit [2025-07-02T14:35:29.746Z] Recording test results [2025-07-02T14:35:33.548Z] [Checks API] No suitable checks publisher found. [Pipeline] } [Pipeline] // stage [Pipeline] echo [2025-07-02T14:35:33.602Z] PROCESSCATCH: Terminating any hung/left over test processes: [Pipeline] sh [2025-07-02T14:35:34.174Z] + aqa-tests/terminateTestProcesses.sh admin [2025-07-02T14:35:34.174Z] Mac type machine.. do not terminate any remaining processes as Orka mac VMs are not process isolated, see: https://github.com/adoptium/aqa-tests/issues/4964 [Pipeline] cleanWs [2025-07-02T14:35:34.340Z] [WS-CLEANUP] Deleting project workspace... [2025-07-02T14:35:34.340Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2025-07-02T14:35:42.152Z] [WS-CLEANUP] done [Pipeline] sh [2025-07-02T14:35:42.711Z] + find /cores -name '*core*' -print -exec rm -f '{}' ';' [2025-07-02T14:35:42.711Z] /cores [Pipeline] } [Pipeline] // timeout [Pipeline] timeout [2025-07-02T14:35:42.819Z] Timeout set to expire in 5 min 0 sec [Pipeline] { [Pipeline] echo [2025-07-02T14:35:42.831Z] Test_openjdk17_hs_extended.openjdk_aarch64_mac_testList_0 #48 result is FAILURE. Checking console log for specific errors... [Pipeline] } [Pipeline] // timeout [Pipeline] } [Pipeline] // node [Pipeline] } [Pipeline] // stage [Pipeline] } [Pipeline] // timestamps [Pipeline] End of Pipeline Finished: ABORTED