[2024-08-07T23:09:04.676Z] Running test TestJlmRemoteThreadNoAuth_2 ... [2024-08-07T23:09:04.676Z] =============================================== [2024-08-07T23:09:04.676Z] TestJlmRemoteThreadNoAuth_2 Start Time: Wed Aug 7 23:09:04 2024 Epoch Time (ms): 1723072144574 [2024-08-07T23:09:04.676Z] variation: Mode1000 [2024-08-07T23:09:05.437Z] JVM_OPTIONS: [2024-08-07T23:09:05.437Z] { \ [2024-08-07T23:09:05.437Z] echo ""; echo "TEST SETUP:"; \ [2024-08-07T23:09:05.437Z] echo "Nothing to be done for setup."; \ [2024-08-07T23:09:05.437Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2"; \ [2024-08-07T23:09:05.437Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2"; \ [2024-08-07T23:09:05.437Z] echo ""; echo "TESTING:"; \ [2024-08-07T23:09:05.437Z] perl /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/../../testDependency/system_lib/" -java-args=' ' -results-root="/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2" -test=TestJlmRemoteThreadNoAuth; \ [2024-08-07T23:09:05.437Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_2""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2"; else echo "-----------------------------------"; echo "TestJlmRemoteThreadNoAuth_2""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-07T23:09:05.437Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-07T23:09:05.437Z] echo "Nothing to be done for teardown."; \ [2024-08-07T23:09:05.437Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestTargetResult"; [2024-08-07T23:09:05.437Z] [2024-08-07T23:09:05.437Z] TEST SETUP: [2024-08-07T23:09:05.437Z] Nothing to be done for setup. [2024-08-07T23:09:05.437Z] [2024-08-07T23:09:05.437Z] TESTING: [2024-08-07T23:09:05.437Z] STF 23:09:05.033 - ========================= S T F ========================= [2024-08-07T23:09:05.437Z] systemtest-prereqs has been processed, and set to: /home/jenkins/testDependency/system_libRetrieving amount of free space on drive containing /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2 [2024-08-07T23:09:05.437Z] There is 229676 Mb free [2024-08-07T23:09:05.437Z] STF 23:09:05.086 - ================== G E N E R A T I O N ================== [2024-08-07T23:09:05.437Z] STF 23:09:05.087 - Checking JVM: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image [2024-08-07T23:09:05.437Z] STF 23:09:05.087 - Starting process to generate scripts: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/../../testDependency/system_lib//log4j/log4j-api.jar:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/../../testDependency/system_lib//log4j/log4j-core.jar:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/stf_parameters.properties, , /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth" [2024-08-07T23:09:25.349Z] GEN 23:09:23.787 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteThreadNoAuth.class' Dir: '/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-08-07T23:09:25.349Z] GEN 23:09:23.970 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteThreadNoAuth' [2024-08-07T23:09:28.071Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-08-07T23:09:28.071Z] GEN /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-08-07T23:09:28.071Z] GEN /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.core/bin [2024-08-07T23:09:28.071Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-api.jar [2024-08-07T23:09:28.071Z] GEN /home/jenkins/testDependency/system_lib/log4j/log4j-core.jar [2024-08-07T23:09:28.071Z] GEN /home/jenkins/testDependency/system_lib/junit/junit.jar [2024-08-07T23:09:30.480Z] GEN 23:09:29.574 - Using Mode NoOptions. Values = '' [2024-08-07T23:09:32.021Z] GEN 23:09:31.802 - [2024-08-07T23:09:32.021Z] GEN 23:09:31.802 - Test command summary: [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - Step Stage Command Description [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - -----+--------+-----------------+------------ [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - 1 execute Run java Running ThreadProfiler Proxy test Server Process [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - 2 execute Run java Running the monitoring Client with proxy connection(without security) [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - 3 execute Monitor Wait for processes to complete [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - 4 execute kill Stop LT1 process [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - 5 execute Run java Running ThreadProfiler Server test Server Process(without security) [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - 6 execute Run java Running the Monitoring Client with server-connection(without security) [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - 7 execute Monitor Wait for processes to complete [2024-08-07T23:09:32.021Z] GEN 23:09:31.803 - 8 execute kill Stop LT2 process [2024-08-07T23:09:34.842Z] STF 23:09:33.759 - [2024-08-07T23:09:34.842Z] STF 23:09:33.759 - Script generation completed [2024-08-07T23:09:34.842Z] STF 23:09:33.759 - [2024-08-07T23:09:34.842Z] STF 23:09:33.759 - [2024-08-07T23:09:34.842Z] STF 23:09:33.759 - ======================= S E T U P ======================= [2024-08-07T23:09:34.842Z] STF 23:09:33.759 - Running setup: perl /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/setUp.pl [2024-08-07T23:09:34.842Z] STF 23:09:34.182 - SETUP stage completed [2024-08-07T23:09:34.842Z] STF 23:09:34.246 - [2024-08-07T23:09:34.842Z] STF 23:09:34.246 - ==================== E X E C U T E - ==================== [2024-08-07T23:09:34.842Z] STF 23:09:34.246 - Running execute: perl /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/execute.pl [2024-08-07T23:09:34.842Z] STF 23:09:34.749 - [2024-08-07T23:09:34.842Z] STF 23:09:34.749 - Java version [2024-08-07T23:09:34.842Z] STF 23:09:34.749 - Running: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java -version [2024-08-07T23:09:37.288Z] openjdk version "11.0.25-beta" 2024-10-15 [2024-08-07T23:09:37.288Z] OpenJDK Runtime Environment Temurin-11.0.25+2-202408072006 (build 11.0.25-beta+2-ea) [2024-08-07T23:09:37.288Z] OpenJDK Server VM Temurin-11.0.25+2-202408072006 (build 11.0.25-beta+2-ea, mixed mode) [2024-08-07T23:09:38.036Z] STF 23:09:37.407 - [2024-08-07T23:09:38.036Z] STF 23:09:37.407 - +------ Step 1 - Running ThreadProfiler Proxy test Server Process [2024-08-07T23:09:38.036Z] STF 23:09:37.407 - | Start background process [2024-08-07T23:09:38.037Z] STF 23:09:37.407 - | Program: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-07T23:09:38.037Z] STF 23:09:37.407 - | Mnemonic: LT1 [2024-08-07T23:09:38.037Z] STF 23:09:37.407 - | Echo: ECHO_OFF [2024-08-07T23:09:38.037Z] STF 23:09:37.408 - | Expectation: NEVER [2024-08-07T23:09:38.037Z] STF 23:09:37.408 - | [2024-08-07T23:09:38.037Z] STF 23:09:37.408 - Running command: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -classpath /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/STF/stf.load/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_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_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-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_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/1.LT1.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.mini-mix.threadCount 30 -suite.mini-mix.selection random -suite.mini-mix.seed -1 -suite.mini-mix.repeatCount 1 -suite.mini-mix.thinkingTime 0ms..0ms [2024-08-07T23:09:38.037Z] STF 23:09:37.408 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/1.LT1.stderr [2024-08-07T23:09:38.037Z] STF 23:09:37.408 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/1.LT1.stdout [2024-08-07T23:09:38.037Z] STF 23:09:37.418 - [2024-08-07T23:09:38.037Z] STF 23:09:37.418 - +------ Step 2 - Running the monitoring Client with proxy connection(without security) [2024-08-07T23:09:38.037Z] STF 23:09:37.418 - | Start background process [2024-08-07T23:09:38.037Z] STF 23:09:37.418 - | Program: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java [2024-08-07T23:09:38.037Z] STF 23:09:37.418 - | Mnemonic: CL1 [2024-08-07T23:09:38.037Z] STF 23:09:37.418 - | Echo: ECHO_ON [2024-08-07T23:09:38.037Z] STF 23:09:37.418 - | Expectation: CLEAN_RUN within 30m [2024-08-07T23:09:38.037Z] STF 23:09:37.418 - | [2024-08-07T23:09:38.037Z] STF 23:09:37.419 - Running command: /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/jdkbinary/j2sdk-image/bin/java -Xmx256m -classpath /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_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_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/thd_proxy.log /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/thd_proxy.csv anon localhost 1234 [2024-08-07T23:09:38.037Z] STF 23:09:37.419 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/2.CL1.stderr [2024-08-07T23:09:38.037Z] STF 23:09:37.419 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/2.CL1.stdout [2024-08-07T23:09:38.037Z] STF 23:09:37.421 - [2024-08-07T23:09:38.037Z] STF 23:09:37.421 - +------ Step 3 - Wait for processes to complete [2024-08-07T23:09:38.037Z] STF 23:09:37.421 - | Wait for processes to meet expectations [2024-08-07T23:09:38.037Z] STF 23:09:37.421 - | Processes: [LT1, CL1] [2024-08-07T23:09:38.037Z] STF 23:09:37.421 - | [2024-08-07T23:09:38.037Z] STF 23:09:37.421 - Monitoring processes: CL1 LT1 [2024-08-07T23:09:45.462Z] CL1 j> 2024/08/07 23:09:42.885 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-08-07T23:09:45.462Z] CL1 j> 2024/08/07 23:09:45.148 Attempting to connect [2024-08-07T23:09:55.570Z] CL1 j> 2024/08/07 23:09:53.934 Connection established! [2024-08-07T23:09:57.155Z] CL1 j> 2024/08/07 23:09:56.318 Starting to write data [2024-08-07T23:14:40.700Z] STF 23:14:34.370 - Heartbeat: Process LT1 is still running [2024-08-07T23:19:42.568Z] STF 23:19:34.445 - Heartbeat: Process LT1 is still running [2024-08-07T23:24:40.716Z] STF 23:24:34.503 - Heartbeat: Process LT1 is still running [2024-08-07T23:29:38.492Z] STF 23:29:34.127 - Heartbeat: Process LT1 is still running [2024-08-07T23:34:35.744Z] STF 23:34:34.423 - Heartbeat: Process LT1 is still running [2024-08-07T23:39:49.393Z] STF 23:39:34.276 - Heartbeat: Process LT1 is still running [2024-08-07T23:39:49.393Z] STF 23:39:38.289 - **FAILED** Process CL1 has timed out [2024-08-07T23:39:49.393Z] STF 23:39:38.289 - Collecting dumps for: CL1 [2024-08-07T23:39:49.393Z] STF 23:39:38.289 - Sending SIG 3 to the java process to generate a javacore [2024-08-07T23:39:49.393Z] STF 23:39:38.290 - Running command: kill -3 13437 [2024-08-07T23:39:49.393Z] STF 23:39:38.290 - Redirecting stderr to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/2.CL1.kill_3.stderr [2024-08-07T23:39:49.393Z] STF 23:39:38.290 - Redirecting stdout to /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/results/2.CL1.kill_3.stdout [2024-08-07T23:39:49.393Z] STF 23:39:38.293 - Pausing for 30 seconds [2024-08-07T23:40:08.792Z] STF 23:40:08.294 - Process CL1 (pid 13437) is no longer running. Abandoning dump collection. [2024-08-07T23:40:08.792Z] CL1 ........................2024-08-07 23:39:38 [2024-08-07T23:40:08.792Z] CL1 Full thread dump OpenJDK Server VM (11.0.25-beta+2-ea mixed mode): [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 Threads class SMR info: [2024-08-07T23:40:08.792Z] CL1 _java_thread_list=0xe3b01608, length=12, elements={ [2024-08-07T23:40:08.792Z] CL1 0xf6e14400, 0xe332bc00, 0xe332f400, 0xe3338800, [2024-08-07T23:40:08.792Z] CL1 0xe333a000, 0xe333b800, 0xe333d000, 0xe337c800, [2024-08-07T23:40:08.792Z] CL1 0xe2e85000, 0xe2e8b400, 0xe2e8c800, 0xe2e10c00 [2024-08-07T23:40:08.792Z] CL1 } [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "main" #1 prio=5 os_prio=0 cpu=5213.09ms elapsed=1799.42s tid=0xf6e14400 nid=0x347e runnable [0xf6ffd000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: RUNNABLE [2024-08-07T23:40:08.792Z] CL1 at java.net.SocketInputStream.socketRead0(java.base@11.0.25-beta/Native Method) [2024-08-07T23:40:08.792Z] CL1 at java.net.SocketInputStream.socketRead(java.base@11.0.25-beta/SocketInputStream.java:115) [2024-08-07T23:40:08.792Z] CL1 at java.net.SocketInputStream.read(java.base@11.0.25-beta/SocketInputStream.java:168) [2024-08-07T23:40:08.792Z] CL1 at java.net.SocketInputStream.read(java.base@11.0.25-beta/SocketInputStream.java:140) [2024-08-07T23:40:08.792Z] CL1 at java.io.BufferedInputStream.fill(java.base@11.0.25-beta/BufferedInputStream.java:252) [2024-08-07T23:40:08.792Z] CL1 at java.io.BufferedInputStream.read(java.base@11.0.25-beta/BufferedInputStream.java:271) [2024-08-07T23:40:08.792Z] CL1 - locked <0xe9520b78> (a java.io.BufferedInputStream) [2024-08-07T23:40:08.792Z] CL1 at java.io.DataInputStream.readByte(java.base@11.0.25-beta/DataInputStream.java:270) [2024-08-07T23:40:08.792Z] CL1 at sun.rmi.transport.StreamRemoteCall.executeCall(java.rmi@11.0.25-beta/StreamRemoteCall.java:240) [2024-08-07T23:40:08.792Z] CL1 at sun.rmi.server.UnicastRef.invoke(java.rmi@11.0.25-beta/UnicastRef.java:164) [2024-08-07T23:40:08.792Z] CL1 at jdk.jmx.remote.internal.rmi.PRef.invoke(jdk.remoteref/Unknown Source) [2024-08-07T23:40:08.792Z] CL1 at javax.management.remote.rmi.RMIConnectionImpl_Stub.invoke(java.management.rmi@11.0.25-beta/Unknown Source) [2024-08-07T23:40:08.792Z] CL1 at javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.invoke(java.management.rmi@11.0.25-beta/RMIConnector.java:1021) [2024-08-07T23:40:08.792Z] CL1 at com.sun.jmx.mbeanserver.MXBeanProxy$InvokeHandler.invoke(java.management@11.0.25-beta/MXBeanProxy.java:150) [2024-08-07T23:40:08.792Z] CL1 at com.sun.jmx.mbeanserver.MXBeanProxy.invoke(java.management@11.0.25-beta/MXBeanProxy.java:167) [2024-08-07T23:40:08.792Z] CL1 at javax.management.MBeanServerInvocationHandler.invoke(java.management@11.0.25-beta/MBeanServerInvocationHandler.java:258) [2024-08-07T23:40:08.792Z] CL1 at com.sun.proxy.$Proxy3.getThreadUserTime(Unknown Source) [2024-08-07T23:40:08.792Z] CL1 at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:271) [2024-08-07T23:40:08.792Z] CL1 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaProxy(ThreadProfiler.java:135) [2024-08-07T23:40:08.792Z] CL1 at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:97) [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "Reference Handler" #2 daemon prio=10 os_prio=0 cpu=2.36ms elapsed=1798.52s tid=0xe332bc00 nid=0x348e waiting on condition [0xe3e7d000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: RUNNABLE [2024-08-07T23:40:08.792Z] CL1 at java.lang.ref.Reference.waitForReferencePendingList(java.base@11.0.25-beta/Native Method) [2024-08-07T23:40:08.792Z] CL1 at java.lang.ref.Reference.processPendingReferences(java.base@11.0.25-beta/Reference.java:241) [2024-08-07T23:40:08.792Z] CL1 at java.lang.ref.Reference$ReferenceHandler.run(java.base@11.0.25-beta/Reference.java:213) [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "Finalizer" #3 daemon prio=8 os_prio=0 cpu=0.48ms elapsed=1798.50s tid=0xe332f400 nid=0x3490 in Object.wait() [0xe3c7d000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: WAITING (on object monitor) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Object.wait(java.base@11.0.25-beta/Native Method) [2024-08-07T23:40:08.792Z] CL1 - waiting on <0xe4c35680> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-07T23:40:08.792Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@11.0.25-beta/ReferenceQueue.java:155) [2024-08-07T23:40:08.792Z] CL1 - waiting to re-lock in wait() <0xe4c35680> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-07T23:40:08.792Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@11.0.25-beta/ReferenceQueue.java:176) [2024-08-07T23:40:08.792Z] CL1 at java.lang.ref.Finalizer$FinalizerThread.run(java.base@11.0.25-beta/Finalizer.java:170) [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "Signal Dispatcher" #4 daemon prio=9 os_prio=0 cpu=0.46ms elapsed=1798.27s tid=0xe3338800 nid=0x3496 waiting on condition [0x00000000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: RUNNABLE [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "Service Thread" #5 daemon prio=9 os_prio=0 cpu=0.28ms elapsed=1798.18s tid=0xe333a000 nid=0x3498 runnable [0x00000000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: RUNNABLE [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "C2 CompilerThread0" #6 daemon prio=9 os_prio=0 cpu=2063.23ms elapsed=1798.15s tid=0xe333b800 nid=0x3499 waiting on condition [0x00000000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: RUNNABLE [2024-08-07T23:40:08.792Z] CL1 No compile task [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "Sweeper thread" #8 daemon prio=9 os_prio=0 cpu=0.18ms elapsed=1798.08s tid=0xe333d000 nid=0x349a runnable [0x00000000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: RUNNABLE [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "Common-Cleaner" #9 daemon prio=8 os_prio=0 cpu=2.86ms elapsed=1797.09s tid=0xe337c800 nid=0x349d in Object.wait() [0xe302d000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Object.wait(java.base@11.0.25-beta/Native Method) [2024-08-07T23:40:08.792Z] CL1 - waiting on <0xe4aeda10> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-07T23:40:08.792Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@11.0.25-beta/ReferenceQueue.java:155) [2024-08-07T23:40:08.792Z] CL1 - waiting to re-lock in wait() <0xe4aeda10> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-07T23:40:08.792Z] CL1 at jdk.internal.ref.CleanerImpl.run(java.base@11.0.25-beta/CleanerImpl.java:148) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-07T23:40:08.792Z] CL1 at jdk.internal.misc.InnocuousThread.run(java.base@11.0.25-beta/InnocuousThread.java:161) [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "RMI Scheduler(0)" #11 daemon prio=5 os_prio=0 cpu=34.16ms elapsed=1788.98s tid=0xe2e85000 nid=0x34ab waiting on condition [0xe28fe000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: TIMED_WAITING (parking) [2024-08-07T23:40:08.792Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@11.0.25-beta/Native Method) [2024-08-07T23:40:08.792Z] CL1 - parking to wait for <0xe4c2b2e8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-08-07T23:40:08.792Z] CL1 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@11.0.25-beta/LockSupport.java:234) [2024-08-07T23:40:08.792Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(java.base@11.0.25-beta/AbstractQueuedSynchronizer.java:2123) [2024-08-07T23:40:08.792Z] CL1 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@11.0.25-beta/ScheduledThreadPoolExecutor.java:1182) [2024-08-07T23:40:08.792Z] CL1 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@11.0.25-beta/ScheduledThreadPoolExecutor.java:899) [2024-08-07T23:40:08.792Z] CL1 at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@11.0.25-beta/ThreadPoolExecutor.java:1054) [2024-08-07T23:40:08.792Z] CL1 at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@11.0.25-beta/ThreadPoolExecutor.java:1114) [2024-08-07T23:40:08.792Z] CL1 at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@11.0.25-beta/ThreadPoolExecutor.java:628) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "RMI RenewClean-[172.17.0.8:38733]" #12 daemon prio=5 os_prio=0 cpu=12.04ms elapsed=1788.50s tid=0xe2e8b400 nid=0x34ad in Object.wait() [0xe28ae000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Object.wait(java.base@11.0.25-beta/Native Method) [2024-08-07T23:40:08.792Z] CL1 - waiting on <0xe4c2b688> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-07T23:40:08.792Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@11.0.25-beta/ReferenceQueue.java:155) [2024-08-07T23:40:08.792Z] CL1 - waiting to re-lock in wait() <0xe4c2b688> (a java.lang.ref.ReferenceQueue$Lock) [2024-08-07T23:40:08.792Z] CL1 at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(java.rmi@11.0.25-beta/DGCClient.java:558) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "RMI GC Daemon" #13 daemon prio=2 os_prio=0 cpu=0.36ms elapsed=1788.48s tid=0xe2e8c800 nid=0x34ae in Object.wait() [0xe285e000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Object.wait(java.base@11.0.25-beta/Native Method) [2024-08-07T23:40:08.792Z] CL1 - waiting on <0xe4c389f0> (a sun.rmi.transport.GC$LatencyLock) [2024-08-07T23:40:08.792Z] CL1 at sun.rmi.transport.GC$Daemon.run(java.rmi@11.0.25-beta/GC.java:126) [2024-08-07T23:40:08.792Z] CL1 - waiting to re-lock in wait() <0xe4c389f0> (a sun.rmi.transport.GC$LatencyLock) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-07T23:40:08.792Z] CL1 at jdk.internal.misc.InnocuousThread.run(java.base@11.0.25-beta/InnocuousThread.java:161) [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "JMX client heartbeat 2" #14 daemon prio=5 os_prio=0 cpu=28.77ms elapsed=1784.40s tid=0xe2e10c00 nid=0x34b3 waiting on condition [0xe280e000] [2024-08-07T23:40:08.792Z] CL1 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Thread.sleep(java.base@11.0.25-beta/Native Method) [2024-08-07T23:40:08.792Z] CL1 at com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker.run(java.management@11.0.25-beta/ClientCommunicatorAdmin.java:180) [2024-08-07T23:40:08.792Z] CL1 at java.lang.Thread.run(java.base@11.0.25-beta/Thread.java:829) [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "VM Thread" os_prio=0 cpu=512.03ms elapsed=1799.00s tid=0xe3325400 nid=0x348c runnable [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "GC Thread#0" os_prio=0 cpu=148.21ms elapsed=1799.42s tid=0xf6e28800 nid=0x3480 runnable [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "GC Thread#1" os_prio=0 cpu=138.64ms elapsed=1780.36s tid=0xe3106c00 nid=0x34b9 runnable [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "G1 Main Marker" os_prio=0 cpu=9.08ms elapsed=1799.39s tid=0xf6e70800 nid=0x3481 runnable [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "G1 Conc#0" os_prio=0 cpu=0.06ms elapsed=1799.39s tid=0xf6e71c00 nid=0x3482 runnable [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "G1 Refine#0" os_prio=0 cpu=7.30ms elapsed=1799.39s tid=0xf6ed7c00 nid=0x3483 runnable [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 "G1 Young RemSet Sampling" os_prio=0 cpu=279.02ms elapsed=1799.33s tid=0xf6ed9000 nid=0x3485 runnable [2024-08-07T23:40:08.792Z] CL1 "VM Periodic Task Thread" os_prio=0 cpu=1578.24ms elapsed=1797.10s tid=0xe3377c00 nid=0x349c waiting on condition [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 JNI global refs: 15, weak refs: 0 [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 Heap [2024-08-07T23:40:08.792Z] CL1 garbage-first heap total 81920K, used 5703K [0xe4a00000, 0xf4a00000) [2024-08-07T23:40:08.792Z] CL1 region size 1024K, 4 young (4096K), 1 survivors (1024K) [2024-08-07T23:40:08.792Z] CL1 Metaspace used 9363K, capacity 9641K, committed 9752K, reserved 10544K [2024-08-07T23:40:08.792Z] CL1 [2024-08-07T23:40:08.792Z] CL1 .j> 2024/08/07 23:40:06.860 Exiting as UnmarshalException thrown receiving data from the connected JVM. This may mean the JVM we are connected to has finished. null [2024-08-07T23:40:08.792Z] CL1 stderr java.lang.reflect.UndeclaredThrowableException [2024-08-07T23:40:08.792Z] CL1 stderr at com.sun.proxy.$Proxy3.getThreadCpuTime(Unknown Source) [2024-08-07T23:40:08.792Z] CL1 stderr at net.adoptopenjdk.test.jlm.resources.ThreadData.writeData(ThreadData.java:220) [2024-08-07T23:40:08.792Z] CL1 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaProxy(ThreadProfiler.java:135) [2024-08-07T23:40:08.792Z] CL1 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:97) [2024-08-07T23:40:08.792Z] CL1 stderr Caused by: java.rmi.UnmarshalException: Error unmarshaling return header; nested exception is: [2024-08-07T23:40:08.792Z] CL1 stderr java.io.EOFException [2024-08-07T23:40:08.792Z] CL1 stderr at java.rmi/sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:254) [2024-08-07T23:40:08.792Z] CL1 stderr at java.rmi/sun.rmi.server.UnicastRef.invoke(UnicastRef.java:164) [2024-08-07T23:40:08.792Z] CL1 stderr at jdk.remoteref/jdk.jmx.remote.internal.rmi.PRef.invoke(Unknown Source) [2024-08-07T23:40:08.792Z] CL1 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnectionImpl_Stub.invoke(Unknown Source) [2024-08-07T23:40:08.792Z] CL1 stderr at java.management.rmi/javax.management.remote.rmi.RMIConnector$RemoteMBeanServerConnection.invoke(RMIConnector.java:1021) [2024-08-07T23:40:08.792Z] CL1 stderr at java.management/com.sun.jmx.mbeanserver.MXBeanProxy$InvokeHandler.invoke(MXBeanProxy.java:150) [2024-08-07T23:40:08.792Z] CL1 stderr at java.management/com.sun.jmx.mbeanserver.MXBeanProxy.invoke(MXBeanProxy.java:167) [2024-08-07T23:40:08.792Z] CL1 stderr at java.management/javax.management.MBeanServerInvocationHandler.invoke(MBeanServerInvocationHandler.java:258) [2024-08-07T23:40:08.792Z] CL1 stderr ... 4 more [2024-08-07T23:40:08.792Z] CL1 stderr Caused by: java.io.EOFException [2024-08-07T23:40:08.792Z] CL1 stderr at java.base/java.io.DataInputStream.readByte(DataInputStream.java:272) [2024-08-07T23:40:08.792Z] CL1 stderr at java.rmi/sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:240) [2024-08-07T23:40:08.793Z] CL1 stderr ... 11 more [2024-08-07T23:40:08.793Z] CL1 stderr Exception in thread "main" java.lang.AssertionError: Exiting as UnmarshalException thrown receiving data from the connected JVM. This may mean the JVM we are connected to has finished. null [2024-08-07T23:40:08.793Z] CL1 stderr at org.junit.Assert.fail(Assert.java:88) [2024-08-07T23:40:08.793Z] CL1 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.getStatsViaProxy(ThreadProfiler.java:164) [2024-08-07T23:40:08.793Z] CL1 stderr at net.adoptopenjdk.test.jlm.remote.ThreadProfiler.main(ThreadProfiler.java:97) [2024-08-07T23:40:08.793Z] STF 23:40:08.295 - Monitoring Report Summary: [2024-08-07T23:40:08.793Z] STF 23:40:08.295 - o Process CL1 has timed out [2024-08-07T23:40:08.793Z] STF 23:40:08.296 - o Process LT1 ended sucessfully [2024-08-07T23:40:08.793Z] STF 23:40:08.337 - Killing processes: CL1 LT1 [2024-08-07T23:40:08.793Z] STF 23:40:08.337 - o Process CL1 pid 13437 is not running [2024-08-07T23:40:08.793Z] STF 23:40:08.337 - o Process LT1 pid 13436 is not running [2024-08-07T23:40:08.793Z] **FAILED** at step 3 (Wait for processes to complete). Expected return value=0 Actual=1 at /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/execute.pl line 158. [2024-08-07T23:40:08.793Z] STF 23:40:08.549 - **FAILED** execute script failed. Expected return value=0 Actual=1 [2024-08-07T23:40:08.793Z] STF 23:40:08.549 - [2024-08-07T23:40:08.793Z] STF 23:40:08.549 - ==================== T E A R D O W N ==================== [2024-08-07T23:40:08.793Z] STF 23:40:08.549 - Running teardown: perl /home/jenkins/workspace/Test_openjdk11_hs_sanity.system_arm_linux_testList_0/aqa-tests/TKG/../TKG/output_17230674786682/TestJlmRemoteThreadNoAuth_2/20240807-230905-TestJlmRemoteThreadNoAuth/tearDown.pl [2024-08-07T23:40:09.544Z] STF 23:40:09.218 - TEARDOWN stage completed [2024-08-07T23:40:09.544Z] STF 23:40:09.230 - [2024-08-07T23:40:09.544Z] STF 23:40:09.230 - ===================== R E S U L T S ===================== [2024-08-07T23:40:09.544Z] STF 23:40:09.230 - Stage results: [2024-08-07T23:40:09.544Z] STF 23:40:09.230 - setUp: pass [2024-08-07T23:40:09.544Z] STF 23:40:09.230 - execute: *fail* [2024-08-07T23:40:09.544Z] STF 23:40:09.230 - teardown: pass [2024-08-07T23:40:09.544Z] STF 23:40:09.230 - [2024-08-07T23:40:09.544Z] STF 23:40:09.230 - Overall result: **FAILED** [2024-08-07T23:40:09.544Z] ----------------------------------- [2024-08-07T23:40:09.544Z] TestJlmRemoteThreadNoAuth_2_FAILED [2024-08-07T23:40:09.544Z] ----------------------------------- [2024-08-07T23:40:09.544Z] [2024-08-07T23:40:09.544Z] TEST TEARDOWN: [2024-08-07T23:40:09.544Z] Nothing to be done for teardown. [2024-08-07T23:40:09.544Z] TestJlmRemoteThreadNoAuth_2 Finish Time: Wed Aug 7 23:40:09 2024 Epoch Time (ms): 1723074009298