JVM调优之jstack找出最耗cpu的线程并定位代码

Posted 2020-09-29

一、jstack使用总结

分析java进程，cpu占用高的问题 {

1、找到cpu占用高的进程pid
在top中，按组合键： shift + h ，会按cpu使用从高到低排序
2、找到cpu占用高的线程pid
top -Hp cpu高的进程pid, shift +h 查找最高线程，显示线程
3、jstack 进程的pid | grep -A 线程pid的十六进制 #分析Java应用程序线程堆栈dump出来
printf "%x\\n" cpu高的线程pid ==》 得到十六进制
python hex(pid)

　 jstack pid | grep -A pid的十六进制 定位到代码 #分析Java应用程序线程堆栈dump出来

二、对于jstack日志，我们要着重关注如下关键信息
　　Deadlock：表示有死锁
　　Waiting on condition：等待某个资源或条件发生来唤醒自己。具体需要结合jstacktrace来分析，比如线程正在sleep，网络读写繁忙而等待
　　Blocked：阻塞
　　Waiting on monitor entry：在等待获取锁

　　如果说系统慢，那么要特别关注Blocked,Waiting on condition
　　如果说系统的cpu耗的高，那么肯定是线程执行有死循环，那么此时要关注下Runable状态。

三、具体案例

jstack可以定位到线程堆栈，根据堆栈信息我们可以定位到具体代码，所以它在JVM性能调优中使用得非常多。下面我们来一个实例找出某个Java进程中最耗费CPU的Java线程并定位堆栈信息，用到的命令有ps、top、printf、jstack、grep。

第一步先找出Java进程ID，服务器上的Java应用名称为mrf-center：

root@ubuntu:/# ps -ef | grep mrf-center | grep -v grep
root     21711     1  1 14:47 pts/3    00:02:10 java -jar mrf-center.jar

得到进程ID为21711，第二步找出该进程内最耗费CPU的线程，可以使用
1）ps -Lfp pid
2）ps -mp pid -o THREAD, tid, time
3）top -Hp pid
用第三个，输出如下：

TIME列就是各个Java线程耗费的CPU时间，CPU时间最长的是线程ID为21742的线程，用

printf "%x\\n" 21742

得到21742的十六进制值为54ee，下面会用到。

OK，下一步终于轮到jstack上场了，它用来输出进程21711的堆栈信息，然后根据线程ID的十六进制值grep，如下：

root@ubuntu:/# jstack 21711 | grep 54ee
"PollIntervalRetrySchedulerThread" prio=10 tid=0x00007f950043e000 nid=0x54ee in Object.wait()

可以看到CPU消耗在PollIntervalRetrySchedulerThread这个类的Object.wait()，我找了下我的代码，定位到下面的代码：

// Idle wait
getLog().info("Thread [" + getName() + "] is idle waiting...");
schedulerThreadState = PollTaskSchedulerThreadState.IdleWaiting;
long now = System.currentTimeMillis();
long waitTime = now + getIdleWaitTime();
long timeUntilContinue = waitTime - now;
synchronized(sigLock) {
  try {
    if(!halted.get()) {
      sigLock.wait(timeUntilContinue);
    }
  } 
  catch (InterruptedException ignore) {
  }
}

它是轮询任务的空闲等待代码，上面的sigLock.wait(timeUntilContinue)就对应了前面的Object.wait()。

 

参考：http://www.cnblogs.com/chengJAVA/p/5821218.html