[2024-09-04T23:51:31.111Z] Running test TestJlmRemoteThreadAuth_1 ... [2024-09-04T23:51:31.111Z] =============================================== [2024-09-04T23:51:31.111Z] TestJlmRemoteThreadAuth_1 Start Time: Wed Sep 4 16:51:30 2024 Epoch Time (ms): 1725493890821 [2024-09-04T23:51:31.111Z] variation: Mode650 [2024-09-04T23:51:31.111Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-09-04T23:51:31.111Z] { \ [2024-09-04T23:51:31.111Z] echo ""; echo "TEST SETUP:"; \ [2024-09-04T23:51:31.111Z] echo "Nothing to be done for setup."; \ [2024-09-04T23:51:31.111Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1"; \ [2024-09-04T23:51:31.111Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1"; \ [2024-09-04T23:51:31.111Z] echo ""; echo "TESTING:"; \ [2024-09-04T23:51:31.111Z] perl /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/../../testDependency/system_lib/" -java-args=' -XX:-UseCompressedOops ' -results-root="/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1" -test=TestJlmRemoteThreadAuth; \ [2024-09-04T23:51:31.111Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_1""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-04T23:51:31.111Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-04T23:51:31.112Z] echo "Nothing to be done for teardown."; \ [2024-09-04T23:51:31.112Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestTargetResult"; [2024-09-04T23:51:31.112Z] [2024-09-04T23:51:31.112Z] TEST SETUP: [2024-09-04T23:51:31.112Z] Nothing to be done for setup. [2024-09-04T23:51:31.112Z] [2024-09-04T23:51:31.112Z] TESTING: [2024-09-04T23:51:31.548Z] STF 16:51:31.134 - ========================= S T F ========================= [2024-09-04T23:51:31.549Z] systemtest-prereqs has been processed, and set to: /Users/admin/workspace/testDependency/system_libRetrieving amount of free space on drive containing /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1 [2024-09-04T23:51:31.549Z] There is 11000 Mb free [2024-09-04T23:51:31.549Z] STF 16:51:31.153 - ================== G E N E R A T I O N ================== [2024-09-04T23:51:31.549Z] STF 16:51:31.154 - Checking JVM: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../ [2024-09-04T23:51:31.549Z] STF 16:51:31.154 - Starting process to generate scripts: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/..//bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/../../testDependency/system_lib//log4j/log4j-api.jar:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/../../testDependency/system_lib//log4j/log4j-core.jar:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/stf_parameters.properties, , /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth" [2024-09-04T23:51:34.552Z] GEN 16:51:33.941 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadAuth.class' Dir: '/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-09-04T23:51:34.552Z] GEN 16:51:34.063 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadAuth' [2024-09-04T23:51:34.972Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-09-04T23:51:34.972Z] GEN /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-09-04T23:51:34.972Z] GEN /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.core/bin [2024-09-04T23:51:34.972Z] GEN /Users/admin/workspace/testDependency/system_lib/log4j/log4j-api.jar [2024-09-04T23:51:34.972Z] GEN /Users/admin/workspace/testDependency/system_lib/log4j/log4j-core.jar [2024-09-04T23:51:34.972Z] GEN /Users/admin/workspace/testDependency/system_lib/junit/junit.jar [2024-09-04T23:51:34.972Z] GEN 16:51:34.763 - Using Mode NoOptions. Values = '' [2024-09-04T23:51:35.486Z] GEN 16:51:35.253 - [2024-09-04T23:51:35.486Z] GEN 16:51:35.254 - Test command summary: [2024-09-04T23:51:35.486Z] GEN 16:51:35.254 - Step Stage Command Description [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - -----+--------+-----------------+------------ [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 1 setUp cp Copying password file to temp [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 2 setUp chmod Restricting file permission of jmxremote.passsword to 600 [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 3 execute Run java Running ThreadProfiler Proxy test Server Process(with security) [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 4 execute Run java Running Monitoring Client with proxy connection(with security) [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 5 execute Monitor Wait for the processes to complete [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 6 execute kill Stop LT1 process [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 7 execute Run java Running ThreadProfiler Server test Server Process(with security) [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 8 execute Run java Run the Monitoring Client with server-connection(with security) [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 9 execute Monitor Wait for processes to complete [2024-09-04T23:51:35.486Z] GEN 16:51:35.289 - 10 execute kill Stop LT2 process [2024-09-04T23:51:36.164Z] STF 16:51:35.670 - [2024-09-04T23:51:36.164Z] STF 16:51:35.670 - Script generation completed [2024-09-04T23:51:36.164Z] STF 16:51:35.670 - [2024-09-04T23:51:36.164Z] STF 16:51:35.671 - [2024-09-04T23:51:36.164Z] STF 16:51:35.671 - ======================= S E T U P ======================= [2024-09-04T23:51:36.164Z] STF 16:51:35.671 - Running setup: perl /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/setUp.pl [2024-09-04T23:51:36.735Z] STF 16:51:36.582 - [2024-09-04T23:51:36.735Z] STF 16:51:36.582 - +------ Step 1 - Copying password file to temp [2024-09-04T23:51:36.735Z] STF 16:51:36.582 - | Copy a file to another directory [2024-09-04T23:51:36.735Z] STF 16:51:36.582 - | Source file: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/jmxremote.password [2024-09-04T23:51:36.735Z] STF 16:51:36.582 - | Dest dir: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/tmp [2024-09-04T23:51:36.735Z] STF 16:51:36.582 - | [2024-09-04T23:51:36.735Z] STF 16:51:36.583 - [2024-09-04T23:51:36.735Z] STF 16:51:36.583 - +------ Step 2 - Restricting file permission of jmxremote.passsword to 600 [2024-09-04T23:51:37.321Z] STF 16:51:36.583 - | Change file permission [2024-09-04T23:51:37.321Z] STF 16:51:36.583 - | targetFile: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/tmp/jmxremote.password [2024-09-04T23:51:37.321Z] STF 16:51:36.583 - | modeBits: 600 [2024-09-04T23:51:37.321Z] STF 16:51:36.583 - | [2024-09-04T23:51:37.321Z] STF 16:51:36.655 - SETUP stage completed [2024-09-04T23:51:37.321Z] STF 16:51:36.721 - [2024-09-04T23:51:37.321Z] STF 16:51:36.722 - ==================== E X E C U T E - ==================== [2024-09-04T23:51:37.321Z] STF 16:51:36.722 - Running execute: perl /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/execute.pl [2024-09-04T23:51:37.739Z] STF 16:51:37.242 - [2024-09-04T23:51:37.739Z] STF 16:51:37.263 - Java version [2024-09-04T23:51:37.740Z] STF 16:51:37.263 - Running: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java -version [2024-09-04T23:51:37.740Z] openjdk version "17.0.13-beta" 2024-10-15 [2024-09-04T23:51:37.740Z] OpenJDK Runtime Environment Temurin-17.0.13+6-202409041934 (build 17.0.13-beta+6-ea) [2024-09-04T23:51:37.740Z] OpenJDK 64-Bit Server VM Temurin-17.0.13+6-202409041934 (build 17.0.13-beta+6-ea, mixed mode) [2024-09-04T23:51:37.740Z] STF 16:51:37.456 - [2024-09-04T23:51:37.740Z] STF 16:51:37.456 - +------ Step 3 - Running ThreadProfiler Proxy test Server Process(with security) [2024-09-04T23:51:37.740Z] STF 16:51:37.456 - | Start background process [2024-09-04T23:51:37.740Z] STF 16:51:37.456 - | Program: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-09-04T23:51:37.740Z] STF 16:51:37.456 - | Mnemonic: LT1 [2024-09-04T23:51:37.740Z] STF 16:51:37.456 - | Echo: ECHO_OFF [2024-09-04T23:51:37.740Z] STF 16:51:37.456 - | Expectation: NEVER [2024-09-04T23:51:37.740Z] STF 16:51:37.456 - | [2024-09-04T23:51:37.740Z] STF 16:51:37.457 - Running command: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.ssl.need.client.auth=true -Djavax.net.ssl.keyStore=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_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=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.load/bin:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.core/bin:/Users/admin/workspace/testDependency/system_lib/log4j/log4j-api.jar:/Users/admin/workspace/testDependency/system_lib/log4j/log4j-core.jar:/Users/admin/workspace/testDependency/system_lib/junit/junit.jar:/Users/admin/workspace/testDependency/system_lib/junit/hamcrest-core.jar:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-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 /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-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-09-04T23:51:37.740Z] STF 16:51:37.457 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/3.LT1.stderr [2024-09-04T23:51:37.740Z] STF 16:51:37.457 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/3.LT1.stdout [2024-09-04T23:51:37.740Z] STF 16:51:37.485 - [2024-09-04T23:51:37.740Z] STF 16:51:37.485 - +------ Step 4 - Running Monitoring Client with proxy connection(with security) [2024-09-04T23:51:37.740Z] STF 16:51:37.485 - | Start background process [2024-09-04T23:51:37.740Z] STF 16:51:37.485 - | Program: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-09-04T23:51:37.740Z] STF 16:51:37.485 - | Mnemonic: CL1 [2024-09-04T23:51:37.740Z] STF 16:51:37.485 - | Echo: ECHO_ON [2024-09-04T23:51:37.740Z] STF 16:51:37.485 - | Expectation: CLEAN_RUN within 30m [2024-09-04T23:51:37.740Z] STF 16:51:37.485 - | [2024-09-04T23:51:37.740Z] STF 16:51:37.486 - Running command: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_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=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/Users/admin/workspace/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/sthd_proxy.log /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/sthd_proxy.csv auth controlRole control1 localhost 1234 [2024-09-04T23:51:37.740Z] STF 16:51:37.486 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/4.CL1.stderr [2024-09-04T23:51:37.740Z] STF 16:51:37.486 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/4.CL1.stdout [2024-09-04T23:51:37.740Z] STF 16:51:37.509 - [2024-09-04T23:51:37.740Z] STF 16:51:37.509 - +------ Step 5 - Wait for the processes to complete [2024-09-04T23:51:37.740Z] STF 16:51:37.509 - | Wait for processes to meet expectations [2024-09-04T23:51:37.740Z] STF 16:51:37.509 - | Processes: [LT1, CL1] [2024-09-04T23:51:37.740Z] STF 16:51:37.509 - | [2024-09-04T23:51:37.740Z] STF 16:51:37.509 - Monitoring processes: CL1 LT1 [2024-09-04T23:51:38.876Z] CL1 j> 2024/09/04 16:51:38.354 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-09-04T23:51:38.876Z] CL1 j> 2024/09/04 16:51:38.530 Attempting to connect [2024-09-04T23:51:39.348Z] CL1 j> 2024/09/04 16:51:39.006 Monitored VM not ready at Sep 4, 2024, 4:51:39 PM (attempt 1, elapsed 453ms). [2024-09-04T23:51:39.348Z] CL1 j> 2024/09/04 16:51:39.144 Waiting 5 secs and trying again... [2024-09-04T23:51:46.242Z] CL1 j> 2024/09/04 16:51:44.153 Attempting to connect [2024-09-04T23:51:51.952Z] CL1 j> 2024/09/04 16:51:50.962 Connection established! [2024-09-04T23:51:51.952Z] CL1 j> 2024/09/04 16:51:51.789 Starting to write data [2024-09-04T23:56:40.499Z] STF 16:56:37.095 - Heartbeat: Process CL1 is still running [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - Monitoring Report Summary: [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - o Process CL1 ended with the expected exit code (0) [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - o Process LT1 is still running as expected [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - +------ Step 6 - Stop LT1 process [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - | Kill running processes [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - | Processes: [LT1] [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - | [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - Killing processes: LT1 [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - o Process clean up attempt 1 for LT1 pid 14362 [2024-09-05T00:01:13.831Z] STF 17:01:08.156 - o Process LT1 pid 14362 stop() [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - o Process LT1 pid 14362 killed [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - +------ Step 7 - Running ThreadProfiler Server test Server Process(with security) [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - | Start background process [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - | Program: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - | Mnemonic: LT2 [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - | Echo: ECHO_OFF [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - | Expectation: NEVER [2024-09-05T00:01:13.831Z] STF 17:01:10.167 - | [2024-09-05T00:01:13.831Z] STF 17:01:10.168 - Running command: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.ssl.need.client.auth=true -Djavax.net.ssl.keyStore=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_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=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.load/bin:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.core/bin:/Users/admin/workspace/testDependency/system_lib/log4j/log4j-api.jar:/Users/admin/workspace/testDependency/system_lib/log4j/log4j-core.jar:/Users/admin/workspace/testDependency/system_lib/junit/junit.jar:/Users/admin/workspace/testDependency/system_lib/junit/hamcrest-core.jar:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-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 /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/7.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-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-09-05T00:01:13.831Z] STF 17:01:10.168 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/7.LT2.stderr [2024-09-05T00:01:13.831Z] STF 17:01:10.168 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/7.LT2.stdout [2024-09-05T00:01:13.832Z] STF 17:01:10.174 - [2024-09-05T00:01:13.832Z] STF 17:01:10.174 - +------ Step 8 - Run the Monitoring Client with server-connection(with security) [2024-09-05T00:01:13.832Z] STF 17:01:10.174 - | Start background process [2024-09-05T00:01:13.832Z] STF 17:01:10.174 - | Program: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-09-05T00:01:13.832Z] STF 17:01:10.174 - | Mnemonic: CL2 [2024-09-05T00:01:13.832Z] STF 17:01:10.174 - | Echo: ECHO_ON [2024-09-05T00:01:13.832Z] STF 17:01:10.174 - | Expectation: CLEAN_RUN within 30m [2024-09-05T00:01:13.832Z] STF 17:01:10.174 - | [2024-09-05T00:01:13.832Z] STF 17:01:10.175 - Running command: /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_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=/Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/Users/admin/workspace/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/sthd_server.log /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/sthd_server.csv auth controlRole control1 localhost 1234 [2024-09-05T00:01:13.832Z] STF 17:01:10.175 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/8.CL2.stderr [2024-09-05T00:01:13.832Z] STF 17:01:10.175 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/results/8.CL2.stdout [2024-09-05T00:01:13.832Z] STF 17:01:10.188 - [2024-09-05T00:01:13.832Z] STF 17:01:10.188 - +------ Step 9 - Wait for processes to complete [2024-09-05T00:01:13.832Z] STF 17:01:10.188 - | Wait for processes to meet expectations [2024-09-05T00:01:13.832Z] STF 17:01:10.188 - | Processes: [LT2, CL2] [2024-09-05T00:01:13.832Z] STF 17:01:10.188 - | [2024-09-05T00:01:13.832Z] STF 17:01:10.188 - Monitoring processes: CL2 LT2 [2024-09-05T00:01:13.832Z] CL2 j> 2024/09/04 17:01:11.562 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-09-05T00:01:13.832Z] CL2 j> 2024/09/04 17:01:11.820 Attempting to connect [2024-09-05T00:01:13.832Z] CL2 j> 2024/09/04 17:01:12.428 Monitored VM not ready at Sep 4, 2024, 5:01:12 PM (attempt 1, elapsed 534ms). [2024-09-05T00:01:13.832Z] CL2 j> 2024/09/04 17:01:12.428 Waiting 5 secs and trying again... [2024-09-05T00:01:18.754Z] CL2 j> 2024/09/04 17:01:17.448 Attempting to connect [2024-09-05T00:01:31.143Z] CL2 j> 2024/09/04 17:01:29.612 Connection established! [2024-09-05T00:01:38.608Z] STF 17:01:37.099 - Heartbeat: Process CL2 is still running [2024-09-05T00:01:52.671Z] CL2 j> 2024/09/04 17:01:50.692 Writing report data ... [2024-09-05T00:02:30.661Z] CL2 j> 2024/09/04 17:02:26.974 done. [2024-09-05T00:02:40.320Z] CL2 j> 2024/09/04 17:02:39.848 Writing report data ... [2024-09-05T00:03:07.595Z] CL2 j> 2024/09/04 17:03:03.311 done. [2024-09-05T00:03:16.892Z] CL2 j> 2024/09/04 17:03:14.717 Writing report data ... [2024-09-05T00:03:33.688Z] CL2 j> 2024/09/04 17:03:32.525 done. [2024-09-05T00:03:45.756Z] CL2 j> 2024/09/04 17:03:43.772 Writing report data ... [2024-09-05T00:04:02.518Z] CL2 j> 2024/09/04 17:04:02.158 done. [2024-09-05T00:04:14.484Z] CL2 j> 2024/09/04 17:04:13.995 Writing report data ... [2024-09-05T00:04:33.772Z] CL2 j> 2024/09/04 17:04:31.772 done. [2024-09-05T00:04:43.869Z] CL2 j> 2024/09/04 17:04:42.693 Writing report data ... [2024-09-05T00:05:02.634Z] CL2 j> 2024/09/04 17:05:00.356 done. [2024-09-05T00:05:12.661Z] CL2 j> 2024/09/04 17:05:11.382 Writing report data ... [2024-09-05T00:05:28.600Z] CL2 j> 2024/09/04 17:05:26.171 done. [2024-09-05T00:05:38.420Z] CL2 j> 2024/09/04 17:05:37.076 Writing report data ... [2024-09-05T00:05:55.010Z] CL2 j> 2024/09/04 17:05:52.907 done. [2024-09-05T00:06:05.282Z] CL2 j> 2024/09/04 17:06:04.443 Writing report data ... [2024-09-05T00:06:24.422Z] CL2 j> 2024/09/04 17:06:21.958 done. [2024-09-05T00:06:36.210Z] CL2 j> 2024/09/04 17:06:33.933 Writing report data ... [2024-09-05T00:06:38.098Z] STF 17:06:37.029 - Heartbeat: Process CL2 is still running [2024-09-05T00:06:51.619Z] CL2 j> 2024/09/04 17:06:50.678 done. [2024-09-05T00:07:03.690Z] CL2 j> 2024/09/04 17:07:02.090 Writing report data ... [2024-09-05T00:07:17.038Z] CL2 j> 2024/09/04 17:07:14.888 done. [2024-09-05T00:07:27.246Z] CL2 j> 2024/09/04 17:07:26.825 Writing report data ... [2024-09-05T00:07:46.295Z] CL2 j> 2024/09/04 17:07:44.304 done. [2024-09-05T00:07:57.915Z] CL2 j> 2024/09/04 17:07:55.558 Writing report data ... [2024-09-05T00:08:17.514Z] CL2 j> 2024/09/04 17:08:15.694 done. [2024-09-05T00:08:29.542Z] CL2 j> 2024/09/04 17:08:27.353 Writing report data ... [2024-09-05T00:08:46.086Z] CL2 j> 2024/09/04 17:08:43.093 done. [2024-09-05T00:08:56.006Z] CL2 j> 2024/09/04 17:08:54.306 Writing report data ... [2024-09-05T00:09:15.189Z] CL2 j> 2024/09/04 17:09:12.166 done. [2024-09-05T00:09:25.180Z] CL2 j> 2024/09/04 17:09:23.032 Writing report data ... [2024-09-05T00:09:38.835Z] CL2 j> 2024/09/04 17:09:37.625 done. [2024-09-05T00:09:50.209Z] CL2 j> 2024/09/04 17:09:48.195 Writing report data ... [2024-09-05T00:10:04.091Z] CL2 j> 2024/09/04 17:10:02.640 done. [2024-09-05T00:10:14.390Z] CL2 j> 2024/09/04 17:10:13.395 Writing report data ... [2024-09-05T00:10:33.731Z] CL2 j> 2024/09/04 17:10:30.480 done. [2024-09-05T00:10:43.381Z] CL2 j> 2024/09/04 17:10:41.749 Writing report data ... [2024-09-05T00:10:54.510Z] CL2 j> 2024/09/04 17:10:53.246 done. [2024-09-05T00:11:08.522Z] CL2 j> 2024/09/04 17:11:06.607 Writing report data ... [2024-09-05T00:11:17.995Z] CL2 j> 2024/09/04 17:11:16.694 done. [2024-09-05T00:11:29.271Z] CL2 j> 2024/09/04 17:11:27.283 Writing report data ... [2024-09-05T00:11:37.343Z] STF 17:11:37.207 - Heartbeat: Process CL2 is still running [2024-09-05T00:11:38.962Z] CL2 j> 2024/09/04 17:11:38.517 done. [2024-09-05T00:11:50.903Z] CL2 j> 2024/09/04 17:11:49.999 Writing report data ... [2024-09-05T00:12:07.436Z] CL2 j> 2024/09/04 17:12:04.711 done. [2024-09-05T00:12:16.195Z] CL2 j> 2024/09/04 17:12:15.865 Writing report data ... [2024-09-05T00:12:32.327Z] CL2 j> 2024/09/04 17:12:31.460 done. [2024-09-05T00:12:44.494Z] CL2 j> 2024/09/04 17:12:42.239 Writing report data ... [2024-09-05T00:12:55.771Z] CL2 j> 2024/09/04 17:12:55.025 done. [2024-09-05T00:13:07.707Z] CL2 j> 2024/09/04 17:13:06.716 Writing report data ... [2024-09-05T00:13:19.457Z] CL2 j> 2024/09/04 17:13:17.901 done. [2024-09-05T00:13:29.708Z] CL2 j> 2024/09/04 17:13:28.976 Writing report data ... [2024-09-05T00:13:40.873Z] CL2 j> 2024/09/04 17:13:39.394 done. [2024-09-05T00:13:52.842Z] CL2 j> 2024/09/04 17:13:50.960 Writing report data ... [2024-09-05T00:14:06.577Z] CL2 j> 2024/09/04 17:14:04.850 done. [2024-09-05T00:14:16.529Z] CL2 j> 2024/09/04 17:14:15.536 Writing report data ... [2024-09-05T00:14:27.924Z] CL2 j> 2024/09/04 17:14:26.802 done. [2024-09-05T00:14:38.654Z] CL2 j> 2024/09/04 17:14:37.331 Writing report data ... [2024-09-05T00:14:50.149Z] CL2 j> 2024/09/04 17:14:49.348 done. [2024-09-05T00:15:00.429Z] CL2 j> 2024/09/04 17:14:59.911 Writing report data ... [2024-09-05T00:15:12.027Z] CL2 j> 2024/09/04 17:15:11.000 done. [2024-09-05T00:15:13.288Z] STF 17:15:12.365 - Monitoring Report Summary: [2024-09-05T00:15:13.288Z] STF 17:15:12.365 - o Process CL2 ended with the expected exit code (0) [2024-09-05T00:15:13.288Z] STF 17:15:12.365 - o Process LT2 is still running as expected [2024-09-05T00:15:13.288Z] STF 17:15:12.366 - [2024-09-05T00:15:13.288Z] STF 17:15:12.366 - +------ Step 10 - Stop LT2 process [2024-09-05T00:15:13.288Z] STF 17:15:12.366 - | Kill running processes [2024-09-05T00:15:13.288Z] STF 17:15:12.366 - | Processes: [LT2] [2024-09-05T00:15:13.288Z] STF 17:15:12.366 - | [2024-09-05T00:15:13.288Z] STF 17:15:12.366 - Killing processes: LT2 [2024-09-05T00:15:13.288Z] STF 17:15:12.366 - o Process clean up attempt 1 for LT2 pid 14732 [2024-09-05T00:15:13.288Z] STF 17:15:12.366 - o Process LT2 pid 14732 stop() [2024-09-05T00:15:14.854Z] STF 17:15:14.370 - o Process LT2 pid 14732 killed [2024-09-05T00:15:14.854Z] STF 17:15:14.370 - EXECUTE stage completed [2024-09-05T00:15:14.854Z] STF 17:15:14.422 - [2024-09-05T00:15:14.854Z] STF 17:15:14.422 - ==================== T E A R D O W N ==================== [2024-09-05T00:15:14.854Z] STF 17:15:14.422 - Running teardown: perl /Users/admin/workspace/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17254900604814/TestJlmRemoteThreadAuth_1/20240904-165131-TestJlmRemoteThreadAuth/tearDown.pl [2024-09-05T00:15:15.335Z] STF 17:15:14.963 - TEARDOWN stage completed [2024-09-05T00:15:15.335Z] STF 17:15:14.988 - [2024-09-05T00:15:15.335Z] STF 17:15:14.988 - ===================== R E S U L T S ===================== [2024-09-05T00:15:15.335Z] STF 17:15:14.988 - Stage results: [2024-09-05T00:15:15.335Z] STF 17:15:14.988 - setUp: pass [2024-09-05T00:15:15.335Z] STF 17:15:14.988 - execute: pass [2024-09-05T00:15:15.335Z] STF 17:15:14.988 - teardown: pass [2024-09-05T00:15:15.335Z] STF 17:15:14.988 - [2024-09-05T00:15:15.335Z] STF 17:15:14.988 - Overall result: PASSED [2024-09-05T00:15:15.335Z] ----------------------------------- [2024-09-05T00:15:15.335Z] TestJlmRemoteThreadAuth_1_PASSED [2024-09-05T00:15:15.335Z] ----------------------------------- [2024-09-05T00:15:15.335Z] [2024-09-05T00:15:15.335Z] TEST TEARDOWN: [2024-09-05T00:15:15.335Z] Nothing to be done for teardown. [2024-09-05T00:15:15.743Z] TestJlmRemoteThreadAuth_1 Finish Time: Wed Sep 4 17:15:15 2024 Epoch Time (ms): 1725495315271