[2024-11-20T23:10:44.593Z] Running test TestJlmRemoteThreadNoAuth_0 ... [2024-11-20T23:10:44.593Z] =============================================== [2024-11-20T23:10:44.593Z] TestJlmRemoteThreadNoAuth_0 Start Time: Wed Nov 20 15:10:44 2024 Epoch Time (ms): 1732144244144 [2024-11-20T23:10:44.593Z] variation: Mode150 [2024-11-20T23:10:44.593Z] JVM_OPTIONS: -XX:+UseCompressedOops [2024-11-20T23:10:44.593Z] { \ [2024-11-20T23:10:44.593Z] echo ""; echo "TEST SETUP:"; \ [2024-11-20T23:10:44.593Z] echo "Nothing to be done for setup."; \ [2024-11-20T23:10:44.593Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0"; \ [2024-11-20T23:10:44.593Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0"; \ [2024-11-20T23:10:44.593Z] echo ""; echo "TESTING:"; \ [2024-11-20T23:10:44.593Z] perl /Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/../../testDependency/system_lib/" -java-args=' -XX:+UseCompressedOops ' -results-root="/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0" -test=TestJlmRemoteThreadNoAuth; \ [2024-11-20T23:10:44.593Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-20T23:10:44.593Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-20T23:10:44.593Z] echo "Nothing to be done for teardown."; \ [2024-11-20T23:10:44.593Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestTargetResult"; [2024-11-20T23:10:44.593Z] [2024-11-20T23:10:44.593Z] TEST SETUP: [2024-11-20T23:10:44.593Z] Nothing to be done for setup. [2024-11-20T23:10:44.593Z] [2024-11-20T23:10:44.593Z] TESTING: [2024-11-20T23:10:45.591Z] STF 15:10:44.820 - ========================= S T F ========================= [2024-11-20T23:10:45.591Z] 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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0 [2024-11-20T23:10:45.591Z] There is 11056 Mb free [2024-11-20T23:10:45.591Z] STF 15:10:44.929 - ================== G E N E R A T I O N ================== [2024-11-20T23:10:45.591Z] STF 15:10:44.931 - Checking JVM: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/../ [2024-11-20T23:10:45.591Z] STF 15:10:44.931 - Starting process to generate scripts: /Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/../../testDependency/system_lib//log4j/log4j-api.jar:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/../../testDependency/system_lib//log4j/log4j-core.jar:/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth" [2024-11-20T23:10:45.591Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-20T23:10:50.398Z] GEN Java lib directory does not exist: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/lib [2024-11-20T23:10:50.398Z] GEN 15:10:49.607 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-11-20T23:10:50.398Z] GEN 15:10:49.637 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-11-20T23:10:50.933Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-11-20T23:10:50.933Z] GEN /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-11-20T23:10:50.933Z] GEN /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.core/bin [2024-11-20T23:10:50.933Z] GEN /Users/admin/workspace/testDependency/system_lib/log4j/log4j-api.jar [2024-11-20T23:10:50.933Z] GEN /Users/admin/workspace/testDependency/system_lib/log4j/log4j-core.jar [2024-11-20T23:10:50.933Z] GEN /Users/admin/workspace/testDependency/system_lib/junit/junit.jar [2024-11-20T23:10:50.933Z] GEN Java lib directory does not exist: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/lib [2024-11-20T23:10:50.933Z] GEN 15:10:50.317 - Using Mode NoOptions. Values = '' [2024-11-20T23:10:51.852Z] GEN 15:10:50.746 - [2024-11-20T23:10:51.852Z] GEN 15:10:50.746 - Test command summary: [2024-11-20T23:10:51.852Z] GEN 15:10:50.746 - Step Stage Command Description [2024-11-20T23:10:51.852Z] GEN 15:10:50.746 - -----+--------+-----------------+------------ [2024-11-20T23:10:51.852Z] GEN 15:10:50.746 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-11-20T23:10:51.852Z] GEN 15:10:50.747 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-11-20T23:10:51.852Z] GEN 15:10:50.747 - 3 execute Monitor Wait for processes to complete [2024-11-20T23:10:51.852Z] GEN 15:10:50.747 - 4 execute kill Stop LT1 process [2024-11-20T23:10:51.852Z] GEN 15:10:50.747 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-11-20T23:10:51.852Z] GEN 15:10:50.747 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-11-20T23:10:51.852Z] GEN 15:10:50.747 - 7 execute Monitor Wait for processes to complete [2024-11-20T23:10:51.852Z] GEN 15:10:50.747 - 8 execute kill Stop LT2 process [2024-11-20T23:10:52.588Z] STF 15:10:51.571 - [2024-11-20T23:10:52.588Z] STF 15:10:51.571 - Script generation completed [2024-11-20T23:10:52.588Z] STF 15:10:51.571 - [2024-11-20T23:10:52.588Z] STF 15:10:51.573 - [2024-11-20T23:10:52.588Z] STF 15:10:51.573 - ======================= S E T U P ======================= [2024-11-20T23:10:52.588Z] STF 15:10:51.574 - Running setup: perl /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/setUp.pl [2024-11-20T23:10:53.369Z] STF 15:10:52.984 - SETUP stage completed [2024-11-20T23:10:53.369Z] STF 15:10:53.044 - [2024-11-20T23:10:53.369Z] STF 15:10:53.044 - ==================== E X E C U T E - ==================== [2024-11-20T23:10:53.369Z] STF 15:10:53.044 - Running execute: perl /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/execute.pl [2024-11-20T23:10:55.692Z] STF 15:10:55.328 - [2024-11-20T23:10:55.692Z] STF 15:10:55.328 - Java version [2024-11-20T23:10:55.692Z] STF 15:10:55.328 - Running: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java -version [2024-11-20T23:10:56.296Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-20T23:10:56.296Z] openjdk version "21.0.6-beta" 2025-01-21 [2024-11-20T23:10:56.296Z] OpenJDK Runtime Environment Temurin-21.0.6+4-202411202005 (build 21.0.6-beta+4-ea) [2024-11-20T23:10:56.296Z] OpenJDK 64-Bit Server VM Temurin-21.0.6+4-202411202005 (build 21.0.6-beta+4-ea, mixed mode) [2024-11-20T23:10:56.296Z] STF 15:10:55.941 - [2024-11-20T23:10:56.296Z] STF 15:10:55.941 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-11-20T23:10:56.296Z] STF 15:10:55.941 - | Start background process [2024-11-20T23:10:56.296Z] STF 15:10:55.941 - | Program: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-11-20T23:10:56.296Z] STF 15:10:55.941 - | Mnemonic: LT1 [2024-11-20T23:10:56.296Z] STF 15:10:55.941 - | Echo: ECHO_OFF [2024-11-20T23:10:56.296Z] STF 15:10:55.941 - | Expectation: NEVER [2024-11-20T23:10:56.296Z] STF 15:10:55.941 - | [2024-11-20T23:10:56.296Z] STF 15:10:55.943 - Running command: /Users/admin/workspace/workspace/Test_openjdk21_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.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:+UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.load/bin:/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-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/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-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 [2024-11-20T23:10:56.296Z] STF 15:10:55.943 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-11-20T23:10:56.296Z] STF 15:10:55.943 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-11-20T23:10:56.296Z] STF 15:10:55.987 - [2024-11-20T23:10:56.296Z] STF 15:10:55.987 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-11-20T23:10:56.296Z] STF 15:10:55.987 - | Start background process [2024-11-20T23:10:56.296Z] STF 15:10:55.987 - | Program: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-11-20T23:10:56.296Z] STF 15:10:55.987 - | Mnemonic: CL1 [2024-11-20T23:10:56.296Z] STF 15:10:55.987 - | Echo: ECHO_ON [2024-11-20T23:10:56.296Z] STF 15:10:55.987 - | Expectation: CLEAN_RUN within 30m [2024-11-20T23:10:56.296Z] STF 15:10:55.988 - | [2024-11-20T23:10:56.296Z] STF 15:10:55.988 - Running command: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -XX:+UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-11-20T23:10:56.296Z] STF 15:10:55.988 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-11-20T23:10:56.296Z] STF 15:10:55.988 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-11-20T23:10:56.296Z] STF 15:10:56.020 - [2024-11-20T23:10:56.296Z] STF 15:10:56.020 - +------ Step 3 - Wait for processes to complete [2024-11-20T23:10:56.296Z] STF 15:10:56.020 - | Wait for processes to meet expectations [2024-11-20T23:10:56.296Z] STF 15:10:56.020 - | Processes: [LT1, CL1] [2024-11-20T23:10:56.296Z] STF 15:10:56.020 - | [2024-11-20T23:10:56.296Z] STF 15:10:56.020 - Monitoring processes: CL1 LT1 [2024-11-20T23:10:56.818Z] CL1 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-20T23:10:59.013Z] CL1 j> 2024/11/20 15:10:57.701 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-20T23:10:59.013Z] CL1 j> 2024/11/20 15:10:58.528 Attempting to connect [2024-11-20T23:10:59.469Z] CL1 j> 2024/11/20 15:10:59.006 Monitored VM not ready at Nov 20, 2024, 3:10:58 PM (attempt 1, elapsed 404ms). [2024-11-20T23:10:59.469Z] CL1 j> 2024/11/20 15:10:59.006 Waiting 5 secs and trying again... [2024-11-20T23:11:04.769Z] CL1 j> 2024/11/20 15:11:04.014 Attempting to connect [2024-11-20T23:11:08.065Z] CL1 j> 2024/11/20 15:11:07.652 Connection established! [2024-11-20T23:11:12.474Z] CL1 j> 2024/11/20 15:11:12.004 Starting to write data [2024-11-20T23:16:05.972Z] STF 15:15:55.010 - Heartbeat: Process CL1 is still running [2024-11-20T23:20:59.773Z] STF 15:20:55.091 - Heartbeat: Process CL1 is still running [2024-11-20T23:21:24.641Z] STF 15:21:20.553 - Monitoring Report Summary: [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - o Process CL1 ended with the expected exit code (0) [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - o Process LT1 is still running as expected [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - +------ Step 4 - Stop LT1 process [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - | Kill running processes [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - | Processes: [LT1] [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - | [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - Killing processes: LT1 [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - o Process clean up attempt 1 for LT1 pid 9857 [2024-11-20T23:21:24.641Z] STF 15:21:20.554 - o Process LT1 pid 9857 stop() [2024-11-20T23:21:24.641Z] STF 15:21:22.624 - o Process LT1 pid 9857 killed [2024-11-20T23:21:24.641Z] STF 15:21:22.626 - [2024-11-20T23:21:24.641Z] STF 15:21:22.626 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-11-20T23:21:24.641Z] STF 15:21:22.626 - | Start background process [2024-11-20T23:21:24.641Z] STF 15:21:22.626 - | Program: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-11-20T23:21:24.641Z] STF 15:21:22.626 - | Mnemonic: LT2 [2024-11-20T23:21:24.641Z] STF 15:21:22.626 - | Echo: ECHO_OFF [2024-11-20T23:21:24.641Z] STF 15:21:22.626 - | Expectation: NEVER [2024-11-20T23:21:24.641Z] STF 15:21:22.626 - | [2024-11-20T23:21:24.642Z] STF 15:21:22.628 - Running command: /Users/admin/workspace/workspace/Test_openjdk21_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.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:+UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/STF/stf.load/bin:/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-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/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-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 [2024-11-20T23:21:24.642Z] STF 15:21:22.629 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-11-20T23:21:24.642Z] STF 15:21:22.629 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-11-20T23:21:24.642Z] STF 15:21:22.691 - [2024-11-20T23:21:24.642Z] STF 15:21:22.692 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-11-20T23:21:24.642Z] STF 15:21:22.692 - | Start background process [2024-11-20T23:21:24.642Z] STF 15:21:22.692 - | Program: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-11-20T23:21:24.642Z] STF 15:21:22.692 - | Mnemonic: CL2 [2024-11-20T23:21:24.642Z] STF 15:21:22.692 - | Echo: ECHO_ON [2024-11-20T23:21:24.642Z] STF 15:21:22.692 - | Expectation: CLEAN_RUN within 30m [2024-11-20T23:21:24.642Z] STF 15:21:22.786 - | [2024-11-20T23:21:24.642Z] STF 15:21:22.823 - Running command: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -XX:+UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-11-20T23:21:24.642Z] STF 15:21:22.824 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-11-20T23:21:24.642Z] STF 15:21:22.824 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-11-20T23:21:24.642Z] STF 15:21:22.979 - [2024-11-20T23:21:24.642Z] STF 15:21:22.980 - +------ Step 7 - Wait for processes to complete [2024-11-20T23:21:24.642Z] STF 15:21:22.980 - | Wait for processes to meet expectations [2024-11-20T23:21:24.642Z] STF 15:21:22.980 - | Processes: [LT2, CL2] [2024-11-20T23:21:24.642Z] STF 15:21:22.980 - | [2024-11-20T23:21:24.642Z] STF 15:21:22.980 - Monitoring processes: CL2 LT2 [2024-11-20T23:21:24.642Z] CL2 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-20T23:21:25.993Z] CL2 j> 2024/11/20 15:21:24.899 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-20T23:21:25.993Z] CL2 j> 2024/11/20 15:21:25.172 Attempting to connect [2024-11-20T23:21:27.513Z] CL2 j> 2024/11/20 15:21:26.318 Monitored VM not ready at Nov 20, 2024, 3:21:26 PM (attempt 1, elapsed 1090ms). [2024-11-20T23:21:27.513Z] CL2 j> 2024/11/20 15:21:26.318 Waiting 5 secs and trying again... [2024-11-20T23:21:32.171Z] CL2 j> 2024/11/20 15:21:31.349 Attempting to connect [2024-11-20T23:21:35.174Z] CL2 j> 2024/11/20 15:21:34.159 Connection established! [2024-11-20T23:21:56.977Z] CL2 j> 2024/11/20 15:21:54.110 Writing report data ... [2024-11-20T23:22:44.131Z] CL2 j> 2024/11/20 15:22:40.433 done. [2024-11-20T23:22:52.255Z] CL2 j> 2024/11/20 15:22:51.250 Writing report data ... [2024-11-20T23:23:20.732Z] CL2 j> 2024/11/20 15:23:16.485 done. [2024-11-20T23:23:28.696Z] CL2 j> 2024/11/20 15:23:27.521 Writing report data ... [2024-11-20T23:23:53.475Z] CL2 j> 2024/11/20 15:23:51.587 done. [2024-11-20T23:24:04.101Z] CL2 j> 2024/11/20 15:24:02.374 Writing report data ... [2024-11-20T23:24:24.035Z] CL2 j> 2024/11/20 15:24:20.340 done. [2024-11-20T23:24:32.103Z] CL2 j> 2024/11/20 15:24:31.432 Writing report data ... [2024-11-20T23:24:55.920Z] CL2 j> 2024/11/20 15:24:53.404 done. [2024-11-20T23:25:06.978Z] CL2 j> 2024/11/20 15:25:04.974 Writing report data ... [2024-11-20T23:25:24.126Z] CL2 j> 2024/11/20 15:25:22.457 done. [2024-11-20T23:25:35.440Z] CL2 j> 2024/11/20 15:25:33.130 Writing report data ... [2024-11-20T23:25:50.466Z] CL2 j> 2024/11/20 15:25:47.124 done. [2024-11-20T23:25:56.172Z] STF 15:25:55.195 - Heartbeat: Process CL2 is still running [2024-11-20T23:25:58.934Z] CL2 j> 2024/11/20 15:25:58.035 Writing report data ... [2024-11-20T23:26:19.580Z] CL2 j> 2024/11/20 15:26:15.038 done. [2024-11-20T23:26:28.805Z] CL2 j> 2024/11/20 15:26:26.296 Writing report data ... [2024-11-20T23:26:46.400Z] CL2 j> 2024/11/20 15:26:45.482 done. [2024-11-20T23:26:59.259Z] CL2 j> 2024/11/20 15:26:57.467 Writing report data ... [2024-11-20T23:27:20.141Z] CL2 j> 2024/11/20 15:27:18.002 done. [2024-11-20T23:27:30.790Z] CL2 j> 2024/11/20 15:27:29.099 Writing report data ... [2024-11-20T23:27:51.417Z] CL2 j> 2024/11/20 15:27:47.682 done. [2024-11-20T23:28:00.915Z] CL2 j> 2024/11/20 15:27:58.650 Writing report data ... [2024-11-20T23:28:16.089Z] CL2 j> 2024/11/20 15:28:14.854 done. [2024-11-20T23:28:28.145Z] CL2 j> 2024/11/20 15:28:26.042 Writing report data ... [2024-11-20T23:28:48.005Z] CL2 j> 2024/11/20 15:28:44.586 done. [2024-11-20T23:28:57.142Z] CL2 j> 2024/11/20 15:28:55.715 Writing report data ... [2024-11-20T23:29:14.645Z] CL2 j> 2024/11/20 15:29:12.510 done. [2024-11-20T23:29:25.487Z] CL2 j> 2024/11/20 15:29:23.918 Writing report data ... [2024-11-20T23:29:45.626Z] CL2 j> 2024/11/20 15:29:44.867 done. [2024-11-20T23:29:56.533Z] CL2 j> 2024/11/20 15:29:55.399 Writing report data ... [2024-11-20T23:30:24.796Z] CL2 j> 2024/11/20 15:30:21.019 done. [2024-11-20T23:30:33.588Z] CL2 j> 2024/11/20 15:30:31.942 Writing report data ... [2024-11-20T23:30:49.215Z] CL2 j> 2024/11/20 15:30:47.678 done. [2024-11-20T23:30:57.067Z] STF 15:30:55.454 - Heartbeat: Process CL2 is still running [2024-11-20T23:30:59.486Z] CL2 j> 2024/11/20 15:30:58.579 Writing report data ... [2024-11-20T23:31:16.511Z] CL2 j> 2024/11/20 15:31:14.300 done. [2024-11-20T23:31:27.484Z] CL2 j> 2024/11/20 15:31:24.929 Writing report data ... [2024-11-20T23:31:39.521Z] CL2 j> 2024/11/20 15:31:37.737 done. [2024-11-20T23:31:50.582Z] CL2 j> 2024/11/20 15:31:48.785 Writing report data ... [2024-11-20T23:32:05.725Z] CL2 j> 2024/11/20 15:32:03.855 done. [2024-11-20T23:32:16.692Z] CL2 j> 2024/11/20 15:32:14.864 Writing report data ... [2024-11-20T23:32:24.985Z] CL2 j> 2024/11/20 15:32:23.551 done. [2024-11-20T23:32:36.273Z] CL2 j> 2024/11/20 15:32:34.174 Writing report data ... [2024-11-20T23:32:51.455Z] CL2 j> 2024/11/20 15:32:50.230 done. [2024-11-20T23:33:02.538Z] CL2 j> 2024/11/20 15:33:00.752 Writing report data ... [2024-11-20T23:33:16.771Z] CL2 j> 2024/11/20 15:33:15.781 done. [2024-11-20T23:33:28.062Z] CL2 j> 2024/11/20 15:33:26.636 Writing report data ... [2024-11-20T23:33:42.194Z] CL2 j> 2024/11/20 15:33:39.261 done. [2024-11-20T23:33:51.446Z] CL2 j> 2024/11/20 15:33:50.076 Writing report data ... [2024-11-20T23:34:02.293Z] CL2 j> 2024/11/20 15:34:00.263 done. [2024-11-20T23:34:11.870Z] CL2 j> 2024/11/20 15:34:11.066 Writing report data ... [2024-11-20T23:34:24.434Z] CL2 j> 2024/11/20 15:34:23.051 done. [2024-11-20T23:34:34.671Z] CL2 j> 2024/11/20 15:34:33.643 Writing report data ... [2024-11-20T23:34:52.942Z] CL2 j> 2024/11/20 15:34:49.932 done. [2024-11-20T23:35:02.473Z] CL2 j> 2024/11/20 15:35:00.470 Writing report data ... [2024-11-20T23:35:17.544Z] CL2 j> 2024/11/20 15:35:15.515 done. [2024-11-20T23:35:29.088Z] CL2 j> 2024/11/20 15:35:26.831 Writing report data ... [2024-11-20T23:35:42.145Z] CL2 j> 2024/11/20 15:35:40.678 done. [2024-11-20T23:35:52.890Z] CL2 j> 2024/11/20 15:35:51.350 Writing report data ... [2024-11-20T23:35:56.408Z] STF 15:35:55.081 - Heartbeat: Process CL2 is still running [2024-11-20T23:36:07.077Z] CL2 j> 2024/11/20 15:36:05.912 done. [2024-11-20T23:36:08.447Z] STF 15:36:07.717 - Monitoring Report Summary: [2024-11-20T23:36:08.447Z] STF 15:36:07.717 - o Process CL2 ended with the expected exit code (0) [2024-11-20T23:36:08.447Z] STF 15:36:07.717 - o Process LT2 is still running as expected [2024-11-20T23:36:08.448Z] STF 15:36:07.718 - [2024-11-20T23:36:08.448Z] STF 15:36:07.718 - +------ Step 8 - Stop LT2 process [2024-11-20T23:36:08.448Z] STF 15:36:07.718 - | Kill running processes [2024-11-20T23:36:08.448Z] STF 15:36:07.718 - | Processes: [LT2] [2024-11-20T23:36:08.448Z] STF 15:36:07.718 - | [2024-11-20T23:36:08.448Z] STF 15:36:07.718 - Killing processes: LT2 [2024-11-20T23:36:08.448Z] STF 15:36:07.718 - o Process clean up attempt 1 for LT2 pid 10370 [2024-11-20T23:36:08.448Z] STF 15:36:07.718 - o Process LT2 pid 10370 stop() [2024-11-20T23:36:09.733Z] STF 15:36:08.719 - o Process LT2 pid 10370 killed [2024-11-20T23:36:09.733Z] STF 15:36:08.719 - EXECUTE stage completed [2024-11-20T23:36:09.733Z] STF 15:36:09.112 - [2024-11-20T23:36:09.733Z] STF 15:36:09.112 - ==================== T E A R D O W N ==================== [2024-11-20T23:36:09.733Z] STF 15:36:09.112 - Running teardown: perl /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17321393272859/TestJlmRemoteThreadNoAuth_0/20241120-151044-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-11-20T23:36:10.406Z] STF 15:36:10.110 - TEARDOWN stage completed [2024-11-20T23:36:10.406Z] STF 15:36:10.156 - [2024-11-20T23:36:10.406Z] STF 15:36:10.156 - ===================== R E S U L T S ===================== [2024-11-20T23:36:10.406Z] STF 15:36:10.156 - Stage results: [2024-11-20T23:36:10.406Z] STF 15:36:10.156 - setUp: pass [2024-11-20T23:36:10.406Z] STF 15:36:10.156 - execute: pass [2024-11-20T23:36:10.406Z] STF 15:36:10.157 - teardown: pass [2024-11-20T23:36:10.406Z] STF 15:36:10.157 - [2024-11-20T23:36:10.406Z] STF 15:36:10.157 - Overall result: PASSED [2024-11-20T23:36:10.935Z] ----------------------------------- [2024-11-20T23:36:10.935Z] TestJlmRemoteThreadNoAuth_0_PASSED [2024-11-20T23:36:10.935Z] ----------------------------------- [2024-11-20T23:36:10.935Z] [2024-11-20T23:36:10.935Z] TEST TEARDOWN: [2024-11-20T23:36:10.935Z] Nothing to be done for teardown. [2024-11-20T23:36:10.935Z] TestJlmRemoteThreadNoAuth_0 Finish Time: Wed Nov 20 15:36:10 2024 Epoch Time (ms): 1732145770593