April 2009 - hotspot-gc-dev

hg: jdk7/hotspot-gc/hotspot: 6819098: G1: reduce RSet scanning times
by igor.veresov＠sun.com 27 Apr '09

27 Apr '09

Changeset: b803b1b9e206 Author: iveresov Date: 2009-04-27 16:52 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/b803b1b9e206 6819098: G1: reduce RSet scanning times Summary: Added a feedback-driven exponential skipping for parallel RSet scanning. Reviewed-by: tonyp, apetrusenko ! src/share/vm/gc_implementation/g1/g1RemSet.cpp

1 0

hg: jdk7/hotspot-gc/hotspot: 21 new changesets
by andrey.petrusenko＠sun.com 27 Apr '09

27 Apr '09

Changeset: f8e839c08615 Author: xdono Date: 2009-04-09 10:37 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/f8e839c08615 Added tag jdk7-b54 for changeset fafab5d5349c ! .hgtags Changeset: bcbec53c367d Author: xdono Date: 2009-04-16 11:22 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/bcbec53c367d Added tag jdk7-b55 for changeset f8e839c08615 ! .hgtags Changeset: a3fd9e40ff2e Author: trims Date: 2009-04-21 15:08 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/a3fd9e40ff2e Merge Changeset: c8152ae3f339 Author: coleenp Date: 2009-04-21 16:12 -0400 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/c8152ae3f339 6830069: UseLargePages is broken on Win64 Summary: Making VirtualAlloc/VirtualProtect two calls for PAGE_EXECUTE_READWRITE doesn't work for MEM_LARGE_PAGES. Reviewed-by: xlu, kvn, jcoomes ! src/os/windows/vm/os_windows.cpp Changeset: 670013185256 Author: xlu Date: 2009-04-22 11:47 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/670013185256 Merge Changeset: a61730a6fdbc Author: trims Date: 2009-04-22 19:30 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/a61730a6fdbc 6833316: jprt.properties not setting values for 6u14 release flag Summary: Fix jprt.properties to do 6u14 tests right Reviewed-by: ohair ! make/jprt.properties Changeset: 67a2f5ba5582 Author: never Date: 2009-04-15 09:53 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/67a2f5ba5582 6684007: PrintAssembly plugin not available for linux or windows Reviewed-by: rasbold, jrose, twisti ! .hgignore ! make/windows/makefiles/vm.make ! src/share/tools/MakeDeps/BuildConfig.java ! src/share/tools/hsdis/Makefile ! src/share/tools/hsdis/README ! src/share/tools/hsdis/hsdis-demo.c ! src/share/tools/hsdis/hsdis.c Changeset: 1b42d5772ae0 Author: never Date: 2009-04-16 10:40 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/1b42d5772ae0 6449385: JCK test dup2_x200106m1 fails with Segmentation Fault on x86 Reviewed-by: kvn ! src/os_cpu/solaris_x86/vm/globals_solaris_x86.hpp Changeset: a134d9824964 Author: never Date: 2009-04-16 15:50 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/a134d9824964 6828024: verification of fixed interval usage is too weak Reviewed-by: kvn ! src/share/vm/c1/c1_LinearScan.cpp ! src/share/vm/includeDB_compiler1 Changeset: 3ec1ff9307d6 Author: never Date: 2009-04-16 21:25 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/3ec1ff9307d6 6741757: minor ctw improvements Reviewed-by: kvn ! src/share/vm/classfile/classLoader.cpp Changeset: 2bf529ef0adb Author: kvn Date: 2009-04-17 09:38 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/2bf529ef0adb 6831323: Use v8plus as minimum required hardware for current Hotspot sources Summary: Use -xarch=v8plus as default for 32-bits VM on sparc. Reviewed-by: never, twisti ! make/solaris/makefiles/sparcWorks.make Changeset: 928912ce8438 Author: never Date: 2009-04-20 14:48 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/928912ce8438 Merge Changeset: be93aad57795 Author: jrose Date: 2009-04-21 23:21 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/be93aad57795 6655646: dynamic languages need dynamically linked call sites Summary: invokedynamic instruction (JSR 292 RI) Reviewed-by: twisti, never ! src/cpu/sparc/vm/templateInterpreter_sparc.cpp ! src/cpu/sparc/vm/templateTable_sparc.cpp ! src/cpu/x86/vm/interp_masm_x86_32.cpp ! src/cpu/x86/vm/interp_masm_x86_32.hpp ! src/cpu/x86/vm/templateInterpreter_x86_32.cpp ! src/cpu/x86/vm/templateInterpreter_x86_64.cpp ! src/cpu/x86/vm/templateTable_x86_32.cpp ! src/cpu/x86/vm/templateTable_x86_32.hpp ! src/cpu/x86/vm/templateTable_x86_64.cpp ! src/share/vm/c1/c1_GraphBuilder.cpp ! src/share/vm/ci/bcEscapeAnalyzer.cpp ! src/share/vm/ci/ciStreams.cpp ! src/share/vm/ci/ciStreams.hpp ! src/share/vm/classfile/javaClasses.cpp ! src/share/vm/classfile/javaClasses.hpp ! src/share/vm/classfile/systemDictionary.cpp ! src/share/vm/classfile/systemDictionary.hpp ! src/share/vm/classfile/verifier.cpp ! src/share/vm/classfile/vmSymbols.hpp ! src/share/vm/includeDB_core ! src/share/vm/includeDB_gc_parallel ! src/share/vm/includeDB_jvmti ! src/share/vm/interpreter/abstractInterpreter.hpp ! src/share/vm/interpreter/bytecode.cpp ! src/share/vm/interpreter/bytecode.hpp ! src/share/vm/interpreter/bytecodeStream.hpp ! src/share/vm/interpreter/bytecodeTracer.cpp ! src/share/vm/interpreter/bytecodes.cpp ! src/share/vm/interpreter/bytecodes.hpp ! src/share/vm/interpreter/interpreterRuntime.cpp ! src/share/vm/interpreter/interpreterRuntime.hpp ! src/share/vm/interpreter/linkResolver.cpp ! src/share/vm/interpreter/linkResolver.hpp ! src/share/vm/interpreter/rewriter.cpp ! src/share/vm/interpreter/rewriter.hpp ! src/share/vm/interpreter/templateInterpreter.cpp ! src/share/vm/interpreter/templateInterpreter.hpp ! src/share/vm/interpreter/templateInterpreterGenerator.hpp ! src/share/vm/interpreter/templateTable.cpp ! src/share/vm/interpreter/templateTable.hpp ! src/share/vm/oops/constantPoolKlass.cpp ! src/share/vm/oops/constantPoolOop.cpp ! src/share/vm/oops/constantPoolOop.hpp ! src/share/vm/oops/cpCacheKlass.cpp ! src/share/vm/oops/cpCacheOop.cpp ! src/share/vm/oops/cpCacheOop.hpp ! src/share/vm/oops/generateOopMap.cpp ! src/share/vm/oops/instanceKlass.hpp ! src/share/vm/oops/instanceKlassKlass.cpp ! src/share/vm/oops/methodDataOop.cpp ! src/share/vm/oops/methodOop.cpp ! src/share/vm/oops/methodOop.hpp ! src/share/vm/opto/bytecodeInfo.cpp ! src/share/vm/opto/doCall.cpp ! src/share/vm/opto/graphKit.cpp ! src/share/vm/opto/parse1.cpp ! src/share/vm/opto/parse2.cpp ! src/share/vm/opto/parseHelper.cpp ! src/share/vm/prims/jvm.cpp ! src/share/vm/prims/jvmtiClassFileReconstituter.cpp ! src/share/vm/prims/methodComparator.cpp ! src/share/vm/prims/methodHandles.cpp ! src/share/vm/runtime/arguments.cpp ! src/share/vm/runtime/globals.hpp ! src/share/vm/utilities/globalDefinitions.hpp Changeset: 6b2273dd6fa9 Author: twisti Date: 2009-04-21 11:16 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/6b2273dd6fa9 6822110: Add AddressLiteral class on SPARC Summary: The Address class on SPARC currently handles both, addresses and address literals, what makes the Address class more complicated than it has to be. Reviewed-by: never, kvn ! src/cpu/sparc/vm/assembler_sparc.cpp ! src/cpu/sparc/vm/assembler_sparc.hpp ! src/cpu/sparc/vm/assembler_sparc.inline.hpp ! src/cpu/sparc/vm/c1_CodeStubs_sparc.cpp ! src/cpu/sparc/vm/c1_FrameMap_sparc.cpp ! src/cpu/sparc/vm/c1_LIRAssembler_sparc.cpp ! src/cpu/sparc/vm/c1_MacroAssembler_sparc.cpp ! src/cpu/sparc/vm/c1_Runtime1_sparc.cpp ! src/cpu/sparc/vm/dump_sparc.cpp ! src/cpu/sparc/vm/icBuffer_sparc.cpp ! src/cpu/sparc/vm/interp_masm_sparc.cpp ! src/cpu/sparc/vm/interpreterRT_sparc.cpp ! src/cpu/sparc/vm/jniFastGetField_sparc.cpp ! src/cpu/sparc/vm/nativeInst_sparc.cpp ! src/cpu/sparc/vm/relocInfo_sparc.cpp ! src/cpu/sparc/vm/runtime_sparc.cpp ! src/cpu/sparc/vm/sharedRuntime_sparc.cpp ! src/cpu/sparc/vm/sparc.ad ! src/cpu/sparc/vm/stubGenerator_sparc.cpp ! src/cpu/sparc/vm/templateInterpreter_sparc.cpp ! src/cpu/sparc/vm/templateTable_sparc.cpp ! src/cpu/sparc/vm/vtableStubs_sparc.cpp Changeset: 85656c8fa13f Author: twisti Date: 2009-04-22 06:09 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/85656c8fa13f Merge ! src/cpu/sparc/vm/templateInterpreter_sparc.cpp ! src/cpu/sparc/vm/templateTable_sparc.cpp Changeset: 04fa5affa478 Author: kvn Date: 2009-04-22 17:03 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/04fa5affa478 6709742: find_base_for_derived's use of Ideal NULL is unsafe causing crashes during register allocation Summary: Create a mach node corresponding to ideal node ConP #NULL specifically for derived pointers. Reviewed-by: never ! src/share/vm/opto/buildOopMap.cpp ! src/share/vm/opto/chaitin.cpp ! src/share/vm/opto/matcher.cpp ! src/share/vm/opto/matcher.hpp Changeset: 9c6be3edf0dc Author: cfang Date: 2009-04-23 14:04 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/9c6be3edf0dc 6589834: deoptimization problem with -XX:+DeoptimizeALot Summary: Relocate the stack pointer adjustment to where uncommon_trap is actually inserted for new_array. Reviewed-by: kvn, jrose ! src/share/vm/opto/graphKit.cpp ! src/share/vm/opto/graphKit.hpp ! src/share/vm/opto/library_call.cpp ! src/share/vm/opto/parse.hpp ! src/share/vm/opto/parse3.cpp + test/compiler/6589834/Test_ia32.java Changeset: aa92a90b1cc6 Author: cfang Date: 2009-04-24 09:14 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/aa92a90b1cc6 6833951: Extra ":" Causes Testcase in CR 6589834 "Parse Exception: Invalid tag: summary:" Summary: Remove the colon Reviewed-by: never ! test/compiler/6589834/Test_ia32.java Changeset: fb4c18a2ec66 Author: never Date: 2009-04-24 15:08 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/fb4c18a2ec66 6833573: C2 sparc: assert(c < 64 && (c & 1) == 0,"bad double float register") Reviewed-by: twisti ! src/cpu/sparc/vm/sparc.ad Changeset: 6ffcd0923239 Author: never Date: 2009-04-24 18:45 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/6ffcd0923239 Merge Changeset: 4753e4079a5a Author: apetrusenko Date: 2009-04-27 12:33 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/4753e4079a5a Merge

1 0

hg: jdk7/hotspot-gc/hotspot: 6829013: G1: set the default value of G1VerifyConcMarkPrintRechable to false
by antonios.printezis＠sun.com 27 Apr '09

27 Apr '09

Changeset: 2b6c55e36143 Author: tonyp Date: 2009-04-23 16:58 -0400 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/hotspot/rev/2b6c55e36143 6829013: G1: set the default value of G1VerifyConcMarkPrintRechable to false Summary: Turn off G1VerifyConcMarkPrintReachable by default to minimize the amount of verbose output we generate by default. Reviewed-by: jmasa ! src/share/vm/gc_implementation/g1/g1_globals.hpp ! src/share/vm/gc_implementation/g1/heapRegion.cpp

1 0

Re: concurrent mode failure in minor gc/ -XX:CMSFullGCsBeforeCompaction=1
by Jon Masamitsu 27 Apr '09

27 Apr '09

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@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(a)openjdk.java.net >http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use > > _______________________________________________ hotspot-gc-use mailing list hotspot-gc-use(a)openjdk.java.net http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use

2 3

Re: Troublesome reflection-cached SoftReferences
by Jon Masamitsu 24 Apr '09

24 Apr '09

Charles Oliver Nutter wrote On 04/24/09 07:02,: >Jon Masamitsu wrote: > > >>Which garbage collector and which jdk release are you using? >> >> > >Java HotSpot(TM) 64-Bit Server VM version 1.6.0_07-b06-57 > >Garbage collector: Name = 'Copy' >Garbage collector: Name = 'MarkSweepCompact' > > I'm don't know how Apple has set it's defaults and the names above are not specific enough. Can you run the command java -XX:+PrintGCDetails -XX:+PrintCommandLineFlags -version You should get output such as java -XX:+PrintGCDetails -XX:+PrintCommandLineFlags -version -XX:MaxHeapSize=1073741824 -XX:+PrintCommandLineFlags -XX:+PrintGCDetails -XX:+UseParallelGC java version "1.6.0" Java(TM) SE Runtime Environment (build 1.6.0-b105) Java HotSpot(TM) Server VM (build 1.6.0-b105, mixed mode) Heap PSYoungGen total 12544K, used 215K [0xf4000000, 0xf4e00000, 0xfb200000) eden space 10752K, 2% used [0xf4000000,0xf4035c38,0xf4a80000) from space 1792K, 0% used [0xf4c40000,0xf4c40000,0xf4e00000) to space 1792K, 0% used [0xf4a80000,0xf4a80000,0xf4c40000) PSOldGen total 110592K, used 0K [0xbb000000, 0xc1c00000, 0xf4000000) object space 110592K, 0% used [0xbb000000,0xbb000000,0xc1c00000) PSPermGen total 16384K, used 1449K [0xb7000000, 0xb8000000, 0xbb000000) object space 16384K, 8% used [0xb7000000,0xb716a5b8,0xb8000000) This will tell us exactly what is being run. >These are the defaults running on Mac. Yes, this is the Apple JDK, but >we've reproduces these issues under Sun/OpenJDK as well. I'm really just >trying to figure out *why* all this stuff is sticking around, and the >soft reference has been my first solid lead. > >- Charlie >_______________________________________________ >hotspot-gc-use mailing list >hotspot-gc-use(a)openjdk.java.net >http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use > > _______________________________________________ hotspot-gc-use mailing list hotspot-gc-use(a)openjdk.java.net http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use

1 0

Re: Troublesome reflection-cached SoftReferences
by Peter B. Kessler 24 Apr '09

24 Apr '09

Charles Oliver Nutter wrote: > I've run into a case in JRuby where a reflected method is keeping alive > a class, and that class references a large graph of JRuby objects. The > reflected method is off an anonymous interface implementation we create > at runtime. In order for that implementation to construct additional > Ruby objects, it has to reference an instance of our org.jruby.Ruby > class, which in turn references all globally-scoped data, and basically > keeps a lot of stuff alive. > > The SoftReference appears to be part of the root set, and holds only an > array of Constructor objects. Given a bit of time, this reference is > cleared and the graph goes with it. But doing repeated redeploys of a > JRuby application can fill up the heap before those soft references get > a chance to clear. > > So my questions: > > * Is there any way to force the internal reflection caches to flush > themselves? It's very inconvenient that the cache is keeping alive a > class that should be dead. > > * Is there any way to force an early SoftReference cleanup, before their > time has expired? If you can get to the SoftReference objects, you can call java.lang.ref.Reference.clear() on them. That way they won't hold on to their referent until the SoftReference policy clears them. I'm with Ramki though: all the SoftReferences should be cleared by the collector before it throws OOME at you. ... peter > * Does anyone else find this caching behavior irritating? > > - Charlie > _______________________________________________ > hotspot-gc-use mailing list > hotspot-gc-use(a)openjdk.java.net > http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use _______________________________________________ hotspot-gc-use mailing list hotspot-gc-use(a)openjdk.java.net http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use

1 0

Re: Troublesome reflection-cached SoftReferences
by Y. Srinivas Ramakrishna 24 Apr '09

24 Apr '09

Charles Oliver Nutter wrote: >> In any event, the JVM spec guarantees that all soft refs that are not >> strongly reachable >> will be cleared before an OOM is issued., I trust your issue is one >> of performance badness >> on account of a large heap, not an OOM? > > Actually it is an OOM problem. If it is an OOM problem we can ignore discussion of how to more aggressively clear the soft ref, since that is in some sense moot. > > The problem is that on repeated redeploys, the memory continues to > grow because these SoftReferences are holding on to a large graph of > data. Some of this data holds also to class references, and so rather > than getting a general heap OOM we get a PermGen OOM. Should a PermGen > OOM force softly-reachable objects to be collected as well? > Yes, each and every softly reachable object should be collected before any kind of OOM (whether in perm or in regular heap) is thrown. Have you made sure your soft ref's referent is not strongly reachable? BTW, -XX:+PrintReferenceGC will give some stats on Reference object processing. If you run a debug JVM then -XX:+TraceReferenceGC will give (altogether too much) tracing info as References are processes by GC, -- ramki _______________________________________________ hotspot-gc-use mailing list hotspot-gc-use(a)openjdk.java.net http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use

2 1

Re: Troublesome reflection-cached SoftReferences
by Y. Srinivas Ramakrishna 24 Apr '09

24 Apr '09

Charles Oliver Nutter wrote: > I've run into a case in JRuby where a reflected method is keeping alive > a class, and that class references a large graph of JRuby objects. The > reflected method is off an anonymous interface implementation we create > at runtime. In order for that implementation to construct additional > Ruby objects, it has to reference an instance of our org.jruby.Ruby > class, which in turn references all globally-scoped data, and basically > keeps a lot of stuff alive. > > The SoftReference appears to be part of the root set, and holds only an > array of Constructor objects. Given a bit of time, this reference is > cleared and the graph goes with it. But doing repeated redeploys of a > JRuby application can fill up the heap before those soft references get > a chance to clear. > > So my questions: > > * Is there any way to force the internal reflection caches to flush > themselves? It's very inconvenient that the cache is keeping alive a > class that should be dead. > > * Is there any way to force an early SoftReference cleanup, before their > time has expired? > You can try -XX:SoftRefLRUPolicyMSPerMB=0 , which is kind of a sledgehammer to clear soft refs the first time GC sees them (making them behave, in essence, like weak references) > * Does anyone else find this caching behavior irritating? > The policy is to clear a soft ref if it has not been accessed "recently". The "recency threshold metric" itself is computed as SoftRefLRUPolicyMSPerMB * FreeMemory following last major collection, so that as heap pressure increases we should be clearing soft refs more agressively albeit still in an LRU fashion. Could it be the case that your soft ref is accessed very frequently, so never falls below the computed recency threshold? In any event, the JVM spec guarantees that all soft refs that are not strongly reachable will be cleared before an OOM is issued., I trust your issue is one of performance badness on account of a large heap, not an OOM? thanks. -- ramki > - Charlie > _______________________________________________ > hotspot-gc-use mailing list > hotspot-gc-use(a)openjdk.java.net > http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use > _______________________________________________ hotspot-gc-use mailing list hotspot-gc-use(a)openjdk.java.net http://mail.openjdk.java.net/mailman/listinfo/hotspot-gc-use

1 0

hg: jdk7/hotspot-gc/langtools: Added tag jdk7-b56 for changeset 825f23a4f262
by john.coomes＠sun.com 23 Apr '09

23 Apr '09

Changeset: 4cfd3a840538 Author: xdono Date: 2009-04-23 15:55 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/langtools/rev/4cfd3a840538 Added tag jdk7-b56 for changeset 825f23a4f262 ! .hgtags

1 0

hg: jdk7/hotspot-gc/jdk: 3 new changesets
by john.coomes＠sun.com 23 Apr '09

23 Apr '09

Changeset: ffc29fac1330 Author: chegar Date: 2009-04-16 17:42 +0100 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/jdk/rev/ffc29fac1330 4927640: Implementation of the sctp protocol Summary: An implementation-specific API for the Stream Control Transmission Protocol Reviewed-by: alanb, michaelm, jccollet ! make/com/sun/Makefile + make/com/sun/nio/Makefile + make/com/sun/nio/sctp/Exportedfiles.gmk + make/com/sun/nio/sctp/FILES_c.gmk + make/com/sun/nio/sctp/FILES_java.gmk + make/com/sun/nio/sctp/Makefile + make/com/sun/nio/sctp/mapfile-vers ! make/docs/NON_CORE_PKGS.gmk ! make/java/nio/mapfile-linux ! make/java/nio/mapfile-solaris + src/share/classes/com/sun/nio/sctp/AbstractNotificationHandler.java + src/share/classes/com/sun/nio/sctp/Association.java + src/share/classes/com/sun/nio/sctp/AssociationChangeNotification.java + src/share/classes/com/sun/nio/sctp/HandlerResult.java + src/share/classes/com/sun/nio/sctp/IllegalReceiveException.java + src/share/classes/com/sun/nio/sctp/IllegalUnbindException.java + src/share/classes/com/sun/nio/sctp/InvalidStreamException.java + src/share/classes/com/sun/nio/sctp/MessageInfo.java + src/share/classes/com/sun/nio/sctp/Notification.java + src/share/classes/com/sun/nio/sctp/NotificationHandler.java + src/share/classes/com/sun/nio/sctp/PeerAddressChangeNotification.java + src/share/classes/com/sun/nio/sctp/SctpChannel.java + src/share/classes/com/sun/nio/sctp/SctpMultiChannel.java + src/share/classes/com/sun/nio/sctp/SctpServerChannel.java + src/share/classes/com/sun/nio/sctp/SctpSocketOption.java + src/share/classes/com/sun/nio/sctp/SctpStandardSocketOption.java + src/share/classes/com/sun/nio/sctp/SendFailedNotification.java + src/share/classes/com/sun/nio/sctp/ShutdownNotification.java + src/share/classes/com/sun/nio/sctp/package-info.java + src/share/classes/sun/nio/ch/SctpMessageInfoImpl.java + src/share/classes/sun/nio/ch/SctpStdSocketOption.java + src/solaris/classes/sun/nio/ch/SctpAssocChange.java + src/solaris/classes/sun/nio/ch/SctpAssociationImpl.java + src/solaris/classes/sun/nio/ch/SctpChannelImpl.java + src/solaris/classes/sun/nio/ch/SctpMultiChannelImpl.java + src/solaris/classes/sun/nio/ch/SctpNet.java + src/solaris/classes/sun/nio/ch/SctpNotification.java + src/solaris/classes/sun/nio/ch/SctpPeerAddrChange.java + src/solaris/classes/sun/nio/ch/SctpResultContainer.java + src/solaris/classes/sun/nio/ch/SctpSendFailed.java + src/solaris/classes/sun/nio/ch/SctpServerChannelImpl.java + src/solaris/classes/sun/nio/ch/SctpShutdown.java + src/solaris/classes/sun/nio/ch/SctpSocketDispatcher.java + src/solaris/native/sun/nio/ch/Sctp.h + src/solaris/native/sun/nio/ch/SctpChannelImpl.c + src/solaris/native/sun/nio/ch/SctpNet.c + src/solaris/native/sun/nio/ch/SctpServerChannelImpl.c + src/windows/classes/sun/nio/ch/SctpChannelImpl.java + src/windows/classes/sun/nio/ch/SctpMultiChannelImpl.java + src/windows/classes/sun/nio/ch/SctpServerChannelImpl.java + test/com/sun/nio/sctp/MessageInfoTests.java + test/com/sun/nio/sctp/SctpChannel/Bind.java + test/com/sun/nio/sctp/SctpChannel/Connect.java + test/com/sun/nio/sctp/SctpChannel/Receive.java + test/com/sun/nio/sctp/SctpChannel/Send.java + test/com/sun/nio/sctp/SctpChannel/Shutdown.java + test/com/sun/nio/sctp/SctpChannel/SocketOptionTests.java + test/com/sun/nio/sctp/SctpChannel/Util.java + test/com/sun/nio/sctp/SctpMultiChannel/Send.java + test/com/sun/nio/sctp/SctpMultiChannel/Util.java + test/com/sun/nio/sctp/SctpServerChannel/Accept.java + test/com/sun/nio/sctp/SctpServerChannel/NonBlockingAccept.java + test/com/sun/nio/sctp/SctpServerChannel/Util.java Changeset: 7fd3bc37afe3 Author: xdono Date: 2009-04-16 19:10 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/jdk/rev/7fd3bc37afe3 Merge - src/share/classes/sun/text/normalizer/UProperty.java - src/windows/native/sun/windows/awt_KeyboardFocusManager.h Changeset: 38e1121342d8 Author: xdono Date: 2009-04-23 15:55 -0700 URL: http://hg.openjdk.java.net/jdk7/hotspot-gc/jdk/rev/38e1121342d8 Added tag jdk7-b56 for changeset 7fd3bc37afe3 ! .hgtags

1 0