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

sun/tools/jmap/BasicJMapTest.java timed out intermittently

    Details

    • Subcomponent:
    • CPU:
      sparc
    • OS:
      solaris

      Description

      This issue was reported and resolved in JDK-8081292 by increasing timeout. But it happens again on Solaris sparc 11.


      See the test output:
      command: main BasicJMapTest
      reason: User specified action: run main/timeout=240 BasicJMapTest
      Timeout signalled after 960 seconds
      Timeout information:
      Running jstack on process 9608
      2015-07-06 11:11:55
      Full thread dump Java HotSpot(TM) 64-Bit Server VM (1.9.0-ea-langtools-nightly-h2957-20150705-b72 mixed mode):

      "Attach Listener" #147 daemon prio=9 os_prio=64 tid=0x0000000100f23000 nid=0x9c waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "SameVMThread" #144 prio=5 os_prio=64 tid=0x0000000101164000 nid=0x99 runnable [0xffffffff601fd000]
         java.lang.Thread.State: RUNNABLE
      at java.io.RandomAccessFile.read0(Native Method)
      at java.io.RandomAccessFile.read(RandomAccessFile.java:348)
      at java.io.RandomAccessFile.readInt(RandomAccessFile.java:809)
      at jdk.test.lib.hprof.parser.FileReadBuffer.getInt(FileReadBuffer.java:77)
      - locked <0x00000007a1600210> (a jdk.test.lib.hprof.parser.FileReadBuffer)
      at jdk.test.lib.hprof.model.JavaObject.readValue(JavaObject.java:227)
      at jdk.test.lib.hprof.model.JavaLazyReadObject.getValue(JavaLazyReadObject.java:72)
      at jdk.test.lib.hprof.model.JavaObject.getFields(JavaObject.java:117)
      at jdk.test.lib.hprof.model.JavaObject.visitReferencedObjects(JavaObject.java:142)
      at jdk.test.lib.hprof.model.Snapshot.calculateReferencesToObjects(Snapshot.java:325)
      at jdk.test.lib.hprof.model.Snapshot.resolve(Snapshot.java:293)
      at jdk.test.lib.hprof.HprofParser.parse(HprofParser.java:87)
      at jdk.test.lib.hprof.HprofParser.parse(HprofParser.java:54)
      at BasicJMapTest.verifyDumpFile(BasicJMapTest.java:98)
      at BasicJMapTest.dump(BasicJMapTest.java:91)
      at BasicJMapTest.testDumpLive(BasicJMapTest.java:75)
      at BasicJMapTest.main(BasicJMapTest.java:57)
      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)

      "RMI Scheduler(0)" #42 daemon prio=5 os_prio=64 tid=0x0000000100b63800 nid=0x33 waiting on condition [0xffffffff60ffe000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000007a01a7e38> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
      at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
      at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
      at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081)
      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)

      "pool-1-thread-1" #10 daemon prio=5 os_prio=64 tid=0x0000000100580000 nid=0x14 waiting on condition [0xffffffff61afe000]
         java.lang.Thread.State: TIMED_WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000007a00faf90> (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=64 tid=0x00000001003a6800 nid=0x12 runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "Sweeper thread" #8 daemon prio=9 os_prio=64 tid=0x0000000100346800 nid=0x11 runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C1 CompilerThread1" #7 daemon prio=9 os_prio=64 tid=0x0000000100344800 nid=0x10 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread0" #6 daemon prio=9 os_prio=64 tid=0x0000000100342800 nid=0xf waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "Signal Dispatcher" #5 daemon prio=9 os_prio=64 tid=0x000000010033e800 nid=0xe runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

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

      "Finalizer" #3 daemon prio=8 os_prio=64 tid=0x000000010030e800 nid=0xc in Object.wait() [0xffffffff648fe000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)
      - waiting on <0x00000007a00fbae8> (a java.lang.ref.ReferenceQueue$Lock)
      at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
      - locked <0x00000007a00fbae8> (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=64 tid=0x0000000100304800 nid=0xb in Object.wait() [0xffffffff77eff000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)
      - waiting on <0x00000007a00fbca0> (a java.lang.ref.Reference$Lock)
      at java.lang.Object.wait(Object.java:508)
      at java.lang.ref.Reference.tryHandlePending(Reference.java:192)
      - locked <0x00000007a00fbca0> (a java.lang.ref.Reference$Lock)
      at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:154)

      "main" #1 prio=5 os_prio=64 tid=0x0000000100112000 nid=0x2 in Object.wait() [0xffffffff7d3fe000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)
      - waiting on <0x00000007a12b22b8> (a java.lang.Thread)
      at java.lang.Thread.join(Thread.java:1249)
      - locked <0x00000007a12b22b8> (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=64 tid=0x00000001002f2800 nid=0xa runnable

      "GC Thread#0" os_prio=64 tid=0x000000010018c800 nid=0x3 runnable

      "GC Thread#1" os_prio=64 tid=0x0000000100181800 nid=0x4 runnable

      "G1 Main Marker" os_prio=64 tid=0x0000000100168000 nid=0x8 runnable

      "G1 Marker#0" os_prio=64 tid=0x0000000100169000 nid=0x9 runnable

      "G1 Refine#0" os_prio=64 tid=0x0000000100177800 nid=0x7 runnable

      "G1 Refine#1" os_prio=64 tid=0x0000000100176000 nid=0x6 runnable

      "G1 Refine#2" os_prio=64 tid=0x0000000100183800 nid=0x5 runnable

      "VM Periodic Task Thread" os_prio=64 tid=0x00000001003a8800 nid=0x13 waiting on condition

      JNI global references: 360

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

      RULE sun/tools/jmap/BasicJMapTest.java Timeout none

        Issue Links

          Activity

          Hide
          goetz Goetz Lindenmaier added a comment -
          I opened this bug as a replacement of JDK-8130653 https://bugs.openjdk.java.net/browse/JDK-8130653 which was closed accidentially.
          Show
          goetz Goetz Lindenmaier added a comment - I opened this bug as a replacement of JDK-8130653 https://bugs.openjdk.java.net/browse/JDK-8130653 which was closed accidentially.
          Hide
          jbachorik Jaroslav BachorĂ­k added a comment -
          ILW = LLL = P5

          Impact = Low-> happens in nightly; only one configuration (solaris sparc)
          Likelihood = Low -> happened just once so far
          Workaround = Low -> restarting the test
          Show
          jbachorik Jaroslav BachorĂ­k added a comment - ILW = LLL = P5 Impact = Low-> happens in nightly; only one configuration (solaris sparc) Likelihood = Low -> happened just once so far Workaround = Low -> restarting the test

            People

            • Assignee:
              Unassigned
              Reporter:
              goetz Goetz Lindenmaier
            • Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

              Dates

              • Created:
                Updated: