JVM占用CPU使用過高問題排查

背景

近期grafana告警測試服務(wù)器CPU使用過高艾疟,需要排查是什么原因?qū)е虏⑻幚怼?/p>

告警信息

排查

使用TOP命令發(fā)現(xiàn)PID為98178的進(jìn)程CPU占用達(dá)到397.5来吩。

top

單獨(dú)查看98178進(jìn)程的使用情況

top -p 98178


各參數(shù)及意義如下:
top - 10:17:20 up 202 days, 12:39, 1 user, load average: 14.19, 14.33, 14.28

任務(wù)隊(duì)列信息 含義
10:17:20 當(dāng)前時(shí)間
202 days 系統(tǒng)運(yùn)行時(shí)間
12:39 用戶在線時(shí)間
1 users 在線用戶數(shù)
load average: 14.19, 14.33, 14.28 系統(tǒng)負(fù)載,即任務(wù)隊(duì)列的平均長度蔽莱。1分鐘前弟疆、5分鐘前、15分鐘前平均負(fù)

Tasks: 1 total, 0 running, 1 sleeping, 0 stopped, 0 zombie

進(jìn)程信息 含義
Tasks: 1 total 進(jìn)程總數(shù)
0 running 正在運(yùn)行的進(jìn)程數(shù)
1 sleeping 睡眠的進(jìn)程數(shù)
0 stopped 停止的進(jìn)程數(shù)
0 zombie 僵尸進(jìn)程數(shù)

Cpu(s): 99.8%us, 0.2%sy, 0.0%ni, 0.0%id, 0.0%wa, 0.0%hi, 0.0%si, 0.0%st

cpu信息 含義
99.8% us 用戶空間占用CPU百分比
0.2% sy 內(nèi)核空間占用CPU百分比
0.0% ni 用戶進(jìn)程空間內(nèi)改變過優(yōu)先級的進(jìn)程占用CPU百分比
0.0% id 空閑CPU百分比
0.0% wa 等待輸入輸出的CPU時(shí)間百分比
0.0% hi 硬件中斷
0.0% si 軟件中斷
0.0%st 實(shí)時(shí)

Mem: 16300292k total, 9979564k used, 6320728k free, 4288k buffers
Swap: 8175612k total, 541836k used, 7633776k free, 82108k cached

物理內(nèi)存信息 含義
Mem: 16300292k total 物理內(nèi)存總量
9979564k used 使用的物理內(nèi)存總量
6320728k free 空閑內(nèi)存總量
4288k buffers 用作內(nèi)核緩存的內(nèi)存量
交換區(qū)信息 含義
Swap: 8175612k total 交換區(qū)總量
541836k used 使用的交換區(qū)總量
7633776k free 空閑交換區(qū)總量
82108k cached 緩沖的交換區(qū)總量

查看線程情況,發(fā)現(xiàn)多個(gè)cpu高耗線程盗冷,并且cpu消耗接近,推斷是一段問題代碼被多次調(diào)用的結(jié)果怠苔。

top -p 98178 -H

查看最上面一個(gè)線程的TID,并轉(zhuǎn)換成16進(jìn)制

printf "%x \n" 212019

根據(jù)得到的16進(jìn)制TID仪糖,通過jstack查找該線程后十行來定位問題代碼柑司。發(fā)現(xiàn)指向一個(gè)運(yùn)行中(RUNNABLE)的線程,在代碼ArchiveApi.java:519行锅劝。

jstack 98178 | grep -A10 33c33

解決

找到查出來的代碼攒驰,發(fā)現(xiàn)是一段文件寫入的代碼有個(gè)while(true)循環(huán),推測是一直無法滿足read==-1導(dǎo)致的CPU使用過高故爵。



修改代碼并進(jìn)行測試:

修改代碼
運(yùn)行代碼幾次后查看cpu使用率

CPU使用率2.3%玻粪,測試成功。

?著作權(quán)歸作者所有,轉(zhuǎn)載或內(nèi)容合作請聯(lián)系作者
  • 序言:七十年代末诬垂,一起剝皮案震驚了整個(gè)濱河市劲室,隨后出現(xiàn)的幾起案子,更是在濱河造成了極大的恐慌剥纷,老刑警劉巖痹籍,帶你破解...
    沈念sama閱讀 218,858評論 6 508
  • 序言:濱河連續(xù)發(fā)生了三起死亡事件,死亡現(xiàn)場離奇詭異晦鞋,居然都是意外死亡蹲缠,警方通過查閱死者的電腦和手機(jī)棺克,發(fā)現(xiàn)死者居然都...
    沈念sama閱讀 93,372評論 3 395
  • 文/潘曉璐 我一進(jìn)店門,熙熙樓的掌柜王于貴愁眉苦臉地迎上來线定,“玉大人娜谊,你說我怎么就攤上這事〗锛ィ” “怎么了纱皆?”我有些...
    開封第一講書人閱讀 165,282評論 0 356
  • 文/不壞的土叔 我叫張陵,是天一觀的道長芭商。 經(jīng)常有香客問我派草,道長,這世上最難降的妖魔是什么铛楣? 我笑而不...
    開封第一講書人閱讀 58,842評論 1 295
  • 正文 為了忘掉前任近迁,我火速辦了婚禮,結(jié)果婚禮上簸州,老公的妹妹穿的比我還像新娘鉴竭。我一直安慰自己,他們只是感情好岸浑,可當(dāng)我...
    茶點(diǎn)故事閱讀 67,857評論 6 392
  • 文/花漫 我一把揭開白布搏存。 她就那樣靜靜地躺著,像睡著了一般矢洲。 火紅的嫁衣襯著肌膚如雪璧眠。 梳的紋絲不亂的頭發(fā)上,一...
    開封第一講書人閱讀 51,679評論 1 305
  • 那天读虏,我揣著相機(jī)與錄音蛆橡,去河邊找鬼。 笑死掘譬,一個(gè)胖子當(dāng)著我的面吹牛,可吹牛的內(nèi)容都是我干的呻拌。 我是一名探鬼主播葱轩,決...
    沈念sama閱讀 40,406評論 3 418
  • 文/蒼蘭香墨 我猛地睜開眼,長吁一口氣:“原來是場噩夢啊……” “哼藐握!你這毒婦竟也來了靴拱?” 一聲冷哼從身側(cè)響起,我...
    開封第一講書人閱讀 39,311評論 0 276
  • 序言:老撾萬榮一對情侶失蹤猾普,失蹤者是張志新(化名)和其女友劉穎袜炕,沒想到半個(gè)月后,有當(dāng)?shù)厝嗽跇淞掷锇l(fā)現(xiàn)了一具尸體初家,經(jīng)...
    沈念sama閱讀 45,767評論 1 315
  • 正文 獨(dú)居荒郊野嶺守林人離奇死亡偎窘,尸身上長有42處帶血的膿包…… 初始之章·張勛 以下內(nèi)容為張勛視角 年9月15日...
    茶點(diǎn)故事閱讀 37,945評論 3 336
  • 正文 我和宋清朗相戀三年乌助,在試婚紗的時(shí)候發(fā)現(xiàn)自己被綠了。 大學(xué)時(shí)的朋友給我發(fā)了我未婚夫和他白月光在一起吃飯的照片陌知。...
    茶點(diǎn)故事閱讀 40,090評論 1 350
  • 序言:一個(gè)原本活蹦亂跳的男人離奇死亡他托,死狀恐怖,靈堂內(nèi)的尸體忽然破棺而出仆葡,到底是詐尸還是另有隱情赏参,我是刑警寧澤,帶...
    沈念sama閱讀 35,785評論 5 346
  • 正文 年R本政府宣布沿盅,位于F島的核電站把篓,受9級特大地震影響,放射性物質(zhì)發(fā)生泄漏腰涧。R本人自食惡果不足惜韧掩,卻給世界環(huán)境...
    茶點(diǎn)故事閱讀 41,420評論 3 331
  • 文/蒙蒙 一、第九天 我趴在偏房一處隱蔽的房頂上張望南窗。 院中可真熱鬧揍很,春花似錦、人聲如沸万伤。這莊子的主人今日做“春日...
    開封第一講書人閱讀 31,988評論 0 22
  • 文/蒼蘭香墨 我抬頭看了看天上的太陽敌买。三九已至简珠,卻和暖如春,著一層夾襖步出監(jiān)牢的瞬間虹钮,已是汗流浹背聋庵。 一陣腳步聲響...
    開封第一講書人閱讀 33,101評論 1 271
  • 我被黑心中介騙來泰國打工, 沒想到剛下飛機(jī)就差點(diǎn)兒被人妖公主榨干…… 1. 我叫王不留芙粱,地道東北人祭玉。 一個(gè)月前我還...
    沈念sama閱讀 48,298評論 3 372
  • 正文 我出身青樓,卻偏偏與公主長得像春畔,于是被迫代替她去往敵國和親脱货。 傳聞我的和親對象是個(gè)殘疾皇子,可洞房花燭夜當(dāng)晚...
    茶點(diǎn)故事閱讀 45,033評論 2 355

推薦閱讀更多精彩內(nèi)容