Test Results Summary Service

hotspot_gc_0

[2024-11-27T21:08:27.815Z] Running test hotspot_gc_0 ... [2024-11-27T21:08:27.815Z] =============================================== [2024-11-27T21:08:27.815Z] hotspot_gc_0 Start Time: Wed Nov 27 21:08:27 2024 Epoch Time (ms): 1732741707649 [2024-11-27T21:08:27.815Z] variation: NoOptions [2024-11-27T21:08:27.815Z] JVM_OPTIONS: [2024-11-27T21:08:27.815Z] { \ [2024-11-27T21:08:27.815Z] echo ""; echo "TEST SETUP:"; \ [2024-11-27T21:08:27.815Z] echo "Nothing to be done for setup."; \ [2024-11-27T21:08:27.815Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17327417078525/hotspot_gc_0"; \ [2024-11-27T21:08:27.815Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17327417078525/hotspot_gc_0"; \ [2024-11-27T21:08:27.815Z] echo ""; echo "TESTING:"; \ [2024-11-27T21:08:27.815Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2024-11-27T21:08:27.815Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:3 -nativepath:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native" -vmoptions:"-Xmx512m " \ [2024-11-27T21:08:27.815Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17327417078525/hotspot_gc_0"/work" \ [2024-11-27T21:08:27.815Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17327417078525/hotspot_gc_0"/report" \ [2024-11-27T21:08:27.815Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image" \ [2024-11-27T21:08:27.815Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg/ProblemList.txt" \ [2024-11-27T21:08:27.815Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2024-11-27T21:08:27.815Z] \ [2024-11-27T21:08:27.815Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2024-11-27T21:08:27.815Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../openjdk/openjdk-jdk/test/hotspot/jtreg:hotspot_gc"; \ [2024-11-27T21:08:27.815Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "hotspot_gc_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "hotspot_gc_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-27T21:08:27.815Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-27T21:08:27.815Z] echo "Nothing to be done for teardown."; \ [2024-11-27T21:08:27.815Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17327417078525/TestTargetResult"; [2024-11-27T21:08:27.815Z] [2024-11-27T21:08:27.815Z] TEST SETUP: [2024-11-27T21:08:27.815Z] Nothing to be done for setup. [2024-11-27T21:08:27.815Z] [2024-11-27T21:08:27.815Z] TESTING: [2024-11-27T21:08:28.499Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17327417078525/hotspot_gc_0/work" not found: creating [2024-11-27T21:08:28.499Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17327417078525/hotspot_gc_0/report" not found: creating [2024-11-27T21:08:41.510Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work [2024-11-28T00:56:55.483Z] -------------------------------------------------- [2024-11-28T00:56:55.483Z] TEST: gc/z/TestHighUsage.java [2024-11-28T00:56:55.483Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image [2024-11-28T00:56:55.483Z] [2024-11-28T00:56:55.483Z] ACTION: build -- Passed. Build successful [2024-11-28T00:56:55.483Z] REASON: Named class compiled on demand [2024-11-28T00:56:55.483Z] TIME: 1.441 seconds [2024-11-28T00:56:55.483Z] messages: [2024-11-28T00:56:55.483Z] command: build gc.z.TestHighUsage [2024-11-28T00:56:55.483Z] reason: Named class compiled on demand [2024-11-28T00:56:55.483Z] started: Thu Nov 28 00:55:15 UTC 2024 [2024-11-28T00:56:55.483Z] Test directory: [2024-11-28T00:56:55.483Z] compile: gc.z.TestHighUsage [2024-11-28T00:56:55.483Z] finished: Thu Nov 28 00:55:17 UTC 2024 [2024-11-28T00:56:55.483Z] elapsed time (seconds): 1.441 [2024-11-28T00:56:55.483Z] [2024-11-28T00:56:55.483Z] ACTION: compile -- Passed. Compilation successful [2024-11-28T00:56:55.483Z] REASON: .class file out of date or does not exist [2024-11-28T00:56:55.483Z] TIME: 1.44 seconds [2024-11-28T00:56:55.483Z] messages: [2024-11-28T00:56:55.483Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestHighUsage.java [2024-11-28T00:56:55.483Z] reason: .class file out of date or does not exist [2024-11-28T00:56:55.483Z] started: Thu Nov 28 00:55:15 UTC 2024 [2024-11-28T00:56:55.483Z] Mode: agentvm [2024-11-28T00:56:55.483Z] Agent id: 3 [2024-11-28T00:56:55.483Z] finished: Thu Nov 28 00:55:17 UTC 2024 [2024-11-28T00:56:55.483Z] elapsed time (seconds): 1.44 [2024-11-28T00:56:55.483Z] configuration: [2024-11-28T00:56:55.483Z] Boot Layer (javac runtime environment) [2024-11-28T00:56:55.483Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-11-28T00:56:55.483Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-11-28T00:56:55.483Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/patches/java.base [2024-11-28T00:56:55.483Z] [2024-11-28T00:56:55.483Z] javac compilation environment [2024-11-28T00:56:55.483Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z [2024-11-28T00:56:55.483Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-11-28T00:56:55.483Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z [2024-11-28T00:56:55.483Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d [2024-11-28T00:56:55.483Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/test/lib [2024-11-28T00:56:55.483Z] [2024-11-28T00:56:55.483Z] rerun: [2024-11-28T00:56:55.483Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/scratch/1 && \ [2024-11-28T00:56:55.483Z] DISPLAY=:0 \ [2024-11-28T00:56:55.483Z] HOME=/home/jenkins \ [2024-11-28T00:56:55.483Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-11-28T00:56:55.483Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image/bin/javac \ [2024-11-28T00:56:55.483Z] -J-ea \ [2024-11-28T00:56:55.483Z] -J-esa \ [2024-11-28T00:56:55.483Z] -J-Xmx512m \ [2024-11-28T00:56:55.483Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m' \ [2024-11-28T00:56:55.483Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2024-11-28T00:56:55.483Z] -J-Dtest.compiler.opts= \ [2024-11-28T00:56:55.483Z] -J-Dtest.java.opts= \ [2024-11-28T00:56:55.484Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image \ [2024-11-28T00:56:55.484Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image \ [2024-11-28T00:56:55.484Z] -J-Dtest.timeout.factor=8.0 \ [2024-11-28T00:56:55.484Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-11-28T00:56:55.484Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2024-11-28T00:56:55.484Z] -J-Dtest.name=gc/z/TestHighUsage.java \ [2024-11-28T00:56:55.484Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestHighUsage.java \ [2024-11-28T00:56:55.484Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \ [2024-11-28T00:56:55.484Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-11-28T00:56:55.484Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d \ [2024-11-28T00:56:55.484Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/test/lib \ [2024-11-28T00:56:55.484Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/test/lib \ [2024-11-28T00:56:55.484Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d \ [2024-11-28T00:56:55.484Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-11-28T00:56:55.484Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/test/lib /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestHighUsage.java [2024-11-28T00:56:55.484Z] [2024-11-28T00:56:55.484Z] ACTION: main -- Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'High Usage' missing from stdout/stderr [2024-11-28T00:56:55.484Z] REASON: User specified action: run main/othervm gc.z.TestHighUsage [2024-11-28T00:56:55.484Z] TIME: 89.251 seconds [2024-11-28T00:56:55.484Z] messages: [2024-11-28T00:56:55.484Z] command: main gc.z.TestHighUsage [2024-11-28T00:56:55.484Z] reason: User specified action: run main/othervm gc.z.TestHighUsage [2024-11-28T00:56:55.484Z] started: Thu Nov 28 00:55:17 UTC 2024 [2024-11-28T00:56:55.484Z] Mode: othervm [/othervm specified] [2024-11-28T00:56:55.484Z] finished: Thu Nov 28 00:56:46 UTC 2024 [2024-11-28T00:56:55.484Z] elapsed time (seconds): 89.251 [2024-11-28T00:56:55.484Z] configuration: [2024-11-28T00:56:55.484Z] STDOUT: [2024-11-28T00:56:55.484Z] Command line: [/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image/bin/java -cp /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar -ea -esa -Xmx512m -XX:+UseZGC -XX:-ZProactive -Xms128M -Xmx128M -XX:ParallelGCThreads=1 -XX:ConcGCThreads=1 -Xlog:gc,gc+start gc.z.TestHighUsage$Test ] [2024-11-28T00:56:55.484Z] [2024-11-28T00:55:19.010835461Z] Gathering output for process 2920329 [2024-11-28T00:56:55.484Z] [2024-11-28T00:56:46.368443694Z] Waiting for completion for process 2920329 [2024-11-28T00:56:55.484Z] [2024-11-28T00:56:46.386499270Z] Waiting for completion finished for process 2920329 [2024-11-28T00:56:55.484Z] Output and diagnostic info for process 2920329 was saved into 'pid-2920329-output.log' [2024-11-28T00:56:55.484Z] STDERR: [2024-11-28T00:56:55.484Z] stdout: [[0.180s][info][gc] Using The Z Garbage Collector [2024-11-28T00:56:55.484Z] Max capacity: 128M [2024-11-28T00:56:55.484Z] High usage threshold: 6M [2024-11-28T00:56:55.484Z] Allocating live-set [2024-11-28T00:56:55.484Z] [0.964s][info][gc,start] GC(0) Garbage Collection (Warmup) [2024-11-28T00:56:55.484Z] [1.528s][info][gc ] GC(0) Garbage Collection (Warmup) 34M(27%)->112M(88%) [2024-11-28T00:56:55.484Z] [1.528s][info][gc,start] GC(1) Garbage Collection (System.gc()) [2024-11-28T00:56:55.484Z] [2.071s][info][gc ] GC(1) Garbage Collection (System.gc()) 112M(88%)->110M(86%) [2024-11-28T00:56:55.484Z] [2.168s][info][gc,start] GC(2) Garbage Collection (Warmup) [2024-11-28T00:56:55.484Z] [2.682s][info][gc ] GC(2) Garbage Collection (Warmup) 112M(88%)->110M(86%) [2024-11-28T00:56:55.484Z] [2.760s][info][gc,start] GC(3) Garbage Collection (Warmup) [2024-11-28T00:56:55.484Z] [3.419s][info][gc ] GC(3) Garbage Collection (Warmup) 110M(86%)->110M(86%) [2024-11-28T00:56:55.484Z] [4.078s][info][gc,start] GC(4) Garbage Collection (System.gc()) [2024-11-28T00:56:55.485Z] [4.542s][info][gc ] GC(4) Garbage Collection (System.gc()) 112M(88%)->110M(86%) [2024-11-28T00:56:55.485Z] [6.542s][info][gc,start] GC(5) Garbage Collection (System.gc()) [2024-11-28T00:56:55.485Z] [7.006s][info][gc ] GC(5) Garbage Collection (System.gc()) 112M(88%)->110M(86%) [2024-11-28T00:56:55.485Z] [9.008s][info][gc,start] GC(6) Garbage Collection (System.gc()) [2024-11-28T00:56:55.485Z] [9.468s][info][gc ] GC(6) Garbage Collection (System.gc()) 112M(88%)->112M(88%) [2024-11-28T00:56:55.485Z] Allocating garbage slowly [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] [11.664s][info][gc,start] GC(7) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [12.029s][info][gc ] GC(7) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] [12.068s][info][gc,start] GC(8) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [12.435s][info][gc ] GC(8) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] [12.461s][info][gc,start] GC(9) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [12.925s][info][gc ] GC(9) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] [13.361s][info][gc,start] GC(10) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [13.760s][info][gc ] GC(10) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [17.072s][info][gc,start] GC(11) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 10M (Allocating) [2024-11-28T00:56:55.485Z] [17.369s][info][gc ] GC(11) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.485Z] [17.476s][info][gc,start] GC(12) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [17.860s][info][gc ] GC(12) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] [17.872s][info][gc,start] GC(13) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [18.220s][info][gc ] GC(13) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [21.660s][info][gc,start] GC(14) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 10M (Allocating) [2024-11-28T00:56:55.485Z] Free: 10M (Allocating) [2024-11-28T00:56:55.485Z] [22.127s][info][gc ] GC(14) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.485Z] [22.160s][info][gc,start] GC(15) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [22.704s][info][gc ] GC(15) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] [22.763s][info][gc,start] GC(16) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [23.328s][info][gc ] GC(16) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] [23.976s][info][gc,start] GC(17) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.485Z] [24.500s][info][gc ] GC(17) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 14M (Allocating) [2024-11-28T00:56:55.485Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [27.759s][info][gc,start] GC(18) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 10M (Allocating) [2024-11-28T00:56:55.486Z] Free: 10M (Allocating) [2024-11-28T00:56:55.486Z] [28.227s][info][gc ] GC(18) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.486Z] [28.260s][info][gc,start] GC(19) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [28.771s][info][gc ] GC(19) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] [28.779s][info][gc,start] GC(20) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [29.246s][info][gc ] GC(20) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [32.261s][info][gc,start] GC(21) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 10M (Allocating) [2024-11-28T00:56:55.486Z] Free: 10M (Allocating) [2024-11-28T00:56:55.486Z] [32.763s][info][gc ] GC(21) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.486Z] [32.860s][info][gc,start] GC(22) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [33.475s][info][gc ] GC(22) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] [33.563s][info][gc,start] GC(23) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [34.178s][info][gc ] GC(23) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [37.364s][info][gc,start] GC(24) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 10M (Allocating) [2024-11-28T00:56:55.486Z] [37.776s][info][gc ] GC(24) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] [37.868s][info][gc,start] GC(25) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [38.369s][info][gc ] GC(25) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] [38.467s][info][gc,start] GC(26) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [38.948s][info][gc ] GC(26) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] [39.863s][info][gc,start] GC(27) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [40.400s][info][gc ] GC(27) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [43.460s][info][gc,start] GC(28) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 10M (Allocating) [2024-11-28T00:56:55.486Z] [43.822s][info][gc ] GC(28) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.486Z] [43.871s][info][gc,start] GC(29) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [44.303s][info][gc ] GC(29) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] [44.368s][info][gc,start] GC(30) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [44.664s][info][gc ] GC(30) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] [45.060s][info][gc,start] GC(31) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [45.408s][info][gc ] GC(31) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 14M (Allocating) [2024-11-28T00:56:55.486Z] Free: 12M (Allocating) [2024-11-28T00:56:55.486Z] [48.764s][info][gc,start] GC(32) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.486Z] Free: 10M (Allocating) [2024-11-28T00:56:55.487Z] [49.240s][info][gc ] GC(32) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] [49.260s][info][gc,start] GC(33) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [49.491s][info][gc ] GC(33) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [49.564s][info][gc,start] GC(34) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [50.044s][info][gc ] GC(34) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [50.060s][info][gc,start] GC(35) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [50.445s][info][gc ] GC(35) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] [50.768s][info][gc,start] GC(36) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [51.206s][info][gc ] GC(36) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [54.164s][info][gc,start] GC(37) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 10M (Allocating) [2024-11-28T00:56:55.487Z] [54.481s][info][gc ] GC(37) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.487Z] [54.566s][info][gc,start] GC(38) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [54.840s][info][gc ] GC(38) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [54.871s][info][gc,start] GC(39) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [55.400s][info][gc ] GC(39) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [55.462s][info][gc,start] GC(40) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [55.892s][info][gc ] GC(40) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] [56.768s][info][gc,start] GC(41) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [57.386s][info][gc ] GC(41) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] [58.259s][info][gc,start] GC(42) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [58.763s][info][gc ] GC(42) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [58.863s][info][gc,start] GC(43) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [59.528s][info][gc ] GC(43) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [59.564s][info][gc,start] GC(44) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [60.169s][info][gc ] GC(44) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [63.272s][info][gc,start] GC(45) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 10M (Allocating) [2024-11-28T00:56:55.487Z] Free: 10M (Allocating) [2024-11-28T00:56:55.487Z] [63.756s][info][gc ] GC(45) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.487Z] [63.768s][info][gc,start] GC(46) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [64.336s][info][gc ] GC(46) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] [64.472s][info][gc,start] GC(47) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [64.938s][info][gc ] GC(47) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] [64.961s][info][gc,start] GC(48) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [65.549s][info][gc ] GC(48) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] [66.264s][info][gc,start] GC(49) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [66.563s][info][gc ] GC(49) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [66.576s][info][gc,start] GC(50) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [66.806s][info][gc ] GC(50) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [66.864s][info][gc,start] GC(51) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [67.265s][info][gc ] GC(51) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] [67.372s][info][gc,start] GC(52) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [67.764s][info][gc ] GC(52) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] [68.165s][info][gc,start] GC(53) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [68.574s][info][gc ] GC(53) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 14M (Allocating) [2024-11-28T00:56:55.487Z] Free: 12M (Allocating) [2024-11-28T00:56:55.487Z] [71.860s][info][gc,start] GC(54) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 10M (Allocating) [2024-11-28T00:56:55.488Z] Free: 10M (Allocating) [2024-11-28T00:56:55.488Z] [72.376s][info][gc ] GC(54) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.488Z] [72.460s][info][gc,start] GC(55) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [72.895s][info][gc ] GC(55) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] [73.360s][info][gc,start] GC(56) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [73.751s][info][gc ] GC(56) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [76.660s][info][gc,start] GC(57) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 10M (Allocating) [2024-11-28T00:56:55.488Z] Free: 10M (Allocating) [2024-11-28T00:56:55.488Z] [77.196s][info][gc ] GC(57) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.488Z] [77.260s][info][gc,start] GC(58) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [77.630s][info][gc ] GC(58) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] [77.664s][info][gc,start] GC(59) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [77.908s][info][gc ] GC(59) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [81.466s][info][gc,start] GC(60) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 10M (Allocating) [2024-11-28T00:56:55.488Z] [81.804s][info][gc ] GC(60) Garbage Collection (Allocation Rate) 116M(91%)->114M(89%) [2024-11-28T00:56:55.488Z] [81.864s][info][gc,start] GC(61) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [82.466s][info][gc ] GC(61) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] [82.564s][info][gc,start] GC(62) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [82.942s][info][gc ] GC(62) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.488Z] [82.968s][info][gc,start] GC(63) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [83.297s][info][gc ] GC(63) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] [84.163s][info][gc,start] GC(64) Garbage Collection (Allocation Rate) [2024-11-28T00:56:55.488Z] Free: 12M (Allocating) [2024-11-28T00:56:55.488Z] [84.420s][info][gc ] GC(64) Garbage Collection (Allocation Rate) 114M(89%)->114M(89%) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Free: 14M (Allocating) [2024-11-28T00:56:55.488Z] Done [2024-11-28T00:56:55.488Z] ]; [2024-11-28T00:56:55.488Z] stderr: [] [2024-11-28T00:56:55.488Z] exitValue = 0 [2024-11-28T00:56:55.488Z] [2024-11-28T00:56:55.488Z] java.lang.RuntimeException: 'High Usage' missing from stdout/stderr [2024-11-28T00:56:55.488Z] [2024-11-28T00:56:55.488Z] at jdk.test.lib.process.OutputAnalyzer.shouldContain(OutputAnalyzer.java:221) [2024-11-28T00:56:55.488Z] at gc.z.TestHighUsage.main(TestHighUsage.java:97) [2024-11-28T00:56:55.488Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2024-11-28T00:56:55.488Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2024-11-28T00:56:55.488Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2024-11-28T00:56:55.488Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2024-11-28T00:56:55.488Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2024-11-28T00:56:55.488Z] at java.base/java.lang.Thread.run(Thread.java:840) [2024-11-28T00:56:55.488Z] [2024-11-28T00:56:55.488Z] JavaTest Message: Test threw exception: java.lang.RuntimeException: 'High Usage' missing from stdout/stderr [2024-11-28T00:56:55.488Z] [2024-11-28T00:56:55.488Z] JavaTest Message: shutting down test [2024-11-28T00:56:55.488Z] [2024-11-28T00:56:55.488Z] STATUS:Failed.`main' threw exception: java.lang.RuntimeException: 'High Usage' missing from stdout/stderr [2024-11-28T00:56:55.488Z] rerun: [2024-11-28T00:56:55.488Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/scratch/1 && \ [2024-11-28T00:56:55.488Z] DISPLAY=:0 \ [2024-11-28T00:56:55.488Z] HOME=/home/jenkins \ [2024-11-28T00:56:55.488Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-11-28T00:56:55.488Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2024-11-28T00:56:55.488Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image/bin/java \ [2024-11-28T00:56:55.488Z] -Dtest.vm.opts='-ea -esa -Xmx512m' \ [2024-11-28T00:56:55.488Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \ [2024-11-28T00:56:55.488Z] -Dtest.compiler.opts= \ [2024-11-28T00:56:55.488Z] -Dtest.java.opts= \ [2024-11-28T00:56:55.488Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image \ [2024-11-28T00:56:55.488Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/j2sdk-image \ [2024-11-28T00:56:55.488Z] -Dtest.timeout.factor=8.0 \ [2024-11-28T00:56:55.489Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-11-28T00:56:55.489Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \ [2024-11-28T00:56:55.489Z] -Dtest.name=gc/z/TestHighUsage.java \ [2024-11-28T00:56:55.489Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z/TestHighUsage.java \ [2024-11-28T00:56:55.489Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z \ [2024-11-28T00:56:55.489Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-11-28T00:56:55.489Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d \ [2024-11-28T00:56:55.489Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/test/lib \ [2024-11-28T00:56:55.489Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/gc/z/TestHighUsage.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/gc/z:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/classes/0/test/lib \ [2024-11-28T00:56:55.489Z] -ea \ [2024-11-28T00:56:55.489Z] -esa \ [2024-11-28T00:56:55.489Z] -Xmx512m \ [2024-11-28T00:56:55.489Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/jdkbinary/openjdk-test-image/hotspot/jtreg/native \ [2024-11-28T00:56:55.489Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work/gc/z/TestHighUsage.d/main.0.jta [2024-11-28T00:56:55.489Z] [2024-11-28T00:56:55.489Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'High Usage' missing from stdout/stderr [2024-11-28T00:56:55.489Z] -------------------------------------------------- [2024-11-28T01:11:55.017Z] Test results: passed: 495; failed: 1 [2024-11-28T01:12:22.780Z] Report written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/report/html/report.html [2024-11-28T01:12:22.780Z] Results written to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_17327417078525/hotspot_gc_0/work [2024-11-28T01:12:22.780Z] Error: Some tests failed or other problems occurred. [2024-11-28T01:12:22.780Z] ----------------------------------- [2024-11-28T01:12:22.780Z] hotspot_gc_0_FAILED [2024-11-28T01:12:22.780Z] ----------------------------------- [2024-11-28T01:12:22.780Z] [2024-11-28T01:12:22.780Z] TEST TEARDOWN: [2024-11-28T01:12:22.780Z] Nothing to be done for teardown. [2024-11-28T01:12:22.780Z] hotspot_gc_0 Finish Time: Thu Nov 28 01:12:21 2024 Epoch Time (ms): 1732756341130