[2024-08-17T00:22:42.306Z] Running test TestJlmRemoteThreadNoAuth_1 ... [2024-08-17T00:22:42.642Z] =============================================== [2024-08-17T00:22:42.642Z] TestJlmRemoteThreadNoAuth_1 Start Time: Sat Aug 17 00:22:42 2024 Epoch Time (ms): 1723854162543 [2024-08-17T00:22:42.983Z] variation: Mode650 [2024-08-17T00:22:43.302Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-08-17T00:22:43.302Z] { \ [2024-08-17T00:22:43.302Z] echo ""; echo "TEST SETUP:"; \ [2024-08-17T00:22:43.302Z] echo "Nothing to be done for setup."; \ [2024-08-17T00:22:43.302Z] mkdir -p "C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238505824347\\TestJlmRemoteThreadNoAuth_1"; \ [2024-08-17T00:22:43.302Z] cd "C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238505824347\\TestJlmRemoteThreadNoAuth_1"; \ [2024-08-17T00:22:43.302Z] echo ""; echo "TESTING:"; \ [2024-08-17T00:22:43.302Z] perl C:/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="C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\system\\jlm/../\\STF;C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\system\\jlm/../\\aqa-systemtest""" -systemtest-prereqs="c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\\" -java-args=' -XX:-UseCompressedOops ' -results-root="C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238505824347\\TestJlmRemoteThreadNoAuth_1" -test=TestJlmRemoteThreadNoAuth; \ [2024-08-17T00:22:43.302Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238505824347\\TestJlmRemoteThreadNoAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-17T00:22:43.302Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-17T00:22:43.302Z] echo "Nothing to be done for teardown."; \ [2024-08-17T00:22:43.302Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238505824347\\TestTargetResult"; [2024-08-17T00:22:43.618Z] [2024-08-17T00:22:43.618Z] TEST SETUP: [2024-08-17T00:22:43.618Z] Nothing to be done for setup. [2024-08-17T00:22:43.618Z] [2024-08-17T00:22:43.618Z] TESTING: [2024-08-17T00:22:43.953Z] STF 00:22:43.668 - ========================= S T F ========================= [2024-08-17T00:22:43.953Z] systemtest-prereqs has been processed, and set to: c:/workspace/testDependency/system_libRetrieving amount of free space on drive containing C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17238505824347\TestJlmRemoteThreadNoAuth_1 [2024-08-17T00:22:43.953Z] There is 107388 Mb free [2024-08-17T00:22:43.953Z] STF 00:22:43.731 - ================== G E N E R A T I O N ================== [2024-08-17T00:22:43.953Z] STF 00:22:43.734 - Checking JVM: c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image [2024-08-17T00:22:43.954Z] STF 00:22:43.734 - Starting process to generate scripts: c:/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 c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\/log4j/log4j-api.jar;c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/../../testDependency/system_lib\/log4j/log4j-core.jar;C:/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 "C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17238505824347\TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17238505824347\TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth" [2024-08-17T00:22:43.954Z] 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-17T00:22:45.087Z] GEN 00:22:44.843 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: 'C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin' [2024-08-17T00:22:45.087Z] GEN 00:22:44.899 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-08-17T00:22:45.420Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-08-17T00:22:45.420Z] GEN C:\\workspace\\workspace\\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\\jvmtest\\system\\aqa-systemtest\\openjdk.test.jlm\\bin [2024-08-17T00:22:45.420Z] GEN C:\\workspace\\workspace\\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\\jvmtest\\system\\STF\\stf.core\\bin [2024-08-17T00:22:45.420Z] GEN c:/workspace/testDependency/system_lib/log4j/log4j-api.jar [2024-08-17T00:22:45.420Z] GEN c:/workspace/testDependency/system_lib/log4j/log4j-core.jar [2024-08-17T00:22:45.420Z] GEN c:/workspace/testDependency/system_lib/junit/junit.jar [2024-08-17T00:22:45.420Z] GEN 00:22:45.185 - Using Mode NoOptions. Values = '' [2024-08-17T00:22:45.420Z] GEN 00:22:45.328 - [2024-08-17T00:22:45.420Z] GEN 00:22:45.328 - Test command summary: [2024-08-17T00:22:45.420Z] GEN 00:22:45.329 - Step Stage Command Description [2024-08-17T00:22:45.420Z] GEN 00:22:45.329 - -----+--------+-----------------+------------ [2024-08-17T00:22:45.420Z] GEN 00:22:45.329 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-08-17T00:22:45.420Z] GEN 00:22:45.329 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-08-17T00:22:45.420Z] GEN 00:22:45.329 - 3 execute Monitor Wait for processes to complete [2024-08-17T00:22:45.420Z] GEN 00:22:45.329 - 4 execute kill Stop LT1 process [2024-08-17T00:22:45.420Z] GEN 00:22:45.329 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-08-17T00:22:45.420Z] GEN 00:22:45.330 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-08-17T00:22:45.420Z] GEN 00:22:45.330 - 7 execute Monitor Wait for processes to complete [2024-08-17T00:22:45.420Z] GEN 00:22:45.330 - 8 execute kill Stop LT2 process [2024-08-17T00:22:45.753Z] STF 00:22:45.394 - [2024-08-17T00:22:45.753Z] STF 00:22:45.394 - Script generation completed [2024-08-17T00:22:45.753Z] STF 00:22:45.394 - [2024-08-17T00:22:45.753Z] STF 00:22:45.396 - [2024-08-17T00:22:45.753Z] STF 00:22:45.396 - ======================= S E T U P ======================= [2024-08-17T00:22:45.753Z] STF 00:22:45.397 - Running setup: perl C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17238505824347\TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/setUp.pl [2024-08-17T00:22:45.753Z] STF 00:22:45.589 - SETUP stage completed [2024-08-17T00:22:45.753Z] STF 00:22:45.604 - [2024-08-17T00:22:45.753Z] STF 00:22:45.604 - ==================== E X E C U T E - ==================== [2024-08-17T00:22:45.753Z] STF 00:22:45.604 - Running execute: perl C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17238505824347\TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/execute.pl [2024-08-17T00:22:46.095Z] STF 00:22:45.785 - [2024-08-17T00:22:46.095Z] STF 00:22:45.785 - Java version [2024-08-17T00:22:46.095Z] STF 00:22:45.785 - Running: c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -version [2024-08-17T00:22:46.095Z] openjdk version "17.0.13-beta" 2024-10-15 [2024-08-17T00:22:46.095Z] OpenJDK Runtime Environment Temurin-17.0.13+3-202408161458 (build 17.0.13-beta+3-ea) [2024-08-17T00:22:46.095Z] OpenJDK 64-Bit Server VM Temurin-17.0.13+3-202408161458 (build 17.0.13-beta+3-ea, mixed mode, sharing) [2024-08-17T00:22:46.095Z] STF 00:22:45.905 - [2024-08-17T00:22:46.095Z] STF 00:22:45.905 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-08-17T00:22:46.095Z] STF 00:22:45.905 - | Start background process [2024-08-17T00:22:46.095Z] STF 00:22:45.905 - | Program: c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-17T00:22:46.095Z] STF 00:22:45.905 - | Mnemonic: LT1 [2024-08-17T00:22:46.095Z] STF 00:22:45.905 - | Echo: ECHO_OFF [2024-08-17T00:22:46.095Z] STF 00:22:45.905 - | Expectation: NEVER [2024-08-17T00:22:46.095Z] STF 00:22:45.906 - | [2024-08-17T00:22:46.095Z] STF 00:22:45.906 - Running command: c:/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 C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.load\bin;C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\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_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;C:\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 C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\aqa-tests\TKG\output_17238505824347\TestJlmRemoteThreadNoAuth_1\20240817-002243-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:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-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-17T00:22:46.095Z] STF 00:22:45.906 - Redirecting stderr to C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-08-17T00:22:46.095Z] STF 00:22:45.906 - Redirecting stdout to C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-08-17T00:22:46.095Z] STF 00:22:45.947 - [2024-08-17T00:22:46.095Z] STF 00:22:45.947 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-08-17T00:22:46.095Z] STF 00:22:45.947 - | Start background process [2024-08-17T00:22:46.095Z] STF 00:22:45.947 - | Program: c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-17T00:22:46.095Z] STF 00:22:45.947 - | Mnemonic: CL1 [2024-08-17T00:22:46.095Z] STF 00:22:45.947 - | Echo: ECHO_ON [2024-08-17T00:22:46.095Z] STF 00:22:45.947 - | Expectation: CLEAN_RUN within 30m [2024-08-17T00:22:46.095Z] STF 00:22:45.947 - | [2024-08-17T00:22:46.095Z] STF 00:22:45.948 - Running command: c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin;c:/workspace/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/thd_proxy.log C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-17T00:22:46.095Z] STF 00:22:45.948 - Redirecting stderr to C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-08-17T00:22:46.095Z] STF 00:22:45.948 - Redirecting stdout to C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-08-17T00:22:46.095Z] STF 00:22:45.958 - [2024-08-17T00:22:46.095Z] STF 00:22:45.958 - +------ Step 3 - Wait for processes to complete [2024-08-17T00:22:46.095Z] STF 00:22:45.958 - | Wait for processes to meet expectations [2024-08-17T00:22:46.095Z] STF 00:22:45.958 - | Processes: [LT1, CL1] [2024-08-17T00:22:46.095Z] STF 00:22:45.958 - | [2024-08-17T00:22:46.095Z] STF 00:22:45.958 - Monitoring processes: CL1 LT1 [2024-08-17T00:22:46.437Z] CL1 j> 2024/08/17 00:22:46.223 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-17T00:22:46.437Z] CL1 j> 2024/08/17 00:22:46.314 Attempting to connect [2024-08-17T00:22:46.777Z] CL1 j> 2024/08/17 00:22:46.604 Monitored VM not ready at Aug 17, 2024, 12:22:46 AM (attempt 1, elapsed 267ms). [2024-08-17T00:22:46.777Z] CL1 j> 2024/08/17 00:22:46.604 Waiting 5 secs and trying again... [2024-08-17T00:22:55.901Z] CL1 j> 2024/08/17 00:22:51.832 Attempting to connect [2024-08-17T00:23:05.557Z] CL1 j> 2024/08/17 00:23:01.090 Connection established! [2024-08-17T00:23:06.362Z] CL1 j> 2024/08/17 00:23:01.928 Starting to write data [2024-08-17T00:27:54.136Z] STF 00:27:45.410 - Heartbeat: Process LT1 is still running [2024-08-17T00:28:45.418Z] STF 00:28:41.887 - Monitoring Report Summary: [2024-08-17T00:28:45.418Z] STF 00:28:41.887 - o Process CL1 ended with the expected exit code (0) [2024-08-17T00:28:45.418Z] STF 00:28:41.887 - o Process LT1 is still running as expected [2024-08-17T00:28:45.418Z] STF 00:28:41.967 - [2024-08-17T00:28:45.418Z] STF 00:28:42.045 - +------ Step 4 - Stop LT1 process [2024-08-17T00:28:45.418Z] STF 00:28:42.045 - | Kill running processes [2024-08-17T00:28:45.418Z] STF 00:28:42.045 - | Processes: [LT1] [2024-08-17T00:28:45.418Z] STF 00:28:42.045 - | [2024-08-17T00:28:45.418Z] STF 00:28:42.045 - Killing processes: LT1 [2024-08-17T00:28:45.418Z] STF 00:28:42.045 - o Process clean up attempt 1 for LT1 pid 11360 [2024-08-17T00:28:45.418Z] STF 00:28:42.045 - o Process LT1 pid 11360 stop() [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - o Process LT1 pid 11360 killed [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - | Start background process [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - | Program: c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - | Mnemonic: LT2 [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - | Echo: ECHO_OFF [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - | Expectation: NEVER [2024-08-17T00:28:45.418Z] STF 00:28:42.131 - | [2024-08-17T00:28:45.419Z] STF 00:28:42.132 - Running command: c:/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 C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\STF\stf.load\bin;C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\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_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;C:\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 C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\aqa-tests\TKG\output_17238505824347\TestJlmRemoteThreadNoAuth_1\20240817-002243-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:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-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-17T00:28:45.419Z] STF 00:28:42.132 - Redirecting stderr to C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-08-17T00:28:45.419Z] STF 00:28:42.132 - Redirecting stdout to C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-08-17T00:28:45.419Z] STF 00:28:42.142 - [2024-08-17T00:28:45.419Z] STF 00:28:42.142 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-08-17T00:28:45.419Z] STF 00:28:42.142 - | Start background process [2024-08-17T00:28:45.419Z] STF 00:28:42.142 - | Program: c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-17T00:28:45.419Z] STF 00:28:42.142 - | Mnemonic: CL2 [2024-08-17T00:28:45.419Z] STF 00:28:42.142 - | Echo: ECHO_ON [2024-08-17T00:28:45.419Z] STF 00:28:42.142 - | Expectation: CLEAN_RUN within 30m [2024-08-17T00:28:45.419Z] STF 00:28:42.142 - | [2024-08-17T00:28:45.419Z] STF 00:28:42.143 - Running command: c:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath C:\workspace\workspace\Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin;c:/workspace/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/thd_proxy.log C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-17T00:28:45.419Z] STF 00:28:42.143 - Redirecting stderr to C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-08-17T00:28:45.419Z] STF 00:28:42.143 - Redirecting stdout to C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/TKG/output_17238505824347/TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-08-17T00:28:45.419Z] STF 00:28:42.152 - [2024-08-17T00:28:45.419Z] STF 00:28:42.152 - +------ Step 7 - Wait for processes to complete [2024-08-17T00:28:45.419Z] STF 00:28:42.152 - | Wait for processes to meet expectations [2024-08-17T00:28:45.419Z] STF 00:28:42.152 - | Processes: [LT2, CL2] [2024-08-17T00:28:45.419Z] STF 00:28:42.152 - | [2024-08-17T00:28:45.419Z] STF 00:28:42.152 - Monitoring processes: CL2 LT2 [2024-08-17T00:28:45.419Z] CL2 j> 2024/08/17 00:28:42.483 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-17T00:28:45.419Z] CL2 j> 2024/08/17 00:28:42.585 Attempting to connect [2024-08-17T00:28:45.419Z] CL2 j> 2024/08/17 00:28:42.846 Monitored VM not ready at Aug 17, 2024, 12:28:42 AM (attempt 1, elapsed 236ms). [2024-08-17T00:28:45.419Z] CL2 j> 2024/08/17 00:28:42.847 Waiting 5 secs and trying again... [2024-08-17T00:28:53.108Z] CL2 j> 2024/08/17 00:28:48.250 Attempting to connect [2024-08-17T00:29:04.222Z] CL2 j> 2024/08/17 00:28:59.006 Connection established! [2024-08-17T00:29:13.892Z] CL2 j> 2024/08/17 00:29:09.868 Writing report data ... [2024-08-17T00:29:18.848Z] CL2 j> 2024/08/17 00:29:15.201 done. [2024-08-17T00:29:31.716Z] CL2 j> 2024/08/17 00:29:26.686 Writing report data ... [2024-08-17T00:29:35.328Z] CL2 j> 2024/08/17 00:29:28.703 done. [2024-08-17T00:29:43.576Z] CL2 j> 2024/08/17 00:29:39.424 Writing report data ... [2024-08-17T00:29:47.666Z] CL2 j> 2024/08/17 00:29:41.037 done. [2024-08-17T00:29:58.945Z] CL2 j> 2024/08/17 00:29:51.780 Writing report data ... [2024-08-17T00:29:58.945Z] CL2 j> 2024/08/17 00:29:52.708 done. [2024-08-17T00:30:06.345Z] CL2 j> 2024/08/17 00:30:03.719 Writing report data ... [2024-08-17T00:30:06.345Z] CL2 j> 2024/08/17 00:30:04.652 done. [2024-08-17T00:30:21.557Z] CL2 j> 2024/08/17 00:30:15.529 Writing report data ... [2024-08-17T00:30:21.557Z] CL2 j> 2024/08/17 00:30:16.615 done. [2024-08-17T00:30:34.104Z] CL2 j> 2024/08/17 00:30:27.452 Writing report data ... [2024-08-17T00:30:34.104Z] CL2 j> 2024/08/17 00:30:28.190 done. [2024-08-17T00:30:46.044Z] CL2 j> 2024/08/17 00:30:38.811 Writing report data ... [2024-08-17T00:30:46.045Z] CL2 j> 2024/08/17 00:30:39.428 done. [2024-08-17T00:30:56.127Z] CL2 j> 2024/08/17 00:30:49.992 Writing report data ... [2024-08-17T00:30:56.127Z] CL2 j> 2024/08/17 00:30:50.743 done. [2024-08-17T00:31:06.004Z] CL2 j> 2024/08/17 00:31:01.542 Writing report data ... [2024-08-17T00:31:06.004Z] CL2 j> 2024/08/17 00:31:01.948 done. [2024-08-17T00:31:18.606Z] CL2 j> 2024/08/17 00:31:12.313 Writing report data ... [2024-08-17T00:31:18.606Z] CL2 j> 2024/08/17 00:31:12.966 done. [2024-08-17T00:31:28.573Z] CL2 j> 2024/08/17 00:31:23.419 Writing report data ... [2024-08-17T00:31:30.212Z] CL2 j> 2024/08/17 00:31:24.119 done. [2024-08-17T00:31:38.138Z] CL2 j> 2024/08/17 00:31:34.812 Writing report data ... [2024-08-17T00:31:40.571Z] CL2 j> 2024/08/17 00:31:35.429 done. [2024-08-17T00:31:49.352Z] CL2 j> 2024/08/17 00:31:46.268 Writing report data ... [2024-08-17T00:31:49.352Z] CL2 j> 2024/08/17 00:31:46.649 done. [2024-08-17T00:32:00.895Z] CL2 j> 2024/08/17 00:31:57.212 Writing report data ... [2024-08-17T00:32:01.864Z] CL2 j> 2024/08/17 00:31:57.724 done. [2024-08-17T00:32:16.185Z] CL2 j> 2024/08/17 00:32:09.450 Writing report data ... [2024-08-17T00:32:16.185Z] CL2 j> 2024/08/17 00:32:10.072 done. [2024-08-17T00:32:25.037Z] CL2 j> 2024/08/17 00:32:20.549 Writing report data ... [2024-08-17T00:32:25.037Z] CL2 j> 2024/08/17 00:32:20.888 done. [2024-08-17T00:32:33.055Z] CL2 j> 2024/08/17 00:32:31.302 Writing report data ... [2024-08-17T00:32:37.126Z] CL2 j> 2024/08/17 00:32:33.245 done. [2024-08-17T00:32:48.666Z] CL2 j> 2024/08/17 00:32:43.782 Writing report data ... [2024-08-17T00:32:48.666Z] STF 00:32:45.858 - Heartbeat: Process LT2 is still running [2024-08-17T00:32:49.927Z] CL2 j> 2024/08/17 00:32:44.977 done. [2024-08-17T00:33:00.866Z] CL2 j> 2024/08/17 00:32:55.315 Writing report data ... [2024-08-17T00:33:02.229Z] CL2 j> 2024/08/17 00:32:56.346 done. [2024-08-17T00:33:10.932Z] CL2 j> 2024/08/17 00:33:07.314 Writing report data ... [2024-08-17T00:33:13.647Z] CL2 j> 2024/08/17 00:33:08.069 done. [2024-08-17T00:33:22.918Z] CL2 j> 2024/08/17 00:33:18.497 Writing report data ... [2024-08-17T00:33:24.835Z] CL2 j> 2024/08/17 00:33:19.767 done. [2024-08-17T00:33:36.796Z] CL2 j> 2024/08/17 00:33:30.293 Writing report data ... [2024-08-17T00:33:36.796Z] CL2 j> 2024/08/17 00:33:30.695 done. [2024-08-17T00:33:45.899Z] CL2 j> 2024/08/17 00:33:41.644 Writing report data ... [2024-08-17T00:33:45.899Z] CL2 j> 2024/08/17 00:33:42.376 done. [2024-08-17T00:33:56.053Z] CL2 j> 2024/08/17 00:33:52.875 Writing report data ... [2024-08-17T00:33:57.832Z] CL2 j> 2024/08/17 00:33:54.407 done. [2024-08-17T00:34:13.245Z] CL2 j> 2024/08/17 00:34:04.759 Writing report data ... [2024-08-17T00:34:13.245Z] CL2 j> 2024/08/17 00:34:06.062 done. [2024-08-17T00:34:21.310Z] CL2 j> 2024/08/17 00:34:16.791 Writing report data ... [2024-08-17T00:34:21.310Z] CL2 j> 2024/08/17 00:34:17.326 done. [2024-08-17T00:34:33.773Z] CL2 j> 2024/08/17 00:34:27.729 Writing report data ... [2024-08-17T00:34:33.773Z] CL2 j> 2024/08/17 00:34:28.412 done. [2024-08-17T00:34:45.326Z] CL2 j> 2024/08/17 00:34:38.926 Writing report data ... [2024-08-17T00:34:45.326Z] CL2 j> 2024/08/17 00:34:39.543 done. [2024-08-17T00:34:56.003Z] CL2 j> 2024/08/17 00:34:49.936 Writing report data ... [2024-08-17T00:34:56.003Z] CL2 j> 2024/08/17 00:34:50.549 done. [2024-08-17T00:34:56.707Z] STF 00:34:55.815 - Monitoring Report Summary: [2024-08-17T00:34:56.707Z] STF 00:34:55.815 - o Process CL2 ended with the expected exit code (0) [2024-08-17T00:34:56.707Z] STF 00:34:55.815 - o Process LT2 is still running as expected [2024-08-17T00:34:56.707Z] STF 00:34:55.869 - [2024-08-17T00:34:56.707Z] STF 00:34:55.913 - +------ Step 8 - Stop LT2 process [2024-08-17T00:34:56.707Z] STF 00:34:55.914 - | Kill running processes [2024-08-17T00:34:56.707Z] STF 00:34:55.914 - | Processes: [LT2] [2024-08-17T00:34:56.707Z] STF 00:34:55.916 - | [2024-08-17T00:34:56.707Z] STF 00:34:55.916 - Killing processes: LT2 [2024-08-17T00:34:56.707Z] STF 00:34:55.916 - o Process clean up attempt 1 for LT2 pid 7920 [2024-08-17T00:34:56.707Z] STF 00:34:55.916 - o Process LT2 pid 7920 stop() [2024-08-17T00:34:56.707Z] STF 00:34:55.988 - o Process LT2 pid 7920 killed [2024-08-17T00:34:56.707Z] STF 00:34:55.989 - EXECUTE stage completed [2024-08-17T00:34:56.707Z] STF 00:34:56.329 - [2024-08-17T00:34:56.707Z] STF 00:34:56.329 - ==================== T E A R D O W N ==================== [2024-08-17T00:34:56.707Z] STF 00:34:56.329 - Running teardown: perl C:/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_windows_testList_0/aqa-tests/\TKG\output_17238505824347\TestJlmRemoteThreadNoAuth_1/20240817-002243-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-08-17T00:34:56.707Z] STF 00:34:56.523 - TEARDOWN stage completed [2024-08-17T00:34:56.707Z] STF 00:34:56.542 - [2024-08-17T00:34:56.707Z] STF 00:34:56.542 - ===================== R E S U L T S ===================== [2024-08-17T00:34:56.707Z] STF 00:34:56.542 - Stage results: [2024-08-17T00:34:56.707Z] STF 00:34:56.542 - setUp: pass [2024-08-17T00:34:56.707Z] STF 00:34:56.542 - execute: pass [2024-08-17T00:34:56.707Z] STF 00:34:56.542 - teardown: pass [2024-08-17T00:34:56.707Z] STF 00:34:56.542 - [2024-08-17T00:34:56.707Z] STF 00:34:56.542 - Overall result: PASSED [2024-08-17T00:34:56.707Z] ----------------------------------- [2024-08-17T00:34:56.707Z] TestJlmRemoteThreadNoAuth_1_PASSED [2024-08-17T00:34:56.707Z] ----------------------------------- [2024-08-17T00:34:57.047Z] [2024-08-17T00:34:57.047Z] TEST TEARDOWN: [2024-08-17T00:34:57.047Z] Nothing to be done for teardown. [2024-08-17T00:34:57.047Z] TestJlmRemoteThreadNoAuth_1 Finish Time: Sat Aug 17 00:34:56 2024 Epoch Time (ms): 1723854896881