eclipse-openj9 / openj9

Eclipse OpenJ9: A Java Virtual Machine for OpenJDK that's optimized for small footprint, fast start-up, and high throughput. Builds on Eclipse OMR (https://github.com/eclipse/omr) and combines with the Extensions for OpenJDK for OpenJ9 repo.
Other
3.28k stars 721 forks source link

OpenJDK TestLogConfigurationDeadLockWithConf Found 2 deadlocked threads #13823

Open pshipton opened 2 years ago

pshipton commented 2 years ago

https://openj9-jenkins.osuosl.org/job/Test_openjdk8_j9_sanity.openjdk_ppc64le_linux_Nightly/136 jdk_util_1 -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:-UseCompressedOops java/util/logging/TestLogConfigurationDeadLockWithConf.java

22:56:55  ACTION: main -- Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: Found 2 deadlocked threads
22:56:55  REASON: User specified action: run main/othervm TestLogConfigurationDeadLockWithConf 
22:56:55  TIME:   3.396 seconds
22:56:55  messages:
22:56:55  command: main TestLogConfigurationDeadLockWithConf
22:56:55  reason: User specified action: run main/othervm TestLogConfigurationDeadLockWithConf 
22:56:55  Mode: othervm [/othervm specified]
22:56:55  elapsed time (seconds): 3.396
22:56:55  configuration:
22:56:55  STDOUT:
22:56:55  No security
22:56:55  3101 ms elapsed (4000 requested)
22:56:55  STDERR:
22:56:55  Oct 28, 2021 10:56:40 PM TestLogConfigurationDeadLockWithConf$Stopper run
22:56:55  INFO: 2992ms remaining...
22:56:55  Oct 28, 2021 10:56:41 PM TestLogConfigurationDeadLockWithConf$Stopper run
22:56:55  INFO: 1913ms remaining...
22:56:55  Found 2 deadlocked threads: 
22:56:55  "Thread-4" Id=33 TIMED_WAITING
22:56:55    at java.lang.Thread.sleep(Native Method)
22:56:55    at java.lang.Thread.sleep(Thread.java:957)
22:56:55    at TestLogConfigurationDeadLockWithConf$ReadConf.run(TestLogConfigurationDeadLockWithConf.java:229)
22:56:55  
22:56:55  "Finalizer thread" Id=40 RUNNABLE
22:56:55  
22:56:55  java.lang.RuntimeException: Found 2 deadlocked threads
22:56:55    at TestLogConfigurationDeadLockWithConf$DeadlockDetector.run(TestLogConfigurationDeadLockWithConf.java:287)
22:56:55  Waiting for Stopper: Thread[Thread-10,5,MainThreadGroup]
22:56:55  Skipping deadlocked thread ReadConf: Thread[Thread-4,5,]
22:56:55  All threads joined.
22:56:55  FAILED: 5097 loggers created by 2 Thread(s),
22:56:55     LogManager.readConfiguration() called 4167 times by 2 Thread(s).
22:56:55     ThreadMXBean.findDeadlockedThreads called 31 times by 1 Thread.
22:56:55     Error is: Found 2 deadlocked threads
22:56:55  java.lang.RuntimeException: Found 2 deadlocked threads
22:56:55    at TestLogConfigurationDeadLockWithConf$DeadlockDetector.run(TestLogConfigurationDeadLockWithConf.java:287)
22:56:55  
22:56:55  JavaTest Message: Test threw exception: java.lang.RuntimeException: Found 2 deadlocked threads
22:56:55  JavaTest Message: shutting down test
22:56:55  
22:56:55  STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Found 2 deadlocked threads
JasonFengJ9 commented 5 months ago

JDK8 ppc64le_linux(sles12le-svl-rt2-1)

[2024-05-11T04:03:35.191Z] variation: -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache Mode150
[2024-05-11T04:03:35.191Z] JVM_OPTIONS:  -Xdump:system:none -Xdump:heap:none -Xdump:system:events=gpf+abort+traceassert+corruptcache -XX:+UseCompressedOops -Xverbosegclog 

[2024-05-11T04:09:21.768Z] TEST: java/util/logging/TestLogConfigurationDeadLock.java

[2024-05-11T04:09:21.769Z] JavaTest Message: Test threw exception: java.lang.RuntimeException: Found 2 deadlocked threads
[2024-05-11T04:09:21.769Z] JavaTest Message: shutting down test
[2024-05-11T04:09:21.769Z] 
[2024-05-11T04:09:21.769Z] STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Found 2 deadlocked threads

[2024-05-11T04:09:21.769Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: Found 2 deadlocked threads
[2024-05-11T04:09:21.769Z] --------------------------------------------------
[2024-05-11T04:17:31.662Z] Test results: passed: 655; failed: 1
[2024-05-11T04:17:35.844Z] Report written to /home/jenkins/workspace/Test_openjdk8_j9_sanity.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_1715397905151/jdk_util_0/report/html/report.html
[2024-05-11T04:17:35.844Z] Results written to /home/jenkins/workspace/Test_openjdk8_j9_sanity.openjdk_ppc64le_linux_testList_0/aqa-tests/TKG/output_1715397905151/jdk_util_0/work
[2024-05-11T04:17:35.844Z] Error: Some tests failed or other problems occurred.
[2024-05-11T04:17:35.844Z] -----------------------------------
[2024-05-11T04:17:35.844Z] jdk_util_0_FAILED

50x grinder - passed