[2024-08-16T19:03:04.486Z] Running test TestJlmRemoteThreadAuth_2 ... [2024-08-16T19:03:04.486Z] =============================================== [2024-08-16T19:03:04.486Z] TestJlmRemoteThreadAuth_2 Start Time: Fri Aug 16 19:03:03 2024 Epoch Time (ms): 1723834983558 [2024-08-16T19:03:04.486Z] variation: Mode1000 [2024-08-16T19:03:04.486Z] JVM_OPTIONS: [2024-08-16T19:03:04.486Z] { \ [2024-08-16T19:03:04.486Z] echo ""; echo "TEST SETUP:"; \ [2024-08-16T19:03:04.486Z] echo "Nothing to be done for setup."; \ [2024-08-16T19:03:04.486Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2"; \ [2024-08-16T19:03:04.486Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2"; \ [2024-08-16T19:03:04.486Z] echo ""; echo "TESTING:"; \ [2024-08-16T19:03:04.486Z] perl /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/../../testDependency/system_lib/" -java-args=' ' -results-root="/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2" -test=TestJlmRemoteThreadAuth; \ [2024-08-16T19:03:04.486Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_2""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_2""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-16T19:03:04.486Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-16T19:03:04.486Z] echo "Nothing to be done for teardown."; \ [2024-08-16T19:03:04.486Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestTargetResult"; [2024-08-16T19:03:04.486Z] [2024-08-16T19:03:04.486Z] TEST SETUP: [2024-08-16T19:03:04.486Z] Nothing to be done for setup. [2024-08-16T19:03:04.486Z] [2024-08-16T19:03:04.486Z] TESTING: [2024-08-16T19:03:04.486Z] STF 19:03:03.681 - ========================= S T F ========================= [2024-08-16T19:03:04.486Z] systemtest-prereqs has been processed, and set to: /home/jenkins/testDependency/system_libRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2 [2024-08-16T19:03:04.486Z] There is 62169 Mb free [2024-08-16T19:03:04.486Z] STF 19:03:03.691 - ================== G E N E R A T I O N ================== [2024-08-16T19:03:04.486Z] STF 19:03:03.693 - Checking JVM: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image [2024-08-16T19:03:04.486Z] STF 19:03:03.693 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth" [2024-08-16T19:03:04.486Z] GEN stderr OpenJDK Server VM warning: Archived non-system classes are disabled because the java.system.class.loader property is specified (value = "net.adoptopenjdk.stf.runner.StfClassLoader"). To use archived non-system classes, this property must not be set [2024-08-16T19:03:05.430Z] GEN 19:03:05.295 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-08-16T19:03:05.430Z] GEN 19:03:05.320 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadAuth' [2024-08-16T19:03:06.381Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-08-16T19:03:06.381Z] GEN /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-08-16T19:03:06.381Z] GEN /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.core/bin [2024-08-16T19:03:06.381Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-08-16T19:03:06.381Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-08-16T19:03:06.381Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-08-16T19:03:06.381Z] GEN 19:03:05.554 - Using Mode NoOptions. Values = '' [2024-08-16T19:03:06.381Z] GEN 19:03:05.758 - [2024-08-16T19:03:06.381Z] GEN 19:03:05.759 - Test command summary: [2024-08-16T19:03:06.381Z] GEN 19:03:05.759 - Step Stage Command Description [2024-08-16T19:03:06.381Z] GEN 19:03:05.760 - -----+--------+-----------------+------------ [2024-08-16T19:03:06.381Z] GEN 19:03:05.760 - 1 setUp cp Copying password file to temp [2024-08-16T19:03:06.381Z] GEN 19:03:05.760 - 2 setUp chmod Restricting file permission of jmxremote.passsword to 600 [2024-08-16T19:03:06.381Z] GEN 19:03:05.760 - 3 execute Run java Running ThreadProfiler Proxy test Server Process(with security) [2024-08-16T19:03:06.381Z] GEN 19:03:05.760 - 4 execute Run java Running Monitoring Client with proxy connection(with security) [2024-08-16T19:03:06.381Z] GEN 19:03:05.761 - 5 execute Monitor Wait for the processes to complete [2024-08-16T19:03:06.381Z] GEN 19:03:05.761 - 6 execute kill Stop LT1 process [2024-08-16T19:03:06.381Z] GEN 19:03:05.761 - 7 execute Run java Running ThreadProfiler Server test Server Process(with security) [2024-08-16T19:03:06.381Z] GEN 19:03:05.761 - 8 execute Run java Run the Monitoring Client with server-connection(with security) [2024-08-16T19:03:06.381Z] GEN 19:03:05.761 - 9 execute Monitor Wait for processes to complete [2024-08-16T19:03:06.381Z] GEN 19:03:05.761 - 10 execute kill Stop LT2 process [2024-08-16T19:03:06.381Z] STF 19:03:05.794 - [2024-08-16T19:03:06.381Z] STF 19:03:05.794 - Script generation completed [2024-08-16T19:03:06.381Z] STF 19:03:05.794 - [2024-08-16T19:03:06.381Z] STF 19:03:05.795 - [2024-08-16T19:03:06.381Z] STF 19:03:05.795 - ======================= S E T U P ======================= [2024-08-16T19:03:06.381Z] STF 19:03:05.795 - Running setup: perl /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/setUp.pl [2024-08-16T19:03:06.381Z] STF 19:03:05.900 - [2024-08-16T19:03:06.381Z] STF 19:03:05.900 - +------ Step 1 - Copying password file to temp [2024-08-16T19:03:06.381Z] STF 19:03:05.900 - | Copy a file to another directory [2024-08-16T19:03:06.381Z] STF 19:03:05.900 - | Source file: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/jmxremote.password [2024-08-16T19:03:06.381Z] STF 19:03:05.900 - | Dest dir: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/tmp [2024-08-16T19:03:06.381Z] STF 19:03:05.900 - | [2024-08-16T19:03:06.381Z] STF 19:03:05.901 - [2024-08-16T19:03:06.381Z] STF 19:03:05.901 - +------ Step 2 - Restricting file permission of jmxremote.passsword to 600 [2024-08-16T19:03:06.381Z] STF 19:03:05.901 - | Change file permission [2024-08-16T19:03:06.381Z] STF 19:03:05.901 - | targetFile: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/tmp/jmxremote.password [2024-08-16T19:03:06.381Z] STF 19:03:05.901 - | modeBits: 600 [2024-08-16T19:03:06.381Z] STF 19:03:05.901 - | [2024-08-16T19:03:06.381Z] STF 19:03:05.905 - SETUP stage completed [2024-08-16T19:03:06.381Z] STF 19:03:05.911 - [2024-08-16T19:03:06.381Z] STF 19:03:05.911 - ==================== E X E C U T E - ==================== [2024-08-16T19:03:06.381Z] STF 19:03:05.911 - Running execute: perl /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/execute.pl [2024-08-16T19:03:06.381Z] STF 19:03:06.015 - [2024-08-16T19:03:06.381Z] STF 19:03:06.015 - Java version [2024-08-16T19:03:06.381Z] STF 19:03:06.015 - Running: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java -version [2024-08-16T19:03:06.381Z] openjdk version "17.0.13-beta" 2024-10-15 [2024-08-16T19:03:06.381Z] OpenJDK Runtime Environment Temurin-17.0.13+3-202408161459 (build 17.0.13-beta+3-ea) [2024-08-16T19:03:06.381Z] OpenJDK Server VM Temurin-17.0.13+3-202408161459 (build 17.0.13-beta+3-ea, mixed mode, sharing) [2024-08-16T19:03:06.381Z] STF 19:03:06.096 - [2024-08-16T19:03:06.381Z] STF 19:03:06.096 - +------ Step 3 - Running ThreadProfiler Proxy test Server Process(with security) [2024-08-16T19:03:06.381Z] STF 19:03:06.096 - | Start background process [2024-08-16T19:03:06.381Z] STF 19:03:06.096 - | Program: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-16T19:03:06.381Z] STF 19:03:06.096 - | Mnemonic: LT1 [2024-08-16T19:03:06.381Z] STF 19:03:06.096 - | Echo: ECHO_OFF [2024-08-16T19:03:06.381Z] STF 19:03:06.096 - | Expectation: NEVER [2024-08-16T19:03:06.381Z] STF 19:03:06.096 - | [2024-08-16T19:03:06.381Z] STF 19:03:06.097 - Running command: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/tmp/jmxremote.password -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-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_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-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-16T19:03:06.381Z] STF 19:03:06.097 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/3.LT1.stderr [2024-08-16T19:03:06.381Z] STF 19:03:06.097 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/3.LT1.stdout [2024-08-16T19:03:06.381Z] STF 19:03:06.113 - [2024-08-16T19:03:06.381Z] STF 19:03:06.113 - +------ Step 4 - Running Monitoring Client with proxy connection(with security) [2024-08-16T19:03:06.381Z] STF 19:03:06.113 - | Start background process [2024-08-16T19:03:06.381Z] STF 19:03:06.113 - | Program: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-16T19:03:06.381Z] STF 19:03:06.113 - | Mnemonic: CL1 [2024-08-16T19:03:06.381Z] STF 19:03:06.113 - | Echo: ECHO_ON [2024-08-16T19:03:06.381Z] STF 19:03:06.113 - | Expectation: CLEAN_RUN within 30m [2024-08-16T19:03:06.381Z] STF 19:03:06.113 - | [2024-08-16T19:03:06.382Z] STF 19:03:06.114 - Running command: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/tmp/jmxremote.password -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/sthd_proxy.log /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/sthd_proxy.csv auth controlRole control1 localhost 1234 [2024-08-16T19:03:06.382Z] STF 19:03:06.114 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/4.CL1.stderr [2024-08-16T19:03:06.382Z] STF 19:03:06.114 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/4.CL1.stdout [2024-08-16T19:03:06.382Z] STF 19:03:06.116 - [2024-08-16T19:03:06.382Z] STF 19:03:06.116 - +------ Step 5 - Wait for the processes to complete [2024-08-16T19:03:06.382Z] STF 19:03:06.116 - | Wait for processes to meet expectations [2024-08-16T19:03:06.382Z] STF 19:03:06.116 - | Processes: [LT1, CL1] [2024-08-16T19:03:06.382Z] STF 19:03:06.116 - | [2024-08-16T19:03:06.382Z] STF 19:03:06.116 - Monitoring processes: CL1 LT1 [2024-08-16T19:03:07.322Z] CL1 j> 2024/08/16 19:03:06.380 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-16T19:03:07.322Z] CL1 j> 2024/08/16 19:03:06.523 Attempting to connect [2024-08-16T19:03:07.322Z] CL1 j> 2024/08/16 19:03:06.746 Monitored VM not ready at Aug 16, 2024, 7:03:06 PM (attempt 1, elapsed 173ms). [2024-08-16T19:03:07.322Z] CL1 j> 2024/08/16 19:03:06.746 Waiting 5 secs and trying again... [2024-08-16T19:03:12.813Z] CL1 j> 2024/08/16 19:03:11.764 Attempting to connect [2024-08-16T19:03:17.048Z] CL1 j> 2024/08/16 19:03:16.584 Connection established! [2024-08-16T19:03:18.018Z] CL1 j> 2024/08/16 19:03:17.130 Starting to write data [2024-08-16T19:08:21.913Z] STF 19:08:06.115 - Heartbeat: Process CL1 is still running [2024-08-16T19:10:31.271Z] STF 19:10:20.423 - Monitoring Report Summary: [2024-08-16T19:10:31.271Z] STF 19:10:20.423 - o Process CL1 ended with the expected exit code (0) [2024-08-16T19:10:31.271Z] STF 19:10:20.423 - o Process LT1 is still running as expected [2024-08-16T19:10:31.271Z] STF 19:10:20.437 - [2024-08-16T19:10:31.271Z] STF 19:10:20.437 - +------ Step 6 - Stop LT1 process [2024-08-16T19:10:31.271Z] STF 19:10:20.437 - | Kill running processes [2024-08-16T19:10:31.271Z] STF 19:10:20.437 - | Processes: [LT1] [2024-08-16T19:10:31.271Z] STF 19:10:20.437 - | [2024-08-16T19:10:31.271Z] STF 19:10:20.437 - Killing processes: LT1 [2024-08-16T19:10:31.271Z] STF 19:10:20.437 - o Process clean up attempt 1 for LT1 pid 387970 [2024-08-16T19:10:31.271Z] STF 19:10:20.437 - o Process LT1 pid 387970 stop() [2024-08-16T19:10:31.271Z] STF 19:10:30.465 - o Process LT1 pid 387970 terminate() [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - o Process LT1 pid 387970 killed [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - +------ Step 7 - Running ThreadProfiler Server test Server Process(with security) [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - | Start background process [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - | Program: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - | Mnemonic: LT2 [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - | Echo: ECHO_OFF [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - | Expectation: NEVER [2024-08-16T19:10:32.219Z] STF 19:10:31.466 - | [2024-08-16T19:10:32.219Z] STF 19:10:31.467 - Running command: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/tmp/jmxremote.password -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-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_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-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-16T19:10:32.219Z] STF 19:10:31.467 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/7.LT2.stderr [2024-08-16T19:10:32.219Z] STF 19:10:31.467 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/7.LT2.stdout [2024-08-16T19:10:32.219Z] STF 19:10:31.469 - [2024-08-16T19:10:32.219Z] STF 19:10:31.469 - +------ Step 8 - Run the Monitoring Client with server-connection(with security) [2024-08-16T19:10:32.219Z] STF 19:10:31.469 - | Start background process [2024-08-16T19:10:32.219Z] STF 19:10:31.469 - | Program: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-16T19:10:32.219Z] STF 19:10:31.469 - | Mnemonic: CL2 [2024-08-16T19:10:32.219Z] STF 19:10:31.469 - | Echo: ECHO_ON [2024-08-16T19:10:32.219Z] STF 19:10:31.469 - | Expectation: CLEAN_RUN within 30m [2024-08-16T19:10:32.219Z] STF 19:10:31.469 - | [2024-08-16T19:10:32.219Z] STF 19:10:31.470 - Running command: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/tmp/jmxremote.password -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_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_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/sthd_server.log /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/sthd_server.csv auth controlRole control1 localhost 1234 [2024-08-16T19:10:32.219Z] STF 19:10:31.470 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/8.CL2.stderr [2024-08-16T19:10:32.219Z] STF 19:10:31.470 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/results/8.CL2.stdout [2024-08-16T19:10:32.219Z] STF 19:10:31.471 - [2024-08-16T19:10:32.219Z] STF 19:10:31.471 - +------ Step 9 - Wait for processes to complete [2024-08-16T19:10:32.219Z] STF 19:10:31.471 - | Wait for processes to meet expectations [2024-08-16T19:10:32.219Z] STF 19:10:31.471 - | Processes: [LT2, CL2] [2024-08-16T19:10:32.219Z] STF 19:10:31.471 - | [2024-08-16T19:10:32.219Z] STF 19:10:31.472 - Monitoring processes: CL2 LT2 [2024-08-16T19:10:33.169Z] CL2 j> 2024/08/16 19:10:31.738 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-16T19:10:33.169Z] CL2 j> 2024/08/16 19:10:31.884 Attempting to connect [2024-08-16T19:10:33.169Z] CL2 j> 2024/08/16 19:10:32.101 Monitored VM not ready at Aug 16, 2024, 7:10:32 PM (attempt 1, elapsed 173ms). [2024-08-16T19:10:33.170Z] CL2 j> 2024/08/16 19:10:32.102 Waiting 5 secs and trying again... [2024-08-16T19:10:38.684Z] CL2 j> 2024/08/16 19:10:37.123 Attempting to connect [2024-08-16T19:10:43.221Z] CL2 j> 2024/08/16 19:10:41.946 Connection established! [2024-08-16T19:10:57.060Z] CL2 j> 2024/08/16 19:10:54.842 Writing report data ... [2024-08-16T19:11:22.127Z] CL2 j> 2024/08/16 19:11:20.814 done. [2024-08-16T19:11:33.933Z] CL2 j> 2024/08/16 19:11:31.526 Writing report data ... [2024-08-16T19:11:52.445Z] CL2 j> 2024/08/16 19:11:50.791 done. [2024-08-16T19:12:02.306Z] CL2 j> 2024/08/16 19:12:01.147 Writing report data ... [2024-08-16T19:12:14.060Z] CL2 j> 2024/08/16 19:12:12.143 done. [2024-08-16T19:12:24.061Z] CL2 j> 2024/08/16 19:12:22.597 Writing report data ... [2024-08-16T19:12:32.319Z] CL2 j> 2024/08/16 19:12:31.094 done. [2024-08-16T19:12:42.282Z] CL2 j> 2024/08/16 19:12:41.418 Writing report data ... [2024-08-16T19:12:47.894Z] CL2 j> 2024/08/16 19:12:47.465 done. [2024-08-16T19:12:59.837Z] CL2 j> 2024/08/16 19:12:57.698 Writing report data ... [2024-08-16T19:13:06.636Z] CL2 j> 2024/08/16 19:13:05.262 done. [2024-08-16T19:13:07.618Z] STF 19:13:06.471 - Heartbeat: Process LT2 is still running [2024-08-16T19:13:17.565Z] CL2 j> 2024/08/16 19:13:15.588 Writing report data ... [2024-08-16T19:13:23.021Z] CL2 j> 2024/08/16 19:13:21.416 done. [2024-08-16T19:13:32.941Z] CL2 j> 2024/08/16 19:13:31.833 Writing report data ... [2024-08-16T19:13:41.249Z] CL2 j> 2024/08/16 19:13:39.757 done. [2024-08-16T19:13:51.171Z] CL2 j> 2024/08/16 19:13:50.027 Writing report data ... [2024-08-16T19:13:56.742Z] CL2 j> 2024/08/16 19:13:56.151 done. [2024-08-16T19:14:08.484Z] CL2 j> 2024/08/16 19:14:06.391 Writing report data ... [2024-08-16T19:14:14.035Z] CL2 j> 2024/08/16 19:14:12.087 done. [2024-08-16T19:14:23.241Z] CL2 j> 2024/08/16 19:14:22.255 Writing report data ... [2024-08-16T19:14:28.733Z] CL2 j> 2024/08/16 19:14:27.805 done. [2024-08-16T19:14:38.726Z] CL2 j> 2024/08/16 19:14:38.049 Writing report data ... [2024-08-16T19:14:45.554Z] CL2 j> 2024/08/16 19:14:44.599 done. [2024-08-16T19:14:55.488Z] CL2 j> 2024/08/16 19:14:54.894 Writing report data ... [2024-08-16T19:15:02.342Z] CL2 j> 2024/08/16 19:15:00.645 done. [2024-08-16T19:15:12.399Z] CL2 j> 2024/08/16 19:15:10.862 Writing report data ... [2024-08-16T19:15:18.004Z] CL2 j> 2024/08/16 19:15:16.770 done. [2024-08-16T19:15:27.967Z] CL2 j> 2024/08/16 19:15:27.001 Writing report data ... [2024-08-16T19:15:34.767Z] CL2 j> 2024/08/16 19:15:34.016 done. [2024-08-16T19:15:44.722Z] CL2 j> 2024/08/16 19:15:44.173 Writing report data ... [2024-08-16T19:15:53.083Z] CL2 j> 2024/08/16 19:15:51.145 done. [2024-08-16T19:16:03.034Z] CL2 j> 2024/08/16 19:16:01.425 Writing report data ... [2024-08-16T19:16:08.556Z] CL2 j> 2024/08/16 19:16:07.117 done. [2024-08-16T19:16:18.518Z] CL2 j> 2024/08/16 19:16:17.308 Writing report data ... [2024-08-16T19:16:22.753Z] CL2 j> 2024/08/16 19:16:21.226 done. [2024-08-16T19:16:32.689Z] CL2 j> 2024/08/16 19:16:31.424 Writing report data ... [2024-08-16T19:16:36.961Z] CL2 j> 2024/08/16 19:16:36.207 done. [2024-08-16T19:16:47.815Z] CL2 j> 2024/08/16 19:16:46.410 Writing report data ... [2024-08-16T19:16:53.294Z] CL2 j> 2024/08/16 19:16:51.562 done. [2024-08-16T19:17:03.299Z] CL2 j> 2024/08/16 19:17:01.741 Writing report data ... [2024-08-16T19:17:08.798Z] CL2 j> 2024/08/16 19:17:06.900 done. [2024-08-16T19:17:18.726Z] CL2 j> 2024/08/16 19:17:17.057 Writing report data ... [2024-08-16T19:17:24.193Z] CL2 j> 2024/08/16 19:17:22.214 done. [2024-08-16T19:17:34.240Z] CL2 j> 2024/08/16 19:17:32.395 Writing report data ... [2024-08-16T19:17:39.763Z] CL2 j> 2024/08/16 19:17:38.006 done. [2024-08-16T19:17:49.823Z] CL2 j> 2024/08/16 19:17:48.173 Writing report data ... [2024-08-16T19:17:54.230Z] CL2 j> 2024/08/16 19:17:52.844 done. [2024-08-16T19:18:04.140Z] CL2 j> 2024/08/16 19:18:02.985 Writing report data ... [2024-08-16T19:18:07.290Z] STF 19:18:06.500 - Heartbeat: Process LT2 is still running [2024-08-16T19:18:08.247Z] CL2 j> 2024/08/16 19:18:07.479 done. [2024-08-16T19:18:19.923Z] CL2 j> 2024/08/16 19:18:17.661 Writing report data ... [2024-08-16T19:18:23.005Z] CL2 j> 2024/08/16 19:18:21.536 done. [2024-08-16T19:18:32.974Z] CL2 j> 2024/08/16 19:18:31.693 Writing report data ... [2024-08-16T19:18:38.521Z] CL2 j> 2024/08/16 19:18:36.903 done. [2024-08-16T19:18:48.428Z] CL2 j> 2024/08/16 19:18:47.073 Writing report data ... [2024-08-16T19:18:52.691Z] CL2 j> 2024/08/16 19:18:52.362 done. [2024-08-16T19:19:04.507Z] CL2 j> 2024/08/16 19:19:02.528 Writing report data ... [2024-08-16T19:19:08.834Z] CL2 j> 2024/08/16 19:19:07.027 done. [2024-08-16T19:19:18.797Z] CL2 j> 2024/08/16 19:19:17.151 Writing report data ... [2024-08-16T19:19:23.101Z] CL2 j> 2024/08/16 19:19:21.757 done. [2024-08-16T19:19:24.072Z] STF 19:19:23.223 - Monitoring Report Summary: [2024-08-16T19:19:24.072Z] STF 19:19:23.223 - o Process CL2 ended with the expected exit code (0) [2024-08-16T19:19:24.072Z] STF 19:19:23.223 - o Process LT2 is still running as expected [2024-08-16T19:19:24.072Z] STF 19:19:23.224 - [2024-08-16T19:19:24.072Z] STF 19:19:23.224 - +------ Step 10 - Stop LT2 process [2024-08-16T19:19:24.072Z] STF 19:19:23.224 - | Kill running processes [2024-08-16T19:19:24.072Z] STF 19:19:23.224 - | Processes: [LT2] [2024-08-16T19:19:24.072Z] STF 19:19:23.224 - | [2024-08-16T19:19:24.072Z] STF 19:19:23.224 - Killing processes: LT2 [2024-08-16T19:19:24.072Z] STF 19:19:23.224 - o Process clean up attempt 1 for LT2 pid 388408 [2024-08-16T19:19:24.072Z] STF 19:19:23.224 - o Process LT2 pid 388408 stop() [2024-08-16T19:19:33.986Z] STF 19:19:33.260 - o Process LT2 pid 388408 terminate() [2024-08-16T19:19:35.076Z] STF 19:19:34.260 - o Process LT2 pid 388408 killed [2024-08-16T19:19:35.076Z] STF 19:19:34.261 - EXECUTE stage completed [2024-08-16T19:19:35.076Z] STF 19:19:34.611 - [2024-08-16T19:19:35.076Z] STF 19:19:34.611 - ==================== T E A R D O W N ==================== [2024-08-16T19:19:35.076Z] STF 19:19:34.611 - Running teardown: perl /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17238334409440/TestJlmRemoteThreadAuth_2/20240816-190303-TestJlmRemoteThreadAuth/tearDown.pl [2024-08-16T19:19:35.076Z] STF 19:19:34.717 - TEARDOWN stage completed [2024-08-16T19:19:35.076Z] STF 19:19:34.721 - [2024-08-16T19:19:35.076Z] STF 19:19:34.721 - ===================== R E S U L T S ===================== [2024-08-16T19:19:35.076Z] STF 19:19:34.721 - Stage results: [2024-08-16T19:19:35.076Z] STF 19:19:34.721 - setUp: pass [2024-08-16T19:19:35.076Z] STF 19:19:34.721 - execute: pass [2024-08-16T19:19:35.076Z] STF 19:19:34.721 - teardown: pass [2024-08-16T19:19:35.076Z] STF 19:19:34.721 - [2024-08-16T19:19:35.076Z] STF 19:19:34.721 - Overall result: PASSED [2024-08-16T19:19:35.076Z] ----------------------------------- [2024-08-16T19:19:35.076Z] TestJlmRemoteThreadAuth_2_PASSED [2024-08-16T19:19:35.076Z] ----------------------------------- [2024-08-16T19:19:35.076Z] [2024-08-16T19:19:35.076Z] TEST TEARDOWN: [2024-08-16T19:19:35.076Z] Nothing to be done for teardown. [2024-08-16T19:19:35.076Z] TestJlmRemoteThreadAuth_2 Finish Time: Fri Aug 16 19:19:34 2024 Epoch Time (ms): 1723835974764