[2024-11-27T14:47:27.663Z] Running test TestJlmRemoteThreadNoAuth_1 ... [2024-11-27T14:47:27.663Z] =============================================== [2024-11-27T14:47:27.663Z] TestJlmRemoteThreadNoAuth_1 Start Time: Wed Nov 27 14:47:27 2024 Epoch Time (ms): 1732718847360 [2024-11-27T14:47:27.663Z] variation: Mode650 [2024-11-27T14:47:27.663Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-11-27T14:47:27.663Z] { \ [2024-11-27T14:47:27.663Z] echo ""; echo "TEST SETUP:"; \ [2024-11-27T14:47:27.663Z] echo "Nothing to be done for setup."; \ [2024-11-27T14:47:27.663Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1"; \ [2024-11-27T14:47:27.663Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1"; \ [2024-11-27T14:47:27.663Z] echo ""; echo "TESTING:"; \ [2024-11-27T14:47:27.663Z] perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/../../testDependency/system_lib/" -java-args=' -XX:-UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1" -test=TestJlmRemoteThreadNoAuth; \ [2024-11-27T14:47:27.663Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-27T14:47:27.663Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-27T14:47:27.663Z] echo "Nothing to be done for teardown."; \ [2024-11-27T14:47:27.663Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestTargetResult"; [2024-11-27T14:47:27.663Z] [2024-11-27T14:47:27.663Z] TEST SETUP: [2024-11-27T14:47:27.663Z] Nothing to be done for setup. [2024-11-27T14:47:27.663Z] [2024-11-27T14:47:27.663Z] TESTING: [2024-11-27T14:47:27.663Z] STF 14:47:27.575 - ========================= S T F ========================= [2024-11-27T14:47:27.663Z] systemtest-prereqs has been processed, and set to: /home/jenkins/testDependency/system_libRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1 [2024-11-27T14:47:28.432Z] There is 131261 Mb free [2024-11-27T14:47:28.432Z] STF 14:47:27.594 - ================== G E N E R A T I O N ================== [2024-11-27T14:47:28.432Z] STF 14:47:27.596 - Checking JVM: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image [2024-11-27T14:47:28.432Z] STF 14:47:27.596 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth" [2024-11-27T14:47:28.432Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-27T14:47:28.432Z] GEN [0.017s][warning][cds] Archived non-system classes are disabled because the java.system.class.loader property is specified (value = "net.adoptopenjdk.stf.runner.StfClassLoader"). To use archived non-system classes, this property must not be set [2024-11-27T14:47:31.904Z] GEN 14:47:31.113 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-11-27T14:47:31.904Z] GEN 14:47:31.137 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-11-27T14:47:31.904Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-11-27T14:47:31.904Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-11-27T14:47:31.904Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.core/bin [2024-11-27T14:47:31.904Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-11-27T14:47:31.904Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-11-27T14:47:31.904Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-11-27T14:47:31.904Z] GEN 14:47:31.746 - Using Mode NoOptions. Values = '' [2024-11-27T14:47:32.684Z] GEN 14:47:32.173 - [2024-11-27T14:47:32.684Z] GEN 14:47:32.173 - Test command summary: [2024-11-27T14:47:32.684Z] GEN 14:47:32.173 - Step Stage Command Description [2024-11-27T14:47:32.684Z] GEN 14:47:32.173 - -----+--------+-----------------+------------ [2024-11-27T14:47:32.684Z] GEN 14:47:32.173 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-11-27T14:47:32.684Z] GEN 14:47:32.174 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-11-27T14:47:32.684Z] GEN 14:47:32.174 - 3 execute Monitor Wait for processes to complete [2024-11-27T14:47:32.684Z] GEN 14:47:32.174 - 4 execute kill Stop LT1 process [2024-11-27T14:47:32.684Z] GEN 14:47:32.174 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-11-27T14:47:32.684Z] GEN 14:47:32.174 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-11-27T14:47:32.684Z] GEN 14:47:32.174 - 7 execute Monitor Wait for processes to complete [2024-11-27T14:47:32.684Z] GEN 14:47:32.174 - 8 execute kill Stop LT2 process [2024-11-27T14:47:32.684Z] STF 14:47:32.277 - [2024-11-27T14:47:32.684Z] STF 14:47:32.277 - Script generation completed [2024-11-27T14:47:32.684Z] STF 14:47:32.277 - [2024-11-27T14:47:32.684Z] STF 14:47:32.277 - [2024-11-27T14:47:32.684Z] STF 14:47:32.278 - ======================= S E T U P ======================= [2024-11-27T14:47:32.684Z] STF 14:47:32.278 - Running setup: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/setUp.pl [2024-11-27T14:47:32.684Z] STF 14:47:32.505 - SETUP stage completed [2024-11-27T14:47:32.684Z] STF 14:47:32.508 - [2024-11-27T14:47:32.684Z] STF 14:47:32.508 - ==================== E X E C U T E - ==================== [2024-11-27T14:47:32.684Z] STF 14:47:32.508 - Running execute: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/execute.pl [2024-11-27T14:47:33.488Z] STF 14:47:32.696 - [2024-11-27T14:47:33.488Z] STF 14:47:32.696 - Java version [2024-11-27T14:47:33.488Z] STF 14:47:32.696 - Running: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java -version [2024-11-27T14:47:33.488Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-27T14:47:33.488Z] openjdk version "21.0.6-beta" 2025-01-21 [2024-11-27T14:47:33.488Z] OpenJDK Runtime Environment Temurin-21.0.6+5-202411271104 (build 21.0.6-beta+5-202411271104) [2024-11-27T14:47:33.488Z] OpenJDK 64-Bit Server VM Temurin-21.0.6+5-202411271104 (build 21.0.6-beta+5-202411271104, mixed mode, sharing) [2024-11-27T14:47:33.488Z] STF 14:47:32.862 - [2024-11-27T14:47:33.488Z] STF 14:47:32.862 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-11-27T14:47:33.488Z] STF 14:47:32.862 - | Start background process [2024-11-27T14:47:33.488Z] STF 14:47:32.862 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java [2024-11-27T14:47:33.488Z] STF 14:47:32.862 - | Mnemonic: LT1 [2024-11-27T14:47:33.488Z] STF 14:47:32.862 - | Echo: ECHO_OFF [2024-11-27T14:47:33.488Z] STF 14:47:32.862 - | Expectation: NEVER [2024-11-27T14:47:33.488Z] STF 14:47:32.862 - | [2024-11-27T14:47:33.488Z] STF 14:47:32.865 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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 /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.core/bin:/home/jenkins/testDependency/system_lib/log4j/log4j-api.jar:/home/jenkins/testDependency/system_lib/log4j/log4j-core.jar:/home/jenkins/testDependency/system_lib/junit/junit.jar:/home/jenkins/testDependency/system_lib/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-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 /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-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-27T14:47:33.488Z] STF 14:47:32.865 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-11-27T14:47:33.488Z] STF 14:47:32.865 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-11-27T14:47:33.488Z] STF 14:47:32.900 - [2024-11-27T14:47:33.488Z] STF 14:47:32.900 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-11-27T14:47:33.488Z] STF 14:47:32.900 - | Start background process [2024-11-27T14:47:33.488Z] STF 14:47:32.900 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java [2024-11-27T14:47:33.488Z] STF 14:47:32.900 - | Mnemonic: CL1 [2024-11-27T14:47:33.488Z] STF 14:47:32.900 - | Echo: ECHO_ON [2024-11-27T14:47:33.488Z] STF 14:47:32.900 - | Expectation: CLEAN_RUN within 30m [2024-11-27T14:47:33.488Z] STF 14:47:32.900 - | [2024-11-27T14:47:33.488Z] STF 14:47:32.901 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/home/jenkins/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-11-27T14:47:33.488Z] STF 14:47:32.901 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-11-27T14:47:33.488Z] STF 14:47:32.901 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-11-27T14:47:33.488Z] STF 14:47:32.902 - [2024-11-27T14:47:33.488Z] STF 14:47:32.902 - +------ Step 3 - Wait for processes to complete [2024-11-27T14:47:33.488Z] STF 14:47:32.902 - | Wait for processes to meet expectations [2024-11-27T14:47:33.488Z] STF 14:47:32.902 - | Processes: [LT1, CL1] [2024-11-27T14:47:33.488Z] STF 14:47:32.902 - | [2024-11-27T14:47:33.488Z] STF 14:47:32.902 - Monitoring processes: CL1 LT1 [2024-11-27T14:47:33.488Z] CL1 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-27T14:47:34.290Z] CL1 j> 2024/11/27 14:47:33.598 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-27T14:47:34.290Z] CL1 j> 2024/11/27 14:47:34.062 Attempting to connect [2024-11-27T14:47:35.071Z] CL1 j> 2024/11/27 14:47:34.770 Monitored VM not ready at Nov 27, 2024, 2:47:34 PM (attempt 1, elapsed 574ms). [2024-11-27T14:47:35.071Z] CL1 j> 2024/11/27 14:47:34.770 Waiting 5 secs and trying again... [2024-11-27T14:47:41.120Z] CL1 j> 2024/11/27 14:47:39.803 Attempting to connect [2024-11-27T14:47:42.912Z] CL1 j> 2024/11/27 14:47:42.327 Connection established! [2024-11-27T14:47:44.785Z] CL1 j> 2024/11/27 14:47:44.163 Starting to write data [2024-11-27T14:52:47.098Z] STF 14:52:32.183 - Heartbeat: Process LT1 is still running [2024-11-27T14:56:31.488Z] STF 14:56:15.652 - Monitoring Report Summary: [2024-11-27T14:56:31.488Z] STF 14:56:15.652 - o Process CL1 ended with the expected exit code (0) [2024-11-27T14:56:31.488Z] STF 14:56:15.652 - o Process LT1 is still running as expected [2024-11-27T14:56:31.488Z] STF 14:56:15.655 - [2024-11-27T14:56:31.488Z] STF 14:56:15.655 - +------ Step 4 - Stop LT1 process [2024-11-27T14:56:31.488Z] STF 14:56:15.655 - | Kill running processes [2024-11-27T14:56:31.488Z] STF 14:56:15.655 - | Processes: [LT1] [2024-11-27T14:56:31.488Z] STF 14:56:15.655 - | [2024-11-27T14:56:31.488Z] STF 14:56:15.655 - Killing processes: LT1 [2024-11-27T14:56:31.488Z] STF 14:56:15.655 - o Process clean up attempt 1 for LT1 pid 15434 [2024-11-27T14:56:31.488Z] STF 14:56:15.655 - o Process LT1 pid 15434 stop() [2024-11-27T14:56:31.488Z] STF 14:56:25.692 - o Process LT1 pid 15434 terminate() [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - o Process LT1 pid 15434 killed [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - | Start background process [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - | Mnemonic: LT2 [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - | Echo: ECHO_OFF [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - | Expectation: NEVER [2024-11-27T14:56:31.488Z] STF 14:56:26.693 - | [2024-11-27T14:56:31.488Z] STF 14:56:26.694 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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 /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.core/bin:/home/jenkins/testDependency/system_lib/log4j/log4j-api.jar:/home/jenkins/testDependency/system_lib/log4j/log4j-core.jar:/home/jenkins/testDependency/system_lib/junit/junit.jar:/home/jenkins/testDependency/system_lib/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-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 /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-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-27T14:56:31.488Z] STF 14:56:26.694 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-11-27T14:56:31.488Z] STF 14:56:26.694 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-11-27T14:56:31.488Z] STF 14:56:26.695 - [2024-11-27T14:56:31.488Z] STF 14:56:26.695 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-11-27T14:56:31.488Z] STF 14:56:26.695 - | Start background process [2024-11-27T14:56:31.488Z] STF 14:56:26.695 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java [2024-11-27T14:56:31.488Z] STF 14:56:26.695 - | Mnemonic: CL2 [2024-11-27T14:56:31.488Z] STF 14:56:26.695 - | Echo: ECHO_ON [2024-11-27T14:56:31.488Z] STF 14:56:26.695 - | Expectation: CLEAN_RUN within 30m [2024-11-27T14:56:31.488Z] STF 14:56:26.695 - | [2024-11-27T14:56:31.488Z] STF 14:56:26.696 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/home/jenkins/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-11-27T14:56:31.488Z] STF 14:56:26.696 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-11-27T14:56:31.488Z] STF 14:56:26.696 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-11-27T14:56:31.488Z] STF 14:56:26.698 - [2024-11-27T14:56:31.488Z] STF 14:56:26.698 - +------ Step 7 - Wait for processes to complete [2024-11-27T14:56:31.488Z] STF 14:56:26.698 - | Wait for processes to meet expectations [2024-11-27T14:56:31.488Z] STF 14:56:26.698 - | Processes: [LT2, CL2] [2024-11-27T14:56:31.488Z] STF 14:56:26.698 - | [2024-11-27T14:56:31.488Z] STF 14:56:26.698 - Monitoring processes: CL2 LT2 [2024-11-27T14:56:31.488Z] CL2 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-27T14:56:31.488Z] CL2 j> 2024/11/27 14:56:27.112 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-27T14:56:31.488Z] CL2 j> 2024/11/27 14:56:27.359 Attempting to connect [2024-11-27T14:56:31.488Z] CL2 j> 2024/11/27 14:56:27.780 Monitored VM not ready at Nov 27, 2024, 2:56:27 PM (attempt 1, elapsed 334ms). [2024-11-27T14:56:31.488Z] CL2 j> 2024/11/27 14:56:27.786 Waiting 5 secs and trying again... [2024-11-27T14:56:33.151Z] CL2 j> 2024/11/27 14:56:32.787 Attempting to connect [2024-11-27T14:56:35.796Z] CL2 j> 2024/11/27 14:56:34.974 Connection established! [2024-11-27T14:56:52.642Z] CL2 j> 2024/11/27 14:56:51.374 Writing report data ... [2024-11-27T14:57:19.207Z] CL2 j> 2024/11/27 14:57:15.897 done. [2024-11-27T14:57:28.027Z] CL2 j> 2024/11/27 14:57:26.597 Writing report data ... [2024-11-27T14:57:32.822Z] STF 14:57:32.343 - Heartbeat: Process CL2 is still running [2024-11-27T14:57:44.958Z] CL2 j> 2024/11/27 14:57:43.628 done. [2024-11-27T14:57:55.988Z] CL2 j> 2024/11/27 14:57:54.571 Writing report data ... [2024-11-27T14:58:12.787Z] CL2 j> 2024/11/27 14:58:11.865 done. [2024-11-27T14:58:24.981Z] CL2 j> 2024/11/27 14:58:22.639 Writing report data ... [2024-11-27T14:58:37.181Z] CL2 j> 2024/11/27 14:58:35.426 done. [2024-11-27T14:58:47.643Z] CL2 j> 2024/11/27 14:58:46.035 Writing report data ... [2024-11-27T14:59:01.833Z] CL2 j> 2024/11/27 14:59:00.895 done. [2024-11-27T14:59:12.719Z] CL2 j> 2024/11/27 14:59:11.494 Writing report data ... [2024-11-27T14:59:23.009Z] CL2 j> 2024/11/27 14:59:21.772 done. [2024-11-27T14:59:33.456Z] CL2 j> 2024/11/27 14:59:32.288 Writing report data ... [2024-11-27T14:59:43.837Z] CL2 j> 2024/11/27 14:59:43.120 done. [2024-11-27T14:59:55.943Z] CL2 j> 2024/11/27 14:59:53.746 Writing report data ... [2024-11-27T15:00:03.029Z] CL2 j> 2024/11/27 15:00:01.641 done. [2024-11-27T15:00:13.243Z] CL2 j> 2024/11/27 15:00:12.350 Writing report data ... [2024-11-27T15:00:22.356Z] CL2 j> 2024/11/27 15:00:21.205 done. [2024-11-27T15:00:33.218Z] CL2 j> 2024/11/27 15:00:31.758 Writing report data ... [2024-11-27T15:00:41.926Z] CL2 j> 2024/11/27 15:00:40.036 done. [2024-11-27T15:00:52.131Z] CL2 j> 2024/11/27 15:00:50.457 Writing report data ... [2024-11-27T15:00:57.770Z] CL2 j> 2024/11/27 15:00:56.627 done. [2024-11-27T15:01:08.220Z] CL2 j> 2024/11/27 15:01:07.226 Writing report data ... [2024-11-27T15:01:15.252Z] CL2 j> 2024/11/27 15:01:14.210 done. [2024-11-27T15:01:27.262Z] CL2 j> 2024/11/27 15:01:24.899 Writing report data ... [2024-11-27T15:01:33.060Z] CL2 j> 2024/11/27 15:01:31.892 done. [2024-11-27T15:01:43.766Z] CL2 j> 2024/11/27 15:01:42.398 Writing report data ... [2024-11-27T15:01:54.075Z] CL2 j> 2024/11/27 15:01:52.042 done. [2024-11-27T15:02:04.330Z] CL2 j> 2024/11/27 15:02:02.415 Writing report data ... [2024-11-27T15:02:11.424Z] CL2 j> 2024/11/27 15:02:10.288 done. [2024-11-27T15:02:21.709Z] CL2 j> 2024/11/27 15:02:20.673 Writing report data ... [2024-11-27T15:02:30.530Z] CL2 j> 2024/11/27 15:02:29.131 done. [2024-11-27T15:02:33.186Z] STF 15:02:32.067 - Heartbeat: Process CL2 is still running [2024-11-27T15:02:40.403Z] CL2 j> 2024/11/27 15:02:39.763 Writing report data ... [2024-11-27T15:02:47.782Z] CL2 j> 2024/11/27 15:02:46.141 done. [2024-11-27T15:02:58.243Z] CL2 j> 2024/11/27 15:02:56.516 Writing report data ... [2024-11-27T15:03:01.833Z] CL2 j> 2024/11/27 15:03:01.462 done. [2024-11-27T15:03:14.009Z] CL2 j> 2024/11/27 15:03:11.951 Writing report data ... [2024-11-27T15:03:19.852Z] CL2 j> 2024/11/27 15:03:18.069 done. [2024-11-27T15:03:30.099Z] CL2 j> 2024/11/27 15:03:28.550 Writing report data ... [2024-11-27T15:03:35.931Z] CL2 j> 2024/11/27 15:03:35.712 done. [2024-11-27T15:03:48.105Z] CL2 j> 2024/11/27 15:03:46.034 Writing report data ... [2024-11-27T15:03:52.206Z] CL2 j> 2024/11/27 15:03:51.209 done. [2024-11-27T15:04:02.472Z] CL2 j> 2024/11/27 15:04:01.557 Writing report data ... [2024-11-27T15:04:12.634Z] CL2 j> 2024/11/27 15:04:11.130 done. [2024-11-27T15:04:22.900Z] CL2 j> 2024/11/27 15:04:21.625 Writing report data ... [2024-11-27T15:04:27.527Z] CL2 j> 2024/11/27 15:04:26.383 done. [2024-11-27T15:04:37.810Z] CL2 j> 2024/11/27 15:04:36.832 Writing report data ... [2024-11-27T15:04:42.469Z] CL2 j> 2024/11/27 15:04:40.944 done. [2024-11-27T15:04:52.891Z] CL2 j> 2024/11/27 15:04:51.233 Writing report data ... [2024-11-27T15:04:57.560Z] CL2 j> 2024/11/27 15:04:56.611 done. [2024-11-27T15:05:08.270Z] CL2 j> 2024/11/27 15:05:07.138 Writing report data ... [2024-11-27T15:05:15.399Z] CL2 j> 2024/11/27 15:05:13.743 done. [2024-11-27T15:05:25.509Z] CL2 j> 2024/11/27 15:05:24.042 Writing report data ... [2024-11-27T15:05:30.140Z] CL2 j> 2024/11/27 15:05:28.556 done. [2024-11-27T15:05:40.430Z] CL2 j> 2024/11/27 15:05:38.999 Writing report data ... [2024-11-27T15:05:46.290Z] CL2 j> 2024/11/27 15:05:45.231 done. [2024-11-27T15:05:56.664Z] CL2 j> 2024/11/27 15:05:55.716 Writing report data ... [2024-11-27T15:06:02.690Z] CL2 j> 2024/11/27 15:06:01.475 done. [2024-11-27T15:06:13.358Z] CL2 j> 2024/11/27 15:06:12.124 Writing report data ... [2024-11-27T15:06:22.135Z] CL2 j> 2024/11/27 15:06:21.275 done. [2024-11-27T15:06:22.948Z] STF 15:06:22.533 - Monitoring Report Summary: [2024-11-27T15:06:22.948Z] STF 15:06:22.533 - o Process CL2 ended with the expected exit code (0) [2024-11-27T15:06:22.948Z] STF 15:06:22.533 - o Process LT2 is still running as expected [2024-11-27T15:06:22.948Z] STF 15:06:22.534 - [2024-11-27T15:06:22.948Z] STF 15:06:22.534 - +------ Step 8 - Stop LT2 process [2024-11-27T15:06:22.948Z] STF 15:06:22.534 - | Kill running processes [2024-11-27T15:06:22.948Z] STF 15:06:22.534 - | Processes: [LT2] [2024-11-27T15:06:22.948Z] STF 15:06:22.534 - | [2024-11-27T15:06:22.948Z] STF 15:06:22.534 - Killing processes: LT2 [2024-11-27T15:06:22.948Z] STF 15:06:22.534 - o Process clean up attempt 1 for LT2 pid 15899 [2024-11-27T15:06:22.948Z] STF 15:06:22.534 - o Process LT2 pid 15899 stop() [2024-11-27T15:06:33.223Z] STF 15:06:32.559 - o Process LT2 pid 15899 terminate() [2024-11-27T15:06:34.810Z] STF 15:06:33.559 - o Process LT2 pid 15899 killed [2024-11-27T15:06:34.810Z] STF 15:06:33.560 - EXECUTE stage completed [2024-11-27T15:06:34.810Z] STF 15:06:33.961 - [2024-11-27T15:06:34.810Z] STF 15:06:33.961 - ==================== T E A R D O W N ==================== [2024-11-27T15:06:34.810Z] STF 15:06:33.961 - Running teardown: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17327156881583/TestJlmRemoteThreadNoAuth_1/20241127-144727-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-11-27T15:06:34.810Z] STF 15:06:34.058 - TEARDOWN stage completed [2024-11-27T15:06:34.810Z] STF 15:06:34.061 - [2024-11-27T15:06:34.810Z] STF 15:06:34.061 - ===================== R E S U L T S ===================== [2024-11-27T15:06:34.810Z] STF 15:06:34.061 - Stage results: [2024-11-27T15:06:34.810Z] STF 15:06:34.061 - setUp: pass [2024-11-27T15:06:34.810Z] STF 15:06:34.061 - execute: pass [2024-11-27T15:06:34.810Z] STF 15:06:34.061 - teardown: pass [2024-11-27T15:06:34.810Z] STF 15:06:34.061 - [2024-11-27T15:06:34.810Z] STF 15:06:34.061 - Overall result: PASSED [2024-11-27T15:06:34.810Z] ----------------------------------- [2024-11-27T15:06:34.810Z] TestJlmRemoteThreadNoAuth_1_PASSED [2024-11-27T15:06:34.810Z] ----------------------------------- [2024-11-27T15:06:34.810Z] [2024-11-27T15:06:34.810Z] TEST TEARDOWN: [2024-11-27T15:06:34.810Z] Nothing to be done for teardown. [2024-11-27T15:06:34.810Z] TestJlmRemoteThreadNoAuth_1 Finish Time: Wed Nov 27 15:06:34 2024 Epoch Time (ms): 1732719994113