[2024-08-30T21:20:13.694Z] Running test TestJlmRemoteThreadNoAuth_0 ... [2024-08-30T21:20:13.694Z] =============================================== [2024-08-30T21:20:13.694Z] TestJlmRemoteThreadNoAuth_0 Start Time: Fri Aug 30 21:20:12 2024 Epoch Time (ms): 1725052812973 [2024-08-30T21:20:13.694Z] variation: Mode150 [2024-08-30T21:20:13.694Z] JVM_OPTIONS: -XX:+UseCompressedOops [2024-08-30T21:20:13.694Z] { \ [2024-08-30T21:20:13.694Z] echo ""; echo "TEST SETUP:"; \ [2024-08-30T21:20:13.694Z] echo "Nothing to be done for setup."; \ [2024-08-30T21:20:13.694Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0"; \ [2024-08-30T21:20:13.694Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0"; \ [2024-08-30T21:20:13.694Z] echo ""; echo "TESTING:"; \ [2024-08-30T21:20:13.694Z] perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_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_linux_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/../../testDependency/system_lib/" -java-args=' -XX:+UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0" -test=TestJlmRemoteThreadNoAuth; \ [2024-08-30T21:20:13.694Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-30T21:20:13.694Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-30T21:20:13.694Z] echo "Nothing to be done for teardown."; \ [2024-08-30T21:20:13.694Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestTargetResult"; [2024-08-30T21:20:13.694Z] [2024-08-30T21:20:13.694Z] TEST SETUP: [2024-08-30T21:20:13.694Z] Nothing to be done for setup. [2024-08-30T21:20:13.694Z] [2024-08-30T21:20:13.694Z] TESTING: [2024-08-30T21:20:13.694Z] STF 21:20:13.390 - ========================= S T F ========================= [2024-08-30T21:20:13.695Z] 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_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0 [2024-08-30T21:20:13.695Z] There is 70789 Mb free [2024-08-30T21:20:13.695Z] STF 21:20:13.407 - ================== G E N E R A T I O N ================== [2024-08-30T21:20:13.695Z] STF 21:20:13.408 - Checking JVM: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/../ [2024-08-30T21:20:13.695Z] STF 21:20:13.409 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/..//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_linux_testList_1/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_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_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth" [2024-08-30T21:20:13.695Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-30T21:20:13.695Z] GEN [0.037s][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-08-30T21:20:20.640Z] GEN 21:20:19.588 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-08-30T21:20:20.640Z] GEN 21:20:19.602 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-08-30T21:20:20.640Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-08-30T21:20:20.640Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-08-30T21:20:20.640Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jvmtest/system/STF/stf.core/bin [2024-08-30T21:20:20.640Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-08-30T21:20:20.640Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-08-30T21:20:20.640Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-08-30T21:20:21.408Z] GEN 21:20:20.865 - Using Mode NoOptions. Values = '' [2024-08-30T21:20:22.160Z] GEN 21:20:21.505 - [2024-08-30T21:20:22.160Z] GEN 21:20:21.505 - Test command summary: [2024-08-30T21:20:22.160Z] GEN 21:20:21.505 - Step Stage Command Description [2024-08-30T21:20:22.160Z] GEN 21:20:21.506 - -----+--------+-----------------+------------ [2024-08-30T21:20:22.160Z] GEN 21:20:21.506 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-08-30T21:20:22.160Z] GEN 21:20:21.519 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-08-30T21:20:22.160Z] GEN 21:20:21.520 - 3 execute Monitor Wait for processes to complete [2024-08-30T21:20:22.160Z] GEN 21:20:21.520 - 4 execute kill Stop LT1 process [2024-08-30T21:20:22.160Z] GEN 21:20:21.520 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-08-30T21:20:22.160Z] GEN 21:20:21.520 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-08-30T21:20:22.160Z] GEN 21:20:21.520 - 7 execute Monitor Wait for processes to complete [2024-08-30T21:20:22.160Z] GEN 21:20:21.521 - 8 execute kill Stop LT2 process [2024-08-30T21:20:22.160Z] STF 21:20:21.667 - [2024-08-30T21:20:22.160Z] STF 21:20:21.667 - Script generation completed [2024-08-30T21:20:22.160Z] STF 21:20:21.667 - [2024-08-30T21:20:22.160Z] STF 21:20:21.668 - [2024-08-30T21:20:22.161Z] STF 21:20:21.668 - ======================= S E T U P ======================= [2024-08-30T21:20:22.161Z] STF 21:20:21.668 - Running setup: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/setUp.pl [2024-08-30T21:20:22.161Z] STF 21:20:21.869 - SETUP stage completed [2024-08-30T21:20:22.161Z] STF 21:20:21.874 - [2024-08-30T21:20:22.161Z] STF 21:20:21.874 - ==================== E X E C U T E - ==================== [2024-08-30T21:20:22.161Z] STF 21:20:21.874 - Running execute: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/execute.pl [2024-08-30T21:20:22.161Z] STF 21:20:22.042 - [2024-08-30T21:20:22.161Z] STF 21:20:22.042 - Java version [2024-08-30T21:20:22.161Z] STF 21:20:22.042 - Running: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/java -version [2024-08-30T21:20:22.161Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-30T21:20:22.964Z] openjdk version "21.0.5-beta" 2024-10-15 [2024-08-30T21:20:22.964Z] OpenJDK Runtime Environment Temurin-21.0.5+5-202408301905 (build 21.0.5-beta+5-ea) [2024-08-30T21:20:22.964Z] OpenJDK 64-Bit Server VM Temurin-21.0.5+5-202408301905 (build 21.0.5-beta+5-ea, mixed mode, sharing) [2024-08-30T21:20:22.964Z] STF 21:20:22.307 - [2024-08-30T21:20:22.964Z] STF 21:20:22.307 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-08-30T21:20:22.964Z] STF 21:20:22.307 - | Start background process [2024-08-30T21:20:22.964Z] STF 21:20:22.307 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/java [2024-08-30T21:20:22.964Z] STF 21:20:22.307 - | Mnemonic: LT1 [2024-08-30T21:20:22.964Z] STF 21:20:22.307 - | Echo: ECHO_OFF [2024-08-30T21:20:22.964Z] STF 21:20:22.307 - | Expectation: NEVER [2024-08-30T21:20:22.964Z] STF 21:20:22.307 - | [2024-08-30T21:20:22.965Z] STF 21:20:22.308 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/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_linux_testList_1/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_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_linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-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-30T21:20:22.965Z] STF 21:20:22.308 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-08-30T21:20:22.965Z] STF 21:20:22.308 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-08-30T21:20:22.965Z] STF 21:20:22.359 - [2024-08-30T21:20:22.965Z] STF 21:20:22.359 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-08-30T21:20:22.965Z] STF 21:20:22.359 - | Start background process [2024-08-30T21:20:22.965Z] STF 21:20:22.359 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/java [2024-08-30T21:20:22.965Z] STF 21:20:22.359 - | Mnemonic: CL1 [2024-08-30T21:20:22.965Z] STF 21:20:22.359 - | Echo: ECHO_ON [2024-08-30T21:20:22.965Z] STF 21:20:22.359 - | Expectation: CLEAN_RUN within 30m [2024-08-30T21:20:22.965Z] STF 21:20:22.359 - | [2024-08-30T21:20:22.965Z] STF 21:20:22.360 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/java -Xmx256m -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-30T21:20:22.965Z] STF 21:20:22.360 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-08-30T21:20:22.965Z] STF 21:20:22.360 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-08-30T21:20:22.965Z] STF 21:20:22.361 - [2024-08-30T21:20:22.965Z] STF 21:20:22.361 - +------ Step 3 - Wait for processes to complete [2024-08-30T21:20:22.965Z] STF 21:20:22.361 - | Wait for processes to meet expectations [2024-08-30T21:20:22.965Z] STF 21:20:22.362 - | Processes: [LT1, CL1] [2024-08-30T21:20:22.965Z] STF 21:20:22.362 - | [2024-08-30T21:20:22.965Z] STF 21:20:22.362 - Monitoring processes: CL1 LT1 [2024-08-30T21:20:22.965Z] CL1 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-30T21:20:24.557Z] CL1 j> 2024/08/30 21:20:23.656 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-30T21:20:24.557Z] CL1 j> 2024/08/30 21:20:24.327 Attempting to connect [2024-08-30T21:20:27.031Z] CL1 j> 2024/08/30 21:20:26.319 Monitored VM not ready at Aug 30, 2024, 9:20:26?PM (attempt 1, elapsed 1491ms). [2024-08-30T21:20:27.031Z] CL1 j> 2024/08/30 21:20:26.319 Waiting 5 secs and trying again... [2024-08-30T21:20:31.536Z] CL1 j> 2024/08/30 21:20:31.331 Attempting to connect [2024-08-30T21:20:38.860Z] CL1 j> 2024/08/30 21:20:38.486 Connection established! [2024-08-30T21:20:44.862Z] CL1 j> 2024/08/30 21:20:44.223 Starting to write data [2024-08-30T21:25:26.856Z] STF 21:25:22.202 - Heartbeat: Process LT1 is still running [2024-08-30T21:30:24.285Z] STF 21:30:22.188 - Heartbeat: Process LT1 is still running [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - Monitoring Report Summary: [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - o Process CL1 ended with the expected exit code (0) [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - o Process LT1 is still running as expected [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - +------ Step 4 - Stop LT1 process [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - | Kill running processes [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - | Processes: [LT1] [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - | [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - Killing processes: LT1 [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - o Process clean up attempt 1 for LT1 pid 2478105 [2024-08-30T21:33:33.409Z] STF 21:33:32.366 - o Process LT1 pid 2478105 stop() [2024-08-30T21:33:44.285Z] STF 21:33:42.461 - o Process LT1 pid 2478105 terminate() [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - o Process LT1 pid 2478105 killed [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - | Start background process [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/java [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - | Mnemonic: LT2 [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - | Echo: ECHO_OFF [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - | Expectation: NEVER [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - | [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/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_linux_testList_1/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_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_linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-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-30T21:33:44.285Z] STF 21:33:43.462 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-08-30T21:33:44.285Z] STF 21:33:43.462 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-08-30T21:33:44.285Z] STF 21:33:43.464 - [2024-08-30T21:33:44.285Z] STF 21:33:43.464 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-08-30T21:33:44.285Z] STF 21:33:43.464 - | Start background process [2024-08-30T21:33:44.285Z] STF 21:33:43.464 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/java [2024-08-30T21:33:44.285Z] STF 21:33:43.464 - | Mnemonic: CL2 [2024-08-30T21:33:44.285Z] STF 21:33:43.464 - | Echo: ECHO_ON [2024-08-30T21:33:44.285Z] STF 21:33:43.464 - | Expectation: CLEAN_RUN within 30m [2024-08-30T21:33:44.285Z] STF 21:33:43.464 - | [2024-08-30T21:33:44.285Z] STF 21:33:43.465 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/jdkbinary/j2sdk-image/jdk-21.0.5+5/bin/java -Xmx256m -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_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_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-30T21:33:44.285Z] STF 21:33:43.465 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-08-30T21:33:44.285Z] STF 21:33:43.465 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-08-30T21:33:44.285Z] STF 21:33:43.466 - [2024-08-30T21:33:44.285Z] STF 21:33:43.466 - +------ Step 7 - Wait for processes to complete [2024-08-30T21:33:44.285Z] STF 21:33:43.467 - | Wait for processes to meet expectations [2024-08-30T21:33:44.285Z] STF 21:33:43.467 - | Processes: [LT2, CL2] [2024-08-30T21:33:44.285Z] STF 21:33:43.467 - | [2024-08-30T21:33:44.285Z] STF 21:33:43.467 - Monitoring processes: CL2 LT2 [2024-08-30T21:33:44.285Z] CL2 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-30T21:33:45.916Z] CL2 j> 2024/08/30 21:33:44.686 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-30T21:33:45.916Z] CL2 j> 2024/08/30 21:33:45.312 Attempting to connect [2024-08-30T21:33:46.690Z] CL2 j> 2024/08/30 21:33:46.380 Monitored VM not ready at Aug 30, 2024, 9:33:46?PM (attempt 1, elapsed 833ms). [2024-08-30T21:33:46.690Z] CL2 j> 2024/08/30 21:33:46.380 Waiting 5 secs and trying again... [2024-08-30T21:33:52.237Z] CL2 j> 2024/08/30 21:33:51.387 Attempting to connect [2024-08-30T21:33:58.048Z] CL2 j> 2024/08/30 21:33:57.192 Connection established! [2024-08-30T21:34:21.101Z] CL2 j> 2024/08/30 21:34:20.655 Writing report data ... [2024-08-30T21:35:30.775Z] STF 21:35:22.149 - Heartbeat: Process LT2 is still running [2024-08-30T21:35:36.880Z] CL2 j> 2024/08/30 21:35:36.474 done. [2024-08-30T21:35:49.077Z] CL2 j> 2024/08/30 21:35:47.694 Writing report data ... [2024-08-30T21:36:38.341Z] CL2 j> 2024/08/30 21:36:34.559 done. [2024-08-30T21:36:46.911Z] CL2 j> 2024/08/30 21:36:45.208 Writing report data ... [2024-08-30T21:37:30.145Z] CL2 j> 2024/08/30 21:37:26.607 done. [2024-08-30T21:37:39.011Z] CL2 j> 2024/08/30 21:37:37.035 Writing report data ... [2024-08-30T21:38:14.748Z] CL2 j> 2024/08/30 21:38:10.378 done. [2024-08-30T21:38:21.816Z] CL2 j> 2024/08/30 21:38:20.615 Writing report data ... [2024-08-30T21:38:52.544Z] CL2 j> 2024/08/30 21:38:48.031 done. [2024-08-30T21:38:59.808Z] CL2 j> 2024/08/30 21:38:58.563 Writing report data ... [2024-08-30T21:39:28.335Z] CL2 j> 2024/08/30 21:39:24.354 done. [2024-08-30T21:39:36.064Z] CL2 j> 2024/08/30 21:39:34.543 Writing report data ... [2024-08-30T21:39:58.280Z] CL2 j> 2024/08/30 21:39:55.968 done. [2024-08-30T21:40:08.344Z] CL2 j> 2024/08/30 21:40:06.563 Writing report data ... [2024-08-30T21:40:24.770Z] STF 21:40:22.445 - Heartbeat: Process LT2 is still running [2024-08-30T21:40:25.607Z] CL2 j> 2024/08/30 21:40:24.830 done. [2024-08-30T21:40:36.056Z] CL2 j> 2024/08/30 21:40:35.147 Writing report data ... [2024-08-30T21:40:55.124Z] CL2 j> 2024/08/30 21:40:52.855 done. [2024-08-30T21:41:05.580Z] CL2 j> 2024/08/30 21:41:04.119 Writing report data ... [2024-08-30T21:41:24.437Z] CL2 j> 2024/08/30 21:41:22.682 done. [2024-08-30T21:41:34.515Z] CL2 j> 2024/08/30 21:41:32.813 Writing report data ... [2024-08-30T21:41:53.596Z] CL2 j> 2024/08/30 21:41:51.555 done. [2024-08-30T21:42:03.784Z] CL2 j> 2024/08/30 21:42:02.233 Writing report data ... [2024-08-30T21:42:22.804Z] CL2 j> 2024/08/30 21:42:20.434 done. [2024-08-30T21:42:31.324Z] CL2 j> 2024/08/30 21:42:30.731 Writing report data ... [2024-08-30T21:42:52.480Z] CL2 j> 2024/08/30 21:42:49.607 done. [2024-08-30T21:43:00.703Z] CL2 j> 2024/08/30 21:42:59.827 Writing report data ... [2024-08-30T21:43:20.105Z] CL2 j> 2024/08/30 21:43:19.223 done. [2024-08-30T21:43:30.439Z] CL2 j> 2024/08/30 21:43:29.823 Writing report data ... [2024-08-30T21:43:47.152Z] CL2 j> 2024/08/30 21:43:45.519 done. [2024-08-30T21:43:57.487Z] CL2 j> 2024/08/30 21:43:56.090 Writing report data ... [2024-08-30T21:44:15.168Z] CL2 j> 2024/08/30 21:44:12.289 done. [2024-08-30T21:44:23.948Z] CL2 j> 2024/08/30 21:44:22.540 Writing report data ... [2024-08-30T21:44:47.196Z] CL2 j> 2024/08/30 21:44:45.575 done. [2024-08-30T21:44:57.582Z] CL2 j> 2024/08/30 21:44:56.067 Writing report data ... [2024-08-30T21:45:17.249Z] CL2 j> 2024/08/30 21:45:14.467 done. [2024-08-30T21:45:23.137Z] STF 21:45:22.172 - Heartbeat: Process LT2 is still running [2024-08-30T21:45:25.676Z] CL2 j> 2024/08/30 21:45:24.847 Writing report data ... [2024-08-30T21:45:46.125Z] CL2 j> 2024/08/30 21:45:42.774 done. [2024-08-30T21:45:55.068Z] CL2 j> 2024/08/30 21:45:52.987 Writing report data ... [2024-08-30T21:46:11.891Z] CL2 j> 2024/08/30 21:46:10.144 done. [2024-08-30T21:46:21.915Z] CL2 j> 2024/08/30 21:46:20.326 Writing report data ... [2024-08-30T21:46:41.119Z] CL2 j> 2024/08/30 21:46:38.420 done. [2024-08-30T21:46:49.766Z] CL2 j> 2024/08/30 21:46:48.731 Writing report data ... [2024-08-30T21:47:06.393Z] CL2 j> 2024/08/30 21:47:05.409 done. [2024-08-30T21:47:18.236Z] CL2 j> 2024/08/30 21:47:15.673 Writing report data ... [2024-08-30T21:47:32.797Z] CL2 j> 2024/08/30 21:47:31.887 done. [2024-08-30T21:47:43.600Z] CL2 j> 2024/08/30 21:47:42.086 Writing report data ... [2024-08-30T21:48:03.028Z] CL2 j> 2024/08/30 21:48:02.036 done. [2024-08-30T21:48:14.897Z] CL2 j> 2024/08/30 21:48:12.323 Writing report data ... [2024-08-30T21:48:27.033Z] CL2 j> 2024/08/30 21:48:25.731 done. [2024-08-30T21:48:37.210Z] CL2 j> 2024/08/30 21:48:36.127 Writing report data ... [2024-08-30T21:48:49.293Z] CL2 j> 2024/08/30 21:48:47.070 done. [2024-08-30T21:48:59.504Z] CL2 j> 2024/08/30 21:48:57.435 Writing report data ... [2024-08-30T21:49:12.156Z] CL2 j> 2024/08/30 21:49:10.583 done. [2024-08-30T21:49:22.342Z] CL2 j> 2024/08/30 21:49:20.750 Writing report data ... [2024-08-30T21:49:32.164Z] CL2 j> 2024/08/30 21:49:31.060 done. [2024-08-30T21:49:42.124Z] CL2 j> 2024/08/30 21:49:41.161 Writing report data ... [2024-08-30T21:49:54.171Z] CL2 j> 2024/08/30 21:49:52.339 done. [2024-08-30T21:50:04.520Z] CL2 j> 2024/08/30 21:50:02.471 Writing report data ... [2024-08-30T21:50:14.899Z] CL2 j> 2024/08/30 21:50:13.291 done. [2024-08-30T21:50:15.742Z] STF 21:50:15.096 - Monitoring Report Summary: [2024-08-30T21:50:15.742Z] STF 21:50:15.096 - o Process CL2 ended with the expected exit code (0) [2024-08-30T21:50:15.742Z] STF 21:50:15.096 - o Process LT2 is still running as expected [2024-08-30T21:50:15.742Z] STF 21:50:15.097 - [2024-08-30T21:50:15.742Z] STF 21:50:15.097 - +------ Step 8 - Stop LT2 process [2024-08-30T21:50:15.742Z] STF 21:50:15.097 - | Kill running processes [2024-08-30T21:50:15.742Z] STF 21:50:15.097 - | Processes: [LT2] [2024-08-30T21:50:15.742Z] STF 21:50:15.097 - | [2024-08-30T21:50:15.742Z] STF 21:50:15.097 - Killing processes: LT2 [2024-08-30T21:50:15.742Z] STF 21:50:15.097 - o Process clean up attempt 1 for LT2 pid 2478696 [2024-08-30T21:50:15.742Z] STF 21:50:15.097 - o Process LT2 pid 2478696 stop() [2024-08-30T21:50:25.635Z] STF 21:50:25.217 - o Process LT2 pid 2478696 terminate() [2024-08-30T21:50:27.176Z] STF 21:50:26.217 - o Process LT2 pid 2478696 killed [2024-08-30T21:50:27.176Z] STF 21:50:26.217 - EXECUTE stage completed [2024-08-30T21:50:27.176Z] STF 21:50:26.466 - [2024-08-30T21:50:27.176Z] STF 21:50:26.466 - ==================== T E A R D O W N ==================== [2024-08-30T21:50:27.176Z] STF 21:50:26.466 - Running teardown: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17250478888121/TestJlmRemoteThreadNoAuth_0/20240830-212013-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-08-30T21:50:27.176Z] STF 21:50:26.610 - TEARDOWN stage completed [2024-08-30T21:50:27.176Z] STF 21:50:26.613 - [2024-08-30T21:50:27.176Z] STF 21:50:26.613 - ===================== R E S U L T S ===================== [2024-08-30T21:50:27.176Z] STF 21:50:26.613 - Stage results: [2024-08-30T21:50:27.176Z] STF 21:50:26.613 - setUp: pass [2024-08-30T21:50:27.176Z] STF 21:50:26.613 - execute: pass [2024-08-30T21:50:27.176Z] STF 21:50:26.613 - teardown: pass [2024-08-30T21:50:27.176Z] STF 21:50:26.613 - [2024-08-30T21:50:27.176Z] STF 21:50:26.613 - Overall result: PASSED [2024-08-30T21:50:27.176Z] ----------------------------------- [2024-08-30T21:50:27.176Z] TestJlmRemoteThreadNoAuth_0_PASSED [2024-08-30T21:50:27.176Z] ----------------------------------- [2024-08-30T21:50:27.176Z] [2024-08-30T21:50:27.176Z] TEST TEARDOWN: [2024-08-30T21:50:27.176Z] Nothing to be done for teardown. [2024-08-30T21:50:27.176Z] TestJlmRemoteThreadNoAuth_0 Finish Time: Fri Aug 30 21:50:26 2024 Epoch Time (ms): 1725054626653