[2023-04-19T10:09:58.230Z] Running test TestJlmRemoteThreadAuth_0 ... [2023-04-19T10:09:58.230Z] =============================================== [2023-04-19T10:09:58.230Z] TestJlmRemoteThreadAuth_0 Start Time: Wed Apr 19 10:09:57 2023 Epoch Time (ms): 1681898997934 [2023-04-19T10:09:58.230Z] variation: Mode150 [2023-04-19T10:09:58.230Z] JVM_OPTIONS: -XX:+UseCompressedOops [2023-04-19T10:09:58.230Z] { \ [2023-04-19T10:09:58.231Z] echo ""; echo "TEST SETUP:"; \ [2023-04-19T10:09:58.231Z] echo "Nothing to be done for setup."; \ [2023-04-19T10:09:58.231Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0"; \ [2023-04-19T10:09:58.231Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0"; \ [2023-04-19T10:09:58.231Z] echo ""; echo "TESTING:"; \ [2023-04-19T10:09:58.231Z] perl /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs" -java-args=' -XX:+UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0" -test=TestJlmRemoteThreadAuth; \ [2023-04-19T10:09:58.231Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-19T10:09:58.231Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-19T10:09:58.231Z] echo "Nothing to be done for teardown."; \ [2023-04-19T10:09:58.231Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestTargetResult"; [2023-04-19T10:09:58.231Z] [2023-04-19T10:09:58.231Z] TEST SETUP: [2023-04-19T10:09:58.231Z] Nothing to be done for setup. [2023-04-19T10:09:58.231Z] [2023-04-19T10:09:58.231Z] TESTING: [2023-04-19T10:09:58.231Z] STF 10:09:57.993 - ========================= S T F ========================= [2023-04-19T10:09:58.231Z] systemtest-prereqs has been processed, and set to: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqsRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0 [2023-04-19T10:09:58.231Z] There is 383955 Mb free [2023-04-19T10:09:58.231Z] STF 10:09:57.998 - ================== G E N E R A T I O N ================== [2023-04-19T10:09:58.231Z] STF 10:09:57.999 - Checking JVM: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/../ [2023-04-19T10:09:58.231Z] STF 10:09:57.999 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/..//bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs/log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs/log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth" [2023-04-19T10:09:58.976Z] GEN 10:09:58.637 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2023-04-19T10:09:58.976Z] GEN 10:09:58.641 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadAuth' [2023-04-19T10:09:58.976Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2023-04-19T10:09:58.976Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2023-04-19T10:09:58.976Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/STF/stf.core/bin [2023-04-19T10:09:58.976Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar [2023-04-19T10:09:58.976Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar [2023-04-19T10:09:58.976Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar [2023-04-19T10:09:58.976Z] GEN 10:09:58.795 - Using Mode NoOptions. Values = '' [2023-04-19T10:09:58.976Z] GEN 10:09:58.846 - [2023-04-19T10:09:58.976Z] GEN 10:09:58.846 - Test command summary: [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - Step Stage Command Description [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - -----+--------+-----------------+------------ [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 1 setUp cp Copying password file to temp [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 2 setUp chmod Restricting file permission of jmxremote.passsword to 600 [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 3 execute Run java Running ThreadProfiler Proxy test Server Process(with security) [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 4 execute Run java Running Monitoring Client with proxy connection(with security) [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 5 execute Monitor Wait for the processes to complete [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 6 execute kill Stop LT1 process [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 7 execute Run java Running ThreadProfiler Server test Server Process(with security) [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 8 execute Run java Run the Monitoring Client with server-connection(with security) [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 9 execute Monitor Wait for processes to complete [2023-04-19T10:09:58.976Z] GEN 10:09:58.847 - 10 execute kill Stop LT2 process [2023-04-19T10:09:58.976Z] STF 10:09:58.871 - [2023-04-19T10:09:58.976Z] STF 10:09:58.871 - Script generation completed [2023-04-19T10:09:58.976Z] STF 10:09:58.871 - [2023-04-19T10:09:58.976Z] STF 10:09:58.871 - [2023-04-19T10:09:58.976Z] STF 10:09:58.871 - ======================= S E T U P ======================= [2023-04-19T10:09:58.976Z] STF 10:09:58.871 - Running setup: perl /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/setUp.pl [2023-04-19T10:09:58.976Z] STF 10:09:58.935 - [2023-04-19T10:09:58.976Z] STF 10:09:58.935 - +------ Step 1 - Copying password file to temp [2023-04-19T10:09:58.976Z] STF 10:09:58.935 - | Copy a file to another directory [2023-04-19T10:09:58.976Z] STF 10:09:58.935 - | Source file: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/jmxremote.password [2023-04-19T10:09:58.976Z] STF 10:09:58.935 - | Dest dir: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/tmp [2023-04-19T10:09:58.976Z] STF 10:09:58.935 - | [2023-04-19T10:09:58.976Z] STF 10:09:58.936 - [2023-04-19T10:09:58.976Z] STF 10:09:58.936 - +------ Step 2 - Restricting file permission of jmxremote.passsword to 600 [2023-04-19T10:09:58.976Z] STF 10:09:58.936 - | Change file permission [2023-04-19T10:09:58.976Z] STF 10:09:58.936 - | targetFile: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/tmp/jmxremote.password [2023-04-19T10:09:58.976Z] STF 10:09:58.936 - | modeBits: 600 [2023-04-19T10:09:58.976Z] STF 10:09:58.936 - | [2023-04-19T10:09:58.976Z] STF 10:09:58.939 - SETUP stage completed [2023-04-19T10:09:58.976Z] STF 10:09:58.941 - [2023-04-19T10:09:58.976Z] STF 10:09:58.941 - ==================== E X E C U T E - ==================== [2023-04-19T10:09:58.976Z] STF 10:09:58.941 - Running execute: perl /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/execute.pl [2023-04-19T10:09:59.356Z] STF 10:09:58.992 - [2023-04-19T10:09:59.356Z] STF 10:09:58.992 - Java version [2023-04-19T10:09:59.356Z] STF 10:09:58.992 - Running: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java -version [2023-04-19T10:09:59.356Z] openjdk version "1.8.0_372" [2023-04-19T10:09:59.356Z] OpenJDK Runtime Environment (Temurin)(build 1.8.0_372-b07) [2023-04-19T10:09:59.356Z] OpenJDK 64-Bit Server VM (Temurin)(build 25.372-b07, mixed mode) [2023-04-19T10:09:59.356Z] STF 10:09:59.043 - [2023-04-19T10:09:59.356Z] STF 10:09:59.043 - +------ Step 3 - Running ThreadProfiler Proxy test Server Process(with security) [2023-04-19T10:09:59.356Z] STF 10:09:59.043 - | Start background process [2023-04-19T10:09:59.356Z] STF 10:09:59.043 - | Program: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java [2023-04-19T10:09:59.356Z] STF 10:09:59.043 - | Mnemonic: LT1 [2023-04-19T10:09:59.356Z] STF 10:09:59.043 - | Echo: ECHO_OFF [2023-04-19T10:09:59.356Z] STF 10:09:59.043 - | Expectation: NEVER [2023-04-19T10:09:59.356Z] STF 10:09:59.043 - | [2023-04-19T10:09:59.357Z] STF 10:09:59.043 - Running command: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.ssl.need.client.auth=true -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.keyStoreType=JKS -Djavax.net.ssl.trustStoreType=JKS -Djavax.net.ssl.keyStorePassword=passphrase -Djavax.net.ssl.trustStorePassword=passphrase -Dcom.sun.management.jmxremote.password.file=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/STF/stf.core/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results -resultsPrefix 3.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_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/3.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 [2023-04-19T10:09:59.357Z] STF 10:09:59.043 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/3.LT1.stderr [2023-04-19T10:09:59.357Z] STF 10:09:59.043 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/3.LT1.stdout [2023-04-19T10:09:59.357Z] STF 10:09:59.051 - [2023-04-19T10:09:59.357Z] STF 10:09:59.051 - +------ Step 4 - Running Monitoring Client with proxy connection(with security) [2023-04-19T10:09:59.357Z] STF 10:09:59.051 - | Start background process [2023-04-19T10:09:59.357Z] STF 10:09:59.051 - | Program: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java [2023-04-19T10:09:59.357Z] STF 10:09:59.051 - | Mnemonic: CL1 [2023-04-19T10:09:59.357Z] STF 10:09:59.051 - | Echo: ECHO_ON [2023-04-19T10:09:59.357Z] STF 10:09:59.051 - | Expectation: CLEAN_RUN within 30m [2023-04-19T10:09:59.357Z] STF 10:09:59.051 - | [2023-04-19T10:09:59.357Z] STF 10:09:59.052 - Running command: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.keyStoreType=JKS -Djavax.net.ssl.trustStoreType=JKS -Djavax.net.ssl.keyStorePassword=passphrase -Djavax.net.ssl.trustStorePassword=passphrase -Dcom.sun.management.jmxremote.password.file=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/sthd_proxy.log /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/sthd_proxy.csv auth controlRole control1 localhost 1234 [2023-04-19T10:09:59.357Z] STF 10:09:59.052 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/4.CL1.stderr [2023-04-19T10:09:59.357Z] STF 10:09:59.052 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/4.CL1.stdout [2023-04-19T10:09:59.357Z] STF 10:09:59.053 - [2023-04-19T10:09:59.357Z] STF 10:09:59.053 - +------ Step 5 - Wait for the processes to complete [2023-04-19T10:09:59.357Z] STF 10:09:59.053 - | Wait for processes to meet expectations [2023-04-19T10:09:59.357Z] STF 10:09:59.053 - | Processes: [LT1, CL1] [2023-04-19T10:09:59.357Z] STF 10:09:59.053 - | [2023-04-19T10:09:59.357Z] STF 10:09:59.053 - Monitoring processes: CL1 LT1 [2023-04-19T10:09:59.357Z] CL1 j> 2023/04/19 10:09:59.114 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2023-04-19T10:09:59.357Z] CL1 j> 2023/04/19 10:09:59.129 Attempting to connect [2023-04-19T10:09:59.357Z] CL1 j> 2023/04/19 10:09:59.315 Monitored VM not ready at Apr 19, 2023 10:09:59 AM (attempt 1, elapsed 178ms). [2023-04-19T10:09:59.357Z] CL1 j> 2023/04/19 10:09:59.315 Waiting 5 secs and trying again... [2023-04-19T10:10:04.905Z] CL1 j> 2023/04/19 10:10:04.315 Attempting to connect [2023-04-19T10:10:15.153Z] CL1 j> 2023/04/19 10:10:13.300 Connection established! [2023-04-19T10:10:15.153Z] CL1 j> 2023/04/19 10:10:14.604 Starting to write data [2023-04-19T10:14:59.206Z] STF 10:14:58.004 - Heartbeat: Process CL1 is still running [2023-04-19T10:16:25.029Z] STF 10:16:22.300 - Monitoring Report Summary: [2023-04-19T10:16:25.029Z] STF 10:16:22.300 - o Process CL1 ended with the expected exit code (0) [2023-04-19T10:16:25.029Z] STF 10:16:22.300 - o Process LT1 is still running as expected [2023-04-19T10:16:25.029Z] STF 10:16:22.301 - [2023-04-19T10:16:25.029Z] STF 10:16:22.301 - +------ Step 6 - Stop LT1 process [2023-04-19T10:16:25.029Z] STF 10:16:22.301 - | Kill running processes [2023-04-19T10:16:25.029Z] STF 10:16:22.301 - | Processes: [LT1] [2023-04-19T10:16:25.029Z] STF 10:16:22.301 - | [2023-04-19T10:16:25.029Z] STF 10:16:22.301 - Killing processes: LT1 [2023-04-19T10:16:25.029Z] STF 10:16:22.301 - o Process clean up attempt 1 for LT1 pid 277791 [2023-04-19T10:16:25.029Z] STF 10:16:22.301 - o Process LT1 pid 277791 stop() [2023-04-19T10:16:33.364Z] STF 10:16:32.303 - o Process LT1 pid 277791 terminate() [2023-04-19T10:16:33.364Z] STF 10:16:33.303 - o Process LT1 pid 277791 killed [2023-04-19T10:16:33.364Z] STF 10:16:33.304 - [2023-04-19T10:16:33.364Z] STF 10:16:33.304 - +------ Step 7 - Running ThreadProfiler Server test Server Process(with security) [2023-04-19T10:16:33.364Z] STF 10:16:33.304 - | Start background process [2023-04-19T10:16:33.364Z] STF 10:16:33.304 - | Program: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java [2023-04-19T10:16:33.364Z] STF 10:16:33.304 - | Mnemonic: LT2 [2023-04-19T10:16:33.364Z] STF 10:16:33.304 - | Echo: ECHO_OFF [2023-04-19T10:16:33.364Z] STF 10:16:33.304 - | Expectation: NEVER [2023-04-19T10:16:33.364Z] STF 10:16:33.304 - | [2023-04-19T10:16:33.364Z] STF 10:16:33.305 - Running command: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.ssl.need.client.auth=true -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.keyStoreType=JKS -Djavax.net.ssl.trustStoreType=JKS -Djavax.net.ssl.keyStorePassword=passphrase -Djavax.net.ssl.trustStorePassword=passphrase -Dcom.sun.management.jmxremote.password.file=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/STF/stf.core/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results -resultsPrefix 7.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_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/7.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 [2023-04-19T10:16:33.364Z] STF 10:16:33.305 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/7.LT2.stderr [2023-04-19T10:16:33.364Z] STF 10:16:33.305 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/7.LT2.stdout [2023-04-19T10:16:33.364Z] STF 10:16:33.306 - [2023-04-19T10:16:33.364Z] STF 10:16:33.306 - +------ Step 8 - Run the Monitoring Client with server-connection(with security) [2023-04-19T10:16:33.364Z] STF 10:16:33.306 - | Start background process [2023-04-19T10:16:33.364Z] STF 10:16:33.307 - | Program: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java [2023-04-19T10:16:33.364Z] STF 10:16:33.307 - | Mnemonic: CL2 [2023-04-19T10:16:33.364Z] STF 10:16:33.307 - | Echo: ECHO_ON [2023-04-19T10:16:33.364Z] STF 10:16:33.307 - | Expectation: CLEAN_RUN within 30m [2023-04-19T10:16:33.364Z] STF 10:16:33.307 - | [2023-04-19T10:16:33.364Z] STF 10:16:33.307 - Running command: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/openjdkbinary/j2sdk-image/jdk8u372-b07/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.keyStoreType=JKS -Djavax.net.ssl.trustStoreType=JKS -Djavax.net.ssl.keyStorePassword=passphrase -Djavax.net.ssl.trustStorePassword=passphrase -Dcom.sun.management.jmxremote.password.file=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/sthd_server.log /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/sthd_server.csv auth controlRole control1 localhost 1234 [2023-04-19T10:16:33.364Z] STF 10:16:33.307 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/8.CL2.stderr [2023-04-19T10:16:33.364Z] STF 10:16:33.307 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/results/8.CL2.stdout [2023-04-19T10:16:33.364Z] STF 10:16:33.309 - [2023-04-19T10:16:33.364Z] STF 10:16:33.309 - +------ Step 9 - Wait for processes to complete [2023-04-19T10:16:33.364Z] STF 10:16:33.309 - | Wait for processes to meet expectations [2023-04-19T10:16:33.364Z] STF 10:16:33.309 - | Processes: [LT2, CL2] [2023-04-19T10:16:33.364Z] STF 10:16:33.309 - | [2023-04-19T10:16:33.364Z] STF 10:16:33.309 - Monitoring processes: CL2 LT2 [2023-04-19T10:16:34.111Z] CL2 j> 2023/04/19 10:16:33.374 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2023-04-19T10:16:34.111Z] CL2 j> 2023/04/19 10:16:33.399 Attempting to connect [2023-04-19T10:16:34.111Z] CL2 j> 2023/04/19 10:16:33.599 Monitored VM not ready at Apr 19, 2023 10:16:33 AM (attempt 1, elapsed 135ms). [2023-04-19T10:16:34.111Z] CL2 j> 2023/04/19 10:16:33.599 Waiting 5 secs and trying again... [2023-04-19T10:16:39.729Z] CL2 j> 2023/04/19 10:16:38.600 Attempting to connect [2023-04-19T10:16:48.806Z] CL2 j> 2023/04/19 10:16:47.299 Connection established! [2023-04-19T10:17:04.306Z] CL2 j> 2023/04/19 10:17:02.696 Writing report data ... [2023-04-19T10:17:14.906Z] CL2 j> 2023/04/19 10:17:12.698 done. [2023-04-19T10:17:23.730Z] CL2 j> 2023/04/19 10:17:22.903 Writing report data ... [2023-04-19T10:17:29.831Z] CL2 j> 2023/04/19 10:17:28.514 done. [2023-04-19T10:17:40.329Z] CL2 j> 2023/04/19 10:17:38.707 Writing report data ... [2023-04-19T10:17:44.929Z] CL2 j> 2023/04/19 10:17:43.299 done. [2023-04-19T10:17:56.006Z] CL2 j> 2023/04/19 10:17:53.409 Writing report data ... [2023-04-19T10:17:58.131Z] CL2 j> 2023/04/19 10:17:57.209 done. [2023-04-19T10:18:08.706Z] CL2 j> 2023/04/19 10:18:07.403 Writing report data ... [2023-04-19T10:18:11.706Z] CL2 j> 2023/04/19 10:18:10.605 done. [2023-04-19T10:18:22.105Z] CL2 j> 2023/04/19 10:18:20.803 Writing report data ... [2023-04-19T10:18:24.106Z] CL2 j> 2023/04/19 10:18:23.606 done. [2023-04-19T10:18:34.606Z] CL2 j> 2023/04/19 10:18:33.797 Writing report data ... [2023-04-19T10:18:37.306Z] CL2 j> 2023/04/19 10:18:36.401 done. [2023-04-19T10:18:47.630Z] CL2 j> 2023/04/19 10:18:46.508 Writing report data ... [2023-04-19T10:18:49.806Z] CL2 j> 2023/04/19 10:18:49.603 done. [2023-04-19T10:19:00.406Z] CL2 j> 2023/04/19 10:18:59.803 Writing report data ... [2023-04-19T10:19:03.206Z] CL2 j> 2023/04/19 10:19:02.502 done. [2023-04-19T10:19:13.530Z] CL2 j> 2023/04/19 10:19:12.608 Writing report data ... [2023-04-19T10:19:15.806Z] CL2 j> 2023/04/19 10:19:15.307 done. [2023-04-19T10:19:26.706Z] CL2 j> 2023/04/19 10:19:25.406 Writing report data ... [2023-04-19T10:19:29.206Z] CL2 j> 2023/04/19 10:19:27.995 done. [2023-04-19T10:19:39.906Z] CL2 j> 2023/04/19 10:19:38.102 Writing report data ... [2023-04-19T10:19:42.731Z] CL2 j> 2023/04/19 10:19:41.600 done. [2023-04-19T10:19:53.355Z] CL2 j> 2023/04/19 10:19:51.706 Writing report data ... [2023-04-19T10:19:54.930Z] CL2 j> 2023/04/19 10:19:54.600 done. [2023-04-19T10:19:58.431Z] STF 10:19:58.197 - Heartbeat: Process CL2 is still running [2023-04-19T10:20:05.505Z] CL2 j> 2023/04/19 10:20:04.707 Writing report data ... [2023-04-19T10:20:08.329Z] CL2 j> 2023/04/19 10:20:07.397 done. [2023-04-19T10:20:18.506Z] CL2 j> 2023/04/19 10:20:17.495 Writing report data ... [2023-04-19T10:20:19.840Z] CL2 j> 2023/04/19 10:20:19.408 done. [2023-04-19T10:20:30.406Z] CL2 j> 2023/04/19 10:20:29.600 Writing report data ... [2023-04-19T10:20:32.260Z] CL2 j> 2023/04/19 10:20:31.996 done. [2023-04-19T10:20:42.805Z] CL2 j> 2023/04/19 10:20:42.103 Writing report data ... [2023-04-19T10:20:45.630Z] CL2 j> 2023/04/19 10:20:44.804 done. [2023-04-19T10:20:55.906Z] CL2 j> 2023/04/19 10:20:54.995 Writing report data ... [2023-04-19T10:20:58.030Z] CL2 j> 2023/04/19 10:20:57.403 done. [2023-04-19T10:21:08.305Z] CL2 j> 2023/04/19 10:21:07.506 Writing report data ... [2023-04-19T10:21:10.829Z] CL2 j> 2023/04/19 10:21:09.803 done. [2023-04-19T10:21:20.606Z] CL2 j> 2023/04/19 10:21:19.908 Writing report data ... [2023-04-19T10:21:23.229Z] CL2 j> 2023/04/19 10:21:22.109 done. [2023-04-19T10:21:33.431Z] CL2 j> 2023/04/19 10:21:32.311 Writing report data ... [2023-04-19T10:21:35.307Z] CL2 j> 2023/04/19 10:21:34.307 done. [2023-04-19T10:21:45.906Z] CL2 j> 2023/04/19 10:21:44.495 Writing report data ... [2023-04-19T10:21:47.406Z] CL2 j> 2023/04/19 10:21:46.604 done. [2023-04-19T10:21:59.331Z] CL2 j> 2023/04/19 10:21:56.706 Writing report data ... [2023-04-19T10:21:59.807Z] CL2 j> 2023/04/19 10:21:58.906 done. [2023-04-19T10:22:10.306Z] CL2 j> 2023/04/19 10:22:09.001 Writing report data ... [2023-04-19T10:22:11.963Z] CL2 j> 2023/04/19 10:22:11.105 done. [2023-04-19T10:22:22.606Z] CL2 j> 2023/04/19 10:22:21.298 Writing report data ... [2023-04-19T10:22:23.705Z] CL2 j> 2023/04/19 10:22:23.300 done. [2023-04-19T10:22:34.229Z] CL2 j> 2023/04/19 10:22:33.403 Writing report data ... [2023-04-19T10:22:37.030Z] CL2 j> 2023/04/19 10:22:36.098 done. [2023-04-19T10:22:47.255Z] CL2 j> 2023/04/19 10:22:46.196 Writing report data ... [2023-04-19T10:22:49.406Z] CL2 j> 2023/04/19 10:22:48.307 done. [2023-04-19T10:22:59.452Z] CL2 j> 2023/04/19 10:22:58.501 Writing report data ... [2023-04-19T10:23:02.131Z] CL2 j> 2023/04/19 10:23:00.908 done. [2023-04-19T10:23:12.506Z] CL2 j> 2023/04/19 10:23:11.006 Writing report data ... [2023-04-19T10:23:14.006Z] CL2 j> 2023/04/19 10:23:13.595 done. [2023-04-19T10:23:24.106Z] CL2 j> 2023/04/19 10:23:23.700 Writing report data ... [2023-04-19T10:23:26.206Z] CL2 j> 2023/04/19 10:23:25.897 done. [2023-04-19T10:23:27.630Z] STF 10:23:27.494 - Monitoring Report Summary: [2023-04-19T10:23:27.630Z] STF 10:23:27.494 - o Process CL2 ended with the expected exit code (0) [2023-04-19T10:23:27.630Z] STF 10:23:27.494 - o Process LT2 is still running as expected [2023-04-19T10:23:27.630Z] STF 10:23:27.495 - [2023-04-19T10:23:27.630Z] STF 10:23:27.495 - +------ Step 10 - Stop LT2 process [2023-04-19T10:23:27.630Z] STF 10:23:27.495 - | Kill running processes [2023-04-19T10:23:27.630Z] STF 10:23:27.495 - | Processes: [LT2] [2023-04-19T10:23:27.630Z] STF 10:23:27.495 - | [2023-04-19T10:23:27.630Z] STF 10:23:27.495 - Killing processes: LT2 [2023-04-19T10:23:27.630Z] STF 10:23:27.495 - o Process clean up attempt 1 for LT2 pid 278120 [2023-04-19T10:23:27.630Z] STF 10:23:27.495 - o Process LT2 pid 278120 stop() [2023-04-19T10:23:37.823Z] STF 10:23:37.496 - o Process LT2 pid 278120 terminate() [2023-04-19T10:23:38.569Z] STF 10:23:38.497 - o Process LT2 pid 278120 killed [2023-04-19T10:23:38.569Z] STF 10:23:38.497 - EXECUTE stage completed [2023-04-19T10:23:38.569Z] STF 10:23:38.531 - [2023-04-19T10:23:38.569Z] STF 10:23:38.531 - ==================== T E A R D O W N ==================== [2023-04-19T10:23:38.569Z] STF 10:23:38.531 - Running teardown: perl /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_linux/aqa-tests/TKG/../TKG/output_1681895265620/TestJlmRemoteThreadAuth_0/20230419-100957-TestJlmRemoteThreadAuth/tearDown.pl [2023-04-19T10:23:38.919Z] STF 10:23:38.582 - TEARDOWN stage completed [2023-04-19T10:23:38.920Z] STF 10:23:38.585 - [2023-04-19T10:23:38.920Z] STF 10:23:38.585 - ===================== R E S U L T S ===================== [2023-04-19T10:23:38.920Z] STF 10:23:38.585 - Stage results: [2023-04-19T10:23:38.920Z] STF 10:23:38.585 - setUp: pass [2023-04-19T10:23:38.920Z] STF 10:23:38.585 - execute: pass [2023-04-19T10:23:38.920Z] STF 10:23:38.585 - teardown: pass [2023-04-19T10:23:38.920Z] STF 10:23:38.585 - [2023-04-19T10:23:38.920Z] STF 10:23:38.585 - Overall result: PASSED [2023-04-19T10:23:38.920Z] ----------------------------------- [2023-04-19T10:23:38.920Z] TestJlmRemoteThreadAuth_0_PASSED [2023-04-19T10:23:38.920Z] ----------------------------------- [2023-04-19T10:23:38.920Z] [2023-04-19T10:23:38.920Z] TEST TEARDOWN: [2023-04-19T10:23:38.920Z] Nothing to be done for teardown. [2023-04-19T10:23:38.920Z] TestJlmRemoteThreadAuth_0 Finish Time: Wed Apr 19 10:23:38 2023 Epoch Time (ms): 1681899818608