concurrent mode failure in minor gc/ -XX:CMSFullGCsBeforeCompaction=1

Jon Masamitsu Jon.Masamitsu at Sun.COM
Sun Apr 26 22:47:25 PDT 2009


The behavior of CMS with regard to concurrent mode failures
has changed quite a bit since 1.4.2. Since this is a mailing list
for the open source jdk (currently jdk7), you might get better
1.4.2 info from your Sun support.

vasu ts wrote On 04/25/09 06:21,:

> 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. Check it
> out.
> <http://windowslive.com/online/skydrive?ocid=TXT_TAGLM_WL_skydrive_042009>
>
>
>------------------------------------------------------------------------
>
>_______________________________________________
>hotspot-gc-use mailing list
>hotspot-gc-use at openjdk.java.net
>http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use
>  
>


_______________________________________________
hotspot-gc-use mailing list
hotspot-gc-use at openjdk.java.net
http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use



More information about the hotspot-gc-dev mailing list