[2024-08-01T03:53:29.939Z] Running test TestJlmRemoteThreadAuth_1 ... [2024-08-01T03:53:29.939Z] =============================================== [2024-08-01T03:53:29.939Z] TestJlmRemoteThreadAuth_1 Start Time: Thu Aug 1 03:53:29 2024 Epoch Time (ms): 1722484409280 [2024-08-01T03:53:29.939Z] variation: Mode650 [2024-08-01T03:53:29.939Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-08-01T03:53:29.939Z] { \ [2024-08-01T03:53:29.939Z] echo ""; echo "TEST SETUP:"; \ [2024-08-01T03:53:29.939Z] echo "Nothing to be done for setup."; \ [2024-08-01T03:53:29.939Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1"; \ [2024-08-01T03:53:29.939Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1"; \ [2024-08-01T03:53:29.939Z] echo ""; echo "TESTING:"; \ [2024-08-01T03:53:29.939Z] perl /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/../../testDependency/system_lib/" -java-args=' -XX:-UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1" -test=TestJlmRemoteThreadAuth; \ [2024-08-01T03:53:29.940Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_1""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-01T03:53:29.940Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-01T03:53:29.940Z] echo "Nothing to be done for teardown."; \ [2024-08-01T03:53:29.940Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestTargetResult"; [2024-08-01T03:53:29.940Z] [2024-08-01T03:53:29.940Z] TEST SETUP: [2024-08-01T03:53:29.940Z] Nothing to be done for setup. [2024-08-01T03:53:29.940Z] [2024-08-01T03:53:29.940Z] TESTING: [2024-08-01T03:53:29.940Z] STF 03:53:29.526 - ========================= S T F ========================= [2024-08-01T03:53:29.940Z] systemtest-prereqs has been processed, and set to: /home/jenkins/testDependency/system_libRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1 [2024-08-01T03:53:29.940Z] There is 139392 Mb free [2024-08-01T03:53:29.940Z] STF 03:53:29.538 - ================== G E N E R A T I O N ================== [2024-08-01T03:53:29.940Z] STF 03:53:29.539 - Checking JVM: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image [2024-08-01T03:53:29.940Z] STF 03:53:29.539 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth" [2024-08-01T03:53:35.389Z] GEN 03:53:34.568 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-08-01T03:53:35.389Z] GEN 03:53:34.738 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadAuth' [2024-08-01T03:53:36.143Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-08-01T03:53:36.143Z] GEN /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-08-01T03:53:36.143Z] GEN /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.core/bin [2024-08-01T03:53:36.143Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-08-01T03:53:36.143Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-08-01T03:53:36.143Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-08-01T03:53:36.143Z] GEN 03:53:35.584 - Using Mode NoOptions. Values = '' [2024-08-01T03:53:36.899Z] GEN 03:53:36.710 - [2024-08-01T03:53:36.899Z] GEN 03:53:36.710 - Test command summary: [2024-08-01T03:53:36.899Z] GEN 03:53:36.711 - Step Stage Command Description [2024-08-01T03:53:36.899Z] GEN 03:53:36.711 - -----+--------+-----------------+------------ [2024-08-01T03:53:36.899Z] GEN 03:53:36.711 - 1 setUp cp Copying password file to temp [2024-08-01T03:53:36.899Z] GEN 03:53:36.747 - 2 setUp chmod Restricting file permission of jmxremote.passsword to 600 [2024-08-01T03:53:36.899Z] GEN 03:53:36.748 - 3 execute Run java Running ThreadProfiler Proxy test Server Process(with security) [2024-08-01T03:53:36.899Z] GEN 03:53:36.748 - 4 execute Run java Running Monitoring Client with proxy connection(with security) [2024-08-01T03:53:36.899Z] GEN 03:53:36.748 - 5 execute Monitor Wait for the processes to complete [2024-08-01T03:53:36.899Z] GEN 03:53:36.748 - 6 execute kill Stop LT1 process [2024-08-01T03:53:36.899Z] GEN 03:53:36.748 - 7 execute Run java Running ThreadProfiler Server test Server Process(with security) [2024-08-01T03:53:36.899Z] GEN 03:53:36.748 - 8 execute Run java Run the Monitoring Client with server-connection(with security) [2024-08-01T03:53:36.899Z] GEN 03:53:36.748 - 9 execute Monitor Wait for processes to complete [2024-08-01T03:53:36.899Z] GEN 03:53:36.748 - 10 execute kill Stop LT2 process [2024-08-01T03:53:37.645Z] STF 03:53:36.898 - [2024-08-01T03:53:37.645Z] STF 03:53:36.898 - Script generation completed [2024-08-01T03:53:37.645Z] STF 03:53:36.898 - [2024-08-01T03:53:37.645Z] STF 03:53:36.898 - [2024-08-01T03:53:37.645Z] STF 03:53:36.898 - ======================= S E T U P ======================= [2024-08-01T03:53:37.645Z] STF 03:53:36.898 - Running setup: perl /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/setUp.pl [2024-08-01T03:53:37.645Z] STF 03:53:37.124 - [2024-08-01T03:53:37.645Z] STF 03:53:37.124 - +------ Step 1 - Copying password file to temp [2024-08-01T03:53:37.645Z] STF 03:53:37.124 - | Copy a file to another directory [2024-08-01T03:53:37.645Z] STF 03:53:37.124 - | Source file: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/jmxremote.password [2024-08-01T03:53:37.645Z] STF 03:53:37.124 - | Dest dir: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/tmp [2024-08-01T03:53:37.645Z] STF 03:53:37.124 - | [2024-08-01T03:53:37.645Z] STF 03:53:37.125 - [2024-08-01T03:53:37.645Z] STF 03:53:37.125 - +------ Step 2 - Restricting file permission of jmxremote.passsword to 600 [2024-08-01T03:53:37.645Z] STF 03:53:37.125 - | Change file permission [2024-08-01T03:53:37.645Z] STF 03:53:37.125 - | targetFile: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/tmp/jmxremote.password [2024-08-01T03:53:37.645Z] STF 03:53:37.125 - | modeBits: 600 [2024-08-01T03:53:37.645Z] STF 03:53:37.125 - | [2024-08-01T03:53:37.645Z] STF 03:53:37.130 - SETUP stage completed [2024-08-01T03:53:37.645Z] STF 03:53:37.137 - [2024-08-01T03:53:37.645Z] STF 03:53:37.138 - ==================== E X E C U T E - ==================== [2024-08-01T03:53:37.645Z] STF 03:53:37.138 - Running execute: perl /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/execute.pl [2024-08-01T03:53:37.645Z] STF 03:53:37.380 - [2024-08-01T03:53:37.645Z] STF 03:53:37.380 - Java version [2024-08-01T03:53:37.645Z] STF 03:53:37.380 - Running: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -version [2024-08-01T03:53:38.424Z] openjdk version "11.0.25-beta" 2024-10-15 [2024-08-01T03:53:38.424Z] OpenJDK Runtime Environment Temurin-11.0.25+1-202407312003 (build 11.0.25-beta+1-ea) [2024-08-01T03:53:38.424Z] OpenJDK 64-Bit Server VM Temurin-11.0.25+1-202407312003 (build 11.0.25-beta+1-ea, mixed mode) [2024-08-01T03:53:38.424Z] STF 03:53:37.761 - [2024-08-01T03:53:38.424Z] STF 03:53:37.761 - +------ Step 3 - Running ThreadProfiler Proxy test Server Process(with security) [2024-08-01T03:53:38.424Z] STF 03:53:37.761 - | Start background process [2024-08-01T03:53:38.424Z] STF 03:53:37.761 - | Program: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-01T03:53:38.424Z] STF 03:53:37.761 - | Mnemonic: LT1 [2024-08-01T03:53:38.424Z] STF 03:53:37.761 - | Echo: ECHO_OFF [2024-08-01T03:53:38.424Z] STF 03:53:37.761 - | Expectation: NEVER [2024-08-01T03:53:38.424Z] STF 03:53:37.761 - | [2024-08-01T03:53:38.424Z] STF 03:53:37.762 - Running command: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-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 [2024-08-01T03:53:38.424Z] STF 03:53:37.762 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/3.LT1.stderr [2024-08-01T03:53:38.424Z] STF 03:53:37.762 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/3.LT1.stdout [2024-08-01T03:53:38.424Z] STF 03:53:37.793 - [2024-08-01T03:53:38.424Z] STF 03:53:37.793 - +------ Step 4 - Running Monitoring Client with proxy connection(with security) [2024-08-01T03:53:38.424Z] STF 03:53:37.793 - | Start background process [2024-08-01T03:53:38.425Z] STF 03:53:37.793 - | Program: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-01T03:53:38.425Z] STF 03:53:37.793 - | Mnemonic: CL1 [2024-08-01T03:53:38.425Z] STF 03:53:37.793 - | Echo: ECHO_ON [2024-08-01T03:53:38.425Z] STF 03:53:37.793 - | Expectation: CLEAN_RUN within 30m [2024-08-01T03:53:38.425Z] STF 03:53:37.793 - | [2024-08-01T03:53:38.425Z] STF 03:53:37.794 - Running command: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/sthd_proxy.log /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/sthd_proxy.csv auth controlRole control1 localhost 1234 [2024-08-01T03:53:38.425Z] STF 03:53:37.794 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/4.CL1.stderr [2024-08-01T03:53:38.425Z] STF 03:53:37.794 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/4.CL1.stdout [2024-08-01T03:53:38.425Z] STF 03:53:37.796 - [2024-08-01T03:53:38.425Z] STF 03:53:37.796 - +------ Step 5 - Wait for the processes to complete [2024-08-01T03:53:38.425Z] STF 03:53:37.796 - | Wait for processes to meet expectations [2024-08-01T03:53:38.425Z] STF 03:53:37.796 - | Processes: [LT1, CL1] [2024-08-01T03:53:38.425Z] STF 03:53:37.796 - | [2024-08-01T03:53:38.425Z] STF 03:53:37.796 - Monitoring processes: CL1 LT1 [2024-08-01T03:53:41.033Z] CL1 j> 2024/08/01 03:53:39.543 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-01T03:53:41.033Z] CL1 j> 2024/08/01 03:53:40.539 Attempting to connect [2024-08-01T03:53:42.643Z] CL1 j> 2024/08/01 03:53:41.919 Monitored VM not ready at Aug 1, 2024, 3:53:41 AM (attempt 1, elapsed 1161ms). [2024-08-01T03:53:42.643Z] CL1 j> 2024/08/01 03:53:41.939 Waiting 5 secs and trying again... [2024-08-01T03:53:47.225Z] CL1 j> 2024/08/01 03:53:46.963 Attempting to connect [2024-08-01T03:53:59.959Z] CL1 j> 2024/08/01 03:53:58.178 Connection established! [2024-08-01T03:54:00.894Z] CL1 j> 2024/08/01 03:53:59.938 Starting to write data [2024-08-01T03:58:41.691Z] STF 03:58:37.476 - Heartbeat: Process CL1 is still running [2024-08-01T04:01:50.795Z] STF 04:01:42.993 - Monitoring Report Summary: [2024-08-01T04:01:50.795Z] STF 04:01:42.993 - o Process CL1 ended with the expected exit code (0) [2024-08-01T04:01:50.795Z] STF 04:01:42.993 - o Process LT1 is still running as expected [2024-08-01T04:01:50.795Z] STF 04:01:42.994 - [2024-08-01T04:01:50.795Z] STF 04:01:42.994 - +------ Step 6 - Stop LT1 process [2024-08-01T04:01:50.795Z] STF 04:01:42.994 - | Kill running processes [2024-08-01T04:01:50.795Z] STF 04:01:42.994 - | Processes: [LT1] [2024-08-01T04:01:50.795Z] STF 04:01:42.994 - | [2024-08-01T04:01:50.795Z] STF 04:01:42.994 - Killing processes: LT1 [2024-08-01T04:01:50.795Z] STF 04:01:42.994 - o Process clean up attempt 1 for LT1 pid 465462 [2024-08-01T04:01:50.795Z] STF 04:01:42.994 - o Process LT1 pid 465462 stop() [2024-08-01T04:01:52.418Z] STF 04:01:52.060 - o Process LT1 pid 465462 terminate() [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - o Process LT1 pid 465462 killed [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - +------ Step 7 - Running ThreadProfiler Server test Server Process(with security) [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - | Start background process [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - | Program: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - | Mnemonic: LT2 [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - | Echo: ECHO_OFF [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - | Expectation: NEVER [2024-08-01T04:01:54.012Z] STF 04:01:53.072 - | [2024-08-01T04:01:54.013Z] STF 04:01:53.073 - Running command: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-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 [2024-08-01T04:01:54.013Z] STF 04:01:53.073 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/7.LT2.stderr [2024-08-01T04:01:54.013Z] STF 04:01:53.073 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/7.LT2.stdout [2024-08-01T04:01:54.013Z] STF 04:01:53.074 - [2024-08-01T04:01:54.013Z] STF 04:01:53.074 - +------ Step 8 - Run the Monitoring Client with server-connection(with security) [2024-08-01T04:01:54.013Z] STF 04:01:53.074 - | Start background process [2024-08-01T04:01:54.013Z] STF 04:01:53.075 - | Program: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-01T04:01:54.013Z] STF 04:01:53.075 - | Mnemonic: CL2 [2024-08-01T04:01:54.013Z] STF 04:01:53.075 - | Echo: ECHO_ON [2024-08-01T04:01:54.013Z] STF 04:01:53.075 - | Expectation: CLEAN_RUN within 30m [2024-08-01T04:01:54.013Z] STF 04:01:53.075 - | [2024-08-01T04:01:54.013Z] STF 04:01:53.075 - Running command: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/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_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/sthd_server.log /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/sthd_server.csv auth controlRole control1 localhost 1234 [2024-08-01T04:01:54.013Z] STF 04:01:53.075 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/8.CL2.stderr [2024-08-01T04:01:54.013Z] STF 04:01:53.075 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/results/8.CL2.stdout [2024-08-01T04:01:54.013Z] STF 04:01:53.077 - [2024-08-01T04:01:54.013Z] STF 04:01:53.077 - +------ Step 9 - Wait for processes to complete [2024-08-01T04:01:54.013Z] STF 04:01:53.077 - | Wait for processes to meet expectations [2024-08-01T04:01:54.013Z] STF 04:01:53.077 - | Processes: [LT2, CL2] [2024-08-01T04:01:54.013Z] STF 04:01:53.077 - | [2024-08-01T04:01:54.013Z] STF 04:01:53.077 - Monitoring processes: CL2 LT2 [2024-08-01T04:01:54.795Z] CL2 j> 2024/08/01 04:01:54.169 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-01T04:01:54.795Z] CL2 j> 2024/08/01 04:01:54.682 Attempting to connect [2024-08-01T04:01:56.425Z] CL2 j> 2024/08/01 04:01:55.711 Monitored VM not ready at Aug 1, 2024, 4:01:55 AM (attempt 1, elapsed 803ms). [2024-08-01T04:01:56.425Z] CL2 j> 2024/08/01 04:01:55.711 Waiting 5 secs and trying again... [2024-08-01T04:02:01.108Z] CL2 j> 2024/08/01 04:02:00.731 Attempting to connect [2024-08-01T04:02:06.940Z] CL2 j> 2024/08/01 04:02:05.889 Connection established! [2024-08-01T04:02:23.556Z] CL2 j> 2024/08/01 04:02:21.591 Writing report data ... [2024-08-01T04:02:45.855Z] CL2 j> 2024/08/01 04:02:44.119 done. [2024-08-01T04:02:55.920Z] CL2 j> 2024/08/01 04:02:55.252 Writing report data ... [2024-08-01T04:03:07.988Z] CL2 j> 2024/08/01 04:03:07.640 done. [2024-08-01T04:03:19.429Z] CL2 j> 2024/08/01 04:03:18.221 Writing report data ... [2024-08-01T04:03:26.298Z] CL2 j> 2024/08/01 04:03:26.061 done. [2024-08-01T04:03:38.528Z] CL2 j> 2024/08/01 04:03:36.492 Writing report data ... [2024-08-01T04:03:38.528Z] STF 04:03:37.248 - Heartbeat: Process CL2 is still running [2024-08-01T04:03:45.487Z] CL2 j> 2024/08/01 04:03:44.193 done. [2024-08-01T04:03:55.886Z] CL2 j> 2024/08/01 04:03:54.563 Writing report data ... [2024-08-01T04:04:01.697Z] CL2 j> 2024/08/01 04:04:00.798 done. [2024-08-01T04:04:11.905Z] CL2 j> 2024/08/01 04:04:11.031 Writing report data ... [2024-08-01T04:04:17.642Z] CL2 j> 2024/08/01 04:04:17.125 done. [2024-08-01T04:04:29.737Z] CL2 j> 2024/08/01 04:04:27.647 Writing report data ... [2024-08-01T04:04:36.528Z] CL2 j> 2024/08/01 04:04:34.623 done. [2024-08-01T04:04:45.422Z] CL2 j> 2024/08/01 04:04:45.035 Writing report data ... [2024-08-01T04:04:52.625Z] CL2 j> 2024/08/01 04:04:51.098 done. [2024-08-01T04:05:02.795Z] CL2 j> 2024/08/01 04:05:01.271 Writing report data ... [2024-08-01T04:05:08.627Z] CL2 j> 2024/08/01 04:05:08.420 done. [2024-08-01T04:05:18.805Z] CL2 j> 2024/08/01 04:05:18.547 Writing report data ... [2024-08-01T04:05:27.078Z] CL2 j> 2024/08/01 04:05:26.167 done. [2024-08-01T04:05:36.976Z] CL2 j> 2024/08/01 04:05:36.614 Writing report data ... [2024-08-01T04:05:45.351Z] CL2 j> 2024/08/01 04:05:43.673 done. [2024-08-01T04:05:54.802Z] CL2 j> 2024/08/01 04:05:54.327 Writing report data ... [2024-08-01T04:06:00.368Z] CL2 j> 2024/08/01 04:05:59.876 done. [2024-08-01T04:06:12.114Z] CL2 j> 2024/08/01 04:06:10.315 Writing report data ... [2024-08-01T04:06:17.654Z] CL2 j> 2024/08/01 04:06:16.646 done. [2024-08-01T04:06:29.405Z] CL2 j> 2024/08/01 04:06:27.430 Writing report data ... [2024-08-01T04:06:36.146Z] CL2 j> 2024/08/01 04:06:34.387 done. [2024-08-01T04:06:46.089Z] CL2 j> 2024/08/01 04:06:44.563 Writing report data ... [2024-08-01T04:06:51.642Z] CL2 j> 2024/08/01 04:06:50.235 done. [2024-08-01T04:07:01.381Z] CL2 j> 2024/08/01 04:07:00.737 Writing report data ... [2024-08-01T04:07:05.523Z] CL2 j> 2024/08/01 04:07:04.787 done. [2024-08-01T04:07:17.439Z] CL2 j> 2024/08/01 04:07:15.161 Writing report data ... [2024-08-01T04:07:23.157Z] CL2 j> 2024/08/01 04:07:22.009 done. [2024-08-01T04:07:33.165Z] CL2 j> 2024/08/01 04:07:32.332 Writing report data ... [2024-08-01T04:07:37.702Z] CL2 j> 2024/08/01 04:07:36.628 done. [2024-08-01T04:07:47.846Z] CL2 j> 2024/08/01 04:07:46.959 Writing report data ... [2024-08-01T04:07:55.007Z] CL2 j> 2024/08/01 04:07:53.552 done. [2024-08-01T04:08:05.207Z] CL2 j> 2024/08/01 04:08:03.756 Writing report data ... [2024-08-01T04:08:09.699Z] CL2 j> 2024/08/01 04:08:08.601 done. [2024-08-01T04:08:19.727Z] CL2 j> 2024/08/01 04:08:18.973 Writing report data ... [2024-08-01T04:08:26.329Z] CL2 j> 2024/08/01 04:08:25.192 done. [2024-08-01T04:08:36.449Z] CL2 j> 2024/08/01 04:08:35.612 Writing report data ... [2024-08-01T04:08:38.102Z] STF 04:08:37.181 - Heartbeat: Process CL2 is still running [2024-08-01T04:08:41.607Z] CL2 j> 2024/08/01 04:08:40.799 done. [2024-08-01T04:08:51.752Z] CL2 j> 2024/08/01 04:08:51.227 Writing report data ... [2024-08-01T04:08:57.481Z] CL2 j> 2024/08/01 04:08:56.556 done. [2024-08-01T04:09:07.428Z] CL2 j> 2024/08/01 04:09:06.640 Writing report data ... [2024-08-01T04:09:13.084Z] CL2 j> 2024/08/01 04:09:11.951 done. [2024-08-01T04:09:23.119Z] CL2 j> 2024/08/01 04:09:22.123 Writing report data ... [2024-08-01T04:09:28.708Z] CL2 j> 2024/08/01 04:09:27.690 done. [2024-08-01T04:09:38.732Z] CL2 j> 2024/08/01 04:09:37.951 Writing report data ... [2024-08-01T04:09:44.006Z] CL2 j> 2024/08/01 04:09:42.857 done. [2024-08-01T04:09:54.084Z] CL2 j> 2024/08/01 04:09:52.956 Writing report data ... [2024-08-01T04:09:58.714Z] CL2 j> 2024/08/01 04:09:56.939 done. [2024-08-01T04:10:08.685Z] CL2 j> 2024/08/01 04:10:07.163 Writing report data ... [2024-08-01T04:10:12.176Z] CL2 j> 2024/08/01 04:10:11.031 done. [2024-08-01T04:10:22.357Z] CL2 j> 2024/08/01 04:10:21.227 Writing report data ... [2024-08-01T04:10:24.811Z] CL2 j> 2024/08/01 04:10:23.950 done. [2024-08-01T04:10:34.791Z] CL2 j> 2024/08/01 04:10:34.155 Writing report data ... [2024-08-01T04:10:39.322Z] CL2 j> 2024/08/01 04:10:37.817 done. [2024-08-01T04:10:40.092Z] STF 04:10:39.253 - Monitoring Report Summary: [2024-08-01T04:10:40.092Z] STF 04:10:39.253 - o Process CL2 ended with the expected exit code (0) [2024-08-01T04:10:40.092Z] STF 04:10:39.253 - o Process LT2 is still running as expected [2024-08-01T04:10:40.092Z] STF 04:10:39.253 - [2024-08-01T04:10:40.093Z] STF 04:10:39.253 - +------ Step 10 - Stop LT2 process [2024-08-01T04:10:40.093Z] STF 04:10:39.253 - | Kill running processes [2024-08-01T04:10:40.093Z] STF 04:10:39.253 - | Processes: [LT2] [2024-08-01T04:10:40.093Z] STF 04:10:39.253 - | [2024-08-01T04:10:40.093Z] STF 04:10:39.253 - Killing processes: LT2 [2024-08-01T04:10:40.093Z] STF 04:10:39.253 - o Process clean up attempt 1 for LT2 pid 465912 [2024-08-01T04:10:40.093Z] STF 04:10:39.253 - o Process LT2 pid 465912 stop() [2024-08-01T04:10:50.071Z] STF 04:10:49.295 - o Process LT2 pid 465912 terminate() [2024-08-01T04:10:50.824Z] STF 04:10:50.296 - o Process LT2 pid 465912 killed [2024-08-01T04:10:50.824Z] STF 04:10:50.296 - EXECUTE stage completed [2024-08-01T04:10:50.824Z] STF 04:10:50.393 - [2024-08-01T04:10:50.824Z] STF 04:10:50.394 - ==================== T E A R D O W N ==================== [2024-08-01T04:10:50.824Z] STF 04:10:50.394 - Running teardown: perl /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17224806191620/TestJlmRemoteThreadAuth_1/20240801-035329-TestJlmRemoteThreadAuth/tearDown.pl [2024-08-01T04:10:50.824Z] STF 04:10:50.539 - TEARDOWN stage completed [2024-08-01T04:10:50.824Z] STF 04:10:50.571 - [2024-08-01T04:10:50.824Z] STF 04:10:50.571 - ===================== R E S U L T S ===================== [2024-08-01T04:10:50.824Z] STF 04:10:50.571 - Stage results: [2024-08-01T04:10:50.824Z] STF 04:10:50.571 - setUp: pass [2024-08-01T04:10:50.824Z] STF 04:10:50.571 - execute: pass [2024-08-01T04:10:50.824Z] STF 04:10:50.571 - teardown: pass [2024-08-01T04:10:50.824Z] STF 04:10:50.571 - [2024-08-01T04:10:50.824Z] STF 04:10:50.571 - Overall result: PASSED [2024-08-01T04:10:50.824Z] ----------------------------------- [2024-08-01T04:10:50.824Z] TestJlmRemoteThreadAuth_1_PASSED [2024-08-01T04:10:50.824Z] ----------------------------------- [2024-08-01T04:10:50.824Z] [2024-08-01T04:10:50.824Z] TEST TEARDOWN: [2024-08-01T04:10:50.824Z] Nothing to be done for teardown. [2024-08-01T04:10:50.824Z] TestJlmRemoteThreadAuth_1 Finish Time: Thu Aug 1 04:10:50 2024 Epoch Time (ms): 1722485450606