29-案例實(shí)戰(zhàn)1:通過jps+jstat針對(duì)系統(tǒng)問題分析和優(yōu)化

歡迎關(guān)注公眾號(hào)OpenCoder,來和我做朋友吧~??????

案例背景:

實(shí)際開發(fā)中有很多類似的這樣的應(yīng)用場(chǎng)景汤徽,比如每秒多少個(gè)請(qǐng)求桥言,每次請(qǐng)求分配多少對(duì)象等,我們的目的就是通過工具分析我們系統(tǒng)在實(shí)際運(yùn)行過程中是否頻繁觸發(fā)GC以及對(duì)象是否頻繁進(jìn)入老年代引發(fā)Full GC厌漂,哪些對(duì)象存在影響性能以及沒有及時(shí)回收的問題。

我們以一個(gè)線上的BI系統(tǒng)來進(jìn)行講解斟珊,整個(gè)的流程運(yùn)行如下:

image.png

針對(duì)上訴系統(tǒng)在商家不多的情況下苇倡,也就是幾分鐘卡頓10ms,對(duì)于用戶端的感受來講幾乎沒有影響囤踩,但是假設(shè)如果我們的商家突然暴增旨椒,同時(shí)訪問量能達(dá)到幾千,我們的機(jī)器可能每秒請(qǐng)求量就會(huì)達(dá)到幾百個(gè)比如500堵漱,那么這時(shí)每秒的數(shù)據(jù)加載就有50MB综慎,那么針對(duì)只有1G的Eden區(qū)域多久就會(huì)發(fā)生一次YongGc呢?自己系統(tǒng)多久會(huì)觸發(fā)一次Full GC呢勤庐?

以下代碼示惊,我們模擬的就是系統(tǒng)正常運(yùn)行,每秒鐘50個(gè)請(qǐng)求愉镰,每個(gè)請(qǐng)求加載100KB數(shù)據(jù)的方式不停運(yùn)行米罚,由于是死循環(huán),我們不停止程序也不會(huì)停止:

/**
 * @Description: 案例實(shí)戰(zhàn)-通過jps丈探、jstat录择、jmap、jhat工具進(jìn)行聯(lián)調(diào)優(yōu)化
JVM參數(shù): -XX:NewSize=100m -XX:MaxNewSize=100m -XX:InitialHeapSize=200m -XX:MaxHeapSize=200m -XX:SurvivorRatio=8 -XX:MaxTenuringThreshold=15 -XX:PretenureSizeThreshold=3m -XX:+UseParNewGC -XX:+UseConcMarkSweepGC -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -Xloggc:gc.log
 *
 */
public class JVMTest {
    public static void main(String[] args) throws InterruptedException {
        Thread.sleep(30000);
        while(true){
            loadData();
        }
    }

    public static final int _1KB = 1024;

    /**
     * 模擬每秒50個(gè)請(qǐng)求碗降,每次請(qǐng)求分配100kb的數(shù)組
     * @throws InterruptedException
     */
    private static void loadData() throws InterruptedException {
        byte[] data = null;
        for (int i = 0; i < 50; i++) {
            data = new byte[100 * _1KB ];
        }
        data = null;

        Thread.sleep(1000);
    }
}

我們給堆內(nèi)存設(shè)置為200M隘竭,新生代100M,Eden區(qū)占80M讼渊,老年代占100M進(jìn)行模擬GC情況动看。

步驟分析:

  1. 在Main方法中首先第一行代碼先執(zhí)行睡眠30S,目的在于方便我們程序啟動(dòng)后精偿,通過jps命令找到我們當(dāng)前程序進(jìn)程ID弧圆,然后結(jié)合jstat來觀察程序運(yùn)行狀態(tài)
  2. 接著無限循環(huán)開始加載數(shù)據(jù),在loadData()方法中每隔1秒向內(nèi)存中申請(qǐng)分配100*50 = 5MB對(duì)象
  3. 通過jstat命令打印觀察數(shù)據(jù)的變化

數(shù)據(jù)分析:

  1. 查找到進(jìn)程id
image.png
  1. 跟蹤進(jìn)程id查看內(nèi)存數(shù)據(jù)變化:

命令:jstat -gc 19492 1000 1000 表示每隔1秒鐘打印1次統(tǒng)計(jì)信息笔咽,連續(xù)打印1000次:

image.png
  1. 通過幾十秒的運(yùn)行后搔预,我們結(jié)合上圖也能明顯觀察出內(nèi)存的一個(gè)變化情況
  • 首先我們先看 EU的變化,EU代表的是Eden區(qū)內(nèi)存的使用情況:
    • 最開始EU只使用了6M左右大小叶组,并且持續(xù)了一段時(shí)間拯田,代表這段時(shí)間其實(shí)就是我們的睡眠時(shí)間
    • 后續(xù)開始增長(zhǎng)并且每秒都有變化,代表我們的loadData()方法開始執(zhí)行甩十,每秒增長(zhǎng)差不多5M左右大小船庇,跟我們的代碼一致
    • 當(dāng)EU的占用已達(dá)到81303KB的時(shí)候吭产,再分配5M對(duì)象很明顯此時(shí)就無法分配了,這時(shí)就會(huì)觸發(fā)一次 Minor GC鸭轮!
image.png
  • 注意:我們發(fā)現(xiàn)EU的大小一下降低到了4471KB差不多4M的內(nèi)存臣淤,這也說明了一次Minor GC回收了大部分對(duì)象

小結(jié):通過以上程序的運(yùn)行以及分析我們知道了,該程序每秒對(duì)象增速在5MB左右窃爷,大概在10幾秒左右會(huì)觸發(fā)一次Mionr GC邑蒋,并且通過 YGCT我們也能知道,一次Minor GC的耗時(shí)也就在8ms按厘,速度非骋降酰快!一次回收差不多接近80MB對(duì)象逮京,如果我們的Eden區(qū)是800MB內(nèi)存卿堂,那一次回收預(yù)估也就在80ms,對(duì)于系統(tǒng)而已幾乎沒有卡頓

  1. 我們繼續(xù)觀察數(shù)據(jù)懒棉,看看每次Mionr GC過后的存活對(duì)象有多少草描?

    S1U代表的就是Survivor1區(qū)使用的內(nèi)存大小,我們發(fā)現(xiàn)觸發(fā)Minor GC后 S1U就有值了策严,大小為:1131KB, 代表從Eden區(qū)中存活的對(duì)象有1131kb對(duì)象內(nèi)移入了S1區(qū)陶珠,接近1MB的對(duì)象對(duì)于10MB的S1區(qū)來說還是很輕松的。

  2. 我們繼續(xù)觀察后續(xù)GC存活的對(duì)象有多少享钞?

image.png

通過后續(xù)的數(shù)據(jù)我們可以發(fā)現(xiàn),第二次GC后剩余存活1387KB诀蓉,第三次GC過后剩余存活1475KB栗竖,增長(zhǎng)量幾乎在幾十KB范圍很小,而且后續(xù)的GC耗時(shí)更短渠啤,我們幾乎可以斷定該系統(tǒng)運(yùn)行非常良好狐肢!幾乎不會(huì)發(fā)生Full GC。

最后編輯于
?著作權(quán)歸作者所有,轉(zhuǎn)載或內(nèi)容合作請(qǐng)聯(lián)系作者
  • 序言:七十年代末沥曹,一起剝皮案震驚了整個(gè)濱河市份名,隨后出現(xiàn)的幾起案子,更是在濱河造成了極大的恐慌妓美,老刑警劉巖僵腺,帶你破解...
    沈念sama閱讀 211,123評(píng)論 6 490
  • 序言:濱河連續(xù)發(fā)生了三起死亡事件,死亡現(xiàn)場(chǎng)離奇詭異壶栋,居然都是意外死亡辰如,警方通過查閱死者的電腦和手機(jī),發(fā)現(xiàn)死者居然都...
    沈念sama閱讀 90,031評(píng)論 2 384
  • 文/潘曉璐 我一進(jìn)店門贵试,熙熙樓的掌柜王于貴愁眉苦臉地迎上來琉兜,“玉大人凯正,你說我怎么就攤上這事⊥泱” “怎么了廊散?”我有些...
    開封第一講書人閱讀 156,723評(píng)論 0 345
  • 文/不壞的土叔 我叫張陵,是天一觀的道長(zhǎng)梧疲。 經(jīng)常有香客問我允睹,道長(zhǎng),這世上最難降的妖魔是什么往声? 我笑而不...
    開封第一講書人閱讀 56,357評(píng)論 1 283
  • 正文 為了忘掉前任擂找,我火速辦了婚禮,結(jié)果婚禮上浩销,老公的妹妹穿的比我還像新娘贯涎。我一直安慰自己,他們只是感情好慢洋,可當(dāng)我...
    茶點(diǎn)故事閱讀 65,412評(píng)論 5 384
  • 文/花漫 我一把揭開白布塘雳。 她就那樣靜靜地躺著,像睡著了一般普筹。 火紅的嫁衣襯著肌膚如雪败明。 梳的紋絲不亂的頭發(fā)上,一...
    開封第一講書人閱讀 49,760評(píng)論 1 289
  • 那天太防,我揣著相機(jī)與錄音妻顶,去河邊找鬼。 笑死蜒车,一個(gè)胖子當(dāng)著我的面吹牛讳嘱,可吹牛的內(nèi)容都是我干的。 我是一名探鬼主播酿愧,決...
    沈念sama閱讀 38,904評(píng)論 3 405
  • 文/蒼蘭香墨 我猛地睜開眼沥潭,長(zhǎng)吁一口氣:“原來是場(chǎng)噩夢(mèng)啊……” “哼!你這毒婦竟也來了嬉挡?” 一聲冷哼從身側(cè)響起钝鸽,我...
    開封第一講書人閱讀 37,672評(píng)論 0 266
  • 序言:老撾萬榮一對(duì)情侶失蹤,失蹤者是張志新(化名)和其女友劉穎庞钢,沒想到半個(gè)月后拔恰,有當(dāng)?shù)厝嗽跇淞掷锇l(fā)現(xiàn)了一具尸體,經(jīng)...
    沈念sama閱讀 44,118評(píng)論 1 303
  • 正文 獨(dú)居荒郊野嶺守林人離奇死亡焊夸,尸身上長(zhǎng)有42處帶血的膿包…… 初始之章·張勛 以下內(nèi)容為張勛視角 年9月15日...
    茶點(diǎn)故事閱讀 36,456評(píng)論 2 325
  • 正文 我和宋清朗相戀三年仁连,在試婚紗的時(shí)候發(fā)現(xiàn)自己被綠了。 大學(xué)時(shí)的朋友給我發(fā)了我未婚夫和他白月光在一起吃飯的照片。...
    茶點(diǎn)故事閱讀 38,599評(píng)論 1 340
  • 序言:一個(gè)原本活蹦亂跳的男人離奇死亡饭冬,死狀恐怖使鹅,靈堂內(nèi)的尸體忽然破棺而出,到底是詐尸還是另有隱情昌抠,我是刑警寧澤患朱,帶...
    沈念sama閱讀 34,264評(píng)論 4 328
  • 正文 年R本政府宣布,位于F島的核電站炊苫,受9級(jí)特大地震影響裁厅,放射性物質(zhì)發(fā)生泄漏。R本人自食惡果不足惜侨艾,卻給世界環(huán)境...
    茶點(diǎn)故事閱讀 39,857評(píng)論 3 312
  • 文/蒙蒙 一执虹、第九天 我趴在偏房一處隱蔽的房頂上張望。 院中可真熱鬧唠梨,春花似錦袋励、人聲如沸。這莊子的主人今日做“春日...
    開封第一講書人閱讀 30,731評(píng)論 0 21
  • 文/蒼蘭香墨 我抬頭看了看天上的太陽。三九已至蚁鳖,卻和暖如春磺芭,著一層夾襖步出監(jiān)牢的瞬間,已是汗流浹背醉箕。 一陣腳步聲響...
    開封第一講書人閱讀 31,956評(píng)論 1 264
  • 我被黑心中介騙來泰國打工钾腺, 沒想到剛下飛機(jī)就差點(diǎn)兒被人妖公主榨干…… 1. 我叫王不留,地道東北人讥裤。 一個(gè)月前我還...
    沈念sama閱讀 46,286評(píng)論 2 360
  • 正文 我出身青樓垮庐,卻偏偏與公主長(zhǎng)得像,于是被迫代替她去往敵國和親坞琴。 傳聞我的和親對(duì)象是個(gè)殘疾皇子,可洞房花燭夜當(dāng)晚...
    茶點(diǎn)故事閱讀 43,465評(píng)論 2 348

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