2010-09-14 86 views
5
Operation System: Red Hat Linux 4.8 

CPU Info: Intel(R) Xeon(R) CPU 5160 @ 3.00GHz X 16 

JDK version: "1.5.0_16" 

JVM Parameter: 
-server 
-Xmx1024m 
-Xms1024m 
-XX:NewSize=256m 
-XX:MaxNewSize=256m 
-XX:PermSize=128m 
-XX:MaxPermSize=128m 
-XX:SurvivorRatio=8 
-XX:+PrintGCDetails 
-XX:+PrintGCTimeStamps 
-XX:+UseConcMarkSweepGC 
-XX:+UseCMSCompactAtFullCollection 
-XX:CMSFullGCsBeforeCompaction=5 
-XX:CMSInitiatingOccupancyFraction=60 
-XX:CMSMaxAbortablePrecleanTime=5 
-XX:+CMSPermGenSweepingEnabled 
-XX:+CMSClassUnloadingEnabled 
-XX:MaxGCPauseMillis=1500 

JVM GC Log: 

945188.489: [GC 945188.489: [ParNew: 224543K->14968K(235968K), 0.0506680 secs] 552200K->344514K(1022400K), 0.0507700 secs] 

945242.102: [GC 945242.102: [ParNew: 224760K->15374K(235968K), 0.0632410 secs] 554306K->346710K(1022400K), 0.0633450 secs] 

945270.397: [GC 945270.402: [ParNew: 225163K->225163K(235968K), 0.0000230 secs]945270.402: [CMS (concurrent mode failure)[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor70] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor58] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor38] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor62] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor54] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor74] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor53] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor73] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor64] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor39] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor59] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor51] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor42] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor48] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor76] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor52] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor57] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor61] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor56] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor55] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor63] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor60] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor40] 
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor65] 
: 331336K->71676K(786432K), 13.8120660 secs] 556499K->71676K(1022400K), 13.8122360 secs] 

945289.234: [GC 945289.234: [ParNew: 209792K->2581K(235968K), 0.0065240 secs] 281468K->74257K(1022400K), 0.0066160 secs] 

945324.703: [GC 945324.703: [ParNew: 212373K->3829K(235968K), 0.0081040 secs] 284049K->75506K(1022400K), 0.0082040 secs] 

为什么CMS(并发模式故障)在这里发生?为什么CMS(并发模式故障)在这里发生?

老一代似乎:331336K-> 71676K

回答

9

Concurrent Mode Failure所定义(786432K)

“并发模式失败” 表示的消息,该并发 收集年老代 的在终身 世代变得完整之前没有完成。

换句话说,新一代充满速度太快,它已经到了终身代代,但CMS无法在后台清除终身代。

在你的情况下,在945270.397

ParNew: 225163K->225163K(235968K)显示了年轻的充满并不能明确对象的。

更新

类似log to yours is explained这里说

这表明,ParNew集合 请求,但没有尝试。 (原因是,据估计 ,有没有在 CMS生成足够的空间来促进 最坏情况下的幸存年轻一代 对象。)我们称这个故障“全 推进保障失败”。结果,CMS的并发模式被中断,并调用完整的GC。

因此,当我看到它时,225M的年轻物体以及331K的Tenured的完整GC需要13秒钟,并将堆积降至71 M,但这是并发模式的结果故障

建议

如果你真的建立这么多的老物件,那么你可能需要一个更大的堆。

或减少尝试减少-XX:从60 CMSInitiatingOccupancyFraction,但不认为这会让DIFF

+0

谢谢你的答案了。 我的终身已经分配了786432k(768m)的内存。 你在哪里看到'老去330Mb到716Mb'? – user447044 2010-09-14 07:48:20

+0

Ah我错误地把71676K算作716 M,实际上是71 M. – JoseK 2010-09-14 08:05:22

+0

331336K-> 71676K(786432K),这意味着在gc之前,Tenured使用了331336k,在gc之后,Tenured使用了71676k。而我的终身已分配786432k。 所以问题出现了,331336/786432 = 42%的终身使用,但为什么CMS(并发模式失败)在这里发生? – user447044 2010-09-14 08:22:36