依赖包滥用System.gc()导致的Full GC

依赖包滥用System.gc()导致的频繁Full GC

简书 涤生
转载请注明原创出处,谢谢!
如果读完觉得有收获的话,欢迎点赞加关注。

介绍

业务部门的一个同事遇到个奇怪的Full GC问题,有个服务迁移到新的应用后,一直频繁Full GC。新应用机器的配置是4c 8g,老应用是4c 4g,老应用GC都很正常,并且代码没有变更,所以比较奇怪。

现象

问题的现象是,从监控图上看一直有大量的Full GC
GC 监控图

排查

遇到这个问题,一般都是先看看各个区的内存占用情况:
old young gen监控图

perm gen监控图

从监控图上看Old Gen、Young Gen、Perm Gen,没什么问题,不会触发Full GC,当然这里看各个Gen是否会触发Full GC需要结合JVM参数配置来看。

顺便也看了下GC日志,一直狂暴CMS GC日志,而且可以看到老年代使用空间也不大,细心可以发现,大量的CMS GC中夹杂着Young、Perm区的回收,所以其实是Full GC。GC日志如下:
GC日志

老应用的jvm参数配置
老应用的jvm参数配置

新应用的jvm参数配置
新应用的jvm参数配置

通过上面的观察,再根据一般触发CMS GC几个可能性:

  • Old Gen使用达到一定的比率,默认为92%,这里看CMSInitiatingOccupancyFraction=80%,而实际才使用2%(看监控图表)不到,所以排除这种情况。
  • 配置了CMSClassUnloadingEnabled,且Perm Gen的使用达到一定的比率默认为92%,这里看CMSInitiatingPermOccupancyFraction=80%,而实际才使用30%(看监控图表)不到,所以排除这种情况。
  • 配置了ExplictGCInvokesConcurrent且未配置DisableExplicitGC的情况下显示调用了System.gc()。
  • Hotspot自己根据估计决定是否要触法,如CMS悲观策略,这类可以通过GC日志分析。

大致判断很可能是System.gc()导致的问题,但是怎么定位调用System.gc()的代码呢?

当时就想如果是System.gc()引起的频繁Full GC,jstack线程堆栈应该能看到一些信息,果不其然,确实通过线程堆栈找到了。
jstack线程堆栈

jstack作用非常大,很多问题都能从这里发现,而且比较轻量,对应用基本无影响。某次的jstack信息只代表那个时刻的线程堆栈,有时只看一个jstack信息可能看不出什么问题,一般可以多jstack几次,然后对比去看,基本就能发现一些问题。
(当然该问题,也可能不是频繁的Full GC,可能通过jstack定位不到问题,可以jstat -gccause pid 1000,来查看gc原因。)

很明显,是由于jxl这个包中的close方法显示调用了System.gc()导致的问题。

跟了下代码,自然确实存在这段代码,不过有个设置开关,可以disable这个功能,所以在使用的时候可以设置setGCDisabled(true),关闭触发System.gc()。
触发System.gc()代码

但是为什么老应用没有问题呢,主要是因为它 -XX:+DisableExplicitGC,屏蔽了System.gc()动作,新应用的JVM没有这个配置。

可能大家还有个疑问,都知道System.gc()会触发Full GC,那为什么一直进行CMS GC(通过GC日志)呢?
主要是因为这个参数-XX:+ExplicitGCInvokesConcurrent,打开此参数后,会做并行Full GC,只有配置-XX:+UseConcMarkSweepGC这个参数,该参数才会生效。因此,System.gc()时Old区会进行CMS GC,可提高Full GC效率。

总结

尽量减少显示使用System.gc()来触发Full GC,这会导致频繁Full GC,非常影响应用性能。

最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 194,088评论 5 459
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 81,715评论 2 371
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 141,361评论 0 319
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 52,099评论 1 263
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 60,987评论 4 355
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 46,063评论 1 272
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 36,486评论 3 381
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 35,175评论 0 253
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 39,440评论 1 290
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 34,518评论 2 309
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 36,305评论 1 326
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 32,190评论 3 312
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 37,550评论 3 298
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 28,880评论 0 17
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 30,152评论 1 250
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 41,451评论 2 341
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 40,637评论 2 335

推荐阅读更多精彩内容

  • 作者:一字马胡 转载标志 【2017-11-12】 更新日志 日期更新内容备注 2017-11-12新建文章初版 ...
    beneke阅读 2,183评论 0 7
  • 原文阅读 前言 这段时间懈怠了,罪过! 最近看到有同事也开始用上了微信公众号写博客了,挺好的~给他们点赞,这博客我...
    码农戏码阅读 5,927评论 2 31
  • System.gc整理 System.gc()源码public static void gc() { Runtim...
    andersonoy阅读 2,903评论 0 1
  • 参数设置 在Java虚拟机的参数中,有3种表示方法用“ps -ef |grep "java"命令,可以得到当前Ja...
    九问阅读 9,077评论 2 52
  • 在微信公众号“7527”上已经推送了一个月的文章,不是朝着成功的方向而去,而是想看看这到底是一个怎样的平台,想知道...
    残剑阅读 358评论 0 1