[2024-11-30T02:14:58.920Z] Running test TestJlmRemoteThreadAuth_0 ... [2024-11-30T02:14:58.920Z] =============================================== [2024-11-30T02:14:58.920Z] TestJlmRemoteThreadAuth_0 Start Time: Sat Nov 30 02:14:56 2024 Epoch Time (ms): 1732932896833 [2024-11-30T02:14:58.920Z] variation: Mode150 [2024-11-30T02:14:58.920Z] JVM_OPTIONS: -XX:+UseCompressedOops [2024-11-30T02:14:58.920Z] { \ [2024-11-30T02:14:58.920Z] echo ""; echo "TEST SETUP:"; \ [2024-11-30T02:14:58.920Z] echo "Nothing to be done for setup."; \ [2024-11-30T02:14:58.920Z] mkdir -p "/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0"; \ [2024-11-30T02:14:58.920Z] cd "/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0"; \ [2024-11-30T02:14:58.920Z] echo ""; echo "TESTING:"; \ [2024-11-30T02:14:58.920Z] perl /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/../../testDependency/system_lib/" -java-args=' -XX:+UseCompressedOops ' -results-root="/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0" -test=TestJlmRemoteThreadAuth; \ [2024-11-30T02:14:58.920Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_0""_PASSED"; echo "-----------------------------------"; cd /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/..; rm -f -r "/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-30T02:14:58.920Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-30T02:14:58.920Z] echo "Nothing to be done for teardown."; \ [2024-11-30T02:14:58.920Z] } 2>&1 | tee -a "/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestTargetResult"; [2024-11-30T02:14:58.920Z] [2024-11-30T02:14:58.920Z] TEST SETUP: [2024-11-30T02:14:58.920Z] Nothing to be done for setup. [2024-11-30T02:14:58.920Z] [2024-11-30T02:14:58.920Z] TESTING: [2024-11-30T02:14:58.920Z] STF 02:14:56.947 - ========================= S T F ========================= [2024-11-30T02:14:58.920Z] systemtest-prereqs has been processed, and set to: /export/home/jenkins/testDependency/system_libRetrieving amount of free space on drive containing /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0 [2024-11-30T02:14:58.920Z] There is 16036 Mb free [2024-11-30T02:14:58.920Z] STF 02:14:56.960 - ================== G E N E R A T I O N ================== [2024-11-30T02:14:58.920Z] STF 02:14:56.962 - Checking JVM: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image [2024-11-30T02:14:58.920Z] STF 02:14:56.962 - Starting process to generate scripts: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/../../testDependency/system_lib//log4j/log4j-api.jar:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/../../testDependency/system_lib//log4j/log4j-core.jar:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/stf_parameters.properties, , /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth" [2024-11-30T02:14:59.556Z] GEN 02:14:58.875 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadAuth.class' Dir: '/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-11-30T02:14:59.556Z] GEN 02:14:58.897 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadAuth' [2024-11-30T02:14:59.556Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-11-30T02:14:59.556Z] GEN /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-11-30T02:14:59.556Z] GEN /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/STF/stf.core/bin [2024-11-30T02:14:59.556Z] GEN /export/home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-11-30T02:14:59.556Z] GEN /export/home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-11-30T02:14:59.556Z] GEN /export/home/jenkins/testDependency/system_lib/junit/junit.jar [2024-11-30T02:14:59.556Z] GEN 02:14:59.168 - Using Mode NoOptions. Values = '' [2024-11-30T02:14:59.556Z] GEN 02:14:59.287 - [2024-11-30T02:14:59.556Z] GEN 02:14:59.287 - Test command summary: [2024-11-30T02:14:59.556Z] GEN 02:14:59.287 - Step Stage Command Description [2024-11-30T02:14:59.556Z] GEN 02:14:59.288 - -----+--------+-----------------+------------ [2024-11-30T02:14:59.556Z] GEN 02:14:59.288 - 1 setUp cp Copying password file to temp [2024-11-30T02:14:59.557Z] GEN 02:14:59.288 - 2 setUp chmod Restricting file permission of jmxremote.passsword to 600 [2024-11-30T02:14:59.557Z] GEN 02:14:59.288 - 3 execute Run java Running ThreadProfiler Proxy test Server Process(with security) [2024-11-30T02:14:59.557Z] GEN 02:14:59.288 - 4 execute Run java Running Monitoring Client with proxy connection(with security) [2024-11-30T02:14:59.557Z] GEN 02:14:59.288 - 5 execute Monitor Wait for the processes to complete [2024-11-30T02:14:59.557Z] GEN 02:14:59.288 - 6 execute kill Stop LT1 process [2024-11-30T02:14:59.557Z] GEN 02:14:59.288 - 7 execute Run java Running ThreadProfiler Server test Server Process(with security) [2024-11-30T02:14:59.557Z] GEN 02:14:59.289 - 8 execute Run java Run the Monitoring Client with server-connection(with security) [2024-11-30T02:14:59.557Z] GEN 02:14:59.289 - 9 execute Monitor Wait for processes to complete [2024-11-30T02:14:59.557Z] GEN 02:14:59.289 - 10 execute kill Stop LT2 process [2024-11-30T02:14:59.557Z] STF 02:14:59.347 - [2024-11-30T02:14:59.557Z] STF 02:14:59.347 - Script generation completed [2024-11-30T02:14:59.557Z] STF 02:14:59.347 - [2024-11-30T02:14:59.557Z] STF 02:14:59.348 - [2024-11-30T02:14:59.557Z] STF 02:14:59.348 - ======================= S E T U P ======================= [2024-11-30T02:14:59.557Z] STF 02:14:59.348 - Running setup: perl /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/setUp.pl [2024-11-30T02:14:59.557Z] STF 02:14:59.430 - [2024-11-30T02:14:59.557Z] STF 02:14:59.430 - +------ Step 1 - Copying password file to temp [2024-11-30T02:14:59.557Z] STF 02:14:59.430 - | Copy a file to another directory [2024-11-30T02:14:59.557Z] STF 02:14:59.430 - | Source file: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/jmxremote.password [2024-11-30T02:14:59.557Z] STF 02:14:59.430 - | Dest dir: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/tmp [2024-11-30T02:14:59.557Z] STF 02:14:59.430 - | [2024-11-30T02:14:59.557Z] STF 02:14:59.431 - [2024-11-30T02:14:59.557Z] STF 02:14:59.431 - +------ Step 2 - Restricting file permission of jmxremote.passsword to 600 [2024-11-30T02:14:59.557Z] STF 02:14:59.431 - | Change file permission [2024-11-30T02:14:59.557Z] STF 02:14:59.431 - | targetFile: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/tmp/jmxremote.password [2024-11-30T02:14:59.557Z] STF 02:14:59.431 - | modeBits: 600 [2024-11-30T02:14:59.557Z] STF 02:14:59.431 - | [2024-11-30T02:14:59.557Z] STF 02:14:59.442 - SETUP stage completed [2024-11-30T02:14:59.557Z] STF 02:14:59.451 - [2024-11-30T02:14:59.557Z] STF 02:14:59.451 - ==================== E X E C U T E - ==================== [2024-11-30T02:14:59.557Z] STF 02:14:59.451 - Running execute: perl /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/execute.pl [2024-11-30T02:14:59.557Z] STF 02:14:59.537 - [2024-11-30T02:14:59.557Z] STF 02:14:59.537 - Java version [2024-11-30T02:14:59.557Z] STF 02:14:59.537 - Running: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image/bin/java -version [2024-11-30T02:15:00.196Z] STF 02:14:59.683 - [2024-11-30T02:15:00.196Z] STF 02:14:59.683 - +------ Step 3 - Running ThreadProfiler Proxy test Server Process(with security) [2024-11-30T02:15:00.196Z] STF 02:14:59.683 - | Start background process [2024-11-30T02:15:00.196Z] STF 02:14:59.683 - | Program: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image/bin/java [2024-11-30T02:15:00.196Z] STF 02:14:59.683 - | Mnemonic: LT1 [2024-11-30T02:15:00.196Z] STF 02:14:59.683 - | Echo: ECHO_OFF [2024-11-30T02:15:00.196Z] STF 02:14:59.684 - | Expectation: NEVER [2024-11-30T02:15:00.196Z] STF 02:14:59.684 - | [2024-11-30T02:15:00.196Z] STF 02:14:59.685 - Running command: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/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=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/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=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:+UseCompressedOops -classpath /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/STF/stf.load/bin:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/STF/stf.core/bin:/export/home/jenkins/testDependency/system_lib/log4j/log4j-api.jar:/export/home/jenkins/testDependency/system_lib/log4j/log4j-core.jar:/export/home/jenkins/testDependency/system_lib/junit/junit.jar:/export/home/jenkins/testDependency/system_lib/junit/hamcrest-core.jar:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-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 /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-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-11-30T02:15:00.196Z] STF 02:14:59.685 - Redirecting stderr to /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/3.LT1.stderr [2024-11-30T02:15:00.196Z] STF 02:14:59.685 - Redirecting stdout to /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/3.LT1.stdout [2024-11-30T02:15:00.196Z] STF 02:14:59.702 - [2024-11-30T02:15:00.196Z] STF 02:14:59.702 - +------ Step 4 - Running Monitoring Client with proxy connection(with security) [2024-11-30T02:15:00.196Z] STF 02:14:59.702 - | Start background process [2024-11-30T02:15:00.196Z] STF 02:14:59.702 - | Program: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image/bin/java [2024-11-30T02:15:00.196Z] STF 02:14:59.702 - | Mnemonic: CL1 [2024-11-30T02:15:00.196Z] STF 02:14:59.702 - | Echo: ECHO_ON [2024-11-30T02:15:00.197Z] STF 02:14:59.702 - | Expectation: CLEAN_RUN within 30m [2024-11-30T02:15:00.197Z] STF 02:14:59.702 - | [2024-11-30T02:15:00.197Z] STF 02:14:59.703 - Running command: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/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=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:+UseCompressedOops -classpath /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/export/home/jenkins/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/sthd_proxy.log /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/sthd_proxy.csv auth controlRole control1 localhost 1234 [2024-11-30T02:15:00.197Z] STF 02:14:59.703 - Redirecting stderr to /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/4.CL1.stderr [2024-11-30T02:15:00.197Z] STF 02:14:59.703 - Redirecting stdout to /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/4.CL1.stdout [2024-11-30T02:15:00.197Z] STF 02:14:59.706 - [2024-11-30T02:15:00.197Z] STF 02:14:59.706 - +------ Step 5 - Wait for the processes to complete [2024-11-30T02:15:00.197Z] STF 02:14:59.706 - | Wait for processes to meet expectations [2024-11-30T02:15:00.197Z] STF 02:14:59.706 - | Processes: [LT1, CL1] [2024-11-30T02:15:00.197Z] STF 02:14:59.706 - | [2024-11-30T02:15:00.197Z] STF 02:14:59.706 - Monitoring processes: CL1 LT1 [2024-11-30T02:15:00.197Z] openjdk version "1.8.0_442-beta" [2024-11-30T02:15:00.197Z] OpenJDK Runtime Environment (Temurin)(build 1.8.0_442-beta-202411291318-b04) [2024-11-30T02:15:00.197Z] OpenJDK 64-Bit Server VM (Temurin)(build 25.442-b04, mixed mode) [2024-11-30T02:15:00.871Z] CL1 j> 2024/11/30 02:15:00.327 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-30T02:15:00.871Z] CL1 j> 2024/11/30 02:15:00.375 Attempting to connect [2024-11-30T02:15:01.510Z] CL1 j> 2024/11/30 02:15:01.172 Monitored VM not ready at Nov 30, 2024 2:15:01 AM (attempt 1, elapsed 784ms). [2024-11-30T02:15:01.510Z] CL1 j> 2024/11/30 02:15:01.173 Waiting 5 secs and trying again... [2024-11-30T02:15:06.942Z] CL1 j> 2024/11/30 02:15:06.269 Attempting to connect [2024-11-30T02:15:11.326Z] CL1 j> 2024/11/30 02:15:10.902 Connection established! [2024-11-30T02:15:11.989Z] CL1 j> 2024/11/30 02:15:11.536 Starting to write data [2024-11-30T02:20:11.596Z] STF 02:19:59.564 - Heartbeat: Process LT1 is still running [2024-11-30T02:23:04.991Z] STF 02:22:52.488 - Monitoring Report Summary: [2024-11-30T02:23:04.991Z] STF 02:22:52.488 - o Process CL1 ended with the expected exit code (0) [2024-11-30T02:23:04.991Z] STF 02:22:52.488 - o Process LT1 is still running as expected [2024-11-30T02:23:04.991Z] STF 02:22:52.489 - [2024-11-30T02:23:04.991Z] STF 02:22:52.489 - +------ Step 6 - Stop LT1 process [2024-11-30T02:23:04.991Z] STF 02:22:52.489 - | Kill running processes [2024-11-30T02:23:04.991Z] STF 02:22:52.489 - | Processes: [LT1] [2024-11-30T02:23:04.991Z] STF 02:22:52.489 - | [2024-11-30T02:23:04.992Z] STF 02:22:52.489 - Killing processes: LT1 [2024-11-30T02:23:04.992Z] STF 02:22:52.489 - o Process clean up attempt 1 for LT1 pid 6295 [2024-11-30T02:23:04.992Z] STF 02:22:52.489 - o Process LT1 pid 6295 stop() [2024-11-30T02:23:04.992Z] STF 02:23:02.024 - o Process LT1 pid 6295 terminate() [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - o Process LT1 pid 6295 killed [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - +------ Step 7 - Running ThreadProfiler Server test Server Process(with security) [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - | Start background process [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - | Program: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image/bin/java [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - | Mnemonic: LT2 [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - | Echo: ECHO_OFF [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - | Expectation: NEVER [2024-11-30T02:23:04.992Z] STF 02:23:03.034 - | [2024-11-30T02:23:04.992Z] STF 02:23:03.035 - Running command: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/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=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/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=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:+UseCompressedOops -classpath /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/STF/stf.load/bin:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/STF/stf.core/bin:/export/home/jenkins/testDependency/system_lib/log4j/log4j-api.jar:/export/home/jenkins/testDependency/system_lib/log4j/log4j-core.jar:/export/home/jenkins/testDependency/system_lib/junit/junit.jar:/export/home/jenkins/testDependency/system_lib/junit/hamcrest-core.jar:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-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 /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-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-11-30T02:23:04.992Z] STF 02:23:03.035 - Redirecting stderr to /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/7.LT2.stderr [2024-11-30T02:23:04.992Z] STF 02:23:03.035 - Redirecting stdout to /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/7.LT2.stdout [2024-11-30T02:23:04.992Z] STF 02:23:03.090 - [2024-11-30T02:23:04.992Z] STF 02:23:03.090 - +------ Step 8 - Run the Monitoring Client with server-connection(with security) [2024-11-30T02:23:04.992Z] STF 02:23:03.090 - | Start background process [2024-11-30T02:23:04.992Z] STF 02:23:03.090 - | Program: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image/bin/java [2024-11-30T02:23:04.992Z] STF 02:23:03.090 - | Mnemonic: CL2 [2024-11-30T02:23:04.992Z] STF 02:23:03.090 - | Echo: ECHO_ON [2024-11-30T02:23:04.992Z] STF 02:23:03.090 - | Expectation: CLEAN_RUN within 30m [2024-11-30T02:23:04.992Z] STF 02:23:03.090 - | [2024-11-30T02:23:04.992Z] STF 02:23:03.091 - Running command: /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jdkbinary/j2sdk-image/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/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=/export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:+UseCompressedOops -classpath /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/export/home/jenkins/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/sthd_server.log /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/sthd_server.csv auth controlRole control1 localhost 1234 [2024-11-30T02:23:04.992Z] STF 02:23:03.091 - Redirecting stderr to /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/8.CL2.stderr [2024-11-30T02:23:04.992Z] STF 02:23:03.091 - Redirecting stdout to /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/results/8.CL2.stdout [2024-11-30T02:23:04.992Z] STF 02:23:03.131 - [2024-11-30T02:23:04.992Z] STF 02:23:03.131 - +------ Step 9 - Wait for processes to complete [2024-11-30T02:23:04.992Z] STF 02:23:03.131 - | Wait for processes to meet expectations [2024-11-30T02:23:04.992Z] STF 02:23:03.131 - | Processes: [LT2, CL2] [2024-11-30T02:23:04.992Z] STF 02:23:03.131 - | [2024-11-30T02:23:04.992Z] STF 02:23:03.131 - Monitoring processes: CL2 LT2 [2024-11-30T02:23:04.992Z] CL2 j> 2024/11/30 02:23:04.226 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-30T02:23:04.992Z] CL2 j> 2024/11/30 02:23:04.273 Attempting to connect [2024-11-30T02:23:04.992Z] CL2 j> 2024/11/30 02:23:04.530 Monitored VM not ready at Nov 30, 2024 2:23:04 AM (attempt 1, elapsed 243ms). [2024-11-30T02:23:04.993Z] CL2 j> 2024/11/30 02:23:04.530 Waiting 5 secs and trying again... [2024-11-30T02:23:10.541Z] CL2 j> 2024/11/30 02:23:09.564 Attempting to connect [2024-11-30T02:23:16.171Z] CL2 j> 2024/11/30 02:23:15.077 Connection established! [2024-11-30T02:23:29.769Z] CL2 j> 2024/11/30 02:23:28.638 Writing report data ... [2024-11-30T02:23:45.981Z] CL2 j> 2024/11/30 02:23:45.119 done. [2024-11-30T02:23:59.575Z] CL2 j> 2024/11/30 02:23:57.285 Writing report data ... [2024-11-30T02:24:00.969Z] CL2 j> 2024/11/30 02:24:00.015 done. [2024-11-30T02:24:14.907Z] CL2 j> 2024/11/30 02:24:12.235 Writing report data ... [2024-11-30T02:24:18.350Z] CL2 j> 2024/11/30 02:24:16.553 done. [2024-11-30T02:24:31.566Z] CL2 j> 2024/11/30 02:24:29.393 Writing report data ... [2024-11-30T02:24:32.454Z] CL2 j> 2024/11/30 02:24:31.581 done. [2024-11-30T02:24:46.390Z] CL2 j> 2024/11/30 02:24:45.100 Writing report data ... [2024-11-30T02:24:50.697Z] CL2 j> 2024/11/30 02:24:49.999 done. [2024-11-30T02:25:00.258Z] STF 02:24:59.395 - Heartbeat: Process CL2 is still running [2024-11-30T02:25:05.411Z] CL2 j> 2024/11/30 02:25:03.919 Writing report data ... [2024-11-30T02:25:12.310Z] CL2 j> 2024/11/30 02:25:11.053 done. [2024-11-30T02:25:25.598Z] CL2 j> 2024/11/30 02:25:24.127 Writing report data ... [2024-11-30T02:25:30.846Z] CL2 j> 2024/11/30 02:25:30.251 done. [2024-11-30T02:25:42.337Z] CL2 j> 2024/11/30 02:25:41.368 Writing report data ... [2024-11-30T02:25:44.572Z] CL2 j> 2024/11/30 02:25:43.479 done. [2024-11-30T02:25:55.546Z] CL2 j> 2024/11/30 02:25:54.839 Writing report data ... [2024-11-30T02:26:02.048Z] CL2 j> 2024/11/30 02:26:01.731 done. [2024-11-30T02:26:18.035Z] CL2 j> 2024/11/30 02:26:15.902 Writing report data ... [2024-11-30T02:26:20.302Z] CL2 j> 2024/11/30 02:26:19.952 done. [2024-11-30T02:26:31.507Z] CL2 j> 2024/11/30 02:26:31.054 Writing report data ... [2024-11-30T02:26:34.809Z] CL2 j> 2024/11/30 02:26:33.853 done. [2024-11-30T02:26:50.767Z] CL2 j> 2024/11/30 02:26:48.267 Writing report data ... [2024-11-30T02:26:50.767Z] CL2 j> 2024/11/30 02:26:48.763 done. [2024-11-30T02:27:01.633Z] CL2 j> 2024/11/30 02:27:00.281 Writing report data ... [2024-11-30T02:27:06.870Z] CL2 j> 2024/11/30 02:27:06.159 done. [2024-11-30T02:27:20.394Z] CL2 j> 2024/11/30 02:27:17.696 Writing report data ... [2024-11-30T02:27:21.085Z] CL2 j> 2024/11/30 02:27:20.210 done. [2024-11-30T02:27:34.839Z] CL2 j> 2024/11/30 02:27:33.424 Writing report data ... [2024-11-30T02:27:36.280Z] CL2 j> 2024/11/30 02:27:35.617 done. [2024-11-30T02:27:47.475Z] CL2 j> 2024/11/30 02:27:47.249 Writing report data ... [2024-11-30T02:27:50.798Z] CL2 j> 2024/11/30 02:27:49.729 done. [2024-11-30T02:28:09.897Z] CL2 j> 2024/11/30 02:28:07.167 Writing report data ... [2024-11-30T02:28:09.897Z] CL2 j> 2024/11/30 02:28:08.017 done. [2024-11-30T02:28:23.067Z] CL2 j> 2024/11/30 02:28:21.312 Writing report data ... [2024-11-30T02:28:25.283Z] CL2 j> 2024/11/30 02:28:24.345 done. [2024-11-30T02:28:38.542Z] CL2 j> 2024/11/30 02:28:37.061 Writing report data ... [2024-11-30T02:28:40.817Z] CL2 j> 2024/11/30 02:28:39.823 done. [2024-11-30T02:28:54.861Z] CL2 j> 2024/11/30 02:28:52.766 Writing report data ... [2024-11-30T02:28:54.861Z] CL2 j> 2024/11/30 02:28:54.603 done. [2024-11-30T02:29:10.551Z] CL2 j> 2024/11/30 02:29:09.001 Writing report data ... [2024-11-30T02:29:15.541Z] CL2 j> 2024/11/30 02:29:14.349 done. [2024-11-30T02:29:28.998Z] CL2 j> 2024/11/30 02:29:27.101 Writing report data ... [2024-11-30T02:29:31.305Z] CL2 j> 2024/11/30 02:29:29.783 done. [2024-11-30T02:29:44.618Z] CL2 j> 2024/11/30 02:29:42.510 Writing report data ... [2024-11-30T02:29:46.806Z] CL2 j> 2024/11/30 02:29:45.225 done. [2024-11-30T02:30:00.464Z] STF 02:29:59.005 - Heartbeat: Process CL2 is still running [2024-11-30T02:30:00.464Z] CL2 j> 2024/11/30 02:29:59.459 Writing report data ... [2024-11-30T02:30:03.550Z] CL2 j> 2024/11/30 02:30:02.763 done. [2024-11-30T02:30:16.708Z] CL2 j> 2024/11/30 02:30:14.162 Writing report data ... [2024-11-30T02:30:16.708Z] CL2 j> 2024/11/30 02:30:14.938 done. [2024-11-30T02:30:30.132Z] CL2 j> 2024/11/30 02:30:27.686 Writing report data ... [2024-11-30T02:30:31.661Z] CL2 j> 2024/11/30 02:30:30.611 done. [2024-11-30T02:30:45.031Z] CL2 j> 2024/11/30 02:30:43.565 Writing report data ... [2024-11-30T02:30:46.719Z] CL2 j> 2024/11/30 02:30:45.072 done. [2024-11-30T02:30:57.757Z] CL2 j> 2024/11/30 02:30:56.953 Writing report data ... [2024-11-30T02:30:59.180Z] CL2 j> 2024/11/30 02:30:57.997 done. [2024-11-30T02:31:10.671Z] CL2 j> 2024/11/30 02:31:08.848 Writing report data ... [2024-11-30T02:31:10.671Z] CL2 j> 2024/11/30 02:31:09.238 done. [2024-11-30T02:31:23.924Z] CL2 j> 2024/11/30 02:31:23.171 Writing report data ... [2024-11-30T02:31:27.104Z] CL2 j> 2024/11/30 02:31:25.675 done. [2024-11-30T02:31:27.929Z] STF 02:31:27.244 - Monitoring Report Summary: [2024-11-30T02:31:27.929Z] STF 02:31:27.244 - o Process CL2 ended with the expected exit code (0) [2024-11-30T02:31:27.929Z] STF 02:31:27.244 - o Process LT2 is still running as expected [2024-11-30T02:31:27.929Z] STF 02:31:27.245 - [2024-11-30T02:31:27.929Z] STF 02:31:27.245 - +------ Step 10 - Stop LT2 process [2024-11-30T02:31:27.929Z] STF 02:31:27.245 - | Kill running processes [2024-11-30T02:31:27.929Z] STF 02:31:27.245 - | Processes: [LT2] [2024-11-30T02:31:27.929Z] STF 02:31:27.245 - | [2024-11-30T02:31:27.929Z] STF 02:31:27.245 - Killing processes: LT2 [2024-11-30T02:31:27.929Z] STF 02:31:27.245 - o Process clean up attempt 1 for LT2 pid 6613 [2024-11-30T02:31:27.929Z] STF 02:31:27.245 - o Process LT2 pid 6613 stop() [2024-11-30T02:31:39.044Z] STF 02:31:37.877 - o Process LT2 pid 6613 terminate() [2024-11-30T02:31:39.044Z] STF 02:31:38.887 - o Process LT2 pid 6613 killed [2024-11-30T02:31:39.044Z] STF 02:31:38.887 - EXECUTE stage completed [2024-11-30T02:31:39.044Z] STF 02:31:38.895 - [2024-11-30T02:31:39.044Z] STF 02:31:38.895 - ==================== T E A R D O W N ==================== [2024-11-30T02:31:39.044Z] STF 02:31:38.895 - Running teardown: perl /export/home/jenkins/workspace/Test_openjdk8_hs_sanity.system_x86-64_solaris/aqa-tests/TKG/../TKG/output_17329308759910/TestJlmRemoteThreadAuth_0/20241130-021456-TestJlmRemoteThreadAuth/tearDown.pl [2024-11-30T02:31:39.044Z] STF 02:31:38.980 - TEARDOWN stage completed [2024-11-30T02:31:39.044Z] STF 02:31:39.001 - [2024-11-30T02:31:39.044Z] STF 02:31:39.001 - ===================== R E S U L T S ===================== [2024-11-30T02:31:39.044Z] STF 02:31:39.001 - Stage results: [2024-11-30T02:31:39.044Z] STF 02:31:39.001 - setUp: pass [2024-11-30T02:31:39.044Z] STF 02:31:39.001 - execute: pass [2024-11-30T02:31:39.044Z] STF 02:31:39.001 - teardown: pass [2024-11-30T02:31:39.044Z] STF 02:31:39.001 - [2024-11-30T02:31:39.044Z] STF 02:31:39.001 - Overall result: PASSED [2024-11-30T02:31:39.044Z] ----------------------------------- [2024-11-30T02:31:39.044Z] TestJlmRemoteThreadAuth_0_PASSED [2024-11-30T02:31:39.044Z] ----------------------------------- [2024-11-30T02:31:39.044Z] [2024-11-30T02:31:39.044Z] TEST TEARDOWN: [2024-11-30T02:31:39.044Z] Nothing to be done for teardown. [2024-11-30T02:31:39.680Z] TestJlmRemoteThreadAuth_0 Finish Time: Sat Nov 30 02:31:39 2024 Epoch Time (ms): 1732933899056