[2024-11-16T08:42:48.454Z] Running test ClassLoadingTest_5m_1 ... [2024-11-16T08:42:48.767Z] =============================================== [2024-11-16T08:42:48.767Z] ClassLoadingTest_5m_1 Start Time: Sat Nov 16 08:42:48 2024 Epoch Time (ms): 1731746568647 [2024-11-16T08:42:49.110Z] variation: Mode650 [2024-11-16T08:42:49.110Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-11-16T08:42:49.110Z] { \ [2024-11-16T08:42:49.110Z] echo ""; echo "TEST SETUP:"; \ [2024-11-16T08:42:49.110Z] echo "Nothing to be done for setup."; \ [2024-11-16T08:42:49.110Z] mkdir -p "C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\\TKG\\output_17317417036024\\ClassLoadingTest_5m_1"; \ [2024-11-16T08:42:49.110Z] cd "C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\\TKG\\output_17317417036024\\ClassLoadingTest_5m_1"; \ [2024-11-16T08:42:49.110Z] echo ""; echo "TESTING:"; \ [2024-11-16T08:42:49.110Z] perl C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests///..//jvmtest\\system\\otherLoadTest/../\\STF\\stf.core\\scripts\\stf.pl -test-root="C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests///..//jvmtest\\system\\otherLoadTest/../\\STF;C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests///..//jvmtest\\system\\otherLoadTest/../\\aqa-systemtest""" -systemtest-prereqs="c:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/../../testDependency/system_lib\\" -java-args=' -XX:-UseCompressedOops ' -results-root="C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\\TKG\\output_17317417036024\\ClassLoadingTest_5m_1" -test=ClassloadingLoadTest -test-args="timeLimit=5m"; \ [2024-11-16T08:42:49.110Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "ClassLoadingTest_5m_1""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\\TKG\\output_17317417036024\\ClassLoadingTest_5m_1"; else echo "-----------------------------------"; echo "ClassLoadingTest_5m_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-16T08:42:49.110Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-16T08:42:49.110Z] echo "Nothing to be done for teardown."; \ [2024-11-16T08:42:49.110Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\\TKG\\output_17317417036024\\TestTargetResult"; [2024-11-16T08:42:49.110Z] [2024-11-16T08:42:49.110Z] TEST SETUP: [2024-11-16T08:42:49.110Z] Nothing to be done for setup. [2024-11-16T08:42:49.110Z] [2024-11-16T08:42:49.110Z] TESTING: [2024-11-16T08:42:49.438Z] STF 08:42:49.180 - ========================= S T F ========================= [2024-11-16T08:42:49.439Z] systemtest-prereqs has been processed, and set to: c:/workspace/testDependency/system_libRetrieving amount of free space on drive containing C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\TKG\output_17317417036024\ClassLoadingTest_5m_1 [2024-11-16T08:42:49.439Z] There is 98034 Mb free [2024-11-16T08:42:49.439Z] STF 08:42:49.243 - ================== G E N E R A T I O N ================== [2024-11-16T08:42:49.439Z] STF 08:42:49.246 - Checking JVM: c:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/jdkbinary/j2sdk-image [2024-11-16T08:42:49.439Z] STF 08:42:49.246 - Starting process to generate scripts: c:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath c:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/../../testDependency/system_lib\/log4j/log4j-api.jar;c:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/../../testDependency/system_lib\/log4j/log4j-core.jar;C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\TKG\output_17317417036024\ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest/stf_parameters.properties, , C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\TKG\output_17317417036024\ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest" [2024-11-16T08:42:50.554Z] GEN 08:42:50.285 - Found test. Project: 'openjdk.test.load' class: 'ClassloadingLoadTest.class' Dir: 'C:\workspace\workspace\Test_openjdk8_hs_sanity.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.load\bin' [2024-11-16T08:42:50.554Z] GEN 08:42:50.306 - Found test. Project: 'openjdk.test.load' class: 'net.adoptopenjdk.stf.ClassloadingLoadTest' [2024-11-16T08:42:50.880Z] GEN Classpath directories used by project 'openjdk.test.load': [2024-11-16T08:42:50.880Z] GEN C:\\workspace\\workspace\\Test_openjdk8_hs_sanity.system_x86-64_windows\\jvmtest\\system\\aqa-systemtest\\openjdk.test.load\\bin [2024-11-16T08:42:50.880Z] GEN C:\\workspace\\workspace\\Test_openjdk8_hs_sanity.system_x86-64_windows\\jvmtest\\system\\STF\\stf.core\\bin [2024-11-16T08:42:50.880Z] GEN C:\\workspace\\workspace\\Test_openjdk8_hs_sanity.system_x86-64_windows\\jvmtest\\system\\STF\\stf.load\\bin [2024-11-16T08:42:50.880Z] GEN c:/workspace/testDependency/system_lib/log4j/log4j-api.jar [2024-11-16T08:42:50.880Z] GEN c:/workspace/testDependency/system_lib/log4j/log4j-core.jar [2024-11-16T08:42:50.880Z] GEN c:/workspace/testDependency/system_lib/junit/junit.jar [2024-11-16T08:42:50.880Z] GEN c:/workspace/testDependency/system_lib/junit/hamcrest-core.jar [2024-11-16T08:42:50.880Z] GEN 08:42:50.548 - Using Mode NoOptions. Values = '' [2024-11-16T08:42:50.880Z] GEN 08:42:50.614 - [2024-11-16T08:42:50.880Z] GEN 08:42:50.615 - Test command summary: [2024-11-16T08:42:50.880Z] GEN 08:42:50.615 - Step Stage Command Description [2024-11-16T08:42:50.880Z] GEN 08:42:50.615 - -----+--------+-----------------+------------ [2024-11-16T08:42:50.880Z] GEN 08:42:50.615 - 1 execute Run java Run classloading tests [2024-11-16T08:42:50.880Z] STF 08:42:50.654 - [2024-11-16T08:42:50.880Z] STF 08:42:50.654 - Script generation completed [2024-11-16T08:42:50.880Z] STF 08:42:50.654 - [2024-11-16T08:42:50.880Z] STF 08:42:50.656 - [2024-11-16T08:42:50.880Z] STF 08:42:50.656 - ======================= S E T U P ======================= [2024-11-16T08:42:50.880Z] STF 08:42:50.656 - Running setup: perl C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\TKG\output_17317417036024\ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest/setUp.pl [2024-11-16T08:42:50.880Z] STF 08:42:50.833 - SETUP stage completed [2024-11-16T08:42:50.880Z] STF 08:42:50.852 - [2024-11-16T08:42:50.880Z] STF 08:42:50.852 - ==================== E X E C U T E - ==================== [2024-11-16T08:42:50.880Z] STF 08:42:50.852 - Running execute: perl C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\TKG\output_17317417036024\ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest/execute.pl [2024-11-16T08:42:51.212Z] STF 08:42:51.041 - [2024-11-16T08:42:51.212Z] STF 08:42:51.042 - Java version [2024-11-16T08:42:51.212Z] STF 08:42:51.042 - Running: c:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/jdkbinary/j2sdk-image/bin/java -version [2024-11-16T08:42:51.212Z] openjdk version "1.8.0_442-beta" [2024-11-16T08:42:51.212Z] OpenJDK Runtime Environment (Temurin)(build 1.8.0_442-beta-202411142104-b03) [2024-11-16T08:42:51.212Z] OpenJDK 64-Bit Server VM (Temurin)(build 25.442-b03, mixed mode) [2024-11-16T08:42:51.212Z] STF 08:42:51.154 - [2024-11-16T08:42:51.212Z] STF 08:42:51.154 - +------ Step 1 - Run classloading tests [2024-11-16T08:42:51.212Z] STF 08:42:51.154 - | Run foreground process [2024-11-16T08:42:51.212Z] STF 08:42:51.154 - | Program: c:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/jdkbinary/j2sdk-image/bin/java [2024-11-16T08:42:51.212Z] STF 08:42:51.154 - | Mnemonic: CLT [2024-11-16T08:42:51.212Z] STF 08:42:51.154 - | Echo: ECHO_ON [2024-11-16T08:42:51.212Z] STF 08:42:51.154 - | Expectation: CLEAN_RUN within 1h5m [2024-11-16T08:42:51.212Z] STF 08:42:51.154 - | [2024-11-16T08:42:51.212Z] STF 08:42:51.155 - Running command: c:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/jdkbinary/j2sdk-image/bin/java -Djava.classloading.dir=C:\workspace\workspace\Test_openjdk8_hs_sanity.system_x86-64_windows\jvmtest\system\aqa-systemtest -Djava.version.number=8 -XX:-UseCompressedOops -classpath C:\workspace\workspace\Test_openjdk8_hs_sanity.system_x86-64_windows\jvmtest\system\STF\stf.load\bin;C:\workspace\workspace\Test_openjdk8_hs_sanity.system_x86-64_windows\jvmtest\system\STF\stf.core\bin;c:/workspace/testDependency/system_lib/log4j/log4j-api.jar;c:/workspace/testDependency/system_lib/log4j/log4j-core.jar;c:/workspace/testDependency/system_lib/junit/junit.jar;c:/workspace/testDependency/system_lib/junit/hamcrest-core.jar;C:\workspace\workspace\Test_openjdk8_hs_sanity.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.classloading\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir C:\workspace\workspace\Test_openjdk8_hs_sanity.system_x86-64_windows\aqa-tests\TKG\output_17317417036024\ClassLoadingTest_5m_1\20241116-084249-ClassloadingLoadTest\results -resultsPrefix 1.CLT. -timeLimit 5m -abortIfOutOfMemory false -reportFailureLimit 1 -abortAtFailureLimit 10 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.classloading.threadCount 10 -suite.classloading.inventoryFile C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/TKG/output_17317417036024/ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest/results/1.CLT.inventory/openjdk.test.load/config/inventories/classloading/classloading.xml -suite.classloading.inventoryExcludeFile none -suite.classloading.selection random -suite.classloading.seed -1 -suite.classloading.repeatCount 1 -suite.classloading.thinkingTime 0ms..0ms [2024-11-16T08:42:51.212Z] STF 08:42:51.155 - Redirecting stderr to C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/TKG/output_17317417036024/ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest/results/1.CLT.stderr [2024-11-16T08:42:51.212Z] STF 08:42:51.155 - Redirecting stdout to C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/TKG/output_17317417036024/ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest/results/1.CLT.stdout [2024-11-16T08:42:51.543Z] STF 08:42:51.194 - Monitoring processes: CLT [2024-11-16T08:42:52.851Z] CLT 08:42:52.186 - Load test parameters [2024-11-16T08:42:52.851Z] CLT 08:42:52.189 - Time limited = true [2024-11-16T08:42:52.851Z] CLT 08:42:52.190 - Time limit = 5m [2024-11-16T08:42:52.851Z] CLT 08:42:52.190 - abortIfOutOfMemory = false [2024-11-16T08:42:52.851Z] CLT 08:42:52.190 - reportFailureLimit = 1 [2024-11-16T08:42:52.851Z] CLT 08:42:52.190 - abortAtFailureLimit = 10 [2024-11-16T08:42:52.851Z] CLT 08:42:52.190 - maxTotalLogFileSpace = 209715200 [2024-11-16T08:42:52.851Z] CLT 08:42:52.190 - maxSingleLogSize = 8388608 [2024-11-16T08:42:52.851Z] CLT 08:42:52.191 - Parameters for suite 0 [2024-11-16T08:42:52.851Z] CLT 08:42:52.191 - Suite name = classloading [2024-11-16T08:42:52.851Z] CLT 08:42:52.191 - Number threads = 10 [2024-11-16T08:42:52.851Z] CLT 08:42:52.191 - Supplied seed = -1 [2024-11-16T08:42:52.851Z] CLT 08:42:52.191 - Inventory file = C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/TKG/output_17317417036024/ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest/results/1.CLT.inventory/openjdk.test.load/config/inventories/classloading/classloading.xml [2024-11-16T08:42:52.851Z] CLT 08:42:52.191 - Exclude file = none [2024-11-16T08:42:52.851Z] CLT 08:42:52.191 - Number tests = -1 [2024-11-16T08:42:52.851Z] CLT 08:42:52.192 - Repeat count = 1 [2024-11-16T08:42:52.851Z] CLT 08:42:52.192 - Thinking time = 0ms..0ms [2024-11-16T08:42:52.851Z] CLT 08:42:52.192 - Selection mode = random [2024-11-16T08:42:52.851Z] CLT 08:42:52.192 - Actual seed = 1732847433013 [2024-11-16T08:42:52.851Z] CLT 08:42:52.202 - Parsing inventory file. Root=C:\\workspace\\workspace\\Test_openjdk8_hs_sanity.system_x86-64_windows\\aqa-tests\\TKG\\output_17317417036024\\ClassLoadingTest_5m_1\\20241116-084249-ClassloadingLoadTest\\results\\1.CLT.inventory File=openjdk.test.load/config/inventories/classloading/classloading.xml [2024-11-16T08:42:52.851Z] CLT 08:42:52.213 - Final test list: [2024-11-16T08:42:52.851Z] CLT 08:42:52.214 - 0 ArbitraryJava[net.adoptopenjdk.test.classloading.deadlock.DeadlockTest runTest] Weighting=1 [2024-11-16T08:42:52.851Z] CLT 08:42:52.214 - 1 ArbitraryJava[net.adoptopenjdk.test.classloading.ClassMapHog run] Weighting=1 [2024-11-16T08:42:52.851Z] CLT 08:42:52.214 - 2 ArbitraryJava[net.adoptopenjdk.test.classloading.ClassHog run] Weighting=1 [2024-11-16T08:42:52.851Z] CLT 08:42:52.246 - Starting thread. Suite=0 thread=0 [2024-11-16T08:42:52.851Z] CLT 08:42:52.248 - Starting thread. Suite=0 thread=1 [2024-11-16T08:42:52.851Z] CLT 08:42:52.248 - Starting thread. Suite=0 thread=2 [2024-11-16T08:42:52.851Z] CLT 08:42:52.248 - Starting thread. Suite=0 thread=3 [2024-11-16T08:42:52.851Z] CLT 08:42:52.249 - Starting thread. Suite=0 thread=4 [2024-11-16T08:42:52.851Z] CLT 08:42:52.249 - Starting thread. Suite=0 thread=5 [2024-11-16T08:42:52.851Z] CLT 08:42:52.249 - Starting thread. Suite=0 thread=6 [2024-11-16T08:42:52.851Z] CLT 08:42:52.249 - Starting thread. Suite=0 thread=7 [2024-11-16T08:42:52.851Z] CLT 08:42:52.249 - Starting thread. Suite=0 thread=8 [2024-11-16T08:42:52.851Z] CLT 08:42:52.250 - Starting thread. Suite=0 thread=9 [2024-11-16T08:43:16.128Z] CLT 08:43:12.355 - Completed 6.7%. Number of tests started=3389 [2024-11-16T08:43:35.237Z] CLT 08:43:32.404 - Completed 13.4%. Number of tests started=6687 (+3298) [2024-11-16T08:43:54.271Z] CLT 08:43:52.253 - Completed 20.0%. Number of tests started=10896 (+4209) [2024-11-16T08:44:13.286Z] CLT 08:44:12.360 - Completed 26.7%. Number of tests started=14363 (+3467) [2024-11-16T08:44:36.291Z] CLT 08:44:32.260 - Completed 33.3%. Number of tests started=17964 (+3601) [2024-11-16T08:44:55.464Z] CLT 08:44:52.314 - Completed 40.0%. Number of tests started=22242 (+4278) [2024-11-16T08:45:14.432Z] CLT 08:45:12.337 - Completed 46.7%. Number of tests started=25523 (+3281) [2024-11-16T08:45:33.463Z] CLT 08:45:32.267 - Completed 53.4%. Number of tests started=29394 (+3871) [2024-11-16T08:45:56.337Z] CLT 08:45:52.316 - Completed 60.0%. Number of tests started=33266 (+3872) [2024-11-16T08:46:15.392Z] CLT 08:46:12.285 - Completed 66.7%. Number of tests started=36629 (+3363) [2024-11-16T08:46:34.740Z] CLT 08:46:32.310 - Completed 73.4%. Number of tests started=41075 (+4446) [2024-11-16T08:46:53.805Z] CLT 08:46:52.341 - Completed 80.0%. Number of tests started=44370 (+3295) [2024-11-16T08:47:13.055Z] CLT 08:47:12.240 - Completed 86.7%. Number of tests started=47966 (+3596) [2024-11-16T08:47:36.171Z] CLT 08:47:32.266 - Completed 93.4%. Number of tests started=52197 (+4231) [2024-11-16T08:47:51.941Z] STF 08:47:51.139 - Heartbeat: Process CLT is still running [2024-11-16T08:47:52.702Z] CLT 08:47:52.187 - Thread completed. Suite=0 thread=9 [2024-11-16T08:47:52.702Z] CLT 08:47:52.187 - Thread completed. Suite=0 thread=6 [2024-11-16T08:47:52.702Z] CLT 08:47:52.189 - Thread completed. Suite=0 thread=0 [2024-11-16T08:47:52.702Z] CLT 08:47:52.192 - Thread completed. Suite=0 thread=1 [2024-11-16T08:47:52.702Z] CLT 08:47:52.197 - Thread completed. Suite=0 thread=4 [2024-11-16T08:47:52.702Z] CLT 08:47:52.199 - Thread completed. Suite=0 thread=2 [2024-11-16T08:47:52.702Z] CLT 08:47:52.200 - Thread completed. Suite=0 thread=5 [2024-11-16T08:47:52.702Z] CLT 08:47:52.201 - Thread completed. Suite=0 thread=3 [2024-11-16T08:47:52.702Z] CLT 08:47:52.202 - Thread completed. Suite=0 thread=8 [2024-11-16T08:47:52.702Z] CLT 08:47:52.203 - Thread completed. Suite=0 thread=7 [2024-11-16T08:47:53.035Z] CLT 08:47:52.249 - Test stopped due to reaching runtime limit [2024-11-16T08:47:53.035Z] CLT 08:47:52.249 - Load test completed [2024-11-16T08:47:53.035Z] CLT 08:47:52.249 - Ran : 55556 [2024-11-16T08:47:53.035Z] CLT 08:47:52.249 - Passed : 55556 [2024-11-16T08:47:53.035Z] CLT 08:47:52.249 - Failed : 0 [2024-11-16T08:47:53.035Z] CLT 08:47:52.249 - Result : PASSED [2024-11-16T08:47:53.035Z] STF 08:47:52.727 - Monitoring Report Summary: [2024-11-16T08:47:53.035Z] STF 08:47:52.727 - o Process CLT ended with the expected exit code (0) [2024-11-16T08:47:53.035Z] STF 08:47:52.727 - EXECUTE stage completed [2024-11-16T08:47:53.035Z] STF 08:47:52.946 - [2024-11-16T08:47:53.035Z] STF 08:47:52.946 - ==================== T E A R D O W N ==================== [2024-11-16T08:47:53.035Z] STF 08:47:52.946 - Running teardown: perl C:/workspace/workspace/Test_openjdk8_hs_sanity.system_x86-64_windows/aqa-tests/\TKG\output_17317417036024\ClassLoadingTest_5m_1/20241116-084249-ClassloadingLoadTest/tearDown.pl [2024-11-16T08:47:53.348Z] STF 08:47:53.141 - TEARDOWN stage completed [2024-11-16T08:47:53.348Z] STF 08:47:53.158 - [2024-11-16T08:47:53.348Z] STF 08:47:53.158 - ===================== R E S U L T S ===================== [2024-11-16T08:47:53.348Z] STF 08:47:53.158 - Stage results: [2024-11-16T08:47:53.348Z] STF 08:47:53.158 - setUp: pass [2024-11-16T08:47:53.348Z] STF 08:47:53.158 - execute: pass [2024-11-16T08:47:53.348Z] STF 08:47:53.158 - teardown: pass [2024-11-16T08:47:53.348Z] STF 08:47:53.158 - [2024-11-16T08:47:53.348Z] STF 08:47:53.158 - Overall result: PASSED [2024-11-16T08:47:53.348Z] ----------------------------------- [2024-11-16T08:47:53.348Z] ClassLoadingTest_5m_1_PASSED [2024-11-16T08:47:53.348Z] ----------------------------------- [2024-11-16T08:47:53.348Z] [2024-11-16T08:47:53.348Z] TEST TEARDOWN: [2024-11-16T08:47:53.348Z] Nothing to be done for teardown. [2024-11-16T08:47:53.678Z] ClassLoadingTest_5m_1 Finish Time: Sat Nov 16 08:47:53 2024 Epoch Time (ms): 1731746873374