OpenJDK java/lang/Thread/virtual/stress/ParkALot.java#id0 timeout

[pshipton]

https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_aarch64_linux_Nightly_testList_0/96/
https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_aarch64_linux_Nightly_testList_1/96/

https://openj9-artifactory.osuosl.org/artifactory/ci-openj9/Test/Test_openjdk21_j9_sanity.openjdk_aarch64_linux_Nightly_testList_0/96/openjdk_test_output.tar.gz
https://openj9-artifactory.osuosl.org/artifactory/ci-openj9/Test/Test_openjdk21_j9_sanity.openjdk_aarch64_linux_Nightly_testList_1/96/openjdk_test_output.tar.gz

java/lang/Thread/virtual/stress/ParkALot.java#id0
Showing selected threads from the timeout:

19:24:15  "ForkJoinPool-1-worker-2" prio=5 Id=31 WAITING
19:24:15  	at java.base@21.0.1-internal/jdk.internal.vm.Continuation.run(Continuation.java:213)
19:24:15  	at java.base@21.0.1-internal/java.lang.VirtualThread.runContinuation(VirtualThread.java:239)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinTask$RunnableExecuteAction.exec(ForkJoinTask.java:1423)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:387)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1312)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1843)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1808)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:188)

19:24:15  "ForkJoinPool-1-worker-4" prio=5 Id=33 BLOCKED on java.lang.Object@1cff76f4 owned by "Thread-5" Id=43
19:24:15  	at java.base@21.0.1-internal/java.lang.VirtualThread.unmount(VirtualThread.java:388)
19:24:15  	at java.base@21.0.1-internal/java.lang.VirtualThread.runContinuation(VirtualThread.java:241)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinTask$RunnableExecuteAction.exec(ForkJoinTask.java:1423)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:387)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1312)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1843)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1808)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:188)

19:24:15  "ForkJoinPool-1-worker-7" prio=5 Id=40 WAITING
19:24:15  	at java.base@21.0.1-internal/jdk.internal.vm.Continuation.run(Continuation.java:213)
19:24:15  	at java.base@21.0.1-internal/java.lang.VirtualThread.runContinuation(VirtualThread.java:239)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinTask$RunnableExecuteAction.exec(ForkJoinTask.java:1423)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:387)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1312)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1843)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1808)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:188)

19:24:15  "Thread-4" prio=5 Id=42 RUNNABLE
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.getStateImpl(Native Method)
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.threadState(Thread.java:2794)
19:24:15  	- locked java.lang.Object@bd52e5b2
19:24:15  	at java.base@21.0.1-internal/java.lang.VirtualThread.threadState(VirtualThread.java:906)
19:24:15  	- locked java.lang.Object@4133e156
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.getState(Thread.java:2778)
19:24:15  	at app//ParkALot.parkALot(ParkALot.java:86)
19:24:15  	at app//ParkALot.lambda$main$0(ParkALot.java:59)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:572)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.FutureTask.run(FutureTask.java:317)
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.run(Thread.java:1595)
19:24:15  
19:24:15  "Thread-5" prio=5 Id=43 RUNNABLE
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.getStateImpl(Native Method)
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.threadState(Thread.java:2794)
19:24:15  	- locked java.lang.Object@d6fbbc8d
19:24:15  	at java.base@21.0.1-internal/java.lang.VirtualThread.threadState(VirtualThread.java:906)
19:24:15  	- locked java.lang.Object@1cff76f4
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.getState(Thread.java:2778)
19:24:15  	at app//ParkALot.parkALot(ParkALot.java:86)
19:24:15  	at app//ParkALot.lambda$main$0(ParkALot.java:59)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:572)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.FutureTask.run(FutureTask.java:317)
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.run(Thread.java:1595)
19:24:15  
19:24:15  "Thread-6" prio=5 Id=44 RUNNABLE
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.getStateImpl(Native Method)
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.threadState(Thread.java:2794)
19:24:15  	- locked java.lang.Object@5d1d0081
19:24:15  	at java.base@21.0.1-internal/java.lang.VirtualThread.threadState(VirtualThread.java:906)
19:24:15  	- locked java.lang.Object@80b77f44
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.getState(Thread.java:2778)
19:24:15  	at app//ParkALot.parkALot(ParkALot.java:86)
19:24:15  	at app//ParkALot.lambda$main$0(ParkALot.java:59)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:572)
19:24:15  	at java.base@21.0.1-internal/java.util.concurrent.FutureTask.run(FutureTask.java:317)
19:24:15  	at java.base@21.0.1-internal/java.lang.Thread.run(Thread.java:1595)

2dc399e...881ab73
eclipse-openj9/openj9-omr@2a9a7f4...9bc6e08
ibmruntimes/openj9-openjdk-jdk21@f8d2943...88ac736

[pshipton]

@fengxue-IS @babsingh @tajila fyi

[pshipton]

Other platforms as well.
https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_ppc64_aix_Nightly_testList_0/86/
https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_ppc64_aix_Nightly_testList_1/86/
https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_ppc64le_linux_Nightly_testList_0/93/
https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_ppc64le_linux_Nightly_testList_1/93/

[JasonFengJ9]

Also observed at internal builds: JDK21 aarch64_linux JDK21 ppc64_aix

[babsingh]

It's a new test introduced in ibmruntimes/openj9-openjdk-jdk21@8f677c4.

It launches num_threads and expects each thread to park and unpark 500_000 times. The test just takes a lot of time, which causes the timeout. The test passes in a reasonable time when the number of iterations are reduced to 5_000, which should be sufficient to verify functional behaviour.

[pshipton]

https://openj9-jenkins.osuosl.org/job/Test_openjdk21_j9_sanity.openjdk_x86-64_windows_OMR_testList_1/43/

@babsingh are you planning to modify the test to avoid the timeouts in our regular testing?

[babsingh]

are you planning to modify the test to avoid the timeouts in our regular testing?

Yes, opened the below PRs to address the timeout:

• JDK21: Reduce the number of park and unpark iterations in ParkALot ibmruntimes/openj9-openjdk-jdk21#60
• JDK-next: Reduce the number of park and unpark iterations in ParkALot ibmruntimes/openj9-openjdk-jdk#690

[babsingh]

The two PRs, mentioned in #18444 (comment), have been merged. Closing this issue since the two PRs should resolve this issue.