java问题排查工具之一板斧jstack——使用 jstack 定位 java进程CPU过高的问题

工程师统元技术 2022年11月16日

0 收藏 729 点赞 2,664 浏览 1293 个字

jstack主要用来查看某个Java进程内的线程堆栈信息。语法格式如下：

jstack [option] pidjstack [option] executable corejstack [option] [server-id@]remote-hostname-or-ip

参数说明:

-l long listings，会打印出额外的锁信息，在发生死锁时可以用jstack -l pid来观察锁持有情况
-m mixed mode，不仅会输出Java堆栈信息，还会输出C/C++堆栈信息（比如Native方法）

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

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

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的线程，可以使用ps -Lfp pid或者ps -mp pid -o THREAD, tid, time或者top -Hp pid 
我们这里通过
top -Hp 21711 找出最消耗CPU的线程 取到PID 为 21742
TIME列就是各个Java线程耗费的CPU时间，CPU时间最长的是线程ID为21742的线程

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

printf "%x\n" 21742

然后使用jstack来输出进程的堆栈信息，然后根据grep线程十六进制id

jstack 21711 | grep 54ee
"PollIntervalRetrySchedulerThread" prio=10 tid=0x00007f950043e000 nid=0x54ee in Object.w

可以看到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) {
    }
}

OVER