JVM内存调优记录

时间:2022-12-26 09:08:01

  java内存的自动管理,解决了给对象分配内存和回收分配给对象的内存。
大对象直接进入老年代,可以使用-XX:PretenureSizeThreshold参数设置,大对象的最大值,例如:-XX:PretenureSizeThreshold=3145728,超过3M的对象直接进入老年区。
IBM的研究表明,新生代中对象98%是朝生夕死的,如果按照1:1的比例划分,会降低内存的利用率,可以将新生代的内存划分为一块较大的Eden和两块较小的Survior区,比例默认为8:1:1
这样利用率为90%,每次使用Eden和其中一块Survior,当回收完时,如果Eden和Survior区还存在存活的对象,就一次性的全部拷贝到另外一块Survior区,最后清理掉Eden和刚刚用过的
Survior区,如果另外一块Survior区内存不够的时候,就需要依赖其他内存(老年区的内存)
1)JVM参数 -Djava.library.path=./lib -Xms256M -Xmx256M -XX:+PrintGCDetails 服务器(10.4.120.121)
   打印出的日志
   NOTICE - file:/E:/cc/hz08744_dev_nbi/Orange/prj/build/classes/com/utstar/omco/main/Main.class
[GC [DefNew: 69952K->1953K(78656K), 0.0079673 secs] 69952K->1953K(253440K), 0.0080031 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
webstart check
false

initrd...
 Login:.
[GC [DefNew: 55780K->5347K(78656K), 0.0180487 secs] 55780K->5347K(253440K), 0.0180940 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
[GC [DefNew: 75299K->3617K(78656K), 0.0336808 secs] 75299K->25453K(253440K), 0.0337267 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
[GC [DefNew: 73569K->5040K(78656K), 0.0181208 secs] 95405K->26877K(253440K), 0.0181647 secs] [Times: user=0.02 sys=0.00, real=0.02 secs]
[GC [DefNew: 74992K->4751K(78656K), 0.0251681 secs] 96829K->29502K(253440K), 0.0252107 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
[GC [DefNew: 74703K->3610K(78656K), 0.0167646 secs] 99454K->30171K(253440K), 0.0168205 secs] [Times: user=0.02 sys=0.00, real=0.02 secs]
[GC [DefNew: 73562K->4405K(78656K), 0.0113504 secs] 100123K->30966K(253440K), 0.0114531 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC [DefNew: 74357K->1752K(78656K), 0.0109753 secs] 100918K->31096K(253440K), 0.0110344 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC [DefNew: 71704K->1929K(78656K), 0.0056460 secs] 101048K->31274K(253440K), 0.0056879 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC [DefNew: 71881K->2618K(78656K), 0.0076565 secs] 101226K->31963K(253440K), 0.0077010 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC [DefNew: 72570K->3003K(78656K), 0.0084164 secs] 101915K->32348K(253440K), 0.0084561 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
[GC [DefNew: 72955K->3195K(78656K), 0.0109871 secs] 102300K->32539K(253440K), 0.0110306 secs] [Times: user=0.02 sys=0.00, real=0.02 secs]
[GC [DefNew: 73147K->4801K(78656K), 0.0116500 secs] 102491K->34146K(253440K), 0.0117118 secs] [Times: user=0.01 sys=0.00, real=0.01 secs]
12-06 14:47:42.520 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.520 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.520 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.520 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.520 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.520 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.551 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.551 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.551 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.551 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.551 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:47:42.551 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
[GC [DefNew: 74753K->8704K(78656K), 0.0361043 secs] 104098K->40339K(253440K), 0.0361473 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
Calculate alarm summary of  6696 MOs cost 341 ms
[GC [DefNew: 78656K->334K(78656K), 0.0244156 secs] 110291K->40414K(253440K), 0.0244679 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
[GC [DefNew: 70286K->515K(78656K), 0.0032695 secs] 110366K->40595K(253440K), 0.0033057 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
LspResourceCache is ready : Fri Dec 06 14:47:46 CST 2013
PwResourceCache is ready : Fri Dec 06 14:47:46 CST 2013
WDMPORTMAP Cache is ready : Fri Dec 06 14:47:47 CST 2013
NMLFaultResourceCache is ready : Fri Dec 06 14:47:47 CST 2013
EMLFaultResourceCache is ready : Fri Dec 06 14:47:47 CST 2013
Name Cache is ready : Fri Dec 06 14:47:47 CST 2013
[GC [DefNew: 70467K->6233K(78656K), 0.0185638 secs] 110547K->46313K(253440K), 0.0186287 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
[GC [DefNew: 76185K->842K(78656K), 0.0180233 secs] 116265K->46745K(253440K), 0.0180807 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
[Full GC [Tenured: 45902K->50963K(174784K), 0.1074688 secs] 74371K->50963K(253440K), [Perm : 16383K->16383K(16384K)], 0.1075292 secs] [Times: user=0.11 sys=0.00, real=0.11 secs]
[Full GC [Tenured: 50963K->55266K(174784K), 0.1237690 secs] 87095K->55266K(253440K), [Perm : 20479K->20479K(20480K)], 0.1238359 secs] [Times: user=0.13 sys=0.00, real=0.12 secs]
Heap
 def new generation   total 78656K, used 68191K [0x02c40000, 0x08190000, 0x08190000)
  eden space 69952K,  97% used [0x02c40000, 0x06ed7e60, 0x07090000)
  from space 8704K,   0% used [0x07090000, 0x07090000, 0x07910000)
  to   space 8704K,   0% used [0x07910000, 0x07910000, 0x08190000)
 tenured generation   total 174784K, used 55266K [0x08190000, 0x12c40000, 0x12c40000)
   the space 174784K,  31% used [0x08190000, 0x0b7889b0, 0x0b788a00, 0x12c40000)
 compacting perm gen  total 21504K, used 21398K [0x12c40000, 0x14140000, 0x16c40000)
   the space 21504K,  99% used [0x12c40000, 0x14125ad8, 0x14125c00, 0x14140000)
No shared spaces configured.
2)JVM参数 -Djava.library.path=./lib -Xms512M -Xmx512M -XX:+PrintGCDetails 服务器(10.4.120.121)
使用这个JVM参数,堆内存使用量维持在125M左右,堆内存一直在上升,当堆内存使用达到200M的时候,就会进行一次MinorGC,Eden向Survior区复制,由于客户端的JDK新生代的收集器默认
使用的是Serial新生代收集器(使用复制算法,暂停所有的用户线程)。
这个就符合JVM内存分配的原则:大多数情况,对象会在新生代的Eden区分配,当Eden区没有足够的空间,执行一次MinorGC
   NOTICE - file:/E:/cc/hz08744_dev_nbi/Orange/prj/build/classes/com/utstar/omco/main/Main.class
webstart check
false

initrd...
 Login:.
[GC [DefNew: 139776K->4728K(157248K), 0.0196050 secs] 139776K->4728K(506816K), 0.0196603 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
[GC [DefNew: 144504K->6030K(157248K), 0.0445373 secs] 144504K->26510K(506816K), 0.0445782 secs] [Times: user=0.01 sys=0.03, real=0.05 secs]
[GC [DefNew: 145806K->9388K(157248K), 0.0346395 secs] 166286K->29868K(506816K), 0.0346800 secs] [Times: user=0.02 sys=0.02, real=0.03 secs]
[GC [DefNew: 149164K->7423K(157248K), 0.0315490 secs] 169644K->31082K(506816K), 0.0315958 secs] [Times: user=0.03 sys=0.00, real=0.04 secs]
[GC [DefNew: 147199K->8299K(157248K), 0.0238696 secs] 170858K->31958K(506816K), 0.0239127 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
[GC [DefNew: 148075K->8770K(157248K), 0.0209814 secs] 171734K->32429K(506816K), 0.0210202 secs] [Times: user=0.03 sys=0.00, real=0.04 secs]
[GC [DefNew: 148546K->10261K(157248K), 0.0385143 secs] 172205K->36009K(506816K), 0.0385457 secs] [Times: user=0.03 sys=0.00, real=0.04 secs]
load module consume 6s : specclient
12-06 14:53:17.160 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.160 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.160 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.160 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.177 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.177 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.177 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.177 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.177 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.177 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.177 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 14:53:17.177 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
Calculate alarm summary of  6696 MOs cost 246 ms
[GC [DefNew: 150037K->10879K(157248K), 0.0408595 secs] 175785K->40522K(506816K), 0.0409079 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
PwResourceCache is ready : Fri Dec 06 14:53:22 CST 2013
LspResourceCache is ready : Fri Dec 06 14:53:22 CST 2013
WDMPORTMAP Cache is ready : Fri Dec 06 14:53:23 CST 2013
NMLFaultResourceCache is ready : Fri Dec 06 14:53:23 CST 2013
EMLFaultResourceCache is ready : Fri Dec 06 14:53:23 CST 2013
Name Cache is ready : Fri Dec 06 14:53:23 CST 2013
[GC [DefNew: 150655K->15014K(157248K), 0.0458627 secs] 180298K->46812K(506816K), 0.0459240 secs] [Times: user=0.03 sys=0.02, real=0.05 secs]
[Full GC [Tenured: 31798K->51104K(349568K), 0.1333943 secs] 113081K->51104K(506816K), [Perm : 16383K->16383K(16384K)], 0.1334573 secs] [Times: user=0.14 sys=0.00, real=0.16 secs]
[Full GC [Tenured: 51104K->55405K(349568K), 0.1240937 secs] 94621K->55405K(506816K), [Perm : 20479K->20479K(20480K)], 0.1241718 secs] [Times: user=0.13 sys=0.00, real=0.14 secs]
3)JVM参数 -Djava.library.path=./lib -Xms1024M -Xmx1024M -XX:+PrintGCDetails 服务器(10.4.120.121)
   NOTICE - file:/E:/cc/hz08744_dev_nbi/Orange/prj/build/classes/com/utstar/omco/main/Main.class
webstart check
false

initrd...
 Login:.
[GC [DefNew: 279616K->26624K(314560K), 0.0397958 secs] 279616K->26624K(1013632K), 0.0398488 secs] [Times: user=0.01 sys=0.01, real=0.03 secs]
[GC [DefNew: 306240K->6178K(314560K), 0.0582002 secs] 306240K->31368K(1013632K), 0.0582441 secs] [Times: user=0.06 sys=0.00, real=0.07 secs]
[GC [DefNew: 285794K->7239K(314560K), 0.0253906 secs] 310984K->32429K(1013632K), 0.0254367 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
load module consume 19s : specclient
12-06 15:00:12.500 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.631 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.631 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.631 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.647 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.647 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.647 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.647 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.647 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.647 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.647 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.647 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:00:12.664 INFO  [DefaultEngine.java:397] thread:MitClient ###   class:SyncTab ###   time_1:262
Calculate alarm summary of  6696 MOs cost 311 ms
count eml alarm cost time is 343
12-06 15:00:13.922 INFO  [DefaultEngine.java:397] thread:MitClient ###   class:SyncTab ###   time_1:147
[GC [DefNew: 286855K->15123K(314560K), 0.0489171 secs] 312045K->40313K(1013632K), 0.0489693 secs] [Times: user=0.05 sys=0.00, real=0.05 secs]
LspResourceCache is ready : Fri Dec 06 15:00:18 CST 2013
PwResourceCache is ready : Fri Dec 06 15:00:18 CST 2013
WDMPORTMAP Cache is ready : Fri Dec 06 15:00:19 CST 2013
EMLFaultResourceCache is ready : Fri Dec 06 15:00:19 CST 2013
NMLFaultResourceCache is ready : Fri Dec 06 15:00:19 CST 2013
12-06 15:00:19.774 INFO  [DefaultEngine.java:397] thread:NMLFaultResourceCache ###   class:NMLFaultResourceCache ###   time_1:131
Name Cache is ready : Fri Dec 06 15:00:19 CST 2013
[GC [DefNew: 294739K->21785K(314560K), 0.0566780 secs] 319929K->46975K(1013632K), 0.0567295 secs] [Times: user=0.06 sys=0.00, real=0.06 secs]
[Full GC [Tenured: 25190K->50910K(699072K), 0.1321287 secs] 100045K->50910K(1013632K), [Perm : 16383K->16383K(16384K)], 0.1321950 secs] [Times: user=0.14 sys=0.00, real=0.14 secs]
[Full GC [Tenured: 50910K->55240K(699072K), 0.1254004 secs] 107217K->55240K(1013632K), [Perm : 20479K->20479K(20480K)], 0.1254728 secs] [Times: user=0.13 sys=0.00, real=0.13 secs]
4)JVM参数 -Djava.library.path=./lib -Xms1470M -Xmx1470M -XX:+PrintGCDetails 服务器(10.4.120.121)
NOTICE - file:/E:/cc/hz08744_dev_nbi/Orange/prj/build/classes/com/utstar/omco/main/Main.class
webstart check
false

initrd...
 Login:.
[GC [DefNew: 401408K->29335K(451584K), 0.0550639 secs] 401408K->29335K(1455104K), 0.0553744 secs] [Times: user=0.05 sys=0.02, real=0.06 secs]
[GC [DefNew: 430743K->4477K(451584K), 0.0578339 secs] 430743K->32232K(1455104K), 0.0578794 secs] [Times: user=0.05 sys=0.00, real=0.05 secs]
12-06 15:06:07.028 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.044 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.044 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.044 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.044 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.044 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.044 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.044 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.044 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.060 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.060 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.060 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:06:07.060 INFO  [DefaultEngine.java:397] thread:MitClient ###   class:SyncTab ###   time_1:126
Calculate alarm summary of  6696 MOs cost 236 ms
[GC [DefNew: 405885K->12855K(451584K), 0.0506138 secs] 433640K->40610K(1455104K), 0.0506499 secs] [Times: user=0.05 sys=0.00, real=0.05 secs]
LspResourceCache is ready : Fri Dec 06 15:06:11 CST 2013
PwResourceCache is ready : Fri Dec 06 15:06:11 CST 2013
WDMPORTMAP Cache is ready : Fri Dec 06 15:06:12 CST 2013
NMLFaultResourceCache is ready : Fri Dec 06 15:06:12 CST 2013
EMLFaultResourceCache is ready : Fri Dec 06 15:06:12 CST 2013
Name Cache is ready : Fri Dec 06 15:06:12 CST 2013
[Full GC [Tenured: 27754K->51311K(1003520K), 0.1747874 secs] 326304K->51311K(1455104K), [Perm : 16383K->16383K(16384K)], 0.1748606 secs] [Times: user=0.14 sys=0.03, real=0.17 secs]
[Full GC [Tenured: 51311K->55639K(1003520K), 0.1759453 secs] 134697K->55639K(1455104K), [Perm : 20480K->20480K(20480K)], 0.1760154 secs] [Times: user=0.16 sys=0.00, real=0.16 secs]
12-06 15:06:19.220 INFO  [DefaultEngine.java:397] thread:AWT-EventQueue-0 ###   class: ###   time_1:110
5)JVM参数 -Djava.library.path=./lib -Xms256M -Xmx256M -XX:+PrintGCDetails -XX:+UseConcMarkSweepGC (使用ParNew+CMS+Serial Old的收集器组合,Serial Old作为CMS收集器出现Concurrent Mode Failure
失败后的后备收集器使用)服务器(10.4.120.121)
ParNew收集器是Serial收集器的多线程版本,除了使用多线程进行垃圾收集之外,其余行为包括所有控制参数、收集算法、Stop the world、对象分配原则、回收策略都与Serial差不多。
ParNew和Serial都是新生代收集器
调优后:和1)进行对比,减少了两次Full GC,新生代的垃圾回收差不多(Minor GC)
  老年代是新生代的担保,新生代使用复制收集算法,但是为了内存使用率,只使用其中一个Survivor空间作为轮换空间,当出现大量对象在Minor GC之后任然存活的话,最极端的就是内存回收之后
  所有对象都存活,就需要老年代进行担保,前提是老年代还有容纳这些对象剩余的空间。根据经验取每一次晋升到老年代的新生代对象值的平均值和老年代剩余的空间进行比较,如果老年代
  剩余的空间不足的话,就要进行Full GC。
  NOTICE - file:/E:/cc/hz08744_dev_nbi/Orange/prj/build/classes/com/utstar/omco/main/Main.class
[GC [ParNew: 69952K->1963K(78656K), 0.0179295 secs] 69952K->1963K(253440K), 0.0179900 secs] [Times: user=0.00 sys=0.00, real=0.05 secs]
webstart check
false

initrd...
 Login:.
[GC [ParNew: 55790K->5773K(78656K), 0.0147450 secs] 55790K->5773K(253440K), 0.0147819 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 75725K->5263K(78656K), 0.0441407 secs] 75725K->27182K(253440K), 0.0441851 secs] [Times: user=0.06 sys=0.00, real=0.06 secs]
[GC [ParNew: 75215K->8010K(78656K), 0.0157413 secs] 97134K->29929K(253440K), 0.0157789 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
[GC [ParNew: 77962K->7337K(78656K), 0.0214456 secs] 99881K->32229K(253440K), 0.0214880 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
[GC [ParNew: 77289K->7082K(78656K), 0.0158418 secs] 102181K->33715K(253440K), 0.0158786 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 77034K->5147K(78656K), 0.0107139 secs] 103667K->31780K(253440K), 0.0107602 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC [ParNew: 75099K->4031K(78656K), 0.0136844 secs] 101732K->33606K(253440K), 0.0137271 secs] [Times: user=0.02 sys=0.00, real=0.01 secs]
[GC [ParNew: 73983K->2482K(78656K), 0.0055753 secs] 103558K->32057K(253440K), 0.0056105 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
[GC [ParNew: 72434K->3667K(78656K), 0.0071360 secs] 102009K->33242K(253440K), 0.0071759 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC [ParNew: 73619K->4836K(78656K), 0.0074599 secs] 103194K->34411K(253440K), 0.0074966 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
[GC [ParNew: 74788K->4276K(78656K), 0.0086984 secs] 104363K->34608K(253440K), 0.0087389 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC [ParNew: 74228K->4877K(78656K), 0.0135708 secs] 104560K->35966K(253440K), 0.0136090 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
load module consume 19s : specclient
12-06 15:21:31.558 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.619 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.619 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.619 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.619 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.619 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.619 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.619 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.635 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.635 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.635 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.635 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 15:21:31.650 INFO  [DefaultEngine.java:397] thread:MitClient ###   class:SyncTab ###   time_1:184
Calculate alarm summary of  6696 MOs cost 261 ms
[GC [ParNew: 74829K->8704K(78656K), 0.0253643 secs] 105918K->40862K(253440K), 0.0254093 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
count eml alarm cost time is 276
[GC [ParNew: 78656K->7121K(78656K), 0.0306950 secs] 110814K->47935K(253440K), 0.0307573 secs] [Times: user=0.08 sys=0.00, real=0.05 secs]
[GC [ParNew: 77073K->1854K(78656K), 0.0034468 secs] 117887K->42668K(253440K), 0.0034963 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
PwResourceCache is ready : Fri Dec 06 15:21:36 CST 2013
LspResourceCache is ready : Fri Dec 06 15:21:36 CST 2013
WDMPORTMAP Cache is ready : Fri Dec 06 15:21:38 CST 2013
NMLFaultResourceCache is ready : Fri Dec 06 15:21:38 CST 2013
EMLFaultResourceCache is ready : Fri Dec 06 15:21:38 CST 2013
Name Cache is ready : Fri Dec 06 15:21:38 CST 2013
[GC [ParNew: 71806K->6455K(78656K), 0.0150287 secs] 112620K->47269K(253440K), 0.0150785 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
[GC [ParNew: 76407K->4668K(78656K), 0.0227762 secs] 117221K->51417K(253440K), 0.0228231 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
[GC [ParNew: 74620K->8704K(78656K), 0.0400156 secs] 121369K->60963K(253440K), 0.0400666 secs] [Times: user=0.06 sys=0.00, real=0.05 secs]
Heap
 par new generation   total 78656K, used 67842K [0x02ce0000, 0x08230000, 0x08230000)
  eden space 69952K,  84% used [0x02ce0000, 0x066a08e0, 0x07130000) eden区只使用了84%
  from space 8704K, 100% used [0x079b0000, 0x08230000, 0x08230000)
  to   space 8704K,   0% used [0x07130000, 0x07130000, 0x079b0000)
 concurrent mark-sweep generation total 174784K, used 52259K [0x08230000, 0x12ce0000, 0x12ce0000)
 concurrent-mark-sweep perm gen total 21504K, used 21399K [0x12ce0000, 0x141e0000, 0x16ce0000)
 6)JVM参数 -Djava.library.path=./lib -Xms512M -Xmx512M -XX:+PrintGCDetails -XX:+UseConcMarkSweepGC
 和2)进行比较也是减少了两次,Full GC,这个是一次很大的改进。
 堆内存使用量从90M,开始一直往上涨,涨到了125M左右,还是有上升的趋势,估计是启动的时候,是使用的单线程,但是在这个线程下面,又挂了不少子线程,会占资源。
   NOTICE - file:/E:/cc/hz08744_dev_nbi/Orange/prj/build/classes/com/utstar/omco/main/Main.class
webstart check
false

initrd...
 Login:.
[GC [ParNew: 104960K->2973K(118016K), 0.0115762 secs] 104960K->2973K(511232K), 0.0116281 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
[GC [ParNew: 107933K->6796K(118016K), 0.0299427 secs] 107933K->27277K(511232K), 0.0299855 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
[GC [ParNew: 111756K->10427K(118016K), 0.0252389 secs] 132237K->30909K(511232K), 0.0252803 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 115387K->11182K(118016K), 0.0354881 secs] 135869K->36591K(511232K), 0.0355275 secs] [Times: user=0.08 sys=0.00, real=0.05 secs]
[GC [ParNew: 116142K->7596K(118016K), 0.0168701 secs] 141551K->33005K(511232K), 0.0169071 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 112556K->8010K(118016K), 0.0144648 secs] 137965K->33419K(511232K), 0.0145121 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 112970K->9022K(118016K), 0.0167721 secs] 138379K->34431K(511232K), 0.0168185 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 113982K->6867K(118016K), 0.0219040 secs] 139391K->36382K(511232K), 0.0219627 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
[GC [ParNew: 111827K->8985K(118016K), 0.0175180 secs] 141342K->38500K(511232K), 0.0175656 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
12-06 16:14:53.541 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.541 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.541 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.541 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.541 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.557 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.557 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.557 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.557 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.557 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.557 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:14:53.557 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
Calculate alarm summary of  6696 MOs cost 280 ms
[GC [ParNew: 113945K->11439K(118016K), 0.0279289 secs] 143460K->41973K(511232K), 0.0279720 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
LspResourceCache is ready : Fri Dec 06 16:14:58 CST 2013
PwResourceCache is ready : Fri Dec 06 16:14:58 CST 2013
WDMPORTMAP Cache is ready : Fri Dec 06 16:14:58 CST 2013
NMLFaultResourceCache is ready : Fri Dec 06 16:14:58 CST 2013
EMLFaultResourceCache is ready : Fri Dec 06 16:14:58 CST 2013
Name Cache is ready : Fri Dec 06 16:14:59 CST 2013
[GC [ParNew: 116399K->13056K(118016K), 0.0429375 secs] 146933K->50215K(511232K), 0.0429997 secs] [Times: user=0.09 sys=0.00, real=0.05 secs]
[GC [ParNew: 118016K->13056K(118016K), 0.0311745 secs] 155175K->54387K(511232K), 0.0312257 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
7)JVM参数 -Djava.library.path=./lib -Xms1024M -Xmx1024M -XX:+PrintGCDetails -XX:+UseConcMarkSweepGC
和3)进行对比也是减少了两次Full GC但是增加了告警计算时间,这个是正常的,因为现在是使用多线程的垃圾收集器,自然会有点影响其他的模块。
NOTICE - file:/E:/cc/hz08744_dev_nbi/Orange/prj/build/classes/com/utstar/omco/main/Main.class
webstart check
false

initrd...
 Login:.
[GC [ParNew: 104960K->2663K(118016K), 0.0138260 secs] 104960K->2663K(1035520K), 0.0138805 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 107623K->6122K(118016K), 0.0324849 secs] 107623K->26603K(1035520K), 0.0325301 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
[GC [ParNew: 111082K->11758K(118016K), 0.0312949 secs] 131563K->32239K(1035520K), 0.0313350 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
[GC [ParNew: 116718K->12537K(118016K), 0.0382779 secs] 137199K->37914K(1035520K), 0.0383263 secs] [Times: user=0.05 sys=0.01, real=0.05 secs]
[GC [ParNew: 117497K->7967K(118016K), 0.0210473 secs] 142874K->33344K(1035520K), 0.0210823 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
[GC [ParNew: 112927K->8100K(118016K), 0.0170796 secs] 138304K->33477K(1035520K), 0.0171249 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
[GC [ParNew: 113060K->8936K(118016K), 0.0197029 secs] 138437K->34313K(1035520K), 0.0197447 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
[GC [ParNew: 113896K->10251K(118016K), 0.0244481 secs] 139273K->38693K(1035520K), 0.0244867 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 115211K->8373K(118016K), 0.0209777 secs] 143653K->36815K(1035520K), 0.0210134 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
12-06 16:20:52.703 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.703 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.703 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.703 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.719 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.719 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.719 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.719 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.719 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.719 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.719 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:20:52.719 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
Calculate alarm summary of  6696 MOs cost 312 ms
[GC [ParNew: 113333K->13056K(118016K), 0.0339688 secs] 141775K->43493K(1035520K), 0.0340094 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
LspResourceCache is ready : Fri Dec 06 16:20:57 CST 2013
PwResourceCache is ready : Fri Dec 06 16:20:57 CST 2013
WDMPORTMAP Cache is ready : Fri Dec 06 16:20:58 CST 2013
NMLFaultResourceCache is ready : Fri Dec 06 16:20:58 CST 2013
EMLFaultResourceCache is ready : Fri Dec 06 16:20:58 CST 2013
Name Cache is ready : Fri Dec 06 16:20:58 CST 2013
[GC [ParNew: 118016K->13056K(118016K), 0.0438419 secs] 148453K->49503K(1035520K), 0.0439071 secs] [Times: user=0.09 sys=0.00, real=0.05 secs]
[GC [ParNew: 118016K->13056K(118016K), 0.0352278 secs] 154463K->54420K(1035520K), 0.0352847 secs] [Times: user=0.08 sys=0.00, real=0.05 secs]
[GC [ParNew: 118016K->13056K(118016K), 0.1309869 secs] 159380K->75432K(1035520K), 0.1310519 secs] [Times: user=0.16 sys=0.00, real=0.13 secs]
8)JVM参数 -Djava.library.path=./lib -Xms1470M -Xmx1470M -XX:+PrintGCDetails -XX:+UseConcMarkSweepGC 服务器都是10.4.120.121
   与4)进行对比减少了两次Full GC,并且MinorGC的时间明显缩短了。
   NOTICE - file:/E:/cc/hz08744_dev_nbi/Orange/prj/build/classes/com/utstar/omco/main/Main.class
webstart check
false

initrd...
 Login:.
[GC [ParNew: 104960K->2648K(118016K), 0.0173783 secs] 104960K->2648K(1492224K), 0.0174298 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
[GC [ParNew: 107608K->6111K(118016K), 0.0353931 secs] 107608K->26592K(1492224K), 0.0354403 secs] [Times: user=0.08 sys=0.00, real=0.05 secs]
[GC [ParNew: 111071K->11741K(118016K), 0.0332955 secs] 131552K->32222K(1492224K), 0.0333378 secs] [Times: user=0.09 sys=0.00, real=0.05 secs]
[GC [ParNew: 116701K->12490K(118016K), 0.0401498 secs] 137182K->37862K(1492224K), 0.0402063 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
[GC [ParNew: 117450K->7949K(118016K), 0.0230252 secs] 142822K->33322K(1492224K), 0.0230653 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 112909K->10812K(118016K), 0.0207827 secs] 138282K->36184K(1492224K), 0.0208232 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 115772K->9144K(118016K), 0.0223053 secs] 141144K->34517K(1492224K), 0.0223440 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
[GC [ParNew: 114104K->9409K(118016K), 0.0266254 secs] 139477K->37831K(1492224K), 0.0266816 secs] [Times: user=0.03 sys=0.00, real=0.02 secs]
[GC [ParNew: 114369K->9664K(118016K), 0.0247118 secs] 142791K->38086K(1492224K), 0.0247480 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
12-06 16:26:08.239 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.239 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.239 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.239 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1118\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.239 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.239 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.239 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.239 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1207\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.255 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=19 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.255 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=26 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.255 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=28 key=s spec=T1SlotSvc24.V8 name= alm={0,0,0,0,0}{0,0,0,0,0}
12-06 16:26:08.255 ERROR [MitClientMod.java:700] localAddNode failed:fid=\e=1277\s=35 key=s spec=T1SlotJMP1T63.V0 name= alm={0,0,0,0,0}{0,0,0,0,0}
Calculate alarm summary of  6696 MOs cost 234 ms
[GC [ParNew: 114624K->12743K(118016K), 0.0361266 secs] 143046K->43095K(1492224K), 0.0361731 secs] [Times: user=0.09 sys=0.00, real=0.05 secs]
LspResourceCache is ready : Fri Dec 06 16:26:12 CST 2013
PwResourceCache is ready : Fri Dec 06 16:26:12 CST 2013
WDMPORTMAP Cache is ready : Fri Dec 06 16:26:13 CST 2013
NMLFaultResourceCache is ready : Fri Dec 06 16:26:13 CST 2013
EMLFaultResourceCache is ready : Fri Dec 06 16:26:13 CST 2013
Name Cache is ready : Fri Dec 06 16:26:13 CST 2013
[GC [ParNew: 117703K->13056K(118016K), 0.0465774 secs] 148055K->49336K(1492224K), 0.0466458 secs] [Times: user=0.09 sys=0.00, real=0.05 secs]
[GC [ParNew: 118016K->13056K(118016K), 0.0384525 secs] 154296K->54356K(1492224K), 0.0385240 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
[GC [ParNew: 118016K->13056K(118016K), 0.1411954 secs] 159316K->74479K(1492224K), 0.1412527 secs] [Times: user=0.17 sys=0.00, real=0.14 secs]