Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

OpenJDK util/concurrent/ScheduledThreadPoolExecutor/GCRetention timeout 1/100 unqueued references remaining #14692

Open
pshipton opened this issue Mar 10, 2022 · 17 comments
Open

OpenJDK util/concurrent/ScheduledThreadPoolExecutor/GCRetention timeout 1/100 unqueued references remaining #14692

pshipton opened this issue Mar 10, 2022 · 17 comments
Labels
test failure

Comments

@pshipton
Copy link
Member

https://openj9-jenkins.osuosl.org/job/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/176 - osx1013-x86-4
jdk_util_1 -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:-UseCompressedOops
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

https://openj9-artifactory.osuosl.org/artifactory/ci-openj9/Test/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/176/openjdk_test_output.tar.gz

23:54:43  ACTION: main -- Error. Agent error: java.lang.Exception: Agent 6 timed out with a timeout of 960 seconds; check console log for any additional details
23:54:43  REASON: Assumed action based on file name: run main GCRetention 
23:54:43  TIME:   965.83 seconds
23:54:43  messages:
23:54:43  command: main GCRetention
23:54:43  reason: Assumed action based on file name: run main GCRetention 
23:54:43  Mode: agentvm
23:54:43  Agent id: 6
23:54:43  Timeout refired 960 times
23:54:43  Timeout information:
23:54:43  Running jcmd on process 61120
23:54:43  Error getting data from 61120: Exception connecting to 61120
23:54:43  Usage : jcmd <vmid> <arguments>
23:54:43  
23:54:43     -J : supply arguments to the Java VM running jcmd
23:54:43     -l : list JVM processes on the local machine
23:54:43     -h : print this help message
23:54:43  
23:54:43     <vmid> : Attach API VM ID as shown in jps or other Attach API-based tools
23:54:43  
23:54:43     arguments:
23:54:43        help : print the list of diagnostic commands
23:54:43        help <command> : print help for the specific command
23:54:43        <command> [command arguments] : command from the list returned by "help"
23:54:43  
23:54:43  list JVM processes on the local machine. Default behavior when no options are specified.
23:54:43  
23:54:43  NOTE: this utility might significantly affect the performance of the target JVM.
23:54:43      The available diagnostic commands are determined by
23:54:43      the target VM and may vary between VMs.
23:54:43  Running jstack on process 61120
23:54:43  2022-03-09T20:54:41.988414
23:54:43  Error getting data from 61120: Exception connecting to 61120
23:54:43  --- Timeout information end.
23:54:43  elapsed time (seconds): 965.83
23:54:43  configuration:
23:54:43  Boot Layer
23:54:43    class path: /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/javatest.jar 
23:54:43                /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/jtreg.jar 
23:54:43                /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/junit.jar 
23:54:43                /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/hamcrest.jar 
23:54:43                /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/testng.jar 
23:54:43                /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/jcommander.jar 
23:54:43                /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/guice.jar 
23:54:43    patch:      java.base /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/TKG/output_16468817757979/jdk_util_1/work/patches/java.base
23:54:43  
23:54:43  Test Layer
23:54:43    class path: /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/TKG/output_16468817757979/jdk_util_1/work/classes/0/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d
23:54:43                /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor
23:54:43  
23:54:43  rerun:
23:54:43  cd /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/TKG/output_16468817757979/jdk_util_1/work/scratch/0 && \
23:54:43  HOME=/Users/jenkins \
23:54:43  PATH=/bin:/usr/bin:/usr/sbin \
23:54:43      /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/openjdkbinary/j2sdk-image/bin/java \
23:54:43          -Dtest.vm.opts='-ea -esa -Xmx1540m -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:-UseCompressedOops' \
23:54:43          -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx1540m -J-Xdump:system:none -J-Xdump:heap:none -J-Xdump:system:events=gpf+abort+traceassert+corruptcache -J-XX:-UseCompressedOops' \
23:54:43          -Dtest.compiler.opts= \
23:54:43          -Dtest.java.opts= \
23:54:43          -Dtest.jdk=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/openjdkbinary/j2sdk-image \
23:54:43          -Dcompile.jdk=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/openjdkbinary/j2sdk-image \
23:54:43          -Dtest.timeout.factor=8.0 \
23:54:43          -Dtest.nativepath=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/openjdkbinary/openjdk-test-image/jdk/jtreg/native \
23:54:43          -Dtest.root=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk \
23:54:43          -Dtest.name=java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java \
23:54:43          -Dtest.file=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java \
23:54:43          -Dtest.src=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor \
23:54:43          -Dtest.src.path=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor \
23:54:43          -Dtest.classes=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/TKG/output_16468817757979/jdk_util_1/work/classes/0/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d \
23:54:43          -Dtest.class.path=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/TKG/output_16468817757979/jdk_util_1/work/classes/0/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d \
23:54:43          -Dtest.class.path.prefix=/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/TKG/output_16468817757979/jdk_util_1/work/classes/0/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d:/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor \
23:54:43          -classpath /Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/TKG/output_16468817757979/jdk_util_1/work/classes/0/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d:/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor:/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/javatest.jar:/Users/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_x86-64_mac_Nightly/jvmtest/openjdk/jtreg/lib/jtreg.jar \
23:54:43          GCRetention
23:54:43  STDOUT:
23:54:43  1/100 unqueued references remaining
23:54:43  1/100 unqueued references remaining
23:54:43  1/100 unqueued references remaining
23:54:43  1/100 unqueued references remaining
23:54:43  1/100 unqueued references remaining
...
23:54:44  1/100 unqueued references remaining
23:54:44  1/100 unqueued references remaining
23:54:44  1/100 unqueued references remaining
23:54:44  1/100 unqueued references remaining
23:54:44  1/100 un
23:54:44  TEST RESULT: Error. Agent error: java.lang.Exception: Agent 6 timed out with a timeout of 960 seconds; check console log for any additional details
23:54:44  --------------------------------------------------
23:58:21  Test results: passed: 887; error: 1
@pshipton
Copy link
Member Author

pshipton commented Jan 5, 2023

https://openj9-jenkins.osuosl.org/job/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/207 (Dec 21)
jdk_util_1
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

21:23:16  ACTION: main -- Error. Agent error: java.lang.Exception: Agent 5 timed out with a timeout of 960 seconds; check console log for any additional details
21:23:16  REASON: Assumed action based on file name: run main GCRetention 
21:23:16  TIME:   960.96 seconds
21:23:16  messages:
21:23:16  command: main GCRetention
21:23:16  reason: Assumed action based on file name: run main GCRetention 
21:23:16  Mode: agentvm
21:23:16  Agent id: 5
21:23:16  Timeout refired 960 times
21:23:16  Timeout information:
21:23:16  Running jcmd on process 88468
21:23:16  Error getting data from 88468: Exception connecting to 88468
21:23:16  Usage : jcmd <vmid> <arguments>
21:23:16  
21:23:16     -J : supply arguments to the Java VM running jcmd
21:23:16     -l : list JVM processes on the local machine
21:23:16     -h : print this help message
21:23:16  
21:23:16     <vmid> : Attach API VM ID as shown in jps or other Attach API-based tools
21:23:16  
21:23:16     arguments:
21:23:16        help : print the list of diagnostic commands
21:23:16        help <command> : print help for the specific command
21:23:16        <command> [command arguments] : command from the list returned by "help"
21:23:16  
21:23:16  list JVM processes on the local machine. Default behavior when no options are specified.
21:23:16  
21:23:16  NOTE: this utility might significantly affect the performance of the target JVM.
21:23:16      The available diagnostic commands are determined by
21:23:16      the target VM and may vary between VMs.
21:23:16  Error getting data from 88468: Exception connecting to 88468
21:23:16  Usage : jcmd <vmid> <arguments>
21:23:16  
21:23:16     -J : supply arguments to the Java VM running jcmd
21:23:16     -l : list JVM processes on the local machine
21:23:16     -h : print this help message
21:23:16  
21:23:16     <vmid> : Attach API VM ID as shown in jps or other Attach API-based tools
21:23:16  
21:23:16     arguments:
21:23:16        help : print the list of diagnostic commands
21:23:16        help <command> : print help for the specific command
21:23:16        <command> [command arguments] : command from the list returned by "help"
21:23:16  
21:23:16  list JVM processes on the local machine. Default behavior when no options are specified.
21:23:16  
21:23:16  NOTE: this utility might significantly affect the performance of the target JVM.
21:23:16      The available diagnostic commands are determined by
21:23:16      the target VM and may vary between VMs.
21:23:16  Running jstack on process 88468
21:23:16  2022-12-22T13:23:12.698204
21:23:16  Error getting data from 88468: Exception connecting to 88468
21:23:16  --- Timeout information end.
21:23:16  elapsed time (seconds): 960.96
21:23:16  configuration:
21:23:16  Boot Layer
21:23:16    class path: /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/javatest.jar 
21:23:16                /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/jtreg.jar 
21:23:16                /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/junit.jar 
21:23:16                /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/hamcrest.jar 
21:23:16                /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/testng.jar 
21:23:16                /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/jcommander.jar 
21:23:16                /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/guice.jar 
21:23:16    patch:      java.base /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/TKG/output_16716745065731/jdk_util_1/work/patches/java.base
21:23:16  
21:23:16  Test Layer
21:23:16    class path: /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/TKG/output_16716745065731/jdk_util_1/work/classes/2/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d
21:23:16                /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor
21:23:16  
21:23:16  rerun:
21:23:16  cd /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/TKG/output_16716745065731/jdk_util_1/work/scratch/3 && \
21:23:16  HOME=/Users/jenkins \
21:23:16  PATH=/bin:/usr/bin:/usr/sbin \
21:23:16      /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/openjdkbinary/j2sdk-image/bin/java \
21:23:16          -Dtest.vm.opts='-ea -esa -Xmx1540m -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:-UseCompressedOops' \
21:23:16          -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx1540m -J-Xdump:system:none -J-Xdump:heap:none -J-Xdump:system:events=gpf+abort+traceassert+corruptcache -J-XX:-UseCompressedOops' \
21:23:16          -Dtest.compiler.opts= \
21:23:16          -Dtest.java.opts= \
21:23:16          -Dtest.jdk=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/openjdkbinary/j2sdk-image \
21:23:16          -Dcompile.jdk=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/openjdkbinary/j2sdk-image \
21:23:16          -Dtest.timeout.factor=8.0 \
21:23:16          -Dtest.nativepath=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/openjdkbinary/openjdk-test-image/jdk/jtreg/native \
21:23:16          -Dtest.root=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk \
21:23:16          -Dtest.name=java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java \
21:23:16          -Dtest.file=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java \
21:23:16          -Dtest.src=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor \
21:23:16          -Dtest.src.path=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor \
21:23:16          -Dtest.classes=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/TKG/output_16716745065731/jdk_util_1/work/classes/2/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d \
21:23:16          -Dtest.class.path=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/TKG/output_16716745065731/jdk_util_1/work/classes/2/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d \
21:23:16          -Dtest.class.path.prefix=/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/TKG/output_16716745065731/jdk_util_1/work/classes/2/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d:/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor \
21:23:16          -classpath /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/TKG/output_16716745065731/jdk_util_1/work/classes/2/java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.d:/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/util/concurrent/ScheduledThreadPoolExecutor:/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/javatest.jar:/Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_aarch64_mac_Nightly/jvmtest/openjdk/jtreg/lib/jtreg.jar \
21:23:16          GCRetention
21:23:16  STDOUT:
21:23:16  0/100 unqueued references remaining
21:23:16  0/100 unqueued references remaining

@pshipton
Copy link
Member Author

https://openj9-jenkins.osuosl.org/job/Test_openjdk19_j9_sanity.openjdk_x86-64_linux_Nightly/148/
jdk_util_0
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

00:05:55  Timeout information:
00:05:55  Running jcmd on process 29458
00:05:55  Dump written to /home/jenkins/workspace/Test_openjdk19_j9_sanity.openjdk_x86-64_linux_Nightly/aqa-tests/TKG/output_16809210296792/jdk_util_0/work/scratch/1/core.20230408.010531.29458.0001.dmp
00:05:55  Dump written to /home/jenkins/workspace/Test_openjdk19_j9_sanity.openjdk_x86-64_linux_Nightly/aqa-tests/TKG/output_16809210296792/jdk_util_0/work/scratch/1/javacore.20230408.010537.29458.0002.txt
00:05:55  Running jstack on process 29458
00:05:55  2023-04-08T01:05:43.197522713
00:05:55  Virtual machine: 29458 JVM information:
00:05:55  JRE 19 Linux amd64-64-Bit Compressed References 20230407_218 (JIT enabled, AOT enabled)
00:05:55  OpenJ9   - ab86d5ac79e
00:05:55  OMR      - 2d7aff0254f
00:05:55  JCL      - fa12970bb58 based on jdk-19.0.2+7
00:05:55  
00:05:55  "main" prio=5 Id=2 WAITING
00:05:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
00:05:55  	at [email protected]/java.lang.Thread.join(Thread.java:2151)
00:05:55  	at [email protected]/java.lang.Thread.join(Thread.java:2227)
00:05:55  	at app//com.sun.javatest.regtest.agent.MainActionHelper.runClass(MainActionHelper.java:198)
00:05:55  	at app//com.sun.javatest.regtest.agent.AgentServer.doMain(AgentServer.java:306)
00:05:55  	at app//com.sun.javatest.regtest.agent.AgentServer.run(AgentServer.java:233)
00:05:55  	at app//com.sun.javatest.regtest.agent.AgentServer.main(AgentServer.java:70)
00:05:55  
00:05:55  "JIT Compilation Thread-000" prio=10 Id=4 RUNNABLE
00:05:55  
00:05:55  "JIT Compilation Thread-001 Suspended" prio=10 Id=5 RUNNABLE
00:05:55  
00:05:55  "JIT Compilation Thread-002 Suspended" prio=10 Id=6 RUNNABLE
00:05:55  
00:05:55  "JIT Compilation Thread-003 Suspended" prio=10 Id=7 RUNNABLE
00:05:55  
00:05:55  "JIT Compilation Thread-004 Suspended" prio=10 Id=8 RUNNABLE
00:05:55  
00:05:55  "JIT Compilation Thread-005 Suspended" prio=10 Id=9 RUNNABLE
00:05:55  
00:05:55  "JIT Compilation Thread-006 Suspended" prio=10 Id=10 RUNNABLE
00:05:55  
00:05:55  "JIT Diagnostic Compilation Thread-007 Suspended" prio=10 Id=11 RUNNABLE
00:05:55  
00:05:55  "JIT-SamplerThread" prio=10 Id=12 TIMED_WAITING
00:05:55  
00:05:55  "IProfiler" prio=5 Id=13 RUNNABLE
00:05:55  
00:05:55  "Common-Cleaner" prio=8 Id=3 TIMED_WAITING
00:05:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
00:05:55  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
00:05:55  	at [email protected]/jdk.internal.ref.CleanerImpl.run(CleanerImpl.java:140)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  	at [email protected]/jdk.internal.misc.InnocuousThread.run(InnocuousThread.java:186)
00:05:55  
00:05:55  "Concurrent Mark Helper" prio=1 Id=14 RUNNABLE
00:05:55  
00:05:55  "Finalizer thread" prio=5 Id=15 RUNNABLE
00:05:55  
00:05:55  "GC Worker" prio=5 Id=16 RUNNABLE
00:05:55  
00:05:55  "GC Worker" prio=5 Id=17 RUNNABLE
00:05:55  
00:05:55  "GC Worker" prio=5 Id=18 RUNNABLE
00:05:55  
00:05:55  "Attach API wait loop" prio=10 Id=21 RUNNABLE
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.IPC.waitSemaphore(Native Method)
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.CommonDirectory.waitSemaphore(CommonDirectory.java:264)
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.WaitLoop.waitForNotification(WaitLoop.java:66)
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.WaitLoop.run(WaitLoop.java:157)
00:05:55  
00:05:55  "pool-1-thread-1" prio=5 Id=22 TIMED_WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:269)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1674)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1182)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "ClassByNameCache Reaper" prio=5 Id=27 WAITING
00:05:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
00:05:55  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
00:05:55  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:109)
00:05:55  	at [email protected]/java.io.ClassByNameCache$Reaper.run(ClassByNameCache.java:232)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "AgentVMThread" prio=5 Id=12018 TIMED_WAITING
00:05:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
00:05:55  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
00:05:55  	at GCRetention.removeAll(GCRetention.java:86)
00:05:55  	at GCRetention.test(GCRetention.java:117)
00:05:55  	at GCRetention.instanceMain(GCRetention.java:138)
00:05:55  	at GCRetention.main(GCRetention.java:136)
00:05:55  	at [email protected]/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
00:05:55  	at [email protected]/java.lang.reflect.Method.invoke(Method.java:578)
00:05:55  	at app//com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:312)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-1" prio=5 Id=12019 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-2" prio=5 Id=12020 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-3" prio=5 Id=12021 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-4" prio=5 Id=12022 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-5" prio=5 Id=12023 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-6" prio=5 Id=12024 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-7" prio=5 Id=12025 TIMED_WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:269)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1674)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1182)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-8" prio=5 Id=12026 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-9" prio=5 Id=12027 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "pool-199-thread-10" prio=5 Id=12028 WAITING
00:05:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
00:05:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3745)
00:05:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3690)
00:05:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1625)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
00:05:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
00:05:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
00:05:55  	at [email protected]/java.lang.Thread.run(Thread.java:1573)
00:05:55  
00:05:55  "file lock watchdog" prio=10 Id=12030 TIMED_WAITING
00:05:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
00:05:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
00:05:55  	at [email protected]/java.util.TimerThread.mainLoop(Timer.java:599)
00:05:55  	at [email protected]/java.util.TimerThread.run(Timer.java:542)
00:05:55  
00:05:55  "Attachment portNumber: 36407" prio=10 Id=12032 RUNNABLE
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.dumpAllThreadsImpl(Native Method)
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.getThreadInfo(DiagnosticUtils.java:245)
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.executeDiagnosticCommand(DiagnosticUtils.java:181)
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.Attachment.doCommand(Attachment.java:248)
00:05:55  	at [email protected]/openj9.internal.tools.attach.target.Attachment.run(Attachment.java:159)
00:05:55  
00:05:55  
00:05:55  --- Timeout information end.

00:05:55  STDOUT:
00:05:55  1/100 unqueued references remaining

@pshipton
Copy link
Member Author

pshipton commented May 3, 2023

https://openj9-jenkins.osuosl.org/job/Test_openjdk11_j9_sanity.openjdk_x86-64_windows_Nightly/530

@JasonFengJ9
Copy link
Member

JDK20 x86-64_windows(win16x64rt-1-10)

openjdk version "20.0.1-beta" 2023-04-18
IBM Semeru Runtime Open Edition 20.0.1+9-202305310015 (build 20.0.1-beta+9-202305310015)
Eclipse OpenJ9 VM 20.0.1+9-202305310015 (build master-f98cb3128, JRE 20 Windows Server 2016 amd64-64-Bit Compressed References 20230531_40 (JIT enabled, AOT enabled)
OpenJ9   - f98cb3128
OMR      - f89af3519
JCL      - a80d1107c based on jdk-20.0.1+9)

[2023-05-31T02:44:50.150Z] variation: -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache Mode150
[2023-05-31T02:44:50.540Z] JVM_OPTIONS:  -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:+UseCompressedOops 

[2023-05-31T03:08:45.060Z] TEST: java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

[2023-05-31T03:08:45.066Z] 1/100 unqueued references remaining
[2023-05-31T03:08:45.066Z] 1/100 unqueued references remaining
[2023-05-31T03:08:45.066Z] 1/100 unqueued referencstderr:
[2023-05-31T03:08:45.066Z] JVMDUMP034I User requested System dump using 'C:\Users\jenkins\workspace\Test_openjdk20_j9_sanity.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_16854983099972\jdk_util_0\work\scratch\0\core.20230530.230815.6524.0001.dmp' through com.ibm.jvm.Dump.systemDumpToFile
[2023-05-31T03:08:45.066Z] JVMDUMP010I System dump written to C:\Users\jenkins\workspace\Test_openjdk20_j9_sanity.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_16854983099972\jdk_util_0\work\scratch\0\core.20230530.230815.6524.0001.dmp
[2023-05-31T03:08:45.066Z] JVMDUMP034I User requested Java dump using 'C:\Users\jenkins\workspace\Test_openjdk20_j9_sanity.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_16854983099972\jdk_util_0\work\scratch\0\javacore.20230530.230838.6524.0002.txt' through com.ibm.jvm.Dump.javaDumpToFile
[2023-05-31T03:08:45.066Z] JVMDUMP010I Java dump written to C:\Users\jenkins\workspace\Test_openjdk20_j9_sanity.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_16854983099972\jdk_util_0\work\scratch\0\javacore.20230530.230838.6524.0002.txt
[2023-05-31T03:08:45.066Z] 
[2023-05-31T03:08:45.066Z] TEST RESULT: Error. Agent error: java.lang.Exception: Agent 12 timed out with a timeout of 960 seconds; check console log for any additional details
[2023-05-31T03:08:45.066Z] --------------------------------------------------
[2023-05-31T03:25:04.017Z] Test results: passed: 923; error: 1
[2023-05-31T03:25:28.174Z] Report written to C:\Users\jenkins\workspace\Test_openjdk20_j9_sanity.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_16854983099972\jdk_util_0\report\html\report.html
[2023-05-31T03:25:28.174Z] Results written to C:\Users\jenkins\workspace\Test_openjdk20_j9_sanity.openjdk_x86-64_windows_testList_0\aqa-tests\TKG\output_16854983099972\jdk_util_0\work
[2023-05-31T03:25:28.174Z] Error: Some tests failed or other problems occurred.
[2023-05-31T03:25:28.174Z] -----------------------------------
[2023-05-31T03:25:28.174Z] jdk_util_0_FAILED

@pshipton
Copy link
Member Author

pshipton commented Jun 6, 2023

https://openj9-jenkins.osuosl.org/job/Test_openjdk20_j9_sanity.openjdk_x86-64_windows_Nightly/70
jdk_util_1
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

03:48:24  Timeout information:
03:48:24  Running jcmd on process 2084
03:48:24  Dump written to C:\Users\jenkins\workspace\Test_openjdk20_j9_sanity.openjdk_x86-64_windows_Nightly\aqa-tests\TKG\output_16860111469090\jdk_util_1\work\scratch\1\core.20230606.034656.2084.0001.dmp
03:48:24  Dump written to C:\Users\jenkins\workspace\Test_openjdk20_j9_sanity.openjdk_x86-64_windows_Nightly\aqa-tests\TKG\output_16860111469090\jdk_util_1\work\scratch\1\javacore.20230606.034746.2084.0002.txt
03:48:24  Running jstack on process 2084
03:48:24  2023-06-06T03:47:47.468695700
03:48:24  Virtual machine: 2084 JVM information:
03:48:24  JRE 20 Windows Server 2012 R2 amd64-64-Bit 20230605_74 (JIT enabled, AOT enabled)
03:48:24  OpenJ9   - 7cabea3aa07
03:48:24  OMR      - 283d187d38a
03:48:24  JCL      - 1e863069cbd based on jdk-20.0.1+9
03:48:24  
03:48:24  "main" prio=5 Id=2 WAITING
03:48:24  	at [email protected]/java.lang.Object.waitImpl(Native Method)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:255)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:221)
03:48:24  	at [email protected]/java.lang.Thread.join(Thread.java:2147)
03:48:24  	at [email protected]/java.lang.Thread.join(Thread.java:2223)
03:48:24  	at app//com.sun.javatest.regtest.agent.MainActionHelper.runClass(MainActionHelper.java:198)
03:48:24  	at app//com.sun.javatest.regtest.agent.AgentServer.doMain(AgentServer.java:307)
03:48:24  	at app//com.sun.javatest.regtest.agent.AgentServer.run(AgentServer.java:234)
03:48:24  	at app//com.sun.javatest.regtest.agent.AgentServer.main(AgentServer.java:71)
03:48:24  
03:48:24  "JIT Compilation Thread-000" prio=10 Id=4 RUNNABLE
03:48:24  
03:48:24  "JIT Compilation Thread-001 Suspended" prio=10 Id=5 RUNNABLE
03:48:24  
03:48:24  "JIT Compilation Thread-002 Suspended" prio=10 Id=6 RUNNABLE
03:48:24  
03:48:24  "JIT Diagnostic Compilation Thread-003 Suspended" prio=10 Id=7 RUNNABLE
03:48:24  
03:48:24  "JIT-SamplerThread" prio=10 Id=8 TIMED_WAITING
03:48:24  
03:48:24  "IProfiler" prio=5 Id=9 RUNNABLE
03:48:24  
03:48:24  "Common-Cleaner" prio=8 Id=3 TIMED_WAITING
03:48:24  	at [email protected]/java.lang.Object.waitImpl(Native Method)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:255)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:221)
03:48:24  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
03:48:24  	at [email protected]/jdk.internal.ref.CleanerImpl.run(CleanerImpl.java:140)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  	at [email protected]/jdk.internal.misc.InnocuousThread.run(InnocuousThread.java:186)
03:48:24  
03:48:24  "Finalizer thread" prio=5 Id=11 RUNNABLE
03:48:24  
03:48:24  "Concurrent Mark Helper" prio=1 Id=10 RUNNABLE
03:48:24  
03:48:24  "GC Worker" prio=5 Id=12 RUNNABLE
03:48:24  
03:48:24  "GC Worker" prio=5 Id=13 RUNNABLE
03:48:24  
03:48:24  "GC Worker" prio=5 Id=14 RUNNABLE
03:48:24  
03:48:24  "Attach API wait loop" prio=10 Id=17 RUNNABLE
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.IPC.waitSemaphore(Native Method)
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.CommonDirectory.waitSemaphore(CommonDirectory.java:264)
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.WaitLoop.waitForNotification(WaitLoop.java:66)
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.WaitLoop.run(WaitLoop.java:157)
03:48:24  
03:48:24  "pool-1-thread-1" prio=5 Id=18 TIMED_WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:269)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1758)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1182)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "SeedGenerator Thread" prio=1 Id=48 WAITING
03:48:24  	at [email protected]/java.lang.Object.waitImpl(Native Method)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:255)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:192)
03:48:24  	at [email protected]/sun.security.provider.SeedGenerator$ThreadedSeedGenerator.run(SeedGenerator.java:334)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "ClassByNameCache Reaper" prio=5 Id=297 WAITING
03:48:24  	at [email protected]/java.lang.Object.waitImpl(Native Method)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:255)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:221)
03:48:24  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
03:48:24  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:109)
03:48:24  	at [email protected]/java.io.ClassByNameCache$Reaper.run(ClassByNameCache.java:232)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "AgentVMThread" prio=5 Id=1158 TIMED_WAITING
03:48:24  	at [email protected]/java.lang.Object.waitImpl(Native Method)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:255)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:221)
03:48:24  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
03:48:24  	at GCRetention.removeAll(GCRetention.java:86)
03:48:24  	at GCRetention.test(GCRetention.java:117)
03:48:24  	at GCRetention.instanceMain(GCRetention.java:138)
03:48:24  	at GCRetention.main(GCRetention.java:136)
03:48:24  	at [email protected]/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
03:48:24  	at [email protected]/java.lang.reflect.Method.invoke(Method.java:578)
03:48:24  	at app//com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:312)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-1" prio=5 Id=1159 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-2" prio=5 Id=1160 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-3" prio=5 Id=1161 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-4" prio=5 Id=1162 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-5" prio=5 Id=1163 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-6" prio=5 Id=1164 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-7" prio=5 Id=1165 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-8" prio=5 Id=1166 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-9" prio=5 Id=1167 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "pool-45-thread-10" prio=5 Id=1168 WAITING
03:48:24  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
03:48:24  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
03:48:24  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
03:48:24  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
03:48:24  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
03:48:24  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
03:48:24  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
03:48:24  
03:48:24  "file lock watchdog" prio=10 Id=1170 TIMED_WAITING
03:48:24  	at [email protected]/java.lang.Object.waitImpl(Native Method)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:255)
03:48:24  	at [email protected]/java.lang.Object.wait(Object.java:221)
03:48:24  	at [email protected]/java.util.TimerThread.mainLoop(Timer.java:569)
03:48:24  	at [email protected]/java.util.TimerThread.run(Timer.java:522)
03:48:24  
03:48:24  "Attachment portNumber: 61492" prio=10 Id=1172 RUNNABLE
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.dumpAllThreadsImpl(Native Method)
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.getThreadInfo(DiagnosticUtils.java:245)
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.executeDiagnosticCommand(DiagnosticUtils.java:181)
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.Attachment.doCommand(Attachment.java:248)
03:48:24  	at [email protected]/openj9.internal.tools.attach.target.Attachment.run(Attachment.java:159)
03:48:24  
03:48:24  
03:48:24  --- Timeout information end.

03:48:24  2/100 unqueued references remaining

@pshipton
Copy link
Member Author

pshipton commented Jun 9, 2023

https://openj9-jenkins.osuosl.org/job/Test_openjdk20_j9_sanity.openjdk_aarch64_linux_Nightly/74
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

20:44:55  Timeout information:
20:44:55  Running jcmd on process 2644000
20:44:55  Dump written to /home/jenkins/workspace/Test_openjdk20_j9_sanity.openjdk_aarch64_linux_Nightly/aqa-tests/TKG/output_16862684166642/jdk_util_0/work/scratch/0/core.20230608.204440.2644000.0001.dmp
20:44:55  Dump written to /home/jenkins/workspace/Test_openjdk20_j9_sanity.openjdk_aarch64_linux_Nightly/aqa-tests/TKG/output_16862684166642/jdk_util_0/work/scratch/0/javacore.20230608.204442.2644000.0002.txt
20:44:55  Running jstack on process 2644000
20:44:55  2023-06-08T20:44:43.910045569
20:44:55  Virtual machine: 2644000 JVM information:
20:44:55  JRE 20 Linux aarch64-64-Bit Compressed References 20230608_78 (JIT enabled, AOT enabled)
20:44:55  OpenJ9   - bad620fcdfe
20:44:55  OMR      - 8cdf471e9aa
20:44:55  JCL      - 7b85cb34310 based on jdk-20.0.1+9
20:44:55  
20:44:55  "main" prio=5 Id=2 WAITING
20:44:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
20:44:55  	at [email protected]/java.lang.Thread.join(Thread.java:2147)
20:44:55  	at [email protected]/java.lang.Thread.join(Thread.java:2223)
20:44:55  	at app//com.sun.javatest.regtest.agent.MainActionHelper.runClass(MainActionHelper.java:198)
20:44:55  	at app//com.sun.javatest.regtest.agent.AgentServer.doMain(AgentServer.java:307)
20:44:55  	at app//com.sun.javatest.regtest.agent.AgentServer.run(AgentServer.java:234)
20:44:55  	at app//com.sun.javatest.regtest.agent.AgentServer.main(AgentServer.java:71)
20:44:55  
20:44:55  "JIT Compilation Thread-000" prio=10 Id=4 RUNNABLE
20:44:55  
20:44:55  "JIT Compilation Thread-001 Suspended" prio=10 Id=5 RUNNABLE
20:44:55  
20:44:55  "JIT Compilation Thread-002 Suspended" prio=10 Id=6 RUNNABLE
20:44:55  
20:44:55  "JIT Compilation Thread-003 Suspended" prio=10 Id=7 RUNNABLE
20:44:55  
20:44:55  "JIT Compilation Thread-004 Suspended" prio=10 Id=8 RUNNABLE
20:44:55  
20:44:55  "JIT Compilation Thread-005 Suspended" prio=10 Id=9 RUNNABLE
20:44:55  
20:44:55  "JIT Compilation Thread-006 Suspended" prio=10 Id=10 RUNNABLE
20:44:55  
20:44:55  "JIT Diagnostic Compilation Thread-007 Suspended" prio=10 Id=11 RUNNABLE
20:44:55  
20:44:55  "JIT-SamplerThread" prio=10 Id=12 TIMED_WAITING
20:44:55  
20:44:55  "IProfiler" prio=5 Id=13 RUNNABLE
20:44:55  
20:44:55  "Common-Cleaner" prio=8 Id=3 TIMED_WAITING
20:44:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
20:44:55  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
20:44:55  	at [email protected]/jdk.internal.ref.CleanerImpl.run(CleanerImpl.java:140)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  	at [email protected]/jdk.internal.misc.InnocuousThread.run(InnocuousThread.java:186)
20:44:55  
20:44:55  "Concurrent Mark Helper" prio=1 Id=14 RUNNABLE
20:44:55  
20:44:55  "Finalizer thread" prio=5 Id=15 RUNNABLE
20:44:55  
20:44:55  "GC Worker" prio=5 Id=16 RUNNABLE
20:44:55  
20:44:55  "GC Worker" prio=5 Id=17 RUNNABLE
20:44:55  
20:44:55  "GC Worker" prio=5 Id=18 RUNNABLE
20:44:55  
20:44:55  "GC Worker" prio=5 Id=19 RUNNABLE
20:44:55  
20:44:55  "GC Worker" prio=5 Id=20 RUNNABLE
20:44:55  
20:44:55  "GC Worker" prio=5 Id=21 RUNNABLE
20:44:55  
20:44:55  "GC Worker" prio=5 Id=22 RUNNABLE
20:44:55  
20:44:55  "Attach API wait loop" prio=10 Id=25 RUNNABLE
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.IPC.waitSemaphore(Native Method)
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.CommonDirectory.waitSemaphore(CommonDirectory.java:264)
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.WaitLoop.waitForNotification(WaitLoop.java:66)
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.WaitLoop.run(WaitLoop.java:157)
20:44:55  
20:44:55  "pool-1-thread-1" prio=5 Id=26 TIMED_WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:269)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1758)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1182)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "ClassByNameCache Reaper" prio=5 Id=32 WAITING
20:44:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
20:44:55  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
20:44:55  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:109)
20:44:55  	at [email protected]/java.io.ClassByNameCache$Reaper.run(ClassByNameCache.java:232)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "AgentVMThread" prio=5 Id=30350 TIMED_WAITING
20:44:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
20:44:55  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
20:44:55  	at GCRetention.removeAll(GCRetention.java:86)
20:44:55  	at GCRetention.test(GCRetention.java:117)
20:44:55  	at GCRetention.instanceMain(GCRetention.java:138)
20:44:55  	at GCRetention.main(GCRetention.java:136)
20:44:55  	at [email protected]/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
20:44:55  	at [email protected]/java.lang.reflect.Method.invoke(Method.java:578)
20:44:55  	at app//com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:312)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-1" prio=5 Id=30351 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-2" prio=5 Id=30352 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-3" prio=5 Id=30353 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-4" prio=5 Id=30354 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-5" prio=5 Id=30355 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-6" prio=5 Id=30356 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-7" prio=5 Id=30357 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-8" prio=5 Id=30358 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-9" prio=5 Id=30359 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "pool-40-thread-10" prio=5 Id=30360 WAITING
20:44:55  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:44:55  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:371)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:519)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3780)
20:44:55  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3725)
20:44:55  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1707)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:44:55  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1070)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
20:44:55  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
20:44:55  	at [email protected]/java.lang.Thread.run(Thread.java:1639)
20:44:55  
20:44:55  "file lock watchdog" prio=10 Id=30362 TIMED_WAITING
20:44:55  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:255)
20:44:55  	at [email protected]/java.lang.Object.wait(Object.java:221)
20:44:55  	at [email protected]/java.util.TimerThread.mainLoop(Timer.java:569)
20:44:55  	at [email protected]/java.util.TimerThread.run(Timer.java:522)
20:44:55  
20:44:55  "Attachment portNumber: 35981" prio=10 Id=30364 RUNNABLE
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.dumpAllThreadsImpl(Native Method)
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.getThreadInfo(DiagnosticUtils.java:245)
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.executeDiagnosticCommand(DiagnosticUtils.java:181)
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.Attachment.doCommand(Attachment.java:248)
20:44:55  	at [email protected]/openj9.internal.tools.attach.target.Attachment.run(Attachment.java:159)
20:44:55  
20:44:55  
20:44:55  --- Timeout information end.

...

20:44:55  1/100 unqueued references remaining

@JasonFengJ9
Copy link
Member

JDK21 aarch64_mac

15:25:38  variation: -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache Mode650
15:25:38  JVM_OPTIONS:  -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:-UseCompressedOops 

15:43:59  TEST: java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

15:43:59  1/100 unqueued references remaining
15:43:59  1/100 un
15:43:59  TEST RESULT: Error. Agent error: java.lang.Exception: Agent 6 timed out with a timeout of 960 seconds; check console log for any additional details
15:43:59  --------------------------------------------------
15:43:59  Test results: passed: 949; error: 1
15:43:59  Report written to /Users/jenkins/workspace/Test_openjdk21_j9_sanity.openjdk_aarch64_mac_Personal_testList_2/aqa-tests/TKG/output_16897944579997/jdk_util_1/report/html/report.html
15:43:59  Results written to /Users/jenkins/workspace/Test_openjdk21_j9_sanity.openjdk_aarch64_mac_Personal_testList_2/aqa-tests/TKG/output_16897944579997/jdk_util_1/work
15:43:59  Error: Some tests failed or other problems occurred.
15:43:59  -----------------------------------
15:43:59  jdk_util_1_FAILED

@pshipton
Copy link
Member Author

pshipton commented Sep 7, 2023

https://openj9-jenkins.osuosl.org/job/Test_openjdk17_j9_sanity.openjdk_aarch64_mac_Nightly_testList_1/49/
jdk_util_1
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java
Timeout, no diagnostics.

@JasonFengJ9
Copy link
Member

JDK11 x86-64_mac 0.41 milestone 1(osxrt1)

[2023-09-06T12:49:35.464Z] variation: -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache Mode150
[2023-09-06T12:49:35.464Z] JVM_OPTIONS:  -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:+UseCompressedOops 

[2023-09-06T13:07:11.139Z] TEST: java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

[2023-09-06T13:07:11.143Z] 1/100 unqueued references remaining
[2023-09-06T13:07:11.143Z] 1/100 un
[2023-09-06T13:07:11.143Z] TEST RESULT: Error. Agent error: java.lang.Exception: Agent 7 timed out with a timeout of 960 seconds; check console log for any additional details
[2023-09-06T13:07:11.143Z] --------------------------------------------------
[2023-09-06T13:07:11.143Z] Test results: passed: 853; error: 1
[2023-09-06T13:07:11.926Z] Report written to /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_x86-64_mac_testList_1/aqa-tests/TKG/output_16940029321122/jdk_util_0/report/html/report.html
[2023-09-06T13:07:11.926Z] Results written to /Users/jenkins/workspace/Test_openjdk11_j9_sanity.openjdk_x86-64_mac_testList_1/aqa-tests/TKG/output_16940029321122/jdk_util_0/work
[2023-09-06T13:07:11.926Z] Error: Some tests failed or other problems occurred.
[2023-09-06T13:07:11.926Z] -----------------------------------
[2023-09-06T13:07:11.926Z] jdk_util_0_FAILED

@pshipton
Copy link
Member Author

pshipton commented Jan 5, 2024

https://openj9-jenkins.osuosl.org/job/Test_openjdk17_j9_sanity.openjdk_aarch64_linux_Nightly_testList_1/126/
jdk_util_0
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

20:23:18  Timeout information:
20:23:18  Running jcmd on process 1130918
20:23:18  Dump written to /home/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/output_17044154288026/jdk_util_0/work/scratch/0/core.20240105.012311.1130918.0001.dmp
20:23:18  Dump written to /home/jenkins/workspace/Test_openjdk17_j9_sanity.openjdk_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/output_17044154288026/jdk_util_0/work/scratch/0/javacore.20240105.012315.1130918.0002.txt
20:23:18  Running jstack on process 1130918
20:23:18  2024-01-05T01:23:16.465002822
20:23:18  Virtual machine: 1130918 JVM information:
20:23:18  JRE 17 Linux aarch64-64-Bit Compressed References 20240105_627 (JIT enabled, AOT enabled)
20:23:18  OpenJ9   - 1b60ab82853
20:23:18  OMR      - 3a4787401ff
20:23:18  JCL      - d15a14efbbe based on jdk-17.0.10+6
20:23:18  
20:23:18  "main" prio=5 Id=1 WAITING
20:23:18  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:251)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:219)
20:23:18  	at [email protected]/java.lang.Thread.join(Thread.java:784)
20:23:18  	at [email protected]/java.lang.Thread.join(Thread.java:739)
20:23:18  	at app//com.sun.javatest.regtest.agent.MainActionHelper.runClass(MainActionHelper.java:219)
20:23:18  	at app//com.sun.javatest.regtest.agent.AgentServer.doMain(AgentServer.java:317)
20:23:18  	at app//com.sun.javatest.regtest.agent.AgentServer.run(AgentServer.java:242)
20:23:18  	at app//com.sun.javatest.regtest.agent.AgentServer.main(AgentServer.java:71)
20:23:18  
20:23:18  "JIT Compilation Thread-000" prio=10 Id=3 RUNNABLE
20:23:18  
20:23:18  "JIT Compilation Thread-001 Suspended" prio=10 Id=4 RUNNABLE
20:23:18  
20:23:18  "JIT Compilation Thread-002 Suspended" prio=10 Id=5 RUNNABLE
20:23:18  
20:23:18  "JIT Compilation Thread-003 Suspended" prio=10 Id=6 RUNNABLE
20:23:18  
20:23:18  "JIT Compilation Thread-004 Suspended" prio=10 Id=7 RUNNABLE
20:23:18  
20:23:18  "JIT Compilation Thread-005 Suspended" prio=10 Id=8 RUNNABLE
20:23:18  
20:23:18  "JIT Compilation Thread-006 Suspended" prio=10 Id=9 RUNNABLE
20:23:18  
20:23:18  "JIT Diagnostic Compilation Thread-007 Suspended" prio=10 Id=10 RUNNABLE
20:23:18  
20:23:18  "JIT-SamplerThread" prio=10 Id=11 TIMED_WAITING
20:23:18  
20:23:18  "IProfiler" prio=5 Id=12 RUNNABLE
20:23:18  
20:23:18  "Common-Cleaner" prio=8 Id=2 TIMED_WAITING
20:23:18  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:251)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:219)
20:23:18  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
20:23:18  	at [email protected]/jdk.internal.ref.CleanerImpl.run(CleanerImpl.java:140)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  	at [email protected]/jdk.internal.misc.InnocuousThread.run(InnocuousThread.java:162)
20:23:18  
20:23:18  "Finalizer thread" prio=5 Id=13 RUNNABLE
20:23:18  
20:23:18  "Concurrent Mark Helper" prio=1 Id=14 RUNNABLE
20:23:18  
20:23:18  "GC Worker" prio=5 Id=15 RUNNABLE
20:23:18  
20:23:18  "GC Worker" prio=5 Id=16 RUNNABLE
20:23:18  
20:23:18  "GC Worker" prio=5 Id=17 RUNNABLE
20:23:18  
20:23:18  "GC Worker" prio=5 Id=18 RUNNABLE
20:23:18  
20:23:18  "GC Worker" prio=5 Id=19 RUNNABLE
20:23:18  
20:23:18  "GC Worker" prio=5 Id=20 RUNNABLE
20:23:18  
20:23:18  "GC Worker" prio=5 Id=21 RUNNABLE
20:23:18  
20:23:18  "Attach API wait loop" prio=10 Id=24 RUNNABLE
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.IPC.waitSemaphore(Native Method)
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.CommonDirectory.waitSemaphore(CommonDirectory.java:264)
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.WaitLoop.waitForNotification(WaitLoop.java:66)
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.WaitLoop.run(WaitLoop.java:157)
20:23:18  
20:23:18  "pool-1-thread-1" prio=5 Id=25 TIMED_WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:252)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1672)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1182)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "ClassByNameCache Reaper" prio=5 Id=31 WAITING
20:23:18  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:251)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:219)
20:23:18  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
20:23:18  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:109)
20:23:18  	at [email protected]/java.io.ClassByNameCache$Reaper.run(ClassByNameCache.java:232)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "AgentVMThread" prio=5 Id=932 TIMED_WAITING
20:23:18  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:251)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:219)
20:23:18  	at [email protected]/java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:137)
20:23:18  	at GCRetention.removeAll(GCRetention.java:86)
20:23:18  	at GCRetention.test(GCRetention.java:117)
20:23:18  	at GCRetention.instanceMain(GCRetention.java:138)
20:23:18  	at GCRetention.main(GCRetention.java:136)
20:23:18  	at [email protected]/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
20:23:18  	at [email protected]/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
20:23:18  	at [email protected]/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
20:23:18  	at [email protected]/java.lang.reflect.Method.invoke(Method.java:574)
20:23:18  	at app//com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-1" prio=5 Id=933 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-2" prio=5 Id=934 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-3" prio=5 Id=935 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-4" prio=5 Id=936 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-5" prio=5 Id=937 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-6" prio=5 Id=938 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-7" prio=5 Id=939 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-8" prio=5 Id=940 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-9" prio=5 Id=941 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "pool-44-thread-10" prio=5 Id=942 WAITING
20:23:18  	at [email protected]/jdk.internal.misc.Unsafe.park(Native Method)
20:23:18  	at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:341)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionNode.block(AbstractQueuedSynchronizer.java:506)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.unmanagedBlock(ForkJoinPool.java:3465)
20:23:18  	at [email protected]/java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3436)
20:23:18  	at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1623)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1177)
20:23:18  	at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1062)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1122)
20:23:18  	at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
20:23:18  	at [email protected]/java.lang.Thread.run(Thread.java:857)
20:23:18  
20:23:18  "file lock watchdog" prio=10 Id=944 TIMED_WAITING
20:23:18  	at [email protected]/java.lang.Object.waitImpl(Native Method)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:251)
20:23:18  	at [email protected]/java.lang.Object.wait(Object.java:219)
20:23:18  	at [email protected]/java.util.TimerThread.mainLoop(Timer.java:602)
20:23:18  	at [email protected]/java.util.TimerThread.run(Timer.java:543)
20:23:18  
20:23:18  "Attachment portNumber: 40479" prio=10 Id=946 RUNNABLE
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.dumpAllThreadsImpl(Native Method)
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.getThreadInfo(DiagnosticUtils.java:249)
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.DiagnosticUtils.executeDiagnosticCommand(DiagnosticUtils.java:185)
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.Attachment.doCommand(Attachment.java:248)
20:23:18  	at [email protected]/openj9.internal.tools.attach.target.Attachment.run(Attachment.java:159)
20:23:18  
20:23:18  
20:23:18  --- Timeout information end.

2/100 unqueued references remaining

@pshipton
Copy link
Member Author

https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_x86-64_mac_OpenJDK21_testList_1/17/
jdk_util_0
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java
01:04:16 2/100 unqueued references remaining
timeout

@pshipton
Copy link
Member Author

https://openj9-jenkins.osuosl.org/job/Test_openjdk17_j9_sanity.openjdk_aarch64_linux_Release_testList_2/40
jdk_util_1
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java
1/100 unqueued references remaining
timeout

@JasonFengJ9
Copy link
Member

JDK22 ppc64_aix(paix905)

[2024-05-29T07:54:30.095Z] variation: -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache Mode650
[2024-05-29T07:54:30.095Z] JVM_OPTIONS:  -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:-UseCompressedOops -Xverbosegclog 

[2024-05-29T08:21:26.959Z] TEST: java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

[2024-05-29T08:21:26.965Z] 1/100 unqueued references remaining

[2024-05-29T08:21:26.965Z] TEST RESULT: Error. Agent error: java.lang.Exception: Agent 2 timed out with a timeout of 960 seconds; check console log for any additional details
[2024-05-29T08:21:26.965Z] --------------------------------------------------
[2024-05-29T09:04:52.831Z] Test results: passed: 973; error: 1
[2024-05-29T09:05:31.604Z] Report written to /home/jenkins/workspace/Test_openjdk22_j9_sanity.openjdk_ppc64_aix_testList_0/aqa-tests/TKG/output_17169612505128/jdk_util_1/report/html/report.html
[2024-05-29T09:05:31.604Z] Results written to /home/jenkins/workspace/Test_openjdk22_j9_sanity.openjdk_ppc64_aix_testList_0/aqa-tests/TKG/output_17169612505128/jdk_util_1/work
[2024-05-29T09:05:31.604Z] Error: Some tests failed or other problems occurred.
[2024-05-29T09:05:31.604Z] -----------------------------------
[2024-05-29T09:05:31.604Z] jdk_util_1_FAILED

@pshipton
Copy link
Member Author

pshipton commented Aug 2, 2024

https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_aarch64_linux_OMR_testList_0/151
jdk_util_1
java/util/concurrent/ScheduledThreadPoolExecutor/GCRetention.java

https://openj9-artifactory.osuosl.org/artifactory/ci-openj9/Test/Test_openjdk21_j9_sanity.openjdk_aarch64_linux_OMR_testList_0/151/openjdk_test_output.tar.gz

07:11:06 3/100 unqueued references remaining
timeout

@hzongaro hzongaro mentioned this issue Aug 27, 2024
Merged
@pshipton
Copy link
Member Author

https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_aarch64_mac_Nightly_testList_0/253

@pshipton
Copy link
Member Author

pshipton commented Dec 6, 2024

https://openj9-jenkins.osuosl.org/job/Test_openjdk11_j9_sanity.openjdk_x86-64_linux_Nightly_testList_0/169

@babsingh babsingh mentioned this issue Dec 18, 2024
Merged
@pshipton
Copy link
Member Author

pshipton commented Jan 6, 2025

https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_x86-64_linux_Release_testList_1/37

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
test failure
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@JasonFengJ9 @pshipton