[2024-08-08T01:44:04.789Z] Running test TestJlmRemoteThreadNoAuth_1 ... [2024-08-08T01:44:04.789Z] =============================================== [2024-08-08T01:44:04.789Z] TestJlmRemoteThreadNoAuth_1 Start Time: Thu Aug 8 01:44:03 2024 Epoch Time (ms): 1723081443964 [2024-08-08T01:44:04.789Z] variation: Mode650 [2024-08-08T01:44:04.789Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-08-08T01:44:04.789Z] { \ [2024-08-08T01:44:04.789Z] echo ""; echo "TEST SETUP:"; \ [2024-08-08T01:44:04.789Z] echo "Nothing to be done for setup."; \ [2024-08-08T01:44:04.789Z] mkdir -p "F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_1723077868254\\TestJlmRemoteThreadNoAuth_1"; \ [2024-08-08T01:44:04.789Z] cd "F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_1723077868254\\TestJlmRemoteThreadNoAuth_1"; \ [2024-08-08T01:44:04.789Z] echo ""; echo "TESTING:"; \ [2024-08-08T01:44:04.789Z] perl F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\system\\jlm/../\\STF\\stf.core\\scripts\\stf.pl -test-root="F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\system\\jlm/../\\STF;F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\system\\jlm/../\\aqa-systemtest""" -systemtest-prereqs="F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\\" -java-args=' -XX:-UseCompressedOops ' -results-root="F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_1723077868254\\TestJlmRemoteThreadNoAuth_1" -test=TestJlmRemoteThreadNoAuth; \ [2024-08-08T01:44:04.789Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_PASSED"; echo "-----------------------------------"; cd F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/; rm -f -r "F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_1723077868254\\TestJlmRemoteThreadNoAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-08T01:44:04.789Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-08T01:44:04.789Z] echo "Nothing to be done for teardown."; \ [2024-08-08T01:44:04.789Z] } 2>&1 | tee -a "F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_1723077868254\\TestTargetResult"; [2024-08-08T01:44:04.789Z] [2024-08-08T01:44:04.789Z] TEST SETUP: [2024-08-08T01:44:04.789Z] Nothing to be done for setup. [2024-08-08T01:44:04.789Z] [2024-08-08T01:44:04.789Z] TESTING: [2024-08-08T01:44:04.789Z] STF 01:44:04.499 - ========================= S T F ========================= [2024-08-08T01:44:04.789Z] systemtest-prereqs has been processed, and set to: F:/workspace/testDependency/system_libRetrieving amount of free space on drive containing F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_1723077868254\TestJlmRemoteThreadNoAuth_1 [2024-08-08T01:44:04.789Z] There is 113822 Mb free [2024-08-08T01:44:04.789Z] STF 01:44:04.527 - ================== G E N E R A T I O N ================== [2024-08-08T01:44:04.789Z] STF 01:44:04.530 - Checking JVM: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image [2024-08-08T01:44:04.790Z] STF 01:44:04.531 - Starting process to generate scripts: F:/workspace/workspace/Test_openjdk17_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 -classpath F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\/log4j/log4j-api.jar;F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\/log4j/log4j-core.jar;F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_1723077868254\TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_1723077868254\TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth" [2024-08-08T01:44:04.790Z] GEN stderr OpenJDK 64-Bit Server VM warning: 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 [2024-08-08T01:44:05.462Z] GEN 01:44:05.400 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: 'F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin' [2024-08-08T01:44:05.462Z] GEN 01:44:05.429 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-08-08T01:44:05.776Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-08-08T01:44:05.776Z] GEN F:\\workspace\\workspace\\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\\jvmtest\\system\\aqa-systemtest\\openjdk.test.jlm\\bin [2024-08-08T01:44:05.776Z] GEN F:\\workspace\\workspace\\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\\jvmtest\\system\\STF\\stf.core\\bin [2024-08-08T01:44:05.776Z] GEN F:/workspace/testDependency/system_lib/log4j/log4j-api.jar [2024-08-08T01:44:05.776Z] GEN F:/workspace/testDependency/system_lib/log4j/log4j-core.jar [2024-08-08T01:44:05.776Z] GEN F:/workspace/testDependency/system_lib/junit/junit.jar [2024-08-08T01:44:05.776Z] GEN 01:44:05.652 - Using Mode NoOptions. Values = '' [2024-08-08T01:44:05.776Z] GEN 01:44:05.756 - [2024-08-08T01:44:05.776Z] GEN 01:44:05.756 - Test command summary: [2024-08-08T01:44:05.776Z] GEN 01:44:05.756 - Step Stage Command Description [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - -----+--------+-----------------+------------ [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - 3 execute Monitor Wait for processes to complete [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - 4 execute kill Stop LT1 process [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - 7 execute Monitor Wait for processes to complete [2024-08-08T01:44:05.776Z] GEN 01:44:05.757 - 8 execute kill Stop LT2 process [2024-08-08T01:44:06.085Z] STF 01:44:05.799 - [2024-08-08T01:44:06.085Z] STF 01:44:05.799 - Script generation completed [2024-08-08T01:44:06.085Z] STF 01:44:05.799 - [2024-08-08T01:44:06.085Z] STF 01:44:05.799 - [2024-08-08T01:44:06.085Z] STF 01:44:05.799 - ======================= S E T U P ======================= [2024-08-08T01:44:06.085Z] STF 01:44:05.799 - Running setup: perl F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_1723077868254\TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/setUp.pl [2024-08-08T01:44:06.085Z] STF 01:44:05.931 - SETUP stage completed [2024-08-08T01:44:06.085Z] STF 01:44:05.942 - [2024-08-08T01:44:06.085Z] STF 01:44:05.942 - ==================== E X E C U T E - ==================== [2024-08-08T01:44:06.085Z] STF 01:44:05.942 - Running execute: perl F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_1723077868254\TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/execute.pl [2024-08-08T01:44:06.394Z] STF 01:44:06.071 - [2024-08-08T01:44:06.394Z] STF 01:44:06.071 - Java version [2024-08-08T01:44:06.394Z] STF 01:44:06.071 - Running: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -version [2024-08-08T01:44:06.394Z] openjdk version "17.0.13-beta" 2024-10-15 [2024-08-08T01:44:06.394Z] OpenJDK Runtime Environment Temurin-17.0.13+2-202408072102 (build 17.0.13-beta+2-ea) [2024-08-08T01:44:06.394Z] OpenJDK 64-Bit Server VM Temurin-17.0.13+2-202408072102 (build 17.0.13-beta+2-ea, mixed mode, sharing) [2024-08-08T01:44:06.394Z] STF 01:44:06.163 - [2024-08-08T01:44:06.394Z] STF 01:44:06.163 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-08-08T01:44:06.394Z] STF 01:44:06.163 - | Start background process [2024-08-08T01:44:06.394Z] STF 01:44:06.163 - | Program: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-08T01:44:06.394Z] STF 01:44:06.163 - | Mnemonic: LT1 [2024-08-08T01:44:06.394Z] STF 01:44:06.163 - | Echo: ECHO_OFF [2024-08-08T01:44:06.394Z] STF 01:44:06.163 - | Expectation: NEVER [2024-08-08T01:44:06.394Z] STF 01:44:06.163 - | [2024-08-08T01:44:06.394Z] STF 01:44:06.164 - Running command: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:-UseCompressedOops -classpath F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.load\bin;F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.core\bin;F:/workspace/testDependency/system_lib/log4j/log4j-api.jar;F:/workspace/testDependency/system_lib/log4j/log4j-core.jar;F:/workspace/testDependency/system_lib/junit/junit.jar;F:/workspace/testDependency/system_lib/junit/hamcrest-core.jar;F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.math\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\aqa-tests\TKG\output_1723077868254\TestJlmRemoteThreadNoAuth_1\20240808-014404-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 F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-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 [2024-08-08T01:44:06.394Z] STF 01:44:06.165 - Redirecting stderr to F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-08-08T01:44:06.394Z] STF 01:44:06.165 - Redirecting stdout to F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - | Start background process [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - | Program: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - | Mnemonic: CL1 [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - | Echo: ECHO_ON [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - | Expectation: CLEAN_RUN within 30m [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - | [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - Running command: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin;F:/workspace/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/thd_proxy.log F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - Redirecting stderr to F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-08-08T01:44:06.394Z] STF 01:44:06.192 - Redirecting stdout to F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-08-08T01:44:06.394Z] STF 01:44:06.196 - [2024-08-08T01:44:06.394Z] STF 01:44:06.196 - +------ Step 3 - Wait for processes to complete [2024-08-08T01:44:06.394Z] STF 01:44:06.196 - | Wait for processes to meet expectations [2024-08-08T01:44:06.394Z] STF 01:44:06.196 - | Processes: [LT1, CL1] [2024-08-08T01:44:06.394Z] STF 01:44:06.196 - | [2024-08-08T01:44:06.394Z] STF 01:44:06.196 - Monitoring processes: CL1 LT1 [2024-08-08T01:44:06.705Z] CL1 j> 2024/08/08 01:44:06.393 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-08T01:44:06.705Z] CL1 j> 2024/08/08 01:44:06.472 Attempting to connect [2024-08-08T01:44:07.028Z] CL1 j> 2024/08/08 01:44:06.691 Monitored VM not ready at Aug 8, 2024, 1:44:06 AM (attempt 1, elapsed 196ms). [2024-08-08T01:44:07.028Z] CL1 j> 2024/08/08 01:44:06.692 Waiting 5 secs and trying again... [2024-08-08T01:44:13.396Z] CL1 j> 2024/08/08 01:44:11.851 Attempting to connect [2024-08-08T01:44:20.219Z] CL1 j> 2024/08/08 01:44:18.130 Connection established! [2024-08-08T01:44:20.219Z] CL1 j> 2024/08/08 01:44:18.438 Starting to write data [2024-08-08T01:49:19.276Z] STF 01:49:06.521 - Heartbeat: Process LT1 is still running [2024-08-08T01:50:01.582Z] STF 01:49:56.209 - Monitoring Report Summary: [2024-08-08T01:50:01.583Z] STF 01:49:56.209 - o Process CL1 ended with the expected exit code (0) [2024-08-08T01:50:01.583Z] STF 01:49:56.209 - o Process LT1 is still running as expected [2024-08-08T01:50:01.583Z] STF 01:49:56.267 - [2024-08-08T01:50:01.583Z] STF 01:49:56.267 - +------ Step 4 - Stop LT1 process [2024-08-08T01:50:01.583Z] STF 01:49:56.267 - | Kill running processes [2024-08-08T01:50:01.583Z] STF 01:49:56.267 - | Processes: [LT1] [2024-08-08T01:50:01.583Z] STF 01:49:56.267 - | [2024-08-08T01:50:01.583Z] STF 01:49:56.267 - Killing processes: LT1 [2024-08-08T01:50:01.583Z] STF 01:49:56.347 - o Process clean up attempt 1 for LT1 pid 12520 [2024-08-08T01:50:01.583Z] STF 01:49:56.348 - o Process LT1 pid 12520 stop() [2024-08-08T01:50:01.583Z] STF 01:49:56.429 - o Process LT1 pid 12520 killed [2024-08-08T01:50:01.583Z] STF 01:49:56.429 - [2024-08-08T01:50:01.583Z] STF 01:49:56.429 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-08-08T01:50:01.583Z] STF 01:49:56.429 - | Start background process [2024-08-08T01:50:01.583Z] STF 01:49:56.429 - | Program: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-08T01:50:01.583Z] STF 01:49:56.429 - | Mnemonic: LT2 [2024-08-08T01:50:01.583Z] STF 01:49:56.429 - | Echo: ECHO_OFF [2024-08-08T01:50:01.583Z] STF 01:49:56.430 - | Expectation: NEVER [2024-08-08T01:50:01.583Z] STF 01:49:56.430 - | [2024-08-08T01:50:01.583Z] STF 01:49:56.430 - Running command: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:-UseCompressedOops -classpath F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.load\bin;F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.core\bin;F:/workspace/testDependency/system_lib/log4j/log4j-api.jar;F:/workspace/testDependency/system_lib/log4j/log4j-core.jar;F:/workspace/testDependency/system_lib/junit/junit.jar;F:/workspace/testDependency/system_lib/junit/hamcrest-core.jar;F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.math\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\aqa-tests\TKG\output_1723077868254\TestJlmRemoteThreadNoAuth_1\20240808-014404-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 F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-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 [2024-08-08T01:50:01.583Z] STF 01:49:56.430 - Redirecting stderr to F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-08-08T01:50:01.583Z] STF 01:49:56.430 - Redirecting stdout to F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-08-08T01:50:01.583Z] STF 01:49:56.434 - [2024-08-08T01:50:01.583Z] STF 01:49:56.434 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-08-08T01:50:01.583Z] STF 01:49:56.434 - | Start background process [2024-08-08T01:50:01.583Z] STF 01:49:56.434 - | Program: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-08T01:50:01.583Z] STF 01:49:56.434 - | Mnemonic: CL2 [2024-08-08T01:50:01.583Z] STF 01:49:56.434 - | Echo: ECHO_ON [2024-08-08T01:50:01.583Z] STF 01:49:56.434 - | Expectation: CLEAN_RUN within 30m [2024-08-08T01:50:01.583Z] STF 01:49:56.434 - | [2024-08-08T01:50:01.583Z] STF 01:49:56.435 - Running command: F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath F:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin;F:/workspace/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/thd_proxy.log F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-08T01:50:01.583Z] STF 01:49:56.435 - Redirecting stderr to F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-08-08T01:50:01.583Z] STF 01:49:56.435 - Redirecting stdout to F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_1723077868254/TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-08-08T01:50:01.583Z] STF 01:49:56.438 - [2024-08-08T01:50:01.583Z] STF 01:49:56.438 - +------ Step 7 - Wait for processes to complete [2024-08-08T01:50:01.583Z] STF 01:49:56.438 - | Wait for processes to meet expectations [2024-08-08T01:50:01.583Z] STF 01:49:56.438 - | Processes: [LT2, CL2] [2024-08-08T01:50:01.583Z] STF 01:49:56.438 - | [2024-08-08T01:50:01.583Z] STF 01:49:56.438 - Monitoring processes: CL2 LT2 [2024-08-08T01:50:01.583Z] CL2 j> 2024/08/08 01:49:56.630 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-08T01:50:01.583Z] CL2 j> 2024/08/08 01:49:56.702 Attempting to connect [2024-08-08T01:50:01.583Z] CL2 j> 2024/08/08 01:49:56.900 Monitored VM not ready at Aug 8, 2024, 1:49:56 AM (attempt 1, elapsed 175ms). [2024-08-08T01:50:01.583Z] CL2 j> 2024/08/08 01:49:56.900 Waiting 5 secs and trying again... [2024-08-08T01:50:04.162Z] CL2 j> 2024/08/08 01:50:02.502 Attempting to connect [2024-08-08T01:50:09.360Z] CL2 j> 2024/08/08 01:50:05.106 Connection established! [2024-08-08T01:50:21.171Z] CL2 j> 2024/08/08 01:50:18.753 Writing report data ... [2024-08-08T01:50:26.328Z] CL2 j> 2024/08/08 01:50:24.171 done. [2024-08-08T01:50:37.064Z] CL2 j> 2024/08/08 01:50:35.232 Writing report data ... [2024-08-08T01:50:39.547Z] CL2 j> 2024/08/08 01:50:36.828 done. [2024-08-08T01:50:48.474Z] CL2 j> 2024/08/08 01:50:47.114 Writing report data ... [2024-08-08T01:50:48.867Z] CL2 j> 2024/08/08 01:50:47.898 done. [2024-08-08T01:51:00.514Z] CL2 j> 2024/08/08 01:50:58.251 Writing report data ... [2024-08-08T01:51:00.514Z] CL2 j> 2024/08/08 01:50:59.002 done. [2024-08-08T01:51:10.458Z] CL2 j> 2024/08/08 01:51:09.287 Writing report data ... [2024-08-08T01:51:11.362Z] CL2 j> 2024/08/08 01:51:09.843 done. [2024-08-08T01:51:23.217Z] CL2 j> 2024/08/08 01:51:20.222 Writing report data ... [2024-08-08T01:51:23.217Z] CL2 j> 2024/08/08 01:51:21.147 done. [2024-08-08T01:51:33.005Z] CL2 j> 2024/08/08 01:51:31.589 Writing report data ... [2024-08-08T01:51:33.936Z] CL2 j> 2024/08/08 01:51:32.194 done. [2024-08-08T01:51:43.640Z] CL2 j> 2024/08/08 01:51:42.392 Writing report data ... [2024-08-08T01:51:44.519Z] CL2 j> 2024/08/08 01:51:42.876 done. [2024-08-08T01:51:54.638Z] CL2 j> 2024/08/08 01:51:53.166 Writing report data ... [2024-08-08T01:51:55.049Z] CL2 j> 2024/08/08 01:51:53.903 done. [2024-08-08T01:52:06.058Z] CL2 j> 2024/08/08 01:52:04.331 Writing report data ... [2024-08-08T01:52:06.058Z] CL2 j> 2024/08/08 01:52:04.932 done. [2024-08-08T01:52:16.899Z] CL2 j> 2024/08/08 01:52:15.401 Writing report data ... [2024-08-08T01:52:17.336Z] CL2 j> 2024/08/08 01:52:15.911 done. [2024-08-08T01:52:27.938Z] CL2 j> 2024/08/08 01:52:26.120 Writing report data ... [2024-08-08T01:52:29.548Z] CL2 j> 2024/08/08 01:52:27.532 done. [2024-08-08T01:52:38.716Z] CL2 j> 2024/08/08 01:52:37.919 Writing report data ... [2024-08-08T01:52:40.554Z] CL2 j> 2024/08/08 01:52:38.568 done. [2024-08-08T01:52:51.023Z] CL2 j> 2024/08/08 01:52:48.907 Writing report data ... [2024-08-08T01:52:51.546Z] CL2 j> 2024/08/08 01:52:49.581 done. [2024-08-08T01:53:02.627Z] CL2 j> 2024/08/08 01:53:00.368 Writing report data ... [2024-08-08T01:53:03.634Z] CL2 j> 2024/08/08 01:53:01.499 done. [2024-08-08T01:53:13.004Z] CL2 j> 2024/08/08 01:53:12.174 Writing report data ... [2024-08-08T01:53:14.390Z] CL2 j> 2024/08/08 01:53:12.771 done. [2024-08-08T01:53:26.317Z] CL2 j> 2024/08/08 01:53:23.309 Writing report data ... [2024-08-08T01:53:26.317Z] CL2 j> 2024/08/08 01:53:24.458 done. [2024-08-08T01:53:38.272Z] CL2 j> 2024/08/08 01:53:34.863 Writing report data ... [2024-08-08T01:53:38.272Z] CL2 j> 2024/08/08 01:53:35.345 done. [2024-08-08T01:53:48.318Z] CL2 j> 2024/08/08 01:53:45.552 Writing report data ... [2024-08-08T01:53:48.318Z] CL2 j> 2024/08/08 01:53:45.803 done. [2024-08-08T01:53:58.019Z] CL2 j> 2024/08/08 01:53:55.982 Writing report data ... [2024-08-08T01:53:59.055Z] CL2 j> 2024/08/08 01:53:57.547 done. [2024-08-08T01:54:06.986Z] STF 01:54:06.287 - Heartbeat: Process CL2 is still running [2024-08-08T01:54:08.939Z] CL2 j> 2024/08/08 01:54:08.030 Writing report data ... [2024-08-08T01:54:10.345Z] CL2 j> 2024/08/08 01:54:08.391 done. [2024-08-08T01:54:20.032Z] CL2 j> 2024/08/08 01:54:18.908 Writing report data ... [2024-08-08T01:54:20.923Z] CL2 j> 2024/08/08 01:54:19.470 done. [2024-08-08T01:54:32.513Z] CL2 j> 2024/08/08 01:54:29.935 Writing report data ... [2024-08-08T01:54:33.341Z] CL2 j> 2024/08/08 01:54:30.631 done. [2024-08-08T01:54:42.952Z] CL2 j> 2024/08/08 01:54:41.107 Writing report data ... [2024-08-08T01:54:43.325Z] CL2 j> 2024/08/08 01:54:41.663 done. [2024-08-08T01:54:52.724Z] CL2 j> 2024/08/08 01:54:51.868 Writing report data ... [2024-08-08T01:54:54.547Z] CL2 j> 2024/08/08 01:54:52.970 done. [2024-08-08T01:55:04.349Z] CL2 j> 2024/08/08 01:55:03.217 Writing report data ... [2024-08-08T01:55:05.135Z] CL2 j> 2024/08/08 01:55:03.732 done. [2024-08-08T01:55:16.706Z] CL2 j> 2024/08/08 01:55:14.434 Writing report data ... [2024-08-08T01:55:19.436Z] CL2 j> 2024/08/08 01:55:17.743 done. [2024-08-08T01:55:31.423Z] CL2 j> 2024/08/08 01:55:28.175 Writing report data ... [2024-08-08T01:55:31.423Z] CL2 j> 2024/08/08 01:55:28.551 done. [2024-08-08T01:55:40.751Z] CL2 j> 2024/08/08 01:55:38.853 Writing report data ... [2024-08-08T01:55:42.408Z] CL2 j> 2024/08/08 01:55:39.768 done. [2024-08-08T01:55:51.073Z] CL2 j> 2024/08/08 01:55:50.353 Writing report data ... [2024-08-08T01:55:52.926Z] CL2 j> 2024/08/08 01:55:51.088 done. [2024-08-08T01:55:56.752Z] STF 01:55:55.675 - Monitoring Report Summary: [2024-08-08T01:55:56.752Z] STF 01:55:55.737 - o Process CL2 ended with the expected exit code (0) [2024-08-08T01:55:56.752Z] STF 01:55:55.739 - o Process LT2 is still running as expected [2024-08-08T01:55:56.752Z] STF 01:55:55.974 - [2024-08-08T01:55:56.752Z] STF 01:55:55.976 - +------ Step 8 - Stop LT2 process [2024-08-08T01:55:56.752Z] STF 01:55:55.976 - | Kill running processes [2024-08-08T01:55:56.752Z] STF 01:55:55.976 - | Processes: [LT2] [2024-08-08T01:55:56.752Z] STF 01:55:55.976 - | [2024-08-08T01:55:56.752Z] STF 01:55:56.020 - Killing processes: LT2 [2024-08-08T01:55:56.752Z] STF 01:55:56.020 - o Process clean up attempt 1 for LT2 pid 3356 [2024-08-08T01:55:56.752Z] STF 01:55:56.022 - o Process LT2 pid 3356 stop() [2024-08-08T01:55:56.752Z] STF 01:55:56.060 - o Process LT2 pid 3356 killed [2024-08-08T01:55:56.752Z] STF 01:55:56.060 - EXECUTE stage completed [2024-08-08T01:55:56.752Z] STF 01:55:56.565 - [2024-08-08T01:55:56.752Z] STF 01:55:56.565 - ==================== T E A R D O W N ==================== [2024-08-08T01:55:56.752Z] STF 01:55:56.565 - Running teardown: perl F:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_1723077868254\TestJlmRemoteThreadNoAuth_1/20240808-014404-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-08-08T01:55:56.753Z] STF 01:55:56.691 - TEARDOWN stage completed [2024-08-08T01:55:56.753Z] STF 01:55:56.709 - [2024-08-08T01:55:56.753Z] STF 01:55:56.709 - ===================== R E S U L T S ===================== [2024-08-08T01:55:56.753Z] STF 01:55:56.709 - Stage results: [2024-08-08T01:55:56.753Z] STF 01:55:56.709 - setUp: pass [2024-08-08T01:55:56.753Z] STF 01:55:56.709 - execute: pass [2024-08-08T01:55:56.753Z] STF 01:55:56.709 - teardown: pass [2024-08-08T01:55:56.753Z] STF 01:55:56.709 - [2024-08-08T01:55:56.753Z] STF 01:55:56.709 - Overall result: PASSED [2024-08-08T01:55:56.753Z] ----------------------------------- [2024-08-08T01:55:56.753Z] TestJlmRemoteThreadNoAuth_1_PASSED [2024-08-08T01:55:56.753Z] ----------------------------------- [2024-08-08T01:55:57.063Z] [2024-08-08T01:55:57.063Z] TEST TEARDOWN: [2024-08-08T01:55:57.063Z] Nothing to be done for teardown. [2024-08-08T01:55:57.063Z] TestJlmRemoteThreadNoAuth_1 Finish Time: Thu Aug 8 01:55:56 2024 Epoch Time (ms): 1723082156958