JDK-8130387 : sun/tools/jstatd/TestJstatdPort.java timed out intermittently
  • Type: Bug
  • Component: core-svc
  • Sub-Component: tools
  • Affected Version: 9
  • Priority: P5
  • Status: Closed
  • Resolution: Duplicate
  • Submitted: 2015-07-03
  • Updated: 2023-12-14
  • Resolved: 2015-07-17
The Version table provides details related to the release that this issue/RFE will be addressed.

Unresolved : Release in which this issue/RFE will be addressed.
Resolved: Release in which this issue/RFE has been resolved.
Fixed : Release in which this issue/RFE has been fixed. The release containing this fix may be available for download as an Early Access Release or a General Availability Release.

To download the current JDK release, click here.
Other
tbdResolved
Related Reports
Duplicate :  
JDK-8081569 - sun/tools/jstatd/TestJstatd* tests timed out intermittently
Description
sun/tools/jstatd/TestJstatdPort.java timed out intermittently.

#section:main
----------messages:(199/10735)----------
command: main TestJstatdPort
reason: User specified action: run main/timeout=60 TestJstatdPort 
Timeout signalled after 480 seconds
Timeout information:
Running jstack on process 14029
2015-06-24 12:25:46
Full thread dump Java HotSpot(TM) 64-Bit Server VM (1.9.0-ea-b69 mixed mode):

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

"Jstatd-Thread" #567 prio=5 os_prio=0 tid=0x00007f3c4c17d000 nid=0x649f waiting on condition [0x00007f3c9ce92000]
   java.lang.Thread.State: WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0x00000000f86761c0> (a java.util.concurrent.CountDownLatch$Sync)
	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:997)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:231)
	at jdk.testlibrary.ProcessTools.startProcess(ProcessTools.java:199)
	at jdk.testlibrary.ProcessTools.startProcess(ProcessTools.java:127)
	at jdk.testlibrary.ProcessTools.startProcess(ProcessTools.java:248)
	at jdk.testlibrary.ProcessThread$ProcessRunnable.xrun(ProcessThread.java:151)
	at jdk.testlibrary.XRun.run(XRun.java:40)
	at java.lang.Thread.run(Thread.java:745)
	at jdk.testlibrary.TestThread.run(TestThread.java:126)

"SameVMThread" #566 prio=5 os_prio=0 tid=0x00007f3d28373000 nid=0x6461 waiting on condition [0x00007f3c9c48f000]
   java.lang.Thread.State: WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0x00000000f861ab60> (a java.util.concurrent.CountDownLatch$Sync)
	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:997)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:231)
	at jdk.testlibrary.ProcessThread$ProcessRunnable.getProcess(ProcessThread.java:209)
	at jdk.testlibrary.ProcessThread$ProcessRunnable.getPid(ProcessThread.java:198)
	at jdk.testlibrary.ProcessThread.getPid(ProcessThread.java:101)
	at JstatdTest.waitOnTool(JstatdTest.java:86)
	at JstatdTest.tryToSetupJstatdProcess(JstatdTest.java:282)
	at JstatdTest.doTest(JstatdTest.java:322)
	at TestJstatdPort.main(TestJstatdPort.java:37)
	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" #22 daemon prio=5 os_prio=0 tid=0x00007f3d28180000 nid=0x371c waiting on condition [0x00007f3c9d931000]
   java.lang.Thread.State: TIMED_WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0x00000000e05d1898> (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" #21 daemon prio=9 os_prio=0 tid=0x00007f3d280fa800 nid=0x36fe runnable [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Sweeper thread" #20 daemon prio=9 os_prio=0 tid=0x00007f3d280f6000 nid=0x36fd runnable [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C1 CompilerThread14" #19 daemon prio=9 os_prio=0 tid=0x00007f3d280f4000 nid=0x36fc waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C1 CompilerThread13" #18 daemon prio=9 os_prio=0 tid=0x00007f3d280f2000 nid=0x36fb waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C1 CompilerThread12" #17 daemon prio=9 os_prio=0 tid=0x00007f3d280f0000 nid=0x36fa waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C1 CompilerThread11" #16 daemon prio=9 os_prio=0 tid=0x00007f3d280ee000 nid=0x36f9 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C1 CompilerThread10" #15 daemon prio=9 os_prio=0 tid=0x00007f3d280ec000 nid=0x36f8 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread9" #14 daemon prio=9 os_prio=0 tid=0x00007f3d280ea000 nid=0x36f7 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread8" #13 daemon prio=9 os_prio=0 tid=0x00007f3d280e8000 nid=0x36f6 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread7" #12 daemon prio=9 os_prio=0 tid=0x00007f3d280e6000 nid=0x36f5 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread6" #11 daemon prio=9 os_prio=0 tid=0x00007f3d280e3800 nid=0x36f4 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread5" #10 daemon prio=9 os_prio=0 tid=0x00007f3d280e1800 nid=0x36f3 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread4" #9 daemon prio=9 os_prio=0 tid=0x00007f3d280d7800 nid=0x36f2 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread3" #8 daemon prio=9 os_prio=0 tid=0x00007f3d280d5000 nid=0x36f1 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread2" #7 daemon prio=9 os_prio=0 tid=0x00007f3d280d3800 nid=0x36f0 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread1" #6 daemon prio=9 os_prio=0 tid=0x00007f3d280d0800 nid=0x36ef waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" #5 daemon prio=9 os_prio=0 tid=0x00007f3d280ce800 nid=0x36ee waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" #4 daemon prio=9 os_prio=0 tid=0x00007f3d280cc800 nid=0x36ed runnable [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Finalizer" #3 daemon prio=8 os_prio=0 tid=0x00007f3d280a5800 nid=0x36ec in Object.wait() [0x00007f3cb93e5000]
   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 <0x00000000e05e5820> (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=0x00007f3d280a3800 nid=0x36eb in Object.wait() [0x00007f3cb94e6000]
   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 <0x00000000e05d2e98> (a java.lang.ref.Reference$Lock)
	at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:154)

"main" #1 prio=5 os_prio=0 tid=0x00007f3d28009800 nid=0x36d1 in Object.wait() [0x00007f3d2d2ff000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	at java.lang.Thread.join(Thread.java:1249)
	- locked <0x00000000f84bfb90> (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=0x00007f3d28090800 nid=0x36ea runnable 

"ParGC Thread#0" os_prio=0 tid=0x00007f3d2801e000 nid=0x36d2 runnable 

"ParGC Thread#1" os_prio=0 tid=0x00007f3d28020000 nid=0x36d3 runnable 

"ParGC Thread#2" os_prio=0 tid=0x00007f3d28021800 nid=0x36d4 runnable 

"ParGC Thread#3" os_prio=0 tid=0x00007f3d28023800 nid=0x36d5 runnable 

"ParGC Thread#4" os_prio=0 tid=0x00007f3d28025000 nid=0x36d6 runnable 

"ParGC Thread#5" os_prio=0 tid=0x00007f3d28027000 nid=0x36d7 runnable 

"ParGC Thread#6" os_prio=0 tid=0x00007f3d28028800 nid=0x36d8 runnable 

"ParGC Thread#7" os_prio=0 tid=0x00007f3d2802a800 nid=0x36d9 runnable 

"ParGC Thread#8" os_prio=0 tid=0x00007f3d2802c000 nid=0x36da runnable 

"ParGC Thread#9" os_prio=0 tid=0x00007f3d2802e000 nid=0x36db runnable 

"ParGC Thread#10" os_prio=0 tid=0x00007f3d2802f800 nid=0x36dc runnable 

"ParGC Thread#11" os_prio=0 tid=0x00007f3d28031800 nid=0x36dd runnable 

"ParGC Thread#12" os_prio=0 tid=0x00007f3d28033000 nid=0x36de runnable 

"ParGC Thread#13" os_prio=0 tid=0x00007f3d28035000 nid=0x36df runnable 

"ParGC Thread#14" os_prio=0 tid=0x00007f3d28036800 nid=0x36e0 runnable 

"ParGC Thread#15" os_prio=0 tid=0x00007f3d28038800 nid=0x36e1 runnable 

"ParGC Thread#16" os_prio=0 tid=0x00007f3d2803a000 nid=0x36e2 runnable 

"ParGC Thread#17" os_prio=0 tid=0x00007f3d2803c000 nid=0x36e3 runnable 

"ParGC Thread#18" os_prio=0 tid=0x00007f3d2803d800 nid=0x36e4 runnable 

"ParGC Thread#19" os_prio=0 tid=0x00007f3d2803f800 nid=0x36e5 runnable 

"ParGC Thread#20" os_prio=0 tid=0x00007f3d28041000 nid=0x36e6 runnable 

"ParGC Thread#21" os_prio=0 tid=0x00007f3d28043000 nid=0x36e7 runnable 

"ParGC Thread#22" os_prio=0 tid=0x00007f3d28044800 nid=0x36e8 runnable 

"VM Periodic Task Thread" os_prio=0 tid=0x00007f3d280fd000 nid=0x36ff waiting on condition 

JNI global references: 2784

--- Timeout information end.
elapsed time (seconds): 482.976
Comments
Two other sun/tools/jstatd/TestJstatd tests also found timed out intermittently, most likely the same root cause. Filed JDK-8081569 for all three tests.
17-07-2015
ILW = LLL = P5 Impact = Low-> happening only in same binary run Likelihood = Low -> 1 in 1426 runs Workaround = Low -> restarting the test
07-07-2015