java獲取到heapdump文件后快速分析技巧

更新時(shí)間：2023年04月22日 07:27:20 作者：扣釘日記

大家都知道HeapDump文件是指定時(shí)刻的Java堆棧的快照，是一種鏡像文件，今天通過本文給大家介紹java獲取到heapdump文件后，如何快速分析,需要的朋友可以參考下

heapdump文件介紹

heap dump： heap dump文件是一個(gè)二進(jìn)制文件，它保存了某一時(shí)刻JVM堆中對(duì)象使用情況。HeapDump文件是指定時(shí)刻的Java堆棧的快照，是一種鏡像文件。

產(chǎn)生heap dump(內(nèi)存溢出)錯(cuò)誤原因一般出于以下原因：

1)JVM內(nèi)存過小，

2)程序不嚴(yán)密，

3)產(chǎn)生過多的垃圾無法回收。

簡(jiǎn)介

在之前的OOM問題復(fù)盤之后，本周，又一Java服務(wù)出現(xiàn)了內(nèi)存問題，這次問題不嚴(yán)重，只會(huì)觸發(fā)堆內(nèi)存占用高報(bào)警，沒有觸發(fā)OOM，但好在之前的復(fù)盤中總結(jié)了dump腳本，會(huì)在堆占用高時(shí)自動(dòng)執(zhí)行jstack與jmap，使得我們成功保留了問題現(xiàn)場(chǎng)。

查看堆占用分布

發(fā)現(xiàn)有heapdump文件后，我立馬拷貝到本機(jī)，并使用MAT分析，如下：

很顯然，好像是什么接口分配了非常大的String對(duì)象，一個(gè)String對(duì)象約200MB，那它是哪分配的呢？

查找大對(duì)象分配線程

這個(gè)分配行為肯定是某個(gè)線程做的，而線程是最常見的GC Root，因此只要查找對(duì)象的GC Root即可，如下：

找到了大對(duì)象對(duì)應(yīng)的分配線程是http-nio-8088-exec-6，如下：

查看線程棧

如何查看這個(gè)線程在干什么呢？在MAT中摸索了一會(huì)，沒找到相關(guān)內(nèi)容，回想起我們的dump腳本中記錄了jstack，打開看看，如下：

可以發(fā)現(xiàn)，這個(gè)線程正在做json序列化，但我仔細(xì)找了好一會(huì)，也沒有找到相關(guān)接口的Controller，這是因?yàn)榫€程已經(jīng)執(zhí)行完了Controller里面的邏輯，之后返回接口響應(yīng)數(shù)據(jù)時(shí)分配的大對(duì)象。

可是，線程棧中沒有業(yè)務(wù)代碼，就沒法定位是哪個(gè)接口有問題了。。。

檢查accesslog日志

考慮到分配大對(duì)象的接口肯定會(huì)很慢，于是我轉(zhuǎn)向查看tomcat的accesslog日志，如下：

終于，找到了問題接口，這個(gè)接口是用來查詢商品數(shù)據(jù)的，當(dāng)輸入3時(shí)會(huì)查詢出所有3開頭的商品，而這有20w+數(shù)據(jù)，解決問題很簡(jiǎn)單，加個(gè)limit完事。

排查過程復(fù)盤

然而，我一直有個(gè)習(xí)慣，就是解決一個(gè)問題后，我會(huì)反思一下問題解決過程中有多少運(yùn)氣成分。

如果你經(jīng)常閱讀排查問題類的技術(shù)文章，就會(huì)發(fā)現(xiàn)不少文章，中間突然有一步定位到了問題根因，可能是突然發(fā)現(xiàn)了一個(gè)線索，或是硬看代碼看出來的，或是猜測(cè)某處有問題，我覺得這種排查過程都有不少運(yùn)氣成分，我希望問題是通過多年理論基礎(chǔ)的積累和對(duì)診斷工具的熟練使用，而有章法的一步步查出來的。

而上面通過accesslog能夠定位到問題，有一定的運(yùn)氣成分，因?yàn)楸敬蝺?nèi)存問題不極端，如果此接口請(qǐng)求量大，那就會(huì)瞬間觸發(fā)多次FGC，進(jìn)而會(huì)影響其它接口也變慢，進(jìn)而無法分辨出哪個(gè)是導(dǎo)致問題的接口！

我想，從理論上來說，Java堆文件里面，應(yīng)該有線程棧以及線程棧上的參數(shù)，因?yàn)榫€程是對(duì)象，參數(shù)也是對(duì)象，它們理應(yīng)都在堆里，于是我找了個(gè)空閑時(shí)間，又摸索起MAT這個(gè)工具了。