[2024-06-27T05:59:49.899Z] Running test UtilLoadTest_5m_0 ... [2024-06-27T05:59:49.899Z] =============================================== [2024-06-27T05:59:49.899Z] UtilLoadTest_5m_0 Start Time: Thu Jun 27 05:59:49 2024 Epoch Time (ms): 1719467989532 [2024-06-27T05:59:49.899Z] variation: Mode150 [2024-06-27T05:59:49.899Z] JVM_OPTIONS: -XX:+UseCompressedOops [2024-06-27T05:59:49.899Z] { \ [2024-06-27T05:59:49.899Z] echo ""; echo "TEST SETUP:"; \ [2024-06-27T05:59:49.899Z] echo "Nothing to be done for setup."; \ [2024-06-27T05:59:49.899Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0"; \ [2024-06-27T05:59:49.899Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0"; \ [2024-06-27T05:59:49.899Z] echo ""; echo "TESTING:"; \ [2024-06-27T05:59:49.899Z] perl /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/otherLoadTest/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/otherLoadTest/..//STF;/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/otherLoadTest/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/../../testDependency/system_lib/" -java-args=' -XX:+UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0" -test=UtilLoadTest -test-args="timeLimit=5m"; \ [2024-06-27T05:59:49.899Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "UtilLoadTest_5m_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0"; else echo "-----------------------------------"; echo "UtilLoadTest_5m_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-27T05:59:49.899Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-27T05:59:49.899Z] echo "Nothing to be done for teardown."; \ [2024-06-27T05:59:49.899Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/TestTargetResult"; [2024-06-27T05:59:49.899Z] [2024-06-27T05:59:49.899Z] TEST SETUP: [2024-06-27T05:59:49.899Z] Nothing to be done for setup. [2024-06-27T05:59:49.899Z] [2024-06-27T05:59:49.899Z] TESTING: [2024-06-27T05:59:49.899Z] STF 05:59:49.663 - ========================= S T F ========================= [2024-06-27T05:59:49.899Z] systemtest-prereqs has been processed, and set to: /home/jenkins/testDependency/system_libRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0 [2024-06-27T05:59:49.899Z] There is 162456 Mb free [2024-06-27T05:59:49.899Z] STF 05:59:49.681 - ================== G E N E R A T I O N ================== [2024-06-27T05:59:49.899Z] STF 05:59:49.682 - Checking JVM: /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jdkbinary/j2sdk-image [2024-06-27T05:59:49.899Z] STF 05:59:49.682 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest" [2024-06-27T05:59:54.109Z] GEN 05:59:53.186 - Found test. Project: 'openjdk.test.load' class: 'UtilLoadTest.class' Dir: '/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.load/bin' [2024-06-27T05:59:54.109Z] GEN 05:59:53.252 - Found test. Project: 'openjdk.test.load' class: 'net.adoptopenjdk.stf.UtilLoadTest' [2024-06-27T05:59:54.109Z] GEN Classpath directories used by project 'openjdk.test.load': [2024-06-27T05:59:54.109Z] GEN /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.load/bin [2024-06-27T05:59:54.109Z] GEN /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/STF/stf.core/bin [2024-06-27T05:59:54.109Z] GEN /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/STF/stf.load/bin [2024-06-27T05:59:54.109Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-06-27T05:59:54.109Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-06-27T05:59:54.109Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-06-27T05:59:54.109Z] GEN /home/jenkins/testDependency/system_lib/junit/hamcrest-core.jar [2024-06-27T05:59:54.625Z] GEN 05:59:54.154 - Using Mode NoOptions. Values = '' [2024-06-27T05:59:54.625Z] GEN 05:59:54.528 - [2024-06-27T05:59:54.625Z] GEN 05:59:54.529 - Test command summary: [2024-06-27T05:59:54.625Z] GEN 05:59:54.545 - Step Stage Command Description [2024-06-27T05:59:54.625Z] GEN 05:59:54.545 - -----+--------+-----------------+------------ [2024-06-27T05:59:54.625Z] GEN 05:59:54.545 - 1 execute Run java Run util load tests [2024-06-27T05:59:55.165Z] STF 05:59:54.658 - [2024-06-27T05:59:55.165Z] STF 05:59:54.658 - Script generation completed [2024-06-27T05:59:55.165Z] STF 05:59:54.658 - [2024-06-27T05:59:55.165Z] STF 05:59:54.658 - [2024-06-27T05:59:55.165Z] STF 05:59:54.658 - ======================= S E T U P ======================= [2024-06-27T05:59:55.165Z] STF 05:59:54.658 - Running setup: perl /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/setUp.pl [2024-06-27T05:59:55.165Z] STF 05:59:54.788 - SETUP stage completed [2024-06-27T05:59:55.165Z] STF 05:59:54.793 - [2024-06-27T05:59:55.165Z] STF 05:59:54.793 - ==================== E X E C U T E - ==================== [2024-06-27T05:59:55.165Z] STF 05:59:54.793 - Running execute: perl /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/execute.pl [2024-06-27T05:59:55.165Z] STF 05:59:54.878 - [2024-06-27T05:59:55.165Z] STF 05:59:54.878 - Java version [2024-06-27T05:59:55.165Z] STF 05:59:54.878 - Running: /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jdkbinary/j2sdk-image/bin/java -version [2024-06-27T05:59:55.708Z] openjdk version "11.0.24-beta" 2024-07-16 [2024-06-27T05:59:55.709Z] OpenJDK Runtime Environment Temurin-11.0.24+7-202406262124 (build 11.0.24-beta+7-ea) [2024-06-27T05:59:55.709Z] OpenJDK 64-Bit Server VM Temurin-11.0.24+7-202406262124 (build 11.0.24-beta+7-ea, mixed mode) [2024-06-27T05:59:55.709Z] STF 05:59:55.239 - [2024-06-27T05:59:55.709Z] STF 05:59:55.239 - +------ Step 1 - Run util load tests [2024-06-27T05:59:55.709Z] STF 05:59:55.239 - | Run foreground process [2024-06-27T05:59:55.709Z] STF 05:59:55.239 - | Program: /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-06-27T05:59:55.709Z] STF 05:59:55.239 - | Mnemonic: ULT [2024-06-27T05:59:55.709Z] STF 05:59:55.239 - | Echo: ECHO_ON [2024-06-27T05:59:55.709Z] STF 05:59:55.239 - | Expectation: CLEAN_RUN within 1h [2024-06-27T05:59:55.709Z] STF 05:59:55.239 - | [2024-06-27T05:59:55.709Z] STF 05:59:55.246 - Running command: /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jdkbinary/j2sdk-image/bin/java -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/STF/stf.core/bin:/home/jenkins/testDependency/system_lib/log4j/log4j-api.jar:/home/jenkins/testDependency/system_lib/log4j/log4j-core.jar:/home/jenkins/testDependency/system_lib/junit/junit.jar:/home/jenkins/testDependency/system_lib/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results -resultsPrefix 1.ULT. -timeLimit 5m -abortIfOutOfMemory false -reportFailureLimit 1 -abortAtFailureLimit 10 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.util.threadCount 3 -suite.util.inventoryFile /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results/1.ULT.inventory/openjdk.test.load/config/inventories/util/util.xml -suite.util.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results/1.ULT.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.util.selection random -suite.util.seed -1 -suite.util.repeatCount 1 -suite.util.thinkingTime 0ms..0ms [2024-06-27T05:59:55.709Z] STF 05:59:55.246 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results/1.ULT.stderr [2024-06-27T05:59:55.709Z] STF 05:59:55.246 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results/1.ULT.stdout [2024-06-27T05:59:55.709Z] STF 05:59:55.260 - Monitoring processes: ULT [2024-06-27T05:59:58.238Z] ULT 05:59:57.934 - Load test parameters [2024-06-27T05:59:58.238Z] ULT 05:59:57.938 - Time limited = true [2024-06-27T05:59:58.238Z] ULT 05:59:57.938 - Time limit = 5m [2024-06-27T05:59:58.238Z] ULT 05:59:57.939 - abortIfOutOfMemory = false [2024-06-27T05:59:58.238Z] ULT 05:59:57.940 - reportFailureLimit = 1 [2024-06-27T05:59:58.238Z] ULT 05:59:57.940 - abortAtFailureLimit = 10 [2024-06-27T05:59:58.238Z] ULT 05:59:57.953 - maxTotalLogFileSpace = 209715200 [2024-06-27T05:59:58.238Z] ULT 05:59:57.954 - maxSingleLogSize = 8388608 [2024-06-27T05:59:58.238Z] ULT 05:59:57.954 - Parameters for suite 0 [2024-06-27T05:59:58.238Z] ULT 05:59:57.955 - Suite name = util [2024-06-27T05:59:58.238Z] ULT 05:59:57.955 - Number threads = 3 [2024-06-27T05:59:58.238Z] ULT 05:59:57.956 - Supplied seed = -1 [2024-06-27T05:59:58.238Z] ULT 05:59:57.957 - Inventory file = /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results/1.ULT.inventory/openjdk.test.load/config/inventories/util/util.xml [2024-06-27T05:59:58.238Z] ULT 05:59:57.957 - Exclude file = /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results/1.ULT.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml [2024-06-27T05:59:58.238Z] ULT 05:59:57.958 - Number tests = -1 [2024-06-27T05:59:58.238Z] ULT 05:59:57.959 - Repeat count = 1 [2024-06-27T05:59:58.238Z] ULT 05:59:57.960 - Thinking time = 0ms..0ms [2024-06-27T05:59:58.238Z] ULT 05:59:57.961 - Selection mode = random [2024-06-27T05:59:58.238Z] ULT 05:59:57.961 - Actual seed = 1721501613445 [2024-06-27T05:59:58.238Z] ULT 05:59:58.033 - Parsing inventory file. Root=/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results/1.ULT.inventory File=openjdk.test.load/config/inventories/util/util.xml [2024-06-27T05:59:58.238Z] ULT 05:59:58.045 - Reading exclusion file. File=openjdk.test.load/config/inventories/util/util_exclude.xml [2024-06-27T05:59:58.238Z] ULT 05:59:58.045 - Parsing inventory file. Root=/home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/results/1.ULT.inventory File=openjdk.test.load/config/inventories/util/util_exclude.xml [2024-06-27T05:59:58.238Z] ULT 05:59:58.052 - Final test list: [2024-06-27T05:59:58.238Z] ULT 05:59:58.100 - 0 ArbitraryJava[net.adoptopenjdk.test.util.calendars.CalendarTest run] Weighting=1 [2024-06-27T05:59:58.238Z] ULT 05:59:58.101 - 1 ArbitraryJava[net.adoptopenjdk.test.util.hashmap.Hog run] Weighting=1 [2024-06-27T05:59:58.238Z] ULT 05:59:58.101 - 2 JUnit[net.adoptopenjdk.test.util.lilac.TestArrays] Weighting=1 [2024-06-27T05:59:58.238Z] ULT 05:59:58.102 - 4 JUnit[net.adoptopenjdk.test.util.treemap.TreeMapAPITest] Weighting=1 [2024-06-27T05:59:58.238Z] ULT 05:59:58.102 - 5 JUnit[net.adoptopenjdk.test.util.treemap.TreeMapTest] Weighting=1 [2024-06-27T05:59:58.776Z] ULT 05:59:58.281 - Starting thread. Suite=0 thread=0 [2024-06-27T05:59:58.776Z] ULT 05:59:58.298 - Starting thread. Suite=0 thread=1 [2024-06-27T05:59:58.776Z] ULT 05:59:58.363 - Starting thread. Suite=0 thread=2 [2024-06-27T06:00:21.483Z] ULT 06:00:18.301 - Completed 6.7%. Number of tests started=3277 [2024-06-27T06:00:40.364Z] ULT 06:00:38.241 - Completed 13.3%. Number of tests started=9181 (+5904) [2024-06-27T06:00:59.319Z] ULT 06:00:58.283 - Completed 20.0%. Number of tests started=15784 (+6603) [2024-06-27T06:01:21.844Z] ULT 06:01:18.295 - Completed 26.7%. Number of tests started=22360 (+6576) [2024-06-27T06:01:40.676Z] ULT 06:01:38.288 - Completed 33.4%. Number of tests started=28894 (+6534) [2024-06-27T06:01:59.679Z] ULT 06:01:58.274 - Completed 40.1%. Number of tests started=35454 (+6560) [2024-06-27T06:02:18.978Z] ULT 06:02:18.239 - Completed 46.7%. Number of tests started=41568 (+6114) [2024-06-27T06:02:42.294Z] ULT 06:02:38.335 - Completed 53.4%. Number of tests started=48351 (+6783) [2024-06-27T06:03:01.217Z] ULT 06:02:58.244 - Completed 60.1%. Number of tests started=54656 (+6305) [2024-06-27T06:03:20.058Z] ULT 06:03:18.242 - Completed 66.7%. Number of tests started=61128 (+6472) [2024-06-27T06:03:42.666Z] ULT 06:03:38.335 - Completed 73.4%. Number of tests started=67624 (+6496) [2024-06-27T06:04:01.449Z] ULT 06:03:58.261 - Completed 80.1%. Number of tests started=73620 (+5996) [2024-06-27T06:04:20.474Z] ULT 06:04:18.291 - Completed 86.8%. Number of tests started=79704 (+6084) [2024-06-27T06:04:39.431Z] ULT 06:04:38.274 - Completed 93.4%. Number of tests started=85441 (+5737) [2024-06-27T06:04:55.524Z] STF 06:04:54.434 - Heartbeat: Process ULT is still running [2024-06-27T06:04:58.889Z] ULT 06:04:57.963 - Thread completed. Suite=0 thread=0 [2024-06-27T06:04:58.889Z] ULT 06:04:57.957 - Thread completed. Suite=0 thread=2 [2024-06-27T06:04:58.889Z] ULT 06:04:57.996 - Thread completed. Suite=0 thread=1 [2024-06-27T06:04:58.889Z] ULT 06:04:58.004 - Test stopped due to reaching runtime limit [2024-06-27T06:04:58.889Z] ULT 06:04:58.004 - Load test completed [2024-06-27T06:04:58.889Z] ULT 06:04:58.005 - Ran : 91482 [2024-06-27T06:04:58.889Z] ULT 06:04:58.006 - Passed : 91482 [2024-06-27T06:04:58.889Z] ULT 06:04:58.006 - Failed : 0 [2024-06-27T06:04:58.889Z] ULT 06:04:58.006 - Result : PASSED [2024-06-27T06:04:59.459Z] STF 06:04:58.532 - Monitoring Report Summary: [2024-06-27T06:04:59.459Z] STF 06:04:58.532 - o Process ULT ended with the expected exit code (0) [2024-06-27T06:04:59.459Z] STF 06:04:58.533 - EXECUTE stage completed [2024-06-27T06:04:59.459Z] STF 06:04:58.874 - [2024-06-27T06:04:59.459Z] STF 06:04:58.874 - ==================== T E A R D O W N ==================== [2024-06-27T06:04:59.460Z] STF 06:04:58.874 - Running teardown: perl /home/jenkins/workspace/Test_openjdk11_hs_extended.system_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17194659693419/UtilLoadTest_5m_0/20240627-055949-UtilLoadTest/tearDown.pl [2024-06-27T06:04:59.460Z] STF 06:04:58.944 - TEARDOWN stage completed [2024-06-27T06:04:59.460Z] STF 06:04:58.951 - [2024-06-27T06:04:59.460Z] STF 06:04:58.951 - ===================== R E S U L T S ===================== [2024-06-27T06:04:59.460Z] STF 06:04:58.951 - Stage results: [2024-06-27T06:04:59.460Z] STF 06:04:58.951 - setUp: pass [2024-06-27T06:04:59.460Z] STF 06:04:58.951 - execute: pass [2024-06-27T06:04:59.460Z] STF 06:04:58.951 - teardown: pass [2024-06-27T06:04:59.460Z] STF 06:04:58.951 - [2024-06-27T06:04:59.460Z] STF 06:04:58.951 - Overall result: PASSED [2024-06-27T06:04:59.460Z] ----------------------------------- [2024-06-27T06:04:59.460Z] UtilLoadTest_5m_0_PASSED [2024-06-27T06:04:59.460Z] ----------------------------------- [2024-06-27T06:04:59.460Z] [2024-06-27T06:04:59.460Z] TEST TEARDOWN: [2024-06-27T06:04:59.460Z] Nothing to be done for teardown. [2024-06-27T06:04:59.460Z] UtilLoadTest_5m_0 Finish Time: Thu Jun 27 06:04:58 2024 Epoch Time (ms): 1719468298969