[2023-04-19T01:22:33.446Z] Running test TestJlmRemoteThreadNoAuth_0 ... [2023-04-19T01:22:33.446Z] =============================================== [2023-04-19T01:22:33.446Z] TestJlmRemoteThreadNoAuth_0 Start Time: Wed Apr 19 02:22:33 2023 Epoch Time (ms): 1681867353124 [2023-04-19T01:22:33.446Z] variation: Mode150 [2023-04-19T01:22:33.446Z] JVM_OPTIONS: -XX:+UseCompressedOops [2023-04-19T01:22:33.446Z] { \ [2023-04-19T01:22:33.446Z] echo ""; echo "TEST SETUP:"; \ [2023-04-19T01:22:33.446Z] echo "Nothing to be done for setup."; \ [2023-04-19T01:22:33.446Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0"; \ [2023-04-19T01:22:33.446Z] cd "/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0"; \ [2023-04-19T01:22:33.446Z] echo ""; echo "TESTING:"; \ [2023-04-19T01:22:33.446Z] perl /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs" -java-args=' -XX:+UseCompressedOops ' -results-root="/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0" -test=TestJlmRemoteThreadNoAuth; \ [2023-04-19T01:22:33.446Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-19T01:22:33.446Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-19T01:22:33.446Z] echo "Nothing to be done for teardown."; \ [2023-04-19T01:22:33.446Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestTargetResult"; [2023-04-19T01:22:33.446Z] [2023-04-19T01:22:33.446Z] TEST SETUP: [2023-04-19T01:22:33.446Z] Nothing to be done for setup. [2023-04-19T01:22:33.446Z] [2023-04-19T01:22:33.446Z] TESTING: [2023-04-19T01:22:33.446Z] STF 02:22:33.219 - ========================= S T F ========================= [2023-04-19T01:22:33.446Z] systemtest-prereqs has been processed, and set to: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqsRetrieving amount of free space on drive containing /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0 [2023-04-19T01:22:33.446Z] There is 48691 Mb free [2023-04-19T01:22:33.446Z] STF 02:22:33.227 - ================== G E N E R A T I O N ================== [2023-04-19T01:22:33.446Z] STF 02:22:33.229 - Checking JVM: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/../ [2023-04-19T01:22:33.447Z] STF 02:22:33.229 - Starting process to generate scripts: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/..//bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs/log4j/log4j-api.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../../jvmtest/system/jlm/..//systemtest_prereqs/log4j/log4j-core.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth" [2023-04-19T01:22:33.447Z] GEN stderr OpenJDK 64-Bit 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 [2023-04-19T01:22:34.200Z] GEN 02:22:33.949 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2023-04-19T01:22:34.200Z] GEN 02:22:33.957 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2023-04-19T01:22:34.200Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2023-04-19T01:22:34.200Z] GEN /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2023-04-19T01:22:34.200Z] GEN /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/STF/stf.core/bin [2023-04-19T01:22:34.200Z] GEN /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar [2023-04-19T01:22:34.200Z] GEN /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar [2023-04-19T01:22:34.200Z] GEN /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/junit/junit.jar [2023-04-19T01:22:34.200Z] GEN 02:22:34.088 - Using Mode NoOptions. Values = '' [2023-04-19T01:22:34.200Z] GEN 02:22:34.180 - [2023-04-19T01:22:34.200Z] GEN 02:22:34.180 - Test command summary: [2023-04-19T01:22:34.200Z] GEN 02:22:34.181 - Step Stage Command Description [2023-04-19T01:22:34.200Z] GEN 02:22:34.181 - -----+--------+-----------------+------------ [2023-04-19T01:22:34.200Z] GEN 02:22:34.181 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2023-04-19T01:22:34.200Z] GEN 02:22:34.182 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2023-04-19T01:22:34.200Z] GEN 02:22:34.182 - 3 execute Monitor Wait for processes to complete [2023-04-19T01:22:34.200Z] GEN 02:22:34.182 - 4 execute kill Stop LT1 process [2023-04-19T01:22:34.200Z] GEN 02:22:34.182 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2023-04-19T01:22:34.200Z] GEN 02:22:34.182 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2023-04-19T01:22:34.200Z] GEN 02:22:34.182 - 7 execute Monitor Wait for processes to complete [2023-04-19T01:22:34.200Z] GEN 02:22:34.182 - 8 execute kill Stop LT2 process [2023-04-19T01:22:34.565Z] STF 02:22:34.214 - [2023-04-19T01:22:34.565Z] STF 02:22:34.214 - Script generation completed [2023-04-19T01:22:34.565Z] STF 02:22:34.214 - [2023-04-19T01:22:34.565Z] STF 02:22:34.214 - [2023-04-19T01:22:34.565Z] STF 02:22:34.214 - ======================= S E T U P ======================= [2023-04-19T01:22:34.565Z] STF 02:22:34.214 - Running setup: perl /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/setUp.pl [2023-04-19T01:22:34.565Z] STF 02:22:34.286 - SETUP stage completed [2023-04-19T01:22:34.565Z] STF 02:22:34.296 - [2023-04-19T01:22:34.565Z] STF 02:22:34.296 - ==================== E X E C U T E - ==================== [2023-04-19T01:22:34.565Z] STF 02:22:34.296 - Running execute: perl /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/execute.pl [2023-04-19T01:22:34.565Z] STF 02:22:34.374 - [2023-04-19T01:22:34.565Z] STF 02:22:34.374 - Java version [2023-04-19T01:22:34.565Z] STF 02:22:34.374 - Running: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java -version [2023-04-19T01:22:34.565Z] openjdk version "17.0.7" 2023-04-18 [2023-04-19T01:22:34.565Z] OpenJDK Runtime Environment Temurin-17.0.7+7 (build 17.0.7+7) [2023-04-19T01:22:34.565Z] OpenJDK 64-Bit Server VM Temurin-17.0.7+7 (build 17.0.7+7, mixed mode, sharing) [2023-04-19T01:22:34.565Z] STF 02:22:34.431 - [2023-04-19T01:22:34.565Z] STF 02:22:34.431 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - | Start background process [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - | Program: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - | Mnemonic: LT1 [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - | Echo: ECHO_OFF [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - | Expectation: NEVER [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - | [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - Running command: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:+UseCompressedOops -classpath /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/STF/stf.load/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/STF/stf.core/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/junit/junit.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/junit/hamcrest-core.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results -resultsPrefix 1.LT1. -timeLimit 30m -abortAtFailureLimit -1 -reportFailureLimit 1 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.mini-mix.totalNumberTests 20000000 -suite.mini-mix.inventoryFile /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.mini-mix.threadCount 30 -suite.mini-mix.selection random -suite.mini-mix.seed -1 -suite.mini-mix.repeatCount 1 -suite.mini-mix.thinkingTime 0ms..0ms [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - Redirecting stderr to /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2023-04-19T01:22:34.565Z] STF 02:22:34.432 - Redirecting stdout to /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2023-04-19T01:22:34.565Z] STF 02:22:34.443 - [2023-04-19T01:22:34.565Z] STF 02:22:34.443 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2023-04-19T01:22:34.565Z] STF 02:22:34.443 - | Start background process [2023-04-19T01:22:34.565Z] STF 02:22:34.443 - | Program: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java [2023-04-19T01:22:34.565Z] STF 02:22:34.443 - | Mnemonic: CL1 [2023-04-19T01:22:34.565Z] STF 02:22:34.443 - | Echo: ECHO_ON [2023-04-19T01:22:34.565Z] STF 02:22:34.443 - | Expectation: CLEAN_RUN within 30m [2023-04-19T01:22:34.565Z] STF 02:22:34.443 - | [2023-04-19T01:22:34.565Z] STF 02:22:34.444 - Running command: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -XX:+UseCompressedOops -classpath /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2023-04-19T01:22:34.565Z] STF 02:22:34.444 - Redirecting stderr to /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2023-04-19T01:22:34.565Z] STF 02:22:34.444 - Redirecting stdout to /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2023-04-19T01:22:34.565Z] STF 02:22:34.446 - [2023-04-19T01:22:34.565Z] STF 02:22:34.446 - +------ Step 3 - Wait for processes to complete [2023-04-19T01:22:34.565Z] STF 02:22:34.446 - | Wait for processes to meet expectations [2023-04-19T01:22:34.565Z] STF 02:22:34.446 - | Processes: [LT1, CL1] [2023-04-19T01:22:34.565Z] STF 02:22:34.446 - | [2023-04-19T01:22:34.565Z] STF 02:22:34.447 - Monitoring processes: CL1 LT1 [2023-04-19T01:22:34.917Z] CL1 j> 2023/04/19 02:22:34.583 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2023-04-19T01:22:34.917Z] CL1 j> 2023/04/19 02:22:34.651 Attempting to connect [2023-04-19T01:22:34.917Z] CL1 j> 2023/04/19 02:22:34.770 Monitored VM not ready at 19 Apr 2023, 02:22:34 (attempt 1, elapsed 100ms). [2023-04-19T01:22:34.917Z] CL1 j> 2023/04/19 02:22:34.770 Waiting 5 secs and trying again... [2023-04-19T01:22:39.886Z] CL1 j> 2023/04/19 02:22:39.775 Attempting to connect [2023-04-19T01:22:40.239Z] CL1 j> 2023/04/19 02:22:39.971 Connection established! [2023-04-19T01:22:40.593Z] CL1 j> 2023/04/19 02:22:40.300 Starting to write data [2023-04-19T01:27:36.415Z] STF 02:27:34.404 - Heartbeat: Process CL1 is still running [2023-04-19T01:27:59.870Z] STF 02:27:59.631 - Monitoring Report Summary: [2023-04-19T01:27:59.870Z] STF 02:27:59.631 - o Process CL1 ended with the expected exit code (0) [2023-04-19T01:27:59.870Z] STF 02:27:59.631 - o Process LT1 is still running as expected [2023-04-19T01:27:59.870Z] STF 02:27:59.632 - [2023-04-19T01:27:59.870Z] STF 02:27:59.632 - +------ Step 4 - Stop LT1 process [2023-04-19T01:27:59.870Z] STF 02:27:59.632 - | Kill running processes [2023-04-19T01:27:59.870Z] STF 02:27:59.632 - | Processes: [LT1] [2023-04-19T01:27:59.870Z] STF 02:27:59.632 - | [2023-04-19T01:27:59.870Z] STF 02:27:59.632 - Killing processes: LT1 [2023-04-19T01:27:59.870Z] STF 02:27:59.632 - o Process clean up attempt 1 for LT1 pid 79802 [2023-04-19T01:27:59.870Z] STF 02:27:59.632 - o Process LT1 pid 79802 stop() [2023-04-19T01:28:01.110Z] STF 02:28:00.641 - o Process LT1 pid 79802 killed [2023-04-19T01:28:01.110Z] STF 02:28:00.642 - [2023-04-19T01:28:01.110Z] STF 02:28:00.642 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2023-04-19T01:28:01.110Z] STF 02:28:00.642 - | Start background process [2023-04-19T01:28:01.110Z] STF 02:28:00.642 - | Program: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java [2023-04-19T01:28:01.110Z] STF 02:28:00.642 - | Mnemonic: LT2 [2023-04-19T01:28:01.110Z] STF 02:28:00.642 - | Echo: ECHO_OFF [2023-04-19T01:28:01.110Z] STF 02:28:00.642 - | Expectation: NEVER [2023-04-19T01:28:01.110Z] STF 02:28:00.642 - | [2023-04-19T01:28:01.111Z] STF 02:28:00.643 - Running command: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:+UseCompressedOops -classpath /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/STF/stf.load/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/STF/stf.core/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/junit/junit.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/junit/hamcrest-core.jar:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results -resultsPrefix 5.LT2. -timeLimit 30m -abortAtFailureLimit -1 -reportFailureLimit 1 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.mini-mix.totalNumberTests 20000000 -suite.mini-mix.inventoryFile /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.mini-mix.threadCount 30 -suite.mini-mix.selection random -suite.mini-mix.seed -1 -suite.mini-mix.repeatCount 1 -suite.mini-mix.thinkingTime 0ms..0ms [2023-04-19T01:28:01.111Z] STF 02:28:00.643 - Redirecting stderr to /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2023-04-19T01:28:01.111Z] STF 02:28:00.643 - Redirecting stdout to /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2023-04-19T01:28:01.111Z] STF 02:28:00.646 - [2023-04-19T01:28:01.111Z] STF 02:28:00.646 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2023-04-19T01:28:01.111Z] STF 02:28:00.646 - | Start background process [2023-04-19T01:28:01.111Z] STF 02:28:00.646 - | Program: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java [2023-04-19T01:28:01.111Z] STF 02:28:00.646 - | Mnemonic: CL2 [2023-04-19T01:28:01.111Z] STF 02:28:00.646 - | Echo: ECHO_ON [2023-04-19T01:28:01.111Z] STF 02:28:00.646 - | Expectation: CLEAN_RUN within 30m [2023-04-19T01:28:01.111Z] STF 02:28:00.646 - | [2023-04-19T01:28:01.111Z] STF 02:28:00.647 - Running command: /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -XX:+UseCompressedOops -classpath /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/jvmtest/system/systemtest_prereqs/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2023-04-19T01:28:01.111Z] STF 02:28:00.647 - Redirecting stderr to /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2023-04-19T01:28:01.111Z] STF 02:28:00.647 - Redirecting stdout to /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2023-04-19T01:28:01.111Z] STF 02:28:00.649 - [2023-04-19T01:28:01.111Z] STF 02:28:00.649 - +------ Step 7 - Wait for processes to complete [2023-04-19T01:28:01.111Z] STF 02:28:00.649 - | Wait for processes to meet expectations [2023-04-19T01:28:01.111Z] STF 02:28:00.649 - | Processes: [LT2, CL2] [2023-04-19T01:28:01.111Z] STF 02:28:00.649 - | [2023-04-19T01:28:01.111Z] STF 02:28:00.649 - Monitoring processes: CL2 LT2 [2023-04-19T01:28:01.461Z] CL2 j> 2023/04/19 02:28:00.782 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2023-04-19T01:28:01.461Z] CL2 j> 2023/04/19 02:28:00.848 Attempting to connect [2023-04-19T01:28:01.461Z] CL2 j> 2023/04/19 02:28:00.965 Monitored VM not ready at 19 Apr 2023, 02:28:00 (attempt 1, elapsed 99ms). [2023-04-19T01:28:01.461Z] CL2 j> 2023/04/19 02:28:00.965 Waiting 5 secs and trying again... [2023-04-19T01:28:06.394Z] CL2 j> 2023/04/19 02:28:05.969 Attempting to connect [2023-04-19T01:28:07.150Z] CL2 j> 2023/04/19 02:28:06.262 Connection established! [2023-04-19T01:28:18.247Z] CL2 j> 2023/04/19 02:28:16.960 Writing report data ... [2023-04-19T01:28:24.362Z] CL2 j> 2023/04/19 02:28:23.112 done. [2023-04-19T01:28:33.513Z] CL2 j> 2023/04/19 02:28:33.181 Writing report data ... [2023-04-19T01:28:39.634Z] CL2 j> 2023/04/19 02:28:38.153 done. [2023-04-19T01:28:50.714Z] CL2 j> 2023/04/19 02:28:48.198 Writing report data ... [2023-04-19T01:28:53.803Z] CL2 j> 2023/04/19 02:28:52.818 done. [2023-04-19T01:29:04.887Z] CL2 j> 2023/04/19 02:29:02.862 Writing report data ... [2023-04-19T01:29:08.769Z] CL2 j> 2023/04/19 02:29:07.762 done. [2023-04-19T01:29:19.866Z] CL2 j> 2023/04/19 02:29:17.823 Writing report data ... [2023-04-19T01:29:22.961Z] CL2 j> 2023/04/19 02:29:22.013 done. [2023-04-19T01:29:34.066Z] CL2 j> 2023/04/19 02:29:32.060 Writing report data ... [2023-04-19T01:29:37.221Z] CL2 j> 2023/04/19 02:29:36.167 done. [2023-04-19T01:29:48.089Z] CL2 j> 2023/04/19 02:29:46.222 Writing report data ... [2023-04-19T01:29:51.195Z] CL2 j> 2023/04/19 02:29:50.283 done. [2023-04-19T01:30:02.323Z] CL2 j> 2023/04/19 02:30:00.346 Writing report data ... [2023-04-19T01:30:05.426Z] CL2 j> 2023/04/19 02:30:04.016 done. [2023-04-19T01:30:16.510Z] CL2 j> 2023/04/19 02:30:14.083 Writing report data ... [2023-04-19T01:30:19.606Z] CL2 j> 2023/04/19 02:30:18.247 done. [2023-04-19T01:30:30.732Z] CL2 j> 2023/04/19 02:30:28.286 Writing report data ... [2023-04-19T01:30:33.819Z] CL2 j> 2023/04/19 02:30:32.390 done. [2023-04-19T01:30:43.289Z] CL2 j> 2023/04/19 02:30:42.437 Writing report data ... [2023-04-19T01:30:48.214Z] CL2 j> 2023/04/19 02:30:46.549 done. [2023-04-19T01:30:57.360Z] CL2 j> 2023/04/19 02:30:56.595 Writing report data ... [2023-04-19T01:31:01.302Z] CL2 j> 2023/04/19 02:31:00.530 done. [2023-04-19T01:31:12.402Z] CL2 j> 2023/04/19 02:31:10.575 Writing report data ... [2023-04-19T01:31:15.324Z] CL2 j> 2023/04/19 02:31:14.647 done. [2023-04-19T01:31:26.459Z] CL2 j> 2023/04/19 02:31:24.704 Writing report data ... [2023-04-19T01:31:29.563Z] CL2 j> 2023/04/19 02:31:28.815 done. [2023-04-19T01:31:40.978Z] CL2 j> 2023/04/19 02:31:38.857 Writing report data ... [2023-04-19T01:31:43.351Z] CL2 j> 2023/04/19 02:31:42.417 done. [2023-04-19T01:31:54.450Z] CL2 j> 2023/04/19 02:31:52.458 Writing report data ... [2023-04-19T01:31:56.843Z] CL2 j> 2023/04/19 02:31:55.810 done. [2023-04-19T01:32:07.931Z] CL2 j> 2023/04/19 02:32:05.854 Writing report data ... [2023-04-19T01:32:10.374Z] CL2 j> 2023/04/19 02:32:09.525 done. [2023-04-19T01:32:21.796Z] CL2 j> 2023/04/19 02:32:19.551 Writing report data ... [2023-04-19T01:32:23.559Z] CL2 j> 2023/04/19 02:32:22.587 done. [2023-04-19T01:32:34.646Z] CL2 j> 2023/04/19 02:32:32.624 Writing report data ... [2023-04-19T01:32:34.646Z] STF 02:32:34.098 - Heartbeat: Process CL2 is still running [2023-04-19T01:32:37.018Z] CL2 j> 2023/04/19 02:32:36.140 done. [2023-04-19T01:32:48.110Z] CL2 j> 2023/04/19 02:32:46.170 Writing report data ... [2023-04-19T01:32:49.856Z] CL2 j> 2023/04/19 02:32:49.722 done. [2023-04-19T01:33:00.985Z] CL2 j> 2023/04/19 02:32:59.763 Writing report data ... [2023-04-19T01:33:04.080Z] CL2 j> 2023/04/19 02:33:03.678 done. [2023-04-19T01:33:15.174Z] CL2 j> 2023/04/19 02:33:13.716 Writing report data ... [2023-04-19T01:33:19.108Z] CL2 j> 2023/04/19 02:33:17.579 done. [2023-04-19T01:33:28.249Z] CL2 j> 2023/04/19 02:33:27.620 Writing report data ... [2023-04-19T01:33:32.173Z] CL2 j> 2023/04/19 02:33:30.920 done. [2023-04-19T01:33:43.254Z] CL2 j> 2023/04/19 02:33:40.959 Writing report data ... [2023-04-19T01:33:45.621Z] CL2 j> 2023/04/19 02:33:44.702 done. [2023-04-19T01:33:56.766Z] CL2 j> 2023/04/19 02:33:54.732 Writing report data ... [2023-04-19T01:33:59.130Z] CL2 j> 2023/04/19 02:33:58.216 done. [2023-04-19T01:34:09.070Z] CL2 j> 2023/04/19 02:34:08.264 Writing report data ... [2023-04-19T01:34:13.019Z] CL2 j> 2023/04/19 02:34:11.856 done. [2023-04-19T01:34:24.150Z] CL2 j> 2023/04/19 02:34:21.886 Writing report data ... [2023-04-19T01:34:26.520Z] CL2 j> 2023/04/19 02:34:25.345 done. [2023-04-19T01:34:37.612Z] CL2 j> 2023/04/19 02:34:35.380 Writing report data ... [2023-04-19T01:34:40.087Z] CL2 j> 2023/04/19 02:34:39.030 done. [2023-04-19T01:34:51.183Z] CL2 j> 2023/04/19 02:34:49.063 Writing report data ... [2023-04-19T01:34:53.556Z] CL2 j> 2023/04/19 02:34:52.631 done. [2023-04-19T01:35:04.651Z] CL2 j> 2023/04/19 02:35:02.692 Writing report data ... [2023-04-19T01:35:06.668Z] CL2 j> 2023/04/19 02:35:05.794 done. [2023-04-19T01:35:07.882Z] STF 02:35:07.156 - Monitoring Report Summary: [2023-04-19T01:35:07.882Z] STF 02:35:07.156 - o Process CL2 ended with the expected exit code (0) [2023-04-19T01:35:07.882Z] STF 02:35:07.156 - o Process LT2 is still running as expected [2023-04-19T01:35:07.882Z] STF 02:35:07.157 - [2023-04-19T01:35:07.882Z] STF 02:35:07.157 - +------ Step 8 - Stop LT2 process [2023-04-19T01:35:07.882Z] STF 02:35:07.157 - | Kill running processes [2023-04-19T01:35:07.882Z] STF 02:35:07.157 - | Processes: [LT2] [2023-04-19T01:35:07.882Z] STF 02:35:07.157 - | [2023-04-19T01:35:07.882Z] STF 02:35:07.157 - Killing processes: LT2 [2023-04-19T01:35:07.882Z] STF 02:35:07.157 - o Process clean up attempt 1 for LT2 pid 80115 [2023-04-19T01:35:07.882Z] STF 02:35:07.157 - o Process LT2 pid 80115 stop() [2023-04-19T01:35:08.633Z] STF 02:35:08.163 - o Process LT2 pid 80115 killed [2023-04-19T01:35:08.633Z] STF 02:35:08.163 - EXECUTE stage completed [2023-04-19T01:35:08.633Z] STF 02:35:08.447 - [2023-04-19T01:35:08.633Z] STF 02:35:08.447 - ==================== T E A R D O W N ==================== [2023-04-19T01:35:08.633Z] STF 02:35:08.447 - Running teardown: perl /Users/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_mac/aqa-tests/TKG/../TKG/output_16818581575339/TestJlmRemoteThreadNoAuth_0/20230419-022233-TestJlmRemoteThreadNoAuth/tearDown.pl [2023-04-19T01:35:08.633Z] STF 02:35:08.530 - TEARDOWN stage completed [2023-04-19T01:35:08.633Z] STF 02:35:08.536 - [2023-04-19T01:35:08.633Z] STF 02:35:08.536 - ===================== R E S U L T S ===================== [2023-04-19T01:35:08.633Z] STF 02:35:08.536 - Stage results: [2023-04-19T01:35:08.633Z] STF 02:35:08.536 - setUp: pass [2023-04-19T01:35:08.633Z] STF 02:35:08.536 - execute: pass [2023-04-19T01:35:08.633Z] STF 02:35:08.536 - teardown: pass [2023-04-19T01:35:08.633Z] STF 02:35:08.536 - [2023-04-19T01:35:08.633Z] STF 02:35:08.536 - Overall result: PASSED [2023-04-19T01:35:08.633Z] ----------------------------------- [2023-04-19T01:35:08.633Z] TestJlmRemoteThreadNoAuth_0_PASSED [2023-04-19T01:35:08.633Z] ----------------------------------- [2023-04-19T01:35:08.633Z] [2023-04-19T01:35:08.633Z] TEST TEARDOWN: [2023-04-19T01:35:08.633Z] Nothing to be done for teardown. [2023-04-19T01:35:08.633Z] TestJlmRemoteThreadNoAuth_0 Finish Time: Wed Apr 19 02:35:08 2023 Epoch Time (ms): 1681868108580