Java CMS 垃圾收集器日志输出

Question

我正在使用 Java CMS 垃圾收集器并尝试理解如下日志行:

22609.787: [GC 22609.788: [ParNew: 1116101K->79200K(1310720K), 0.2369136 secs]     1551730K->516431K(6029312K), 0.2379422 secs] [Times: user=1.68 sys=0.02, real=0.24 secs] 
22610.741: [Full GC 22610.741: [CMS: 437230K->278442K(4718592K), 14.8596841 secs]   573355K->278442K(6029312K), [CMS Perm : 241468K->236967K(241856K)], 14.8694544 secs]   [Times: user=14.80 sys=0.13, real=14.87 secs] 
22635.415: [GC 22635.416: [ParNew: 1048576K->43613K(1310720K), 0.0904065 secs] 1327018K->322055K(6029312K), 0.0914701 secs] [Times: user=0.45 sys=0.00, real=0.09 secs] 

基于阅读http://blogs.oracle.com/poonam/entry/understanding_cms_gc_logs CMS 将在各种情况下进行完整的垃圾收集(例如，如果“清除尝试中的提升失败”)，但根据该博客，它会记录完整 GC 的原因。

相反，我看到的是临时完整 GC。肯定是 CMS，因为还有其他 CMS 日志条目。

是否有理由执行完整 GC 但不记录原因？

编辑:抱歉，多个 java 安装(我继承了这个设置)。它实际上是使用jdk1.6.0_27

编辑不幸的是，JVM 参数包含在配置文件中(这是一个在 Tomcat 上运行的商业应用程序)，但我很确定它们是:

min.heapsize=6144m
max.heapsize=6144m
-Xss=512k
-XX:MaxPermSize=512m
-XX:NewSize=1536m 
-XX:MaxNewSize=1536m 
-XX:SurvivorRatio=4 
-XX:+UseConcMarkSweepGC 
-XX:+UseParNewGC 
-XX:+UseTLAB 
-XX:+UseCompressedOops 
-XX:+PrintVMOptions 
-XX:+PrintGCDetails 
-XX:+PrintGCTimeStamps 
-XX:+PrintGCTaskTimeStamps
-XX:+PrintCommandLineFlags 
-XX:+PrintGCApplicationStoppedTime 
-XX:StackShadowPages=20 
-XX:+DisableExplicitGC 

Answer 1

ConcurrentMarkSweepGC 将在确定即将填满或已达到低水位线时执行 GC。 (我不相信这里是这种情况)

更有可能是某些代码直接调用了 System.gc()。例如。默认情况下，RMI 将每小时调用一次。您可以尝试使用 -XX:+DisableExplicitGC 看看它是否有所作为。如果是这样，我会追查原因并阻止它发生(有些人只是保留此选项)。

另一个可能的原因是可用直接内存不足。直接内存仅在 GC 上清理，但对堆大小贡献不大，因此看起来您确实需要 GC。当你用完直接内存时，它必须调用 System.gc() 来释放那些正在使用它的 ByteBuffers。我解决这个问题的方法是明确清理直接和内存映射的字节缓冲区，这样我就不会用完。

另外我会尝试 Java 6 update 30 甚至 Java 7 update 2，因为它可能有更多可预测的行为(即使你不能使用它也可以提供信息)

您可以尝试哪个可以告诉您触发了什么 GC(但并非总是如此)

`jstat -gccause {pid} 10s`