[2025-05-16T11:12:01.338Z] Running test TestJlmRemoteThreadNoAuth_1 ... [2025-05-16T11:12:01.693Z] =============================================== [2025-05-16T11:12:01.693Z] TestJlmRemoteThreadNoAuth_1 Start Time: Fri May 16 11:12:01 2025 Epoch Time (ms): 1747393921463 [2025-05-16T11:12:01.693Z] variation: Mode650 [2025-05-16T11:12:01.693Z] JVM_OPTIONS: -XX:-UseCompressedOops [2025-05-16T11:12:01.693Z] { \ [2025-05-16T11:12:01.693Z] echo ""; echo "TEST SETUP:"; \ [2025-05-16T11:12:01.693Z] echo "Nothing to be done for setup."; \ [2025-05-16T11:12:01.693Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17473932069130\\TestJlmRemoteThreadNoAuth_1"; \ [2025-05-16T11:12:01.693Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17473932069130\\TestJlmRemoteThreadNoAuth_1"; \ [2025-05-16T11:12:01.693Z] echo ""; echo "TESTING:"; \ [2025-05-16T11:12:01.693Z] perl C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\system\\jlm/../\\STF\\stf.core\\scripts\\stf.pl -test-root="C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\system\\jlm/../\\STF;C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\system\\jlm/../\\aqa-systemtest""" -systemtest-prereqs="c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\\" -java-args=' -XX:-UseCompressedOops ' -results-root="C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17473932069130\\TestJlmRemoteThreadNoAuth_1" -test=TestJlmRemoteThreadNoAuth; \ [2025-05-16T11:12:01.693Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17473932069130\\TestJlmRemoteThreadNoAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-16T11:12:01.693Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-16T11:12:01.693Z] echo "Nothing to be done for teardown."; \ [2025-05-16T11:12:01.693Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17473932069130\\TestTargetResult"; [2025-05-16T11:12:02.008Z] [2025-05-16T11:12:02.008Z] TEST SETUP: [2025-05-16T11:12:02.008Z] Nothing to be done for setup. [2025-05-16T11:12:02.008Z] [2025-05-16T11:12:02.008Z] TESTING: [2025-05-16T11:12:02.008Z] STF 11:12:01.840 - ========================= S T F ========================= [2025-05-16T11:12:02.008Z] systemtest-prereqs has been processed, and set to: c:/jenkins/testDependency/system_libRetrieving amount of free space on drive containing C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17473932069130\TestJlmRemoteThreadNoAuth_1 [2025-05-16T11:12:02.008Z] There is 94703 Mb free [2025-05-16T11:12:02.008Z] STF 11:12:01.886 - ================== G E N E R A T I O N ================== [2025-05-16T11:12:02.008Z] STF 11:12:01.888 - Checking JVM: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image [2025-05-16T11:12:02.008Z] STF 11:12:01.888 - Starting process to generate scripts: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -Dload.agent.path=C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jvmtest/system/STF/stf.core/scripts/../../stf.load/bin/stf.load.jar -classpath c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\/asm/asm.jar;c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\/asm/asm-commons.jar;c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\/log4j/log4j-api.jar;c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\/log4j/log4j-core.jar;C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17473932069130\TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17473932069130\TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth" [2025-05-16T11:12:02.008Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2025-05-16T11:12:02.008Z] GEN [0.013s][warning][cds] Archived non-system classes are disabled because the java.system.class.loader property is specified (value = "net.adoptopenjdk.stf.runner.StfClassLoader"). To use archived non-system classes, this property must not be set [2025-05-16T11:12:03.096Z] GEN 11:12:02.791 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: 'C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin' [2025-05-16T11:12:03.096Z] GEN 11:12:02.822 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2025-05-16T11:12:03.096Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2025-05-16T11:12:03.096Z] GEN C:\\jenkins\\workspace\\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\\jvmtest\\system\\aqa-systemtest\\openjdk.test.jlm\\bin [2025-05-16T11:12:03.096Z] GEN C:\\jenkins\\workspace\\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\\jvmtest\\system\\STF\\stf.core\\bin [2025-05-16T11:12:03.096Z] GEN c:/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2025-05-16T11:12:03.096Z] GEN c:/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2025-05-16T11:12:03.096Z] GEN c:/jenkins/testDependency/system_lib/junit/junit.jar [2025-05-16T11:12:03.096Z] GEN 11:12:03.019 - Using Mode NoOptions. Values = '' [2025-05-16T11:12:03.412Z] GEN 11:12:03.151 - [2025-05-16T11:12:03.412Z] GEN 11:12:03.151 - Test command summary: [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - Step Stage Command Description [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - -----+--------+-----------------+------------ [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - 3 execute Monitor Wait for processes to complete [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - 4 execute kill Stop LT1 process [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - 7 execute Monitor Wait for processes to complete [2025-05-16T11:12:03.412Z] GEN 11:12:03.152 - 8 execute kill Stop LT2 process [2025-05-16T11:12:03.412Z] STF 11:12:03.189 - [2025-05-16T11:12:03.412Z] STF 11:12:03.189 - Script generation completed [2025-05-16T11:12:03.412Z] STF 11:12:03.189 - [2025-05-16T11:12:03.412Z] STF 11:12:03.190 - [2025-05-16T11:12:03.412Z] STF 11:12:03.190 - ======================= S E T U P ======================= [2025-05-16T11:12:03.412Z] STF 11:12:03.190 - Running setup: perl C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17473932069130\TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/setUp.pl [2025-05-16T11:12:03.412Z] STF 11:12:03.322 - SETUP stage completed [2025-05-16T11:12:03.412Z] STF 11:12:03.337 - [2025-05-16T11:12:03.412Z] STF 11:12:03.337 - ==================== E X E C U T E - ==================== [2025-05-16T11:12:03.412Z] STF 11:12:03.337 - Running execute: perl C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17473932069130\TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/execute.pl [2025-05-16T11:12:03.733Z] STF 11:12:03.468 - [2025-05-16T11:12:03.733Z] STF 11:12:03.468 - Java version [2025-05-16T11:12:03.733Z] STF 11:12:03.468 - Running: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -version [2025-05-16T11:12:03.733Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2025-05-16T11:12:03.733Z] openjdk version "21.0.8-beta" 2025-07-15 [2025-05-16T11:12:03.733Z] OpenJDK Runtime Environment Temurin-21.0.8+3-202505160836 (build 21.0.8-beta+3-ea) [2025-05-16T11:12:03.733Z] OpenJDK 64-Bit Server VM Temurin-21.0.8+3-202505160836 (build 21.0.8-beta+3-ea, mixed mode, sharing) [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - | Start background process [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - | Program: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - | Mnemonic: LT1 [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - | Echo: ECHO_OFF [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - | Expectation: NEVER [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - | [2025-05-16T11:12:03.733Z] STF 11:12:03.552 - Running command: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -javaagent:C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jvmtest/system/STF/stf.core/scripts/../../stf.load/bin/stf.load.jar -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:-UseCompressedOops -classpath C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.load\bin;C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.core\bin;c:/jenkins/testDependency/system_lib/log4j/log4j-api.jar;c:/jenkins/testDependency/system_lib/log4j/log4j-core.jar;c:/jenkins/testDependency/system_lib/asm/asm.jar;c:/jenkins/testDependency/system_lib/asm/asm-commons.jar;c:/jenkins/testDependency/system_lib/junit/junit.jar;c:/jenkins/testDependency/system_lib/junit/hamcrest-core.jar;C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.math\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\aqa-tests\TKG\output_17473932069130\TestJlmRemoteThreadNoAuth_1\20250516-111201-TestJlmRemoteThreadNoAuth\results -resultsPrefix 1.LT1. -timeLimit 30m -abortAtFailureLimit -1 -reportFailureLimit 1 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.mini-mix.totalNumberTests 20000000 -suite.mini-mix.inventoryFile C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.mini-mix.threadCount 30 -suite.mini-mix.selection random -suite.mini-mix.seed -1 -suite.mini-mix.repeatCount 1 -suite.mini-mix.thinkingTime 0ms..0ms [2025-05-16T11:12:03.733Z] STF 11:12:03.553 - Redirecting stderr to C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2025-05-16T11:12:03.733Z] STF 11:12:03.553 - Redirecting stdout to C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - | Start background process [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - | Program: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - | Mnemonic: CL1 [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - | Echo: ECHO_ON [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - | Expectation: CLEAN_RUN within 30m [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - | [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - Running command: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin;c:/jenkins/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/thd_proxy.log C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - Redirecting stderr to C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2025-05-16T11:12:03.733Z] STF 11:12:03.578 - Redirecting stdout to C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2025-05-16T11:12:03.733Z] STF 11:12:03.584 - [2025-05-16T11:12:03.733Z] STF 11:12:03.584 - +------ Step 3 - Wait for processes to complete [2025-05-16T11:12:03.733Z] STF 11:12:03.584 - | Wait for processes to meet expectations [2025-05-16T11:12:03.733Z] STF 11:12:03.584 - | Processes: [LT1, CL1] [2025-05-16T11:12:03.733Z] STF 11:12:03.584 - | [2025-05-16T11:12:03.733Z] STF 11:12:03.584 - Monitoring processes: CL1 LT1 [2025-05-16T11:12:03.733Z] CL1 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2025-05-16T11:12:04.070Z] CL1 j> 2025/05/16 11:12:03.773 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2025-05-16T11:12:04.070Z] CL1 j> 2025/05/16 11:12:03.841 Attempting to connect [2025-05-16T11:12:04.394Z] CL1 j> 2025/05/16 11:12:04.087 Monitored VM not ready at May 16, 2025, 11:12:04?AM (attempt 1, elapsed 217ms). [2025-05-16T11:12:04.395Z] CL1 j> 2025/05/16 11:12:04.088 Waiting 5 secs and trying again... [2025-05-16T11:12:13.474Z] CL1 j> 2025/05/16 11:12:10.026 Attempting to connect [2025-05-16T11:12:14.304Z] CL1 j> 2025/05/16 11:12:11.818 Connection established! [2025-05-16T11:12:20.877Z] CL1 j> 2025/05/16 11:12:18.973 Starting to write data [2025-05-16T11:17:17.386Z] STF 11:17:03.851 - Heartbeat: Process LT1 is still running [2025-05-16T11:17:58.100Z] STF 11:17:56.750 - Monitoring Report Summary: [2025-05-16T11:17:58.100Z] STF 11:17:56.750 - o Process CL1 ended with the expected exit code (0) [2025-05-16T11:17:58.100Z] STF 11:17:56.795 - o Process LT1 is still running as expected [2025-05-16T11:17:58.100Z] STF 11:17:56.883 - [2025-05-16T11:17:58.100Z] STF 11:17:56.883 - +------ Step 4 - Stop LT1 process [2025-05-16T11:17:58.100Z] STF 11:17:56.883 - | Kill running processes [2025-05-16T11:17:58.100Z] STF 11:17:56.883 - | Processes: [LT1] [2025-05-16T11:17:58.100Z] STF 11:17:56.883 - | [2025-05-16T11:17:58.100Z] STF 11:17:56.883 - Killing processes: LT1 [2025-05-16T11:17:58.100Z] STF 11:17:56.883 - o Process clean up attempt 1 for LT1 pid 7328 [2025-05-16T11:17:58.100Z] STF 11:17:56.883 - o Process LT1 pid 7328 stop() [2025-05-16T11:17:58.100Z] STF 11:17:56.884 - o Process LT1 pid 7328 killed [2025-05-16T11:17:58.100Z] STF 11:17:56.884 - [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - | Start background process [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - | Program: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - | Mnemonic: LT2 [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - | Echo: ECHO_OFF [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - | Expectation: NEVER [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - | [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - Running command: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -javaagent:C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jvmtest/system/STF/stf.core/scripts/../../stf.load/bin/stf.load.jar -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:-UseCompressedOops -classpath C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.load\bin;C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.core\bin;c:/jenkins/testDependency/system_lib/log4j/log4j-api.jar;c:/jenkins/testDependency/system_lib/log4j/log4j-core.jar;c:/jenkins/testDependency/system_lib/asm/asm.jar;c:/jenkins/testDependency/system_lib/asm/asm-commons.jar;c:/jenkins/testDependency/system_lib/junit/junit.jar;c:/jenkins/testDependency/system_lib/junit/hamcrest-core.jar;C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.math\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\aqa-tests\TKG\output_17473932069130\TestJlmRemoteThreadNoAuth_1\20250516-111201-TestJlmRemoteThreadNoAuth\results -resultsPrefix 5.LT2. -timeLimit 30m -abortAtFailureLimit -1 -reportFailureLimit 1 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.mini-mix.totalNumberTests 20000000 -suite.mini-mix.inventoryFile C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.mini-mix.threadCount 30 -suite.mini-mix.selection random -suite.mini-mix.seed -1 -suite.mini-mix.repeatCount 1 -suite.mini-mix.thinkingTime 0ms..0ms [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - Redirecting stderr to C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2025-05-16T11:17:58.100Z] STF 11:17:56.885 - Redirecting stdout to C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - | Start background process [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - | Program: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - | Mnemonic: CL2 [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - | Echo: ECHO_ON [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - | Expectation: CLEAN_RUN within 30m [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - | [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - Running command: c:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath C:\jenkins\workspace\Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin;c:/jenkins/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/thd_proxy.log C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - Redirecting stderr to C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2025-05-16T11:17:58.100Z] STF 11:17:56.897 - Redirecting stdout to C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17473932069130/TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2025-05-16T11:17:58.100Z] STF 11:17:56.903 - [2025-05-16T11:17:58.100Z] STF 11:17:56.903 - +------ Step 7 - Wait for processes to complete [2025-05-16T11:17:58.100Z] STF 11:17:56.903 - | Wait for processes to meet expectations [2025-05-16T11:17:58.100Z] STF 11:17:56.903 - | Processes: [LT2, CL2] [2025-05-16T11:17:58.100Z] STF 11:17:56.903 - | [2025-05-16T11:17:58.100Z] STF 11:17:56.903 - Monitoring processes: CL2 LT2 [2025-05-16T11:17:58.100Z] CL2 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2025-05-16T11:17:58.100Z] CL2 j> 2025/05/16 11:17:57.108 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2025-05-16T11:17:58.100Z] CL2 j> 2025/05/16 11:17:57.191 Attempting to connect [2025-05-16T11:17:58.100Z] CL2 j> 2025/05/16 11:17:57.433 Monitored VM not ready at May 16, 2025, 11:17:57?AM (attempt 1, elapsed 210ms). [2025-05-16T11:17:58.100Z] CL2 j> 2025/05/16 11:17:57.433 Waiting 5 secs and trying again... [2025-05-16T11:18:06.192Z] CL2 j> 2025/05/16 11:18:02.878 Attempting to connect [2025-05-16T11:18:20.821Z] CL2 j> 2025/05/16 11:18:16.346 Connection established! [2025-05-16T11:18:30.330Z] CL2 j> 2025/05/16 11:18:27.450 Writing report data ... [2025-05-16T11:18:34.353Z] CL2 j> 2025/05/16 11:18:30.322 done. [2025-05-16T11:18:43.788Z] CL2 j> 2025/05/16 11:18:41.304 Writing report data ... [2025-05-16T11:18:43.788Z] CL2 j> 2025/05/16 11:18:42.315 done. [2025-05-16T11:18:57.911Z] CL2 j> 2025/05/16 11:18:52.628 Writing report data ... [2025-05-16T11:18:59.769Z] CL2 j> 2025/05/16 11:18:53.574 done. [2025-05-16T11:19:09.060Z] CL2 j> 2025/05/16 11:19:03.929 Writing report data ... [2025-05-16T11:19:09.060Z] CL2 j> 2025/05/16 11:19:04.442 done. [2025-05-16T11:19:20.384Z] CL2 j> 2025/05/16 11:19:14.984 Writing report data ... [2025-05-16T11:19:20.384Z] CL2 j> 2025/05/16 11:19:15.761 done. [2025-05-16T11:19:29.663Z] CL2 j> 2025/05/16 11:19:26.197 Writing report data ... [2025-05-16T11:19:29.663Z] CL2 j> 2025/05/16 11:19:26.819 done. [2025-05-16T11:19:43.298Z] CL2 j> 2025/05/16 11:19:37.490 Writing report data ... [2025-05-16T11:19:43.298Z] CL2 j> 2025/05/16 11:19:38.123 done. [2025-05-16T11:19:52.760Z] CL2 j> 2025/05/16 11:19:49.015 Writing report data ... [2025-05-16T11:19:52.760Z] CL2 j> 2025/05/16 11:19:49.442 done. [2025-05-16T11:20:06.958Z] CL2 j> 2025/05/16 11:20:00.292 Writing report data ... [2025-05-16T11:20:06.958Z] CL2 j> 2025/05/16 11:20:00.622 done. [2025-05-16T11:20:15.027Z] CL2 j> 2025/05/16 11:20:11.362 Writing report data ... [2025-05-16T11:20:17.472Z] CL2 j> 2025/05/16 11:20:12.942 done. [2025-05-16T11:20:28.765Z] CL2 j> 2025/05/16 11:20:24.059 Writing report data ... [2025-05-16T11:20:28.765Z] CL2 j> 2025/05/16 11:20:24.960 done. [2025-05-16T11:20:38.139Z] CL2 j> 2025/05/16 11:20:35.149 Writing report data ... [2025-05-16T11:20:39.499Z] CL2 j> 2025/05/16 11:20:35.599 done. [2025-05-16T11:20:49.017Z] CL2 j> 2025/05/16 11:20:45.874 Writing report data ... [2025-05-16T11:20:49.401Z] CL2 j> 2025/05/16 11:20:46.377 done. [2025-05-16T11:21:00.912Z] CL2 j> 2025/05/16 11:20:56.537 Writing report data ... [2025-05-16T11:21:01.710Z] CL2 j> 2025/05/16 11:20:56.938 done. [2025-05-16T11:21:12.864Z] CL2 j> 2025/05/16 11:21:07.727 Writing report data ... [2025-05-16T11:21:12.864Z] CL2 j> 2025/05/16 11:21:07.965 done. [2025-05-16T11:21:27.642Z] CL2 j> 2025/05/16 11:21:18.360 Writing report data ... [2025-05-16T11:21:27.642Z] CL2 j> 2025/05/16 11:21:19.664 done. [2025-05-16T11:21:35.333Z] CL2 j> 2025/05/16 11:21:31.334 Writing report data ... [2025-05-16T11:21:35.333Z] CL2 j> 2025/05/16 11:21:32.126 done. [2025-05-16T11:21:44.666Z] CL2 j> 2025/05/16 11:21:42.298 Writing report data ... [2025-05-16T11:21:47.859Z] CL2 j> 2025/05/16 11:21:42.854 done. [2025-05-16T11:22:01.805Z] CL2 j> 2025/05/16 11:21:53.381 Writing report data ... [2025-05-16T11:22:01.805Z] CL2 j> 2025/05/16 11:21:53.856 done. [2025-05-16T11:22:05.986Z] STF 11:22:03.384 - Heartbeat: Process LT2 is still running [2025-05-16T11:22:09.832Z] CL2 j> 2025/05/16 11:22:05.566 Writing report data ... [2025-05-16T11:22:12.289Z] CL2 j> 2025/05/16 11:22:06.178 done. [2025-05-16T11:22:20.073Z] CL2 j> 2025/05/16 11:22:16.323 Writing report data ... [2025-05-16T11:22:23.325Z] CL2 j> 2025/05/16 11:22:17.228 done. [2025-05-16T11:22:34.121Z] CL2 j> 2025/05/16 11:22:27.659 Writing report data ... [2025-05-16T11:22:34.121Z] CL2 j> 2025/05/16 11:22:28.957 done. [2025-05-16T11:22:45.483Z] CL2 j> 2025/05/16 11:22:39.652 Writing report data ... [2025-05-16T11:22:45.483Z] CL2 j> 2025/05/16 11:22:40.040 done. [2025-05-16T11:22:54.989Z] CL2 j> 2025/05/16 11:22:50.199 Writing report data ... [2025-05-16T11:22:54.989Z] CL2 j> 2025/05/16 11:22:50.473 done. [2025-05-16T11:23:06.353Z] CL2 j> 2025/05/16 11:23:00.729 Writing report data ... [2025-05-16T11:23:06.353Z] CL2 j> 2025/05/16 11:23:01.948 done. [2025-05-16T11:23:17.834Z] CL2 j> 2025/05/16 11:23:12.069 Writing report data ... [2025-05-16T11:23:17.834Z] CL2 j> 2025/05/16 11:23:12.281 done. [2025-05-16T11:23:27.112Z] CL2 j> 2025/05/16 11:23:22.963 Writing report data ... [2025-05-16T11:23:27.112Z] CL2 j> 2025/05/16 11:23:23.379 done. [2025-05-16T11:23:38.446Z] CL2 j> 2025/05/16 11:23:33.672 Writing report data ... [2025-05-16T11:23:38.446Z] CL2 j> 2025/05/16 11:23:33.954 done. [2025-05-16T11:23:51.984Z] CL2 j> 2025/05/16 11:23:44.424 Writing report data ... [2025-05-16T11:23:51.984Z] CL2 j> 2025/05/16 11:23:44.760 done. [2025-05-16T11:23:59.581Z] CL2 j> 2025/05/16 11:23:55.176 Writing report data ... [2025-05-16T11:24:00.279Z] CL2 j> 2025/05/16 11:23:56.148 done. [2025-05-16T11:24:00.279Z] STF 11:23:59.937 - Monitoring Report Summary: [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - o Process CL2 ended with the expected exit code (0) [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - o Process LT2 is still running as expected [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - +------ Step 8 - Stop LT2 process [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - | Kill running processes [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - | Processes: [LT2] [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - | [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - Killing processes: LT2 [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - o Process clean up attempt 1 for LT2 pid 3736 [2025-05-16T11:24:00.279Z] STF 11:23:59.938 - o Process LT2 pid 3736 stop() [2025-05-16T11:24:00.279Z] STF 11:23:59.939 - o Process LT2 pid 3736 killed [2025-05-16T11:24:00.593Z] STF 11:23:59.939 - EXECUTE stage completed [2025-05-16T11:24:00.593Z] STF 11:24:00.456 - [2025-05-16T11:24:00.593Z] STF 11:24:00.456 - ==================== T E A R D O W N ==================== [2025-05-16T11:24:00.593Z] STF 11:24:00.456 - Running teardown: perl C:/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17473932069130\TestJlmRemoteThreadNoAuth_1/20250516-111201-TestJlmRemoteThreadNoAuth/tearDown.pl [2025-05-16T11:24:00.895Z] STF 11:24:00.583 - TEARDOWN stage completed [2025-05-16T11:24:00.895Z] STF 11:24:00.595 - [2025-05-16T11:24:00.895Z] STF 11:24:00.595 - ===================== R E S U L T S ===================== [2025-05-16T11:24:00.895Z] STF 11:24:00.595 - Stage results: [2025-05-16T11:24:00.895Z] STF 11:24:00.595 - setUp: pass [2025-05-16T11:24:00.895Z] STF 11:24:00.595 - execute: pass [2025-05-16T11:24:00.895Z] STF 11:24:00.595 - teardown: pass [2025-05-16T11:24:00.895Z] STF 11:24:00.595 - [2025-05-16T11:24:00.895Z] STF 11:24:00.595 - Overall result: PASSED [2025-05-16T11:24:00.895Z] ----------------------------------- [2025-05-16T11:24:00.895Z] TestJlmRemoteThreadNoAuth_1_PASSED [2025-05-16T11:24:00.895Z] ----------------------------------- [2025-05-16T11:24:00.895Z] [2025-05-16T11:24:00.895Z] TEST TEARDOWN: [2025-05-16T11:24:00.895Z] Nothing to be done for teardown. [2025-05-16T11:24:00.895Z] TestJlmRemoteThreadNoAuth_1 Finish Time: Fri May 16 11:24:00 2025 Epoch Time (ms): 1747394640751