前言:本文主要介绍排查分析Java应用log4j2+kafka日志输出导致cpu飙到200%问题的过程。
备注:因为排查问题时没有记录到对应每个步骤的截图,所以下面部分图片是解决问题后,在正常的机器环境下截取的。
1、使用 top 命令,查看占用cpu高的进程pid。出现问题时,可以看到%CPU列显示大于100%。PID为进程ID。
2、使用 ps -mp PID -o THREAD,tid,time | sort -rn 命令,查找进程对应的线程ID,并查找出对应的占用CPU时间。当时故障现场是存在大量线程占用CPU时间为1分钟左右。这里注意,大量线程,并且阻塞时间都差不多,根据经验,可以往阻塞时间方向去思考问题。因为CPU占用过高,最经典的是两种原因:线程死循环和阻塞。因为这里是大量线程出现问题,并且时间都是1分钟左右,时间比较短,所以猜测是阻塞。如果占用时间很长,例如达到30分钟以上,就很大可能是线程死循环导致。
3、使用 printf "%x\n" TID 命令,将十进制线程ID转换为十六进程,因为在线程堆栈日志中,记录的线程ID为十六进制。