记一次 JVM CPU 使用率飙高问题的排查过程

红太狼 2023-10-02 21:44 43阅读 0赞

### 文章目录 ###

*  问题现象
 *  排查过程

### 问题现象 ###

首先，我们一起看看通过 VisualVM 监控到的机器 CPU 使用率图：

![ba053f86033cea25a444c34bca6e416f.png][]

如上图所示，在 下午3:45 分之前，CPU 的使用率明显飙高，最高飙到近 100%，为什么会出现这样的现象呢？

### 排查过程 ###

**Step 1：**使用top命令，查询资源占用情况：

![50b41f5a1e8618319c992bbcb6cb24ec.png][]

如上图所示，显示了服务器当前的资源占用情况，其中PID为5456的进程占用的资源最多。

在这里，我们也使用`top -p PID`命令，查询指定PID的资源占用情况：

![cc51042973b2d849503fef01a32086c3.png][]

**Step 2：**使用`ps -mp PID -o THREAD,tid,time`命令，查询该进程的线程情况：

![52fd2df93179126f3985c6ffed683601.png][]

在这里，我们也使用`ps -mp PID -o THREAD,tid,time | sort -rn`命令，将该进程下的线程按资源使用情况倒序展示：

![d788a5c3b1e0b8956ead062473e31e4d.png][]

**Step 3：**使用`printf "%x\n" PID`命令，将PID转为十六进制的TID：

![e09294cdc178c22af7df308133eaf299.png][]

在这里，我们之所以需要将PID转为十六进制是因为在堆栈信息中，PID是以十六进制形式存在的。

**Step 4：**使用`jstack PID | grep TID -A 100`命令，查询堆栈信息：

![c0854601cc19dd291463e040ae6373e4.png][]

如上图所示，显示该进程下多个线程均处于`TIMED_WAITING`状态。

虽然线程处于`WAITING`或者`TIMED_WAITING`状态都不会消耗 CPU，但是线程频繁的挂起和唤醒却会消耗 CPU，而且代价高昂。

而上面之所以会出现 CPU 使用率飙高的情况，则是因为有人在做压测。

特别地，在 mock 底层接口的时候，使用了类似`TimeUnit.SECONDS.sleep(1)`这样的语句。

至于为何在 下午3:45 分之后，CPU 的使用率降下来了，则是因为停止了压测。

除此之外，我们还可以使用jinfo和jstat命令来查询 Java 进程的启动参数以及 GC 情况：

**使用`jinfo PID`命令，查询启动参数：**

![e9f89463fdb0b3755faf6fc9ddbacd03.png][]

如上图所示，使用该命令我们主要是为了查询启动参数，如初始化堆大小、垃圾回收器等配置。

**使用`jstat -gcutil PID 1000`命令，查询 GC 情况：**

![abaf13293257d83c04f620c3d515aeb9.png][]

如上图所示，显示了PID为20567的 Java 进程每秒的 GC 情况，其中1000表示 GC 状态的更新频率，单位为毫秒。

[ba053f86033cea25a444c34bca6e416f.png]: https://img-blog.csdnimg.cn/img_convert/ba053f86033cea25a444c34bca6e416f.png
[50b41f5a1e8618319c992bbcb6cb24ec.png]: https://img-blog.csdnimg.cn/img_convert/50b41f5a1e8618319c992bbcb6cb24ec.png
[cc51042973b2d849503fef01a32086c3.png]: https://img-blog.csdnimg.cn/img_convert/cc51042973b2d849503fef01a32086c3.png
[52fd2df93179126f3985c6ffed683601.png]: https://img-blog.csdnimg.cn/img_convert/52fd2df93179126f3985c6ffed683601.png
[d788a5c3b1e0b8956ead062473e31e4d.png]: https://img-blog.csdnimg.cn/img_convert/d788a5c3b1e0b8956ead062473e31e4d.png
[e09294cdc178c22af7df308133eaf299.png]: https://img-blog.csdnimg.cn/img_convert/e09294cdc178c22af7df308133eaf299.png
[c0854601cc19dd291463e040ae6373e4.png]: https://img-blog.csdnimg.cn/img_convert/c0854601cc19dd291463e040ae6373e4.png
[e9f89463fdb0b3755faf6fc9ddbacd03.png]: https://img-blog.csdnimg.cn/img_convert/e9f89463fdb0b3755faf6fc9ddbacd03.png
[abaf13293257d83c04f620c3d515aeb9.png]: https://img-blog.csdnimg.cn/img_convert/abaf13293257d83c04f620c3d515aeb9.png