[2024-08-10T00:54:40.249Z] Running test TestJlmRemoteThreadNoAuth_1 ... [2024-08-10T00:54:40.249Z] =============================================== [2024-08-10T00:54:40.249Z] TestJlmRemoteThreadNoAuth_1 Start Time: Sat Aug 10 00:54:38 2024 Epoch Time (ms): 1723251278989 [2024-08-10T00:54:40.249Z] variation: Mode650 [2024-08-10T00:54:40.249Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-08-10T00:54:40.249Z] { \ [2024-08-10T00:54:40.249Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T00:54:40.249Z] echo "Nothing to be done for setup."; \ [2024-08-10T00:54:40.249Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1"; \ [2024-08-10T00:54:40.249Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1"; \ [2024-08-10T00:54:40.249Z] echo ""; echo "TESTING:"; \ [2024-08-10T00:54:40.249Z] perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/../../testDependency/system_lib/" -java-args=' -XX:-UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1" -test=TestJlmRemoteThreadNoAuth; \ [2024-08-10T00:54:40.249Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T00:54:40.249Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T00:54:40.249Z] echo "Nothing to be done for teardown."; \ [2024-08-10T00:54:40.249Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestTargetResult"; [2024-08-10T00:54:40.249Z] [2024-08-10T00:54:40.249Z] TEST SETUP: [2024-08-10T00:54:40.249Z] Nothing to be done for setup. [2024-08-10T00:54:40.249Z] [2024-08-10T00:54:40.249Z] TESTING: [2024-08-10T00:54:41.031Z] STF 00:54:39.389 - ========================= S T F ========================= [2024-08-10T00:54:41.032Z] 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_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1 [2024-08-10T00:54:41.032Z] There is 220260 Mb free [2024-08-10T00:54:41.032Z] STF 00:54:39.431 - ================== G E N E R A T I O N ================== [2024-08-10T00:54:41.032Z] STF 00:54:39.432 - Checking JVM: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jdkbinary/j2sdk-image [2024-08-10T00:54:41.032Z] STF 00:54:39.433 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/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_rerun/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth" [2024-08-10T00:54:41.032Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-10T00:54:41.032Z] GEN [0.108s][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-10T00:54:49.658Z] GEN 00:54:48.279 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-08-10T00:54:50.441Z] GEN 00:54:48.463 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-08-10T00:54:51.246Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-08-10T00:54:51.246Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-08-10T00:54:51.246Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/STF/stf.core/bin [2024-08-10T00:54:51.246Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-08-10T00:54:51.246Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-08-10T00:54:51.246Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-08-10T00:54:51.246Z] GEN 00:54:49.717 - Using Mode NoOptions. Values = '' [2024-08-10T00:54:52.022Z] GEN 00:54:50.648 - [2024-08-10T00:54:52.022Z] GEN 00:54:50.648 - Test command summary: [2024-08-10T00:54:52.022Z] GEN 00:54:50.664 - Step Stage Command Description [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - -----+--------+-----------------+------------ [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - 3 execute Monitor Wait for processes to complete [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - 4 execute kill Stop LT1 process [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - 7 execute Monitor Wait for processes to complete [2024-08-10T00:54:52.022Z] GEN 00:54:50.665 - 8 execute kill Stop LT2 process [2024-08-10T00:54:52.811Z] STF 00:54:50.884 - [2024-08-10T00:54:52.811Z] STF 00:54:50.884 - Script generation completed [2024-08-10T00:54:52.811Z] STF 00:54:50.884 - [2024-08-10T00:54:52.811Z] STF 00:54:50.890 - [2024-08-10T00:54:52.811Z] STF 00:54:50.890 - ======================= S E T U P ======================= [2024-08-10T00:54:52.811Z] STF 00:54:50.890 - Running setup: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/setUp.pl [2024-08-10T00:54:52.811Z] STF 00:54:51.343 - SETUP stage completed [2024-08-10T00:54:52.811Z] STF 00:54:51.386 - [2024-08-10T00:54:52.811Z] STF 00:54:51.386 - ==================== E X E C U T E - ==================== [2024-08-10T00:54:52.811Z] STF 00:54:51.386 - Running execute: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/execute.pl [2024-08-10T00:54:53.648Z] STF 00:54:51.780 - [2024-08-10T00:54:53.648Z] STF 00:54:51.781 - Java version [2024-08-10T00:54:53.648Z] STF 00:54:51.781 - Running: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jdkbinary/j2sdk-image/bin/java -version [2024-08-10T00:54:53.648Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-10T00:54:53.648Z] openjdk version "21.0.5-beta" 2024-10-15 [2024-08-10T00:54:53.648Z] OpenJDK Runtime Environment Temurin-21.0.5+2-202408091902 (build 21.0.5-beta+2-ea) [2024-08-10T00:54:53.648Z] OpenJDK 64-Bit Server VM Temurin-21.0.5+2-202408091902 (build 21.0.5-beta+2-ea, mixed mode, sharing) [2024-08-10T00:54:54.464Z] STF 00:54:52.526 - [2024-08-10T00:54:54.464Z] STF 00:54:52.526 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-08-10T00:54:54.464Z] STF 00:54:52.526 - | Start background process [2024-08-10T00:54:54.464Z] STF 00:54:52.526 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jdkbinary/j2sdk-image/bin/java [2024-08-10T00:54:54.464Z] STF 00:54:52.526 - | Mnemonic: LT1 [2024-08-10T00:54:54.464Z] STF 00:54:52.526 - | Echo: ECHO_OFF [2024-08-10T00:54:54.464Z] STF 00:54:52.526 - | Expectation: NEVER [2024-08-10T00:54:54.464Z] STF 00:54:52.526 - | [2024-08-10T00:54:54.464Z] STF 00:54:52.527 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/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_rerun/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/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_rerun/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-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_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-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_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-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_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-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-10T00:54:54.464Z] STF 00:54:52.527 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-08-10T00:54:54.464Z] STF 00:54:52.527 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-08-10T00:54:54.464Z] STF 00:54:52.610 - [2024-08-10T00:54:54.464Z] STF 00:54:52.610 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-08-10T00:54:54.464Z] STF 00:54:52.610 - | Start background process [2024-08-10T00:54:54.464Z] STF 00:54:52.610 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jdkbinary/j2sdk-image/bin/java [2024-08-10T00:54:54.464Z] STF 00:54:52.610 - | Mnemonic: CL1 [2024-08-10T00:54:54.464Z] STF 00:54:52.610 - | Echo: ECHO_ON [2024-08-10T00:54:54.464Z] STF 00:54:52.610 - | Expectation: CLEAN_RUN within 30m [2024-08-10T00:54:54.464Z] STF 00:54:52.610 - | [2024-08-10T00:54:54.464Z] STF 00:54:52.617 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/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_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-10T00:54:54.464Z] STF 00:54:52.617 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-08-10T00:54:54.464Z] STF 00:54:52.617 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-08-10T00:54:54.464Z] STF 00:54:52.650 - [2024-08-10T00:54:54.464Z] STF 00:54:52.650 - +------ Step 3 - Wait for processes to complete [2024-08-10T00:54:54.465Z] STF 00:54:52.650 - | Wait for processes to meet expectations [2024-08-10T00:54:54.465Z] STF 00:54:52.650 - | Processes: [LT1, CL1] [2024-08-10T00:54:54.465Z] STF 00:54:52.650 - | [2024-08-10T00:54:54.465Z] STF 00:54:52.650 - Monitoring processes: CL1 LT1 [2024-08-10T00:54:54.465Z] CL1 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-10T00:54:58.135Z] CL1 j> 2024/08/10 00:54:54.532 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-10T00:54:58.135Z] CL1 j> 2024/08/10 00:54:56.017 Attempting to connect [2024-08-10T00:54:59.761Z] CL1 j> 2024/08/10 00:54:57.744 Monitored VM not ready at Aug 10, 2024, 12:54:57?AM (attempt 1, elapsed 1330ms). [2024-08-10T00:54:59.762Z] CL1 j> 2024/08/10 00:54:57.744 Waiting 5 secs and trying again... [2024-08-10T00:55:04.339Z] CL1 j> 2024/08/10 00:55:02.755 Attempting to connect [2024-08-10T00:55:07.012Z] CL1 j> 2024/08/10 00:55:04.870 Connection established! [2024-08-10T00:55:08.754Z] CL1 j> 2024/08/10 00:55:06.458 Starting to write data [2024-08-10T01:00:07.809Z] STF 00:59:51.438 - Heartbeat: Process LT1 is still running [2024-08-10T01:05:06.676Z] STF 01:04:51.263 - Heartbeat: Process LT1 is still running [2024-08-10T01:10:05.087Z] STF 01:09:51.222 - Heartbeat: Process LT1 is still running [2024-08-10T01:11:40.762Z] STF 01:11:31.853 - Monitoring Report Summary: [2024-08-10T01:11:40.762Z] STF 01:11:31.853 - o Process CL1 ended with the expected exit code (0) [2024-08-10T01:11:40.762Z] STF 01:11:31.853 - o Process LT1 is still running as expected [2024-08-10T01:11:40.762Z] STF 01:11:31.854 - [2024-08-10T01:11:40.762Z] STF 01:11:31.854 - +------ Step 4 - Stop LT1 process [2024-08-10T01:11:40.762Z] STF 01:11:31.854 - | Kill running processes [2024-08-10T01:11:40.762Z] STF 01:11:31.854 - | Processes: [LT1] [2024-08-10T01:11:40.762Z] STF 01:11:31.854 - | [2024-08-10T01:11:40.762Z] STF 01:11:31.854 - Killing processes: LT1 [2024-08-10T01:11:40.762Z] STF 01:11:31.854 - o Process clean up attempt 1 for LT1 pid 182943 [2024-08-10T01:11:40.762Z] STF 01:11:31.854 - o Process LT1 pid 182943 stop() [2024-08-10T01:11:43.439Z] STF 01:11:41.924 - o Process LT1 pid 182943 terminate() [2024-08-10T01:11:45.116Z] STF 01:11:42.930 - o Process LT1 pid 182943 killed [2024-08-10T01:11:45.116Z] STF 01:11:42.930 - [2024-08-10T01:11:45.116Z] STF 01:11:42.930 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-08-10T01:11:45.116Z] STF 01:11:42.930 - | Start background process [2024-08-10T01:11:45.116Z] STF 01:11:42.930 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jdkbinary/j2sdk-image/bin/java [2024-08-10T01:11:45.116Z] STF 01:11:42.930 - | Mnemonic: LT2 [2024-08-10T01:11:45.116Z] STF 01:11:42.931 - | Echo: ECHO_OFF [2024-08-10T01:11:45.116Z] STF 01:11:42.931 - | Expectation: NEVER [2024-08-10T01:11:45.116Z] STF 01:11:42.931 - | [2024-08-10T01:11:45.116Z] STF 01:11:42.933 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/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_rerun/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/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_rerun/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-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_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-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_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-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_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-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-10T01:11:45.116Z] STF 01:11:42.933 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-08-10T01:11:45.116Z] STF 01:11:42.933 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-08-10T01:11:45.116Z] STF 01:11:42.935 - [2024-08-10T01:11:45.116Z] STF 01:11:42.935 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-08-10T01:11:45.116Z] STF 01:11:42.935 - | Start background process [2024-08-10T01:11:45.116Z] STF 01:11:42.935 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jdkbinary/j2sdk-image/bin/java [2024-08-10T01:11:45.116Z] STF 01:11:42.935 - | Mnemonic: CL2 [2024-08-10T01:11:45.116Z] STF 01:11:42.935 - | Echo: ECHO_ON [2024-08-10T01:11:45.116Z] STF 01:11:42.935 - | Expectation: CLEAN_RUN within 30m [2024-08-10T01:11:45.116Z] STF 01:11:42.936 - | [2024-08-10T01:11:45.116Z] STF 01:11:42.959 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/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_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-10T01:11:45.116Z] STF 01:11:42.975 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-08-10T01:11:45.116Z] STF 01:11:42.975 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-08-10T01:11:45.116Z] STF 01:11:42.983 - [2024-08-10T01:11:45.116Z] STF 01:11:43.002 - +------ Step 7 - Wait for processes to complete [2024-08-10T01:11:45.116Z] STF 01:11:43.002 - | Wait for processes to meet expectations [2024-08-10T01:11:45.116Z] STF 01:11:43.002 - | Processes: [LT2, CL2] [2024-08-10T01:11:45.116Z] STF 01:11:43.002 - | [2024-08-10T01:11:45.116Z] STF 01:11:43.003 - Monitoring processes: CL2 LT2 [2024-08-10T01:11:45.116Z] CL2 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-08-10T01:11:47.746Z] CL2 j> 2024/08/10 01:11:44.642 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-10T01:11:47.746Z] CL2 j> 2024/08/10 01:11:45.563 Attempting to connect [2024-08-10T01:11:49.395Z] CL2 j> 2024/08/10 01:11:47.358 Monitored VM not ready at Aug 10, 2024, 1:11:47?AM (attempt 1, elapsed 1447ms). [2024-08-10T01:11:49.395Z] CL2 j> 2024/08/10 01:11:47.402 Waiting 5 secs and trying again... [2024-08-10T01:11:53.967Z] CL2 j> 2024/08/10 01:11:52.403 Attempting to connect [2024-08-10T01:11:56.529Z] CL2 j> 2024/08/10 01:11:54.514 Connection established! [2024-08-10T01:12:20.387Z] CL2 j> 2024/08/10 01:12:18.124 Writing report data ... [2024-08-10T01:13:57.370Z] CL2 j> 2024/08/10 01:13:48.451 done. [2024-08-10T01:14:03.831Z] CL2 j> 2024/08/10 01:14:01.481 Writing report data ... [2024-08-10T01:14:55.078Z] STF 01:14:51.136 - Heartbeat: Process LT2 is still running [2024-08-10T01:15:12.399Z] CL2 j> 2024/08/10 01:15:09.673 done. [2024-08-10T01:15:25.642Z] CL2 j> 2024/08/10 01:15:22.527 Writing report data ... [2024-08-10T01:16:36.101Z] CL2 j> 2024/08/10 01:16:25.355 done. [2024-08-10T01:16:39.869Z] CL2 j> 2024/08/10 01:16:37.123 Writing report data ... [2024-08-10T01:17:39.327Z] CL2 j> 2024/08/10 01:17:35.305 done. [2024-08-10T01:17:50.181Z] CL2 j> 2024/08/10 01:17:48.516 Writing report data ... [2024-08-10T01:18:59.954Z] CL2 j> 2024/08/10 01:18:50.360 done. [2024-08-10T01:19:06.324Z] CL2 j> 2024/08/10 01:19:03.526 Writing report data ... [2024-08-10T01:19:57.042Z] STF 01:19:51.018 - Heartbeat: Process LT2 is still running [2024-08-10T01:20:03.620Z] CL2 j> 2024/08/10 01:20:00.902 done. [2024-08-10T01:20:15.161Z] CL2 j> 2024/08/10 01:20:12.949 Writing report data ... [2024-08-10T01:21:16.633Z] CL2 j> 2024/08/10 01:21:07.157 done. [2024-08-10T01:21:22.369Z] CL2 j> 2024/08/10 01:21:19.311 Writing report data ... [2024-08-10T01:22:16.229Z] CL2 j> 2024/08/10 01:22:13.527 done. [2024-08-10T01:22:28.063Z] CL2 j> 2024/08/10 01:22:25.179 Writing report data ... [2024-08-10T01:23:21.627Z] CL2 j> 2024/08/10 01:23:12.860 done. [2024-08-10T01:23:25.822Z] CL2 j> 2024/08/10 01:23:24.045 Writing report data ... [2024-08-10T01:24:18.504Z] CL2 j> 2024/08/10 01:24:12.022 done. [2024-08-10T01:24:27.141Z] CL2 j> 2024/08/10 01:24:24.095 Writing report data ... [2024-08-10T01:24:56.343Z] STF 01:24:51.026 - Heartbeat: Process LT2 is still running [2024-08-10T01:25:14.768Z] CL2 j> 2024/08/10 01:25:12.524 done. [2024-08-10T01:25:26.930Z] CL2 j> 2024/08/10 01:25:23.736 Writing report data ... [2024-08-10T01:26:11.610Z] CL2 j> 2024/08/10 01:26:05.592 done. [2024-08-10T01:26:18.603Z] CL2 j> 2024/08/10 01:26:17.072 Writing report data ... [2024-08-10T01:27:12.252Z] CL2 j> 2024/08/10 01:27:08.378 done. [2024-08-10T01:27:22.624Z] CL2 j> 2024/08/10 01:27:19.865 Writing report data ... [2024-08-10T01:28:07.489Z] CL2 j> 2024/08/10 01:28:02.671 done. [2024-08-10T01:28:16.392Z] CL2 j> 2024/08/10 01:28:13.958 Writing report data ... [2024-08-10T01:29:09.707Z] CL2 j> 2024/08/10 01:29:06.626 done. [2024-08-10T01:29:19.588Z] CL2 j> 2024/08/10 01:29:17.891 Writing report data ... [2024-08-10T01:29:53.986Z] STF 01:29:51.452 - Heartbeat: Process LT2 is still running [2024-08-10T01:30:05.773Z] CL2 j> 2024/08/10 01:30:03.212 done. [2024-08-10T01:30:17.439Z] CL2 j> 2024/08/10 01:30:15.169 Writing report data ... [2024-08-10T01:31:10.665Z] CL2 j> 2024/08/10 01:31:01.928 done. [2024-08-10T01:31:14.826Z] CL2 j> 2024/08/10 01:31:12.842 Writing report data ... [2024-08-10T01:31:53.362Z] CL2 j> 2024/08/10 01:31:48.785 done. [2024-08-10T01:32:01.863Z] CL2 j> 2024/08/10 01:31:59.476 Writing report data ... [2024-08-10T01:32:46.174Z] CL2 j> 2024/08/10 01:32:42.304 done. [2024-08-10T01:32:55.593Z] CL2 j> 2024/08/10 01:32:53.131 Writing report data ... [2024-08-10T01:33:39.373Z] CL2 j> 2024/08/10 01:33:33.541 done. [2024-08-10T01:33:47.861Z] CL2 j> 2024/08/10 01:33:44.539 Writing report data ... [2024-08-10T01:34:40.625Z] CL2 j> 2024/08/10 01:34:31.540 done. [2024-08-10T01:34:45.512Z] CL2 j> 2024/08/10 01:34:41.901 Writing report data ... [2024-08-10T01:34:54.402Z] STF 01:34:51.103 - Heartbeat: Process LT2 is still running [2024-08-10T01:35:28.940Z] CL2 j> 2024/08/10 01:35:26.635 done. [2024-08-10T01:35:39.670Z] CL2 j> 2024/08/10 01:35:37.269 Writing report data ... [2024-08-10T01:36:31.489Z] CL2 j> 2024/08/10 01:36:23.418 done. [2024-08-10T01:36:36.662Z] CL2 j> 2024/08/10 01:36:34.668 Writing report data ... [2024-08-10T01:37:28.125Z] CL2 j> 2024/08/10 01:37:19.557 done. [2024-08-10T01:37:32.290Z] CL2 j> 2024/08/10 01:37:30.279 Writing report data ... [2024-08-10T01:38:10.794Z] CL2 j> 2024/08/10 01:38:07.817 done. [2024-08-10T01:38:21.242Z] CL2 j> 2024/08/10 01:38:18.989 Writing report data ... [2024-08-10T01:39:00.070Z] CL2 j> 2024/08/10 01:38:55.014 done. [2024-08-10T01:39:08.522Z] CL2 j> 2024/08/10 01:39:06.696 Writing report data ... [2024-08-10T01:39:53.231Z] CL2 j> 2024/08/10 01:39:48.820 done. [2024-08-10T01:39:53.231Z] STF 01:39:51.432 - Heartbeat: Process LT2 is still running [2024-08-10T01:40:02.871Z] CL2 j> 2024/08/10 01:40:00.152 Writing report data ... [2024-08-10T01:40:46.739Z] CL2 j> 2024/08/10 01:40:43.366 done. [2024-08-10T01:40:56.463Z] CL2 j> 2024/08/10 01:40:54.052 Writing report data ... [2024-08-10T01:41:41.347Z] CL2 j> 2024/08/10 01:41:33.430 done. [2024-08-10T01:41:45.417Z] STF 01:41:43.396 - **FAILED** Process CL2 has timed out [2024-08-10T01:41:45.417Z] STF 01:41:43.396 - Collecting dumps for: CL2 [2024-08-10T01:41:45.417Z] STF 01:41:43.396 - Sending SIG 3 to the java process to generate a javacore [2024-08-10T01:41:45.417Z] STF 01:41:43.396 - Running command: kill -3 183701 [2024-08-10T01:41:45.417Z] STF 01:41:43.396 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/6.CL2.kill_3.stderr [2024-08-10T01:41:45.417Z] STF 01:41:43.396 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/results/6.CL2.kill_3.stdout [2024-08-10T01:41:45.417Z] STF 01:41:43.453 - Pausing for 30 seconds [2024-08-10T01:42:18.184Z] STF 01:42:13.455 - Process CL2 (pid 183701) is no longer running. Abandoning dump collection. [2024-08-10T01:42:18.184Z] CL2 2024-08-10 01:41:43 [2024-08-10T01:42:18.184Z] CL2 Full thread dump OpenJDK 64-Bit Server VM (21.0.5-beta+2-ea mixed mode, sharing): [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 Threads class SMR info: [2024-08-10T01:42:18.184Z] CL2 _java_thread_list=0x0000e3b8e00040d0, length=14, elements={ [2024-08-10T01:42:18.184Z] CL2 0x0000e3b99c02b0c0, 0x0000e3b99c0d76c0, 0x0000e3b99c0d8cb0, 0x0000e3b99c0da580, [2024-08-10T01:42:18.184Z] CL2 0x0000e3b99c0dbbd0, 0x0000e3b99c0dd180, 0x0000e3b99c0debf0, 0x0000e3b99c0e02c0, [2024-08-10T01:42:18.184Z] CL2 0x0000e3b99c1041a0, 0x0000e3b99c106fa0, 0x0000e3b99c141900, 0x0000e3b99c0f6610, [2024-08-10T01:42:18.184Z] CL2 0x0000e3b99c0f8290, 0x0000e3b99c0efb60 [2024-08-10T01:42:18.184Z] CL2 } [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "main" #1 [183702] prio=5 os_prio=0 cpu=6716.57ms elapsed=1800.40s tid=0x0000e3b99c02b0c0 nid=183702 waiting on condition [0x0000e3b9a3a53000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-08-10T01:42:18.184Z] CL2 at java.lang.Thread.sleep0(java.base@21.0.5-beta/Native Method) [2024-08-10T01:42:18.184Z] CL2 at java.lang.Thread.sleep(java.base@21.0.5-beta/Thread.java:509) [2024-08-10T01:42:18.184Z] CL2 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:205) [2024-08-10T01:42:18.184Z] CL2 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "Reference Handler" #9 [183716] daemon prio=10 os_prio=0 cpu=2.41ms elapsed=1799.82s tid=0x0000e3b99c0d76c0 nid=183716 waiting on condition [0x0000e3b96fffe000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.Reference.waitForReferencePendingList(java.base@21.0.5-beta/Native Method) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.Reference.processPendingReferences(java.base@21.0.5-beta/Reference.java:246) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.Reference$ReferenceHandler.run(java.base@21.0.5-beta/Reference.java:208) [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "Finalizer" #10 [183717] daemon prio=8 os_prio=0 cpu=0.45ms elapsed=1799.81s tid=0x0000e3b99c0d8cb0 nid=183717 in Object.wait() [0x0000e3b96fe00000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: WAITING (on object monitor) [2024-08-10T01:42:18.184Z] CL2 at java.lang.Object.wait0(java.base@21.0.5-beta/Native Method) [2024-08-10T01:42:18.184Z] CL2 - waiting on <0x0000e3b974971540> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-08-10T01:42:18.184Z] CL2 at java.lang.Object.wait(java.base@21.0.5-beta/Object.java:366) [2024-08-10T01:42:18.184Z] CL2 at java.lang.Object.wait(java.base@21.0.5-beta/Object.java:339) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.NativeReferenceQueue.await(java.base@21.0.5-beta/NativeReferenceQueue.java:48) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.5-beta/ReferenceQueue.java:158) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.NativeReferenceQueue.remove(java.base@21.0.5-beta/NativeReferenceQueue.java:89) [2024-08-10T01:42:18.184Z] CL2 - locked <0x0000e3b974971540> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.Finalizer$FinalizerThread.run(java.base@21.0.5-beta/Finalizer.java:173) [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "Signal Dispatcher" #11 [183718] daemon prio=9 os_prio=0 cpu=0.79ms elapsed=1799.81s tid=0x0000e3b99c0da580 nid=183718 waiting on condition [0x0000000000000000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "Service Thread" #12 [183719] daemon prio=9 os_prio=0 cpu=3.08ms elapsed=1799.80s tid=0x0000e3b99c0dbbd0 nid=183719 runnable [0x0000000000000000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "Monitor Deflation Thread" #13 [183721] daemon prio=9 os_prio=0 cpu=321.60ms elapsed=1799.78s tid=0x0000e3b99c0dd180 nid=183721 runnable [0x0000000000000000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "C2 CompilerThread0" #14 [183722] daemon prio=9 os_prio=0 cpu=5786.10ms elapsed=1799.78s tid=0x0000e3b99c0debf0 nid=183722 waiting on condition [0x0000000000000000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-10T01:42:18.184Z] CL2 No compile task [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "C1 CompilerThread0" #16 [183723] daemon prio=9 os_prio=0 cpu=1145.63ms elapsed=1799.77s tid=0x0000e3b99c0e02c0 nid=183723 waiting on condition [0x0000000000000000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-10T01:42:18.184Z] CL2 No compile task [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "Notification Thread" #17 [183724] daemon prio=9 os_prio=0 cpu=0.38ms elapsed=1799.73s tid=0x0000e3b99c1041a0 nid=183724 runnable [0x0000000000000000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: RUNNABLE [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "Common-Cleaner" #18 [183725] daemon prio=8 os_prio=0 cpu=4.93ms elapsed=1799.71s tid=0x0000e3b99c106fa0 nid=183725 waiting on condition [0x0000e3b96f00d000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-08-10T01:42:18.184Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.5-beta/Native Method) [2024-08-10T01:42:18.184Z] CL2 - parking to wait for <0x0000e3b974901050> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-08-10T01:42:18.184Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.5-beta/LockSupport.java:269) [2024-08-10T01:42:18.184Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.5-beta/AbstractQueuedSynchronizer.java:1852) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.ReferenceQueue.await(java.base@21.0.5-beta/ReferenceQueue.java:71) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.5-beta/ReferenceQueue.java:143) [2024-08-10T01:42:18.184Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.5-beta/ReferenceQueue.java:218) [2024-08-10T01:42:18.184Z] CL2 at jdk.internal.ref.CleanerImpl.run(java.base@21.0.5-beta/CleanerImpl.java:140) [2024-08-10T01:42:18.184Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-10T01:42:18.184Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-10T01:42:18.184Z] CL2 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.5-beta/InnocuousThread.java:186) [2024-08-10T01:42:18.184Z] CL2 [2024-08-10T01:42:18.184Z] CL2 "RMI Scheduler(0)" #20 [183754] daemon prio=5 os_prio=0 cpu=41.77ms elapsed=1790.30s tid=0x0000e3b99c141900 nid=183754 waiting on condition [0x0000e3b96ee0f000] [2024-08-10T01:42:18.184Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-08-10T01:42:18.184Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.5-beta/Native Method) [2024-08-10T01:42:18.184Z] CL2 - parking to wait for <0x0000e3b974971720> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.5-beta/LockSupport.java:269) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(java.base@21.0.5-beta/AbstractQueuedSynchronizer.java:1763) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.5-beta/ScheduledThreadPoolExecutor.java:1182) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.5-beta/ScheduledThreadPoolExecutor.java:899) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@21.0.5-beta/ThreadPoolExecutor.java:1070) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@21.0.5-beta/ThreadPoolExecutor.java:1130) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@21.0.5-beta/ThreadPoolExecutor.java:642) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "RMI RenewClean-[172.17.0.6:45571]" #21 [183755] daemon prio=5 os_prio=0 cpu=12.65ms elapsed=1790.11s tid=0x0000e3b99c0f6610 nid=183755 waiting on condition [0x0000e3b96ec11000] [2024-08-10T01:42:18.185Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-08-10T01:42:18.185Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.5-beta/Native Method) [2024-08-10T01:42:18.185Z] CL2 - parking to wait for <0x0000e3b974971b78> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.5-beta/LockSupport.java:269) [2024-08-10T01:42:18.185Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.5-beta/AbstractQueuedSynchronizer.java:1852) [2024-08-10T01:42:18.185Z] CL2 at java.lang.ref.ReferenceQueue.await(java.base@21.0.5-beta/ReferenceQueue.java:71) [2024-08-10T01:42:18.185Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.5-beta/ReferenceQueue.java:143) [2024-08-10T01:42:18.185Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.5-beta/ReferenceQueue.java:218) [2024-08-10T01:42:18.185Z] CL2 at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(java.rmi@21.0.5-beta/DGCClient.java:567) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "RMI GC Daemon" #22 [183756] daemon prio=2 os_prio=0 cpu=0.39ms elapsed=1790.07s tid=0x0000e3b99c0f8290 nid=183756 in Object.wait() [0x0000e3b96ea14000] [2024-08-10T01:42:18.185Z] CL2 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Object.wait0(java.base@21.0.5-beta/Native Method) [2024-08-10T01:42:18.185Z] CL2 - waiting on <0x0000e3b974971de8> (a sun.rmi.transport.GC$LatencyLock) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Object.wait(java.base@21.0.5-beta/Object.java:366) [2024-08-10T01:42:18.185Z] CL2 at sun.rmi.transport.GC$Daemon.run(java.rmi@21.0.5-beta/GC.java:127) [2024-08-10T01:42:18.185Z] CL2 - locked <0x0000e3b974971de8> (a sun.rmi.transport.GC$LatencyLock) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-10T01:42:18.185Z] CL2 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.5-beta/InnocuousThread.java:186) [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "JMX client heartbeat 2" #23 [183759] daemon prio=5 os_prio=0 cpu=38.70ms elapsed=1789.08s tid=0x0000e3b99c0efb60 nid=183759 waiting on condition [0x0000e3b96e816000] [2024-08-10T01:42:18.185Z] CL2 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.sleep0(java.base@21.0.5-beta/Native Method) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.sleep(java.base@21.0.5-beta/Thread.java:509) [2024-08-10T01:42:18.185Z] CL2 at com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker.run(java.management@21.0.5-beta/ClientCommunicatorAdmin.java:180) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.runWith(java.base@21.0.5-beta/Thread.java:1596) [2024-08-10T01:42:18.185Z] CL2 at java.lang.Thread.run(java.base@21.0.5-beta/Thread.java:1583) [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "GC Thread#3" os_prio=0 cpu=31.86ms elapsed=1756.52s tid=0x0000e3b94800a3b0 nid=183815 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "GC Thread#2" os_prio=0 cpu=14.44ms elapsed=1756.53s tid=0x0000e3b948009960 nid=183814 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "GC Thread#1" os_prio=0 cpu=28.52ms elapsed=1756.93s tid=0x0000e3b948006f90 nid=183811 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "VM Thread" os_prio=0 cpu=118.54ms elapsed=1799.86s tid=0x0000e3b99c0ce5b0 nid=183714 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "VM Periodic Task Thread" os_prio=0 cpu=1472.37ms elapsed=1799.92s tid=0x0000e3b99c0bca20 nid=183713 waiting on condition [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "G1 Service" os_prio=0 cpu=111.29ms elapsed=1800.33s tid=0x0000e3b99c0a4510 nid=183712 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "G1 Refine#0" os_prio=0 cpu=211.52ms elapsed=1800.34s tid=0x0000e3b99c0a3540 nid=183709 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "G1 Conc#0" os_prio=0 cpu=0.26ms elapsed=1800.35s tid=0x0000e3b99c0521d0 nid=183708 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "G1 Main Marker" os_prio=0 cpu=0.10ms elapsed=1800.35s tid=0x0000e3b99c051220 nid=183707 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 "GC Thread#0" os_prio=0 cpu=27.68ms elapsed=1800.38s tid=0x0000e3b99c04dd90 nid=183705 runnable [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 JNI global refs: 17, weak refs: 0 [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 Heap [2024-08-10T01:42:18.185Z] CL2 garbage-first heap total 100352K, used 46352K [0x0000e3b974800000, 0x0000e3b984800000) [2024-08-10T01:42:18.185Z] CL2 region size 1024K, 41 young (41984K), 1 survivors (1024K) [2024-08-10T01:42:18.185Z] CL2 Metaspace used 6515K, committed 6976K, reserved 1114112K [2024-08-10T01:42:18.185Z] CL2 class space used 616K, committed 832K, reserved 1048576K [2024-08-10T01:42:18.185Z] CL2 [2024-08-10T01:42:18.185Z] CL2 j> 2024/08/10 01:41:44.561 Writing report data ... [2024-08-10T01:42:18.185Z] CL2 j> 2024/08/10 01:41:56.770 Error unmarshaling return header; nested exception is: [2024-08-10T01:42:18.185Z] CL2 java.net.SocketException: Connection reset [2024-08-10T01:42:18.185Z] CL2 stderr java.rmi.UnmarshalException: Error unmarshaling return header; nested exception is: [2024-08-10T01:42:18.185Z] CL2 stderr java.net.SocketException: Connection reset [2024-08-10T01:42:18.185Z] CL2 stderr at java.rmi/sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:255) [2024-08-10T01:42:18.185Z] CL2 stderr at java.rmi/sun.rmi.server.UnicastRef.invoke(UnicastRef.java:166) [2024-08-10T01:42:18.185Z] CL2 stderr at jdk.remoteref/jdk.jmx.remote.internal.rmi.PRef.invoke(Unknown Source) [2024-08-10T01:42:18.185Z] CL2 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnectionImpl_Stub.invoke(RMIConnectionImpl_Stub.java:419) [2024-08-10T01:42:18.185Z] CL2 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.invoke(RMIConnector.java:1020) [2024-08-10T01:42:18.185Z] CL2 stderr at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:549) [2024-08-10T01:42:18.185Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-08-10T01:42:18.185Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-08-10T01:42:18.185Z] CL2 stderr Caused by: java.net.SocketException: Connection reset [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:318) [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/java.io.DataInputStream.readUnsignedByte(DataInputStream.java:295) [2024-08-10T01:42:18.185Z] CL2 stderr at java.base/java.io.DataInputStream.readByte(DataInputStream.java:275) [2024-08-10T01:42:18.185Z] CL2 stderr at java.rmi/sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:241) [2024-08-10T01:42:18.185Z] CL2 stderr ... 7 more [2024-08-10T01:42:18.185Z] CL2 stderr Exception in thread "main" java.lang.AssertionError: Error unmarshaling return header; nested exception is: [2024-08-10T01:42:18.185Z] CL2 stderr java.net.SocketException: Connection reset [2024-08-10T01:42:18.185Z] CL2 stderr at org.junit.Assert.fail(Assert.java:88) [2024-08-10T01:42:18.185Z] CL2 stderr at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:727) [2024-08-10T01:42:18.185Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-08-10T01:42:18.185Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-08-10T01:42:18.185Z] STF 01:42:13.456 - Monitoring Report Summary: [2024-08-10T01:42:18.185Z] STF 01:42:13.457 - o Process CL2 has timed out [2024-08-10T01:42:18.185Z] STF 01:42:13.462 - o Process LT2 ended sucessfully [2024-08-10T01:42:18.185Z] STF 01:42:13.463 - Killing processes: CL2 LT2 [2024-08-10T01:42:18.185Z] STF 01:42:13.464 - o Process LT2 pid 183699 is not running [2024-08-10T01:42:18.185Z] STF 01:42:13.464 - o Process CL2 pid 183701 is not running [2024-08-10T01:42:18.185Z] **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_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/execute.pl line 296. [2024-08-10T01:42:18.185Z] STF 01:42:13.831 - **FAILED** execute script failed. Expected return value=0 Actual=1 [2024-08-10T01:42:18.185Z] STF 01:42:13.831 - [2024-08-10T01:42:18.185Z] STF 01:42:13.831 - ==================== T E A R D O W N ==================== [2024-08-10T01:42:18.185Z] STF 01:42:13.831 - Running teardown: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_aarch64_linux_rerun/aqa-tests/TKG/../TKG/output_17232487157033/TestJlmRemoteThreadNoAuth_1/20240810-005439-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-08-10T01:42:18.185Z] STF 01:42:14.147 - TEARDOWN stage completed [2024-08-10T01:42:18.185Z] STF 01:42:14.170 - [2024-08-10T01:42:18.185Z] STF 01:42:14.171 - ===================== R E S U L T S ===================== [2024-08-10T01:42:18.185Z] STF 01:42:14.171 - Stage results: [2024-08-10T01:42:18.185Z] STF 01:42:14.171 - setUp: pass [2024-08-10T01:42:18.185Z] STF 01:42:14.171 - execute: *fail* [2024-08-10T01:42:18.185Z] STF 01:42:14.171 - teardown: pass [2024-08-10T01:42:18.185Z] STF 01:42:14.171 - [2024-08-10T01:42:18.185Z] STF 01:42:14.171 - Overall result: **FAILED** [2024-08-10T01:42:18.185Z] ----------------------------------- [2024-08-10T01:42:18.185Z] TestJlmRemoteThreadNoAuth_1_FAILED [2024-08-10T01:42:18.185Z] ----------------------------------- [2024-08-10T01:42:18.185Z] [2024-08-10T01:42:18.185Z] TEST TEARDOWN: [2024-08-10T01:42:18.185Z] Nothing to be done for teardown. [2024-08-10T01:42:18.185Z] TestJlmRemoteThreadNoAuth_1 Finish Time: Sat Aug 10 01:42:14 2024 Epoch Time (ms): 1723254134304