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

sun/security/tools/jarsigner/AltProvider.java timed out intermittently

    Details

      Description

      sun/security/tools/jarsigner/AltProvider.java timed out intermittently, very rare though.

      #section:main
      ----------messages:(325/16628)----------
      command: main AltProvider
      reason: Assumed action based on file name: run main AltProvider
      Mode: agentvm
      Additional exports to unnamed modules from @modules: java.base/jdk.internal.misc
      Timeout signalled after 960 seconds
      Timeout information:
      Running jstack on process 8695
      2016-09-21 01:58:06
      Full thread dump Java HotSpot(TM) 64-Bit Server VM (9-ea+135 mixed mode):

      "Attach Listener" #10350 daemon prio=9 os_prio=0 tid=0x00007fad24001000 nid=0x5d5 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "Thread-10074" #10349 daemon prio=5 os_prio=0 tid=0x00007facc8392800 nid=0x1c0d runnable [0x00007fac6b9f8000]
         java.lang.Thread.State: RUNNABLE
      at java.io.FileInputStream.readBytes(java.base@9-ea/Native Method)
      at java.io.FileInputStream.read(java.base@9-ea/FileInputStream.java:255)
      at java.io.BufferedInputStream.read1(java.base@9-ea/BufferedInputStream.java:284)
      at java.io.BufferedInputStream.read(java.base@9-ea/BufferedInputStream.java:345)
      - locked <0x00000000f1dbb260> (a java.lang.ProcessImpl$ProcessPipeInputStream)
      at java.io.BufferedInputStream.fill(java.base@9-ea/BufferedInputStream.java:246)
      at java.io.BufferedInputStream.read1(java.base@9-ea/BufferedInputStream.java:286)
      at java.io.BufferedInputStream.read(java.base@9-ea/BufferedInputStream.java:345)
      - locked <0x00000000f1780800> (a java.io.BufferedInputStream)
      at java.io.FilterInputStream.read(java.base@9-ea/FilterInputStream.java:107)
      at jdk.test.lib.process.StreamPumper.run(StreamPumper.java:110)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:843)

      "Thread-10073" #10348 daemon prio=5 os_prio=0 tid=0x00007facc84fa800 nid=0x1c0c runnable [0x00007fac688c7000]
         java.lang.Thread.State: RUNNABLE
      at java.io.FileInputStream.readBytes(java.base@9-ea/Native Method)
      at java.io.FileInputStream.read(java.base@9-ea/FileInputStream.java:255)
      at java.io.BufferedInputStream.read1(java.base@9-ea/BufferedInputStream.java:284)
      at java.io.BufferedInputStream.read(java.base@9-ea/BufferedInputStream.java:345)
      - locked <0x00000000f1db91a0> (a java.lang.ProcessImpl$ProcessPipeInputStream)
      at java.io.BufferedInputStream.fill(java.base@9-ea/BufferedInputStream.java:246)
      at java.io.BufferedInputStream.read1(java.base@9-ea/BufferedInputStream.java:286)
      at java.io.BufferedInputStream.read(java.base@9-ea/BufferedInputStream.java:345)
      - locked <0x00000000f1700000> (a java.io.BufferedInputStream)
      at java.io.FilterInputStream.read(java.base@9-ea/FilterInputStream.java:107)
      at jdk.test.lib.process.StreamPumper.run(StreamPumper.java:110)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:843)

      "SameVMThread" #10317 prio=5 os_prio=0 tid=0x00007fae4071d800 nid=0x79f1 in Object.wait() [0x00007fac6b4f2000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <no object reference available>
      at java.lang.Object.wait(java.base@9-ea/Object.java:516)
      at java.lang.ProcessImpl.waitFor(java.base@9-ea/ProcessImpl.java:498)
      - waiting to re-lock in wait() <0x00000000f1db6ff8> (a java.lang.ProcessImpl)
      at jdk.test.lib.process.ProcessTools.getOutput(ProcessTools.java:101)
      at jdk.test.lib.process.OutputAnalyzer.<init>(OutputAnalyzer.java:46)
      at jdk.test.lib.process.ProcessTools.executeProcess(ProcessTools.java:437)
      at jdk.test.lib.process.ProcessTools.executeProcess(ProcessTools.java:465)
      at jdk.test.lib.process.ProcessTools.executeCommand(ProcessTools.java:515)
      at AltProvider.tool(AltProvider.java:159)
      at AltProvider.testJarsigner(AltProvider.java:143)
      at AltProvider.testBoth(AltProvider.java:129)
      at AltProvider.main(AltProvider.java:105)
      at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
      at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
      at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
      at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
      at com.sun.javatest.regtest.agent.MainActionHelper$SameVMRunnable.run(MainActionHelper.java:226)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:843)

      "process reaper" #10308 daemon prio=10 os_prio=0 tid=0x00007facc82a7800 nid=0x70db runnable [0x00007fad44ef8000]
         java.lang.Thread.State: RUNNABLE
      at java.lang.ProcessHandleImpl.waitForProcessExit0(java.base@9-ea/Native Method)
      at java.lang.ProcessHandleImpl.lambda$completion$2(java.base@9-ea/ProcessHandleImpl.java:133)
      at java.lang.ProcessHandleImpl$$Lambda$417/1076405054.run(java.base@9-ea/Unknown Source)
      at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@9-ea/ThreadPoolExecutor.java:1158)
      at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@9-ea/ThreadPoolExecutor.java:632)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:843)

      "Keep-Alive-SocketCleaner" #10208 daemon prio=8 os_prio=0 tid=0x00007facc0112000 nid=0x6c85 in Object.wait() [0x00007fac69fde000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <no object reference available>
      at sun.net.www.http.KeepAliveStreamCleaner.run(java.base@9-ea/KeepAliveStreamCleaner.java:101)
      - waiting to re-lock in wait() <0x00000000e13265c0> (a sun.net.www.http.KeepAliveStreamCleaner)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
      at jdk.internal.misc.InnocuousThread.run(java.base@9-ea/InnocuousThread.java:122)

      "pool-1-thread-1" #24 daemon prio=5 os_prio=0 tid=0x00007fae403e0000 nid=0x22f0 waiting on condition [0x00007fad45a48000]
         java.lang.Thread.State: TIMED_WAITING (parking)
      at jdk.internal.misc.Unsafe.park(java.base@9-ea/Native Method)
      - parking to wait for <0x00000000e06d9de0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
      at java.util.concurrent.locks.LockSupport.parkNanos(java.base@9-ea/LockSupport.java:232)
      at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(java.base@9-ea/AbstractQueuedSynchronizer.java:2104)
      at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@9-ea/ScheduledThreadPoolExecutor.java:1131)
      at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(java.base@9-ea/ScheduledThreadPoolExecutor.java:848)
      at java.util.concurrent.ThreadPoolExecutor.getTask(java.base@9-ea/ThreadPoolExecutor.java:1083)
      at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@9-ea/ThreadPoolExecutor.java:1143)
      at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@9-ea/ThreadPoolExecutor.java:632)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:843)

      "Service Thread" #23 daemon prio=9 os_prio=0 tid=0x00007fae403ac000 nid=0x22b2 runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "Common-Cleaner" #22 daemon prio=8 os_prio=0 tid=0x00007fae402f5000 nid=0x2299 in Object.wait() [0x00007fad45d4b000]
         java.lang.Thread.State: TIMED_WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <no object reference available>
      at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:151)
      - waiting to re-lock in wait() <0x00000000e02cb110> (a java.lang.ref.ReferenceQueue$Lock)
      at jdk.internal.ref.CleanerImpl.run(java.base@9-ea/CleanerImpl.java:148)
      at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
      at jdk.internal.misc.InnocuousThread.run(java.base@9-ea/InnocuousThread.java:122)

      "Sweeper thread" #21 daemon prio=9 os_prio=0 tid=0x00007fae402df800 nid=0x2296 runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C1 CompilerThread14" #20 daemon prio=9 os_prio=0 tid=0x00007fae402dd800 nid=0x2295 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C1 CompilerThread13" #19 daemon prio=9 os_prio=0 tid=0x00007fae402dc000 nid=0x2294 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C1 CompilerThread12" #18 daemon prio=9 os_prio=0 tid=0x00007fae402da000 nid=0x2293 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C1 CompilerThread11" #17 daemon prio=9 os_prio=0 tid=0x00007fae402d8000 nid=0x2292 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C1 CompilerThread10" #16 daemon prio=9 os_prio=0 tid=0x00007fae402d6000 nid=0x2291 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread9" #15 daemon prio=9 os_prio=0 tid=0x00007fae402d4000 nid=0x2290 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread8" #14 daemon prio=9 os_prio=0 tid=0x00007fae402d2000 nid=0x228f waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread7" #13 daemon prio=9 os_prio=0 tid=0x00007fae402d0000 nid=0x228e waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread6" #12 daemon prio=9 os_prio=0 tid=0x00007fae402ce000 nid=0x228d waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread5" #11 daemon prio=9 os_prio=0 tid=0x00007fae402cc000 nid=0x228c waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread4" #10 daemon prio=9 os_prio=0 tid=0x00007fae402ca000 nid=0x228b waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread3" #9 daemon prio=9 os_prio=0 tid=0x00007fae402c0000 nid=0x228a waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread2" #8 daemon prio=9 os_prio=0 tid=0x00007fae402be000 nid=0x2289 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread1" #7 daemon prio=9 os_prio=0 tid=0x00007fae402bc000 nid=0x2288 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

      "C2 CompilerThread0" #6 daemon prio=9 os_prio=0 tid=0x00007fae402b2000 nid=0x2287 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE
         No compile task

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

      "Reference Pending List Locker" #4 daemon prio=9 os_prio=0 tid=0x00007fae402a7800 nid=0x2285 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "Finalizer" #3 daemon prio=8 os_prio=0 tid=0x00007fae40291800 nid=0x224d in Object.wait() [0x00007fad47485000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <no object reference available>
      at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:151)
      - waiting to re-lock in wait() <0x00000000e02a4610> (a java.lang.ref.ReferenceQueue$Lock)
      at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:172)
      at java.lang.ref.Finalizer$FinalizerThread.run(java.base@9-ea/Finalizer.java:216)

      "Reference Handler" #2 daemon prio=10 os_prio=0 tid=0x00007fae4028d800 nid=0x224c in Object.wait() [0x00007fad47586000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <no object reference available>
      at java.lang.Object.wait(java.base@9-ea/Object.java:516)
      at java.lang.ref.Reference.tryHandlePending(java.base@9-ea/Reference.java:193)
      - waiting to re-lock in wait() <0x00000000e0643c60> (a java.lang.ref.Reference$Lock)
      at java.lang.ref.Reference$ReferenceHandler.run(java.base@9-ea/Reference.java:155)

      "main" #1 prio=5 os_prio=0 tid=0x00007fae4000f000 nid=0x21fa in Object.wait() [0x00007fae46780000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(java.base@9-ea/Native Method)
      - waiting on <no object reference available>
      at java.lang.Thread.join(java.base@9-ea/Thread.java:1352)
      - waiting to re-lock in wait() <0x00000000f2f9a620> (a java.lang.Thread)
      at java.lang.Thread.join(java.base@9-ea/Thread.java:1426)
      at com.sun.javatest.regtest.agent.MainActionHelper.runClass(MainActionHelper.java:130)
      at com.sun.javatest.regtest.agent.AgentServer.doMain(AgentServer.java:233)
      at com.sun.javatest.regtest.agent.AgentServer.run(AgentServer.java:182)
      at com.sun.javatest.regtest.agent.AgentServer.main(AgentServer.java:59)

      "VM Thread" os_prio=0 tid=0x00007fae40281800 nid=0x2247 runnable

      "GC Thread#0" os_prio=0 tid=0x00007fae40028000 nid=0x21fd runnable

      "GC Thread#1" os_prio=0 tid=0x00007fae40029800 nid=0x21fe runnable

      "GC Thread#2" os_prio=0 tid=0x00007fae4002b000 nid=0x21ff runnable

      "GC Thread#3" os_prio=0 tid=0x00007fae4002d000 nid=0x2200 runnable

      "GC Thread#4" os_prio=0 tid=0x00007fae4002e800 nid=0x2201 runnable

      "GC Thread#5" os_prio=0 tid=0x00007fae40030800 nid=0x2202 runnable

      "GC Thread#6" os_prio=0 tid=0x00007fae40032000 nid=0x2203 runnable

      "GC Thread#7" os_prio=0 tid=0x00007fae40034000 nid=0x2204 runnable

      "GC Thread#8" os_prio=0 tid=0x00007fae40035800 nid=0x2205 runnable

      "GC Thread#9" os_prio=0 tid=0x00007fae40037800 nid=0x2206 runnable

      "GC Thread#10" os_prio=0 tid=0x00007fae40039000 nid=0x2207 runnable

      "GC Thread#11" os_prio=0 tid=0x00007fae4003a800 nid=0x2208 runnable

      "GC Thread#12" os_prio=0 tid=0x00007fae4003c800 nid=0x2209 runnable

      "GC Thread#13" os_prio=0 tid=0x00007fae4003e000 nid=0x220a runnable

      "GC Thread#14" os_prio=0 tid=0x00007fae40040000 nid=0x220b runnable

      "GC Thread#15" os_prio=0 tid=0x00007fae40041800 nid=0x220c runnable

      "GC Thread#16" os_prio=0 tid=0x00007fae40043800 nid=0x220d runnable

      "GC Thread#17" os_prio=0 tid=0x00007fae40045000 nid=0x220e runnable

      "GC Thread#18" os_prio=0 tid=0x00007fae40046800 nid=0x220f runnable

      "GC Thread#19" os_prio=0 tid=0x00007fae40048800 nid=0x2210 runnable

      "GC Thread#20" os_prio=0 tid=0x00007fae4004a000 nid=0x2211 runnable

      "GC Thread#21" os_prio=0 tid=0x00007fae4004c000 nid=0x2212 runnable

      "GC Thread#22" os_prio=0 tid=0x00007fae4004d800 nid=0x2213 runnable

      "G1 Main Marker" os_prio=0 tid=0x00007fae40086800 nid=0x222c runnable

      "G1 Marker#0" os_prio=0 tid=0x00007fae40088800 nid=0x222d runnable

      "G1 Marker#1" os_prio=0 tid=0x00007fae4008a000 nid=0x222e runnable

      "G1 Marker#2" os_prio=0 tid=0x00007fae4008b800 nid=0x222f runnable

      "G1 Marker#3" os_prio=0 tid=0x00007fae4008d800 nid=0x2230 runnable

      "G1 Marker#4" os_prio=0 tid=0x00007fae4008f000 nid=0x2231 runnable

      "G1 Marker#5" os_prio=0 tid=0x00007fae40091000 nid=0x2232 runnable

      "G1 Refine#0" os_prio=0 tid=0x00007fae40077800 nid=0x222a runnable

      "G1 Refine#1" os_prio=0 tid=0x00007fae40075800 nid=0x2229 runnable

      "G1 Refine#2" os_prio=0 tid=0x00007fae40074000 nid=0x2228 runnable

      "G1 Refine#3" os_prio=0 tid=0x00007fae40072000 nid=0x2227 runnable

      "G1 Refine#4" os_prio=0 tid=0x00007fae40070000 nid=0x2226 runnable

      "G1 Refine#5" os_prio=0 tid=0x00007fae4006e800 nid=0x2225 runnable

      "G1 Refine#6" os_prio=0 tid=0x00007fae4006c800 nid=0x2224 runnable

      "G1 Refine#7" os_prio=0 tid=0x00007fae4006b000 nid=0x2223 runnable

      "G1 Refine#8" os_prio=0 tid=0x00007fae40069000 nid=0x2222 runnable

      "G1 Refine#9" os_prio=0 tid=0x00007fae40067800 nid=0x2221 runnable

      "G1 Refine#10" os_prio=0 tid=0x00007fae40065800 nid=0x2220 runnable

      "G1 Refine#11" os_prio=0 tid=0x00007fae40064000 nid=0x221f runnable

      "G1 Refine#12" os_prio=0 tid=0x00007fae40062000 nid=0x221e runnable

      "G1 Refine#13" os_prio=0 tid=0x00007fae40060800 nid=0x221d runnable

      "G1 Refine#14" os_prio=0 tid=0x00007fae4005e800 nid=0x221c runnable

      "G1 Refine#15" os_prio=0 tid=0x00007fae4005d000 nid=0x221b runnable

      "G1 Refine#16" os_prio=0 tid=0x00007fae4005b000 nid=0x221a runnable

      "G1 Refine#17" os_prio=0 tid=0x00007fae40059000 nid=0x2219 runnable

      "G1 Refine#18" os_prio=0 tid=0x00007fae40057800 nid=0x2218 runnable

      "G1 Refine#19" os_prio=0 tid=0x00007fae40055800 nid=0x2217 runnable

      "G1 Refine#20" os_prio=0 tid=0x00007fae40054000 nid=0x2216 runnable

      "G1 Refine#21" os_prio=0 tid=0x00007fae40052000 nid=0x2215 runnable

      "G1 Refine#22" os_prio=0 tid=0x00007fae40050800 nid=0x2214 runnable

      "G1 Young RemSet Sampling" os_prio=0 tid=0x00007fae40079000 nid=0x222b runnable

      "VM Periodic Task Thread" os_prio=0 tid=0x00007fae403af000 nid=0x22b3 waiting on condition

      JNI global references: 4078

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

        Activity

        Hide
        ggalimbe Gustavo Galimberti (Inactive) added a comment -
        We don't have enough information to go by as to why the test took longer and thus was timed out by the testing harness. In conjunction, this seems to be a one off issue seen during same binary runs. We are closing this issue as won't fix. Will only open similar report should we seen other incidents again. Then we might need to further instrument the test to gather more data.
        Show
        ggalimbe Gustavo Galimberti (Inactive) added a comment - We don't have enough information to go by as to why the test took longer and thus was timed out by the testing harness. In conjunction, this seems to be a one off issue seen during same binary runs. We are closing this issue as won't fix. Will only open similar report should we seen other incidents again. Then we might need to further instrument the test to gather more data.

          People

          • Assignee:
            tidu Tim Du
            Reporter:
            amlu Amy Lu
          • Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

            Dates

            • Created:
              Updated:
              Resolved: