[2024-11-15T14:34:41.346Z] Running test TestJlmRemoteThreadNoAuth_2 ... [2024-11-15T14:34:41.346Z] =============================================== [2024-11-15T14:34:41.658Z] TestJlmRemoteThreadNoAuth_2 Start Time: Fri Nov 15 14:34:41 2024 Epoch Time (ms): 1731681281346 [2024-11-15T14:34:41.658Z] variation: Mode1000 [2024-11-15T14:34:41.658Z] JVM_OPTIONS: [2024-11-15T14:34:41.658Z] { \ [2024-11-15T14:34:41.658Z] echo ""; echo "TEST SETUP:"; \ [2024-11-15T14:34:41.659Z] echo "Nothing to be done for setup."; \ [2024-11-15T14:34:41.659Z] mkdir -p "C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\\TKG\\output_17316799798033\\TestJlmRemoteThreadNoAuth_2"; \ [2024-11-15T14:34:41.659Z] cd "C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\\TKG\\output_17316799798033\\TestJlmRemoteThreadNoAuth_2"; \ [2024-11-15T14:34:41.659Z] echo ""; echo "TESTING:"; \ [2024-11-15T14:34:41.659Z] perl C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests///..//jvmtest\\system\\jlm/../\\STF\\stf.core\\scripts\\stf.pl -test-root="C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests///..//jvmtest\\system\\jlm/../\\STF;C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests///..//jvmtest\\system\\jlm/../\\aqa-systemtest""" -systemtest-prereqs="c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/../../testDependency/system_lib\\" -java-args=' ' -results-root="C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\\TKG\\output_17316799798033\\TestJlmRemoteThreadNoAuth_2" -test=TestJlmRemoteThreadNoAuth; \ [2024-11-15T14:34:41.659Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_2""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\\TKG\\output_17316799798033\\TestJlmRemoteThreadNoAuth_2"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_2""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-15T14:34:41.659Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-15T14:34:41.659Z] echo "Nothing to be done for teardown."; \ [2024-11-15T14:34:41.659Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\\TKG\\output_17316799798033\\TestTargetResult"; [2024-11-15T14:34:41.984Z] [2024-11-15T14:34:41.984Z] TEST SETUP: [2024-11-15T14:34:41.984Z] Nothing to be done for setup. [2024-11-15T14:34:41.984Z] [2024-11-15T14:34:41.984Z] TESTING: [2024-11-15T14:34:42.307Z] STF 14:34:41.955 - ========================= S T F ========================= [2024-11-15T14:34:42.307Z] systemtest-prereqs has been processed, and set to: c:/workspace/testDependency/system_libRetrieving amount of free space on drive containing C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\TKG\output_17316799798033\TestJlmRemoteThreadNoAuth_2 [2024-11-15T14:34:42.632Z] There is 97350 Mb free [2024-11-15T14:34:42.632Z] STF 14:34:42.063 - ================== G E N E R A T I O N ================== [2024-11-15T14:34:42.632Z] STF 14:34:42.067 - Checking JVM: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image [2024-11-15T14:34:42.632Z] STF 14:34:42.068 - Starting process to generate scripts: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/../../testDependency/system_lib\/log4j/log4j-api.jar;c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/../../testDependency/system_lib\/log4j/log4j-core.jar;C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\TKG\output_17316799798033\TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\TKG\output_17316799798033\TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth" [2024-11-15T14:34:44.215Z] GEN 14:34:43.902 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: 'C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\aqa-systemtest\openjdk.test.jlm\bin' [2024-11-15T14:34:44.215Z] GEN 14:34:43.963 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-11-15T14:34:44.538Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-11-15T14:34:44.538Z] GEN C:\\workspace\\workspace\\Test_openjdk11_hs_sanity.system_x86-32_windows\\jvmtest\\system\\aqa-systemtest\\openjdk.test.jlm\\bin [2024-11-15T14:34:44.538Z] GEN C:\\workspace\\workspace\\Test_openjdk11_hs_sanity.system_x86-32_windows\\jvmtest\\system\\STF\\stf.core\\bin [2024-11-15T14:34:44.538Z] GEN c:/workspace/testDependency/system_lib/log4j/log4j-api.jar [2024-11-15T14:34:44.538Z] GEN c:/workspace/testDependency/system_lib/log4j/log4j-core.jar [2024-11-15T14:34:44.538Z] GEN c:/workspace/testDependency/system_lib/junit/junit.jar [2024-11-15T14:34:44.862Z] GEN 14:34:44.545 - Using Mode NoOptions. Values = '' [2024-11-15T14:34:45.188Z] GEN 14:34:45.044 - [2024-11-15T14:34:45.188Z] GEN 14:34:45.044 - Test command summary: [2024-11-15T14:34:45.188Z] GEN 14:34:45.044 - Step Stage Command Description [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - -----+--------+-----------------+------------ [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - 3 execute Monitor Wait for processes to complete [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - 4 execute kill Stop LT1 process [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - 7 execute Monitor Wait for processes to complete [2024-11-15T14:34:45.188Z] GEN 14:34:45.045 - 8 execute kill Stop LT2 process [2024-11-15T14:34:45.188Z] STF 14:34:45.103 - [2024-11-15T14:34:45.188Z] STF 14:34:45.103 - Script generation completed [2024-11-15T14:34:45.188Z] STF 14:34:45.103 - [2024-11-15T14:34:45.188Z] STF 14:34:45.111 - [2024-11-15T14:34:45.188Z] STF 14:34:45.111 - ======================= S E T U P ======================= [2024-11-15T14:34:45.188Z] STF 14:34:45.111 - Running setup: perl C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\TKG\output_17316799798033\TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/setUp.pl [2024-11-15T14:34:45.517Z] STF 14:34:45.312 - SETUP stage completed [2024-11-15T14:34:45.517Z] STF 14:34:45.334 - [2024-11-15T14:34:45.517Z] STF 14:34:45.334 - ==================== E X E C U T E - ==================== [2024-11-15T14:34:45.517Z] STF 14:34:45.334 - Running execute: perl C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\TKG\output_17316799798033\TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/execute.pl [2024-11-15T14:34:45.844Z] STF 14:34:45.522 - [2024-11-15T14:34:45.844Z] STF 14:34:45.522 - Java version [2024-11-15T14:34:45.844Z] STF 14:34:45.522 - Running: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java -version [2024-11-15T14:34:45.844Z] openjdk version "11.0.26-beta" 2025-01-21 [2024-11-15T14:34:45.844Z] OpenJDK Runtime Environment Temurin-11.0.26+1-202411140033 (build 11.0.26-beta+1-ea) [2024-11-15T14:34:45.844Z] OpenJDK Client VM Temurin-11.0.26+1-202411140033 (build 11.0.26-beta+1-ea, mixed mode) [2024-11-15T14:34:45.844Z] STF 14:34:45.683 - [2024-11-15T14:34:45.844Z] STF 14:34:45.683 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-11-15T14:34:45.844Z] STF 14:34:45.683 - | Start background process [2024-11-15T14:34:45.844Z] STF 14:34:45.683 - | Program: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java [2024-11-15T14:34:45.844Z] STF 14:34:45.683 - | Mnemonic: LT1 [2024-11-15T14:34:45.844Z] STF 14:34:45.683 - | Echo: ECHO_OFF [2024-11-15T14:34:45.844Z] STF 14:34:45.683 - | Expectation: NEVER [2024-11-15T14:34:45.844Z] STF 14:34:45.683 - | [2024-11-15T14:34:45.844Z] STF 14:34:45.684 - Running command: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -classpath C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\STF\stf.load\bin;C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\STF\stf.core\bin;c:/workspace/testDependency/system_lib/log4j/log4j-api.jar;c:/workspace/testDependency/system_lib/log4j/log4j-core.jar;c:/workspace/testDependency/system_lib/junit/junit.jar;c:/workspace/testDependency/system_lib/junit/hamcrest-core.jar;C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\aqa-systemtest\openjdk.test.math\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\aqa-tests\TKG\output_17316799798033\TestJlmRemoteThreadNoAuth_2\20241115-143441-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_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-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-11-15T14:34:45.844Z] STF 14:34:45.684 - Redirecting stderr to C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-11-15T14:34:45.844Z] STF 14:34:45.684 - Redirecting stdout to C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-11-15T14:34:45.844Z] STF 14:34:45.732 - [2024-11-15T14:34:45.844Z] STF 14:34:45.732 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-11-15T14:34:45.844Z] STF 14:34:45.732 - | Start background process [2024-11-15T14:34:45.844Z] STF 14:34:45.732 - | Program: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java [2024-11-15T14:34:45.844Z] STF 14:34:45.732 - | Mnemonic: CL1 [2024-11-15T14:34:45.844Z] STF 14:34:45.732 - | Echo: ECHO_ON [2024-11-15T14:34:45.844Z] STF 14:34:45.732 - | Expectation: CLEAN_RUN within 30m [2024-11-15T14:34:45.844Z] STF 14:34:45.732 - | [2024-11-15T14:34:45.844Z] STF 14:34:45.733 - Running command: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java -Xmx256m -classpath C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\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_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/thd_proxy.log C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-11-15T14:34:45.844Z] STF 14:34:45.733 - Redirecting stderr to C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-11-15T14:34:45.844Z] STF 14:34:45.733 - Redirecting stdout to C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-11-15T14:34:45.844Z] STF 14:34:45.746 - [2024-11-15T14:34:45.844Z] STF 14:34:45.746 - +------ Step 3 - Wait for processes to complete [2024-11-15T14:34:45.844Z] STF 14:34:45.746 - | Wait for processes to meet expectations [2024-11-15T14:34:45.844Z] STF 14:34:45.746 - | Processes: [LT1, CL1] [2024-11-15T14:34:45.844Z] STF 14:34:45.746 - | [2024-11-15T14:34:45.844Z] STF 14:34:45.746 - Monitoring processes: CL1 LT1 [2024-11-15T14:34:46.536Z] CL1 j> 2024/11/15 14:34:46.149 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-15T14:34:46.536Z] CL1 j> 2024/11/15 14:34:46.268 Attempting to connect [2024-11-15T14:34:46.863Z] CL1 j> 2024/11/15 14:34:46.634 Monitored VM not ready at Nov 15, 2024, 2:34:46 PM (attempt 1, elapsed 343ms). [2024-11-15T14:34:46.863Z] CL1 j> 2024/11/15 14:34:46.634 Waiting 5 secs and trying again... [2024-11-15T14:34:52.783Z] CL1 j> 2024/11/15 14:34:51.640 Attempting to connect [2024-11-15T14:34:52.783Z] CL1 j> 2024/11/15 14:34:51.959 Connection established! [2024-11-15T14:34:52.783Z] CL1 j> 2024/11/15 14:34:52.125 Starting to write data [2024-11-15T14:39:47.009Z] STF 14:39:45.043 - Heartbeat: Process CL1 is still running [2024-11-15T14:40:06.195Z] STF 14:40:04.853 - Monitoring Report Summary: [2024-11-15T14:40:06.195Z] STF 14:40:04.853 - o Process CL1 ended with the expected exit code (0) [2024-11-15T14:40:06.195Z] STF 14:40:04.853 - o Process LT1 is still running as expected [2024-11-15T14:40:06.195Z] STF 14:40:04.854 - [2024-11-15T14:40:06.195Z] STF 14:40:04.855 - +------ Step 4 - Stop LT1 process [2024-11-15T14:40:06.195Z] STF 14:40:04.855 - | Kill running processes [2024-11-15T14:40:06.195Z] STF 14:40:04.871 - | Processes: [LT1] [2024-11-15T14:40:06.195Z] STF 14:40:04.871 - | [2024-11-15T14:40:06.195Z] STF 14:40:04.871 - Killing processes: LT1 [2024-11-15T14:40:06.195Z] STF 14:40:04.871 - o Process clean up attempt 1 for LT1 pid 43476 [2024-11-15T14:40:06.195Z] STF 14:40:04.872 - o Process LT1 pid 43476 stop() [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - o Process LT1 pid 43476 killed [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - | Start background process [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - | Program: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - | Mnemonic: LT2 [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - | Echo: ECHO_OFF [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - | Expectation: NEVER [2024-11-15T14:40:06.195Z] STF 14:40:04.873 - | [2024-11-15T14:40:06.196Z] STF 14:40:04.874 - Running command: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -classpath C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\STF\stf.load\bin;C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\STF\stf.core\bin;c:/workspace/testDependency/system_lib/log4j/log4j-api.jar;c:/workspace/testDependency/system_lib/log4j/log4j-core.jar;c:/workspace/testDependency/system_lib/junit/junit.jar;c:/workspace/testDependency/system_lib/junit/hamcrest-core.jar;C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\jvmtest\system\aqa-systemtest\openjdk.test.math\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\aqa-tests\TKG\output_17316799798033\TestJlmRemoteThreadNoAuth_2\20241115-143441-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_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-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-11-15T14:40:06.196Z] STF 14:40:04.875 - Redirecting stderr to C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-11-15T14:40:06.196Z] STF 14:40:04.875 - Redirecting stdout to C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - | Start background process [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - | Program: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - | Mnemonic: CL2 [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - | Echo: ECHO_ON [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - | Expectation: CLEAN_RUN within 30m [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - | [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - Running command: c:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/jdkbinary/j2sdk-image/bin/java -Xmx256m -classpath C:\workspace\workspace\Test_openjdk11_hs_sanity.system_x86-32_windows\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_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/thd_proxy.log C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - Redirecting stderr to C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-11-15T14:40:06.196Z] STF 14:40:04.889 - Redirecting stdout to C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/TKG/output_17316799798033/TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-11-15T14:40:06.196Z] STF 14:40:04.902 - [2024-11-15T14:40:06.196Z] STF 14:40:04.902 - +------ Step 7 - Wait for processes to complete [2024-11-15T14:40:06.196Z] STF 14:40:04.902 - | Wait for processes to meet expectations [2024-11-15T14:40:06.196Z] STF 14:40:04.902 - | Processes: [LT2, CL2] [2024-11-15T14:40:06.196Z] STF 14:40:04.902 - | [2024-11-15T14:40:06.196Z] STF 14:40:04.902 - Monitoring processes: CL2 LT2 [2024-11-15T14:40:06.196Z] CL2 j> 2024/11/15 14:40:05.214 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-15T14:40:06.196Z] CL2 j> 2024/11/15 14:40:05.323 Attempting to connect [2024-11-15T14:40:06.196Z] CL2 j> 2024/11/15 14:40:05.593 Monitored VM not ready at Nov 15, 2024, 2:40:05 PM (attempt 1, elapsed 251ms). [2024-11-15T14:40:06.196Z] CL2 j> 2024/11/15 14:40:05.594 Waiting 5 secs and trying again... [2024-11-15T14:40:10.972Z] CL2 j> 2024/11/15 14:40:10.598 Attempting to connect [2024-11-15T14:40:11.694Z] CL2 j> 2024/11/15 14:40:10.776 Connection established! [2024-11-15T14:40:22.497Z] CL2 j> 2024/11/15 14:40:21.163 Writing report data ... [2024-11-15T14:40:22.841Z] CL2 j> 2024/11/15 14:40:22.153 done. [2024-11-15T14:40:33.695Z] CL2 j> 2024/11/15 14:40:32.253 Writing report data ... [2024-11-15T14:40:34.036Z] CL2 j> 2024/11/15 14:40:32.766 done. [2024-11-15T14:40:44.886Z] CL2 j> 2024/11/15 14:40:42.834 Writing report data ... [2024-11-15T14:40:44.886Z] CL2 j> 2024/11/15 14:40:43.313 done. [2024-11-15T14:40:55.762Z] CL2 j> 2024/11/15 14:40:53.413 Writing report data ... [2024-11-15T14:40:55.762Z] CL2 j> 2024/11/15 14:40:53.868 done. [2024-11-15T14:41:06.618Z] CL2 j> 2024/11/15 14:41:03.942 Writing report data ... [2024-11-15T14:41:06.618Z] CL2 j> 2024/11/15 14:41:04.389 done. [2024-11-15T14:41:15.654Z] CL2 j> 2024/11/15 14:41:14.445 Writing report data ... [2024-11-15T14:41:16.000Z] CL2 j> 2024/11/15 14:41:14.905 done. [2024-11-15T14:41:26.959Z] CL2 j> 2024/11/15 14:41:24.999 Writing report data ... [2024-11-15T14:41:26.959Z] CL2 j> 2024/11/15 14:41:25.428 done. [2024-11-15T14:41:37.844Z] CL2 j> 2024/11/15 14:41:35.526 Writing report data ... [2024-11-15T14:41:37.844Z] CL2 j> 2024/11/15 14:41:35.961 done. [2024-11-15T14:41:46.831Z] CL2 j> 2024/11/15 14:41:46.051 Writing report data ... [2024-11-15T14:41:46.831Z] CL2 j> 2024/11/15 14:41:46.463 done. [2024-11-15T14:41:57.714Z] CL2 j> 2024/11/15 14:41:56.556 Writing report data ... [2024-11-15T14:41:58.063Z] CL2 j> 2024/11/15 14:41:56.936 done. [2024-11-15T14:42:08.965Z] CL2 j> 2024/11/15 14:42:07.024 Writing report data ... [2024-11-15T14:42:08.965Z] CL2 j> 2024/11/15 14:42:07.395 done. [2024-11-15T14:42:19.890Z] CL2 j> 2024/11/15 14:42:17.494 Writing report data ... [2024-11-15T14:42:19.890Z] CL2 j> 2024/11/15 14:42:17.869 done. [2024-11-15T14:42:28.868Z] CL2 j> 2024/11/15 14:42:27.941 Writing report data ... [2024-11-15T14:42:28.868Z] CL2 j> 2024/11/15 14:42:28.323 done. [2024-11-15T14:42:39.775Z] CL2 j> 2024/11/15 14:42:38.396 Writing report data ... [2024-11-15T14:42:40.131Z] CL2 j> 2024/11/15 14:42:38.772 done. [2024-11-15T14:42:50.993Z] CL2 j> 2024/11/15 14:42:48.851 Writing report data ... [2024-11-15T14:42:50.993Z] CL2 j> 2024/11/15 14:42:49.208 done. [2024-11-15T14:42:59.997Z] CL2 j> 2024/11/15 14:42:59.310 Writing report data ... [2024-11-15T14:43:00.703Z] CL2 j> 2024/11/15 14:42:59.695 done. [2024-11-15T14:43:11.578Z] CL2 j> 2024/11/15 14:43:09.776 Writing report data ... [2024-11-15T14:43:11.578Z] CL2 j> 2024/11/15 14:43:10.140 done. [2024-11-15T14:43:22.425Z] CL2 j> 2024/11/15 14:43:20.237 Writing report data ... [2024-11-15T14:43:22.425Z] CL2 j> 2024/11/15 14:43:20.595 done. [2024-11-15T14:43:33.287Z] CL2 j> 2024/11/15 14:43:30.716 Writing report data ... [2024-11-15T14:43:33.287Z] CL2 j> 2024/11/15 14:43:31.082 done. [2024-11-15T14:43:42.324Z] CL2 j> 2024/11/15 14:43:41.163 Writing report data ... [2024-11-15T14:43:43.025Z] CL2 j> 2024/11/15 14:43:41.550 done. [2024-11-15T14:43:53.843Z] CL2 j> 2024/11/15 14:43:51.623 Writing report data ... [2024-11-15T14:43:53.843Z] CL2 j> 2024/11/15 14:43:52.032 done. [2024-11-15T14:44:04.667Z] CL2 j> 2024/11/15 14:44:02.126 Writing report data ... [2024-11-15T14:44:04.667Z] CL2 j> 2024/11/15 14:44:02.520 done. [2024-11-15T14:44:13.679Z] CL2 j> 2024/11/15 14:44:12.618 Writing report data ... [2024-11-15T14:44:14.009Z] CL2 j> 2024/11/15 14:44:12.981 done. [2024-11-15T14:44:24.952Z] CL2 j> 2024/11/15 14:44:23.039 Writing report data ... [2024-11-15T14:44:24.952Z] CL2 j> 2024/11/15 14:44:23.391 done. [2024-11-15T14:44:35.872Z] CL2 j> 2024/11/15 14:44:33.471 Writing report data ... [2024-11-15T14:44:35.872Z] CL2 j> 2024/11/15 14:44:33.829 done. [2024-11-15T14:44:44.861Z] CL2 j> 2024/11/15 14:44:43.902 Writing report data ... [2024-11-15T14:44:45.578Z] CL2 j> 2024/11/15 14:44:44.266 done. [2024-11-15T14:44:45.578Z] STF 14:44:45.277 - Heartbeat: Process LT2 is still running [2024-11-15T14:44:56.507Z] CL2 j> 2024/11/15 14:44:54.357 Writing report data ... [2024-11-15T14:44:56.507Z] CL2 j> 2024/11/15 14:44:54.707 done. [2024-11-15T14:45:05.427Z] CL2 j> 2024/11/15 14:45:04.802 Writing report data ... [2024-11-15T14:45:06.153Z] CL2 j> 2024/11/15 14:45:05.192 done. [2024-11-15T14:45:17.050Z] CL2 j> 2024/11/15 14:45:15.273 Writing report data ... [2024-11-15T14:45:17.050Z] CL2 j> 2024/11/15 14:45:15.626 done. [2024-11-15T14:45:27.890Z] CL2 j> 2024/11/15 14:45:25.728 Writing report data ... [2024-11-15T14:45:27.890Z] CL2 j> 2024/11/15 14:45:26.076 done. [2024-11-15T14:45:28.201Z] STF 14:45:27.366 - Monitoring Report Summary: [2024-11-15T14:45:28.201Z] STF 14:45:27.366 - o Process CL2 ended with the expected exit code (0) [2024-11-15T14:45:28.201Z] STF 14:45:27.366 - o Process LT2 is still running as expected [2024-11-15T14:45:28.201Z] STF 14:45:27.367 - [2024-11-15T14:45:28.201Z] STF 14:45:27.367 - +------ Step 8 - Stop LT2 process [2024-11-15T14:45:28.201Z] STF 14:45:27.367 - | Kill running processes [2024-11-15T14:45:28.201Z] STF 14:45:27.367 - | Processes: [LT2] [2024-11-15T14:45:28.201Z] STF 14:45:27.367 - | [2024-11-15T14:45:28.201Z] STF 14:45:27.367 - Killing processes: LT2 [2024-11-15T14:45:28.201Z] STF 14:45:27.367 - o Process clean up attempt 1 for LT2 pid 50132 [2024-11-15T14:45:28.201Z] STF 14:45:27.367 - o Process LT2 pid 50132 stop() [2024-11-15T14:45:28.201Z] STF 14:45:27.368 - o Process LT2 pid 50132 killed [2024-11-15T14:45:28.201Z] STF 14:45:27.368 - EXECUTE stage completed [2024-11-15T14:45:28.201Z] STF 14:45:27.883 - [2024-11-15T14:45:28.201Z] STF 14:45:27.883 - ==================== T E A R D O W N ==================== [2024-11-15T14:45:28.201Z] STF 14:45:27.883 - Running teardown: perl C:/workspace/workspace/Test_openjdk11_hs_sanity.system_x86-32_windows/aqa-tests/\TKG\output_17316799798033\TestJlmRemoteThreadNoAuth_2/20241115-143441-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-11-15T14:45:28.201Z] STF 14:45:28.056 - TEARDOWN stage completed [2024-11-15T14:45:28.201Z] STF 14:45:28.073 - [2024-11-15T14:45:28.201Z] STF 14:45:28.073 - ===================== R E S U L T S ===================== [2024-11-15T14:45:28.201Z] STF 14:45:28.073 - Stage results: [2024-11-15T14:45:28.201Z] STF 14:45:28.073 - setUp: pass [2024-11-15T14:45:28.201Z] STF 14:45:28.073 - execute: pass [2024-11-15T14:45:28.201Z] STF 14:45:28.073 - teardown: pass [2024-11-15T14:45:28.201Z] STF 14:45:28.073 - [2024-11-15T14:45:28.201Z] STF 14:45:28.073 - Overall result: PASSED [2024-11-15T14:45:28.201Z] ----------------------------------- [2024-11-15T14:45:28.201Z] TestJlmRemoteThreadNoAuth_2_PASSED [2024-11-15T14:45:28.201Z] ----------------------------------- [2024-11-15T14:45:28.201Z] [2024-11-15T14:45:28.201Z] TEST TEARDOWN: [2024-11-15T14:45:28.201Z] Nothing to be done for teardown. [2024-11-15T14:45:28.515Z] TestJlmRemoteThreadNoAuth_2 Finish Time: Fri Nov 15 14:45:28 2024 Epoch Time (ms): 1731681928272