Test Results Summary Service

No Possible Issues Found via Git Search

TestJlmRemoteClassAuth_1

[2024-10-30T21:49:50.100Z] Running test TestJlmRemoteClassAuth_1 ... [2024-10-30T21:49:50.100Z] =============================================== [2024-10-30T21:49:50.560Z] TestJlmRemoteClassAuth_1 Start Time: Wed Oct 30 14:49:50 2024 Epoch Time (ms): 1730324990068 [2024-10-30T21:49:50.560Z] variation: Mode650 [2024-10-30T21:49:50.560Z] JVM_OPTIONS: -XX:-UseCompressedOops [2024-10-30T21:49:50.560Z] { \ [2024-10-30T21:49:50.560Z] echo ""; echo "TEST SETUP:"; \ [2024-10-30T21:49:50.560Z] echo "Nothing to be done for setup."; \ [2024-10-30T21:49:50.560Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1"; \ [2024-10-30T21:49:50.560Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1"; \ [2024-10-30T21:49:50.560Z] echo ""; echo "TESTING:"; \ [2024-10-30T21:49:50.560Z] perl /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF/stf.core/scripts/stf.pl -test-root="/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//STF;/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/system/jlm/..//aqa-systemtest""" -systemtest-prereqs="/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/../../testDependency/system_lib/" -java-args=' -XX:-UseCompressedOops ' -results-root="/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1" -test=TestJlmRemoteClassAuth; \ [2024-10-30T21:49:50.561Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "TestJlmRemoteClassAuth_1""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1"; else echo "-----------------------------------"; echo "TestJlmRemoteClassAuth_1""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-30T21:49:50.561Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-30T21:49:50.561Z] echo "Nothing to be done for teardown."; \ [2024-10-30T21:49:50.561Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestTargetResult"; [2024-10-30T21:49:50.561Z] [2024-10-30T21:49:50.561Z] TEST SETUP: [2024-10-30T21:49:50.561Z] Nothing to be done for setup. [2024-10-30T21:49:50.561Z] [2024-10-30T21:49:50.561Z] TESTING: [2024-10-30T21:49:50.561Z] STF 14:49:50.306 - ========================= S T F ========================= [2024-10-30T21:49:50.561Z] systemtest-prereqs has been processed, and set to: /Users/admin/workspace/testDependency/system_libRetrieving amount of free space on drive containing /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1 [2024-10-30T21:49:50.561Z] There is 11002 Mb free [2024-10-30T21:49:50.561Z] STF 14:49:50.319 - ================== G E N E R A T I O N ================== [2024-10-30T21:49:50.561Z] STF 14:49:50.320 - Checking JVM: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/../ [2024-10-30T21:49:50.561Z] STF 14:49:50.320 - Starting process to generate scripts: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/..//bin/java -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/../../testDependency/system_lib//log4j/log4j-api.jar:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/../../testDependency/system_lib//log4j/log4j-core.jar:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/stf_parameters.properties, , /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth" [2024-10-30T21:49:50.561Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-10-30T21:49:54.702Z] GEN 14:49:54.020 - Found test. Project: 'openjdk.test.jlm' class: 'TestJlmRemoteClassAuth.class' Dir: '/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin' [2024-10-30T21:49:54.703Z] GEN 14:49:54.053 - Found test. Project: 'openjdk.test.jlm' class: 'net.adoptopenjdk.test.jlm.TestJlmRemoteClassAuth' [2024-10-30T21:49:54.703Z] GEN Classpath directories used by project 'openjdk.test.jlm': [2024-10-30T21:49:54.703Z] GEN /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin [2024-10-30T21:49:54.703Z] GEN /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/STF/stf.core/bin [2024-10-30T21:49:54.703Z] GEN /Users/admin/workspace/testDependency/system_lib/log4j/log4j-api.jar [2024-10-30T21:49:54.703Z] GEN /Users/admin/workspace/testDependency/system_lib/log4j/log4j-core.jar [2024-10-30T21:49:54.703Z] GEN /Users/admin/workspace/testDependency/system_lib/junit/junit.jar [2024-10-30T21:49:55.178Z] GEN 14:49:54.691 - Using Mode NoOptions. Values = '' [2024-10-30T21:49:56.506Z] GEN 14:49:56.100 - [2024-10-30T21:49:56.506Z] GEN 14:49:56.100 - Test command summary: [2024-10-30T21:49:56.506Z] GEN 14:49:56.100 - Step Stage Command Description [2024-10-30T21:49:56.506Z] GEN 14:49:56.100 - -----+--------+-----------------+------------ [2024-10-30T21:49:56.506Z] GEN 14:49:56.100 - 1 setUp cp Copying password file to temp [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 2 setUp chmod Restricting file permission of jmxremote.passsword to 600 [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 3 execute Run java Running ClassProfiler Proxy test Server Process(with security) [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 4 execute Run java Running Monitoring Client with proxy connection(with security) [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 5 execute Monitor Wait for the processes to complete [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 6 execute kill Stop LT1 process [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 7 execute Run java Running ClassProfiler Server test Server Process(with security) [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 8 execute Run java Run the Monitoring Client with server-connection(with security) [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 9 execute Monitor Wait for processes to complete [2024-10-30T21:49:56.506Z] GEN 14:49:56.101 - 10 execute kill Stop LT2 process [2024-10-30T21:49:56.506Z] STF 14:49:56.359 - [2024-10-30T21:49:56.506Z] STF 14:49:56.359 - Script generation completed [2024-10-30T21:49:56.506Z] STF 14:49:56.359 - [2024-10-30T21:49:56.506Z] STF 14:49:56.360 - [2024-10-30T21:49:56.506Z] STF 14:49:56.360 - ======================= S E T U P ======================= [2024-10-30T21:49:56.506Z] STF 14:49:56.360 - Running setup: perl /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/setUp.pl [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - +------ Step 1 - Copying password file to temp [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - | Copy a file to another directory [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - | Source file: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/jmxremote.password [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - | Dest dir: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/tmp [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - | [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - +------ Step 2 - Restricting file permission of jmxremote.passsword to 600 [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - | Change file permission [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - | targetFile: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/tmp/jmxremote.password [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - | modeBits: 600 [2024-10-30T21:49:57.157Z] STF 14:49:56.760 - | [2024-10-30T21:49:57.157Z] STF 14:49:56.948 - SETUP stage completed [2024-10-30T21:49:57.157Z] STF 14:49:57.005 - [2024-10-30T21:49:57.157Z] STF 14:49:57.005 - ==================== E X E C U T E - ==================== [2024-10-30T21:49:57.157Z] STF 14:49:57.005 - Running execute: perl /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/execute.pl [2024-10-30T21:49:59.445Z] STF 14:49:58.968 - [2024-10-30T21:49:59.445Z] STF 14:49:59.026 - Java version [2024-10-30T21:49:59.445Z] STF 14:49:59.026 - Running: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/java -version [2024-10-30T21:49:59.445Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-10-30T21:49:59.997Z] openjdk version "21.0.6-beta" 2025-01-21 [2024-10-30T21:49:59.997Z] OpenJDK Runtime Environment Temurin-21.0.6+1-202410302003 (build 21.0.6-beta+1-ea) [2024-10-30T21:49:59.997Z] OpenJDK 64-Bit Server VM Temurin-21.0.6+1-202410302003 (build 21.0.6-beta+1-ea, mixed mode) [2024-10-30T21:49:59.997Z] STF 14:49:59.569 - [2024-10-30T21:49:59.997Z] STF 14:49:59.569 - +------ Step 3 - Running ClassProfiler Proxy test Server Process(with security) [2024-10-30T21:49:59.997Z] STF 14:49:59.569 - | Start background process [2024-10-30T21:49:59.997Z] STF 14:49:59.569 - | Program: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-10-30T21:49:59.997Z] STF 14:49:59.569 - | Mnemonic: LT1 [2024-10-30T21:49:59.997Z] STF 14:49:59.569 - | Echo: ECHO_OFF [2024-10-30T21:49:59.997Z] STF 14:49:59.569 - | Expectation: NEVER [2024-10-30T21:49:59.997Z] STF 14:49:59.569 - | [2024-10-30T21:49:59.998Z] STF 14:49:59.571 - Running command: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -Dcom.sun.management.jmxremote.port=1234 -Dcom.sun.management.jmxremote.ssl.need.client.auth=true -Djavax.net.ssl.keyStore=/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.keyStoreType=JKS -Djavax.net.ssl.trustStoreType=JKS -Djavax.net.ssl.keyStorePassword=passphrase -Djavax.net.ssl.trustStorePassword=passphrase -Dcom.sun.management.jmxremote.password.file=/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/STF/stf.load/bin:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/STF/stf.core/bin:/Users/admin/workspace/testDependency/system_lib/log4j/log4j-api.jar:/Users/admin/workspace/testDependency/system_lib/log4j/log4j-core.jar:/Users/admin/workspace/testDependency/system_lib/junit/junit.jar:/Users/admin/workspace/testDependency/system_lib/junit/hamcrest-core.jar:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.lang/bin:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.util/bin:/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.math/bin net.adoptopenjdk.loadTest.LoadTest -resultsDir /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results -resultsPrefix 3.LT1. -timeLimit 30m -abortAtFailureLimit -1 -reportFailureLimit 1 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.mini-mix.totalNumberTests 20000000 -suite.mini-mix.inventoryFile /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/3.LT1.inventory/openjdk.test.load/config/inventories/mix/mini-mix_exclude.xml -suite.mini-mix.inventoryExcludeFile /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/3.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-30T21:49:59.998Z] STF 14:49:59.571 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/3.LT1.stderr [2024-10-30T21:49:59.998Z] STF 14:49:59.571 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/3.LT1.stdout [2024-10-30T21:49:59.998Z] STF 14:49:59.602 - [2024-10-30T21:49:59.998Z] STF 14:49:59.602 - +------ Step 4 - Running Monitoring Client with proxy connection(with security) [2024-10-30T21:49:59.998Z] STF 14:49:59.602 - | Start background process [2024-10-30T21:49:59.998Z] STF 14:49:59.602 - | Program: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/java [2024-10-30T21:49:59.998Z] STF 14:49:59.602 - | Mnemonic: CL1 [2024-10-30T21:49:59.998Z] STF 14:49:59.602 - | Echo: ECHO_ON [2024-10-30T21:49:59.998Z] STF 14:49:59.602 - | Expectation: CLEAN_RUN within 30m [2024-10-30T21:49:59.998Z] STF 14:49:59.602 - | [2024-10-30T21:49:59.998Z] STF 14:49:59.603 - Running command: /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/java -Xmx256m -Djavax.net.ssl.keyStore=/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.trustStore=/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/src/test.jlm/net/adoptopenjdk/test/jlm/testkeys -Djavax.net.ssl.keyStoreType=JKS -Djavax.net.ssl.trustStoreType=JKS -Djavax.net.ssl.keyStorePassword=passphrase -Djavax.net.ssl.trustStorePassword=passphrase -Dcom.sun.management.jmxremote.password.file=/Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/tmp/jmxremote.password -XX:-UseCompressedOops -classpath /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/jvmtest/system/aqa-systemtest/openjdk.test.jlm/bin:/Users/admin/workspace/testDependency/system_lib/junit/junit.jar net.adoptopenjdk.test.jlm.remote.ClassProfiler proxy /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/scls_proxy.log /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/scls_proxy.csv auth controlRole control1 localhost 1234 [2024-10-30T21:49:59.998Z] STF 14:49:59.603 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.stderr [2024-10-30T21:49:59.998Z] STF 14:49:59.603 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.stdout [2024-10-30T21:49:59.998Z] STF 14:49:59.605 - [2024-10-30T21:49:59.998Z] STF 14:49:59.605 - +------ Step 5 - Wait for the processes to complete [2024-10-30T21:49:59.998Z] STF 14:49:59.605 - | Wait for processes to meet expectations [2024-10-30T21:49:59.998Z] STF 14:49:59.605 - | Processes: [LT1, CL1] [2024-10-30T21:49:59.998Z] STF 14:49:59.606 - | [2024-10-30T21:49:59.998Z] STF 14:49:59.606 - Monitoring processes: CL1 LT1 [2024-10-30T21:49:59.998Z] CL1 stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2024-10-30T21:50:02.366Z] CL1 j> 2024/10/30 14:50:00.290 ServerURL=service:jmx:rmi:///jndi/rmi://localhost:1234/jmxrmi [2024-10-30T21:50:02.366Z] CL1 j> 2024/10/30 14:50:02.152 Attempting to connect [2024-10-30T21:50:03.247Z] CL1 j> 2024/10/30 14:50:02.773 Monitored VM not ready at Oct 30, 2024, 2:50:02 PM (attempt 1, elapsed 482ms). [2024-10-30T21:50:03.247Z] CL1 j> 2024/10/30 14:50:02.773 Waiting 5 secs and trying again... [2024-10-30T21:50:08.569Z] CL1 j> 2024/10/30 14:50:07.781 Attempting to connect [2024-10-30T21:50:30.054Z] CL1 j> 2024/10/30 14:50:26.404 Connection established! [2024-10-30T21:50:30.719Z] CL1 j> 2024/10/30 14:50:29.782 Starting to write data [2024-10-30T21:55:09.068Z] STF 14:54:58.488 - Heartbeat: Process CL1 is still running [2024-10-30T22:00:01.068Z] STF 14:59:58.179 - Heartbeat: Process CL1 is still running [2024-10-30T22:05:05.223Z] STF 15:04:58.298 - Heartbeat: Process CL1 is still running [2024-10-30T22:10:11.752Z] STF 15:09:58.033 - Heartbeat: Process CL1 is still running [2024-10-30T22:15:02.021Z] STF 15:14:58.209 - Heartbeat: Process CL1 is still running [2024-10-30T22:20:06.723Z] STF 15:19:58.240 - Heartbeat: Process CL1 is still running [2024-10-30T22:20:06.723Z] STF 15:20:00.417 - **FAILED** Process CL1 has timed out [2024-10-30T22:20:06.723Z] STF 15:20:00.417 - Collecting dumps for: CL1 [2024-10-30T22:20:06.723Z] STF 15:20:00.417 - Sending SIG 3 to the java process to generate a javacore [2024-10-30T22:20:06.723Z] STF 15:20:00.418 - Running command: kill -3 4918 [2024-10-30T22:20:06.723Z] STF 15:20:00.418 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_3.stderr [2024-10-30T22:20:06.723Z] STF 15:20:00.418 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_3.stdout [2024-10-30T22:20:06.723Z] STF 15:20:00.593 - Pausing for 30 seconds [2024-10-30T22:20:35.373Z] STF 15:20:30.598 - Sending SIG 3 to the java process to generate a javacore [2024-10-30T22:20:35.373Z] STF 15:20:30.599 - Running command: kill -3 4918 [2024-10-30T22:20:35.373Z] STF 15:20:30.599 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_3.stderr [2024-10-30T22:20:35.373Z] STF 15:20:30.599 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_3.stdout [2024-10-30T22:20:35.373Z] STF 15:20:30.601 - Pausing for 30 seconds [2024-10-30T22:21:03.948Z] STF 15:21:00.604 - Sending SIG 3 to the java process to generate a javacore [2024-10-30T22:21:03.948Z] STF 15:21:00.608 - Running command: kill -3 4918 [2024-10-30T22:21:03.948Z] STF 15:21:00.608 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_3.stderr [2024-10-30T22:21:03.948Z] STF 15:21:00.608 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_3.stdout [2024-10-30T22:21:03.948Z] STF 15:21:00.690 - Pausing for 30 seconds [2024-10-30T22:21:32.766Z] STF 15:21:30.706 - Sending SIGABRT (kill -6) to the java process to generate a core [2024-10-30T22:21:32.766Z] STF 15:21:30.707 - Running command: kill -6 4918 [2024-10-30T22:21:32.766Z] STF 15:21:30.707 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_6.stderr [2024-10-30T22:21:32.766Z] STF 15:21:30.707 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_6.stdout [2024-10-30T22:21:32.766Z] STF 15:21:30.710 - Pausing for 30 seconds [2024-10-30T22:22:01.657Z] STF 15:22:00.720 - Sending SIGXCPU (kill -24) to the java process to generate an OS dump [2024-10-30T22:22:01.657Z] STF 15:22:00.721 - Running command: kill -24 4918 [2024-10-30T22:22:01.657Z] STF 15:22:00.721 - Redirecting stderr to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_24.stderr [2024-10-30T22:22:01.657Z] STF 15:22:00.721 - Redirecting stdout to /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/results/4.CL1.kill_24.stdout [2024-10-30T22:22:01.657Z] CL1 ......2024-10-30 15:20:00 [2024-10-30T22:22:01.657Z] CL1 Full thread dump OpenJDK 64-Bit Server VM (21.0.6-beta+1-ea mixed mode): [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 Threads class SMR info: [2024-10-30T22:22:01.657Z] CL1 _java_thread_list=0x0000600000155de0, length=14, elements={ [2024-10-30T22:22:01.657Z] CL1 0x00007fac3f813600, 0x00007fac408ea800, 0x00007fac408fce00, 0x00007fac3f813e00, [2024-10-30T22:22:01.657Z] CL1 0x00007fac3f816800, 0x00007fac3f817000, 0x00007fac3f831600, 0x00007fac3f894600, [2024-10-30T22:22:01.657Z] CL1 0x00007fac41872200, 0x00007fac408e7800, 0x00007fac419e8400, 0x00007fac419eca00, [2024-10-30T22:22:01.657Z] CL1 0x00007fac4195ae00, 0x00007fac419ea600 [2024-10-30T22:22:01.657Z] CL1 } [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "main" #1 [8451] prio=5 os_prio=31 cpu=4928.08ms elapsed=1800.95s tid=0x00007fac3f813600 nid=8451 waiting on condition [0x0000700007515000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Thread.sleep0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Thread.sleep(java.base@21.0.6-beta/Thread.java:509) [2024-10-30T22:22:01.657Z] CL1 at net.adoptopenjdk.test.jlm.remote.ClassProfiler.getStatsViaProxy(ClassProfiler.java:161) [2024-10-30T22:22:01.657Z] CL1 at net.adoptopenjdk.test.jlm.remote.ClassProfiler.main(ClassProfiler.java:102) [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "Reference Handler" #9 [24579] daemon prio=10 os_prio=31 cpu=48.18ms elapsed=1800.85s tid=0x00007fac408ea800 nid=24579 waiting on condition [0x0000700007eb6000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.Reference.waitForReferencePendingList(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.Reference.processPendingReferences(java.base@21.0.6-beta/Reference.java:246) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.Reference$ReferenceHandler.run(java.base@21.0.6-beta/Reference.java:208) [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "Finalizer" #10 [29187] daemon prio=8 os_prio=31 cpu=0.36ms elapsed=1800.85s tid=0x00007fac408fce00 nid=29187 in Object.wait() [0x0000700007fb9000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: WAITING (on object monitor) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Object.wait0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.657Z] CL1 - waiting on <0x000000012105cd70> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:366) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:339) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.NativeReferenceQueue.await(java.base@21.0.6-beta/NativeReferenceQueue.java:48) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:158) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.NativeReferenceQueue.remove(java.base@21.0.6-beta/NativeReferenceQueue.java:89) [2024-10-30T22:22:01.657Z] CL1 - locked <0x000000012105cd70> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.Finalizer$FinalizerThread.run(java.base@21.0.6-beta/Finalizer.java:173) [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "Signal Dispatcher" #11 [28931] daemon prio=9 os_prio=31 cpu=6.34ms elapsed=1800.85s tid=0x00007fac3f813e00 nid=28931 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "Service Thread" #12 [28675] daemon prio=9 os_prio=31 cpu=1.34ms elapsed=1800.85s tid=0x00007fac3f816800 nid=28675 runnable [0x0000000000000000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "Monitor Deflation Thread" #13 [28419] daemon prio=9 os_prio=31 cpu=798.13ms elapsed=1800.85s tid=0x00007fac3f817000 nid=28419 runnable [0x0000000000000000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "C2 CompilerThread0" #14 [27907] daemon prio=9 os_prio=31 cpu=5023.08ms elapsed=1800.85s tid=0x00007fac3f831600 nid=27907 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.657Z] CL1 No compile task [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "C1 CompilerThread0" #15 [25859] daemon prio=9 os_prio=31 cpu=3355.00ms elapsed=1800.84s tid=0x00007fac3f894600 nid=25859 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.657Z] CL1 No compile task [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "Common-Cleaner" #16 [26115] daemon prio=8 os_prio=31 cpu=9.47ms elapsed=1800.67s tid=0x00007fac41872200 nid=26115 waiting on condition [0x00007000085cb000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: TIMED_WAITING (parking) [2024-10-30T22:22:01.657Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.657Z] CL1 - parking to wait for <0x000000012105d3d8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1852) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.ReferenceQueue.await(java.base@21.0.6-beta/ReferenceQueue.java:71) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:143) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.6-beta/ReferenceQueue.java:218) [2024-10-30T22:22:01.657Z] CL1 at jdk.internal.ref.CleanerImpl.run(java.base@21.0.6-beta/CleanerImpl.java:140) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.657Z] CL1 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.6-beta/InnocuousThread.java:186) [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "Notification Thread" #17 [27139] daemon prio=9 os_prio=31 cpu=0.16ms elapsed=1800.52s tid=0x00007fac408e7800 nid=27139 runnable [0x0000000000000000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "RMI Scheduler(0)" #19 [24071] daemon prio=5 os_prio=31 cpu=66.24ms elapsed=1791.40s tid=0x00007fac419e8400 nid=24071 waiting on condition [0x00007000087d1000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: TIMED_WAITING (parking) [2024-10-30T22:22:01.657Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.657Z] CL1 - parking to wait for <0x000000012105d8b8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1763) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.6-beta/ScheduledThreadPoolExecutor.java:1182) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.6-beta/ScheduledThreadPoolExecutor.java:899) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@21.0.6-beta/ThreadPoolExecutor.java:1070) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@21.0.6-beta/ThreadPoolExecutor.java:1130) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@21.0.6-beta/ThreadPoolExecutor.java:642) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.657Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.657Z] CL1 [2024-10-30T22:22:01.657Z] CL1 "RMI RenewClean-[192.168.122.2:49536,javax.rmi.ssl.SslRMIClientSocketFactory@4d49af10]" #20 [26627] daemon prio=5 os_prio=31 cpu=168.86ms elapsed=1791.38s tid=0x00007fac419eca00 nid=26627 waiting on condition [0x00007000088d4000] [2024-10-30T22:22:01.657Z] CL1 java.lang.Thread.State: TIMED_WAITING (parking) [2024-10-30T22:22:01.657Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.657Z] CL1 - parking to wait for <0x000000012105dcf8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-10-30T22:22:01.657Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1852) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.ReferenceQueue.await(java.base@21.0.6-beta/ReferenceQueue.java:71) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:143) [2024-10-30T22:22:01.657Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.6-beta/ReferenceQueue.java:218) [2024-10-30T22:22:01.658Z] CL1 at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(java.rmi@21.0.6-beta/DGCClient.java:567) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "RMI GC Daemon" #21 [32771] daemon prio=2 os_prio=31 cpu=43.19ms elapsed=1791.36s tid=0x00007fac4195ae00 nid=32771 in Object.wait() [0x00007000089d7000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Object.wait0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.658Z] CL1 - waiting on <0x000000012105df40> (a sun.rmi.transport.GC$LatencyLock) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:366) [2024-10-30T22:22:01.658Z] CL1 at sun.rmi.transport.GC$Daemon.run(java.rmi@21.0.6-beta/GC.java:127) [2024-10-30T22:22:01.658Z] CL1 - locked <0x000000012105df40> (a sun.rmi.transport.GC$LatencyLock) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.658Z] CL1 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.6-beta/InnocuousThread.java:186) [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "JMX client heartbeat 2" #24 [42499] daemon prio=5 os_prio=31 cpu=977.68ms elapsed=1774.24s tid=0x00007fac419ea600 nid=42499 waiting on condition [0x0000700008ce0000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.sleep0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.sleep(java.base@21.0.6-beta/Thread.java:509) [2024-10-30T22:22:01.658Z] CL1 at com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker.run(java.management@21.0.6-beta/ClientCommunicatorAdmin.java:180) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "GC Thread#2" os_prio=31 cpu=93.33ms elapsed=1788.96s tid=0x00007fac41129430 nid=43011 runnable [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "GC Thread#1" os_prio=31 cpu=170.55ms elapsed=1788.96s tid=0x00007fac3f7169e0 nid=43267 runnable [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "VM Thread" os_prio=31 cpu=267.81ms elapsed=1800.91s tid=0x00007fac3f707900 nid=20227 runnable [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "VM Periodic Task Thread" os_prio=31 cpu=3606.98ms elapsed=1800.95s tid=0x00007fac4010a7e0 nid=17155 waiting on condition [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "G1 Service" os_prio=31 cpu=290.36ms elapsed=1800.95s tid=0x00007fac40109200 nid=16643 runnable [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "G1 Refine#0" os_prio=31 cpu=3352.90ms elapsed=1800.95s tid=0x00007fac408bee00 nid=16387 runnable [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "G1 Conc#0" os_prio=31 cpu=0.13ms elapsed=1800.96s tid=0x00007fac401076e0 nid=13571 runnable [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "G1 Main Marker" os_prio=31 cpu=0.15ms elapsed=1800.96s tid=0x00007fac40106d70 nid=12803 runnable [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "GC Thread#0" os_prio=31 cpu=152.18ms elapsed=1800.96s tid=0x00007fac401065d0 nid=14339 runnable [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 JNI global refs: 15, weak refs: 0 [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 Heap [2024-10-30T22:22:01.658Z] CL1 garbage-first heap total 231424K, used 7827K [0x0000000120e00000, 0x0000000130e00000) [2024-10-30T22:22:01.658Z] CL1 region size 1024K, 3 young (3072K), 1 survivors (1024K) [2024-10-30T22:22:01.658Z] CL1 Metaspace used 20848K, committed 21184K, reserved 1114112K [2024-10-30T22:22:01.658Z] CL1 class space used 1963K, committed 2112K, reserved 1048576K [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 2024-10-30 15:20:30 [2024-10-30T22:22:01.658Z] CL1 Full thread dump OpenJDK 64-Bit Server VM (21.0.6-beta+1-ea mixed mode): [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 Threads class SMR info: [2024-10-30T22:22:01.658Z] CL1 _java_thread_list=0x0000600000155de0, length=14, elements={ [2024-10-30T22:22:01.658Z] CL1 0x00007fac3f813600, 0x00007fac408ea800, 0x00007fac408fce00, 0x00007fac3f813e00, [2024-10-30T22:22:01.658Z] CL1 0x00007fac3f816800, 0x00007fac3f817000, 0x00007fac3f831600, 0x00007fac3f894600, [2024-10-30T22:22:01.658Z] CL1 0x00007fac41872200, 0x00007fac408e7800, 0x00007fac419e8400, 0x00007fac419eca00, [2024-10-30T22:22:01.658Z] CL1 0x00007fac4195ae00, 0x00007fac419ea600 [2024-10-30T22:22:01.658Z] CL1 } [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "main" #1 [8451] prio=5 os_prio=31 cpu=4928.08ms elapsed=1830.95s tid=0x00007fac3f813600 nid=8451 waiting on condition [0x0000700007515000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.sleep0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.sleep(java.base@21.0.6-beta/Thread.java:509) [2024-10-30T22:22:01.658Z] CL1 at net.adoptopenjdk.test.jlm.remote.ClassProfiler.getStatsViaProxy(ClassProfiler.java:161) [2024-10-30T22:22:01.658Z] CL1 at net.adoptopenjdk.test.jlm.remote.ClassProfiler.main(ClassProfiler.java:102) [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "Reference Handler" #9 [24579] daemon prio=10 os_prio=31 cpu=48.18ms elapsed=1830.85s tid=0x00007fac408ea800 nid=24579 waiting on condition [0x0000700007eb6000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.Reference.waitForReferencePendingList(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.Reference.processPendingReferences(java.base@21.0.6-beta/Reference.java:246) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.Reference$ReferenceHandler.run(java.base@21.0.6-beta/Reference.java:208) [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "Finalizer" #10 [29187] daemon prio=8 os_prio=31 cpu=0.36ms elapsed=1830.85s tid=0x00007fac408fce00 nid=29187 in Object.wait() [0x0000700007fb9000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: WAITING (on object monitor) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Object.wait0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.658Z] CL1 - waiting on <0x000000012105cd70> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:366) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:339) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.NativeReferenceQueue.await(java.base@21.0.6-beta/NativeReferenceQueue.java:48) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:158) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.NativeReferenceQueue.remove(java.base@21.0.6-beta/NativeReferenceQueue.java:89) [2024-10-30T22:22:01.658Z] CL1 - locked <0x000000012105cd70> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.Finalizer$FinalizerThread.run(java.base@21.0.6-beta/Finalizer.java:173) [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "Signal Dispatcher" #11 [28931] daemon prio=9 os_prio=31 cpu=6.73ms elapsed=1830.85s tid=0x00007fac3f813e00 nid=28931 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "Service Thread" #12 [28675] daemon prio=9 os_prio=31 cpu=1.34ms elapsed=1830.85s tid=0x00007fac3f816800 nid=28675 runnable [0x0000000000000000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "Monitor Deflation Thread" #13 [28419] daemon prio=9 os_prio=31 cpu=805.60ms elapsed=1830.85s tid=0x00007fac3f817000 nid=28419 runnable [0x0000000000000000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "C2 CompilerThread0" #14 [27907] daemon prio=9 os_prio=31 cpu=5023.66ms elapsed=1830.85s tid=0x00007fac3f831600 nid=27907 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.658Z] CL1 No compile task [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "C1 CompilerThread0" #15 [25859] daemon prio=9 os_prio=31 cpu=3358.65ms elapsed=1830.84s tid=0x00007fac3f894600 nid=25859 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.658Z] CL1 No compile task [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "Common-Cleaner" #16 [26115] daemon prio=8 os_prio=31 cpu=9.75ms elapsed=1830.67s tid=0x00007fac41872200 nid=26115 waiting on condition [0x00007000085cb000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: TIMED_WAITING (parking) [2024-10-30T22:22:01.658Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.658Z] CL1 - parking to wait for <0x000000012105d3d8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1852) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.ReferenceQueue.await(java.base@21.0.6-beta/ReferenceQueue.java:71) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:143) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.6-beta/ReferenceQueue.java:218) [2024-10-30T22:22:01.658Z] CL1 at jdk.internal.ref.CleanerImpl.run(java.base@21.0.6-beta/CleanerImpl.java:140) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.658Z] CL1 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.6-beta/InnocuousThread.java:186) [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "Notification Thread" #17 [27139] daemon prio=9 os_prio=31 cpu=0.16ms elapsed=1830.52s tid=0x00007fac408e7800 nid=27139 runnable [0x0000000000000000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "RMI Scheduler(0)" #19 [24071] daemon prio=5 os_prio=31 cpu=67.42ms elapsed=1821.40s tid=0x00007fac419e8400 nid=24071 waiting on condition [0x00007000087d1000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: WAITING (parking) [2024-10-30T22:22:01.658Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.658Z] CL1 - parking to wait for <0x000000012105d8b8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.locks.LockSupport.park(java.base@21.0.6-beta/LockSupport.java:371) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:519) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.ForkJoinPool.unmanagedBlock(java.base@21.0.6-beta/ForkJoinPool.java:3780) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.ForkJoinPool.managedBlock(java.base@21.0.6-beta/ForkJoinPool.java:3725) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1712) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.6-beta/ScheduledThreadPoolExecutor.java:1170) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.6-beta/ScheduledThreadPoolExecutor.java:899) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@21.0.6-beta/ThreadPoolExecutor.java:1070) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@21.0.6-beta/ThreadPoolExecutor.java:1130) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@21.0.6-beta/ThreadPoolExecutor.java:642) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.658Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.658Z] CL1 [2024-10-30T22:22:01.658Z] CL1 "RMI RenewClean-[192.168.122.2:49536,javax.rmi.ssl.SslRMIClientSocketFactory@4d49af10]" #20 [26627] daemon prio=5 os_prio=31 cpu=178.30ms elapsed=1821.39s tid=0x00007fac419eca00 nid=26627 waiting on condition [0x00007000088d4000] [2024-10-30T22:22:01.658Z] CL1 java.lang.Thread.State: TIMED_WAITING (parking) [2024-10-30T22:22:01.658Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.658Z] CL1 - parking to wait for <0x000000012105dcf8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-10-30T22:22:01.658Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1852) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.ReferenceQueue.await(java.base@21.0.6-beta/ReferenceQueue.java:71) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:143) [2024-10-30T22:22:01.658Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.6-beta/ReferenceQueue.java:218) [2024-10-30T22:22:01.659Z] CL1 at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(java.rmi@21.0.6-beta/DGCClient.java:567) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "RMI GC Daemon" #21 [32771] daemon prio=2 os_prio=31 cpu=43.19ms elapsed=1821.38s tid=0x00007fac4195ae00 nid=32771 in Object.wait() [0x00007000089d7000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Object.wait0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.659Z] CL1 - waiting on <0x000000012105df40> (a sun.rmi.transport.GC$LatencyLock) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:366) [2024-10-30T22:22:01.659Z] CL1 at sun.rmi.transport.GC$Daemon.run(java.rmi@21.0.6-beta/GC.java:127) [2024-10-30T22:22:01.659Z] CL1 - locked <0x000000012105df40> (a sun.rmi.transport.GC$LatencyLock) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.659Z] CL1 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.6-beta/InnocuousThread.java:186) [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "JMX client heartbeat 2" #24 [42499] daemon prio=5 os_prio=31 cpu=977.68ms elapsed=1804.26s tid=0x00007fac419ea600 nid=42499 waiting on condition [0x0000700008ce0000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.sleep0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.sleep(java.base@21.0.6-beta/Thread.java:509) [2024-10-30T22:22:01.659Z] CL1 at com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker.run(java.management@21.0.6-beta/ClientCommunicatorAdmin.java:180) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "GC Thread#2" os_prio=31 cpu=93.33ms elapsed=1818.97s tid=0x00007fac41129430 nid=43011 runnable [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "GC Thread#1" os_prio=31 cpu=170.55ms elapsed=1818.98s tid=0x00007fac3f7169e0 nid=43267 runnable [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "VM Thread" os_prio=31 cpu=299.28ms elapsed=1830.93s tid=0x00007fac3f707900 nid=20227 runnable [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "VM Periodic Task Thread" os_prio=31 cpu=3648.98ms elapsed=1830.96s tid=0x00007fac4010a7e0 nid=17155 waiting on condition [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "G1 Service" os_prio=31 cpu=292.91ms elapsed=1830.97s tid=0x00007fac40109200 nid=16643 runnable [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "G1 Refine#0" os_prio=31 cpu=3359.86ms elapsed=1830.97s tid=0x00007fac408bee00 nid=16387 runnable [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "G1 Conc#0" os_prio=31 cpu=0.13ms elapsed=1830.97s tid=0x00007fac401076e0 nid=13571 runnable [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "G1 Main Marker" os_prio=31 cpu=0.15ms elapsed=1830.97s tid=0x00007fac40106d70 nid=12803 runnable [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "GC Thread#0" os_prio=31 cpu=152.18ms elapsed=1830.97s tid=0x00007fac401065d0 nid=14339 runnable [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 JNI global refs: 15, weak refs: 0 [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 Heap [2024-10-30T22:22:01.659Z] CL1 garbage-first heap total 231424K, used 7878K [0x0000000120e00000, 0x0000000130e00000) [2024-10-30T22:22:01.659Z] CL1 region size 1024K, 3 young (3072K), 1 survivors (1024K) [2024-10-30T22:22:01.659Z] CL1 Metaspace used 20849K, committed 21184K, reserved 1114112K [2024-10-30T22:22:01.659Z] CL1 class space used 1963K, committed 2112K, reserved 1048576K [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 2024-10-30 15:21:00 [2024-10-30T22:22:01.659Z] CL1 Full thread dump OpenJDK 64-Bit Server VM (21.0.6-beta+1-ea mixed mode): [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 Threads class SMR info: [2024-10-30T22:22:01.659Z] CL1 _java_thread_list=0x000060000012bac0, length=13, elements={ [2024-10-30T22:22:01.659Z] CL1 0x00007fac3f813600, 0x00007fac408ea800, 0x00007fac408fce00, 0x00007fac3f813e00, [2024-10-30T22:22:01.659Z] CL1 0x00007fac3f816800, 0x00007fac3f817000, 0x00007fac3f831600, 0x00007fac3f894600, [2024-10-30T22:22:01.659Z] CL1 0x00007fac41872200, 0x00007fac408e7800, 0x00007fac419e8400, 0x00007fac419eca00, [2024-10-30T22:22:01.659Z] CL1 0x00007fac4195ae00 [2024-10-30T22:22:01.659Z] CL1 } [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "main" #1 [8451] prio=5 os_prio=31 cpu=4928.08ms elapsed=1861.09s tid=0x00007fac3f813600 nid=8451 waiting on condition [0x0000700007515000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: TIMED_WAITING (sleeping) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.sleep0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.sleep(java.base@21.0.6-beta/Thread.java:509) [2024-10-30T22:22:01.659Z] CL1 at net.adoptopenjdk.test.jlm.remote.ClassProfiler.getStatsViaProxy(ClassProfiler.java:161) [2024-10-30T22:22:01.659Z] CL1 at net.adoptopenjdk.test.jlm.remote.ClassProfiler.main(ClassProfiler.java:102) [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "Reference Handler" #9 [24579] daemon prio=10 os_prio=31 cpu=48.65ms elapsed=1860.99s tid=0x00007fac408ea800 nid=24579 waiting on condition [0x0000700007eb6000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.Reference.waitForReferencePendingList(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.Reference.processPendingReferences(java.base@21.0.6-beta/Reference.java:246) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.Reference$ReferenceHandler.run(java.base@21.0.6-beta/Reference.java:208) [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "Finalizer" #10 [29187] daemon prio=8 os_prio=31 cpu=0.42ms elapsed=1861.02s tid=0x00007fac408fce00 nid=29187 in Object.wait() [0x0000700007fb9000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: WAITING (on object monitor) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Object.wait0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.659Z] CL1 - waiting on <0x000000012105cd70> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:366) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:339) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.NativeReferenceQueue.await(java.base@21.0.6-beta/NativeReferenceQueue.java:48) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:158) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.NativeReferenceQueue.remove(java.base@21.0.6-beta/NativeReferenceQueue.java:89) [2024-10-30T22:22:01.659Z] CL1 - locked <0x000000012105cd70> (a java.lang.ref.NativeReferenceQueue$Lock) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.Finalizer$FinalizerThread.run(java.base@21.0.6-beta/Finalizer.java:173) [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "Signal Dispatcher" #11 [28931] daemon prio=9 os_prio=31 cpu=7.34ms elapsed=1861.03s tid=0x00007fac3f813e00 nid=28931 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "Service Thread" #12 [28675] daemon prio=9 os_prio=31 cpu=3.16ms elapsed=1861.02s tid=0x00007fac3f816800 nid=28675 runnable [0x0000000000000000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "Monitor Deflation Thread" #13 [28419] daemon prio=9 os_prio=31 cpu=812.75ms elapsed=1861.02s tid=0x00007fac3f817000 nid=28419 runnable [0x0000000000000000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "C2 CompilerThread0" #14 [27907] daemon prio=9 os_prio=31 cpu=5061.52ms elapsed=1861.02s tid=0x00007fac3f831600 nid=27907 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.659Z] CL1 No compile task [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "C1 CompilerThread0" #15 [25859] daemon prio=9 os_prio=31 cpu=3365.25ms elapsed=1861.03s tid=0x00007fac3f894600 nid=25859 waiting on condition [0x0000000000000000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.659Z] CL1 No compile task [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "Common-Cleaner" #16 [26115] daemon prio=8 os_prio=31 cpu=9.85ms elapsed=1860.86s tid=0x00007fac41872200 nid=26115 waiting on condition [0x00007000085cb000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: TIMED_WAITING (parking) [2024-10-30T22:22:01.659Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.659Z] CL1 - parking to wait for <0x000000012105d3d8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1852) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.ReferenceQueue.await(java.base@21.0.6-beta/ReferenceQueue.java:71) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:143) [2024-10-30T22:22:01.659Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.6-beta/ReferenceQueue.java:218) [2024-10-30T22:22:01.659Z] CL1 at jdk.internal.ref.CleanerImpl.run(java.base@21.0.6-beta/CleanerImpl.java:140) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.659Z] CL1 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.6-beta/InnocuousThread.java:186) [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "Notification Thread" #17 [27139] daemon prio=9 os_prio=31 cpu=0.16ms elapsed=1860.71s tid=0x00007fac408e7800 nid=27139 runnable [0x0000000000000000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: RUNNABLE [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.659Z] CL1 "RMI Scheduler(0)" #19 [24071] daemon prio=5 os_prio=31 cpu=67.42ms elapsed=1851.60s tid=0x00007fac419e8400 nid=24071 waiting on condition [0x00007000087d1000] [2024-10-30T22:22:01.659Z] CL1 java.lang.Thread.State: WAITING (parking) [2024-10-30T22:22:01.659Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.659Z] CL1 - parking to wait for <0x000000012105d8b8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.locks.LockSupport.park(java.base@21.0.6-beta/LockSupport.java:371) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:519) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.ForkJoinPool.unmanagedBlock(java.base@21.0.6-beta/ForkJoinPool.java:3780) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.ForkJoinPool.managedBlock(java.base@21.0.6-beta/ForkJoinPool.java:3725) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1712) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.6-beta/ScheduledThreadPoolExecutor.java:1170) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@21.0.6-beta/ScheduledThreadPoolExecutor.java:899) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@21.0.6-beta/ThreadPoolExecutor.java:1070) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@21.0.6-beta/ThreadPoolExecutor.java:1130) [2024-10-30T22:22:01.659Z] CL1 at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@21.0.6-beta/ThreadPoolExecutor.java:642) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.659Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.659Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "RMI RenewClean-[192.168.122.2:49536,javax.rmi.ssl.SslRMIClientSocketFactory@4d49af10]" #20 [26627] daemon prio=5 os_prio=31 cpu=182.62ms elapsed=1851.58s tid=0x00007fac419eca00 nid=26627 waiting on condition [0x00007000088d4000] [2024-10-30T22:22:01.660Z] CL1 java.lang.Thread.State: TIMED_WAITING (parking) [2024-10-30T22:22:01.660Z] CL1 at jdk.internal.misc.Unsafe.park(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.660Z] CL1 - parking to wait for <0x000000012105dcf8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) [2024-10-30T22:22:01.660Z] CL1 at java.util.concurrent.locks.LockSupport.parkNanos(java.base@21.0.6-beta/LockSupport.java:269) [2024-10-30T22:22:01.660Z] CL1 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(java.base@21.0.6-beta/AbstractQueuedSynchronizer.java:1852) [2024-10-30T22:22:01.660Z] CL1 at java.lang.ref.ReferenceQueue.await(java.base@21.0.6-beta/ReferenceQueue.java:71) [2024-10-30T22:22:01.660Z] CL1 at java.lang.ref.ReferenceQueue.remove0(java.base@21.0.6-beta/ReferenceQueue.java:143) [2024-10-30T22:22:01.660Z] CL1 at java.lang.ref.ReferenceQueue.remove(java.base@21.0.6-beta/ReferenceQueue.java:218) [2024-10-30T22:22:01.660Z] CL1 at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(java.rmi@21.0.6-beta/DGCClient.java:567) [2024-10-30T22:22:01.660Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.660Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "RMI GC Daemon" #21 [32771] daemon prio=2 os_prio=31 cpu=43.19ms elapsed=1851.57s tid=0x00007fac4195ae00 nid=32771 in Object.wait() [0x00007000089d7000] [2024-10-30T22:22:01.660Z] CL1 java.lang.Thread.State: TIMED_WAITING (on object monitor) [2024-10-30T22:22:01.660Z] CL1 at java.lang.Object.wait0(java.base@21.0.6-beta/Native Method) [2024-10-30T22:22:01.660Z] CL1 - waiting on <0x000000012105df40> (a sun.rmi.transport.GC$LatencyLock) [2024-10-30T22:22:01.660Z] CL1 at java.lang.Object.wait(java.base@21.0.6-beta/Object.java:366) [2024-10-30T22:22:01.660Z] CL1 at sun.rmi.transport.GC$Daemon.run(java.rmi@21.0.6-beta/GC.java:127) [2024-10-30T22:22:01.660Z] CL1 - locked <0x000000012105df40> (a sun.rmi.transport.GC$LatencyLock) [2024-10-30T22:22:01.660Z] CL1 at java.lang.Thread.runWith(java.base@21.0.6-beta/Thread.java:1596) [2024-10-30T22:22:01.660Z] CL1 at java.lang.Thread.run(java.base@21.0.6-beta/Thread.java:1583) [2024-10-30T22:22:01.660Z] CL1 at jdk.internal.misc.InnocuousThread.run(java.base@21.0.6-beta/InnocuousThread.java:186) [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "GC Thread#2" os_prio=31 cpu=120.98ms elapsed=1849.16s tid=0x00007fac41129430 nid=43011 runnable [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "GC Thread#1" os_prio=31 cpu=181.54ms elapsed=1849.16s tid=0x00007fac3f7169e0 nid=43267 runnable [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "VM Thread" os_prio=31 cpu=400.29ms elapsed=1861.12s tid=0x00007fac3f707900 nid=20227 runnable [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "VM Periodic Task Thread" os_prio=31 cpu=3686.74ms elapsed=1861.15s tid=0x00007fac4010a7e0 nid=17155 waiting on condition [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "G1 Service" os_prio=31 cpu=297.44ms elapsed=1861.16s tid=0x00007fac40109200 nid=16643 runnable [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "G1 Refine#0" os_prio=31 cpu=3377.70ms elapsed=1861.16s tid=0x00007fac408bee00 nid=16387 runnable [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "G1 Conc#0" os_prio=31 cpu=8.36ms elapsed=1861.17s tid=0x00007fac401076e0 nid=13571 runnable [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "G1 Main Marker" os_prio=31 cpu=0.51ms elapsed=1861.17s tid=0x00007fac40106d70 nid=12803 runnable [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 "GC Thread#0" os_prio=31 cpu=186.27ms elapsed=1861.17s tid=0x00007fac401065d0 nid=14339 runnable [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 JNI global refs: 15, weak refs: 0 [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 Heap [2024-10-30T22:22:01.660Z] CL1 garbage-first heap total 24576K, used 6194K [0x0000000120e00000, 0x0000000130e00000) [2024-10-30T22:22:01.660Z] CL1 region size 1024K, 2 young (2048K), 1 survivors (1024K) [2024-10-30T22:22:01.660Z] CL1 Metaspace used 20955K, committed 21568K, reserved 1114112K [2024-10-30T22:22:01.660Z] CL1 class space used 1945K, committed 2176K, reserved 1048576K [2024-10-30T22:22:01.660Z] CL1 [2024-10-30T22:22:01.660Z] CL1 stderr Oct 30, 2024 3:20:46 PM com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker run [2024-10-30T22:22:01.660Z] CL1 stderr WARNING: Failed to check connection: java.net.ConnectException: Connection refused [2024-10-30T22:22:01.660Z] CL1 stderr Oct 30, 2024 3:20:46 PM com.sun.jmx.remote.internal.ClientCommunicatorAdmin$Checker run [2024-10-30T22:22:01.660Z] CL1 stderr WARNING: stopping [2024-10-30T22:22:01.660Z] STF 15:22:00.736 - Monitoring Report Summary: [2024-10-30T22:22:01.660Z] STF 15:22:00.736 - o Process CL1 has timed out [2024-10-30T22:22:01.660Z] STF 15:22:00.737 - o Process LT1 ended sucessfully [2024-10-30T22:22:01.660Z] STF 15:22:00.738 - Killing processes: CL1 LT1 [2024-10-30T22:22:01.660Z] STF 15:22:00.738 - o Process CL1 pid 4918 is not running [2024-10-30T22:22:01.660Z] STF 15:22:00.738 - o Process LT1 pid 4917 is not running [2024-10-30T22:22:01.660Z] **FAILED** at step 5 (Wait for the processes to complete). Expected return value=0 Actual=1 at /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/execute.pl line 175. [2024-10-30T22:22:01.660Z] STF 15:22:01.114 - **FAILED** execute script failed. Expected return value=0 Actual=1 [2024-10-30T22:22:01.660Z] STF 15:22:01.114 - [2024-10-30T22:22:01.660Z] STF 15:22:01.114 - ==================== T E A R D O W N ==================== [2024-10-30T22:22:01.660Z] STF 15:22:01.114 - Running teardown: perl /Users/admin/workspace/workspace/Test_openjdk21_hs_sanity.system_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17303237555167/TestJlmRemoteClassAuth_1/20241030-144950-TestJlmRemoteClassAuth/tearDown.pl [2024-10-30T22:22:01.660Z] STF 15:22:01.357 - TEARDOWN stage completed [2024-10-30T22:22:01.660Z] STF 15:22:01.368 - [2024-10-30T22:22:01.660Z] STF 15:22:01.368 - ===================== R E S U L T S ===================== [2024-10-30T22:22:01.660Z] STF 15:22:01.368 - Stage results: [2024-10-30T22:22:01.660Z] STF 15:22:01.368 - setUp: pass [2024-10-30T22:22:01.660Z] STF 15:22:01.368 - execute: *fail* [2024-10-30T22:22:01.660Z] STF 15:22:01.368 - teardown: pass [2024-10-30T22:22:01.660Z] STF 15:22:01.368 - [2024-10-30T22:22:01.660Z] STF 15:22:01.368 - Overall result: **FAILED** [2024-10-30T22:22:01.660Z] ----------------------------------- [2024-10-30T22:22:01.660Z] TestJlmRemoteClassAuth_1_FAILED [2024-10-30T22:22:01.660Z] ----------------------------------- [2024-10-30T22:22:01.660Z] [2024-10-30T22:22:01.660Z] TEST TEARDOWN: [2024-10-30T22:22:01.660Z] Nothing to be done for teardown. [2024-10-30T22:22:01.660Z] TestJlmRemoteClassAuth_1 Finish Time: Wed Oct 30 15:22:01 2024 Epoch Time (ms): 1730326921482