Uploaded image for project: 'JDK'
  1. JDK
  2. JDK-8137289

java/util/logging/DrainFindDeadlockTest.java hangs

XMLWordPrintable

        jdk9 b84 VM PIT, java/util/logging/DrainFindDeadlockTest.java timed out twice: on linux-arm64 and solaris-x64

        command: main DrainFindDeadlockTest
        reason: User specified action: run main/othervm/timeout=10 DrainFindDeadlockTest
        Timeout information:
        Running jstack on process 20963
        2015-09-24 19:47:50
        Full thread dump Java HotSpot(TM) 64-Bit Server VM (1.9.0-internal-fastdebug-20150924184226.amurillo.jdk9-hs-2015-09--b00 compiled mode):

        "Attach Listener" #12 daemon prio=9 os_prio=0 tid=0x0000007f00001000 nid=0x5227 waiting on condition [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           JavaThread state: _thread_blocked
        Thread: 0x0000007f00001000 [0x5227] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked

        "MainThread" #11 prio=5 os_prio=0 tid=0x0000007f7c391000 nid=0x5206 waiting on condition [0x0000007f547b1000]
           java.lang.Thread.State: RUNNABLE
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c391000 [0x5206] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked
        at java.lang.invoke.ConstantCallSite.<init>(ConstantCallSite.java:43)
        at java.lang.invoke.InnerClassLambdaMetafactory.buildCallSite(InnerClassLambdaMetafactory.java:226)
        at java.lang.invoke.LambdaMetafactory.metafactory(LambdaMetafactory.java:304)
        at java.lang.invoke.LambdaForm$DMH/1009245154.invokeStatic_L6_L(LambdaForm$DMH)
        at java.lang.invoke.LambdaForm$BMH/2111528872.reinvoke(LambdaForm$BMH)
        at java.lang.invoke.LambdaForm$MH/2005257537.invoke_MT(LambdaForm$MH)
        at java.lang.invoke.CallSite.makeSite(CallSite.java:309)
        at java.lang.invoke.MethodHandleNatives.linkCallSiteImpl(MethodHandleNatives.java:248)
        at java.lang.invoke.MethodHandleNatives.linkCallSite(MethodHandleNatives.java:238)
        at java.util.stream.Collectors.mapMerger(Collectors.java:364)
        at java.util.stream.Collectors.toMap(Collectors.java:1429)
        at java.util.stream.Collectors.toMap(Collectors.java:1378)
        at java.lang.management.ManagementFactory$PlatformMBeanFinder.<clinit>(ManagementFactory.java:893)
        at java.lang.management.ManagementFactory.getPlatformMXBean(ManagementFactory.java:641)
        at java.lang.management.ManagementFactory.getThreadMXBean(ManagementFactory.java:344)
        at DrainFindDeadlockTest.<clinit>(DrainFindDeadlockTest.java:58)
        at java.lang.Class.forName0(Native Method)
        at java.lang.Class.forName(Class.java:287)
        at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:86)
        at java.lang.Thread.run(Thread.java:746)

        "Service Thread" #9 daemon prio=9 os_prio=0 tid=0x0000007f7c33f800 nid=0x5204 runnable [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c33f800 [0x5204] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked

        "Sweeper thread" #8 daemon prio=9 os_prio=0 tid=0x0000007f7c32f000 nid=0x5203 runnable [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c32f000 [0x5203] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked

        "C2 CompilerThread1" #7 daemon prio=9 os_prio=0 tid=0x0000007f7c32c800 nid=0x5202 waiting on condition [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c32c800 [0x5202] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked

        "C2 CompilerThread0" #6 daemon prio=9 os_prio=0 tid=0x0000007f7c32a000 nid=0x5201 runnable [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           JavaThread state: _thread_in_native
        Thread: 0x0000007f7c32a000 [0x5201] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_in_native

        "Signal Dispatcher" #5 daemon prio=9 os_prio=0 tid=0x0000007f7c328000 nid=0x5200 runnable [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c328000 [0x5200] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked

        "Surrogate Locker Thread (Concurrent GC)" #4 daemon prio=9 os_prio=0 tid=0x0000007f7c318800 nid=0x51ff waiting on condition [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c318800 [0x51ff] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked

        "Finalizer" #3 daemon prio=8 os_prio=0 tid=0x0000007f7c2d7000 nid=0x51fe in Object.wait() [0x0000007f54fb4000]
           java.lang.Thread.State: WAITING (on object monitor)
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c2d7000 [0x51fe] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00000006d4b0b4f0> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:150)
        - locked <0x00000006d4b0b4f0> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:171)
        at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:217)

        "Reference Handler" #2 daemon prio=10 os_prio=0 tid=0x0000007f7c2e2800 nid=0x51fd in Object.wait() [0x0000007f550b3000]
           java.lang.Thread.State: WAITING (on object monitor)
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c2e2800 [0x51fd] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00000006d4b06a78> (a java.lang.ref.Reference$Lock)
        at java.lang.Object.wait(Object.java:516)
        at java.lang.ref.Reference.tryHandlePending(Reference.java:193)
        - locked <0x00000006d4b06a78> (a java.lang.ref.Reference$Lock)
        at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:155)

        "main" #1 prio=5 os_prio=0 tid=0x0000007f7c012000 nid=0x51e7 in Object.wait() [0x0000007f839f5000]
           java.lang.Thread.State: WAITING (on object monitor)
           JavaThread state: _thread_blocked
        Thread: 0x0000007f7c012000 [0x51e7] State: _at_safepoint _has_called_back 0 _at_poll_safepoint 0
           JavaThread state: _thread_blocked
        at java.lang.Object.wait(Native Method)
        - waiting on <no object reference available>
        at java.lang.Thread.join(Thread.java:1251)
        - locked <0x00000006d4a1a098> (a java.lang.Thread)
        at java.lang.Thread.join(Thread.java:1325)
        at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:69)

        "VM Thread" os_prio=0 tid=0x0000007f7c2d5800 nid=0x51fc runnable

        "GC Thread#0" os_prio=0 tid=0x0000007f7c031000 nid=0x51e8 runnable

        "GC Thread#1" os_prio=0 tid=0x0000007f7c033000 nid=0x51e9 runnable

        "GC Thread#2" os_prio=0 tid=0x0000007f7c035000 nid=0x51ea runnable

        "GC Thread#3" os_prio=0 tid=0x0000007f7c037000 nid=0x51eb runnable

        "GC Thread#4" os_prio=0 tid=0x0000007f7c039000 nid=0x51ec runnable

        "GC Thread#5" os_prio=0 tid=0x0000007f7c03b000 nid=0x51ed runnable

        "GC Thread#6" os_prio=0 tid=0x0000007f7c03d800 nid=0x51ee runnable

        "GC Thread#7" os_prio=0 tid=0x0000007f7c03f800 nid=0x51ef runnable

        "G1 Main Marker" os_prio=0 tid=0x0000007f7c089800 nid=0x51f9 runnable

        "G1 Marker#0" os_prio=0 tid=0x0000007f7c08b800 nid=0x51fa runnable

        "G1 Marker#1" os_prio=0 tid=0x0000007f7c08d800 nid=0x51fb runnable

        "G1 Refine#0" os_prio=0 tid=0x0000007f7c053000 nid=0x51f8 runnable

        "G1 Refine#1" os_prio=0 tid=0x0000007f7c051000 nid=0x51f7 runnable

        "G1 Refine#2" os_prio=0 tid=0x0000007f7c04e800 nid=0x51f6 runnable

        "G1 Refine#3" os_prio=0 tid=0x0000007f7c04c800 nid=0x51f5 runnable

        "G1 Refine#4" os_prio=0 tid=0x0000007f7c04a800 nid=0x51f4 runnable

        "G1 Refine#5" os_prio=0 tid=0x0000007f7c048800 nid=0x51f3 runnable

        "G1 Refine#6" os_prio=0 tid=0x0000007f7c046800 nid=0x51f2 runnable

        "G1 Refine#7" os_prio=0 tid=0x0000007f7c044800 nid=0x51f1 runnable

        "G1 Refine#8" os_prio=0 tid=0x0000007f7c042000 nid=0x51f0 runnable

        "VM Periodic Task Thread" os_prio=0 tid=0x0000007f7c33e000 nid=0x5205 waiting on condition

        Compiler thread printing unimplemented.

        JNI global references: 344

        --- Timeout information end.
        elapsed time (seconds): 30.84
        ----------System.out:(1/35)----------
        Timeout signalled after 30 seconds
        ----------System.err:(0/0)----------
        ----------rerun:(24/1804)*----------
        HOME=/export/local/home/gtee \\
        JTREG_COF=false \\
        JTREG_HOME=/export/local/aurora/CommonData/jtreg_dir \\
        JTREG_NATIVEPATH=/export/local/aurora/sandbox/JTREG_NATIVEPATH_LIBRARY_PREPARED \\
        JTREG_NATIVEPATH_LIBRARY=/export/local/aurora/CommonData/JTREG_NATIVEPATH_LIBRARY \\
        JTREG_NATIVEPATH_LIBRARY_arl=aurora://jprt/2015-09-24-184226.amurillo.jdk9-hs-2015-09-24-snapshot/binaries/fastdebug-tests/linux-aarch64 \\
        LANG=C \\
        PATH=/bin:/usr/bin \\
        CLASSPATH=/export/local/aurora/CommonData/jtreg_dir/lib/javatest.jar:/export/local/aurora/CommonData/jtreg_dir/lib/jtreg.jar:/export/local/aurora/sandbox/results/workDir/classes/java/util/logging:/export/local/aurora/CommonData/j2se_jdk/jdk/test/java/util/logging \\
            /export/local/aurora/CommonData/jdk/bin/java \\
                -Dtest.src=/export/local/aurora/CommonData/j2se_jdk/jdk/test/java/util/logging \\
                -Dtest.src.path=/export/local/aurora/CommonData/j2se_jdk/jdk/test/java/util/logging \\
                -Dtest.classes=/export/local/aurora/sandbox/results/workDir/classes/java/util/logging \\
                -Dtest.class.path=/export/local/aurora/sandbox/results/workDir/classes/java/util/logging \\
                -Dtest.vm.opts= \\
                -Dtest.tool.vm.opts= \\
                -Dtest.compiler.opts= \\
                -Dtest.java.opts='-Xcomp -server -Xcomp' \\
                -Dtest.jdk=/export/local/aurora/CommonData/jdk \\
                -Dcompile.jdk=/export/local/aurora/CommonData/jdk \\
                -Dtest.timeout.factor=3.0 \\
                -Dtest.nativepath=/export/local/aurora/sandbox/JTREG_NATIVEPATH_LIBRARY_PREPARED \\
                -Xcomp -server -Xcomp -Djava.library.path=/export/local/aurora/sandbox/JTREG_NATIVEPATH_LIBRARY_PREPARED \\
                com.sun.javatest.regtest.agent.MainWrapper /export/local/aurora/sandbox/results/workDir/classes/java/util/logging/DrainFindDeadlockTest.jta
        result: Error. Program `/export/local/aurora/CommonData/jdk/bin/java' timed out!

              dfuchs Daniel Fuchs
              dfazunen Dmitry Fazunenko (Inactive)
              Votes:
              0 Vote for this issue
              Watchers:
              5 Start watching this issue

                Created:
                Updated:
                Resolved: