[2024-10-02T22:41:35.666Z] Running test TestJlmRemoteThreadNoAuth_0 ... [2024-10-02T22:41:35.666Z] =============================================== [2024-10-02T22:41:35.666Z] TestJlmRemoteThreadNoAuth_0 Start Time: Wed Oct 2 22:41:34 2024 Epoch Time (ms): 1727908894892 [2024-10-02T22:41:35.666Z] variation: Mode150 [2024-10-02T22:41:35.666Z] JVM_OPTIONS: -XX:+UseCompressedOops [2024-10-02T22:41:35.666Z] { \ [2024-10-02T22:41:35.666Z] echo ""; echo "TEST SETUP:"; \ [2024-10-02T22:41:35.666Z] echo "Nothing to be done for setup."; \ [2024-10-02T22:41:35.666Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0"; \ [2024-10-02T22:41:35.666Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0"; \ [2024-10-02T22:41:35.666Z] echo ""; echo "TESTING:"; \ [2024-10-02T22:41:35.666Z] perl /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/../../testDependency/system_lib/" -java-args=' -XX:+UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0" -test=TestJlmRemoteThreadNoAuth; \ [2024-10-02T22:41:35.666Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-02T22:41:35.666Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-02T22:41:35.667Z] echo "Nothing to be done for teardown."; \ [2024-10-02T22:41:35.667Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestTargetResult"; [2024-10-02T22:41:35.667Z] [2024-10-02T22:41:35.667Z] TEST SETUP: [2024-10-02T22:41:35.667Z] Nothing to be done for setup. [2024-10-02T22:41:35.667Z] [2024-10-02T22:41:35.667Z] TESTING: [2024-10-02T22:41:36.815Z] STF 22:41:35.455 - ========================= S T F ========================= [2024-10-02T22:41:36.815Z] systemtest-prereqs has been processed, and set to: /home/jenkins/testDependency/system_libRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0 [2024-10-02T22:41:36.815Z] There is 198225 Mb free [2024-10-02T22:41:36.815Z] STF 22:41:35.509 - ================== G E N E R A T I O N ================== [2024-10-02T22:41:36.815Z] STF 22:41:35.521 - Checking JVM: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/../ [2024-10-02T22:41:36.815Z] STF 22:41:35.521 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/..//bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth" [2024-10-02T22:41:36.815Z] GEN stderr OpenJDK 64-Bit Server VM warning: Archived non-system classes are disabled because the java.system.class.loader property is specified (value = "net.adoptopenjdk.stf.runner.StfClassLoader"). To use archived non-system classes, this property must not be set [2024-10-02T22:41:46.565Z] GEN 22:41:45.197 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-10-02T22:41:46.565Z] GEN 22:41:45.228 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-10-02T22:41:46.565Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-10-02T22:41:46.565Z] GEN /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-10-02T22:41:46.565Z] GEN /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/STF/stf.core/bin [2024-10-02T22:41:46.565Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-10-02T22:41:46.565Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-10-02T22:41:46.565Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-10-02T22:41:47.321Z] GEN 22:41:46.973 - Using Mode NoOptions. Values = '' [2024-10-02T22:41:49.734Z] GEN 22:41:48.802 - [2024-10-02T22:41:49.734Z] GEN 22:41:48.802 - Test command summary: [2024-10-02T22:41:49.734Z] GEN 22:41:48.802 - Step Stage Command Description [2024-10-02T22:41:49.734Z] GEN 22:41:48.812 - -----+--------+-----------------+------------ [2024-10-02T22:41:49.734Z] GEN 22:41:48.814 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-10-02T22:41:49.734Z] GEN 22:41:48.814 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-10-02T22:41:49.734Z] GEN 22:41:48.830 - 3 execute Monitor Wait for processes to complete [2024-10-02T22:41:49.734Z] GEN 22:41:48.830 - 4 execute kill Stop LT1 process [2024-10-02T22:41:49.734Z] GEN 22:41:48.830 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-10-02T22:41:49.734Z] GEN 22:41:48.830 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-10-02T22:41:49.734Z] GEN 22:41:48.846 - 7 execute Monitor Wait for processes to complete [2024-10-02T22:41:49.734Z] GEN 22:41:48.846 - 8 execute kill Stop LT2 process [2024-10-02T22:41:49.734Z] STF 22:41:49.110 - [2024-10-02T22:41:49.734Z] STF 22:41:49.110 - Script generation completed [2024-10-02T22:41:49.734Z] STF 22:41:49.110 - [2024-10-02T22:41:49.734Z] STF 22:41:49.110 - [2024-10-02T22:41:49.734Z] STF 22:41:49.110 - ======================= S E T U P ======================= [2024-10-02T22:41:49.734Z] STF 22:41:49.110 - Running setup: perl /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/setUp.pl [2024-10-02T22:41:49.734Z] STF 22:41:49.571 - SETUP stage completed [2024-10-02T22:41:49.734Z] STF 22:41:49.590 - [2024-10-02T22:41:49.734Z] STF 22:41:49.590 - ==================== E X E C U T E - ==================== [2024-10-02T22:41:49.734Z] STF 22:41:49.590 - Running execute: perl /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/execute.pl [2024-10-02T22:41:50.493Z] STF 22:41:50.242 - [2024-10-02T22:41:50.493Z] STF 22:41:50.242 - Java version [2024-10-02T22:41:50.493Z] STF 22:41:50.242 - Running: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/java -version [2024-10-02T22:41:51.274Z] openjdk version "17.0.13-beta" 2024-10-15 [2024-10-02T22:41:51.274Z] OpenJDK Runtime Environment Temurin-17.0.13+10-202410021937 (build 17.0.13-beta+10-ea) [2024-10-02T22:41:51.274Z] OpenJDK 64-Bit Server VM Temurin-17.0.13+10-202410021937 (build 17.0.13-beta+10-ea, mixed mode, sharing) [2024-10-02T22:41:51.274Z] STF 22:41:50.776 - [2024-10-02T22:41:51.274Z] STF 22:41:50.776 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-10-02T22:41:51.274Z] STF 22:41:50.776 - | Start background process [2024-10-02T22:41:51.274Z] STF 22:41:50.776 - | Program: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/java [2024-10-02T22:41:51.274Z] STF 22:41:50.776 - | Mnemonic: LT1 [2024-10-02T22:41:51.274Z] STF 22:41:50.777 - | Echo: ECHO_OFF [2024-10-02T22:41:51.274Z] STF 22:41:50.777 - | Expectation: NEVER [2024-10-02T22:41:51.274Z] STF 22:41:50.777 - | [2024-10-02T22:41:51.274Z] STF 22:41:50.777 - Running command: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/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_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_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_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-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_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-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-10-02T22:41:51.274Z] STF 22:41:50.777 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-10-02T22:41:51.274Z] STF 22:41:50.777 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-10-02T22:41:51.274Z] STF 22:41:50.875 - [2024-10-02T22:41:51.274Z] STF 22:41:50.876 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-10-02T22:41:51.274Z] STF 22:41:50.876 - | Start background process [2024-10-02T22:41:51.275Z] STF 22:41:50.876 - | Program: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/java [2024-10-02T22:41:51.275Z] STF 22:41:50.876 - | Mnemonic: CL1 [2024-10-02T22:41:51.275Z] STF 22:41:50.876 - | Echo: ECHO_ON [2024-10-02T22:41:51.275Z] STF 22:41:50.876 - | Expectation: CLEAN_RUN within 30m [2024-10-02T22:41:51.275Z] STF 22:41:50.876 - | [2024-10-02T22:41:51.275Z] STF 22:41:50.876 - Running command: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/java -Xmx256m -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_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_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-10-02T22:41:51.275Z] STF 22:41:50.876 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-10-02T22:41:51.275Z] STF 22:41:50.876 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-10-02T22:41:51.275Z] STF 22:41:50.878 - [2024-10-02T22:41:51.275Z] STF 22:41:50.878 - +------ Step 3 - Wait for processes to complete [2024-10-02T22:41:51.275Z] STF 22:41:50.878 - | Wait for processes to meet expectations [2024-10-02T22:41:51.275Z] STF 22:41:50.878 - | Processes: [LT1, CL1] [2024-10-02T22:41:51.275Z] STF 22:41:50.878 - | [2024-10-02T22:41:51.275Z] STF 22:41:50.897 - Monitoring processes: CL1 LT1 [2024-10-02T22:41:54.687Z] CL1 j> 2024/10/02 22:41:52.918 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-10-02T22:41:54.687Z] CL1 j> 2024/10/02 22:41:54.301 Attempting to connect [2024-10-02T22:41:57.099Z] CL1 j> 2024/10/02 22:41:56.246 Monitored VM not ready at Oct 2, 2024, 10:41:56 PM (attempt 1, elapsed 1551ms). [2024-10-02T22:41:57.099Z] CL1 j> 2024/10/02 22:41:56.246 Waiting 5 secs and trying again... [2024-10-02T22:42:02.566Z] CL1 j> 2024/10/02 22:42:01.246 Attempting to connect [2024-10-02T22:42:04.147Z] CL1 j> 2024/10/02 22:42:03.391 Connection established! [2024-10-02T22:42:04.930Z] CL1 j> 2024/10/02 22:42:04.640 Starting to write data [2024-10-02T22:47:03.515Z] STF 22:46:50.164 - Heartbeat: Process CL1 is still running [2024-10-02T22:52:00.329Z] STF 22:51:50.136 - Heartbeat: Process CL1 is still running [2024-10-02T22:56:59.601Z] STF 22:56:50.387 - Heartbeat: Process CL1 is still running [2024-10-02T22:59:39.771Z] STF 22:59:34.390 - Monitoring Report Summary: [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - o Process CL1 ended with the expected exit code (0) [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - o Process LT1 is still running as expected [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - +------ Step 4 - Stop LT1 process [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - | Kill running processes [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - | Processes: [LT1] [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - | [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - Killing processes: LT1 [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - o Process clean up attempt 1 for LT1 pid 928537 [2024-10-02T22:59:39.772Z] STF 22:59:34.391 - o Process LT1 pid 928537 stop() [2024-10-02T22:59:45.582Z] STF 22:59:44.627 - o Process LT1 pid 928537 terminate() [2024-10-02T22:59:46.392Z] STF 22:59:45.627 - o Process LT1 pid 928537 killed [2024-10-02T22:59:46.392Z] STF 22:59:45.628 - [2024-10-02T22:59:46.392Z] STF 22:59:45.628 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-10-02T22:59:46.392Z] STF 22:59:45.628 - | Start background process [2024-10-02T22:59:46.392Z] STF 22:59:45.628 - | Program: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/java [2024-10-02T22:59:46.392Z] STF 22:59:45.628 - | Mnemonic: LT2 [2024-10-02T22:59:46.392Z] STF 22:59:45.628 - | Echo: ECHO_OFF [2024-10-02T22:59:46.392Z] STF 22:59:45.628 - | Expectation: NEVER [2024-10-02T22:59:46.392Z] STF 22:59:45.628 - | [2024-10-02T22:59:46.393Z] STF 22:59:45.628 - Running command: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/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_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_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_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-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_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-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-10-02T22:59:46.393Z] STF 22:59:45.628 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-10-02T22:59:46.393Z] STF 22:59:45.628 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - | Start background process [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - | Program: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/java [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - | Mnemonic: CL2 [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - | Echo: ECHO_ON [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - | Expectation: CLEAN_RUN within 30m [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - | [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - Running command: /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-17.0.13+10/bin/java -Xmx256m -XX:+UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_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_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-10-02T22:59:46.393Z] STF 22:59:45.630 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-10-02T22:59:46.393Z] STF 22:59:45.660 - [2024-10-02T22:59:46.393Z] STF 22:59:45.660 - +------ Step 7 - Wait for processes to complete [2024-10-02T22:59:46.393Z] STF 22:59:45.660 - | Wait for processes to meet expectations [2024-10-02T22:59:46.393Z] STF 22:59:45.660 - | Processes: [LT2, CL2] [2024-10-02T22:59:46.393Z] STF 22:59:45.660 - | [2024-10-02T22:59:46.393Z] STF 22:59:45.660 - Monitoring processes: CL2 LT2 [2024-10-02T22:59:49.914Z] CL2 j> 2024/10/02 22:59:47.586 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-10-02T22:59:49.914Z] CL2 j> 2024/10/02 22:59:48.890 Attempting to connect [2024-10-02T22:59:51.486Z] CL2 j> 2024/10/02 22:59:50.552 Monitored VM not ready at Oct 2, 2024, 10:59:50 PM (attempt 1, elapsed 1390ms). [2024-10-02T22:59:51.486Z] CL2 j> 2024/10/02 22:59:50.552 Waiting 5 secs and trying again... [2024-10-02T22:59:57.096Z] CL2 j> 2024/10/02 22:59:55.558 Attempting to connect [2024-10-02T22:59:57.870Z] CL2 j> 2024/10/02 22:59:57.201 Connection established! [2024-10-02T23:00:25.689Z] CL2 j> 2024/10/02 23:00:24.958 Writing report data ... [2024-10-02T23:02:03.249Z] STF 23:01:50.519 - Heartbeat: Process CL2 is still running [2024-10-02T23:02:08.807Z] CL2 j> 2024/10/02 23:02:06.874 done. [2024-10-02T23:02:20.534Z] CL2 j> 2024/10/02 23:02:19.536 Writing report data ... [2024-10-02T23:03:32.158Z] CL2 j> 2024/10/02 23:03:22.613 done. [2024-10-02T23:03:35.237Z] CL2 j> 2024/10/02 23:03:34.495 Writing report data ... [2024-10-02T23:04:26.767Z] CL2 j> 2024/10/02 23:04:23.732 done. [2024-10-02T23:04:38.454Z] CL2 j> 2024/10/02 23:04:37.064 Writing report data ... [2024-10-02T23:06:01.206Z] CL2 j> 2024/10/02 23:05:52.846 done. [2024-10-02T23:06:07.903Z] CL2 j> 2024/10/02 23:06:05.229 Writing report data ... [2024-10-02T23:06:53.817Z] STF 23:06:50.004 - Heartbeat: Process CL2 is still running [2024-10-02T23:07:12.667Z] CL2 j> 2024/10/02 23:07:10.195 done. [2024-10-02T23:07:22.640Z] CL2 j> 2024/10/02 23:07:22.099 Writing report data ... [2024-10-02T23:08:23.800Z] CL2 j> 2024/10/02 23:08:22.794 done. [2024-10-02T23:08:37.081Z] CL2 j> 2024/10/02 23:08:35.219 Writing report data ... [2024-10-02T23:09:48.879Z] CL2 j> 2024/10/02 23:09:42.637 done. [2024-10-02T23:09:56.113Z] CL2 j> 2024/10/02 23:09:55.208 Writing report data ... [2024-10-02T23:10:49.956Z] CL2 j> 2024/10/02 23:10:47.077 done. [2024-10-02T23:11:01.977Z] CL2 j> 2024/10/02 23:11:00.084 Writing report data ... [2024-10-02T23:11:54.355Z] STF 23:11:50.298 - Heartbeat: Process CL2 is still running [2024-10-02T23:11:54.355Z] CL2 j> 2024/10/02 23:11:52.673 done. [2024-10-02T23:12:04.837Z] CL2 j> 2024/10/02 23:12:03.934 Writing report data ... [2024-10-02T23:13:06.023Z] CL2 j> 2024/10/02 23:13:03.026 done. [2024-10-02T23:13:17.266Z] CL2 j> 2024/10/02 23:13:15.923 Writing report data ... [2024-10-02T23:14:28.481Z] CL2 j> 2024/10/02 23:14:19.845 done. [2024-10-02T23:14:33.009Z] CL2 j> 2024/10/02 23:14:31.092 Writing report data ... [2024-10-02T23:15:24.843Z] CL2 j> 2024/10/02 23:15:22.391 done. [2024-10-02T23:15:34.317Z] CL2 j> 2024/10/02 23:15:33.797 Writing report data ... [2024-10-02T23:16:26.503Z] CL2 j> 2024/10/02 23:16:25.423 done. [2024-10-02T23:16:38.711Z] CL2 j> 2024/10/02 23:16:37.076 Writing report data ... [2024-10-02T23:16:52.601Z] STF 23:16:50.434 - Heartbeat: Process CL2 is still running [2024-10-02T23:17:37.286Z] CL2 j> 2024/10/02 23:17:35.147 done. [2024-10-02T23:17:47.177Z] CL2 j> 2024/10/02 23:17:46.420 Writing report data ... [2024-10-02T23:18:47.625Z] CL2 j> 2024/10/02 23:18:43.512 done. [2024-10-02T23:18:55.829Z] CL2 j> 2024/10/02 23:18:55.048 Writing report data ... [2024-10-02T23:19:57.468Z] CL2 j> 2024/10/02 23:19:49.069 done. [2024-10-02T23:20:04.176Z] CL2 j> 2024/10/02 23:20:02.583 Writing report data ... [2024-10-02T23:21:04.117Z] CL2 j> 2024/10/02 23:21:00.982 done. [2024-10-02T23:21:13.495Z] CL2 j> 2024/10/02 23:21:12.278 Writing report data ... [2024-10-02T23:21:56.527Z] STF 23:21:50.215 - Heartbeat: Process CL2 is still running [2024-10-02T23:22:05.547Z] CL2 j> 2024/10/02 23:22:04.489 done. [2024-10-02T23:22:16.719Z] CL2 j> 2024/10/02 23:22:15.828 Writing report data ... [2024-10-02T23:23:14.528Z] CL2 j> 2024/10/02 23:23:07.490 done. [2024-10-02T23:23:20.345Z] CL2 j> 2024/10/02 23:23:19.720 Writing report data ... [2024-10-02T23:24:19.538Z] CL2 j> 2024/10/02 23:24:12.888 done. [2024-10-02T23:24:24.992Z] CL2 j> 2024/10/02 23:24:24.195 Writing report data ... [2024-10-02T23:25:25.736Z] CL2 j> 2024/10/02 23:25:21.173 done. [2024-10-02T23:25:34.283Z] CL2 j> 2024/10/02 23:25:33.071 Writing report data ... [2024-10-02T23:26:23.763Z] CL2 j> 2024/10/02 23:26:22.270 done. [2024-10-02T23:26:35.819Z] CL2 j> 2024/10/02 23:26:34.145 Writing report data ... [2024-10-02T23:26:51.803Z] STF 23:26:50.270 - Heartbeat: Process CL2 is still running [2024-10-02T23:27:28.404Z] CL2 j> 2024/10/02 23:27:23.994 done. [2024-10-02T23:27:35.614Z] CL2 j> 2024/10/02 23:27:34.892 Writing report data ... [2024-10-02T23:28:17.714Z] CL2 j> 2024/10/02 23:28:15.534 done. [2024-10-02T23:28:30.216Z] CL2 j> 2024/10/02 23:28:28.100 Writing report data ... [2024-10-02T23:29:19.816Z] CL2 j> 2024/10/02 23:29:13.122 done. [2024-10-02T23:29:25.712Z] CL2 j> 2024/10/02 23:29:25.333 Writing report data ... [2024-10-02T23:29:48.529Z] STF 23:29:46.462 - **FAILED** Process CL2 has timed out [2024-10-02T23:29:48.529Z] STF 23:29:46.462 - Collecting dumps for: CL2 [2024-10-02T23:29:48.529Z] STF 23:29:46.462 - Sending SIG 3 to the java process to generate a javacore [2024-10-02T23:29:48.529Z] STF 23:29:46.463 - Running command: kill -3 929327 [2024-10-02T23:29:48.529Z] STF 23:29:46.463 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/6.CL2.kill_3.stderr [2024-10-02T23:29:48.529Z] STF 23:29:46.463 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/results/6.CL2.kill_3.stdout [2024-10-02T23:29:48.529Z] STF 23:29:46.465 - Pausing for 30 seconds [2024-10-02T23:30:18.865Z] STF 23:30:16.465 - Process CL2 (pid 929327) is no longer running. Abandoning dump collection. [2024-10-02T23:30:18.865Z] CL2 2024-10-02 23:29:46 [2024-10-02T23:30:18.865Z] CL2 Full thread dump OpenJDK 64-Bit Server VM (17.0.13-beta+10-ea mixed mode, sharing): [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 Threads class SMR info: [2024-10-02T23:30:18.865Z] CL2 _java_thread_list=0x00007fdefc57bc80, length=15, elements={ [2024-10-02T23:30:18.865Z] CL2 0x00007fdfdc029f00, 0x00007fdfdc0cfa70, 0x00007fdfdc0d0e60, 0x00007fdfdc0d8e30, [2024-10-02T23:30:18.865Z] CL2 0x00007fdfdc0da1f0, 0x00007fdfdc0db610, 0x00007fdfdc0dcfd0, 0x00007fdfdc0de510, [2024-10-02T23:30:18.865Z] CL2 0x00007fdfdc0df5a0, 0x00007fdfdc0e76b0, 0x00007fdfdc0ea700, 0x00007fdfdc1b4580, [2024-10-02T23:30:18.865Z] CL2 0x00007fdfdc1bd4a0, 0x00007fdfdc1a7590, 0x00007fdfdc1c3090 [2024-10-02T23:30:18.865Z] CL2 } [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 "main" #1 prio=5 os_prio=0 cpu=5048.74ms elapsed=1800.97s tid=0x00007fdfdc029f00 nid=0xe2e30 runnable [0x00007fdfe195a000] [2024-10-02T23:30:18.865Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.865Z] CL2 at sun.nio.ch.Net.poll(java.base@17.0.13-beta/Native Method) [2024-10-02T23:30:18.865Z] CL2 at sun.nio.ch.NioSocketImpl.park(java.base@17.0.13-beta/NioSocketImpl.java:186) [2024-10-02T23:30:18.865Z] CL2 at sun.nio.ch.NioSocketImpl.park(java.base@17.0.13-beta/NioSocketImpl.java:195) [2024-10-02T23:30:18.865Z] CL2 at sun.nio.ch.NioSocketImpl.implRead(java.base@17.0.13-beta/NioSocketImpl.java:319) [2024-10-02T23:30:18.865Z] CL2 at sun.nio.ch.NioSocketImpl.read(java.base@17.0.13-beta/NioSocketImpl.java:355) [2024-10-02T23:30:18.865Z] CL2 at sun.nio.ch.NioSocketImpl$1.read(java.base@17.0.13-beta/NioSocketImpl.java:808) [2024-10-02T23:30:18.865Z] CL2 at java.net.Socket$SocketInputStream.read(java.base@17.0.13-beta/Socket.java:966) [2024-10-02T23:30:18.865Z] CL2 at java.io.BufferedInputStream.fill(java.base@17.0.13-beta/BufferedInputStream.java:244) [2024-10-02T23:30:18.865Z] CL2 at java.io.BufferedInputStream.read(java.base@17.0.13-beta/BufferedInputStream.java:263) [2024-10-02T23:30:18.865Z] CL2 - locked <0x00000000f00411e0> (a java.io.BufferedInputStream) [2024-10-02T23:30:18.865Z] CL2 at java.io.DataInputStream.readUnsignedByte(java.base@17.0.13-beta/DataInputStream.java:288) [2024-10-02T23:30:18.865Z] CL2 at java.io.DataInputStream.readByte(java.base@17.0.13-beta/DataInputStream.java:268) [2024-10-02T23:30:18.865Z] CL2 at sun.rmi.transport.StreamRemoteCall.executeCall(java.rmi@17.0.13-beta/StreamRemoteCall.java:241) [2024-10-02T23:30:18.865Z] CL2 at sun.rmi.server.UnicastRef.invoke(java.rmi@17.0.13-beta/UnicastRef.java:165) [2024-10-02T23:30:18.865Z] CL2 at jdk.jmx.remote.internal.rmi.PRef.invoke(jdk.remoteref/Unknown Source) [2024-10-02T23:30:18.865Z] CL2 at javax.management.remote.rmi.RMIConnectionImpl_Stub.invoke(java.management.rmi@17.0.13-beta/RMIConnectionImpl_Stub.java:419) [2024-10-02T23:30:18.865Z] CL2 at javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.invoke(java.management.rmi@17.0.13-beta/RMIConnector.java:1021) [2024-10-02T23:30:18.865Z] CL2 at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:566) [2024-10-02T23:30:18.865Z] CL2 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-10-02T23:30:18.865Z] CL2 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 "Reference Handler" #2 daemon prio=10 os_prio=0 cpu=0.79ms elapsed=1800.60s tid=0x00007fdfdc0cfa70 nid=0xe2e3d waiting on condition [0x00007fdfc502a000] [2024-10-02T23:30:18.865Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.865Z] CL2 at java.lang.ref.Reference.waitForReferencePendingList(java.base@17.0.13-beta/Native Method) [2024-10-02T23:30:18.865Z] CL2 at java.lang.ref.Reference.processPendingReferences(java.base@17.0.13-beta/Reference.java:253) [2024-10-02T23:30:18.865Z] CL2 at java.lang.ref.Reference$ReferenceHandler.run(java.base@17.0.13-beta/Reference.java:215) [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 "Finalizer" #3 daemon prio=8 os_prio=0 cpu=0.47ms elapsed=1800.59s tid=0x00007fdfdc0d0e60 nid=0xe2e40 in Object.wait() [0x00007fdfc4f29000] [2024-10-02T23:30:18.865Z] CL2 java.lang.Thread.State: WAITING (on object monitor) [2024-10-02T23:30:18.865Z] CL2 at java.lang.Object.wait(java.base@17.0.13-beta/Native Method) [2024-10-02T23:30:18.865Z] CL2 - waiting on <0x00000000f007d048> (a java.lang.ref.ReferenceQueue$Lock) [2024-10-02T23:30:18.865Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@17.0.13-beta/ReferenceQueue.java:155) [2024-10-02T23:30:18.865Z] CL2 - locked <0x00000000f007d048> (a java.lang.ref.ReferenceQueue$Lock) [2024-10-02T23:30:18.865Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@17.0.13-beta/ReferenceQueue.java:176) [2024-10-02T23:30:18.865Z] CL2 at java.lang.ref.Finalizer$FinalizerThread.run(java.base@17.0.13-beta/Finalizer.java:172) [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 "Signal Dispatcher" #4 daemon prio=9 os_prio=0 cpu=0.26ms elapsed=1800.56s tid=0x00007fdfdc0d8e30 nid=0xe2e41 waiting on condition [0x0000000000000000] [2024-10-02T23:30:18.865Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 "Service Thread" #5 daemon prio=9 os_prio=0 cpu=1.84ms elapsed=1800.56s tid=0x00007fdfdc0da1f0 nid=0xe2e42 runnable [0x0000000000000000] [2024-10-02T23:30:18.865Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 "Monitor Deflation Thread" #6 daemon prio=9 os_prio=0 cpu=145.50ms elapsed=1800.55s tid=0x00007fdfdc0db610 nid=0xe2e43 runnable [0x0000000000000000] [2024-10-02T23:30:18.865Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 "C2 CompilerThread0" #7 daemon prio=9 os_prio=0 cpu=7715.03ms elapsed=1800.54s tid=0x00007fdfdc0dcfd0 nid=0xe2e44 waiting on condition [0x0000000000000000] [2024-10-02T23:30:18.865Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.865Z] CL2 No compile task [2024-10-02T23:30:18.865Z] CL2 [2024-10-02T23:30:18.865Z] CL2 "C1 CompilerThread0" #9 daemon prio=9 os_prio=0 cpu=1039.07ms elapsed=1800.53s tid=0x00007fdfdc0de510 nid=0xe2e45 waiting on condition [0x0000000000000000] [2024-10-02T23:30:18.865Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.865Z] CL2 No compile task [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "Sweeper thread" #10 daemon prio=9 os_prio=0 cpu=4.07ms elapsed=1800.53s tid=0x00007fdfdc0df5a0 nid=0xe2e46 runnable [0x0000000000000000] [2024-10-02T23:30:18.866Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "Notification Thread" #11 daemon prio=9 os_prio=0 cpu=0.15ms elapsed=1800.39s tid=0x00007fdfdc0e76b0 nid=0xe2e4d runnable [0x0000000000000000] [2024-10-02T23:30:18.866Z] CL2 java.lang.Thread.State: RUNNABLE [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "Common-Cleaner" #12 daemon prio=8 os_prio=0 cpu=2.77ms elapsed=1800.35s tid=0x00007fdfdc0ea700 nid=0xe2e4f in Object.wait() [0x00007fdfc42dc000] [2024-10-02T23:30:18.866Z] CL2 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Object.wait(java.base@17.0.13-beta/Native Method) [2024-10-02T23:30:18.866Z] CL2 - waiting on <0x00000000f003ebf0> (a java.lang.ref.ReferenceQueue$Lock) [2024-10-02T23:30:18.866Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@17.0.13-beta/ReferenceQueue.java:155) [2024-10-02T23:30:18.866Z] CL2 - locked <0x00000000f003ebf0> (a java.lang.ref.ReferenceQueue$Lock) [2024-10-02T23:30:18.866Z] CL2 at jdk.internal.ref.CleanerImpl.run(java.base@17.0.13-beta/CleanerImpl.java:140) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Thread.run(java.base@17.0.13-beta/Thread.java:840) [2024-10-02T23:30:18.866Z] CL2 at jdk.internal.misc.InnocuousThread.run(java.base@17.0.13-beta/InnocuousThread.java:162) [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "RMI Scheduler(0)" #14 daemon prio=5 os_prio=0 cpu=30.87ms elapsed=1790.49s tid=0x00007fdfdc1b4580 nid=0xe2e63 waiting on condition [0x00007fdfc418c000] [2024-10-02T23:30:18.866Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-10-02T23:30:18.866Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@17.0.13-beta/Native Method) [2024-10-02T23:30:18.866Z] CL2 - parking to wait for <0x00000000f004f310> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-02T23:30:18.866Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@17.0.13-beta/LockSupport.java:252) [2024-10-02T23:30:18.866Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(java.base@17.0.13-beta/AbstractQueuedSynchronizer.java:1679) [2024-10-02T23:30:18.866Z] CL2 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@17.0.13-beta/ScheduledThreadPoolExecutor.java:1182) [2024-10-02T23:30:18.866Z] CL2 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@17.0.13-beta/ScheduledThreadPoolExecutor.java:899) [2024-10-02T23:30:18.866Z] CL2 at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@17.0.13-beta/ThreadPoolExecutor.java:1062) [2024-10-02T23:30:18.866Z] CL2 at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@17.0.13-beta/ThreadPoolExecutor.java:1122) [2024-10-02T23:30:18.866Z] CL2 at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@17.0.13-beta/ThreadPoolExecutor.java:635) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Thread.run(java.base@17.0.13-beta/Thread.java:840) [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "RMI RenewClean-[172.17.0.8:41755]" #15 daemon prio=5 os_prio=0 cpu=12.19ms elapsed=1790.33s tid=0x00007fdfdc1bd4a0 nid=0xe2e64 in Object.wait() [0x00007fdfbdffe000] [2024-10-02T23:30:18.866Z] CL2 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Object.wait(java.base@17.0.13-beta/Native Method) [2024-10-02T23:30:18.866Z] CL2 - waiting on <0x00000000f004cdc8> (a java.lang.ref.ReferenceQueue$Lock) [2024-10-02T23:30:18.866Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@17.0.13-beta/ReferenceQueue.java:155) [2024-10-02T23:30:18.866Z] CL2 - locked <0x00000000f004cdc8> (a java.lang.ref.ReferenceQueue$Lock) [2024-10-02T23:30:18.866Z] CL2 at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(java.rmi@17.0.13-beta/DGCClient.java:559) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Thread.run(java.base@17.0.13-beta/Thread.java:840) [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "RMI GC Daemon" #16 daemon prio=2 os_prio=0 cpu=0.27ms elapsed=1790.30s tid=0x00007fdfdc1a7590 nid=0xe2e65 in Object.wait() [0x00007fdfbdefd000] [2024-10-02T23:30:18.866Z] CL2 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Object.wait(java.base@17.0.13-beta/Native Method) [2024-10-02T23:30:18.866Z] CL2 - waiting on <0x00000000f007d360> (a sun.rmi.transport.GC$LatencyLock) [2024-10-02T23:30:18.866Z] CL2 at sun.rmi.transport.GC$Daemon.run(java.rmi@17.0.13-beta/GC.java:127) [2024-10-02T23:30:18.866Z] CL2 - locked <0x00000000f007d360> (a sun.rmi.transport.GC$LatencyLock) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Thread.run(java.base@17.0.13-beta/Thread.java:840) [2024-10-02T23:30:18.866Z] CL2 at jdk.internal.misc.InnocuousThread.run(java.base@17.0.13-beta/InnocuousThread.java:162) [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "JMX client heartbeat 2" #17 daemon prio=5 os_prio=0 cpu=21.28ms elapsed=1789.50s tid=0x00007fdfdc1c3090 nid=0xe2e6a waiting on condition [0x00007fdfbddfc000] [2024-10-02T23:30:18.866Z] CL2 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Thread.sleep(java.base@17.0.13-beta/Native Method) [2024-10-02T23:30:18.866Z] CL2 at com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker.run(java.management@17.0.13-beta/ClientCommunicatorAdmin.java:180) [2024-10-02T23:30:18.866Z] CL2 at java.lang.Thread.run(java.base@17.0.13-beta/Thread.java:840) [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "GC Thread#2" os_prio=0 cpu=5.06ms elapsed=1744.61s tid=0x00007fdf5c0072c0 nid=0xe2ea9 runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "GC Thread#3" os_prio=0 cpu=8.15ms elapsed=1744.54s tid=0x00007fdf5c007c40 nid=0xe2eaa runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "GC Thread#1" os_prio=0 cpu=15.23ms elapsed=1745.13s tid=0x00007fdf5c005140 nid=0xe2ea7 runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "VM Periodic Task Thread" os_prio=0 cpu=903.91ms elapsed=1800.36s tid=0x00007fdfdc0e9000 nid=0xe2e4e waiting on condition [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "VM Thread" os_prio=0 cpu=59.20ms elapsed=1800.66s tid=0x00007fdfdc0cbae0 nid=0xe2e3b runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "G1 Service" os_prio=0 cpu=280.00ms elapsed=1800.87s tid=0x00007fdfdc0a7810 nid=0xe2e3a runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "G1 Refine#0" os_prio=0 cpu=0.10ms elapsed=1800.87s tid=0x00007fdfdc0a6910 nid=0xe2e39 runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "G1 Conc#0" os_prio=0 cpu=0.10ms elapsed=1800.87s tid=0x00007fdfdc054b90 nid=0xe2e38 runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "G1 Main Marker" os_prio=0 cpu=0.07ms elapsed=1800.87s tid=0x00007fdfdc053c20 nid=0xe2e37 runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 "GC Thread#0" os_prio=0 cpu=21.71ms elapsed=1800.87s tid=0x00007fdfdc050630 nid=0xe2e35 runnable [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 JNI global refs: 16, weak refs: 0 [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 Heap [2024-10-02T23:30:18.866Z] CL2 garbage-first heap total 100352K, used 39214K [0x00000000f0000000, 0x0000000100000000) [2024-10-02T23:30:18.866Z] CL2 region size 1024K, 36 young (36864K), 1 survivors (1024K) [2024-10-02T23:30:18.866Z] CL2 Metaspace used 6360K, committed 6720K, reserved 1114112K [2024-10-02T23:30:18.866Z] CL2 class space used 641K, committed 832K, reserved 1048576K [2024-10-02T23:30:18.866Z] CL2 [2024-10-02T23:30:18.866Z] CL2 j> 2024/10/02 23:30:06.617 Error unmarshaling return header; nested exception is: [2024-10-02T23:30:18.866Z] CL2 java.net.SocketException: Connection reset [2024-10-02T23:30:18.866Z] CL2 stderr java.rmi.UnmarshalException: Error unmarshaling return header; nested exception is: [2024-10-02T23:30:18.866Z] CL2 stderr java.net.SocketException: Connection reset [2024-10-02T23:30:18.866Z] CL2 stderr at java.rmi/sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:255) [2024-10-02T23:30:18.866Z] CL2 stderr at java.rmi/sun.rmi.server.UnicastRef.invoke(UnicastRef.java:165) [2024-10-02T23:30:18.866Z] CL2 stderr at jdk.remoteref/jdk.jmx.remote.internal.rmi.PRef.invoke(Unknown Source) [2024-10-02T23:30:18.866Z] CL2 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnectionImpl_Stub.invoke(RMIConnectionImpl_Stub.java:419) [2024-10-02T23:30:18.866Z] CL2 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.invoke(RMIConnector.java:1021) [2024-10-02T23:30:18.866Z] CL2 stderr at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:608) [2024-10-02T23:30:18.866Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-10-02T23:30:18.866Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-10-02T23:30:18.866Z] CL2 stderr Caused by: java.net.SocketException: Connection reset [2024-10-02T23:30:18.866Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:328) [2024-10-02T23:30:18.866Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:355) [2024-10-02T23:30:18.866Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:808) [2024-10-02T23:30:18.866Z] CL2 stderr at java.base/java.net.Socket$SocketInputStream.read(Socket.java:966) [2024-10-02T23:30:18.866Z] CL2 stderr at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:244) [2024-10-02T23:30:18.866Z] CL2 stderr at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:263) [2024-10-02T23:30:18.866Z] CL2 stderr at java.base/java.io.DataInputStream.readUnsignedByte(DataInputStream.java:288) [2024-10-02T23:30:18.866Z] CL2 stderr at java.base/java.io.DataInputStream.readByte(DataInputStream.java:268) [2024-10-02T23:30:18.866Z] CL2 stderr at java.rmi/sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:241) [2024-10-02T23:30:18.866Z] CL2 stderr ... 7 more [2024-10-02T23:30:18.866Z] CL2 stderr Exception in thread "main" java.lang.AssertionError: Error unmarshaling return header; nested exception is: [2024-10-02T23:30:18.866Z] CL2 stderr java.net.SocketException: Connection reset [2024-10-02T23:30:18.866Z] CL2 stderr at org.junit.Assert.fail(Assert.java:88) [2024-10-02T23:30:18.866Z] CL2 stderr at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:727) [2024-10-02T23:30:18.866Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-10-02T23:30:18.866Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-10-02T23:30:18.866Z] CL2 stderr Oct 02, 2024 11:30:06 PM com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker run [2024-10-02T23:30:18.866Z] CL2 stderr WARNING: Failed to check connection: java.net.SocketException: Connection reset [2024-10-02T23:30:18.866Z] STF 23:30:16.466 - Monitoring Report Summary: [2024-10-02T23:30:18.866Z] STF 23:30:16.466 - o Process CL2 has timed out [2024-10-02T23:30:18.866Z] STF 23:30:16.466 - o Process LT2 ended sucessfully [2024-10-02T23:30:18.866Z] STF 23:30:16.467 - Killing processes: CL2 LT2 [2024-10-02T23:30:18.866Z] STF 23:30:16.467 - o Process LT2 pid 929325 is not running [2024-10-02T23:30:18.866Z] STF 23:30:16.467 - o Process CL2 pid 929327 is not running [2024-10-02T23:30:18.866Z] **FAILED** at step 7 (Wait for processes to complete). Expected return value=0 Actual=1 at /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/execute.pl line 296. [2024-10-02T23:30:18.866Z] STF 23:30:16.853 - **FAILED** execute script failed. Expected return value=0 Actual=1 [2024-10-02T23:30:18.866Z] STF 23:30:16.853 - [2024-10-02T23:30:18.866Z] STF 23:30:16.853 - ==================== T E A R D O W N ==================== [2024-10-02T23:30:18.866Z] STF 23:30:16.853 - Running teardown: perl /home/jenkins/workspace/Test_openjdk17_hs_sanity.system_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17279014265367/TestJlmRemoteThreadNoAuth_0/20241002-224135-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-10-02T23:30:18.866Z] STF 23:30:17.552 - TEARDOWN stage completed [2024-10-02T23:30:18.866Z] STF 23:30:17.607 - [2024-10-02T23:30:18.866Z] STF 23:30:17.607 - ===================== R E S U L T S ===================== [2024-10-02T23:30:18.866Z] STF 23:30:17.607 - Stage results: [2024-10-02T23:30:18.866Z] STF 23:30:17.607 - setUp: pass [2024-10-02T23:30:18.866Z] STF 23:30:17.607 - execute: *fail* [2024-10-02T23:30:18.866Z] STF 23:30:17.607 - teardown: pass [2024-10-02T23:30:18.866Z] STF 23:30:17.607 - [2024-10-02T23:30:18.866Z] STF 23:30:17.607 - Overall result: **FAILED** [2024-10-02T23:30:18.866Z] ----------------------------------- [2024-10-02T23:30:18.866Z] TestJlmRemoteThreadNoAuth_0_FAILED [2024-10-02T23:30:18.866Z] ----------------------------------- [2024-10-02T23:30:18.866Z] [2024-10-02T23:30:18.866Z] TEST TEARDOWN: [2024-10-02T23:30:18.866Z] Nothing to be done for teardown. [2024-10-02T23:30:18.866Z] TestJlmRemoteThreadNoAuth_0 Finish Time: Wed Oct 2 23:30:17 2024 Epoch Time (ms): 1727911817730