concurrent mode failure in minor gc/ -XX:CMSFullGCsBeforeCompaction=1
vasu ts
vasu_t_s at hotmail.com
Sat Apr 25 06:21:52 PDT 2009
Hi all,
We have an application which is deployed on IBM websphere 5.1/Solaris 5.9/Sun hotspot JRE1.4.2_17/. We have 4 JVM's which are running on the same machine. These JVM's recieve xml messages from MQ queue which are processed ( business logic stores the data from xml into database) and xml replies are sent back to the MQ queue. In our application, we generate lots of objects (xml marshalling and unmarshalling) which are never re-used.Our goal is to sustain the application for 24 hrs with a steady load of 2500 users.
Hardware
8 dual core - sparc IV
4 single core - sparc III
1)
Options used :
-server -Xmx768m -Xms768m -XX:MaxNewSize=256m -XX:NewSize=256m -XX:+UseParNewGC -XX:+CMSParallelRemarkEnabled -XX:+UseConcMarkSweepGC -XX:CMSInitiatingOccupancyFraction=60 -XX:+UseCMSInitiatingOccupancyOnly
We did the stress test with -XX:CMSInitiatingOccupancyFraction=60 flag and what we saw was that initially, CMS collector was adhering to the setting and was starting a full GC, when old gen is 60% full. But when we reach the steady load of 2500 users seems like CMS collector cannot keep up with allocation rate and thus it cannot do a Full GC, when it is 60% full. See attached excel which shows how much old gen % was filled up. Also, I have attached the native logs (native_stdout_option_1.log) from this test.
Once the old gen is 94% full, there is a concurrent mode failure which results in a compaction which takes 21.2 secs. Please note we saw CPU spikes (80-99% usage) when the old gen was filling up. We did not continue the test further because we thought we will eventually end up with the several compactions over a period of time.
2)
Options used:
-server -Xmx768m -Xms768m -XX:MaxNewSize=256m -XX:NewSize=256m -XX:+UseParNewGC -XX:+CMSParallelRemarkEnabled -XX:+UseConcMarkSweepGC -XX:CMSInitiatingOccupancyFraction=60 -XX:+UseCMSInitiatingOccupancyOnly -XX:CMSFullGCsBeforeCompaction=1
To reduce the compactions we added -XX:CMSFullGCsBeforeCompaction=1 and tested our application again. We saw that there were several "concurrent mode failure" when minor collections were happening. Any ideas?, why this is happening so early into the test (23 secs into the test). See below snapshot from the native logs. Also, I see that the survivor ratio is only being set to 192K , Is this the default size with CMS collector?. Next week, we will see if increasing the heap size and tweaking the survivor ratio helps the application.
thanks
vasu..
23.144: [GC {Heap before GC invocations=0:
Heap
par new generation total 261952K, used 261754K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 99% used [0xc4800000, 0xd479eb68, 0xd47a0000)
from space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
to space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
concurrent mark-sweep generation total 524288K, used 0K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 28544K, used 28479K [0xf4800000, 0xf63e0000, 0xf8800000)
23.147: [ParNew: 261754K->0K(261952K), 0.1828999 secs] 261754K->9956K(786240K) Heap after GC invocations=1:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 9956K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 28544K, used 28479K [0xf4800000, 0xf63e0000, 0xf8800000)
} , 0.1843301 secs]
34.492: [GC {Heap before GC invocations=1:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 9956K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 35200K, used 35085K [0xf4800000, 0xf6a60000, 0xf8800000)
34.492: [ParNew: 261760K->261760K(261952K), 0.0001138 secs]34.493: [CMS (concurrent mode failure)[Unloading class com.ibm.ws.Transaction.JTA.XARecUtil]
: 9956K->17291K(524288K), 2.1463156 secs] 271716K->17291K(786240K) Heap after GC invocations=2:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 17291K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 35200K, used 35023K [0xf4800000, 0xf6a60000, 0xf8800000)
} , 2.1476960 secs]
495.514: [GC {Heap before GC invocations=2:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 17291K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 36096K, used 35999K [0xf4800000, 0xf6b40000, 0xf8800000)
495.515: [ParNew: 261760K->261760K(261952K), 0.0001167 secs]495.515: [CMS (concurrent mode failure): 17291K->20296K(524288K), 1.7551403 secs] 279051K->20296K(786240K) Heap after GC invocations=3:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 20296K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 36096K, used 35983K [0xf4800000, 0xf6b40000, 0xf8800000)
} , 1.7565744 secs]
660.887: [GC {Heap before GC invocations=3:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 20296K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 36992K, used 36930K [0xf4800000, 0xf6c20000, 0xf8800000)
660.888: [ParNew: 261760K->261760K(261952K), 0.0001172 secs]660.888: [CMS (concurrent mode failure): 20296K->23930K(524288K), 1.9326939 secs] 282056K->23930K(786240K) Heap after GC invocations=4:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 23930K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 36992K, used 36908K [0xf4800000, 0xf6c20000, 0xf8800000)
} , 1.9341771 secs]
755.905: [GC {Heap before GC invocations=4:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 23930K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38528K, used 38413K [0xf4800000, 0xf6da0000, 0xf8800000)
755.906: [ParNew: 261760K->261760K(261952K), 0.0001159 secs]755.906: [CMS (concurrent mode failure): 23930K->23518K(524288K), 1.9600006 secs] 285690K->23518K(786240K) Heap after GC invocations=5:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 23518K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38528K, used 38377K [0xf4800000, 0xf6da0000, 0xf8800000)
} , 1.9614396 secs]
832.698: [GC {Heap before GC invocations=5:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 23518K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38633K [0xf4800000, 0xf6de0000, 0xf8800000)
832.699: [ParNew: 261760K->261760K(261952K), 0.0001141 secs]832.699: [CMS (concurrent mode failure)[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor1]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor22]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor21]
: 23518K->22650K(524288K), 2.0814234 secs] 285278K->22650K(786240K) Heap after GC invocations=6:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 22650K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38563K [0xf4800000, 0xf6de0000, 0xf8800000)
} , 2.0828510 secs]
900.246: [GC {Heap before GC invocations=6:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 22650K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38664K [0xf4800000, 0xf6de0000, 0xf8800000)
900.247: [ParNew: 261760K->261760K(261952K), 0.0001151 secs]900.247: [CMS (concurrent mode failure)[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor15]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor40]
[Unloading class sun.reflect.GeneratedMethodAccessor2]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor36]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor20]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor34]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor31]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor29]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor16]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor13]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor39]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor27]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor32]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor67]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor10]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor35]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor17]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor11]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor26]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor28]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor18]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor38]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor33]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor25]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor6]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor7]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor37]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor9]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor8]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor4]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor42]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor30]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor3]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor14]
: 22650K->21627K(524288K), 2.0554604 secs] 284410K->21627K(786240K) Heap after GC invocations=7:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21627K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38530K [0xf4800000, 0xf6de0000, 0xf8800000)
} , 2.0569150 secs]
973.776: [GC {Heap before GC invocations=7:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21627K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38606K [0xf4800000, 0xf6de0000, 0xf8800000)
973.777: [ParNew: 261760K->261760K(261952K), 0.0001188 secs]973.777: [CMS (concurrent mode failure): 21627K->21663K(524288K), 1.9085358 secs] 283387K->21663K(786240K) Heap after GC invocations=8:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21663K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38573K [0xf4800000, 0xf6de0000, 0xf8800000)
} , 1.9100083 secs]
1044.718: [GC {Heap before GC invocations=8:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21663K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38647K [0xf4800000, 0xf6de0000, 0xf8800000)
1044.719: [ParNew: 261760K->261760K(261952K), 0.0001185 secs]1044.719: [CMS (concurrent mode failure): 21663K->21546K(524288K), 1.8343919 secs] 283423K->21546K(786240K) Heap after GC invocations=9:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21546K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38615K [0xf4800000, 0xf6de0000, 0xf8800000)
} , 1.8358148 secs]
1115.795: [GC {Heap before GC invocations=9:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21546K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38701K [0xf4800000, 0xf6de0000, 0xf8800000)
1115.795: [ParNew: 261760K->261760K(261952K), 0.0001158 secs]1115.796: [CMS (concurrent mode failure): 21546K->20650K(524288K), 1.8198966 secs] 283306K->20650K(786240K) Heap after GC invocations=10:
primadm at condo102# head -200 native_stdout.log |more
23.144: [GC {Heap before GC invocations=0:
Heap
par new generation total 261952K, used 261754K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 99% used [0xc4800000, 0xd479eb68, 0xd47a0000)
from space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
to space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
concurrent mark-sweep generation total 524288K, used 0K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 28544K, used 28479K [0xf4800000, 0xf63e0000, 0xf8800000)
23.147: [ParNew: 261754K->0K(261952K), 0.1828999 secs] 261754K->9956K(786240K) Heap after GC invoca
tions=1:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 9956K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 28544K, used 28479K [0xf4800000, 0xf63e0000, 0xf8800000)
} , 0.1843301 secs]
34.492: [GC {Heap before GC invocations=1:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 9956K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 35200K, used 35085K [0xf4800000, 0xf6a60000, 0xf8800000)
34.492: [ParNew: 261760K->261760K(261952K), 0.0001138 secs]34.493: [CMS (concurrent mode failure)[U
nloading class com.ibm.ws.Transaction.JTA.XARecUtil]
: 9956K->17291K(524288K), 2.1463156 secs] 271716K->17291K(786240K) Heap after GC invocations=2:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 17291K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 35200K, used 35023K [0xf4800000, 0xf6a60000, 0xf8800000)
} , 2.1476960 secs]
495.514: [GC {Heap before GC invocations=2:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 17291K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 36096K, used 35999K [0xf4800000, 0xf6b40000, 0xf8800000)
495.515: [ParNew: 261760K->261760K(261952K), 0.0001167 secs]495.515: [CMS (concurrent mode failure)
: 17291K->20296K(524288K), 1.7551403 secs] 279051K->20296K(786240K) Heap after GC invocations=3:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 20296K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 36096K, used 35983K [0xf4800000, 0xf6b40000, 0xf8800000)
} , 1.7565744 secs]
660.887: [GC {Heap before GC invocations=3:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 20296K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 36992K, used 36930K [0xf4800000, 0xf6c20000, 0xf8800000)
660.888: [ParNew: 261760K->261760K(261952K), 0.0001172 secs]660.888: [CMS (concurrent mode failure)
: 20296K->23930K(524288K), 1.9326939 secs] 282056K->23930K(786240K) Heap after GC invocations=4:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 23930K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 36992K, used 36908K [0xf4800000, 0xf6c20000, 0xf8800000)
} , 1.9341771 secs]
755.905: [GC {Heap before GC invocations=4:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 23930K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38528K, used 38413K [0xf4800000, 0xf6da0000, 0xf8800000)
755.906: [ParNew: 261760K->261760K(261952K), 0.0001159 secs]755.906: [CMS (concurrent mode failure)
: 23930K->23518K(524288K), 1.9600006 secs] 285690K->23518K(786240K) Heap after GC invocations=5:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 23518K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38528K, used 38377K [0xf4800000, 0xf6da0000, 0xf8800000)
} , 1.9614396 secs]
832.698: [GC {Heap before GC invocations=5:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 23518K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38633K [0xf4800000, 0xf6de0000, 0xf8800000)
832.699: [ParNew: 261760K->261760K(261952K), 0.0001141 secs]832.699: [CMS (concurrent mode failure)
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor1]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor22]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor21]
: 23518K->22650K(524288K), 2.0814234 secs] 285278K->22650K(786240K) Heap after GC invocations=6:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 22650K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38563K [0xf4800000, 0xf6de0000, 0xf8800000)
} , 2.0828510 secs]
900.246: [GC {Heap before GC invocations=6:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 22650K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38664K [0xf4800000, 0xf6de0000, 0xf8800000)
900.247: [ParNew: 261760K->261760K(261952K), 0.0001151 secs]900.247: [CMS (concurrent mode failure)
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor15]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor40]
[Unloading class sun.reflect.GeneratedMethodAccessor2]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor36]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor20]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor34]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor31]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor29]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor16]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor13]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor39]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor27]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor32]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor67]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor10]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor35]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor17]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor11]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor26]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor28]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor18]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor38]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor33]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor25]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor6]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor7]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor37]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor9]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor8]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor4]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor42]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor30]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor3]
[Unloading class sun.reflect.GeneratedSerializationConstructorAccessor14]
: 22650K->21627K(524288K), 2.0554604 secs] 284410K->21627K(786240K) Heap after GC invocations=7:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21627K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38530K [0xf4800000, 0xf6de0000, 0xf8800000)
} , 2.0569150 secs]
973.776: [GC {Heap before GC invocations=7:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21627K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38606K [0xf4800000, 0xf6de0000, 0xf8800000)
973.777: [ParNew: 261760K->261760K(261952K), 0.0001188 secs]973.777: [CMS (concurrent mode failure)
: 21627K->21663K(524288K), 1.9085358 secs] 283387K->21663K(786240K) Heap after GC invocations=8:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21663K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38573K [0xf4800000, 0xf6de0000, 0xf8800000)
} , 1.9100083 secs]
1044.718: [GC {Heap before GC invocations=8:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21663K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38647K [0xf4800000, 0xf6de0000, 0xf8800000)
1044.719: [ParNew: 261760K->261760K(261952K), 0.0001185 secs]1044.719: [CMS (concurrent mode failur
e): 21663K->21546K(524288K), 1.8343919 secs] 283423K->21546K(786240K) Heap after GC invocations=9:
Heap
par new generation total 261952K, used 0K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 0% used [0xc4800000, 0xc4800000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21546K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38615K [0xf4800000, 0xf6de0000, 0xf8800000)
} , 1.8358148 secs]
1115.795: [GC {Heap before GC invocations=9:
Heap
par new generation total 261952K, used 261760K [0xc4800000, 0xd4800000, 0xd4800000)
eden space 261760K, 100% used [0xc4800000, 0xd47a0000, 0xd47a0000)
from space 192K, 0% used [0xd47d0000, 0xd47d0000, 0xd4800000)
to space 192K, 0% used [0xd47a0000, 0xd47a0000, 0xd47d0000)
concurrent mark-sweep generation total 524288K, used 21546K [0xd4800000, 0xf4800000, 0xf4800000)
concurrent-mark-sweep perm gen total 38784K, used 38701K [0xf4800000, 0xf6de0000, 0xf8800000)
1115.795: [ParNew: 261760K->261760K(261952K), 0.0001158 secs]1115.796: [CMS (concurrent mode failur
e): 21546K->20650K(524288K), 1.8198966 secs] 283306K->20650K(786240K) Heap after GC invocations=10:
_________________________________________________________________
Windows Live™ SkyDrive™: Get 25 GB of free online storage.
http://windowslive.com/online/skydrive?ocid=TXT_TAGLM_WL_skydrive_042009
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://mail.openjdk.java.net/pipermail/hotspot-gc-use/attachments/20090425/1103810a/attachment.html
-------------- next part --------------
A non-text attachment was scrubbed...
Name: gcanalysis_option1.xls
Type: application/vnd.ms-excel
Size: 192000 bytes
Desc: not available
Url : http://mail.openjdk.java.net/pipermail/hotspot-gc-use/attachments/20090425/1103810a/attachment.xls
More information about the hotspot-gc-use
mailing list