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

java/util/logging/LoggingDeadlock2.java times out

XMLWordPrintable

        java/util/logging/LoggingDeadlock2.java times out, intermittently

        #section:main
        ----------messages:(146/7674)----------
        command: main LoggingDeadlock2
        reason: User specified action: run main LoggingDeadlock2
        Timeout signalled after 480 seconds
        Timeout information:
        Running jstack on process 25413
        2015-07-01 20:01:36
        Full thread dump Java HotSpot(TM) Server VM (1.9.0-ea-langtools-nightly-h2936-20150701-b71 mixed mode):

        "Attach Listener" #31609 daemon prio=9 os_prio=0 tid=0x08f00800 nid=0xe83 runnable [0x00000000]
           java.lang.Thread.State: RUNNABLE

        "Thread-30069" #31608 prio=5 os_prio=0 tid=0x08be5000 nid=0xd29 runnable [0x7fa20000]
           java.lang.Thread.State: RUNNABLE
        at java.io.FileInputStream.readBytes(Native Method)
        at java.io.FileInputStream.read(FileInputStream.java:255)
        at java.io.BufferedInputStream.read1(BufferedInputStream.java:284)
        at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
        - locked <0x8b3da0a8> (a java.lang.ProcessImpl$ProcessPipeInputStream)
        at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:284)
        at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:326)
        at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:178)
        - locked <0x8b200000> (a java.io.InputStreamReader)
        at java.io.InputStreamReader.read(InputStreamReader.java:185)
        at java.io.Reader.read(Reader.java:140)
        at LoggingDeadlock2$StreamAccumulator.run(LoggingDeadlock2.java:187)

        "Thread-30068" #31607 prio=5 os_prio=0 tid=0x09266400 nid=0xd28 runnable [0x808fe000]
           java.lang.Thread.State: RUNNABLE
        at java.io.FileInputStream.readBytes(Native Method)
        at java.io.FileInputStream.read(FileInputStream.java:255)
        at java.io.BufferedInputStream.read1(BufferedInputStream.java:284)
        at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
        - locked <0x8b3d8008> (a java.lang.ProcessImpl$ProcessPipeInputStream)
        at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:284)
        at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:326)
        at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:178)
        - locked <0x8b3ebd88> (a java.io.InputStreamReader)
        at java.io.InputStreamReader.read(InputStreamReader.java:185)
        at java.io.Reader.read(Reader.java:140)
        at LoggingDeadlock2$StreamAccumulator.run(LoggingDeadlock2.java:187)

        "process reaper" #31606 daemon prio=10 os_prio=0 tid=0x08e83000 nid=0xd27 runnable [0x7fac2000]
           java.lang.Thread.State: RUNNABLE
        at java.lang.ProcessHandleImpl.waitForProcessExit0(Native Method)
        at java.lang.ProcessHandleImpl.lambda$completion$2(ProcessHandleImpl.java:113)
        at java.lang.ProcessHandleImpl$$Lambda$350/13841088.run(Unknown Source)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)

        "SameVMThread" #31605 prio=5 os_prio=0 tid=0x08bb5c00 nid=0xd25 in Object.wait() [0x8034d000]
           java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:508)
        at java.lang.ProcessImpl.waitFor(ProcessImpl.java:463)
        - locked <0x8b3d5e48> (a java.lang.ProcessImpl)
        at LoggingDeadlock2.run(LoggingDeadlock2.java:214)
        at LoggingDeadlock2.realMain(LoggingDeadlock2.java:70)
        at LoggingDeadlock2.main(LoggingDeadlock2.java:240)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:502)
        at com.sun.javatest.regtest.agent.MainActionHelper$SameVMRunnable.run(MainActionHelper.java:218)
        at java.lang.Thread.run(Thread.java:745)

        "pool-1-thread-1" #10 daemon prio=5 os_prio=0 tid=0x84f4ac00 nid=0x635b waiting on condition [0x84d28000]
           java.lang.Thread.State: TIMED_WAITING (parking)
        at sun.misc.Unsafe.park(Native Method)
        - parking to wait for <0x87034168> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
        at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
        at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)

        "Service Thread" #9 daemon prio=9 os_prio=0 tid=0xa74b0000 nid=0x6359 runnable [0x00000000]
           java.lang.Thread.State: RUNNABLE

        "Sweeper thread" #8 daemon prio=9 os_prio=0 tid=0xa74ac000 nid=0x6358 runnable [0x00000000]
           java.lang.Thread.State: RUNNABLE

        "C1 CompilerThread1" #7 daemon prio=9 os_prio=0 tid=0xa74aa800 nid=0x6357 waiting on condition [0x00000000]
           java.lang.Thread.State: RUNNABLE

        "C2 CompilerThread0" #6 daemon prio=9 os_prio=0 tid=0xa74a9000 nid=0x6356 waiting on condition [0x00000000]
           java.lang.Thread.State: RUNNABLE

        "Signal Dispatcher" #5 daemon prio=9 os_prio=0 tid=0xa74a7800 nid=0x6355 runnable [0x00000000]
           java.lang.Thread.State: RUNNABLE

        "Surrogate Locker Thread (Concurrent GC)" #4 daemon prio=9 os_prio=0 tid=0xa74a4000 nid=0x6354 waiting on condition [0x00000000]
           java.lang.Thread.State: RUNNABLE

        "Finalizer" #3 daemon prio=8 os_prio=0 tid=0xa7496400 nid=0x6353 in Object.wait() [0x8561d000]
           java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
        - locked <0x87034cb8> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
        at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:213)

        "Reference Handler" #2 daemon prio=10 os_prio=0 tid=0xa7494c00 nid=0x6352 in Object.wait() [0x8566e000]
           java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:508)
        at java.lang.ref.Reference.tryHandlePending(Reference.java:192)
        - locked <0x87034e58> (a java.lang.ref.Reference$Lock)
        at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:154)

        "main" #1 prio=5 os_prio=0 tid=0x0879c000 nid=0x6349 in Object.wait() [0xb6834000]
           java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        at java.lang.Thread.join(Thread.java:1249)
        - locked <0x8b3d2798> (a java.lang.Thread)
        at java.lang.Thread.join(Thread.java:1323)
        at com.sun.javatest.regtest.agent.MainActionHelper.runClass(MainActionHelper.java:127)
        at com.sun.javatest.regtest.agent.AgentServer.doMain(AgentServer.java:230)
        at com.sun.javatest.regtest.agent.AgentServer.run(AgentServer.java:180)
        at com.sun.javatest.regtest.agent.AgentServer.main(AgentServer.java:57)

        "VM Thread" os_prio=0 tid=0xa7487000 nid=0x6351 runnable

        "GC Thread#0" os_prio=0 tid=0x087a6800 nid=0x634a runnable

        "GC Thread#1" os_prio=0 tid=0x087a7c00 nid=0x634b runnable

        "G1 Main Marker" os_prio=0 tid=0x087d4800 nid=0x634f runnable

        "G1 Marker#0" os_prio=0 tid=0x087d6000 nid=0x6350 runnable

        "G1 Refine#0" os_prio=0 tid=0x087cc000 nid=0x634e runnable

        "G1 Refine#1" os_prio=0 tid=0x087ca800 nid=0x634d runnable

        "G1 Refine#2" os_prio=0 tid=0x087c9400 nid=0x634c runnable

        "VM Periodic Task Thread" os_prio=0 tid=0xa74b1c00 nid=0x635a waiting on condition

        JNI global references: 413

        --- Timeout information end.
        elapsed time (seconds): 480.779

              dfuchs Daniel Fuchs
              amlu Amy Lu (Inactive)
              Votes:
              0 Vote for this issue
              Watchers:
              5 Start watching this issue

                Created:
                Updated:
                Resolved: