[2024-08-09T22:49:25.257Z] Running test TestJlmRemoteThreadNoAuth_1 ... [2024-08-09T22:49:25.257Z] =============================================== [2024-08-09T22:49:25.257Z] TestJlmRemoteThreadNoAuth_1 Start Time: Fri Aug 9 22:49:23 2024 Epoch Time (ms): 1723243763669 [2024-08-09T22:49:25.257Z] variation: Mode650 [2024-08-09T22:49:25.257Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-08-09T22:49:25.257Z] { \ [2024-08-09T22:49:25.257Z] echo ""; echo "TEST SETUP:"; \ [2024-08-09T22:49:25.257Z] echo "Nothing to be done for setup."; \ [2024-08-09T22:49:25.257Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1"; \ [2024-08-09T22:49:25.257Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1"; \ [2024-08-09T22:49:25.257Z] echo ""; echo "TESTING:"; \ [2024-08-09T22:49:25.257Z] perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/../../testDependency/system_lib/" -java-args=' -XX:-UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1" -test=TestJlmRemoteThreadNoAuth; \ [2024-08-09T22:49:25.257Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-09T22:49:25.257Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-09T22:49:25.257Z] echo "Nothing to be done for teardown."; \ [2024-08-09T22:49:25.257Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestTargetResult"; [2024-08-09T22:49:25.257Z] [2024-08-09T22:49:25.257Z] TEST SETUP: [2024-08-09T22:49:25.257Z] Nothing to be done for setup. [2024-08-09T22:49:25.257Z] [2024-08-09T22:49:25.257Z] TESTING: [2024-08-09T22:49:26.099Z] STF 22:49:24.231 - ========================= S T F ========================= [2024-08-09T22:49:26.099Z] systemtest-prereqs has been processed, and set to: /home/jenkins/testDependency/system_libRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1 [2024-08-09T22:49:26.099Z] There is 224514 Mb free [2024-08-09T22:49:26.099Z] STF 22:49:24.278 - ================== G E N E R A T I O N ================== [2024-08-09T22:49:26.099Z] STF 22:49:24.360 - Checking JVM: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image [2024-08-09T22:49:26.099Z] STF 22:49:24.360 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth" [2024-08-09T22:49:26.099Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-09T22:49:26.099Z] GEN [0.078s][warning][cds] Archived non-system classes are disabled because the java.system.class.loader property is specified (value = "net.adoptopenjdk.stf.runner.StfClassLoader"). To use archived non-system classes, this property must not be set [2024-08-09T22:49:34.624Z] GEN 22:49:32.789 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-08-09T22:49:34.624Z] GEN 22:49:32.936 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-08-09T22:49:35.470Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-08-09T22:49:35.470Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-08-09T22:49:35.470Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.core/bin [2024-08-09T22:49:35.470Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-08-09T22:49:35.470Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-08-09T22:49:35.470Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-08-09T22:49:35.470Z] GEN 22:49:34.177 - Using Mode NoOptions. Values = '' [2024-08-09T22:49:36.272Z] GEN 22:49:34.948 - [2024-08-09T22:49:36.272Z] GEN 22:49:34.949 - Test command summary: [2024-08-09T22:49:36.272Z] GEN 22:49:34.958 - Step Stage Command Description [2024-08-09T22:49:36.272Z] GEN 22:49:34.958 - -----+--------+-----------------+------------ [2024-08-09T22:49:36.272Z] GEN 22:49:34.959 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-08-09T22:49:36.272Z] GEN 22:49:34.959 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-08-09T22:49:36.272Z] GEN 22:49:34.959 - 3 execute Monitor Wait for processes to complete [2024-08-09T22:49:36.272Z] GEN 22:49:34.959 - 4 execute kill Stop LT1 process [2024-08-09T22:49:36.272Z] GEN 22:49:34.959 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-08-09T22:49:36.272Z] GEN 22:49:34.959 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-08-09T22:49:36.272Z] GEN 22:49:34.959 - 7 execute Monitor Wait for processes to complete [2024-08-09T22:49:36.272Z] GEN 22:49:34.959 - 8 execute kill Stop LT2 process [2024-08-09T22:49:36.272Z] STF 22:49:35.116 - [2024-08-09T22:49:36.272Z] STF 22:49:35.125 - Script generation completed [2024-08-09T22:49:36.272Z] STF 22:49:35.125 - [2024-08-09T22:49:36.272Z] STF 22:49:35.126 - [2024-08-09T22:49:36.272Z] STF 22:49:35.126 - ======================= S E T U P ======================= [2024-08-09T22:49:36.272Z] STF 22:49:35.126 - Running setup: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/setUp.pl [2024-08-09T22:49:37.046Z] STF 22:49:35.375 - SETUP stage completed [2024-08-09T22:49:37.046Z] STF 22:49:35.387 - [2024-08-09T22:49:37.046Z] STF 22:49:35.390 - ==================== E X E C U T E - ==================== [2024-08-09T22:49:37.046Z] STF 22:49:35.391 - Running execute: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/execute.pl [2024-08-09T22:49:37.046Z] STF 22:49:35.774 - [2024-08-09T22:49:37.047Z] STF 22:49:35.774 - Java version [2024-08-09T22:49:37.047Z] STF 22:49:35.774 - Running: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -version [2024-08-09T22:49:37.047Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-09T22:49:37.850Z] openjdk version "21.0.5-beta" 2024-10-15 [2024-08-09T22:49:37.850Z] OpenJDK Runtime Environment Temurin-21.0.5+2-202408091902 (build 21.0.5-beta+2-ea) [2024-08-09T22:49:37.850Z] OpenJDK 64-Bit Server VM Temurin-21.0.5+2-202408091902 (build 21.0.5-beta+2-ea, mixed mode, sharing) [2024-08-09T22:49:37.850Z] STF 22:49:36.438 - [2024-08-09T22:49:37.850Z] STF 22:49:36.438 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-08-09T22:49:37.850Z] STF 22:49:36.438 - | Start background process [2024-08-09T22:49:37.850Z] STF 22:49:36.438 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-09T22:49:37.850Z] STF 22:49:36.438 - | Mnemonic: LT1 [2024-08-09T22:49:37.850Z] STF 22:49:36.438 - | Echo: ECHO_OFF [2024-08-09T22:49:37.850Z] STF 22:49:36.438 - | Expectation: NEVER [2024-08-09T22:49:37.850Z] STF 22:49:36.438 - | [2024-08-09T22:49:37.850Z] STF 22:49:36.447 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.core/bin:/home/jenkins/testDependency/system_lib/log4j/log4j-api.jar:/home/jenkins/testDependency/system_lib/log4j/log4j-core.jar:/home/jenkins/testDependency/system_lib/junit/junit.jar:/home/jenkins/testDependency/system_lib/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-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 /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-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-08-09T22:49:37.850Z] STF 22:49:36.447 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-08-09T22:49:37.850Z] STF 22:49:36.447 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-08-09T22:49:37.850Z] STF 22:49:36.581 - [2024-08-09T22:49:37.850Z] STF 22:49:36.581 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-08-09T22:49:37.850Z] STF 22:49:36.581 - | Start background process [2024-08-09T22:49:37.850Z] STF 22:49:36.581 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-09T22:49:37.850Z] STF 22:49:36.581 - | Mnemonic: CL1 [2024-08-09T22:49:37.850Z] STF 22:49:36.581 - | Echo: ECHO_ON [2024-08-09T22:49:37.850Z] STF 22:49:36.581 - | Expectation: CLEAN_RUN within 30m [2024-08-09T22:49:37.850Z] STF 22:49:36.581 - | [2024-08-09T22:49:37.850Z] STF 22:49:36.608 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/home/jenkins/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler proxy /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-09T22:49:37.850Z] STF 22:49:36.609 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-08-09T22:49:37.850Z] STF 22:49:36.609 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-08-09T22:49:37.850Z] STF 22:49:36.611 - [2024-08-09T22:49:37.850Z] STF 22:49:36.642 - +------ Step 3 - Wait for processes to complete [2024-08-09T22:49:37.850Z] STF 22:49:36.642 - | Wait for processes to meet expectations [2024-08-09T22:49:37.850Z] STF 22:49:36.642 - | Processes: [LT1, CL1] [2024-08-09T22:49:37.850Z] STF 22:49:36.642 - | [2024-08-09T22:49:37.851Z] STF 22:49:36.642 - Monitoring processes: CL1 LT1 [2024-08-09T22:49:38.646Z] CL1 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-09T22:49:41.045Z] CL1 j> 2024/08/09 22:49:38.778 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-09T22:49:41.045Z] CL1 j> 2024/08/09 22:49:39.723 Attempting to connect [2024-08-09T22:49:43.460Z] CL1 j> 2024/08/09 22:49:41.568 Monitored VM not ready at Aug 9, 2024, 10:49:41 PM (attempt 1, elapsed 1481ms). [2024-08-09T22:49:43.460Z] CL1 j> 2024/08/09 22:49:41.577 Waiting 5 secs and trying again... [2024-08-09T22:49:48.955Z] CL1 j> 2024/08/09 22:49:46.582 Attempting to connect [2024-08-09T22:49:49.705Z] CL1 j> 2024/08/09 22:49:48.457 Connection established! [2024-08-09T22:49:51.365Z] CL1 j> 2024/08/09 22:49:49.615 Starting to write data [2024-08-09T22:54:51.476Z] STF 22:54:35.335 - Heartbeat: Process CL1 is still running [2024-08-09T22:59:50.696Z] STF 22:59:35.041 - Heartbeat: Process CL1 is still running [2024-08-09T23:04:36.821Z] STF 23:04:35.020 - Heartbeat: Process LT1 is still running [2024-08-09T23:08:38.467Z] STF 23:08:29.738 - Monitoring Report Summary: [2024-08-09T23:08:38.467Z] STF 23:08:29.738 - o Process CL1 ended with the expected exit code (0) [2024-08-09T23:08:38.467Z] STF 23:08:29.738 - o Process LT1 is still running as expected [2024-08-09T23:08:38.467Z] STF 23:08:29.821 - [2024-08-09T23:08:38.467Z] STF 23:08:29.821 - +------ Step 4 - Stop LT1 process [2024-08-09T23:08:38.467Z] STF 23:08:29.821 - | Kill running processes [2024-08-09T23:08:38.467Z] STF 23:08:29.821 - | Processes: [LT1] [2024-08-09T23:08:38.467Z] STF 23:08:29.821 - | [2024-08-09T23:08:38.467Z] STF 23:08:29.821 - Killing processes: LT1 [2024-08-09T23:08:38.467Z] STF 23:08:29.821 - o Process clean up attempt 1 for LT1 pid 173510 [2024-08-09T23:08:38.467Z] STF 23:08:29.821 - o Process LT1 pid 173510 stop() [2024-08-09T23:08:41.608Z] STF 23:08:39.941 - o Process LT1 pid 173510 terminate() [2024-08-09T23:08:43.172Z] STF 23:08:40.944 - o Process LT1 pid 173510 killed [2024-08-09T23:08:43.172Z] STF 23:08:40.944 - [2024-08-09T23:08:43.172Z] STF 23:08:40.944 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-08-09T23:08:43.172Z] STF 23:08:40.944 - | Start background process [2024-08-09T23:08:43.172Z] STF 23:08:40.944 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-09T23:08:43.172Z] STF 23:08:40.944 - | Mnemonic: LT2 [2024-08-09T23:08:43.172Z] STF 23:08:40.944 - | Echo: ECHO_OFF [2024-08-09T23:08:43.173Z] STF 23:08:40.944 - | Expectation: NEVER [2024-08-09T23:08:43.173Z] STF 23:08:40.944 - | [2024-08-09T23:08:43.173Z] STF 23:08:40.945 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/STF/stf.core/bin:/home/jenkins/testDependency/system_lib/log4j/log4j-api.jar:/home/jenkins/testDependency/system_lib/log4j/log4j-core.jar:/home/jenkins/testDependency/system_lib/junit/junit.jar:/home/jenkins/testDependency/system_lib/junit/hamcrest-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-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 /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-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-08-09T23:08:43.173Z] STF 23:08:40.945 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-08-09T23:08:43.173Z] STF 23:08:40.945 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-08-09T23:08:43.173Z] STF 23:08:40.963 - [2024-08-09T23:08:43.173Z] STF 23:08:40.963 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-08-09T23:08:43.173Z] STF 23:08:40.963 - | Start background process [2024-08-09T23:08:43.173Z] STF 23:08:40.963 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-09T23:08:43.173Z] STF 23:08:40.963 - | Mnemonic: CL2 [2024-08-09T23:08:43.173Z] STF 23:08:40.963 - | Echo: ECHO_ON [2024-08-09T23:08:43.173Z] STF 23:08:40.963 - | Expectation: CLEAN_RUN within 30m [2024-08-09T23:08:43.173Z] STF 23:08:40.963 - | [2024-08-09T23:08:43.173Z] STF 23:08:40.974 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/home/jenkins/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ThreadProfiler server /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-09T23:08:43.173Z] STF 23:08:40.974 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-08-09T23:08:43.173Z] STF 23:08:40.974 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-08-09T23:08:43.173Z] STF 23:08:40.990 - [2024-08-09T23:08:43.173Z] STF 23:08:40.990 - +------ Step 7 - Wait for processes to complete [2024-08-09T23:08:43.173Z] STF 23:08:40.990 - | Wait for processes to meet expectations [2024-08-09T23:08:43.173Z] STF 23:08:40.990 - | Processes: [LT2, CL2] [2024-08-09T23:08:43.173Z] STF 23:08:40.990 - | [2024-08-09T23:08:43.173Z] STF 23:08:40.990 - Monitoring processes: CL2 LT2 [2024-08-09T23:08:43.173Z] CL2 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-09T23:08:45.703Z] CL2 j> 2024/08/09 23:08:42.739 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-09T23:08:45.703Z] CL2 j> 2024/08/09 23:08:43.892 Attempting to connect [2024-08-09T23:08:48.197Z] CL2 j> 2024/08/09 23:08:46.354 Monitored VM not ready at Aug 9, 2024, 11:08:46 PM (attempt 1, elapsed 1882ms). [2024-08-09T23:08:48.197Z] CL2 j> 2024/08/09 23:08:46.357 Waiting 5 secs and trying again... [2024-08-09T23:08:52.696Z] CL2 j> 2024/08/09 23:08:51.362 Attempting to connect [2024-08-09T23:08:56.059Z] CL2 j> 2024/08/09 23:08:53.867 Connection established! [2024-08-09T23:09:24.085Z] CL2 j> 2024/08/09 23:09:20.495 Writing report data ... [2024-08-09T23:09:39.860Z] STF 23:09:35.595 - Heartbeat: Process CL2 is still running [2024-08-09T23:11:03.442Z] CL2 j> 2024/08/09 23:10:56.414 done. [2024-08-09T23:11:13.057Z] CL2 j> 2024/08/09 23:11:10.020 Writing report data ... [2024-08-09T23:12:34.942Z] CL2 j> 2024/08/09 23:12:30.289 done. [2024-08-09T23:12:48.159Z] CL2 j> 2024/08/09 23:12:45.066 Writing report data ... [2024-08-09T23:14:01.199Z] CL2 j> 2024/08/09 23:13:56.887 done. [2024-08-09T23:14:12.238Z] CL2 j> 2024/08/09 23:14:09.347 Writing report data ... [2024-08-09T23:14:40.068Z] STF 23:14:35.134 - Heartbeat: Process CL2 is still running [2024-08-09T23:15:13.049Z] CL2 j> 2024/08/09 23:15:07.686 done. [2024-08-09T23:15:22.659Z] CL2 j> 2024/08/09 23:15:20.022 Writing report data ... [2024-08-09T23:16:07.055Z] CL2 j> 2024/08/09 23:15:59.989 done. [2024-08-09T23:16:13.700Z] CL2 j> 2024/08/09 23:16:11.215 Writing report data ... [2024-08-09T23:16:47.208Z] CL2 j> 2024/08/09 23:16:42.259 done. [2024-08-09T23:16:56.850Z] CL2 j> 2024/08/09 23:16:54.079 Writing report data ... [2024-08-09T23:17:48.673Z] CL2 j> 2024/08/09 23:17:42.683 done. [2024-08-09T23:17:58.450Z] CL2 j> 2024/08/09 23:17:55.221 Writing report data ... [2024-08-09T23:18:50.280Z] CL2 j> 2024/08/09 23:18:47.883 done. [2024-08-09T23:19:02.042Z] CL2 j> 2024/08/09 23:18:58.976 Writing report data ... [2024-08-09T23:19:40.107Z] STF 23:19:35.317 - Heartbeat: Process CL2 is still running [2024-08-09T23:20:07.441Z] CL2 j> 2024/08/09 23:20:03.089 done. [2024-08-09T23:20:18.139Z] CL2 j> 2024/08/09 23:20:15.024 Writing report data ... [2024-08-09T23:21:17.766Z] CL2 j> 2024/08/09 23:21:13.121 done. [2024-08-09T23:21:28.944Z] CL2 j> 2024/08/09 23:21:26.265 Writing report data ... [2024-08-09T23:22:13.833Z] CL2 j> 2024/08/09 23:22:09.519 done. [2024-08-09T23:22:23.211Z] CL2 j> 2024/08/09 23:22:20.372 Writing report data ... [2024-08-09T23:23:14.096Z] CL2 j> 2024/08/09 23:23:05.073 done. [2024-08-09T23:23:20.734Z] CL2 j> 2024/08/09 23:23:17.501 Writing report data ... [2024-08-09T23:24:05.651Z] CL2 j> 2024/08/09 23:24:00.754 done. [2024-08-09T23:24:13.813Z] CL2 j> 2024/08/09 23:24:12.077 Writing report data ... [2024-08-09T23:24:37.670Z] STF 23:24:35.230 - Heartbeat: Process CL2 is still running [2024-08-09T23:24:58.313Z] CL2 j> 2024/08/09 23:24:54.445 done. [2024-08-09T23:25:07.465Z] CL2 j> 2024/08/09 23:25:05.684 Writing report data ... [2024-08-09T23:25:59.304Z] CL2 j> 2024/08/09 23:25:57.195 done. [2024-08-09T23:26:10.500Z] CL2 j> 2024/08/09 23:26:08.022 Writing report data ... [2024-08-09T23:26:54.095Z] CL2 j> 2024/08/09 23:26:49.494 done. [2024-08-09T23:27:02.054Z] CL2 j> 2024/08/09 23:27:00.188 Writing report data ... [2024-08-09T23:27:45.717Z] CL2 j> 2024/08/09 23:27:41.226 done. [2024-08-09T23:27:54.258Z] CL2 j> 2024/08/09 23:27:52.242 Writing report data ... [2024-08-09T23:28:38.039Z] CL2 j> 2024/08/09 23:28:34.536 done. [2024-08-09T23:28:48.827Z] CL2 j> 2024/08/09 23:28:45.959 Writing report data ... [2024-08-09T23:29:40.356Z] STF 23:29:35.317 - Heartbeat: Process CL2 is still running [2024-08-09T23:29:40.356Z] CL2 j> 2024/08/09 23:29:38.607 done. [2024-08-09T23:29:52.101Z] CL2 j> 2024/08/09 23:29:50.388 Writing report data ... [2024-08-09T23:30:44.557Z] CL2 j> 2024/08/09 23:30:38.396 done. [2024-08-09T23:30:51.397Z] CL2 j> 2024/08/09 23:30:49.291 Writing report data ... [2024-08-09T23:31:34.982Z] CL2 j> 2024/08/09 23:31:30.775 done. [2024-08-09T23:31:43.678Z] CL2 j> 2024/08/09 23:31:41.685 Writing report data ... [2024-08-09T23:32:35.105Z] CL2 j> 2024/08/09 23:32:29.692 done. [2024-08-09T23:32:43.454Z] CL2 j> 2024/08/09 23:32:41.645 Writing report data ... [2024-08-09T23:33:35.035Z] CL2 j> 2024/08/09 23:33:32.407 done. [2024-08-09T23:33:46.556Z] CL2 j> 2024/08/09 23:33:44.544 Writing report data ... [2024-08-09T23:34:14.719Z] CL2 j> 2024/08/09 23:34:11.028 done. [2024-08-09T23:34:25.018Z] CL2 j> 2024/08/09 23:34:22.534 Writing report data ... [2024-08-09T23:34:38.096Z] STF 23:34:35.289 - Heartbeat: Process CL2 is still running [2024-08-09T23:34:58.780Z] CL2 j> 2024/08/09 23:34:55.955 done. [2024-08-09T23:35:09.488Z] CL2 j> 2024/08/09 23:35:07.389 Writing report data ... [2024-08-09T23:36:00.531Z] CL2 j> 2024/08/09 23:35:51.805 done. [2024-08-09T23:36:04.572Z] CL2 j> 2024/08/09 23:36:02.323 Writing report data ... [2024-08-09T23:36:49.548Z] CL2 j> 2024/08/09 23:36:45.091 done. [2024-08-09T23:36:58.860Z] CL2 j> 2024/08/09 23:36:55.820 Writing report data ... [2024-08-09T23:37:43.182Z] CL2 j> 2024/08/09 23:37:38.588 done. [2024-08-09T23:37:52.594Z] CL2 j> 2024/08/09 23:37:50.258 Writing report data ... [2024-08-09T23:38:30.613Z] CL2 j> 2024/08/09 23:38:26.432 done. [2024-08-09T23:38:39.856Z] CL2 j> 2024/08/09 23:38:37.672 Writing report data ... [2024-08-09T23:38:43.588Z] STF 23:38:41.224 - **FAILED** Process CL2 has timed out [2024-08-09T23:38:43.588Z] STF 23:38:41.224 - Collecting dumps for: CL2 [2024-08-09T23:38:43.588Z] STF 23:38:41.224 - Sending SIG 3 to the java process to generate a javacore [2024-08-09T23:38:43.588Z] STF 23:38:41.225 - Running command: kill -3 174354 [2024-08-09T23:38:43.588Z] STF 23:38:41.225 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/6.CL2.kill_3.stderr [2024-08-09T23:38:43.588Z] STF 23:38:41.225 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/results/6.CL2.kill_3.stdout [2024-08-09T23:38:43.588Z] STF 23:38:41.300 - Pausing for 30 seconds [2024-08-09T23:39:15.934Z] STF 23:39:11.301 - Process CL2 (pid 174354) is no longer running. Abandoning dump collection. [2024-08-09T23:39:15.934Z] CL2 2024-08-09 23:38:41 [2024-08-09T23:39:15.934Z] CL2 Full thread dump OpenJDK 64-Bit Server VM (21.0.5-beta+2-ea mixed mode, sharing): [2024-08-09T23:39:15.934Z] CL2 [2024-08-09T23:39:15.934Z] CL2 Threads class SMR info: [2024-08-09T23:39:15.934Z] CL2 _java_thread_list=0x0000fc78ec1a8ac0, length=14, elements={ [2024-08-09T23:39:15.934Z] CL2 0x0000fc78ec02b3d0, 0x0000fc78ec0dabf0, 0x0000fc78ec0dc1e0, 0x0000fc78ec0de1b0, [2024-08-09T23:39:15.935Z] CL2 0x0000fc78ec0df800, 0x0000fc78ec0e0db0, 0x0000fc78ec0e2890, 0x0000fc78ec0e3f60, [2024-08-09T23:39:15.935Z] CL2 0x0000fc78ec0efea0, 0x0000fc78ec0f2930, 0x0000fc78ec193b80, 0x0000fc78ec19b270, [2024-08-09T23:39:15.935Z] CL2 0x0000fc78ec19cfa0, 0x0000fc78ec1a7a00 [2024-08-09T23:39:15.935Z] CL2 } [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "main" #1 [174361] prio=5 os_prio=0 cpu=6758.34ms elapsed=1800.17s tid=0x0000fc78ec02b3d0 nid=174361 runnable [0x0000fc78f2d3d000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-09T23:39:15.935Z] CL2 at sun.nio.ch.Net.poll(java.base@21.0.5-beta/Native Method) [2024-08-09T23:39:15.935Z] CL2 at sun.nio.ch.NioSocketImpl.park(java.base@21.0.5-beta/NioSocketImpl.java:191) [2024-08-09T23:39:15.935Z] CL2 at sun.nio.ch.NioSocketImpl.park(java.base@21.0.5-beta/NioSocketImpl.java:201) [2024-08-09T23:39:15.935Z] CL2 at sun.nio.ch.NioSocketImpl.implRead(java.base@21.0.5-beta/NioSocketImpl.java:309) [2024-08-09T23:39:15.935Z] CL2 at sun.nio.ch.NioSocketImpl.read(java.base@21.0.5-beta/NioSocketImpl.java:346) [2024-08-09T23:39:15.935Z] CL2 at sun.nio.ch.NioSocketImpl$1.read(java.base@21.0.5-beta/NioSocketImpl.java:796) [2024-08-09T23:39:15.935Z] CL2 at java.net.Socket$SocketInputStream.read(java.base@21.0.5-beta/Socket.java:1099) [2024-08-09T23:39:15.935Z] CL2 at java.io.BufferedInputStream.fill(java.base@21.0.5-beta/BufferedInputStream.java:291) [2024-08-09T23:39:15.935Z] CL2 at java.io.BufferedInputStream.implRead(java.base@21.0.5-beta/BufferedInputStream.java:325) [2024-08-09T23:39:15.935Z] CL2 at java.io.BufferedInputStream.read(java.base@21.0.5-beta/BufferedInputStream.java:312) [2024-08-09T23:39:15.935Z] CL2 at java.io.DataInputStream.readUnsignedByte(java.base@21.0.5-beta/DataInputStream.java:295) [2024-08-09T23:39:15.935Z] CL2 at java.io.DataInputStream.readByte(java.base@21.0.5-beta/DataInputStream.java:275) [2024-08-09T23:39:15.935Z] CL2 at sun.rmi.transport.StreamRemoteCall.executeCall(java.rmi@21.0.5-beta/StreamRemoteCall.java:241) [2024-08-09T23:39:15.935Z] CL2 at sun.rmi.server.UnicastRef.invoke(java.rmi@21.0.5-beta/UnicastRef.java:166) [2024-08-09T23:39:15.935Z] CL2 at jdk.jmx.remote.internal.rmi.PRef.invoke(jdk.remoteref/Unknown Source) [2024-08-09T23:39:15.935Z] CL2 at javax.management.remote.rmi.RMIConnectionImpl_Stub.getMBeanInfo(java.management.rmi@21.0.5-beta/RMIConnectionImpl_Stub.java:381) [2024-08-09T23:39:15.935Z] CL2 at javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.getMBeanInfo(java.management.rmi@21.0.5-beta/RMIConnector.java:1079) [2024-08-09T23:39:15.935Z] CL2 at net.adoptopenjdk.test.jlm.resources.ThreadData.invokeMBeanOperation(ThreadData.java:1013) [2024-08-09T23:39:15.935Z] CL2 at net.adoptopenjdk.test.jlm.resources.ThreadData.writeMBeanInfo(ThreadData.java:886) [2024-08-09T23:39:15.935Z] CL2 at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:427) [2024-08-09T23:39:15.935Z] CL2 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-08-09T23:39:15.935Z] CL2 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "Reference Handler" #9 [174381] daemon prio=10 os_prio=0 cpu=2.85ms elapsed=1799.55s tid=0x0000fc78ec0dabf0 nid=174381 waiting on condition [0x0000fc78f03fe000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.Reference.waitForReferencePendingList(java.base@21.0.5-beta/Native Method) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.Reference.processPendingReferences(java.base@21.0.5-beta/Reference.java:246) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.Reference$ReferenceHandler.run(java.base@21.0.5-beta/Reference.java:208) [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "Finalizer" #10 [174382] daemon prio=8 os_prio=0 cpu=0.48ms elapsed=1799.54s tid=0x0000fc78ec0dc1e0 nid=174382 in Object.wait() [0x0000fc78f0200000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: WAITING (on object monitor) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Object.wait0(java.base@21.0.5-beta/Native Method) [2024-08-09T23:39:15.935Z] CL2 - waiting on <0x0000fc78c4956b00> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Object.wait(java.base@21.0.5-beta/Object.java:366) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Object.wait(java.base@21.0.5-beta/Object.java:339) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.NativeReferenceQueue.await(java.base@21.0.5-beta/NativeReferenceQueue.java:48) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.5-beta/ReferenceQueue.java:158) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.NativeReferenceQueue.remove(java.base@21.0.5-beta/NativeReferenceQueue.java:89) [2024-08-09T23:39:15.935Z] CL2 - locked <0x0000fc78c4956b00> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.Finalizer$FinalizerThread.run(java.base@21.0.5-beta/Finalizer.java:173) [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "Signal Dispatcher" #11 [174383] daemon prio=9 os_prio=0 cpu=0.65ms elapsed=1799.54s tid=0x0000fc78ec0de1b0 nid=174383 waiting on condition [0x0000000000000000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "Service Thread" #12 [174384] daemon prio=9 os_prio=0 cpu=7.03ms elapsed=1799.53s tid=0x0000fc78ec0df800 nid=174384 runnable [0x0000000000000000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "Monitor Deflation Thread" #13 [174385] daemon prio=9 os_prio=0 cpu=318.64ms elapsed=1799.53s tid=0x0000fc78ec0e0db0 nid=174385 runnable [0x0000000000000000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "C2 CompilerThread0" #14 [174386] daemon prio=9 os_prio=0 cpu=5728.38ms elapsed=1799.53s tid=0x0000fc78ec0e2890 nid=174386 waiting on condition [0x0000000000000000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-09T23:39:15.935Z] CL2 No compile task [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "C1 CompilerThread0" #16 [174387] daemon prio=9 os_prio=0 cpu=1109.85ms elapsed=1799.53s tid=0x0000fc78ec0e3f60 nid=174387 waiting on condition [0x0000000000000000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-09T23:39:15.935Z] CL2 No compile task [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "Notification Thread" #17 [174388] daemon prio=9 os_prio=0 cpu=0.29ms elapsed=1799.48s tid=0x0000fc78ec0efea0 nid=174388 runnable [0x0000000000000000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "Common-Cleaner" #18 [174389] daemon prio=8 os_prio=0 cpu=4.44ms elapsed=1799.44s tid=0x0000fc78ec0f2930 nid=174389 waiting on condition [0x0000fc78bdc01000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-08-09T23:39:15.935Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.5-beta/Native Method) [2024-08-09T23:39:15.935Z] CL2 - parking to wait for <0x0000fc78c4956c88> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.5-beta/LockSupport.java:269) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.5-beta/AbstractQueuedSynchronizer.java:1852) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.ReferenceQueue.await(java.base@21.0.5-beta/ReferenceQueue.java:71) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.5-beta/ReferenceQueue.java:143) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.5-beta/ReferenceQueue.java:218) [2024-08-09T23:39:15.935Z] CL2 at jdk.internal.ref.CleanerImpl.run(java.base@21.0.5-beta/CleanerImpl.java:140) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-09T23:39:15.935Z] CL2 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.5-beta/InnocuousThread.java:186) [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "RMI Scheduler(0)" #20 [174410] daemon prio=5 os_prio=0 cpu=30.92ms elapsed=1789.14s tid=0x0000fc78ec193b80 nid=174410 waiting on condition [0x0000fc78bda03000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-08-09T23:39:15.935Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.5-beta/Native Method) [2024-08-09T23:39:15.935Z] CL2 - parking to wait for <0x0000fc78c4956e08> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.5-beta/LockSupport.java:269) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(java.base@21.0.5-beta/AbstractQueuedSynchronizer.java:1763) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.5-beta/ScheduledThreadPoolExecutor.java:1182) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.5-beta/ScheduledThreadPoolExecutor.java:899) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@21.0.5-beta/ThreadPoolExecutor.java:1070) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@21.0.5-beta/ThreadPoolExecutor.java:1130) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@21.0.5-beta/ThreadPoolExecutor.java:642) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "RMI RenewClean-[172.17.0.14:43459]" #21 [174411] daemon prio=5 os_prio=0 cpu=12.07ms elapsed=1788.91s tid=0x0000fc78ec19b270 nid=174411 waiting on condition [0x0000fc78bd805000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-08-09T23:39:15.935Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.5-beta/Native Method) [2024-08-09T23:39:15.935Z] CL2 - parking to wait for <0x0000fc78c4957260> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.5-beta/LockSupport.java:269) [2024-08-09T23:39:15.935Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.5-beta/AbstractQueuedSynchronizer.java:1852) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.ReferenceQueue.await(java.base@21.0.5-beta/ReferenceQueue.java:71) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.5-beta/ReferenceQueue.java:143) [2024-08-09T23:39:15.935Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.5-beta/ReferenceQueue.java:218) [2024-08-09T23:39:15.935Z] CL2 at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(java.rmi@21.0.5-beta/DGCClient.java:567) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "RMI GC Daemon" #22 [174412] daemon prio=2 os_prio=0 cpu=0.62ms elapsed=1788.86s tid=0x0000fc78ec19cfa0 nid=174412 in Object.wait() [0x0000fc78bd608000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Object.wait0(java.base@21.0.5-beta/Native Method) [2024-08-09T23:39:15.935Z] CL2 - waiting on <0x0000fc78c49574d0> (a sun.rmi.transport.GC$LatencyLock) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Object.wait(java.base@21.0.5-beta/Object.java:366) [2024-08-09T23:39:15.935Z] CL2 at sun.rmi.transport.GC$Daemon.run(java.rmi@21.0.5-beta/GC.java:127) [2024-08-09T23:39:15.935Z] CL2 - locked <0x0000fc78c49574d0> (a sun.rmi.transport.GC$LatencyLock) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-09T23:39:15.935Z] CL2 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.5-beta/InnocuousThread.java:186) [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "JMX client heartbeat 2" #23 [174417] daemon prio=5 os_prio=0 cpu=33.86ms elapsed=1787.55s tid=0x0000fc78ec1a7a00 nid=174417 waiting on condition [0x0000fc78bd40a000] [2024-08-09T23:39:15.935Z] CL2 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.sleep0(java.base@21.0.5-beta/Native Method) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.sleep(java.base@21.0.5-beta/Thread.java:509) [2024-08-09T23:39:15.935Z] CL2 at com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker.run(java.management@21.0.5-beta/ClientCommunicatorAdmin.java:180) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-09T23:39:15.935Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "GC Thread#3" os_prio=0 cpu=12.21ms elapsed=1752.79s tid=0x0000fc789800a530 nid=174474 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "GC Thread#2" os_prio=0 cpu=12.47ms elapsed=1752.81s tid=0x0000fc7898009ae0 nid=174473 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "GC Thread#1" os_prio=0 cpu=26.90ms elapsed=1753.29s tid=0x0000fc7898007110 nid=174472 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "VM Thread" os_prio=0 cpu=131.10ms elapsed=1799.66s tid=0x0000fc78ec0cf3f0 nid=174379 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "VM Periodic Task Thread" os_prio=0 cpu=2113.87ms elapsed=1799.72s tid=0x0000fc78ec0bd860 nid=174378 waiting on condition [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "G1 Service" os_prio=0 cpu=96.72ms elapsed=1800.02s tid=0x0000fc78ec0a54b0 nid=174370 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "G1 Refine#0" os_prio=0 cpu=197.40ms elapsed=1800.05s tid=0x0000fc78ec0a44e0 nid=174369 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "G1 Conc#0" os_prio=0 cpu=0.09ms elapsed=1800.05s tid=0x0000fc78ec053170 nid=174368 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "G1 Main Marker" os_prio=0 cpu=0.30ms elapsed=1800.10s tid=0x0000fc78ec0521c0 nid=174366 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 "GC Thread#0" os_prio=0 cpu=24.29ms elapsed=1800.14s tid=0x0000fc78ec04ed30 nid=174363 runnable [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 JNI global refs: 17, weak refs: 0 [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 Heap [2024-08-09T23:39:15.935Z] CL2 garbage-first heap total 100352K, used 46491K [0x0000fc78c4800000, 0x0000fc78d4800000) [2024-08-09T23:39:15.935Z] CL2 region size 1024K, 41 young (41984K), 1 survivors (1024K) [2024-08-09T23:39:15.935Z] CL2 Metaspace used 6536K, committed 6976K, reserved 1114112K [2024-08-09T23:39:15.935Z] CL2 class space used 617K, committed 832K, reserved 1048576K [2024-08-09T23:39:15.935Z] CL2 [2024-08-09T23:39:15.935Z] CL2 j> 2024/08/09 23:38:55.287 Error unmarshaling return header; nested exception is: [2024-08-09T23:39:15.935Z] CL2 java.net.SocketException: Connection reset [2024-08-09T23:39:15.935Z] CL2 stderr java.rmi.UnmarshalException: Error unmarshaling return header; nested exception is: [2024-08-09T23:39:15.935Z] CL2 stderr java.net.SocketException: Connection reset [2024-08-09T23:39:15.935Z] CL2 stderr at java.rmi/sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:255) [2024-08-09T23:39:15.935Z] CL2 stderr at java.rmi/sun.rmi.server.UnicastRef.invoke(UnicastRef.java:166) [2024-08-09T23:39:15.935Z] CL2 stderr at jdk.remoteref/jdk.jmx.remote.internal.rmi.PRef.invoke(Unknown Source) [2024-08-09T23:39:15.935Z] CL2 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnectionImpl_Stub.invoke(RMIConnectionImpl_Stub.java:419) [2024-08-09T23:39:15.935Z] CL2 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.invoke(RMIConnector.java:1020) [2024-08-09T23:39:15.935Z] CL2 stderr at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:549) [2024-08-09T23:39:15.935Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-08-09T23:39:15.935Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-08-09T23:39:15.935Z] CL2 stderr Caused by: java.net.SocketException: Connection reset [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:318) [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/java.io.DataInputStream.readUnsignedByte(DataInputStream.java:295) [2024-08-09T23:39:15.935Z] CL2 stderr at java.base/java.io.DataInputStream.readByte(DataInputStream.java:275) [2024-08-09T23:39:15.935Z] CL2 stderr at java.rmi/sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:241) [2024-08-09T23:39:15.935Z] CL2 stderr ... 7 more [2024-08-09T23:39:15.935Z] CL2 stderr Exception in thread "main" java.lang.AssertionError: Error unmarshaling return header; nested exception is: [2024-08-09T23:39:15.935Z] CL2 stderr java.net.SocketException: Connection reset [2024-08-09T23:39:15.935Z] CL2 stderr at org.junit.Assert.fail(Assert.java:88) [2024-08-09T23:39:15.935Z] CL2 stderr at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:727) [2024-08-09T23:39:15.935Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-08-09T23:39:15.935Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-08-09T23:39:15.935Z] STF 23:39:11.305 - Monitoring Report Summary: [2024-08-09T23:39:15.935Z] STF 23:39:11.305 - o Process CL2 has timed out [2024-08-09T23:39:15.935Z] STF 23:39:11.305 - o Process LT2 ended sucessfully [2024-08-09T23:39:15.935Z] STF 23:39:11.313 - Killing processes: CL2 LT2 [2024-08-09T23:39:15.935Z] STF 23:39:11.314 - o Process LT2 pid 174353 is not running [2024-08-09T23:39:15.935Z] STF 23:39:11.314 - o Process CL2 pid 174354 is not running [2024-08-09T23:39:15.935Z] **FAILED** at step 7 (Wait for processes to complete). Expected return value=0 Actual=1 at /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/execute.pl line 296. [2024-08-09T23:39:15.935Z] STF 23:39:11.645 - **FAILED** execute script failed. Expected return value=0 Actual=1 [2024-08-09T23:39:15.935Z] STF 23:39:11.645 - [2024-08-09T23:39:15.935Z] STF 23:39:11.645 - ==================== T E A R D O W N ==================== [2024-08-09T23:39:15.936Z] STF 23:39:11.645 - Running teardown: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_testList_0/aqa-tests/TKG/../TKG/output_17232344389902/TestJlmRemoteThreadNoAuth_1/20240809-224924-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-08-09T23:39:15.936Z] STF 23:39:12.021 - TEARDOWN stage completed [2024-08-09T23:39:15.936Z] STF 23:39:12.036 - [2024-08-09T23:39:15.936Z] STF 23:39:12.043 - ===================== R E S U L T S ===================== [2024-08-09T23:39:15.936Z] STF 23:39:12.043 - Stage results: [2024-08-09T23:39:15.936Z] STF 23:39:12.043 - setUp: pass [2024-08-09T23:39:15.936Z] STF 23:39:12.045 - execute: *fail* [2024-08-09T23:39:15.936Z] STF 23:39:12.046 - teardown: pass [2024-08-09T23:39:15.936Z] STF 23:39:12.050 - [2024-08-09T23:39:15.936Z] STF 23:39:12.060 - Overall result: **FAILED** [2024-08-09T23:39:15.936Z] ----------------------------------- [2024-08-09T23:39:15.936Z] TestJlmRemoteThreadNoAuth_1_FAILED [2024-08-09T23:39:15.936Z] ----------------------------------- [2024-08-09T23:39:15.936Z] [2024-08-09T23:39:15.936Z] TEST TEARDOWN: [2024-08-09T23:39:15.936Z] Nothing to be done for teardown. [2024-08-09T23:39:15.936Z] TestJlmRemoteThreadNoAuth_1 Finish Time: Fri Aug 9 23:39:12 2024 Epoch Time (ms): 1723246752191