java調優1：找出Java進程中大量消耗CPU

問題分析：
1，程序屬於CPU密集型，和開發溝通過，排除此類情況。
2，程序代碼有問題，出現死循環，可能性極大。

問題解決：
1，開發那邊無法排查代碼某個模塊有問題，從日志上也無法分析得出。
2，記得原來通過strace跟蹤的方法解決了一台PHP服務器CPU占用高的問題，但是通過這種方法無效，經過google搜索，發現可以通過下面的方法進行解決，那就嘗試下吧。

解決過程：
1，根據top命令，發現PID為2633的Java進程占用CPU高達300%，出現故障。
2，找到該進程后，如何定位具體線程或代碼呢，首先顯示線程列表,並按照CPU占用高的線程排序：
[root@localhost logs]# ps -mp 2633 -o THREAD,tid,time | sort -rn
顯示結果如下：
USER     %CPU PRI SCNT WCHAN  USER SYSTEM   TID     TIME
root     10.5  19    - -         -      -  3626 00:12:48
root     10.1  19    - -         -      -  3593 00:12:16
找到了耗時最高的線程3626，占用CPU時間有12分鍾了！
將需要的線程ID轉換為16進制格式：
[root@localhost logs]# printf "%x\n" 3626
e18
最后打印線程的堆棧信息：
[root@localhost logs]# jstack 2633 |grep e18 -A 30
將輸出的信息發給開發部進行確認，這樣就能找出有問題的代碼。
通過最近幾天的監控，CPU已經安靜下來了。

 

 問題分析：
1，程序屬於CPU密集型，和開發溝通過，排除此類情況。
2，程序代碼有問題，出現死循環，可能性極大。

 Java程序很耗CPU是比較好分析的，有這么幾步：

1、先通過top命令找到消耗cpu很高的進程id假設是123

2、執行top -p 123單獨監控該進程

3、在第2步的監控界面輸入H，獲取當前進程下的所有線程信息

4、找到消耗cpu特別高的線程編號，假設是123

5、執行jstack 123456對當前的進程做dump，輸出所有的線程信息

6 將第4步得到的線程編號11354轉成16進制是0x7b

7 根據第6步得到的0x7b在第5步的線程信息里面去找對應線程內容

8 解讀線程信息，定位具體代碼位置
不一定一次就能抓准線程狀態，可以第1步時多記幾個線程。

 

 

我們使用jdk自帶的jstack來分析。當linux出現cpu被java程序消耗過高時，以下過程說不定可以幫上你的忙：
1.top查找出哪個進程消耗的cpu高 
21125 co_ad2    18   0 1817m 776m 9712 S  3.3  4.9  12:03.24 java                                                                                           
5284 co_ad     21   0 3028m 2.5g 9432 S  1.0 16.3   6629:44 java                                                                                           
21994 mysql     15   0  449m  88m 5072 S  1.0  0.6  67582:38 mysqld                                                                                         
8657 co_sparr  19   0 2678m 892m 9220 S  0.3  5.7 103:06.13 java

這里我們分析21125這個java進程。 

2.top中shift+h查找出哪個線程消耗的cpu高 
先輸入top -p 21125，然后再按shift+h。這里意思為只查看21125的進程，並且顯示線程。 
21233 co_ad2    15   0 1807m 630m 9492 S  1.3  4.0   0:05.12 java                                                                                           
20503 co_ad2_s  15   0 1360m 560m 9176 S  0.3  3.6   0:46.72 java                                                                                           
21134 co_ad2    15   0 1807m 630m 9492 S  0.3  4.0   0:00.72 java                                                                                           
22673 co_ad2    15   0 1807m 630m 9492 S  0.3  4.0   0:03.12 java
這里我們分析21233這個線程，並且注意的是，這個線程是屬於21125這個進程的。 

3.將需要的線程ID轉換為16進制格式：

duanxz@duanxz-pc MINGW64 /d/ebook/spring
$ printf "%x\n" 21233
52f1

4.jstack查找這個線程的信息 
jstack [進程]|grep -A 10 [線程的16進制] 
即： 

jstack 21125|grep -A 10 52f1

-A 10表示查找到所在行的后10行。21233用計算器轉換為16進制52f1，注意字母是小寫。 
結果： 

    "http-8081-11" daemon prio=10 tid=0x00002aab049a1800 nid=0x52f1 in Object.wait() [0x0000000042c75000]  
       java.lang.Thread.State: WAITING (on object monitor)  
         at java.lang.Object.wait(Native Method)  
         at java.lang.Object.wait(Object.java:485)  
         at org.apache.tomcat.util.net.JIoEndpoint$Worker.await(JIoEndpoint.java:416)  

說不定可以一下子定位到出問題的代碼。

 

也可以用useful-shells

原文：https://github.com/oldratlee/useful-shells

把平時有用的手動操作做成腳本，這樣可以便捷的使用。

show-busy-java-threads.sh

在排查Java的CPU性能問題時，找出Java進程中消耗CPU多(top us值過高)的線程，查看它的線程棧，從而找出有性能問題的方法調用。

$ ./show-busy-java-threads.sh 
The stack of busy(57.0%) thread(23355/0x5b3b) of java process(23269) of user(admin): "pool-1-thread-1" prio=10 tid=0x000000005b5c5000 nid=0x5b3b runnable [0x000000004062c000] java.lang.Thread.State: RUNNABLE at java.text.DateFormat.format(DateFormat.java:316) at com.xxx.foo.services.common.DateFormatUtil.format(DateFormatUtil.java:41) at com.xxx.foo.shared.monitor.schedule.AppMonitorDataAvgScheduler.run(AppMonitorDataAvgScheduler.java:127) at com.xxx.foo.services.common.utils.AliTimer$2.run(AliTimer.java:128) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) at java.lang.Thread.run(Thread.java:662)