java - Full GC后年轻代回收减少10倍

Question

这里是我的 gc.log 的摘录:

2013-02-28T12:02:13.209+0100: 1486457.849: [GC 1486457.850: [ParNew 3483368K->96838K(3774912K), 0.2273030 秒] 8085678K->4710336K(49912256K), 0.2278070 秒] [时间: user=1.54 sys=0.01, real=0.23 秒]

2013-02-28T12:02:26.551+0100: 1486471.191: [GC 1486471.191: [ParNew 3452358K->125528K(3774912K), 0.2386800 秒] 8065856K->4748772K(49912256K), 0.2392150 秒] [次数: user=1.64 sys=0.01, real=0.24 秒]

2013-02-28T12:02:27.398+0100: 1486472.038: [完整 GC 1486472.039: [CMS: 4623244K->3323834K(46137344K), 3.0873920 秒] 50346 29K->3323834K(49912256K)，[CMS 权限:97619K->54323K(98304K)]，3.0878880 秒] [时间:user=3.04 sys=0.01，real=3.08 秒]

2013-02-28T12:02:42.758+0100: 1486487.398: [GC 1486487.399: [ParNew 3355519K->50321K(3774912K)，0.0289980 秒] 6679353K->3374156K(49912256K)，0.0295550 秒] [时间:user=0.20 sys=0.00，real=0.03 秒]

2013-02-28T12:02:59.045+0100: 1486503.685: [GC 1486503.685: [ParNew 3405841K->71853K(3774912K)，0.0316570 秒] 6729676K->3395688K(49912256K)，0.0321860 秒] [时间:user=0.22 sys=0.00，real=0.03 秒]

Full GC 后(由于 Perm 空间耗尽)，年轻代回收时间从 0.23 秒减少到 0.03 秒(10 倍)。

我必须找出perm空间变满的原因，但我不明白的是为什么年轻代收集时间会因为Full GC而减少10倍。

有人可以帮我吗？

谢谢，琼。

Answer 1

通过提到的日志，我可以看出几点。

原因可能是:

当程序运行时，会发生分配和引用。短命对象死亡，其余的被提升到幸存者空间或永久空间。

可能的引用情况如下。

1)eden空间(年轻代)和survivor空间中的对象引用perm空间和tenured空间中的对象，使它们生命周期更长。

2)有时perm空间和tenured空间中的对象会引用年轻代对象(eden空间和survivor空间中)。

因为 tenured 和 perm 空间中的垃圾收集不像年轻代那么频繁。

可能存在这样的情况:永久空间或永久空间中的对象 obj1 不再被引用，并准备好被 GC 声明，因此这个 obj1 正在等待 GC，这种情况很少发生。同样，这个 obj1 可能引用了 Survivor 和 eden 空间中的对象。所以obj1引用的eden空间和survivor空间中的这个对象不符合垃圾回收的条件。因此，eden空间和survivor空间中可能有很多这样的对象，这些对象被perm空间中的obj1等对象引用，这导致eden空间和survivor空间中的对象数量更多。因此minor GC需要更多时间。

在full GC中，像obj1这样的对象会被收集，因此像obj1这样的对象引用的eden和survivor中的对象不会被GC声明，从而导致eden和survivor空间中的对象数量减少。由于对象数量较少，Minor GC 花费的时间也较少。如果您观察到进一步的日志缓慢地小GC时间再次增加(0.0289980秒，0.0316570如您的日志中提到的)，它可能会增加直到下一次FULL GC。

您还可以观察 Full GC 之前的 GC 时间模式，它正在增加。(日志中提到的 0.2273030 秒，0.2386800 秒)