[2024-08-16T17:19:17.577Z] Running test jdk_management_1 ... [2024-08-16T17:19:17.577Z] =============================================== [2024-08-16T17:19:17.577Z] jdk_management_1 Start Time: Fri Aug 16 17:19:17 2024 Epoch Time (ms): 1723828757128 [2024-08-16T17:19:17.577Z] variation: Mode650 [2024-08-16T17:19:17.577Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-08-16T17:19:17.577Z] { \ [2024-08-16T17:19:17.577Z] echo ""; echo "TEST SETUP:"; \ [2024-08-16T17:19:17.577Z] echo "Nothing to be done for setup."; \ [2024-08-16T17:19:17.577Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../TKG/output_17238230649044/jdk_management_1"; \ [2024-08-16T17:19:17.577Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../TKG/output_17238230649044/jdk_management_1"; \ [2024-08-16T17:19:17.577Z] echo ""; echo "TESTING:"; \ [2024-08-16T17:19:17.577Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2024-08-16T17:19:17.577Z] -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_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/openjdk-test-image/jdk/jtreg/native" -vmoptions:"-Xmx512m -XX:-UseCompressedOops " \ [2024-08-16T17:19:17.577Z] \ [2024-08-16T17:19:17.577Z] -w ""/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../TKG/output_17238230649044/jdk_management_1"/work" \ [2024-08-16T17:19:17.577Z] -r ""/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../TKG/output_17238230649044/jdk_management_1"/report" \ [2024-08-16T17:19:17.577Z] -jdk:"/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image" \ [2024-08-16T17:19:17.577Z] -exclude:"/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../openjdk/openjdk-jdk/test/jdk/ProblemList.txt" \ [2024-08-16T17:19:17.577Z] -exclude:"/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk11.txt" \ [2024-08-16T17:19:17.577Z] \ [2024-08-16T17:19:17.578Z] -exclude:"/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk11.txt" \ [2024-08-16T17:19:17.578Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../openjdk/openjdk-jdk/test/jdk:jdk_management"; \ [2024-08-16T17:19:17.578Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "jdk_management_1""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "jdk_management_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-16T17:19:17.578Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-16T17:19:17.578Z] echo "Nothing to be done for teardown."; \ [2024-08-16T17:19:17.578Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../TKG/output_17238230649044/TestTargetResult"; [2024-08-16T17:19:17.578Z] [2024-08-16T17:19:17.578Z] TEST SETUP: [2024-08-16T17:19:17.578Z] Nothing to be done for setup. [2024-08-16T17:19:17.578Z] [2024-08-16T17:19:17.578Z] TESTING: [2024-08-16T17:19:19.890Z] Directory "/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../TKG/output_17238230649044/jdk_management_1/work" not found: creating [2024-08-16T17:19:19.890Z] Directory "/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/../TKG/output_17238230649044/jdk_management_1/report" not found: creating [2024-08-16T17:19:43.318Z] XML output with verification to /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work [2024-08-16T18:43:01.343Z] -------------------------------------------------- [2024-08-16T18:43:01.343Z] TEST: java/lang/management/MemoryMXBean/LowMemoryTest.java [2024-08-16T18:43:01.343Z] TEST JDK: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image [2024-08-16T18:43:01.343Z] [2024-08-16T18:43:01.343Z] ACTION: build -- Passed. Build successful [2024-08-16T18:43:01.343Z] REASON: User specified action: run build LowMemoryTest MemoryUtil RunUtil [2024-08-16T18:43:01.343Z] TIME: 12.173 seconds [2024-08-16T18:43:01.343Z] messages: [2024-08-16T18:43:01.343Z] command: build LowMemoryTest MemoryUtil RunUtil [2024-08-16T18:43:01.343Z] reason: User specified action: run build LowMemoryTest MemoryUtil RunUtil [2024-08-16T18:43:01.343Z] started: Fri Aug 16 17:22:31 GMT 2024 [2024-08-16T18:43:01.343Z] Test directory: [2024-08-16T18:43:01.343Z] compile: LowMemoryTest, MemoryUtil, RunUtil [2024-08-16T18:43:01.343Z] finished: Fri Aug 16 17:22:43 GMT 2024 [2024-08-16T18:43:01.343Z] elapsed time (seconds): 12.173 [2024-08-16T18:43:01.343Z] [2024-08-16T18:43:01.343Z] ACTION: compile -- Passed. Compilation successful [2024-08-16T18:43:01.343Z] REASON: .class file out of date or does not exist [2024-08-16T18:43:01.343Z] TIME: 12.171 seconds [2024-08-16T18:43:01.343Z] messages: [2024-08-16T18:43:01.343Z] command: compile /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/LowMemoryTest.java /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/MemoryUtil.java /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/RunUtil.java [2024-08-16T18:43:01.343Z] reason: .class file out of date or does not exist [2024-08-16T18:43:01.343Z] started: Fri Aug 16 17:22:31 GMT 2024 [2024-08-16T18:43:01.343Z] Additional options from @modules: --add-modules java.management [2024-08-16T18:43:01.343Z] Mode: agentvm [2024-08-16T18:43:01.343Z] Agent id: 3 [2024-08-16T18:43:01.343Z] finished: Fri Aug 16 17:22:43 GMT 2024 [2024-08-16T18:43:01.343Z] elapsed time (seconds): 12.171 [2024-08-16T18:43:01.343Z] configuration: [2024-08-16T18:43:01.343Z] Boot Layer (javac runtime environment) [2024-08-16T18:43:01.343Z] class path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-08-16T18:43:01.343Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-08-16T18:43:01.343Z] patch: java.base /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/patches/java.base [2024-08-16T18:43:01.343Z] [2024-08-16T18:43:01.343Z] javac compilation environment [2024-08-16T18:43:01.343Z] add modules: java.management [2024-08-16T18:43:01.343Z] source path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean [2024-08-16T18:43:01.343Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-08-16T18:43:01.343Z] class path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean [2024-08-16T18:43:01.343Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d [2024-08-16T18:43:01.343Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib [2024-08-16T18:43:01.343Z] [2024-08-16T18:43:01.343Z] rerun: [2024-08-16T18:43:01.343Z] cd /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/scratch/2 && \ [2024-08-16T18:43:01.343Z] DISPLAY=:0 \ [2024-08-16T18:43:01.343Z] HOME=/home/jenkins \ [2024-08-16T18:43:01.343Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-08-16T18:43:01.343Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/javac \ [2024-08-16T18:43:01.344Z] -J-ea \ [2024-08-16T18:43:01.344Z] -J-esa \ [2024-08-16T18:43:01.344Z] -J-Xmx512m \ [2024-08-16T18:43:01.344Z] -J-XX:-UseCompressedOops \ [2024-08-16T18:43:01.344Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2024-08-16T18:43:01.344Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2024-08-16T18:43:01.344Z] -J-Dtest.compiler.opts= \ [2024-08-16T18:43:01.344Z] -J-Dtest.java.opts= \ [2024-08-16T18:43:01.344Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.344Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.344Z] -J-Dtest.timeout.factor=8.0 \ [2024-08-16T18:43:01.344Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2024-08-16T18:43:01.344Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2024-08-16T18:43:01.344Z] -J-Dtest.name=java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.344Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.344Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean \ [2024-08-16T18:43:01.344Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d \ [2024-08-16T18:43:01.344Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.modules=java.management \ [2024-08-16T18:43:01.344Z] --add-modules java.management \ [2024-08-16T18:43:01.344Z] -d /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d \ [2024-08-16T18:43:01.344Z] -sourcepath /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-08-16T18:43:01.344Z] -classpath /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/LowMemoryTest.java /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/MemoryUtil.java /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/RunUtil.java [2024-08-16T18:43:01.344Z] [2024-08-16T18:43:01.344Z] ACTION: build -- Passed. Build successful [2024-08-16T18:43:01.344Z] REASON: User specified action: run build sun.hotspot.WhiteBox [2024-08-16T18:43:01.344Z] TIME: 1.787 seconds [2024-08-16T18:43:01.344Z] messages: [2024-08-16T18:43:01.344Z] command: build sun.hotspot.WhiteBox [2024-08-16T18:43:01.344Z] reason: User specified action: run build sun.hotspot.WhiteBox [2024-08-16T18:43:01.344Z] started: Fri Aug 16 17:22:43 GMT 2024 [2024-08-16T18:43:01.344Z] Library /test/lib: [2024-08-16T18:43:01.344Z] compile: sun.hotspot.WhiteBox [2024-08-16T18:43:01.344Z] finished: Fri Aug 16 17:22:45 GMT 2024 [2024-08-16T18:43:01.344Z] elapsed time (seconds): 1.787 [2024-08-16T18:43:01.344Z] [2024-08-16T18:43:01.344Z] ACTION: compile -- Passed. Compilation successful [2024-08-16T18:43:01.344Z] REASON: .class file out of date or does not exist [2024-08-16T18:43:01.344Z] TIME: 1.787 seconds [2024-08-16T18:43:01.344Z] messages: [2024-08-16T18:43:01.344Z] command: compile /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib/sun/hotspot/WhiteBox.java [2024-08-16T18:43:01.344Z] reason: .class file out of date or does not exist [2024-08-16T18:43:01.344Z] started: Fri Aug 16 17:22:43 GMT 2024 [2024-08-16T18:43:01.344Z] Additional options from @modules: --add-modules java.management [2024-08-16T18:43:01.344Z] Mode: agentvm [2024-08-16T18:43:01.344Z] Agent id: 3 [2024-08-16T18:43:01.344Z] finished: Fri Aug 16 17:22:45 GMT 2024 [2024-08-16T18:43:01.344Z] elapsed time (seconds): 1.787 [2024-08-16T18:43:01.344Z] configuration: [2024-08-16T18:43:01.344Z] Boot Layer (javac runtime environment) [2024-08-16T18:43:01.344Z] class path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-08-16T18:43:01.344Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-08-16T18:43:01.344Z] patch: java.base /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/patches/java.base [2024-08-16T18:43:01.344Z] [2024-08-16T18:43:01.344Z] javac compilation environment [2024-08-16T18:43:01.344Z] add modules: java.management [2024-08-16T18:43:01.344Z] source path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-08-16T18:43:01.344Z] class path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d [2024-08-16T18:43:01.344Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib [2024-08-16T18:43:01.344Z] [2024-08-16T18:43:01.344Z] rerun: [2024-08-16T18:43:01.344Z] cd /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/scratch/2 && \ [2024-08-16T18:43:01.344Z] DISPLAY=:0 \ [2024-08-16T18:43:01.344Z] HOME=/home/jenkins \ [2024-08-16T18:43:01.344Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-08-16T18:43:01.344Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/javac \ [2024-08-16T18:43:01.344Z] -J-ea \ [2024-08-16T18:43:01.344Z] -J-esa \ [2024-08-16T18:43:01.344Z] -J-Xmx512m \ [2024-08-16T18:43:01.344Z] -J-XX:-UseCompressedOops \ [2024-08-16T18:43:01.344Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2024-08-16T18:43:01.344Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2024-08-16T18:43:01.344Z] -J-Dtest.compiler.opts= \ [2024-08-16T18:43:01.344Z] -J-Dtest.java.opts= \ [2024-08-16T18:43:01.344Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.344Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.344Z] -J-Dtest.timeout.factor=8.0 \ [2024-08-16T18:43:01.344Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2024-08-16T18:43:01.344Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2024-08-16T18:43:01.344Z] -J-Dtest.name=java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.344Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.344Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean \ [2024-08-16T18:43:01.344Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d \ [2024-08-16T18:43:01.344Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.modules=java.management \ [2024-08-16T18:43:01.344Z] --add-modules java.management \ [2024-08-16T18:43:01.344Z] -d /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.344Z] -sourcepath /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-08-16T18:43:01.344Z] -classpath /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib/sun/hotspot/WhiteBox.java [2024-08-16T18:43:01.344Z] [2024-08-16T18:43:01.344Z] ACTION: build -- Passed. Build successful [2024-08-16T18:43:01.344Z] REASON: Named class compiled on demand [2024-08-16T18:43:01.344Z] TIME: 1.442 seconds [2024-08-16T18:43:01.344Z] messages: [2024-08-16T18:43:01.344Z] command: build ClassFileInstaller [2024-08-16T18:43:01.344Z] reason: Named class compiled on demand [2024-08-16T18:43:01.344Z] started: Fri Aug 16 17:22:45 GMT 2024 [2024-08-16T18:43:01.344Z] Library /test/lib: [2024-08-16T18:43:01.344Z] compile: ClassFileInstaller [2024-08-16T18:43:01.344Z] finished: Fri Aug 16 17:22:46 GMT 2024 [2024-08-16T18:43:01.344Z] elapsed time (seconds): 1.442 [2024-08-16T18:43:01.344Z] [2024-08-16T18:43:01.344Z] ACTION: compile -- Passed. Compilation successful [2024-08-16T18:43:01.344Z] REASON: .class file out of date or does not exist [2024-08-16T18:43:01.344Z] TIME: 1.441 seconds [2024-08-16T18:43:01.344Z] messages: [2024-08-16T18:43:01.344Z] command: compile /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib/ClassFileInstaller.java [2024-08-16T18:43:01.344Z] reason: .class file out of date or does not exist [2024-08-16T18:43:01.344Z] started: Fri Aug 16 17:22:45 GMT 2024 [2024-08-16T18:43:01.344Z] Additional options from @modules: --add-modules java.management [2024-08-16T18:43:01.344Z] Mode: agentvm [2024-08-16T18:43:01.344Z] Agent id: 3 [2024-08-16T18:43:01.344Z] finished: Fri Aug 16 17:22:46 GMT 2024 [2024-08-16T18:43:01.344Z] elapsed time (seconds): 1.441 [2024-08-16T18:43:01.344Z] configuration: [2024-08-16T18:43:01.344Z] Boot Layer (javac runtime environment) [2024-08-16T18:43:01.344Z] class path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-08-16T18:43:01.344Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-08-16T18:43:01.344Z] patch: java.base /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/patches/java.base [2024-08-16T18:43:01.344Z] [2024-08-16T18:43:01.344Z] javac compilation environment [2024-08-16T18:43:01.344Z] add modules: java.management [2024-08-16T18:43:01.344Z] source path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-08-16T18:43:01.344Z] class path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d [2024-08-16T18:43:01.344Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib [2024-08-16T18:43:01.344Z] [2024-08-16T18:43:01.344Z] rerun: [2024-08-16T18:43:01.344Z] cd /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/scratch/2 && \ [2024-08-16T18:43:01.344Z] DISPLAY=:0 \ [2024-08-16T18:43:01.344Z] HOME=/home/jenkins \ [2024-08-16T18:43:01.344Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-08-16T18:43:01.344Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/javac \ [2024-08-16T18:43:01.344Z] -J-ea \ [2024-08-16T18:43:01.344Z] -J-esa \ [2024-08-16T18:43:01.344Z] -J-Xmx512m \ [2024-08-16T18:43:01.344Z] -J-XX:-UseCompressedOops \ [2024-08-16T18:43:01.344Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2024-08-16T18:43:01.344Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2024-08-16T18:43:01.344Z] -J-Dtest.compiler.opts= \ [2024-08-16T18:43:01.344Z] -J-Dtest.java.opts= \ [2024-08-16T18:43:01.344Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.344Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.344Z] -J-Dtest.timeout.factor=8.0 \ [2024-08-16T18:43:01.344Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2024-08-16T18:43:01.344Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2024-08-16T18:43:01.344Z] -J-Dtest.name=java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.344Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.344Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean \ [2024-08-16T18:43:01.344Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d \ [2024-08-16T18:43:01.344Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.344Z] -J-Dtest.modules=java.management \ [2024-08-16T18:43:01.344Z] --add-modules java.management \ [2024-08-16T18:43:01.345Z] -d /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.345Z] -sourcepath /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-08-16T18:43:01.345Z] -classpath /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib/ClassFileInstaller.java [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] ACTION: driver -- Passed. Execution successful [2024-08-16T18:43:01.345Z] REASON: User specified action: run driver ClassFileInstaller sun.hotspot.WhiteBox sun.hotspot.WhiteBox$WhiteBoxPermission [2024-08-16T18:43:01.345Z] TIME: 6.217 seconds [2024-08-16T18:43:01.345Z] messages: [2024-08-16T18:43:01.345Z] command: driver ClassFileInstaller sun.hotspot.WhiteBox sun.hotspot.WhiteBox$WhiteBoxPermission [2024-08-16T18:43:01.345Z] reason: User specified action: run driver ClassFileInstaller sun.hotspot.WhiteBox sun.hotspot.WhiteBox$WhiteBoxPermission [2024-08-16T18:43:01.345Z] started: Fri Aug 16 17:22:46 GMT 2024 [2024-08-16T18:43:01.345Z] Mode: agentvm [2024-08-16T18:43:01.345Z] Agent id: 10 [2024-08-16T18:43:01.345Z] finished: Fri Aug 16 17:22:52 GMT 2024 [2024-08-16T18:43:01.345Z] elapsed time (seconds): 6.217 [2024-08-16T18:43:01.345Z] configuration: [2024-08-16T18:43:01.345Z] Boot Layer [2024-08-16T18:43:01.345Z] class path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/javatest.jar [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/jtreg.jar [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/junit-platform-console-standalone-1.9.2.jar [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2024-08-16T18:43:01.345Z] patch: java.base /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/patches/java.base [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] Test Layer [2024-08-16T18:43:01.345Z] class path: /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] rerun: [2024-08-16T18:43:01.345Z] cd /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/scratch/2 && \ [2024-08-16T18:43:01.345Z] DISPLAY=:0 \ [2024-08-16T18:43:01.345Z] HOME=/home/jenkins \ [2024-08-16T18:43:01.345Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-08-16T18:43:01.345Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/java \ [2024-08-16T18:43:01.345Z] -Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2024-08-16T18:43:01.345Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2024-08-16T18:43:01.345Z] -Dtest.compiler.opts= \ [2024-08-16T18:43:01.345Z] -Dtest.java.opts= \ [2024-08-16T18:43:01.345Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.345Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.345Z] -Dtest.timeout.factor=8.0 \ [2024-08-16T18:43:01.345Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2024-08-16T18:43:01.345Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2024-08-16T18:43:01.345Z] -Dtest.name=java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.345Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.345Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean \ [2024-08-16T18:43:01.345Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-08-16T18:43:01.345Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d \ [2024-08-16T18:43:01.345Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.345Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.345Z] -Dtest.modules=java.management \ [2024-08-16T18:43:01.345Z] -classpath /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2024-08-16T18:43:01.345Z] ClassFileInstaller sun.hotspot.WhiteBox 'sun.hotspot.WhiteBox$WhiteBoxPermission' [2024-08-16T18:43:01.345Z] STDERR: [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] JavaTest Message: Test complete. [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] ACTION: build -- Passed. All files up to date [2024-08-16T18:43:01.345Z] REASON: Named class compiled on demand [2024-08-16T18:43:01.345Z] TIME: 0.01 seconds [2024-08-16T18:43:01.345Z] messages: [2024-08-16T18:43:01.345Z] command: build LowMemoryTest [2024-08-16T18:43:01.345Z] reason: Named class compiled on demand [2024-08-16T18:43:01.345Z] started: Fri Aug 16 17:22:52 GMT 2024 [2024-08-16T18:43:01.345Z] finished: Fri Aug 16 17:22:52 GMT 2024 [2024-08-16T18:43:01.345Z] elapsed time (seconds): 0.01 [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] ACTION: main -- Error. Program `/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 4800000ms, elapsed time including timeout handling was 4802416ms). [2024-08-16T18:43:01.345Z] REASON: User specified action: run main/othervm/timeout=600 -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI -Xbootclasspath/a:. LowMemoryTest [2024-08-16T18:43:01.345Z] TIME: 4802.493 seconds [2024-08-16T18:43:01.345Z] messages: [2024-08-16T18:43:01.345Z] command: main -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI -Xbootclasspath/a:. LowMemoryTest [2024-08-16T18:43:01.345Z] reason: User specified action: run main/othervm/timeout=600 -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI -Xbootclasspath/a:. LowMemoryTest [2024-08-16T18:43:01.345Z] started: Fri Aug 16 17:22:52 GMT 2024 [2024-08-16T18:43:01.345Z] Mode: othervm [/othervm specified] [2024-08-16T18:43:01.345Z] Additional options from @modules: --add-modules java.management [2024-08-16T18:43:01.345Z] Timeout information: [2024-08-16T18:43:01.345Z] Running jstack on process 2942661 [2024-08-16T18:43:01.345Z] 2024-08-16 18:42:55 [2024-08-16T18:43:01.345Z] Full thread dump OpenJDK 64-Bit Server VM (11.0.25-beta+3-ea mixed mode): [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] Threads class SMR info: [2024-08-16T18:43:01.345Z] _java_thread_list=0x0000770858000c40, length=14, elements={ [2024-08-16T18:43:01.345Z] 0x00007708bc029000, 0x00007708bc118800, 0x00007708bc11c800, 0x00007708bc135800, [2024-08-16T18:43:01.345Z] 0x00007708bc13b800, 0x00007708bc13d800, 0x00007708bc13f800, 0x00007708bc141800, [2024-08-16T18:43:01.345Z] 0x00007708bc1b8800, 0x00007708bc23e800, 0x000077084408b800, 0x0000770844094000, [2024-08-16T18:43:01.345Z] 0x0000770844081000, 0x0000770858001000 [2024-08-16T18:43:01.345Z] } [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "main" #1 prio=5 os_prio=0 cpu=236.39ms elapsed=4802.38s tid=0x00007708bc029000 nid=0x2ce6c7 in Object.wait() [0x00007708c1b6d000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: WAITING (on object monitor) [2024-08-16T18:43:01.345Z] at java.lang.Object.wait(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.345Z] - waiting on <0x0000770882841078> (a java.lang.Thread) [2024-08-16T18:43:01.345Z] at java.lang.Thread.join(java.base@11.0.25-beta/Thread.java:1300) [2024-08-16T18:43:01.345Z] - waiting to re-lock in wait() <0x0000770882841078> (a java.lang.Thread) [2024-08-16T18:43:01.345Z] at java.lang.Thread.join(java.base@11.0.25-beta/Thread.java:1375) [2024-08-16T18:43:01.345Z] at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:85) [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "Reference Handler" #2 daemon prio=10 os_prio=0 cpu=0.59ms elapsed=4802.16s tid=0x00007708bc118800 nid=0x2ce6d0 waiting on condition [0x00007708a438d000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.345Z] at java.lang.ref.Reference.waitForReferencePendingList(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.345Z] at java.lang.ref.Reference.processPendingReferences(java.base@11.0.25-beta/Reference.java:241) [2024-08-16T18:43:01.345Z] at java.lang.ref.Reference$ReferenceHandler.run(java.base@11.0.25-beta/Reference.java:213) [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "Finalizer" #3 daemon prio=8 os_prio=0 cpu=0.19ms elapsed=4802.15s tid=0x00007708bc11c800 nid=0x2ce6d1 in Object.wait() [0x00007708757fd000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: WAITING (on object monitor) [2024-08-16T18:43:01.345Z] at java.lang.Object.wait(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.345Z] - waiting on <0x0000770882859c80> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-16T18:43:01.345Z] at java.lang.ref.ReferenceQueue.remove(java.base@11.0.25-beta/ReferenceQueue.java:155) [2024-08-16T18:43:01.345Z] - waiting to re-lock in wait() <0x0000770882859c80> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-16T18:43:01.345Z] at java.lang.ref.ReferenceQueue.remove(java.base@11.0.25-beta/ReferenceQueue.java:176) [2024-08-16T18:43:01.345Z] at java.lang.ref.Finalizer$FinalizerThread.run(java.base@11.0.25-beta/Finalizer.java:170) [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "Signal Dispatcher" #4 daemon prio=9 os_prio=0 cpu=0.42ms elapsed=4802.05s tid=0x00007708bc135800 nid=0x2ce6d2 runnable [0x0000000000000000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "Service Thread" #5 daemon prio=9 os_prio=0 cpu=0.12ms elapsed=4802.03s tid=0x00007708bc13b800 nid=0x2ce6d3 runnable [0x0000000000000000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "C2 CompilerThread0" #6 daemon prio=9 os_prio=0 cpu=2023.89ms elapsed=4802.03s tid=0x00007708bc13d800 nid=0x2ce6d4 waiting on condition [0x0000000000000000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.345Z] No compile task [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "C1 CompilerThread0" #8 daemon prio=9 os_prio=0 cpu=371.68ms elapsed=4802.01s tid=0x00007708bc13f800 nid=0x2ce6d5 waiting on condition [0x0000000000000000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.345Z] No compile task [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "Sweeper thread" #9 daemon prio=9 os_prio=0 cpu=0.10ms elapsed=4802.00s tid=0x00007708bc141800 nid=0x2ce6d6 runnable [0x0000000000000000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "Common-Cleaner" #10 daemon prio=8 os_prio=0 cpu=4.75ms elapsed=4801.66s tid=0x00007708bc1b8800 nid=0x2ce6d7 in Object.wait() [0x00007708747fd000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-08-16T18:43:01.345Z] at java.lang.Object.wait(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.345Z] - waiting on <0x00007708829ed6f0> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-16T18:43:01.345Z] at java.lang.ref.ReferenceQueue.remove(java.base@11.0.25-beta/ReferenceQueue.java:155) [2024-08-16T18:43:01.345Z] - waiting to re-lock in wait() <0x00007708829ed6f0> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-16T18:43:01.345Z] at jdk.internal.ref.CleanerImpl.run(java.base@11.0.25-beta/CleanerImpl.java:148) [2024-08-16T18:43:01.345Z] at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-16T18:43:01.345Z] at jdk.internal.misc.InnocuousThread.run(java.base@11.0.25-beta/InnocuousThread.java:161) [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "MainThread" #11 prio=5 os_prio=0 cpu=229.83ms elapsed=4800.56s tid=0x00007708bc23e800 nid=0x2ce6ed in Object.wait() [0x00007708743ec000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: WAITING (on object monitor) [2024-08-16T18:43:01.345Z] at java.lang.Object.wait(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.345Z] - waiting on <0x00007708828417a8> (a java.lang.ProcessImpl) [2024-08-16T18:43:01.345Z] at java.lang.Object.wait(java.base@11.0.25-beta/Object.java:328) [2024-08-16T18:43:01.345Z] at java.lang.ProcessImpl.waitFor(java.base@11.0.25-beta/ProcessImpl.java:495) [2024-08-16T18:43:01.345Z] - waiting to re-lock in wait() <0x00007708828417a8> (a java.lang.ProcessImpl) [2024-08-16T18:43:01.345Z] at jdk.test.lib.process.ProcessTools$ProcessImpl.waitFor(ProcessTools.java:610) [2024-08-16T18:43:01.345Z] at LowMemoryTest.traceTest(LowMemoryTest.java:141) [2024-08-16T18:43:01.345Z] at LowMemoryTest.main(LowMemoryTest.java:86) [2024-08-16T18:43:01.345Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.345Z] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@11.0.25-beta/NativeMethodAccessorImpl.java:62) [2024-08-16T18:43:01.345Z] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@11.0.25-beta/DelegatingMethodAccessorImpl.java:43) [2024-08-16T18:43:01.345Z] at java.lang.reflect.Method.invoke(java.base@11.0.25-beta/Method.java:566) [2024-08-16T18:43:01.345Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2024-08-16T18:43:01.345Z] at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "process reaper (pid 2942870)" #12 daemon prio=10 os_prio=0 cpu=1.72ms elapsed=4798.60s tid=0x000077084408b800 nid=0x2ce706 runnable [0x00007708a406d000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.345Z] at java.lang.ProcessHandleImpl.waitForProcessExit0(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.345Z] at java.lang.ProcessHandleImpl$1.run(java.base@11.0.25-beta/ProcessHandleImpl.java:145) [2024-08-16T18:43:01.345Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@11.0.25-beta/ThreadPoolExecutor.java:1128) [2024-08-16T18:43:01.345Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@11.0.25-beta/ThreadPoolExecutor.java:628) [2024-08-16T18:43:01.345Z] at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-16T18:43:01.345Z] [2024-08-16T18:43:01.345Z] "Thread-5" #17 daemon prio=5 os_prio=0 cpu=22094.88ms elapsed=4783.69s tid=0x0000770844094000 nid=0x2ce797 runnable [0x000077083fdfd000] [2024-08-16T18:43:01.345Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.345Z] at java.io.FileInputStream.readBytes(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.345Z] at java.io.FileInputStream.read(java.base@11.0.25-beta/FileInputStream.java:279) [2024-08-16T18:43:01.345Z] at java.io.BufferedInputStream.read1(java.base@11.0.25-beta/BufferedInputStream.java:290) [2024-08-16T18:43:01.345Z] at java.io.BufferedInputStream.read(java.base@11.0.25-beta/BufferedInputStream.java:351) [2024-08-16T18:43:01.345Z] - locked <0x0000770882838710> (a java.lang.ProcessImpl$ProcessPipeInputStream) [2024-08-16T18:43:01.345Z] at java.io.BufferedInputStream.fill(java.base@11.0.25-beta/BufferedInputStream.java:252) [2024-08-16T18:43:01.345Z] at java.io.BufferedInputStream.read1(java.base@11.0.25-beta/BufferedInputStream.java:292) [2024-08-16T18:43:01.345Z] at java.io.BufferedInputStream.read(java.base@11.0.25-beta/BufferedInputStream.java:351) [2024-08-16T18:43:01.345Z] - locked <0x000077088285adc0> (a java.io.BufferedInputStream) [2024-08-16T18:43:01.345Z] at java.io.FilterInputStream.read(java.base@11.0.25-beta/FilterInputStream.java:107) [2024-08-16T18:43:01.345Z] at jdk.test.lib.process.StreamPumper.run(StreamPumper.java:109) [2024-08-16T18:43:01.346Z] at java.util.concurrent.Executors$RunnableAdapter.call(java.base@11.0.25-beta/Executors.java:515) [2024-08-16T18:43:01.346Z] at java.util.concurrent.FutureTask.run(java.base@11.0.25-beta/FutureTask.java:264) [2024-08-16T18:43:01.346Z] at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "Thread-6" #18 daemon prio=5 os_prio=0 cpu=0.09ms elapsed=4783.68s tid=0x0000770844081000 nid=0x2ce799 runnable [0x000077083fbfd000] [2024-08-16T18:43:01.346Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.346Z] at java.io.FileInputStream.readBytes(java.base@11.0.25-beta/Native Method) [2024-08-16T18:43:01.346Z] at java.io.FileInputStream.read(java.base@11.0.25-beta/FileInputStream.java:279) [2024-08-16T18:43:01.346Z] at java.io.BufferedInputStream.read1(java.base@11.0.25-beta/BufferedInputStream.java:290) [2024-08-16T18:43:01.346Z] at java.io.BufferedInputStream.read(java.base@11.0.25-beta/BufferedInputStream.java:351) [2024-08-16T18:43:01.346Z] - locked <0x000077088282f918> (a java.lang.ProcessImpl$ProcessPipeInputStream) [2024-08-16T18:43:01.346Z] at java.io.BufferedInputStream.fill(java.base@11.0.25-beta/BufferedInputStream.java:252) [2024-08-16T18:43:01.346Z] at java.io.BufferedInputStream.read1(java.base@11.0.25-beta/BufferedInputStream.java:292) [2024-08-16T18:43:01.346Z] at java.io.BufferedInputStream.read(java.base@11.0.25-beta/BufferedInputStream.java:351) [2024-08-16T18:43:01.346Z] - locked <0x000077088285d1e0> (a java.io.BufferedInputStream) [2024-08-16T18:43:01.346Z] at java.io.FilterInputStream.read(java.base@11.0.25-beta/FilterInputStream.java:107) [2024-08-16T18:43:01.346Z] at jdk.test.lib.process.StreamPumper.run(StreamPumper.java:109) [2024-08-16T18:43:01.346Z] at java.util.concurrent.Executors$RunnableAdapter.call(java.base@11.0.25-beta/Executors.java:515) [2024-08-16T18:43:01.346Z] at java.util.concurrent.FutureTask.run(java.base@11.0.25-beta/FutureTask.java:264) [2024-08-16T18:43:01.346Z] at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "Attach Listener" #19 daemon prio=9 os_prio=0 cpu=0.55ms elapsed=0.12s tid=0x0000770858001000 nid=0x2d0537 waiting on condition [0x0000000000000000] [2024-08-16T18:43:01.346Z] java.lang.Thread.State: RUNNABLE [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "VM Thread" os_prio=0 cpu=351.86ms elapsed=4802.19s tid=0x00007708bc10e800 nid=0x2ce6cf runnable [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "GC Thread#0" os_prio=0 cpu=146.10ms elapsed=4802.38s tid=0x00007708bc044000 nid=0x2ce6c9 runnable [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "GC Thread#1" os_prio=0 cpu=134.45ms elapsed=4753.65s tid=0x000077086c001000 nid=0x2ce866 runnable [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "GC Thread#2" os_prio=0 cpu=154.47ms elapsed=4753.63s tid=0x000077086c002000 nid=0x2ce867 runnable [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "GC Thread#3" os_prio=0 cpu=117.09ms elapsed=4753.60s tid=0x000077086c003800 nid=0x2ce868 runnable [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "G1 Main Marker" os_prio=0 cpu=1.07ms elapsed=4802.32s tid=0x00007708bc050800 nid=0x2ce6cb runnable [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "G1 Conc#0" os_prio=0 cpu=0.08ms elapsed=4802.32s tid=0x00007708bc052800 nid=0x2ce6cc runnable [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "G1 Refine#0" os_prio=0 cpu=0.79ms elapsed=4802.30s tid=0x00007708bc0af800 nid=0x2ce6cd runnable [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] "G1 Young RemSet Sampling" os_prio=0 cpu=709.28ms elapsed=4802.25s tid=0x00007708bc0b1800 nid=0x2ce6ce runnable [2024-08-16T18:43:01.346Z] "VM Periodic Task Thread" os_prio=0 cpu=1837.98ms elapsed=4801.06s tid=0x00007708bc204800 nid=0x2ce6de waiting on condition [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] JNI global refs: 8, weak refs: 0 [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] --- Timeout information end. [2024-08-16T18:43:01.346Z] finished: Fri Aug 16 18:42:55 GMT 2024 [2024-08-16T18:43:01.346Z] elapsed time (seconds): 4802.493 [2024-08-16T18:43:01.346Z] configuration: [2024-08-16T18:43:01.346Z] Boot Layer [2024-08-16T18:43:01.346Z] add modules: java.management [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] STDOUT: [2024-08-16T18:43:01.346Z] [2024-08-16T18:43:01.346Z] ========= Tracing of subprocess LowMemoryTest$TestMain, -XX:+UseSerialGC ========= [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC]:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/java -ea -esa -Xmx512m -XX:-UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib -Xlog:gc*=debug -Xmn8388608 -XX:-UseLargePages -XX:+UseSerialGC LowMemoryTest$TestMain trace [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [0.027s][debug][gc,heap] Minimum heap 33554432 Initial heap 100663296 Maximum heap 536870912 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [0.028s][info ][gc ] Using Serial [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Selected memory pool for low memory detection. [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Memory Pool name: Tenured Gen [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Type: Heap memory [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Memory Usage: init = 92274688(90112K) used = 0(0K) committed = 92274688(90112K) max = 528482304(516096K) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Threshold: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] ThresholdCount: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Manager = [MarkSweepCompact] [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Setting threshold for Tenured Gen from 0 to 16779264. Current used = 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][debug][gc,heap] GC(0) Heap before GC invocations=0 (full 0): def new generation total 7424K, used 6656K [0x00007053f4000000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][debug][gc,heap] GC(0) eden space 6656K, 100% used [0x00007053f4000000, 0x00007053f4680000, 0x00007053f4680000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][debug][gc,heap] GC(0) from space 768K, 0% used [0x00007053f4680000, 0x00007053f4680000, 0x00007053f4740000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][debug][gc,heap] GC(0) to space 768K, 0% used [0x00007053f4740000, 0x00007053f4740000, 0x00007053f4800000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][debug][gc,heap] GC(0) tenured generation total 90112K, used 0K [0x00007053f4800000, 0x00007053fa000000, 0x0000705414000000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][debug][gc,heap] GC(0) the space 90112K, 0% used [0x00007053f4800000, 0x00007053f4800000, 0x00007053f4800200, 0x00007053fa000000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][debug][gc,heap] GC(0) Metaspace used 6757K, capacity 6932K, committed 7168K, reserved 8192K [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][info ][gc,start] GC(0) Pause Young (Allocation Failure) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.832s][debug][gc,tlab ] GC(0) TLAB totals: thrds: 5 refills: 50 max: 44 slow allocs: 0 max 0 waste: 8.4% gc: 520504B max: 136312B slow: 51944B max: 48768B fast: 0B max: 0B [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.847s][debug][gc,ref ] GC(0) Skipped phase1 of Reference Processing due to unavailable references [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.847s][debug][gc,ref ] GC(0) Skipped phase2 of Reference Processing due to unavailable references [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.847s][debug][gc,ref ] GC(0) Skipped phase3 of Reference Processing due to unavailable references [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.847s][debug][gc,ref ] GC(0) Skipped phase4 of Reference Processing due to unavailable references [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.848s][debug][gc,phases,ref] GC(0) Reference Processing: 0.7ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.848s][debug][gc,phases,ref] GC(0) Reconsider SoftReferences: 0.2ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.848s][debug][gc,phases,ref] GC(0) SoftRef: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.848s][debug][gc,phases,ref] GC(0) Notify Soft/WeakReferences: 0.2ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.848s][debug][gc,phases,ref] GC(0) SoftRef: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) WeakRef: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) FinalRef: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Total: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Notify and keep alive finalizable: 0.2ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) FinalRef: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Notify PhantomReferences: 0.2ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) PhantomRef: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) SoftReference: [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Discovered: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Cleared: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) WeakReference: [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Discovered: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Cleared: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) FinalReference: [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Discovered: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Cleared: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) PhantomReference: [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Discovered: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,phases,ref] GC(0) Cleared: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,age ] GC(0) Desired survivor size 393216 bytes, new threshold 1 (max threshold 15) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][info ][gc,heap ] GC(0) DefNew: 6656K->768K(7424K) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][info ][gc,heap ] GC(0) Tenured: 0K->964K(90112K) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][info ][gc,metaspace ] GC(0) Metaspace: 6757K(7168K)->6757K(7168K) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][info ][gc ] GC(0) Pause Young (Allocation Failure) 6M->1M(95M) 16.940ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][info ][gc,cpu ] GC(0) User=0.01s Sys=0.00s Real=0.02s [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,heap ] GC(0) Heap after GC invocations=1 (full 0): def new generation total 7424K, used 768K [0x00007053f4000000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,heap ] GC(0) eden space 6656K, 0% used [0x00007053f4000000, 0x00007053f4000000, 0x00007053f4680000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,heap ] GC(0) from space 768K, 100% used [0x00007053f4740000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,heap ] GC(0) to space 768K, 0% used [0x00007053f4680000, 0x00007053f4680000, 0x00007053f4740000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,heap ] GC(0) tenured generation total 90112K, used 964K [0x00007053f4800000, 0x00007053fa000000, 0x0000705414000000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,heap ] GC(0) the space 90112K, 1% used [0x00007053f4800000, 0x00007053f48f1238, 0x00007053f48f1400, 0x00007053fa000000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [4.849s][debug][gc,heap ] GC(0) Metaspace used 6757K, capacity 6932K, committed 7168K, reserved 8192K [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] AllocatorThread is doing task 1 phase 1 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Iteration 1: before allocation 987704 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Notification for Tenured Gen [type = java.management.memory.threshold.exceeded after allocation 17766992 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] count = 1] [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] usage = init = 92274688(90112K) used = 17766992(17350K) committed = 92274688(90112K) max = 528482304(516096K) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] AllocatorThread done task 1 phase 2 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread is doing task 1 phase 2 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread clearing allocated objects. [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][debug][gc,heap ] GC(1) Heap before GC invocations=1 (full 0): def new generation total 7424K, used 2558K [0x00007053f4000000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][debug][gc,heap ] GC(1) eden space 6656K, 26% used [0x00007053f4000000, 0x00007053f41bf8d8, 0x00007053f4680000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][debug][gc,heap ] GC(1) from space 768K, 100% used [0x00007053f4740000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][debug][gc,heap ] GC(1) to space 768K, 0% used [0x00007053f4680000, 0x00007053f4680000, 0x00007053f4740000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][debug][gc,heap ] GC(1) tenured generation total 90112K, used 17350K [0x00007053f4800000, 0x00007053fa000000, 0x0000705414000000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][debug][gc,heap ] GC(1) the space 90112K, 19% used [0x00007053f4800000, 0x00007053f58f1a50, 0x00007053f58f1c00, 0x00007053fa000000) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][debug][gc,heap ] GC(1) Metaspace used 7452K, capacity 7638K, committed 7680K, reserved 8192K [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][info ][gc,start ] GC(1) Pause Full (System.gc()) [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][debug][gc,tlab ] GC(1) TLAB totals: thrds: 4 refills: 21 max: 14 slow allocs: 1 max 1 waste: 14.9% gc: 253760B max: 83224B slow: 19656B max: 15136B fast: 0B max: 0B [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.328s][info ][gc,phases,start] GC(1) Phase 1: Mark live objects [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,start] GC(1) Reference Processing [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,ref ] GC(1) Skipped phase1 of Reference Processing due to unavailable references [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,ref ] GC(1) Skipped phase3 of Reference Processing due to unavailable references [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Reference Processing: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Reconsider SoftReferences: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) SoftRef: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Notify Soft/WeakReferences: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) SoftRef: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) WeakRef: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) FinalRef: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Total: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Notify and keep alive finalizable: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) FinalRef: skipped [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Notify PhantomReferences: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) PhantomRef: 0.0ms [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) SoftReference: [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Discovered: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Cleared: 0 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) WeakReference: [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Discovered: 242 [2024-08-16T18:43:01.346Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Cleared: 25 [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) FinalReference: [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Discovered: 0 [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Cleared: 0 [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) PhantomReference: [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Discovered: 53 [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,ref ] GC(1) Cleared: 0 [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases ] GC(1) Reference Processing 0.222ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,start] GC(1) Weak Processing [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases ] GC(1) Weak Processing 0.029ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,start] GC(1) Class Unloading [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,start] GC(1) ClassLoaderData [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases ] GC(1) ClassLoaderData 0.085ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,start] GC(1) ProtectionDomainCacheTable [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases ] GC(1) ProtectionDomainCacheTable 0.010ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,start] GC(1) ResolvedMethodTable [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases ] GC(1) ResolvedMethodTable 0.024ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases ] GC(1) Class Unloading 0.280ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.336s][debug][gc,phases,start] GC(1) Scrub String Table [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.337s][debug][gc,phases ] GC(1) Scrub String Table 0.082ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.337s][debug][gc,phases,start] GC(1) Scrub Symbol Table [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.337s][debug][gc,phases ] GC(1) Scrub Symbol Table 0.658ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.337s][info ][gc,phases ] GC(1) Phase 1: Mark live objects 9.515ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.337s][info ][gc,phases,start] GC(1) Phase 2: Compute new object addresses [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.338s][info ][gc,phases ] GC(1) Phase 2: Compute new object addresses 1.060ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.338s][info ][gc,phases,start] GC(1) Phase 3: Adjust pointers [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][info ][gc,phases ] GC(1) Phase 3: Adjust pointers 20.509ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][info ][gc,phases,start] GC(1) Phase 4: Move objects [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][info ][gc,phases ] GC(1) Phase 4: Move objects 0.537ms [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][info ][gc,heap ] GC(1) DefNew: 2558K->0K(7424K) [2024-08-16T18:43:01.348Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][info ][gc,heap ] GC(1) Tenured: 17350K->1949K(90112K) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][info ][gc,metaspace ] GC(1) Metaspace: 7452K(7680K)->7452K(7680K) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][info ][gc ] GC(1) Pause Full (System.gc()) 19M->1M(95M) 31.872ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][info ][gc,cpu ] GC(1) User=0.01s Sys=0.00s Real=0.04s [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.359s][debug][gc,heap ] GC(1) Heap after GC invocations=2 (full 1): def new generation total 7424K, used 0K [0x00007053f4000000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.360s][debug][gc,heap ] GC(1) eden space 6656K, 0% used [0x00007053f4000000, 0x00007053f4000000, 0x00007053f4680000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.360s][debug][gc,heap ] GC(1) from space 768K, 0% used [0x00007053f4740000, 0x00007053f4740000, 0x00007053f4800000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.360s][debug][gc,heap ] GC(1) to space 768K, 0% used [0x00007053f4680000, 0x00007053f4680000, 0x00007053f4740000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.360s][debug][gc,heap ] GC(1) tenured generation total 90112K, used 1949K [0x00007053f4800000, 0x00007053fa000000, 0x0000705414000000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.360s][debug][gc,heap ] GC(1) the space 90112K, 2% used [0x00007053f4800000, 0x00007053f49e77e0, 0x00007053f49e7800, 0x00007053fa000000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.360s][debug][gc,heap ] GC(1) Metaspace used 7452K, capacity 7638K, committed 7680K, reserved 8192K [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread called 1 time(s) MemoryMXBean.gc(). [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread done task 1 phase 3 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] AllocatorThread is doing task 2 phase 3 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Iteration 1: before allocation 1996768 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] after allocation 18776056 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Notification for Tenured Gen [type = java.management.memory.threshold.exceeded count = 2] [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] usage = init = 92274688(90112K) used = 18776056(18335K) committed = 92274688(90112K) max = 528482304(516096K) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] AllocatorThread done task 2 phase 4 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread is doing task 2 phase 4 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread clearing allocated objects. [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][debug][gc,heap ] GC(2) Heap before GC invocations=2 (full 1): def new generation total 7424K, used 538K [0x00007053f4000000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][debug][gc,heap ] GC(2) eden space 6656K, 8% used [0x00007053f4000000, 0x00007053f4086bd0, 0x00007053f4680000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][debug][gc,heap ] GC(2) from space 768K, 0% used [0x00007053f4740000, 0x00007053f4740000, 0x00007053f4800000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][debug][gc,heap ] GC(2) to space 768K, 0% used [0x00007053f4680000, 0x00007053f4680000, 0x00007053f4740000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][debug][gc,heap ] GC(2) tenured generation total 90112K, used 18335K [0x00007053f4800000, 0x00007053fa000000, 0x0000705414000000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][debug][gc,heap ] GC(2) the space 90112K, 20% used [0x00007053f4800000, 0x00007053f59e7ff8, 0x00007053f59e8000, 0x00007053fa000000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][debug][gc,heap ] GC(2) Metaspace used 7476K, capacity 7667K, committed 7680K, reserved 8192K [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][info ][gc,start ] GC(2) Pause Full (System.gc()) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][debug][gc,tlab ] GC(2) TLAB totals: thrds: 3 refills: 7 max: 5 slow allocs: 2 max 1 waste: 42.9% gc: 231584B max: 130808B slow: 4480B max: 4480B fast: 0B max: 0B [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.615s][info ][gc,phases,start] GC(2) Phase 1: Mark live objects [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,start] GC(2) Reference Processing [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,ref ] GC(2) Skipped phase1 of Reference Processing due to unavailable references [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,ref ] GC(2) Skipped phase3 of Reference Processing due to unavailable references [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Reference Processing: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Reconsider SoftReferences: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) SoftRef: skipped [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Notify Soft/WeakReferences: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) SoftRef: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) WeakRef: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) FinalRef: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Total: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Notify and keep alive finalizable: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) FinalRef: skipped [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Notify PhantomReferences: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) PhantomRef: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) SoftReference: [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Discovered: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Cleared: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) WeakReference: [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Discovered: 59 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Cleared: 29 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) FinalReference: [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Discovered: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Cleared: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) PhantomReference: [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Discovered: 2 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,ref ] GC(2) Cleared: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases ] GC(2) Reference Processing 0.211ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,start] GC(2) Weak Processing [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases ] GC(2) Weak Processing 0.028ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,start] GC(2) Class Unloading [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,start] GC(2) ClassLoaderData [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases ] GC(2) ClassLoaderData 0.020ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,start] GC(2) ProtectionDomainCacheTable [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases ] GC(2) ProtectionDomainCacheTable 0.011ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,start] GC(2) ResolvedMethodTable [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases ] GC(2) ResolvedMethodTable 0.027ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases ] GC(2) Class Unloading 0.213ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,start] GC(2) Scrub String Table [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases ] GC(2) Scrub String Table 0.074ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.618s][debug][gc,phases,start] GC(2) Scrub Symbol Table [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.619s][debug][gc,phases ] GC(2) Scrub Symbol Table 0.839ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.619s][info ][gc,phases ] GC(2) Phase 1: Mark live objects 3.683ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.619s][info ][gc,phases,start] GC(2) Phase 2: Compute new object addresses [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.649s][info ][gc,phases ] GC(2) Phase 2: Compute new object addresses 29.401ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.649s][info ][gc,phases,start] GC(2) Phase 3: Adjust pointers [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.651s][info ][gc,phases ] GC(2) Phase 3: Adjust pointers 1.976ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.651s][info ][gc,phases,start] GC(2) Phase 4: Move objects [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.651s][info ][gc,phases ] GC(2) Phase 4: Move objects 0.032ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.651s][info ][gc,heap ] GC(2) DefNew: 538K->0K(7424K) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.651s][info ][gc,heap ] GC(2) Tenured: 18335K->1978K(90112K) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.651s][info ][gc,metaspace ] GC(2) Metaspace: 7476K(7680K)->7476K(7680K) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][info ][gc ] GC(2) Pause Full (System.gc()) 18M->1M(95M) 49.661ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][info ][gc,cpu ] GC(2) User=0.01s Sys=0.00s Real=0.05s [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][debug][gc,heap ] GC(2) Heap after GC invocations=3 (full 2): def new generation total 7424K, used 0K [0x00007053f4000000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][debug][gc,heap ] GC(2) eden space 6656K, 0% used [0x00007053f4000000, 0x00007053f4000000, 0x00007053f4680000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][debug][gc,heap ] GC(2) from space 768K, 0% used [0x00007053f4740000, 0x00007053f4740000, 0x00007053f4800000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][debug][gc,heap ] GC(2) to space 768K, 0% used [0x00007053f4680000, 0x00007053f4680000, 0x00007053f4740000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][debug][gc,heap ] GC(2) tenured generation total 90112K, used 1978K [0x00007053f4800000, 0x00007053fa000000, 0x0000705414000000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][debug][gc,heap ] GC(2) the space 90112K, 2% used [0x00007053f4800000, 0x00007053f49ee938, 0x00007053f49eea00, 0x00007053fa000000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.665s][debug][gc,heap ] GC(2) Metaspace used 7476K, capacity 7667K, committed 7680K, reserved 8192K [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread called 1 time(s) MemoryMXBean.gc(). [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread done task 2 phase 5 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] AllocatorThread is doing task 3 phase 5 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Iteration 1: before allocation 2025784 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] after allocation 18805072 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] Notification for Tenured Gen [type = java.management.memory.threshold.exceeded count = 3] [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] usage = init = 92274688(90112K) used = 18805072(18364K) committed = 92274688(90112K) max = 528482304(516096K) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] AllocatorThread done task 3 phase 6 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread is doing task 3 phase 6 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] SweeperThread clearing allocated objects. [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][debug][gc,heap ] GC(3) Heap before GC invocations=3 (full 2): def new generation total 7424K, used 270K [0x00007053f4000000, 0x00007053f4800000, 0x00007053f4800000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][debug][gc,heap ] GC(3) eden space 6656K, 4% used [0x00007053f4000000, 0x00007053f4043908, 0x00007053f4680000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][debug][gc,heap ] GC(3) from space 768K, 0% used [0x00007053f4740000, 0x00007053f4740000, 0x00007053f4800000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][debug][gc,heap ] GC(3) to space 768K, 0% used [0x00007053f4680000, 0x00007053f4680000, 0x00007053f4740000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][debug][gc,heap ] GC(3) tenured generation total 90112K, used 18364K [0x00007053f4800000, 0x00007053fa000000, 0x0000705414000000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][debug][gc,heap ] GC(3) the space 90112K, 20% used [0x00007053f4800000, 0x00007053f59ef150, 0x00007053f59ef200, 0x00007053fa000000) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][debug][gc,heap ] GC(3) Metaspace used 7476K, capacity 7667K, committed 7680K, reserved 8192K [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][info ][gc,start ] GC(3) Pause Full (System.gc()) [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][debug][gc,tlab ] GC(3) TLAB totals: thrds: 3 refills: 3 max: 1 slow allocs: 1 max 1 waste: 97.3% gc: 269224B max: 130808B slow: 0B max: 0B fast: 0B max: 0B [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.871s][info ][gc,phases,start] GC(3) Phase 1: Mark live objects [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,start] GC(3) Reference Processing [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,ref ] GC(3) Skipped phase1 of Reference Processing due to unavailable references [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,ref ] GC(3) Skipped phase3 of Reference Processing due to unavailable references [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,ref ] GC(3) Skipped phase4 of Reference Processing due to unavailable references [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Reference Processing: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Reconsider SoftReferences: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) SoftRef: skipped [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Notify Soft/WeakReferences: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) SoftRef: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) WeakRef: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) FinalRef: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Total: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Notify and keep alive finalizable: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) FinalRef: skipped [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Notify PhantomReferences: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) PhantomRef: skipped [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) SoftReference: [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Discovered: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Cleared: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) WeakReference: [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Discovered: 29 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Cleared: 29 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) FinalReference: [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Discovered: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) Cleared: 0 [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) PhantomReference: [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseSerialGC] [5.874s][debug][gc,phases,ref ] GC(3) D [2024-08-16T18:43:01.349Z] [2024-08-16T18:43:01.349Z] ... [2024-08-16T18:43:01.349Z] Output overflow: [2024-08-16T18:43:01.349Z] JT Harness has limited the test output to the text [2024-08-16T18:43:01.349Z] at the beginning and the end, so that you can see how the [2024-08-16T18:43:01.349Z] test began, and how it completed. [2024-08-16T18:43:01.349Z] [2024-08-16T18:43:01.349Z] If you need to see more of the output from the test, [2024-08-16T18:43:01.349Z] set the system property javatest.maxOutputSize to a higher [2024-08-16T18:43:01.349Z] value. The current value is 100000 [2024-08-16T18:43:01.349Z] ... [2024-08-16T18:43:01.349Z] [2024-08-16T18:43:01.349Z] ft/WeakReferences: 0.0ms [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) SoftRef (ms): skipped [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) WeakRef (ms): skipped [2024-08-16T18:43:01.349Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) FinalRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Total (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Notify and keep alive finalizable: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) FinalRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Notify PhantomReferences: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) PhantomRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) SoftReference: [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Discovered: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Cleared: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) WeakReference: [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Discovered: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Cleared: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) FinalReference: [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Discovered: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Cleared: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) PhantomReference: [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.251s][debug][gc,phases,ref ] GC(13245) Discovered: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases,ref ] GC(13245) Cleared: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Weak Processing: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Merge Per-Thread State: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Code Roots Purge: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Redirty Cards: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) DerivedPointerTable Update: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Free Collection Set: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Humongous Reclaim: 0.1ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Start New Collection Set: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Resize TLABs: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,phases ] GC(13245) Expand Heap After Collection: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,phases ] GC(13245) Other: 0.3ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,heap ] GC(13245) Eden regions: 1->0(8) [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,heap ] GC(13245) Survivor regions: 0->0(1) [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,heap ] GC(13245) Old regions: 3->3 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,heap ] GC(13245) Humongous regions: 153->153 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,metaspace ] GC(13245) Metaspace: 7215K(7936K)->7215K(7936K) [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,heap ] GC(13245) Heap after GC invocations=13244 (full 3): garbage-first heap total 361472K, used 158643K [0x000079dab4000000, 0x000079dad4000000) [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,heap ] GC(13245) region size 1024K, 0 young (0K), 0 survivors (0K) [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][debug][gc,heap ] GC(13245) Metaspace used 7215K, capacity 7351K, committed 7936K, reserved 8192K [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc ] GC(13245) Pause Young (Concurrent Start) (G1 Humongous Allocation) 155M->154M(353M) 2.335ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,cpu ] GC(13245) User=0.00s Sys=0.01s Real=0.00s [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc ] GC(13246) Concurrent Cycle [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,marking ] GC(13246) Concurrent Clear Claimed Marks [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,marking ] GC(13246) Concurrent Clear Claimed Marks 0.011ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,marking ] GC(13246) Concurrent Scan Root Regions [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,marking ] GC(13246) Concurrent Scan Root Regions 0.007ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,marking ] GC(13246) Concurrent Mark (4782.252s) [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,marking ] GC(13246) Concurrent Mark From Roots [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.252s][info ][gc,task ] GC(13246) Using 1 workers of 1 for marking [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 180276576 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46342: before allocation 180276576 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 197053792 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,stats ] Marking Stats, task = 0, calls = 4 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,stats ] Elapsed time = 146.41ms, Termination time = -0.00ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,stats ] Step Times (cum): num = 134948, avg = 1.32ms, sd = 3.21ms max = 13.40ms, total = 178033.95ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,stats ] Mark Stats Cache: hits 23214 misses 4 ratio 99.983 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][info ][gc,marking ] GC(13246) Concurrent Mark From Roots 148.997ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][info ][gc,marking ] GC(13246) Concurrent Preclean [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,ref,start ] GC(13246) Preclean SoftReferences [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,ref ] GC(13246) Preclean SoftReferences 0.039ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,ref,start ] GC(13246) Preclean WeakReferences [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,ref ] GC(13246) Preclean WeakReferences 0.050ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,ref,start ] GC(13246) Preclean FinalReferences [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,ref ] GC(13246) Preclean FinalReferences 0.027ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,ref,start ] GC(13246) Preclean PhantomReferences [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,ref ] GC(13246) Preclean PhantomReferences 0.026ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][info ][gc,marking ] GC(13246) Concurrent Preclean 0.192ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][info ][gc,marking ] GC(13246) Concurrent Mark (4782.252s, 4782.401s) 149.215ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][info ][gc,start ] GC(13246) Pause Remark [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.401s][debug][gc,phases,start ] GC(13246) Finalize Marking [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Marking Stats, task = 0, calls = 21 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Elapsed time = 6.11ms, Termination time = 0.08ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Step Times (cum): num = 134965, avg = 1.32ms, sd = 3.21ms max = 13.40ms, total = 178034.03ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Mark Stats Cache: hits 23214 misses 4 ratio 99.983 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Marking Stats, task = 1, calls = 1 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Elapsed time = 4.03ms, Termination time = 0.07ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Step Times (cum): num = 6624, avg = 0.24ms, sd = 0.62ms max = 12.00ms, total = 1575.45ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Mark Stats Cache: hits 0 misses 0 ratio 0.000 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Marking Stats, task = 2, calls = 1 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Elapsed time = 0.22ms, Termination time = 0.08ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Step Times (cum): num = 6624, avg = 0.22ms, sd = 0.58ms max = 11.98ms, total = 1484.22ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Mark Stats Cache: hits 0 misses 0 ratio 0.000 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Marking Stats, task = 3, calls = 1 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Elapsed time = 0.01ms, Termination time = 0.00ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Step Times (cum): num = 6624, avg = 0.00ms, sd = 0.00ms max = 0.07ms, total = 0.16ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] Mark Stats Cache: hits 0 misses 0 ratio 0.000 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases ] GC(13246) Finalize Marking 6.433ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,start ] GC(13246) Reference Processing [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,ref ] GC(13246) Skipped phase1 of Reference Processing due to unavailable references [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,ref ] GC(13246) Skipped phase2 of Reference Processing due to unavailable references [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,ref ] GC(13246) Skipped phase3 of Reference Processing due to unavailable references [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,ref ] GC(13246) Skipped phase4 of Reference Processing due to unavailable references [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Reference Processing: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Reconsider SoftReferences: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) SoftRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Notify Soft/WeakReferences: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) SoftRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) WeakRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) FinalRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Total (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Notify and keep alive finalizable: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) FinalRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Notify PhantomReferences: 0.0ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) PhantomRef (ms): skipped [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) SoftReference: [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Discovered: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Cleared: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) WeakReference: [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Discovered: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Cleared: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) FinalReference: [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Discovered: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Cleared: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) PhantomReference: [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Discovered: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,ref ] GC(13246) Cleared: 0 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases ] GC(13246) Reference Processing 0.252ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,start ] GC(13246) Weak Processing [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases ] GC(13246) Weak Processing 0.034ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,start ] GC(13246) Class Unloading [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,start ] GC(13246) ClassLoaderData [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases ] GC(13246) ClassLoaderData 0.035ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases,start ] GC(13246) ProtectionDomainCacheTable [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.408s][debug][gc,phases ] GC(13246) ProtectionDomainCacheTable 0.017ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][info ][gc,stringtable ] GC(13246) Cleaned string and symbol table, strings: 2683 processed, 0 removed, symbols: 28417 processed, 0 removed [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,phases ] GC(13246) Class Unloading 2.988ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,phases,start ] GC(13246) Flush Task Caches [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,stats ] Mark stats cache hits 23214 misses 4 ratio 99.983 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,phases ] GC(13246) Flush Task Caches 0.026ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,phases,start ] GC(13246) Update Remembered Set Tracking Before Rebuild [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,ergo ] GC(13246) Running G1 Update RemSet Tracking Before Rebuild using 1 workers for 353 regions in heap [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,remset,tracking] GC(13246) Remembered Set Tracking update regions total 353, selected 3 [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,phases ] GC(13246) Update Remembered Set Tracking Before Rebuild 0.121ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.411s][debug][gc,phases,start ] GC(13246) Reclaim Empty Regions [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][debug][gc ] GC(13246) Reclaimed 119 empty regions [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][debug][gc,phases ] GC(13246) Reclaim Empty Regions 0.210ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][debug][gc,phases,start ] GC(13246) Purge Metaspace [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][debug][gc,phases ] GC(13246) Purge Metaspace 0.008ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][debug][gc,phases,start ] GC(13246) Report Object Count [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][debug][gc,phases ] GC(13246) Report Object Count 0.009ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][info ][gc ] GC(13246) Pause Remark 189M->70M(353M) 10.204ms [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][info ][gc,cpu ] GC(13246) User=0.00s Sys=0.00s Real=0.01s [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.412s][info ][gc,marking ] GC(13246) Concurrent Rebuild Remembered Sets [2024-08-16T18:43:01.350Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46343: before allocation 72273248 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 90099040 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46344: before allocation 90099040 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 107924832 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][info ][gc,marking ] GC(13246) Concurrent Rebuild Remembered Sets 149.991ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][info ][gc,start ] GC(13246) Pause Cleanup [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][debug][gc,phases,start ] GC(13246) Update Remembered Set Tracking After Rebuild [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][debug][gc,phases ] GC(13246) Update Remembered Set Tracking After Rebuild 0.033ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][debug][gc,phases,start ] GC(13246) Finalize Concurrent Mark Cleanup [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][debug][gc,ergo ] GC(13246) request young-only gcs (reclaimable percentage not over threshold). candidate old regions: 2 reclaimable: 567024 (0.15) threshold: 5 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][debug][gc,phases ] GC(13246) Finalize Concurrent Mark Cleanup 0.208ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][info ][gc ] GC(13246) Pause Cleanup 104M->104M(353M) 0.281ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][info ][gc,cpu ] GC(13246) User=0.00s Sys=0.00s Real=0.00s [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][info ][gc,marking ] GC(13246) Concurrent Cleanup for Next Mark [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.562s][debug][gc,ergo ] GC(13246) Running G1 Clear Bitmap with 1 workers for 6 work units. [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.563s][info ][gc,marking ] GC(13246) Concurrent Cleanup for Next Mark 0.549ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.563s][info ][gc ] GC(13246) Concurrent Cycle 311.047ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46345: before allocation 107924832 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 125750624 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46346: before allocation 125750624 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 143576416 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46347: before allocation 143576416 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 161402208 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46348: before allocation 161402208 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,ergo,ihop ] Request concurrent cycle initiation (occupancy higher than threshold) occupancy: 163577856B allocation request: 16779288B threshold: 166566297B (45.00) source: concurrent humongous allocation [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,ergo ] Request concurrent cycle initiation (requested by GC cause). GC cause: G1 Humongous Allocation [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,heap ] GC(13247) Heap before GC invocations=13245 (full 3): garbage-first heap total 361472K, used 158643K [0x000079dab4000000, 0x000079dad4000000) [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,heap ] GC(13247) region size 1024K, 1 young (1024K), 0 survivors (0K) [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,heap ] GC(13247) Metaspace used 7215K, capacity 7351K, committed 7936K, reserved 8192K [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,ergo ] GC(13247) Initiate concurrent cycle (concurrent cycle initiation requested) [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][info ][gc,start ] GC(13247) Pause Young (Concurrent Start) (G1 Humongous Allocation) [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][info ][gc,task ] GC(13247) Using 4 workers of 4 for evacuation [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,tlab ] GC(13247) TLAB totals: thrds: 1 refills: 1 max: 1 slow allocs: 7 max 7 waste: 97.8% gc: 164120B max: 164120B slow: 0B max: 0B fast: 0B max: 0B [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,alloc,region ] GC(13247) Mutator Allocation stats, regions: 1, wasted size: 0B ( 0.0%) [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,age ] GC(13247) Desired survivor size 524288 bytes, new threshold 15 (max threshold 15) [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,ergo,cset ] GC(13247) Finish choosing CSet. old: 0 regions, predicted old region time: 0.00ms, time remaining: 199.59 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,task,stats ] GC(13247) GC Termination Stats [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,task,stats ] GC(13247) elapsed --strong roots-- -------termination------- ------waste (KiB)------ [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,task,stats ] GC(13247) thr ms ms % ms % attempts total alloc undo [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.968s][debug][gc,task,stats ] GC(13247) --- --------- --------- ------ --------- ------ -------- ------- ------- ------- [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,task,stats ] GC(13247) 2 0.63 0.63 99.41 0.00 0.04 1 0 0 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,task,stats ] GC(13247) 3 0.64 0.16 24.50 0.48 74.83 1 0 0 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,task,stats ] GC(13247) 0 0.97 0.79 81.55 0.17 17.62 1 0 0 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,task,stats ] GC(13247) 1 0.76 0.57 75.43 0.18 23.97 1 0 0 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ergo ] GC(13247) Running G1 Clear Card Table Task using 1 workers for 1 units of work for 3 regions. [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ref ] GC(13247) Skipped phase1 of Reference Processing due to unavailable references [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ref ] GC(13247) Skipped phase2 of Reference Processing due to unavailable references [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ref ] GC(13247) Skipped phase3 of Reference Processing due to unavailable references [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ref ] GC(13247) Skipped phase4 of Reference Processing due to unavailable references [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ergo ] GC(13247) Running G1 Free Collection Set using 1 workers for collection set length 1 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 2 object size 16779288 start 0x000079dab4200000 with remset 1 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 31 object size 16779288 start 0x000079dab5f00000 with remset 1 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 48 object size 16779288 start 0x000079dab7000000 with remset 0 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 65 object size 16779288 start 0x000079dab8100000 with remset 0 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 82 object size 16779288 start 0x000079dab9200000 with remset 0 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 99 object size 16779288 start 0x000079daba300000 with remset 0 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 116 object size 16779288 start 0x000079dabb400000 with remset 0 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 133 object size 16779288 start 0x000079dabc500000 with remset 0 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,humongous ] GC(13247) Live humongous region 167 object size 16779288 start 0x000079dabe700000 with remset 0 code roots 0 is marked 0 reclaim candidate 0 type array 0 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,plab ] GC(13247) Young PLAB allocation: allocated: 0B, wasted: 0B, unused: 0B, used: 0B, undo waste: 0B, [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,plab ] GC(13247) Young other allocation: region end waste: 0B, regions filled: 0, direct allocated: 0B, failure used: 0B, failure wasted: 0B [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,plab ] GC(13247) Young sizing: calculated: 0B, actual: 2072B [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,plab ] GC(13247) Old PLAB allocation: allocated: 0B, wasted: 0B, unused: 0B, used: 0B, undo waste: 0B, [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,plab ] GC(13247) Old other allocation: region end waste: 0B, regions filled: 1, direct allocated: 0B, failure used: 0B, failure wasted: 0B [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,plab ] GC(13247) Old sizing: calculated: 0B, actual: 2072B [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,alloc,stats ] Old generation allocation in the last mutator period, old gen allocated: 0B, humongous allocated: 124780544B,old gen growth: 0B. [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ihop ] GC(13247) Basic information (value update), threshold: 166566297B (45.00), target occupancy: 370147328B, current occupancy: 162450784B, recent allocation size: 124780544B, recent allocation duration: 706.43ms, recent old gen allocation rate: 176634953.13B/s, recent marking phase length: 0.00ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ihop ] GC(13247) Adaptive IHOP information (value update), threshold: 166566297B (47.37), internal target occupancy: 351639961B, occupancy: 162450784B, additional buffer size: 8388608B, predicted old gen allocation rate: 0.00B/s, predicted marking phase length: 0.00ms, prediction active: false [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,ergo,refine ] GC(13247) Updated Refinement Zones: green: 4, yellow: 12, red: 20 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][info ][gc,phases ] GC(13247) Pre Evacuate Collection Set: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,phases ] GC(13247) Prepare TLABs: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,phases ] GC(13247) Choose Collection Set: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,phases ] GC(13247) Humongous Register: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,phases ] GC(13247) Clear Claimed Marks: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][info ][gc,phases ] GC(13247) Evacuate Collection Set: 1.1ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,phases ] GC(13247) Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 0.8, Diff: 0.8, Sum: 2.0, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,phases ] GC(13247) Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.969s][debug][gc,phases ] GC(13247) Processed Buffers: Min: 0, Avg: 0.2, Max: 1, Diff: 1, Sum: 1, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Scanned Cards: Min: 0, Avg: 31.0, Max: 124, Diff: 124, Sum: 124, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Scanned Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Claimed Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Skipped Cards: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.5, Diff: 0.5, Sum: 0.8, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) GC Worker Total (ms): Min: 0.6, Avg: 0.8, Max: 1.0, Diff: 0.3, Sum: 3.1, Workers: 4 [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,phases ] GC(13247) Post Evacuate Collection Set: 0.2ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Code Roots Fixup: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Clear Card Table: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Reference Processing: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Reconsider SoftReferences: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) SoftRef (ms): skipped [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Notify Soft/WeakReferences: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) SoftRef (ms): skipped [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) WeakRef (ms): skipped [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) FinalRef (ms): skipped [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Total (ms): skipped [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Notify and keep alive finalizable: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) FinalRef (ms): skipped [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Notify PhantomReferences: 0.0ms [2024-08-16T18:43:01.351Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) PhantomRef (ms): skipped [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) SoftReference: [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Discovered: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Cleared: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) WeakReference: [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Discovered: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Cleared: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) FinalReference: [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Discovered: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Cleared: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) PhantomReference: [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Discovered: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases,ref ] GC(13247) Cleared: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Weak Processing: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Merge Per-Thread State: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Code Roots Purge: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Redirty Cards: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) DerivedPointerTable Update: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Free Collection Set: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Humongous Reclaim: 0.1ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Start New Collection Set: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Resize TLABs: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,phases ] GC(13247) Expand Heap After Collection: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,phases ] GC(13247) Other: 0.3ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,heap ] GC(13247) Eden regions: 1->0(8) [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,heap ] GC(13247) Survivor regions: 0->0(1) [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,heap ] GC(13247) Old regions: 3->3 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,heap ] GC(13247) Humongous regions: 153->153 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,metaspace ] GC(13247) Metaspace: 7215K(7936K)->7215K(7936K) [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,heap ] GC(13247) Heap after GC invocations=13246 (full 3): garbage-first heap total 361472K, used 158643K [0x000079dab4000000, 0x000079dad4000000) [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,heap ] GC(13247) region size 1024K, 0 young (0K), 0 survivors (0K) [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][debug][gc,heap ] GC(13247) Metaspace used 7215K, capacity 7351K, committed 7936K, reserved 8192K [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc ] GC(13247) Pause Young (Concurrent Start) (G1 Humongous Allocation) 155M->154M(353M) 2.175ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,cpu ] GC(13247) User=0.00s Sys=0.00s Real=0.00s [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc ] GC(13248) Concurrent Cycle [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,marking ] GC(13248) Concurrent Clear Claimed Marks [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,marking ] GC(13248) Concurrent Clear Claimed Marks 0.009ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,marking ] GC(13248) Concurrent Scan Root Regions [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,marking ] GC(13248) Concurrent Scan Root Regions 0.007ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,marking ] GC(13248) Concurrent Mark (4782.970s) [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,marking ] GC(13248) Concurrent Mark From Roots [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4782.970s][info ][gc,task ] GC(13248) Using 1 workers of 1 for marking [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 180276576 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46349: before allocation 180276576 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 197053792 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,stats ] Marking Stats, task = 0, calls = 4 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,stats ] Elapsed time = 189.62ms, Termination time = 0.00ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,stats ] Step Times (cum): num = 134969, avg = 1.32ms, sd = 3.21ms max = 13.40ms, total = 178062.13ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,stats ] Mark Stats Cache: hits 23214 misses 4 ratio 99.983 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][info ][gc,marking ] GC(13248) Concurrent Mark From Roots 192.103ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][info ][gc,marking ] GC(13248) Concurrent Preclean [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,ref,start ] GC(13248) Preclean SoftReferences [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,ref ] GC(13248) Preclean SoftReferences 0.036ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,ref,start ] GC(13248) Preclean WeakReferences [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,ref ] GC(13248) Preclean WeakReferences 0.046ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,ref,start ] GC(13248) Preclean FinalReferences [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,ref ] GC(13248) Preclean FinalReferences 0.026ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,ref,start ] GC(13248) Preclean PhantomReferences [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][debug][gc,ref ] GC(13248) Preclean PhantomReferences 0.025ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][info ][gc,marking ] GC(13248) Concurrent Preclean 0.176ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.162s][info ][gc,marking ] GC(13248) Concurrent Mark (4782.970s, 4783.162s) 192.304ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.163s][info ][gc,start ] GC(13248) Pause Remark [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.163s][debug][gc,phases,start ] GC(13248) Finalize Marking [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.167s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Marking Stats, task = 0, calls = 21 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Elapsed time = 0.23ms, Termination time = 0.07ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Step Times (cum): num = 134986, avg = 1.32ms, sd = 3.21ms max = 13.40ms, total = 178062.21ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Mark Stats Cache: hits 23214 misses 4 ratio 99.983 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Marking Stats, task = 1, calls = 1 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Elapsed time = 0.15ms, Termination time = 0.07ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Step Times (cum): num = 6625, avg = 0.24ms, sd = 0.62ms max = 12.00ms, total = 1575.52ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Mark Stats Cache: hits 0 misses 0 ratio 0.000 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Marking Stats, task = 2, calls = 1 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Elapsed time = 0.09ms, Termination time = 0.07ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Step Times (cum): num = 6625, avg = 0.22ms, sd = 0.58ms max = 11.98ms, total = 1484.29ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Mark Stats Cache: hits 0 misses 0 ratio 0.000 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Marking Stats, task = 3, calls = 1 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Elapsed time = 0.01ms, Termination time = 0.00ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Step Times (cum): num = 6625, avg = 0.00ms, sd = 0.00ms max = 0.07ms, total = 0.16ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] Mark Stats Cache: hits 0 misses 0 ratio 0.000 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,stats ] --------------------------------------------------------------------- [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases ] GC(13248) Finalize Marking 4.912ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,start ] GC(13248) Reference Processing [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,ref ] GC(13248) Skipped phase1 of Reference Processing due to unavailable references [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,ref ] GC(13248) Skipped phase2 of Reference Processing due to unavailable references [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,ref ] GC(13248) Skipped phase3 of Reference Processing due to unavailable references [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,ref ] GC(13248) Skipped phase4 of Reference Processing due to unavailable references [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Reference Processing: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Reconsider SoftReferences: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) SoftRef (ms): skipped [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Notify Soft/WeakReferences: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) SoftRef (ms): skipped [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) WeakRef (ms): skipped [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) FinalRef (ms): skipped [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Total (ms): skipped [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Notify and keep alive finalizable: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) FinalRef (ms): skipped [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Notify PhantomReferences: 0.0ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) PhantomRef (ms): skipped [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) SoftReference: [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Discovered: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Cleared: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) WeakReference: [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Discovered: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Cleared: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) FinalReference: [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Discovered: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Cleared: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) PhantomReference: [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Discovered: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,ref ] GC(13248) Cleared: 0 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases ] GC(13248) Reference Processing 0.232ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,start ] GC(13248) Weak Processing [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases ] GC(13248) Weak Processing 0.030ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,start ] GC(13248) Class Unloading [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,start ] GC(13248) ClassLoaderData [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases ] GC(13248) ClassLoaderData 0.024ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases,start ] GC(13248) ProtectionDomainCacheTable [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.168s][debug][gc,phases ] GC(13248) ProtectionDomainCacheTable 0.012ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.172s][info ][gc,stringtable ] GC(13248) Cleaned string and symbol table, strings: 2683 processed, 0 removed, symbols: 28417 processed, 0 removed [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.172s][debug][gc,phases ] GC(13248) Class Unloading 3.614ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.172s][debug][gc,phases,start ] GC(13248) Flush Task Caches [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.172s][debug][gc,stats ] Mark stats cache hits 23214 misses 4 ratio 99.983 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.172s][debug][gc,phases ] GC(13248) Flush Task Caches 0.025ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.172s][debug][gc,phases,start ] GC(13248) Update Remembered Set Tracking Before Rebuild [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.172s][debug][gc,ergo ] GC(13248) Running G1 Update RemSet Tracking Before Rebuild using 1 workers for 353 regions in heap [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc,remset,tracking] GC(13248) Remembered Set Tracking update regions total 353, selected 3 [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc,phases ] GC(13248) Update Remembered Set Tracking Before Rebuild 6.134ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc,phases,start ] GC(13248) Reclaim Empty Regions [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc ] GC(13248) Reclaimed 119 empty regions [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc,phases ] GC(13248) Reclaim Empty Regions 0.185ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc,phases,start ] GC(13248) Purge Metaspace [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc,phases ] GC(13248) Purge Metaspace 0.008ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc,phases,start ] GC(13248) Report Object Count [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][debug][gc,phases ] GC(13248) Report Object Count 0.009ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][info ][gc ] GC(13248) Pause Remark 189M->70M(353M) 15.274ms [2024-08-16T18:43:01.352Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][info ][gc,cpu ] GC(13248) User=0.00s Sys=0.00s Real=0.02s [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.178s][info ][gc,marking ] GC(13248) Concurrent Rebuild Remembered Sets [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46350: before allocation 72273248 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 90099040 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46351: before allocation 90099040 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 107924832 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.321s][info ][gc,marking ] GC(13248) Concurrent Rebuild Remembered Sets 143.254ms [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.336s][info ][gc,start ] GC(13248) Pause Cleanup [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.336s][debug][gc,phases,start ] GC(13248) Update Remembered Set Tracking After Rebuild [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.336s][debug][gc,phases ] GC(13248) Update Remembered Set Tracking After Rebuild 0.049ms [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.336s][debug][gc,phases,start ] GC(13248) Finalize Concurrent Mark Cleanup [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.336s][debug][gc,ergo ] GC(13248) request young-only gcs (reclaimable percentage not over threshold). candidate old regions: 2 reclaimable: 567024 (0.15) threshold: 5 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.336s][debug][gc,phases ] GC(13248) Finalize Concurrent Mark Cleanup 0.250ms [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.336s][info ][gc ] GC(13248) Pause Cleanup 104M->104M(353M) 0.382ms [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.336s][info ][gc,cpu ] GC(13248) User=0.00s Sys=0.00s Real=0.00s [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.338s][info ][gc,marking ] GC(13248) Concurrent Cleanup for Next Mark [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.338s][debug][gc,ergo ] GC(13248) Running G1 Clear Bitmap with 1 workers for 6 work units. [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.339s][info ][gc,marking ] GC(13248) Concurrent Cleanup for Next Mark 0.545ms [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] [4783.339s][info ][gc ] GC(13248) Concurrent Cycle 368.766ms [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46352: before allocation 107924832 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 125750624 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46353: before allocation 125750624 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 143576416 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] Iteration 46354: before allocation 143576416 [2024-08-16T18:43:01.353Z] [LowMemoryTest$TestMain, -XX:+UseG1GC] after allocation 161402208 [2024-08-16T18:43:01.353Z] STDERR: [2024-08-16T18:43:01.353Z] rerun: [2024-08-16T18:43:01.353Z] cd /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/scratch/2 && \ [2024-08-16T18:43:01.353Z] DISPLAY=:0 \ [2024-08-16T18:43:01.353Z] HOME=/home/jenkins \ [2024-08-16T18:43:01.353Z] PATH=/bin:/usr/bin:/usr/sbin \ [2024-08-16T18:43:01.353Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2024-08-16T18:43:01.353Z] /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/java \ [2024-08-16T18:43:01.353Z] -Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2024-08-16T18:43:01.353Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2024-08-16T18:43:01.353Z] -Dtest.compiler.opts= \ [2024-08-16T18:43:01.353Z] -Dtest.java.opts= \ [2024-08-16T18:43:01.353Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.353Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image \ [2024-08-16T18:43:01.353Z] -Dtest.timeout.factor=8.0 \ [2024-08-16T18:43:01.353Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2024-08-16T18:43:01.353Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2024-08-16T18:43:01.353Z] -Dtest.name=java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.353Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean/LowMemoryTest.java \ [2024-08-16T18:43:01.353Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean \ [2024-08-16T18:43:01.353Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/lib \ [2024-08-16T18:43:01.353Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d \ [2024-08-16T18:43:01.353Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.353Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/java/lang/management/MemoryMXBean/LowMemoryTest.d:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/lang/management/MemoryMXBean:/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/classes/0/test/lib \ [2024-08-16T18:43:01.353Z] -Dtest.modules=java.management \ [2024-08-16T18:43:01.353Z] --add-modules java.management \ [2024-08-16T18:43:01.353Z] -ea \ [2024-08-16T18:43:01.353Z] -esa \ [2024-08-16T18:43:01.353Z] -Xmx512m \ [2024-08-16T18:43:01.353Z] -XX:-UseCompressedOops \ [2024-08-16T18:43:01.353Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2024-08-16T18:43:01.353Z] -XX:+UnlockDiagnosticVMOptions \ [2024-08-16T18:43:01.353Z] -XX:+WhiteBoxAPI \ [2024-08-16T18:43:01.353Z] -Xbootclasspath/a:. \ [2024-08-16T18:43:01.353Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work/java/lang/management/MemoryMXBean/LowMemoryTest.d/main.0.jta [2024-08-16T18:43:01.353Z] [2024-08-16T18:43:01.353Z] TEST RESULT: Error. Program `/home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 4800000ms, elapsed time including timeout handling was 4802416ms). [2024-08-16T18:43:01.353Z] -------------------------------------------------- [2024-08-16T18:43:01.353Z] Test results: passed: 125; error: 1 [2024-08-16T18:43:35.036Z] Report written to /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/report/html/report.html [2024-08-16T18:43:35.037Z] Results written to /home/jenkins/workspace/Test_openjdk11_hs_extended.openjdk_ppc64le_linux_testList_4/aqa-tests/TKG/output_17238230649044/jdk_management_1/work [2024-08-16T18:43:35.037Z] Error: Some tests failed or other problems occurred. [2024-08-16T18:43:35.037Z] ----------------------------------- [2024-08-16T18:43:35.037Z] jdk_management_1_FAILED [2024-08-16T18:43:35.037Z] ----------------------------------- [2024-08-16T18:43:35.037Z] [2024-08-16T18:43:35.037Z] TEST TEARDOWN: [2024-08-16T18:43:35.037Z] Nothing to be done for teardown. [2024-08-16T18:43:35.037Z] jdk_management_1 Finish Time: Fri Aug 16 18:43:33 2024 Epoch Time (ms): 1723833813539