[2024-11-21T09:18:10.433Z] Running test TestJlmRemoteThreadNoAuth_1 ... [2024-11-21T09:18:10.433Z] =============================================== [2024-11-21T09:18:10.433Z] TestJlmRemoteThreadNoAuth_1 Start Time: Thu Nov 21 09:18:06 2024 Epoch Time (ms): 1732180686929 [2024-11-21T09:18:10.433Z] variation: Mode650 [2024-11-21T09:18:10.433Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-11-21T09:18:10.433Z] { \ [2024-11-21T09:18:10.433Z] echo ""; echo "TEST SETUP:"; \ [2024-11-21T09:18:10.433Z] echo "Nothing to be done for setup."; \ [2024-11-21T09:18:10.433Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1"; \ [2024-11-21T09:18:10.433Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1"; \ [2024-11-21T09:18:10.433Z] echo ""; echo "TESTING:"; \ [2024-11-21T09:18:10.433Z] perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/../../testDependency/system_lib/" -java-args=' -XX:-UseCompressedOops ' -results-root="/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1" -test=TestJlmRemoteThreadNoAuth; \ [2024-11-21T09:18:10.433Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-21T09:18:10.433Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-21T09:18:10.433Z] echo "Nothing to be done for teardown."; \ [2024-11-21T09:18:10.433Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestTargetResult"; [2024-11-21T09:18:10.433Z] [2024-11-21T09:18:10.433Z] TEST SETUP: [2024-11-21T09:18:10.433Z] Nothing to be done for setup. [2024-11-21T09:18:10.433Z] [2024-11-21T09:18:10.433Z] TESTING: [2024-11-21T09:18:10.433Z] STF 09:18:07.442 - ========================= S T F ========================= [2024-11-21T09:18:10.433Z] 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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1 [2024-11-21T09:18:10.433Z] There is 117784 Mb free [2024-11-21T09:18:10.433Z] STF 09:18:07.481 - ================== G E N E R A T I O N ================== [2024-11-21T09:18:10.433Z] STF 09:18:07.482 - Checking JVM: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image [2024-11-21T09:18:10.433Z] STF 09:18:07.482 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth" [2024-11-21T09:18:10.433Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-21T09:18:10.433Z] GEN [0.193s][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-11-21T09:18:17.586Z] GEN Java lib directory does not exist: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/lib [2024-11-21T09:18:17.586Z] GEN 09:18:16.917 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-11-21T09:18:17.586Z] GEN 09:18:17.028 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-11-21T09:18:18.407Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-11-21T09:18:18.407Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-11-21T09:18:18.407Z] GEN /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.core/bin [2024-11-21T09:18:18.407Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-11-21T09:18:18.407Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-11-21T09:18:18.407Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-11-21T09:18:18.407Z] GEN Java lib directory does not exist: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/lib [2024-11-21T09:18:19.223Z] GEN 09:18:18.473 - Using Mode NoOptions. Values = '' [2024-11-21T09:18:20.033Z] GEN 09:18:19.766 - [2024-11-21T09:18:20.033Z] GEN 09:18:19.766 - Test command summary: [2024-11-21T09:18:20.033Z] GEN 09:18:19.788 - Step Stage Command Description [2024-11-21T09:18:20.033Z] GEN 09:18:19.813 - -----+--------+-----------------+------------ [2024-11-21T09:18:20.033Z] GEN 09:18:19.813 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-11-21T09:18:20.033Z] GEN 09:18:19.813 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-11-21T09:18:20.033Z] GEN 09:18:19.813 - 3 execute Monitor Wait for processes to complete [2024-11-21T09:18:20.033Z] GEN 09:18:19.813 - 4 execute kill Stop LT1 process [2024-11-21T09:18:20.033Z] GEN 09:18:19.813 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-11-21T09:18:20.033Z] GEN 09:18:19.813 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-11-21T09:18:20.033Z] GEN 09:18:19.813 - 7 execute Monitor Wait for processes to complete [2024-11-21T09:18:20.033Z] GEN 09:18:19.814 - 8 execute kill Stop LT2 process [2024-11-21T09:18:20.033Z] STF 09:18:19.933 - [2024-11-21T09:18:20.033Z] STF 09:18:19.933 - Script generation completed [2024-11-21T09:18:20.033Z] STF 09:18:19.933 - [2024-11-21T09:18:20.033Z] STF 09:18:19.934 - [2024-11-21T09:18:20.033Z] STF 09:18:19.934 - ======================= S E T U P ======================= [2024-11-21T09:18:20.033Z] STF 09:18:19.934 - Running setup: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/setUp.pl [2024-11-21T09:18:20.828Z] STF 09:18:20.377 - SETUP stage completed [2024-11-21T09:18:20.828Z] STF 09:18:20.389 - [2024-11-21T09:18:20.828Z] STF 09:18:20.393 - ==================== E X E C U T E - ==================== [2024-11-21T09:18:20.828Z] STF 09:18:20.393 - Running execute: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/execute.pl [2024-11-21T09:18:21.640Z] STF 09:18:20.786 - [2024-11-21T09:18:21.640Z] STF 09:18:20.786 - Java version [2024-11-21T09:18:21.640Z] STF 09:18:20.786 - Running: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java -version [2024-11-21T09:18:21.640Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-21T09:18:21.640Z] openjdk version "21.0.6-beta" 2025-01-21 [2024-11-21T09:18:21.640Z] OpenJDK Runtime Environment Temurin-21.0.6+4-202411202009 (build 21.0.6-beta+4-ea) [2024-11-21T09:18:21.640Z] OpenJDK 64-Bit Server VM Temurin-21.0.6+4-202411202009 (build 21.0.6-beta+4-ea, mixed mode, sharing) [2024-11-21T09:18:21.640Z] STF 09:18:21.173 - [2024-11-21T09:18:21.640Z] STF 09:18:21.173 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-11-21T09:18:21.640Z] STF 09:18:21.173 - | Start background process [2024-11-21T09:18:21.640Z] STF 09:18:21.173 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java [2024-11-21T09:18:21.640Z] STF 09:18:21.173 - | Mnemonic: LT1 [2024-11-21T09:18:21.640Z] STF 09:18:21.173 - | Echo: ECHO_OFF [2024-11-21T09:18:21.640Z] STF 09:18:21.173 - | Expectation: NEVER [2024-11-21T09:18:21.640Z] STF 09:18:21.173 - | [2024-11-21T09:18:21.640Z] STF 09:18:21.180 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.mini-mix.threadCount 30 -suite.mini-mix.selection random -suite.mini-mix.seed -1 -suite.mini-mix.repeatCount 1 -suite.mini-mix.thinkingTime 0ms..0ms [2024-11-21T09:18:21.640Z] STF 09:18:21.180 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-11-21T09:18:21.640Z] STF 09:18:21.180 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-11-21T09:18:21.640Z] STF 09:18:21.248 - [2024-11-21T09:18:21.640Z] STF 09:18:21.248 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-11-21T09:18:21.640Z] STF 09:18:21.248 - | Start background process [2024-11-21T09:18:21.641Z] STF 09:18:21.248 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java [2024-11-21T09:18:21.641Z] STF 09:18:21.248 - | Mnemonic: CL1 [2024-11-21T09:18:21.641Z] STF 09:18:21.248 - | Echo: ECHO_ON [2024-11-21T09:18:21.641Z] STF 09:18:21.248 - | Expectation: CLEAN_RUN within 30m [2024-11-21T09:18:21.641Z] STF 09:18:21.248 - | [2024-11-21T09:18:21.641Z] STF 09:18:21.248 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-11-21T09:18:21.641Z] STF 09:18:21.248 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-11-21T09:18:21.641Z] STF 09:18:21.248 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-11-21T09:18:21.641Z] STF 09:18:21.261 - [2024-11-21T09:18:21.641Z] STF 09:18:21.261 - +------ Step 3 - Wait for processes to complete [2024-11-21T09:18:21.641Z] STF 09:18:21.261 - | Wait for processes to meet expectations [2024-11-21T09:18:21.641Z] STF 09:18:21.261 - | Processes: [LT1, CL1] [2024-11-21T09:18:21.641Z] STF 09:18:21.261 - | [2024-11-21T09:18:21.641Z] STF 09:18:21.261 - Monitoring processes: CL1 LT1 [2024-11-21T09:18:21.641Z] CL1 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-21T09:18:24.320Z] CL1 j> 2024/11/21 09:18:22.805 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-21T09:18:24.320Z] CL1 j> 2024/11/21 09:18:23.562 Attempting to connect [2024-11-21T09:18:25.998Z] CL1 j> 2024/11/21 09:18:25.255 Monitored VM not ready at Nov 21, 2024, 9:18:25 AM (attempt 1, elapsed 1317ms). [2024-11-21T09:18:25.998Z] CL1 j> 2024/11/21 09:18:25.271 Waiting 5 secs and trying again... [2024-11-21T09:18:30.639Z] CL1 j> 2024/11/21 09:18:30.273 Attempting to connect [2024-11-21T09:18:33.249Z] CL1 j> 2024/11/21 09:18:32.269 Connection established! [2024-11-21T09:18:34.090Z] CL1 j> 2024/11/21 09:18:33.587 Starting to write data [2024-11-21T09:23:32.296Z] STF 09:23:20.024 - Heartbeat: Process LT1 is still running [2024-11-21T09:28:30.486Z] STF 09:28:20.365 - Heartbeat: Process LT1 is still running [2024-11-21T09:33:28.260Z] STF 09:33:20.332 - Heartbeat: Process LT1 is still running [2024-11-21T09:37:55.026Z] STF 09:37:41.318 - Monitoring Report Summary: [2024-11-21T09:37:55.026Z] STF 09:37:41.318 - o Process CL1 ended with the expected exit code (0) [2024-11-21T09:37:55.026Z] STF 09:37:41.318 - o Process LT1 is still running as expected [2024-11-21T09:37:55.026Z] STF 09:37:41.319 - [2024-11-21T09:37:55.026Z] STF 09:37:41.319 - +------ Step 4 - Stop LT1 process [2024-11-21T09:37:55.026Z] STF 09:37:41.319 - | Kill running processes [2024-11-21T09:37:55.026Z] STF 09:37:41.319 - | Processes: [LT1] [2024-11-21T09:37:55.026Z] STF 09:37:41.319 - | [2024-11-21T09:37:55.026Z] STF 09:37:41.319 - Killing processes: LT1 [2024-11-21T09:37:55.026Z] STF 09:37:41.319 - o Process clean up attempt 1 for LT1 pid 815824 [2024-11-21T09:37:55.026Z] STF 09:37:41.319 - o Process LT1 pid 815824 stop() [2024-11-21T09:37:55.026Z] STF 09:37:51.564 - o Process LT1 pid 815824 terminate() [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - o Process LT1 pid 815824 killed [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - +------ Step 5 - Running ThreadProfiler Server test Server Process(without security) [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - | Start background process [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - | Mnemonic: LT2 [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - | Echo: ECHO_OFF [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - | Expectation: NEVER [2024-11-21T09:37:55.026Z] STF 09:37:52.572 - | [2024-11-21T09:37:55.026Z] STF 09:37:52.573 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/5.LT2.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.mini-mix.threadCount 30 -suite.mini-mix.selection random -suite.mini-mix.seed -1 -suite.mini-mix.repeatCount 1 -suite.mini-mix.thinkingTime 0ms..0ms [2024-11-21T09:37:55.026Z] STF 09:37:52.573 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/5.LT2.stderr [2024-11-21T09:37:55.026Z] STF 09:37:52.573 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/5.LT2.stdout [2024-11-21T09:37:55.026Z] STF 09:37:52.575 - [2024-11-21T09:37:55.026Z] STF 09:37:52.575 - +------ Step 6 - Running the Monitoring Client with server-connection(without security) [2024-11-21T09:37:55.026Z] STF 09:37:52.575 - | Start background process [2024-11-21T09:37:55.026Z] STF 09:37:52.575 - | Program: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java [2024-11-21T09:37:55.026Z] STF 09:37:52.575 - | Mnemonic: CL2 [2024-11-21T09:37:55.026Z] STF 09:37:52.575 - | Echo: ECHO_ON [2024-11-21T09:37:55.026Z] STF 09:37:52.575 - | Expectation: CLEAN_RUN within 30m [2024-11-21T09:37:55.026Z] STF 09:37:52.575 - | [2024-11-21T09:37:55.026Z] STF 09:37:52.599 - Running command: /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/jdkbinary/j2sdk-image/bin/java -Xmx256m -XX:-UseCompressedOops -classpath /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-11-21T09:37:55.026Z] STF 09:37:52.599 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/6.CL2.stderr [2024-11-21T09:37:55.026Z] STF 09:37:52.599 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/6.CL2.stdout [2024-11-21T09:37:55.026Z] STF 09:37:52.606 - [2024-11-21T09:37:55.026Z] STF 09:37:52.606 - +------ Step 7 - Wait for processes to complete [2024-11-21T09:37:55.026Z] STF 09:37:52.606 - | Wait for processes to meet expectations [2024-11-21T09:37:55.026Z] STF 09:37:52.606 - | Processes: [LT2, CL2] [2024-11-21T09:37:55.026Z] STF 09:37:52.606 - | [2024-11-21T09:37:55.026Z] STF 09:37:52.606 - Monitoring processes: CL2 LT2 [2024-11-21T09:37:55.026Z] CL2 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-11-21T09:37:56.711Z] CL2 j> 2024/11/21 09:37:54.277 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-11-21T09:37:56.711Z] CL2 j> 2024/11/21 09:37:55.363 Attempting to connect [2024-11-21T09:37:58.457Z] CL2 j> 2024/11/21 09:37:57.463 Monitored VM not ready at Nov 21, 2024, 9:37:57 AM (attempt 1, elapsed 1798ms). [2024-11-21T09:37:58.457Z] CL2 j> 2024/11/21 09:37:57.463 Waiting 5 secs and trying again... [2024-11-21T09:38:03.084Z] CL2 j> 2024/11/21 09:38:02.464 Attempting to connect [2024-11-21T09:38:05.750Z] CL2 j> 2024/11/21 09:38:04.743 Connection established! [2024-11-21T09:38:20.660Z] STF 09:38:20.118 - Heartbeat: Process CL2 is still running [2024-11-21T09:38:23.557Z] CL2 j> 2024/11/21 09:38:22.462 Writing report data ... [2024-11-21T09:39:47.579Z] CL2 j> 2024/11/21 09:39:42.262 done. [2024-11-21T09:39:55.266Z] CL2 j> 2024/11/21 09:39:54.413 Writing report data ... [2024-11-21T09:41:06.894Z] CL2 j> 2024/11/21 09:41:04.424 done. [2024-11-21T09:41:19.268Z] CL2 j> 2024/11/21 09:41:17.631 Writing report data ... [2024-11-21T09:42:31.586Z] CL2 j> 2024/11/21 09:42:24.018 done. [2024-11-21T09:42:37.598Z] CL2 j> 2024/11/21 09:42:36.900 Writing report data ... [2024-11-21T09:43:23.343Z] STF 09:43:20.351 - Heartbeat: Process CL2 is still running [2024-11-21T09:43:42.602Z] CL2 j> 2024/11/21 09:43:40.945 done. [2024-11-21T09:43:54.397Z] CL2 j> 2024/11/21 09:43:53.356 Writing report data ... [2024-11-21T09:44:56.600Z] CL2 j> 2024/11/21 09:44:53.897 done. [2024-11-21T09:45:07.230Z] CL2 j> 2024/11/21 09:45:05.869 Writing report data ... [2024-11-21T09:46:00.222Z] CL2 j> 2024/11/21 09:45:54.725 done. [2024-11-21T09:46:08.548Z] CL2 j> 2024/11/21 09:46:06.470 Writing report data ... [2024-11-21T09:46:54.745Z] CL2 j> 2024/11/21 09:46:54.182 done. [2024-11-21T09:47:06.934Z] CL2 j> 2024/11/21 09:47:06.144 Writing report data ... [2024-11-21T09:48:08.186Z] CL2 j> 2024/11/21 09:48:01.175 done. [2024-11-21T09:48:15.423Z] CL2 j> 2024/11/21 09:48:13.642 Writing report data ... [2024-11-21T09:48:21.172Z] STF 09:48:20.479 - Heartbeat: Process CL2 is still running [2024-11-21T09:49:23.949Z] CL2 j> 2024/11/21 09:49:15.664 done. [2024-11-21T09:49:29.650Z] CL2 j> 2024/11/21 09:49:27.815 Writing report data ... [2024-11-21T09:50:22.701Z] CL2 j> 2024/11/21 09:50:22.342 done. [2024-11-21T09:50:34.744Z] CL2 j> 2024/11/21 09:50:34.283 Writing report data ... [2024-11-21T09:51:36.065Z] CL2 j> 2024/11/21 09:51:33.556 done. [2024-11-21T09:51:46.120Z] CL2 j> 2024/11/21 09:51:45.483 Writing report data ... [2024-11-21T09:52:38.910Z] CL2 j> 2024/11/21 09:52:35.235 done. [2024-11-21T09:52:48.224Z] CL2 j> 2024/11/21 09:52:46.784 Writing report data ... [2024-11-21T09:53:23.978Z] STF 09:53:20.429 - Heartbeat: Process CL2 is still running [2024-11-21T09:53:53.685Z] CL2 j> 2024/11/21 09:53:51.709 done. [2024-11-21T09:54:06.046Z] CL2 j> 2024/11/21 09:54:04.149 Writing report data ... [2024-11-21T09:55:07.466Z] CL2 j> 2024/11/21 09:54:58.268 done. [2024-11-21T09:55:11.821Z] CL2 j> 2024/11/21 09:55:10.278 Writing report data ... [2024-11-21T09:56:05.637Z] CL2 j> 2024/11/21 09:56:01.711 done. [2024-11-21T09:56:15.230Z] CL2 j> 2024/11/21 09:56:14.135 Writing report data ... [2024-11-21T09:56:59.872Z] CL2 j> 2024/11/21 09:56:58.816 done. [2024-11-21T09:57:11.714Z] CL2 j> 2024/11/21 09:57:10.331 Writing report data ... [2024-11-21T09:58:05.125Z] CL2 j> 2024/11/21 09:58:02.089 done. [2024-11-21T09:58:15.527Z] CL2 j> 2024/11/21 09:58:13.338 Writing report data ... [2024-11-21T09:58:21.527Z] STF 09:58:20.434 - Heartbeat: Process CL2 is still running [2024-11-21T09:59:06.627Z] CL2 j> 2024/11/21 09:59:00.686 done. [2024-11-21T09:59:12.888Z] CL2 j> 2024/11/21 09:59:12.023 Writing report data ... [2024-11-21T10:00:02.872Z] CL2 j> 2024/11/21 09:59:57.305 done. [2024-11-21T10:00:09.371Z] CL2 j> 2024/11/21 10:00:09.112 Writing report data ... [2024-11-21T10:00:59.288Z] CL2 j> 2024/11/21 10:00:53.179 done. [2024-11-21T10:01:04.884Z] CL2 j> 2024/11/21 10:01:04.466 Writing report data ... [2024-11-21T10:02:03.281Z] CL2 j> 2024/11/21 10:01:54.448 done. [2024-11-21T10:02:07.142Z] CL2 j> 2024/11/21 10:02:06.539 Writing report data ... [2024-11-21T10:03:05.308Z] CL2 j> 2024/11/21 10:03:00.954 done. [2024-11-21T10:03:14.299Z] CL2 j> 2024/11/21 10:03:13.089 Writing report data ... [2024-11-21T10:03:20.797Z] STF 10:03:20.020 - Heartbeat: Process CL2 is still running [2024-11-21T10:04:10.456Z] CL2 j> 2024/11/21 10:04:06.091 done. [2024-11-21T10:04:19.610Z] CL2 j> 2024/11/21 10:04:17.978 Writing report data ... [2024-11-21T10:05:09.593Z] CL2 j> 2024/11/21 10:05:05.748 done. [2024-11-21T10:05:19.317Z] CL2 j> 2024/11/21 10:05:17.662 Writing report data ... [2024-11-21T10:06:09.227Z] CL2 j> 2024/11/21 10:06:05.943 done. [2024-11-21T10:06:18.087Z] CL2 j> 2024/11/21 10:06:17.176 Writing report data ... [2024-11-21T10:07:08.498Z] CL2 j> 2024/11/21 10:07:06.169 done. [2024-11-21T10:07:18.933Z] CL2 j> 2024/11/21 10:07:17.590 Writing report data ... [2024-11-21T10:07:56.350Z] STF 10:07:53.473 - **FAILED** Process CL2 has timed out [2024-11-21T10:07:56.350Z] STF 10:07:53.473 - Collecting dumps for: CL2 [2024-11-21T10:07:56.350Z] STF 10:07:53.473 - Sending SIG 3 to the java process to generate a javacore [2024-11-21T10:07:56.350Z] STF 10:07:53.474 - Running command: kill -3 816694 [2024-11-21T10:07:56.350Z] STF 10:07:53.474 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/6.CL2.kill_3.stderr [2024-11-21T10:07:56.350Z] STF 10:07:53.474 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/results/6.CL2.kill_3.stdout [2024-11-21T10:07:56.350Z] STF 10:07:53.656 - Pausing for 30 seconds [2024-11-21T10:08:27.732Z] STF 10:08:23.656 - Process CL2 (pid 816694) is no longer running. Abandoning dump collection. [2024-11-21T10:08:27.732Z] CL2 2024-11-21 10:07:53 [2024-11-21T10:08:27.732Z] CL2 Full thread dump OpenJDK 64-Bit Server VM (21.0.6-beta+4-ea mixed mode, sharing): [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 Threads class SMR info: [2024-11-21T10:08:27.732Z] CL2 _java_thread_list=0x00007f2320c06780, length=14, elements={ [2024-11-21T10:08:27.732Z] CL2 0x00007f23920f48a0, 0x00007f236a087fe0, 0x00007f236a079050, 0x00007f236a07a030, [2024-11-21T10:08:27.732Z] CL2 0x00007f236a07a820, 0x00007f236a07b800, 0x00007f236a07bff0, 0x00007f236a0727e0, [2024-11-21T10:08:27.732Z] CL2 0x00007f236a075fd0, 0x00007f236a077f90, 0x00007f23212c2020, 0x00007f23212c3740, [2024-11-21T10:08:27.732Z] CL2 0x00007f23212c3f40, 0x00007f23212c6f70 [2024-11-21T10:08:27.732Z] CL2 } [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "main" #1 [816695] prio=5 os_prio=0 cpu=6097.89ms elapsed=1801.07s tid=0x00007f23920f48a0 nid=816695 runnable [0x00007f23920d9000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: RUNNABLE [2024-11-21T10:08:27.732Z] CL2 at sun.nio.ch.Net.poll(java.base@21.0.6-beta/Native Method) [2024-11-21T10:08:27.732Z] CL2 at sun.nio.ch.NioSocketImpl.park(java.base@21.0.6-beta/NioSocketImpl.java:191) [2024-11-21T10:08:27.732Z] CL2 at sun.nio.ch.NioSocketImpl.park(java.base@21.0.6-beta/NioSocketImpl.java:201) [2024-11-21T10:08:27.732Z] CL2 at sun.nio.ch.NioSocketImpl.implRead(java.base@21.0.6-beta/NioSocketImpl.java:309) [2024-11-21T10:08:27.732Z] CL2 at sun.nio.ch.NioSocketImpl.read(java.base@21.0.6-beta/NioSocketImpl.java:346) [2024-11-21T10:08:27.732Z] CL2 at sun.nio.ch.NioSocketImpl$1.read(java.base@21.0.6-beta/NioSocketImpl.java:796) [2024-11-21T10:08:27.732Z] CL2 at java.net.Socket$SocketInputStream.read(java.base@21.0.6-beta/Socket.java:1099) [2024-11-21T10:08:27.732Z] CL2 at java.io.BufferedInputStream.fill(java.base@21.0.6-beta/BufferedInputStream.java:291) [2024-11-21T10:08:27.732Z] CL2 at java.io.BufferedInputStream.implRead(java.base@21.0.6-beta/BufferedInputStream.java:325) [2024-11-21T10:08:27.732Z] CL2 at java.io.BufferedInputStream.read(java.base@21.0.6-beta/BufferedInputStream.java:312) [2024-11-21T10:08:27.732Z] CL2 at java.io.DataInputStream.readUnsignedByte(java.base@21.0.6-beta/DataInputStream.java:295) [2024-11-21T10:08:27.732Z] CL2 at java.io.DataInputStream.readByte(java.base@21.0.6-beta/DataInputStream.java:275) [2024-11-21T10:08:27.732Z] CL2 at sun.rmi.transport.StreamRemoteCall.executeCall(java.rmi@21.0.6-beta/StreamRemoteCall.java:241) [2024-11-21T10:08:27.732Z] CL2 at sun.rmi.server.UnicastRef.invoke(java.rmi@21.0.6-beta/UnicastRef.java:166) [2024-11-21T10:08:27.732Z] CL2 at jdk.jmx.remote.internal.rmi.PRef.invoke(jdk.remoteref/Unknown Source) [2024-11-21T10:08:27.732Z] CL2 at javax.management.remote.rmi.RMIConnectionImpl_Stub.invoke(java.management.rmi@21.0.6-beta/RMIConnectionImpl_Stub.java:419) [2024-11-21T10:08:27.732Z] CL2 at javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.invoke(java.management.rmi@21.0.6-beta/RMIConnector.java:1020) [2024-11-21T10:08:27.732Z] CL2 at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:628) [2024-11-21T10:08:27.732Z] CL2 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:199) [2024-11-21T10:08:27.732Z] CL2 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "Reference Handler" #9 [816711] daemon prio=10 os_prio=0 cpu=1.38ms elapsed=1800.79s tid=0x00007f236a087fe0 nid=816711 waiting on condition [0x00007f2321efc000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: RUNNABLE [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.Reference.waitForReferencePendingList(java.base@21.0.6-beta/Native Method) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.Reference.processPendingReferences(java.base@21.0.6-beta/Reference.java:246) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.Reference$ReferenceHandler.run(java.base@21.0.6-beta/Reference.java:208) [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "Finalizer" #10 [816712] daemon prio=8 os_prio=0 cpu=0.35ms elapsed=1800.77s tid=0x00007f236a079050 nid=816712 in Object.wait() [0x00007f2321df8000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: WAITING (on object monitor) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Object.wait0(java.base@21.0.6-beta/Native Method) [2024-11-21T10:08:27.732Z] CL2 - waiting on <0x00007f236a362de8> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:366) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:339) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.NativeReferenceQueue.await(java.base@21.0.6-beta/NativeReferenceQueue.java:48) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:158) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.NativeReferenceQueue.remove(java.base@21.0.6-beta/NativeReferenceQueue.java:89) [2024-11-21T10:08:27.732Z] CL2 - locked <0x00007f236a362de8> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.Finalizer$FinalizerThread.run(java.base@21.0.6-beta/Finalizer.java:173) [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "Signal Dispatcher" #11 [816713] daemon prio=9 os_prio=0 cpu=0.21ms elapsed=1800.77s tid=0x00007f236a07a030 nid=816713 waiting on condition [0x0000000000000000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: RUNNABLE [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "Service Thread" #12 [816714] daemon prio=9 os_prio=0 cpu=1.45ms elapsed=1800.77s tid=0x00007f236a07a820 nid=816714 runnable [0x0000000000000000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: RUNNABLE [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "Monitor Deflation Thread" #13 [816715] daemon prio=9 os_prio=0 cpu=134.14ms elapsed=1800.76s tid=0x00007f236a07b800 nid=816715 runnable [0x0000000000000000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: RUNNABLE [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "C2 CompilerThread0" #14 [816716] daemon prio=9 os_prio=0 cpu=9161.47ms elapsed=1800.76s tid=0x00007f236a07bff0 nid=816716 waiting on condition [0x0000000000000000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: RUNNABLE [2024-11-21T10:08:27.732Z] CL2 No compile task [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "C1 CompilerThread0" #16 [816717] daemon prio=9 os_prio=0 cpu=1097.55ms elapsed=1800.73s tid=0x00007f236a0727e0 nid=816717 waiting on condition [0x0000000000000000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: RUNNABLE [2024-11-21T10:08:27.732Z] CL2 No compile task [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "Notification Thread" #17 [816719] daemon prio=9 os_prio=0 cpu=0.07ms elapsed=1800.70s tid=0x00007f236a075fd0 nid=816719 runnable [0x0000000000000000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: RUNNABLE [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "Common-Cleaner" #18 [816720] daemon prio=8 os_prio=0 cpu=4.25ms elapsed=1800.69s tid=0x00007f236a077f90 nid=816720 waiting on condition [0x00007f23216bc000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-11-21T10:08:27.732Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-11-21T10:08:27.732Z] CL2 - parking to wait for <0x00007f236a3511f0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1852) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.ReferenceQueue.await(java.base@21.0.6-beta/ReferenceQueue.java:71) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:143) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.6-beta/ReferenceQueue.java:218) [2024-11-21T10:08:27.732Z] CL2 at jdk.internal.ref.CleanerImpl.run(java.base@21.0.6-beta/CleanerImpl.java:140) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-11-21T10:08:27.732Z] CL2 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.6-beta/InnocuousThread.java:186) [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "RMI Scheduler(0)" #20 [816748] daemon prio=5 os_prio=0 cpu=32.02ms elapsed=1790.46s tid=0x00007f23212c2020 nid=816748 waiting on condition [0x00007f2321272000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-11-21T10:08:27.732Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-11-21T10:08:27.732Z] CL2 - parking to wait for <0x00007f236a35ab50> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1763) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.6-beta/ScheduledThreadPoolExecutor.java:1182) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.6-beta/ScheduledThreadPoolExecutor.java:899) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@21.0.6-beta/ThreadPoolExecutor.java:1070) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@21.0.6-beta/ThreadPoolExecutor.java:1130) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@21.0.6-beta/ThreadPoolExecutor.java:642) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "RMI RenewClean-[172.17.0.5:41877]" #21 [816749] daemon prio=5 os_prio=0 cpu=14.93ms elapsed=1790.23s tid=0x00007f23212c3740 nid=816749 waiting on condition [0x00007f232116f000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: TIMED_WAITING (parking) [2024-11-21T10:08:27.732Z] CL2 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-11-21T10:08:27.732Z] CL2 - parking to wait for <0x00007f236a363278> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-11-21T10:08:27.732Z] CL2 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1852) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.ReferenceQueue.await(java.base@21.0.6-beta/ReferenceQueue.java:71) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:143) [2024-11-21T10:08:27.732Z] CL2 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.6-beta/ReferenceQueue.java:218) [2024-11-21T10:08:27.732Z] CL2 at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(java.rmi@21.0.6-beta/DGCClient.java:567) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "RMI GC Daemon" #22 [816750] daemon prio=2 os_prio=0 cpu=0.34ms elapsed=1790.21s tid=0x00007f23212c3f40 nid=816750 in Object.wait() [0x00007f232106d000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Object.wait0(java.base@21.0.6-beta/Native Method) [2024-11-21T10:08:27.732Z] CL2 - waiting on <0x00007f236a3634c0> (a sun.rmi.transport.GC$LatencyLock) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:366) [2024-11-21T10:08:27.732Z] CL2 at sun.rmi.transport.GC$Daemon.run(java.rmi@21.0.6-beta/GC.java:127) [2024-11-21T10:08:27.732Z] CL2 - locked <0x00007f236a3634c0> (a sun.rmi.transport.GC$LatencyLock) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-11-21T10:08:27.732Z] CL2 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.6-beta/InnocuousThread.java:186) [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "JMX client heartbeat 2" #23 [816755] daemon prio=5 os_prio=0 cpu=21.06ms elapsed=1789.02s tid=0x00007f23212c6f70 nid=816755 waiting on condition [0x00007f2320f6a000] [2024-11-21T10:08:27.732Z] CL2 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.sleep0(java.base@21.0.6-beta/Native Method) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.sleep(java.base@21.0.6-beta/Thread.java:509) [2024-11-21T10:08:27.732Z] CL2 at com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker.run(java.management@21.0.6-beta/ClientCommunicatorAdmin.java:180) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-11-21T10:08:27.732Z] CL2 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "GC Thread#3" os_prio=0 cpu=18.23ms elapsed=1765.38s tid=0x00007f232136a700 nid=816806 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "GC Thread#2" os_prio=0 cpu=9.06ms elapsed=1765.38s tid=0x00007f2321344740 nid=816805 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "GC Thread#1" os_prio=0 cpu=13.60ms elapsed=1765.74s tid=0x00007f2321342770 nid=816804 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "VM Thread" os_prio=0 cpu=58.48ms elapsed=1800.82s tid=0x00007f236a08e6c0 nid=816710 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "VM Periodic Task Thread" os_prio=0 cpu=751.99ms elapsed=1800.87s tid=0x00007f236a056710 nid=816709 waiting on condition [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "G1 Service" os_prio=0 cpu=43.05ms elapsed=1800.97s tid=0x00007f236a0a50f0 nid=816704 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "G1 Refine#0" os_prio=0 cpu=144.61ms elapsed=1800.98s tid=0x00007f2389826b60 nid=816700 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "G1 Main Marker" os_prio=0 cpu=0.08ms elapsed=1800.99s tid=0x00007f2389801030 nid=816698 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "GC Thread#0" os_prio=0 cpu=15.53ms elapsed=1801.03s tid=0x00007f2389824bf0 nid=816697 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 "G1 Conc#0" os_prio=0 cpu=0.04ms elapsed=1800.98s tid=0x00007f23898017f0 nid=816699 runnable [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 JNI global refs: 17, weak refs: 0 [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 Heap [2024-11-21T10:08:27.732Z] CL2 garbage-first heap total 100352K, used 51113K [0x00007f236a200000, 0x00007f237a200000) [2024-11-21T10:08:27.732Z] CL2 region size 1024K, 46 young (47104K), 1 survivors (1024K) [2024-11-21T10:08:27.732Z] CL2 Metaspace used 6479K, committed 6912K, reserved 1114112K [2024-11-21T10:08:27.732Z] CL2 class space used 617K, committed 832K, reserved 1048576K [2024-11-21T10:08:27.732Z] CL2 [2024-11-21T10:08:27.732Z] CL2 j> 2024/11/21 10:08:05.264 done. [2024-11-21T10:08:27.732Z] CL2 j> 2024/11/21 10:08:07.500 Problem with the MBean access [2024-11-21T10:08:27.732Z] CL2 stderr java.rmi.ConnectIOException: error during JRMP connection establishment; nested exception is: [2024-11-21T10:08:27.732Z] CL2 stderr java.net.SocketException: Connection reset [2024-11-21T10:08:27.732Z] CL2 stderr at java.rmi/sun.rmi.transport.tcp.TCPChannel.createConnection(TCPChannel.java:308) [2024-11-21T10:08:27.732Z] CL2 stderr at java.rmi/sun.rmi.transport.tcp.TCPChannel.newConnection(TCPChannel.java:204) [2024-11-21T10:08:27.732Z] CL2 stderr at java.rmi/sun.rmi.server.UnicastRef.invoke(UnicastRef.java:134) [2024-11-21T10:08:27.732Z] CL2 stderr at jdk.remoteref/jdk.jmx.remote.internal.rmi.PRef.invoke(Unknown Source) [2024-11-21T10:08:27.732Z] CL2 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnectionImpl_Stub.getAttribute(RMIConnectionImpl_Stub.java:279) [2024-11-21T10:08:27.732Z] CL2 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.getAttribute(RMIConnector.java:903) [2024-11-21T10:08:27.732Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.recordThreadStats(ThreadProfiler.java:255) [2024-11-21T10:08:27.732Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:204) [2024-11-21T10:08:27.732Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-11-21T10:08:27.733Z] CL2 stderr Caused by: java.net.SocketException: Connection reset [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:318) [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/java.io.DataInputStream.readUnsignedByte(DataInputStream.java:295) [2024-11-21T10:08:27.733Z] CL2 stderr at java.base/java.io.DataInputStream.readByte(DataInputStream.java:275) [2024-11-21T10:08:27.733Z] CL2 stderr at java.rmi/sun.rmi.transport.tcp.TCPChannel.createConnection(TCPChannel.java:247) [2024-11-21T10:08:27.733Z] CL2 stderr ... 8 more [2024-11-21T10:08:27.733Z] CL2 stderr Exception in thread "main" java.lang.AssertionError: Problem with the MBean access [2024-11-21T10:08:27.733Z] CL2 stderr at org.junit.Assert.fail(Assert.java:88) [2024-11-21T10:08:27.733Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.recordThreadStats(ThreadProfiler.java:271) [2024-11-21T10:08:27.733Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaServer(ThreadProfiler.java:204) [2024-11-21T10:08:27.733Z] CL2 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:99) [2024-11-21T10:08:27.733Z] STF 10:08:23.657 - Monitoring Report Summary: [2024-11-21T10:08:27.733Z] STF 10:08:23.657 - o Process CL2 has timed out [2024-11-21T10:08:27.733Z] STF 10:08:23.657 - o Process LT2 ended sucessfully [2024-11-21T10:08:27.733Z] STF 10:08:23.658 - Killing processes: CL2 LT2 [2024-11-21T10:08:27.733Z] STF 10:08:23.658 - o Process LT2 pid 816693 is not running [2024-11-21T10:08:27.733Z] STF 10:08:23.658 - o Process CL2 pid 816694 is not running [2024-11-21T10:08:27.733Z] **FAILED** at step 7 (Wait for processes to complete). Expected return value=0 Actual=1 at /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/execute.pl line 296. [2024-11-21T10:08:27.733Z] STF 10:08:24.035 - **FAILED** execute script failed. Expected return value=0 Actual=1 [2024-11-21T10:08:27.733Z] STF 10:08:24.035 - [2024-11-21T10:08:27.733Z] STF 10:08:24.035 - ==================== T E A R D O W N ==================== [2024-11-21T10:08:27.733Z] STF 10:08:24.035 - Running teardown: perl /home/jenkins/workspace/Test_openjdk21_hs_sanity.system_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17321768445910/TestJlmRemoteThreadNoAuth_1/20241121-091807-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-11-21T10:08:27.733Z] STF 10:08:24.601 - TEARDOWN stage completed [2024-11-21T10:08:27.733Z] STF 10:08:24.637 - [2024-11-21T10:08:27.733Z] STF 10:08:24.639 - ===================== R E S U L T S ===================== [2024-11-21T10:08:27.733Z] STF 10:08:24.639 - Stage results: [2024-11-21T10:08:27.733Z] STF 10:08:24.639 - setUp: pass [2024-11-21T10:08:27.733Z] STF 10:08:24.639 - execute: *fail* [2024-11-21T10:08:27.733Z] STF 10:08:24.639 - teardown: pass [2024-11-21T10:08:27.733Z] STF 10:08:24.639 - [2024-11-21T10:08:27.733Z] STF 10:08:24.639 - Overall result: **FAILED** [2024-11-21T10:08:27.733Z] ----------------------------------- [2024-11-21T10:08:27.733Z] TestJlmRemoteThreadNoAuth_1_FAILED [2024-11-21T10:08:27.733Z] ----------------------------------- [2024-11-21T10:08:27.733Z] [2024-11-21T10:08:27.733Z] TEST TEARDOWN: [2024-11-21T10:08:27.733Z] Nothing to be done for teardown. [2024-11-21T10:08:27.733Z] TestJlmRemoteThreadNoAuth_1 Finish Time: Thu Nov 21 10:08:24 2024 Epoch Time (ms): 1732183704751