[2023-04-19T10:14:51.323Z] Running test TestJlmRemoteThreadAuth_1 ... [2023-04-19T10:14:51.323Z] =============================================== [2023-04-19T10:14:51.323Z] TestJlmRemoteThreadAuth_1 Start Time: Wed Apr 19 10:14:51 2023 Epoch Time (ms): 1681899291196 [2023-04-19T10:14:51.324Z] variation: Mode650 [2023-04-19T10:14:51.324Z] JVM_OPTIONS: -XX:-UseCompressedOops [2023-04-19T10:14:51.324Z] { \ [2023-04-19T10:14:51.324Z] echo ""; echo "TEST SETUP:"; \ [2023-04-19T10:14:51.324Z] echo "Nothing to be done for setup."; \ [2023-04-19T10:14:51.324Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1"; \ [2023-04-19T10:14:51.324Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1"; \ [2023-04-19T10:14:51.324Z] echo ""; echo "TESTING:"; \ [2023-04-19T10:14:51.324Z] perl /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs" -java-args=' -XX:-UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1" -test=TestJlmRemoteThreadAuth; \ [2023-04-19T10:14:51.324Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_1""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-19T10:14:51.324Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-19T10:14:51.324Z] echo "Nothing to be done for teardown."; \ [2023-04-19T10:14:51.324Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestTargetResult"; [2023-04-19T10:14:51.324Z] [2023-04-19T10:14:51.324Z] TEST SETUP: [2023-04-19T10:14:51.324Z] Nothing to be done for setup. [2023-04-19T10:14:51.324Z] [2023-04-19T10:14:51.324Z] TESTING: [2023-04-19T10:14:51.324Z] STF 10:14:51.261 - ========================= S T F ========================= [2023-04-19T10:14:51.324Z] systemtest-prereqs has been processed, and set to: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqsRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1 [2023-04-19T10:14:51.324Z] There is 339082 Mb free [2023-04-19T10:14:51.324Z] STF 10:14:51.267 - ================== G E N E R A T I O N ================== [2023-04-19T10:14:51.324Z] STF 10:14:51.268 - Checking JVM: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image [2023-04-19T10:14:51.324Z] STF 10:14:51.268 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs/log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs/log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth" [2023-04-19T10:14:52.441Z] GEN 10:14:52.081 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2023-04-19T10:14:52.442Z] GEN 10:14:52.099 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadAuth' [2023-04-19T10:14:52.442Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2023-04-19T10:14:52.442Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2023-04-19T10:14:52.442Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/STF/stf.core/bin [2023-04-19T10:14:52.442Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar [2023-04-19T10:14:52.442Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar [2023-04-19T10:14:52.442Z] GEN /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar [2023-04-19T10:14:52.442Z] GEN 10:14:52.237 - Using Mode NoOptions. Values = '' [2023-04-19T10:14:52.442Z] GEN 10:14:52.346 - [2023-04-19T10:14:52.442Z] GEN 10:14:52.346 - Test command summary: [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - Step Stage Command Description [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - -----+--------+-----------------+------------ [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 1 setUp cp Copying password file to temp [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 2 setUp chmod Restricting file permission of jmxremote.passsword to 600 [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 3 execute Run java Running ThreadProfiler Proxy test Server Process(with security) [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 4 execute Run java Running Monitoring Client with proxy connection(with security) [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 5 execute Monitor Wait for the processes to complete [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 6 execute kill Stop LT1 process [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 7 execute Run java Running ThreadProfiler Server test Server Process(with security) [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 8 execute Run java Run the Monitoring Client with server-connection(with security) [2023-04-19T10:14:52.442Z] GEN 10:14:52.347 - 9 execute Monitor Wait for processes to complete [2023-04-19T10:14:52.442Z] GEN 10:14:52.348 - 10 execute kill Stop LT2 process [2023-04-19T10:14:52.442Z] STF 10:14:52.369 - [2023-04-19T10:14:52.442Z] STF 10:14:52.369 - Script generation completed [2023-04-19T10:14:52.442Z] STF 10:14:52.370 - [2023-04-19T10:14:52.442Z] STF 10:14:52.370 - [2023-04-19T10:14:52.442Z] STF 10:14:52.370 - ======================= S E T U P ======================= [2023-04-19T10:14:52.442Z] STF 10:14:52.370 - Running setup: perl /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/setUp.pl [2023-04-19T10:14:52.442Z] STF 10:14:52.427 - [2023-04-19T10:14:52.442Z] STF 10:14:52.427 - +------ Step 1 - Copying password file to temp [2023-04-19T10:14:52.442Z] STF 10:14:52.427 - | Copy a file to another directory [2023-04-19T10:14:52.442Z] STF 10:14:52.427 - | Source file: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/jmxremote.password [2023-04-19T10:14:52.442Z] STF 10:14:52.427 - | Dest dir: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/tmp [2023-04-19T10:14:52.442Z] STF 10:14:52.427 - | [2023-04-19T10:14:52.442Z] STF 10:14:52.427 - [2023-04-19T10:14:52.442Z] STF 10:14:52.427 - +------ Step 2 - Restricting file permission of jmxremote.passsword to 600 [2023-04-19T10:14:52.442Z] STF 10:14:52.428 - | Change file permission [2023-04-19T10:14:52.442Z] STF 10:14:52.428 - | targetFile: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/tmp/jmxremote.password [2023-04-19T10:14:52.442Z] STF 10:14:52.428 - | modeBits: 600 [2023-04-19T10:14:52.442Z] STF 10:14:52.428 - | [2023-04-19T10:14:52.442Z] STF 10:14:52.429 - SETUP stage completed [2023-04-19T10:14:52.442Z] STF 10:14:52.431 - [2023-04-19T10:14:52.442Z] STF 10:14:52.431 - ==================== E X E C U T E - ==================== [2023-04-19T10:14:52.442Z] STF 10:14:52.431 - Running execute: perl /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/execute.pl [2023-04-19T10:14:52.760Z] STF 10:14:52.489 - [2023-04-19T10:14:52.761Z] STF 10:14:52.489 - Java version [2023-04-19T10:14:52.761Z] STF 10:14:52.489 - Running: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/bin/java -version [2023-04-19T10:14:52.761Z] openjdk version "1.8.0_372" [2023-04-19T10:14:52.761Z] OpenJDK Runtime Environment (Temurin)(build 1.8.0_372-b07) [2023-04-19T10:14:52.761Z] OpenJDK 64-Bit Server VM (Temurin)(build 25.372-b07, mixed mode) [2023-04-19T10:14:52.761Z] STF 10:14:52.541 - [2023-04-19T10:14:52.761Z] STF 10:14:52.541 - +------ Step 3 - Running ThreadProfiler Proxy test Server Process(with security) [2023-04-19T10:14:52.761Z] STF 10:14:52.541 - | Start background process [2023-04-19T10:14:52.761Z] STF 10:14:52.541 - | Program: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/bin/java [2023-04-19T10:14:52.761Z] STF 10:14:52.541 - | Mnemonic: LT1 [2023-04-19T10:14:52.761Z] STF 10:14:52.541 - | Echo: ECHO_OFF [2023-04-19T10:14:52.761Z] STF 10:14:52.541 - | Expectation: NEVER [2023-04-19T10:14:52.761Z] STF 10:14:52.541 - | [2023-04-19T10:14:52.761Z] STF 10:14:52.542 - Running command: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/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_ppc64le_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_ppc64le_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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/STF/stf.core/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-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:14:52.761Z] STF 10:14:52.542 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/3.LT1.stderr [2023-04-19T10:14:52.761Z] STF 10:14:52.542 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/3.LT1.stdout [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - +------ Step 4 - Running Monitoring Client with proxy connection(with security) [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - | Start background process [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - | Program: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/bin/java [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - | Mnemonic: CL1 [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - | Echo: ECHO_ON [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - | Expectation: CLEAN_RUN within 30m [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - | [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - Running command: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_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_ppc64le_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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/sthd_proxy.log /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/sthd_proxy.csv auth controlRole control1 localhost 1234 [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/4.CL1.stderr [2023-04-19T10:14:52.761Z] STF 10:14:52.551 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/4.CL1.stdout [2023-04-19T10:14:52.761Z] STF 10:14:52.552 - [2023-04-19T10:14:52.761Z] STF 10:14:52.552 - +------ Step 5 - Wait for the processes to complete [2023-04-19T10:14:52.761Z] STF 10:14:52.552 - | Wait for processes to meet expectations [2023-04-19T10:14:52.761Z] STF 10:14:52.552 - | Processes: [LT1, CL1] [2023-04-19T10:14:52.761Z] STF 10:14:52.552 - | [2023-04-19T10:14:52.761Z] STF 10:14:52.552 - Monitoring processes: CL1 LT1 [2023-04-19T10:14:52.761Z] CL1 j> 2023/04/19 10:14:52.635 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2023-04-19T10:14:52.761Z] CL1 j> 2023/04/19 10:14:52.659 Attempting to connect [2023-04-19T10:14:53.079Z] CL1 j> 2023/04/19 10:14:52.810 Monitored VM not ready at Apr 19, 2023 10:14:52 AM (attempt 1, elapsed 140ms). [2023-04-19T10:14:53.079Z] CL1 j> 2023/04/19 10:14:52.810 Waiting 5 secs and trying again... [2023-04-19T10:14:58.749Z] CL1 j> 2023/04/19 10:14:57.845 Attempting to connect [2023-04-19T10:15:14.048Z] CL1 j> 2023/04/19 10:15:12.867 Connection established! [2023-04-19T10:15:14.049Z] CL1 j> 2023/04/19 10:15:13.257 Starting to write data [2023-04-19T10:19:53.508Z] STF 10:19:52.365 - Heartbeat: Process LT1 is still running [2023-04-19T10:20:41.809Z] STF 10:20:36.998 - Monitoring Report Summary: [2023-04-19T10:20:41.809Z] STF 10:20:36.998 - o Process CL1 ended with the expected exit code (0) [2023-04-19T10:20:41.809Z] STF 10:20:36.998 - o Process LT1 is still running as expected [2023-04-19T10:20:41.809Z] STF 10:20:36.999 - [2023-04-19T10:20:41.809Z] STF 10:20:36.999 - +------ Step 6 - Stop LT1 process [2023-04-19T10:20:41.809Z] STF 10:20:36.999 - | Kill running processes [2023-04-19T10:20:41.809Z] STF 10:20:36.999 - | Processes: [LT1] [2023-04-19T10:20:41.809Z] STF 10:20:36.999 - | [2023-04-19T10:20:41.809Z] STF 10:20:36.999 - Killing processes: LT1 [2023-04-19T10:20:41.809Z] STF 10:20:36.999 - o Process clean up attempt 1 for LT1 pid 191534 [2023-04-19T10:20:41.809Z] STF 10:20:36.999 - o Process LT1 pid 191534 stop() [2023-04-19T10:20:47.697Z] STF 10:20:47.000 - o Process LT1 pid 191534 terminate() [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - o Process LT1 pid 191534 killed [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - +------ Step 7 - Running ThreadProfiler Server test Server Process(with security) [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - | Start background process [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - | Program: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/bin/java [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - | Mnemonic: LT2 [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - | Echo: ECHO_OFF [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - | Expectation: NEVER [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - | [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - Running command: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/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_ppc64le_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_ppc64le_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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/STF/stf.core/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-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:20:48.809Z] STF 10:20:48.001 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/7.LT2.stderr [2023-04-19T10:20:48.809Z] STF 10:20:48.001 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/7.LT2.stdout [2023-04-19T10:20:48.809Z] STF 10:20:48.002 - [2023-04-19T10:20:48.809Z] STF 10:20:48.002 - +------ Step 8 - Run the Monitoring Client with server-connection(with security) [2023-04-19T10:20:48.809Z] STF 10:20:48.002 - | Start background process [2023-04-19T10:20:48.809Z] STF 10:20:48.002 - | Program: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/bin/java [2023-04-19T10:20:48.809Z] STF 10:20:48.002 - | Mnemonic: CL2 [2023-04-19T10:20:48.809Z] STF 10:20:48.002 - | Echo: ECHO_ON [2023-04-19T10:20:48.809Z] STF 10:20:48.002 - | Expectation: CLEAN_RUN within 30m [2023-04-19T10:20:48.809Z] STF 10:20:48.002 - | [2023-04-19T10:20:48.810Z] STF 10:20:48.003 - Running command: /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/openjdkbinary/j2sdk-image/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_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_ppc64le_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_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/jvmtest/system/systemtest_prereqs/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/sthd_server.log /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/sthd_server.csv auth controlRole control1 localhost 1234 [2023-04-19T10:20:48.810Z] STF 10:20:48.003 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/8.CL2.stderr [2023-04-19T10:20:48.810Z] STF 10:20:48.003 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/results/8.CL2.stdout [2023-04-19T10:20:48.810Z] STF 10:20:48.003 - [2023-04-19T10:20:48.810Z] STF 10:20:48.004 - +------ Step 9 - Wait for processes to complete [2023-04-19T10:20:48.810Z] STF 10:20:48.004 - | Wait for processes to meet expectations [2023-04-19T10:20:48.810Z] STF 10:20:48.004 - | Processes: [LT2, CL2] [2023-04-19T10:20:48.810Z] STF 10:20:48.004 - | [2023-04-19T10:20:48.810Z] STF 10:20:48.004 - Monitoring processes: CL2 LT2 [2023-04-19T10:20:48.810Z] CL2 j> 2023/04/19 10:20:48.082 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2023-04-19T10:20:48.810Z] CL2 j> 2023/04/19 10:20:48.107 Attempting to connect [2023-04-19T10:20:48.810Z] CL2 j> 2023/04/19 10:20:48.249 Monitored VM not ready at Apr 19, 2023 10:20:48 AM (attempt 1, elapsed 132ms). [2023-04-19T10:20:48.810Z] CL2 j> 2023/04/19 10:20:48.249 Waiting 5 secs and trying again... [2023-04-19T10:20:53.667Z] CL2 j> 2023/04/19 10:20:53.250 Attempting to connect [2023-04-19T10:21:11.548Z] CL2 j> 2023/04/19 10:21:08.753 Connection established! [2023-04-19T10:21:25.682Z] CL2 j> 2023/04/19 10:21:24.902 Writing report data ... [2023-04-19T10:21:29.499Z] CL2 j> 2023/04/19 10:21:27.964 done. [2023-04-19T10:21:38.447Z] CL2 j> 2023/04/19 10:21:38.065 Writing report data ... [2023-04-19T10:21:40.683Z] CL2 j> 2023/04/19 10:21:39.849 done. [2023-04-19T10:21:51.518Z] CL2 j> 2023/04/19 10:21:49.884 Writing report data ... [2023-04-19T10:21:51.840Z] CL2 j> 2023/04/19 10:21:51.272 done. [2023-04-19T10:22:02.706Z] CL2 j> 2023/04/19 10:22:01.306 Writing report data ... [2023-04-19T10:22:03.083Z] CL2 j> 2023/04/19 10:22:02.478 done. [2023-04-19T10:22:13.930Z] CL2 j> 2023/04/19 10:22:12.548 Writing report data ... [2023-04-19T10:22:14.672Z] CL2 j> 2023/04/19 10:22:13.796 done. [2023-04-19T10:22:25.764Z] CL2 j> 2023/04/19 10:22:23.888 Writing report data ... [2023-04-19T10:22:25.764Z] CL2 j> 2023/04/19 10:22:24.973 done. [2023-04-19T10:22:36.483Z] CL2 j> 2023/04/19 10:22:35.003 Writing report data ... [2023-04-19T10:22:36.483Z] CL2 j> 2023/04/19 10:22:36.157 done. [2023-04-19T10:22:47.370Z] CL2 j> 2023/04/19 10:22:46.180 Writing report data ... [2023-04-19T10:22:47.689Z] CL2 j> 2023/04/19 10:22:47.151 done. [2023-04-19T10:22:58.663Z] CL2 j> 2023/04/19 10:22:57.178 Writing report data ... [2023-04-19T10:22:58.663Z] CL2 j> 2023/04/19 10:22:58.104 done. [2023-04-19T10:23:09.556Z] CL2 j> 2023/04/19 10:23:08.179 Writing report data ... [2023-04-19T10:23:09.556Z] CL2 j> 2023/04/19 10:23:09.275 done. [2023-04-19T10:23:20.582Z] CL2 j> 2023/04/19 10:23:19.298 Writing report data ... [2023-04-19T10:23:20.582Z] CL2 j> 2023/04/19 10:23:20.197 done. [2023-04-19T10:23:31.482Z] CL2 j> 2023/04/19 10:23:30.264 Writing report data ... [2023-04-19T10:23:31.806Z] CL2 j> 2023/04/19 10:23:31.184 done. [2023-04-19T10:23:42.781Z] CL2 j> 2023/04/19 10:23:41.206 Writing report data ... [2023-04-19T10:23:42.781Z] CL2 j> 2023/04/19 10:23:42.196 done. [2023-04-19T10:23:53.647Z] CL2 j> 2023/04/19 10:23:52.265 Writing report data ... [2023-04-19T10:23:54.393Z] CL2 j> 2023/04/19 10:23:53.192 done. [2023-04-19T10:24:05.349Z] CL2 j> 2023/04/19 10:24:03.257 Writing report data ... [2023-04-19T10:24:05.349Z] CL2 j> 2023/04/19 10:24:04.147 done. [2023-04-19T10:24:16.400Z] CL2 j> 2023/04/19 10:24:14.170 Writing report data ... [2023-04-19T10:24:16.400Z] CL2 j> 2023/04/19 10:24:15.159 done. [2023-04-19T10:24:27.381Z] CL2 j> 2023/04/19 10:24:25.192 Writing report data ... [2023-04-19T10:24:27.381Z] CL2 j> 2023/04/19 10:24:26.056 done. [2023-04-19T10:24:38.282Z] CL2 j> 2023/04/19 10:24:36.078 Writing report data ... [2023-04-19T10:24:38.282Z] CL2 j> 2023/04/19 10:24:36.904 done. [2023-04-19T10:24:49.247Z] CL2 j> 2023/04/19 10:24:46.962 Writing report data ... [2023-04-19T10:24:49.247Z] CL2 j> 2023/04/19 10:24:47.760 done. [2023-04-19T10:24:53.166Z] STF 10:24:52.352 - Heartbeat: Process CL2 is still running [2023-04-19T10:24:59.167Z] CL2 j> 2023/04/19 10:24:57.779 Writing report data ... [2023-04-19T10:24:59.491Z] CL2 j> 2023/04/19 10:24:58.610 done. [2023-04-19T10:25:10.509Z] CL2 j> 2023/04/19 10:25:08.672 Writing report data ... [2023-04-19T10:25:10.509Z] CL2 j> 2023/04/19 10:25:09.682 done. [2023-04-19T10:25:21.480Z] CL2 j> 2023/04/19 10:25:19.701 Writing report data ... [2023-04-19T10:25:21.480Z] CL2 j> 2023/04/19 10:25:20.678 done. [2023-04-19T10:25:32.464Z] CL2 j> 2023/04/19 10:25:30.696 Writing report data ... [2023-04-19T10:25:32.464Z] CL2 j> 2023/04/19 10:25:31.481 done. [2023-04-19T10:25:43.386Z] CL2 j> 2023/04/19 10:25:41.502 Writing report data ... [2023-04-19T10:25:43.386Z] CL2 j> 2023/04/19 10:25:42.470 done. [2023-04-19T10:25:54.300Z] CL2 j> 2023/04/19 10:25:52.488 Writing report data ... [2023-04-19T10:25:54.300Z] CL2 j> 2023/04/19 10:25:53.160 done. [2023-04-19T10:26:05.481Z] CL2 j> 2023/04/19 10:26:03.178 Writing report data ... [2023-04-19T10:26:05.481Z] CL2 j> 2023/04/19 10:26:03.913 done. [2023-04-19T10:26:14.463Z] CL2 j> 2023/04/19 10:26:13.968 Writing report data ... [2023-04-19T10:26:15.682Z] CL2 j> 2023/04/19 10:26:14.769 done. [2023-04-19T10:26:26.570Z] CL2 j> 2023/04/19 10:26:24.789 Writing report data ... [2023-04-19T10:26:26.570Z] CL2 j> 2023/04/19 10:26:25.606 done. [2023-04-19T10:26:37.487Z] CL2 j> 2023/04/19 10:26:35.663 Writing report data ... [2023-04-19T10:26:37.488Z] CL2 j> 2023/04/19 10:26:36.472 done. [2023-04-19T10:26:48.517Z] CL2 j> 2023/04/19 10:26:46.494 Writing report data ... [2023-04-19T10:26:48.517Z] CL2 j> 2023/04/19 10:26:47.296 done. [2023-04-19T10:26:49.208Z] STF 10:26:48.778 - Monitoring Report Summary: [2023-04-19T10:26:49.208Z] STF 10:26:48.778 - o Process CL2 ended with the expected exit code (0) [2023-04-19T10:26:49.208Z] STF 10:26:48.779 - o Process LT2 is still running as expected [2023-04-19T10:26:49.209Z] STF 10:26:48.779 - [2023-04-19T10:26:49.209Z] STF 10:26:48.779 - +------ Step 10 - Stop LT2 process [2023-04-19T10:26:49.209Z] STF 10:26:48.779 - | Kill running processes [2023-04-19T10:26:49.209Z] STF 10:26:48.779 - | Processes: [LT2] [2023-04-19T10:26:49.209Z] STF 10:26:48.779 - | [2023-04-19T10:26:49.209Z] STF 10:26:48.779 - Killing processes: LT2 [2023-04-19T10:26:49.209Z] STF 10:26:48.779 - o Process clean up attempt 1 for LT2 pid 191849 [2023-04-19T10:26:49.209Z] STF 10:26:48.779 - o Process LT2 pid 191849 stop() [2023-04-19T10:27:00.192Z] STF 10:26:58.780 - o Process LT2 pid 191849 terminate() [2023-04-19T10:27:00.192Z] STF 10:26:59.781 - o Process LT2 pid 191849 killed [2023-04-19T10:27:00.192Z] STF 10:26:59.781 - EXECUTE stage completed [2023-04-19T10:27:00.192Z] STF 10:27:00.050 - [2023-04-19T10:27:00.192Z] STF 10:27:00.050 - ==================== T E A R D O W N ==================== [2023-04-19T10:27:00.192Z] STF 10:27:00.050 - Running teardown: perl /home/jenkins/workspace/Test_openjdk8_hs_sanity.system_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818954738650/TestJlmRemoteThreadAuth_1/20230419-101451-TestJlmRemoteThreadAuth/tearDown.pl [2023-04-19T10:27:00.192Z] STF 10:27:00.111 - TEARDOWN stage completed [2023-04-19T10:27:00.192Z] STF 10:27:00.113 - [2023-04-19T10:27:00.192Z] STF 10:27:00.113 - ===================== R E S U L T S ===================== [2023-04-19T10:27:00.192Z] STF 10:27:00.113 - Stage results: [2023-04-19T10:27:00.192Z] STF 10:27:00.113 - setUp: pass [2023-04-19T10:27:00.192Z] STF 10:27:00.113 - execute: pass [2023-04-19T10:27:00.192Z] STF 10:27:00.113 - teardown: pass [2023-04-19T10:27:00.192Z] STF 10:27:00.113 - [2023-04-19T10:27:00.192Z] STF 10:27:00.113 - Overall result: PASSED [2023-04-19T10:27:00.192Z] ----------------------------------- [2023-04-19T10:27:00.192Z] TestJlmRemoteThreadAuth_1_PASSED [2023-04-19T10:27:00.192Z] ----------------------------------- [2023-04-19T10:27:00.192Z] [2023-04-19T10:27:00.192Z] TEST TEARDOWN: [2023-04-19T10:27:00.192Z] Nothing to be done for teardown. [2023-04-19T10:27:00.192Z] TestJlmRemoteThreadAuth_1 Finish Time: Wed Apr 19 10:27:00 2023 Epoch Time (ms): 1681900020129