Closed
Description
JVM logging options (java7):
-XX:+UseG1GC
-XX:+PrintGC
-XX:+PrintGCApplicationConcurrentTime
-XX:+PrintGCApplicationStoppedTime
-XX:+PrintGCDateStamps
-XX:+PrintGCDetails
-XX:+PrintGCTimeStamps
-XX:+PrintPromotionFailure
-XX:+PrintTenuringDistribution
GCviewer produces the following warnings and does not plot any data in the end:
INFO [DataReaderFacade]: GCViewer version 1.33 (2013-10-30T18:47:41+0100)
INFO [DataReaderFactory]: File format: Sun 1.6.x G1 collector
INFO [DataReaderSun1_6_0G1]: Reading Sun 1.6.x / 1.7.x G1 format...
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '-' Line 40: 2014-03-07T18:39:01.784+0000: 8.199: 2014-03-07T18:39:01.786+0000: 8.201: [GC pause (young), 0.0219050 secs]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Parallel Time" Line 41: [Parallel Time: 11.0 ms, GC Workers: 18]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[GC Worker Start (ms)" Line 42: [GC Worker Start (ms): Min: 8201.2, Avg: 8205.3, Max: 8210.9, Diff: 9.8]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Ext Root Scanning (ms)" Line 43: [Ext Root Scanning (ms): Min: 0.0, Avg: 1.4, Max: 5.0, Diff: 4.9, Sum: 25.9]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Update RS (ms)" Line 44: [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Processed Buffers" Line 45: [Processed Buffers: Min: 0, Avg: 2.7, Max: 21, Diff: 21, Sum: 48]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Scan RS (ms)" Line 46: [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Object Copy (ms)" Line 47: [Object Copy (ms): Min: 0.0, Avg: 2.6, Max: 7.7, Diff: 7.7, Sum: 47.4]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Termination (ms)" Line 48: [Termination (ms): Min: 0.0, Avg: 2.7, Max: 3.7, Diff: 3.7, Sum: 48.5]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[GC Worker Other (ms)" Line 49: [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[GC Worker Total (ms)" Line 50: [GC Worker Total (ms): Min: 1.2, Avg: 6.8, Max: 11.0, Diff: 9.8, Sum: 122.4]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[GC Worker End (ms)" Line 51: [GC Worker End (ms): Min: 8212.1, Avg: 8212.1, Max: 8212.1, Diff: 0.0]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Code Root Fixup" Line 52: [Code Root Fixup: 0.8 ms]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Clear CT" Line 53: [Clear CT: 0.8 ms]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Other" Line 54: [Other: 9.4 ms]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Choose CSet" Line 55: [Choose CSet: 0.0 ms]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Ref Proc" Line 56: [Ref Proc: 9.1 ms]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Ref Enq" Line 57: [Ref Enq: 0.2 ms]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Free CSet" Line 58: [Free CSet: 0.0 ms]
The GC output is as follows:
2014-03-07T18:39:01.786+0000: 8.201: [GC pause (young)
Desired survivor size 100663296 bytes, new threshold 15 (max 15)
, 0.0219050 secs]
[Parallel Time: 11.0 ms, GC Workers: 18]
[GC Worker Start (ms): Min: 8201.2, Avg: 8205.3, Max: 8210.9, Diff: 9.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 1.4, Max: 5.0, Diff: 4.9, Sum: 25.9]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
[Processed Buffers: Min: 0, Avg: 2.7, Max: 21, Diff: 21, Sum: 48]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Object Copy (ms): Min: 0.0, Avg: 2.6, Max: 7.7, Diff: 7.7, Sum: 47.4]
[Termination (ms): Min: 0.0, Avg: 2.7, Max: 3.7, Diff: 3.7, Sum: 48.5]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
[GC Worker Total (ms): Min: 1.2, Avg: 6.8, Max: 11.0, Diff: 9.8, Sum: 122.4]
[GC Worker End (ms): Min: 8212.1, Avg: 8212.1, Max: 8212.1, Diff: 0.0]
[Code Root Fixup: 0.8 ms]
[Clear CT: 0.8 ms]
[Other: 9.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 9.1 ms]
[Ref Enq: 0.2 ms]
[Free CSet: 0.0 ms]
[Eden: 192.0M(192.0M)->0.0B(160.0M) Survivors: 0.0B->32.0M Heap: 192.0M(1024.0M)->17.8M(1024.0M)]
[Times: user=0.12 sys=0.00, real=0.03 secs]
2014-03-07T18:39:01.808+0000: 8.223: Total time for which application threads were stopped: 0.0240450 seconds
2014-03-07T18:39:01.808+0000: 8.223: Application time: 0.0001850 seconds