Fork of tagtraum industries' GCViewer. Tagtraum stopped development in 2008, I aim to improve support for Sun's / Oracle's java 1.6+ garbage collector logs (including G1 collector)
Home Page: https://github.com/chewiebug/GCViewer/wiki
License: Other
Assignee. @rollandf
Another feature that could help with analysis is a "Min / max full GC pause interval", where we could see if Full GCs are happening too frequent, or not.
GCViewer 1.29 doesn't show much information about concurrent collections as they occur in Suns CMS or G1 collector. Sometimes it is very interesting to see when a concurrent collection begins and when it ends. The level of tenured memory occupation at the start of a concurrent collection is interesting as well (for CMS can be influenced using -XX:CMSInitiatingOccupancyFraction=<N>).
GCViewer 1.29 shows some metrics about pauses occurred in the gc log file. It would be interesting to have more information about pauses:
sum / min / max / avg pause values not only for all pauses but separately for
I'm reviewing a gc.log that is approximately 12 hours long. My window is sized such that I can see 20m without scrolling.
If I click in the space on the horizontal scroll bar, it should go one 'page'. So if I'm on a page showing 6h to 6h20m, and click in the scroll bar to move it right, it should show roughly 6h20m to 6h40. Instead, it moves to 7h20 to 7h40.
I am currently writing a exporter for a specific subset/format only for the Young Generation. When I iterate through the GCModel I check for the generation == YOUNG. The following events:
6.727: [GC 6.727: [ParNew: 1610619K->7990K(22649280K), 0.0379110 secs] 1610619K->7990K(47815104K), 0.0380570 secs] [Times: user=0.59 sys=0.04, real=0.04 secs]
6.765: [GC [1 CMS-initial-mark: 0K(25165824K)] 410644K(47815104K), 0.0100670 secs] [Times: user=0.01 sys=0.00, real=0.01 secs]
6.776: [CMS-concurrent-mark-start]
6.892: [GC 6.892: [ParNew: 20140726K->15589K(22649280K), 0.0220010 secs] 20140726K->15589K(47815104K), 0.0221740 secs] [Times: user=0.39 sys=0.01, real=0.02 secs]
6.934: [CMS-concurrent-mark: 0.034/0.159 secs] [Times: user=1.29 sys=0.13, real=0.16 secs]
6.934: [CMS-concurrent-preclean-start]
7.034: [CMS-concurrent-preclean: 0.096/0.100 secs] [Times: user=0.89 sys=0.05, real=0.10 secs]
7.034: [CMS-concurrent-abortable-preclean-start]
CMS: abort preclean due to time 12.202: [CMS-concurrent-abortable-preclean: 4.915/5.168 secs] [Times: user=17.03 sys=1.25, real=5.17 secs]
12.203: [GC[YG occupancy: 11281900 K (22649280 K)]12.203: [Rescan (parallel) , 0.3773770 secs]12.580: [weak refs processing, 0.0000310 secs]12.580: [class unloading, 0.0055480 secs]12.586: [scrub symbol & string tables, 0.0041920 secs] [1 CMS-remark: 0K(25165824K)] 11281900K(47815104K), 0.3881550 secs] [Times: user=17.73 sys=0.04, real=0.39 secs]
Generate the following generation infos for the event and for event.getYoung()
ts,event.gen event.type event.typeasstring young.gen young.type young.typeasstring
6.727000,YOUNG GC GC ParNew:, YOUNG ParNew: ParNew
6.765000,YOUNG GC GC CMS-initial-mark:,YOUNG undefined null
6.892000,YOUNG GC GC ParNew:, YOUNG ParNew: ParNew
12.203000,YOUNG GC GC CMS-remark:, YOUNG undefined null
As you can see, the initial-mark is wrongly classified as young (it does have the right TypeAsString).
I use this code:
for (Iterator i = model.getGCEvents(); i.hasNext();) {
GCEvent event = (GCEvent) i.next();
if (event.getGeneration() != Generation.YOUNG)
continue;
Strange enough the definition for the inital-mark type seems to be correctly using TENURED.
Support "simple gc log" as export format to create a file having a line per event in the format
GC_TYPE START_SEC DURATION_SEC
Like this the histogram functionality of gchisto can be used for gc logs that otherwise can't be parsed with gchisto.
see Charlie Hunts suggestion in the hotspot-gc-use mailing list and gchisto (direct link to documentation)
Java 7 supports Rolling GC logs files with the parameters
-XX:+UseGCLogFileRotation
-XX:GCLogFileSize=M
-XX:NumberOfGCLogFiles=
the files will be suffexed with .1 .2 .3 etc....
GCViewer should be able to read all the files at once and merge them in correct order.
Add a checkbox to load all related files or add a popup dialog to ask if you want to load all related log files.
The zoom option does not work properly.
If you reduce the Zoom from 1000% to 1%, the scroll bar is missing and with every change the width of the graph decreases.
GCViewer 1.29 (and up to 1.31-RC3) can't parse logfiles written with -XX:+PrintAdaptiveSizePolicy option. It should be able to parse the file ignoring the additional output.
Sample log entry:
0.175: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 2721008 promoted: 13580768 overflow: trueAdaptiveSizeStart: 0.186 collection: 1 PSAdaptiveSizePolicy::compute_generation_free_space: costs minor_time: 0.059538 major_cost: 0.000000 mutator_cost: 0.940462 throughput_goal: 0.990000 live_space: 273821824 free_space: 33685504 old_promo_size: 16842752 old_eden_size: 16842752 desired_promo_size: 16842752 desired_eden_size: 33685504 AdaptiveSizePolicy::survivor space sizes: collection: 1 (2752512, 2752512) -> (2752512, 2752512) AdaptiveSizeStop: collection: 1 [PSYoungGen: 16420K->2657K(19136K)] 16420K->15919K(62848K), 0.0109211 secs] [Times: user=0.00 sys=0.00, real=0.01 secs]
In java 8, this will be on by default. In the next non-security Java 7 release, it is going to be available via the -XX:+PrintGCCause flag (it's currently available in ea build)
The AbstractGCEvent.parse method currently barfs when hit with a GC log message that comes out of something with the PrintGCCause information in it:
the log entries look like:
"GC pause (G1 Evacuation Pause) (young)"
This will not be just for G1, this additional detail will be provided for all collectors - it makes the parsing in AbstractGCEvent.parse a bit trickier because it will have to know that
"GC pause (G1 Evacuation Pause) (young)"
"GC pause (G1 Humongous Allocation) (young)"
both map to:
"GC pause (young)"
Looking at the JDK7 / JDK8 sources - the constants are defined in the gcCause.cpp file:
System.gc()
FullGCAlot
ScavengeAlot
Allocation Profiler
JvmtiEnv ForceGarbageCollection
GCLocker Initiated GC
Heap Inspection Initiated GC
Heap Dump Initiated GC
No GC
Allocation Failure
Tenured Generation Full
Metadata GC Threshold
CMS Generation Full
CMS Initial Mark
CMS Final Remark
Old Generation Expanded On Last Scavenge
Old Generation Too Full To Scavenge
Ergonomics
G1 Evacuation Pause
G1 Humongous Allocation
Last ditch collection
ILLEGAL VALUE - last gc cause - ILLEGAL VALUE
unknown GCCause
Java 7 has
Permanent Generation Full
instead of "Metadata GC Threshold"
I can help with a patch if you have an idea of where this might be easiest to wedge in
Dear Author,
I encountered an exception when I parse GC log.
The jdk version of the GC log is
java version "1.6.0"
Java(TM) SE Runtime Environment (build pxa6460_26fp1-20110419_01)
IBM J9 VM (build 2.6, JRE 1.6.0 Linux amd64-64 20110418_80450 (JIT enabled, AOT enabled)
J9VM - R26_Java626_GA_FP1_20110418_1915_B80450
JIT - r11_20110215_18645ifx8
GC - R26_Java626_GA_FP1_20110418_1915_B80450
J9CL - 20110418_80450)
JCL - 20110401_01
Will you support this version?
Aug 16, 2013 8:32:22 PM com.tagtraum.perf.gcviewer.imp.DataReaderFacade loadModel
INFO: GCViewer version 1.32 (2013-08-16 18:13)
Aug 16, 2013 8:32:22 PM com.tagtraum.perf.gcviewer.imp.DataReaderFactory getDataReaderBySample
INFO: File format: Sun 1.4.x
Aug 16, 2013 8:32:22 PM com.tagtraum.perf.gcviewer.imp.DataReaderSun1_6_0 read
INFO: Reading Sun / Oracle 1.4.x / 1.5.x / 1.6.x / 1.7.x format...
Aug 16, 2013 8:32:22 PM com.tagtraum.perf.gcviewer.imp.DataReaderSun1_6_0 read
WARNING: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '
Hello. I'm trying to open log done by G1 garbage collector on Java7u4 Build b17 (it's in beta at the moment). It recocgnizes it as 1.6 format, throws few Parse exceptions as usual and finally no graph and no summary. All values are equal 0. The only value it shows is "Total heap (usage / alloc. max)".
JVM was running with the following options:
-Xms2G -Xmx2G -XX:MaxPermSize=128m -XX:PermSize=128m -XX:+DisableExplicitGC -XX:+UnlockExperimentalVMOptions -XX:+UseG1GC -verbose:gc -Xloggc:gcpipe -XX:+PrintGCDateStamps -XX:+PrintGCDetails -XX:+PrintTenuringDistribution -XX:+PrintGCApplicationConcurrentTime -XX:+PrintGCApplicationStoppedTime
I use gcviewer-1.31-RC5.jar. Here is the link to GC log in case you are interested http://db.tt/m3MxmEMi (1MB)
UPD: I observe the same behaviour on Java7u3.
Thanks for supporting this great tool!
We're using jdk1.6.0_20 for a particular app with the following flags:
-XX:+HeapDumpOnOutOfMemoryError
-XX:+PrintGCDateStamps
-XX:+PrintGCDetails
-XX:+UseConcMarkSweepGC
-Xloggc:/var/log/gc-jobtracker.log.201301231016
-verbose:gc
While investigating a gc issue on the system, we found that the latest trunk of GCViewer throws an exception when parsing the log file. When this error occurs, the rendering of the window stops working.
The output that we get when this occurs is:
:; java -jar gcviewer-1.32-SNAPSHOT.jar gc.out
Jan 23, 2013 11:31:45 AM com.tagtraum.perf.gcviewer.imp.DataReaderFacade loadModel
INFO: GCViewer version 1.32-SNAPSHOT (2013-01-23 10:39)
Jan 23, 2013 11:31:45 AM com.tagtraum.perf.gcviewer.imp.DataReaderFactory getDataReaderBySample
INFO: File format: Sun 1.6.x
Jan 23, 2013 11:31:45 AM com.tagtraum.perf.gcviewer.imp.DataReaderSun1_6_0 read
INFO: Reading Sun / Oracle 1.4.x / 1.5.x / 1.6.x / 1.7.x format...
Jan 23, 2013 11:31:47 AM com.tagtraum.perf.gcviewer.imp.DataReaderSun1_6_0 read
INFO: Done reading.
GC CMS-initial-mark: [n, avg, sum, min, max]: 1010 0.16448917227722776 166.13406400000002 0.001687 0.409346
GC CMS-remark: [n, avg, sum, min, max]: 1008 0.133618384920635 134.68733200000008 0.002712 0.442009
GC ParNew: [n, avg, sum, min, max]: 89902 0.014387094313808193 1293.428552999984 0.001537 0.350223
Full GC CMS: CMS Perm : [n, avg, sum, min, max]: 1 0.174175 0.174175 0.174175 0.174175
GC ParNew (promotion failed): CMS (concurrent mode failure): CMS Perm : [n, avg, sum, min, max]: 1 16.474027 16.474027 16.474027 16.474027
GC ParNew (promotion failed): CMS: CMS Perm : [n, avg, sum, min, max]: 2 11.6213235 23.242647 10.142537 13.10011
CMS-concurrent-abortable-preclean [n, avg, sum, min, max]: 1007 1.549451837140023 1560.2980000000032 0.082 3.113
CMS-concurrent-abortable-preclean-start [n, avg, sum, min, max]: 1007 0.0 0.0 0.0 4.9E-324
CMS-concurrent-mark [n, avg, sum, min, max]: 1009 2.321055500495541 2341.9450000000006 0.346 2.867
CMS-concurrent-mark-start [n, avg, sum, min, max]: 1009 0.0 0.0 0.0 4.9E-324
CMS-concurrent-preclean [n, avg, sum, min, max]: 1008 0.011240079365079276 11.32999999999991 0.009 0.076
CMS-concurrent-preclean-start [n, avg, sum, min, max]: 1008 0.0 0.0 0.0 4.9E-324
CMS-concurrent-reset [n, avg, sum, min, max]: 1008 0.00865476190476185 8.723999999999943 0.008 0.048
CMS-concurrent-reset-start [n, avg, sum, min, max]: 1008 0.0 0.0 0.0 4.9E-324
CMS-concurrent-sweep [n, avg, sum, min, max]: 1008 2.2752023809523845 2293.4040000000036 1.805 4.076
CMS-concurrent-sweep-start [n, avg, sum, min, max]: 1008 0.0 0.0 0.0 4.9E-324
initiatingOccupancyFraction (n, avg, stddev, min, max): 1010 0.9503323679533382 0.02316152919347306 0.5005524809799913 0.9830980096021718
heap size used (n, avg, stddev, min, max): 91924 2899635.8066228624 628769.3665589424 64572 3988090
perm size used (n, avg, stddev, min, max): 4 24644.25 2267.8153033260887 21247 25941
tenured size used (n, avg, stddev, min, max): 2022 3627107.74975272 121860.00399843974 38472 3754631
young size used (n, avg, stddev, min, max): 89905 232522.0330904844 5460.312867300275 3412 249216
Exception in thread "AWT-EventQueue-0" java.lang.ArrayIndexOutOfBoundsException: -1
at com.tagtraum.perf.gcviewer.renderer.PolygonChartRenderer.findInsertionBoundary(PolygonChartRenderer.java:147)
at com.tagtraum.perf.gcviewer.renderer.PolygonChartRenderer.initClippedPolygon(PolygonChartRenderer.java:92)
at com.tagtraum.perf.gcviewer.renderer.PolygonChartRenderer.paintComponent(PolygonChartRenderer.java:58)
at com.tagtraum.perf.gcviewer.ChartRenderer.paintComponent(ChartRenderer.java:53)
at javax.swing.JComponent.paint(JComponent.java:1054)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JViewport.paint(JViewport.java:731)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JLayeredPane.paint(JLayeredPane.java:585)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JLayeredPane.paint(JLayeredPane.java:585)
at com.tagtraum.perf.gcviewer.DesktopPane.paint(DesktopPane.java:95)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paint(JComponent.java:1063)
at javax.swing.JLayeredPane.paint(JLayeredPane.java:585)
at javax.swing.JComponent.paintChildren(JComponent.java:887)
at javax.swing.JComponent.paintToOffscreen(JComponent.java:5228)
at javax.swing.BufferStrategyPaintManager.paint(BufferStrategyPaintManager.java:295)
at javax.swing.RepaintManager.paint(RepaintManager.java:1206)
at javax.swing.JComponent.paint(JComponent.java:1040)
at java.awt.GraphicsCallback$PaintCallback.run(GraphicsCallback.java:39)
at sun.awt.SunGraphicsCallback.runOneComponent(SunGraphicsCallback.java:78)
at sun.awt.SunGraphicsCallback.runComponents(SunGraphicsCallback.java:115)
at java.awt.Container.paint(Container.java:1967)
at java.awt.Window.paint(Window.java:3877)
at javax.swing.RepaintManager.paintDirtyRegions(RepaintManager.java:781)
at javax.swing.RepaintManager.paintDirtyRegions(RepaintManager.java:728)
at javax.swing.RepaintManager.prePaintDirtyRegions(RepaintManager.java:677)
at javax.swing.RepaintManager.access$700(RepaintManager.java:59)
at javax.swing.RepaintManager$ProcessingRunnable.run(RepaintManager.java:1621)
at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:251)
at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:721)
at java.awt.EventQueue.access$200(EventQueue.java:103)
at java.awt.EventQueue$3.run(EventQueue.java:682)
at java.awt.EventQueue$3.run(EventQueue.java:680)
at java.security.AccessController.doPrivileged(Native Method)
at java.security.ProtectionDomain$1.doIntersectionPrivilege(ProtectionDomain.java:76)
at java.awt.EventQueue.dispatchEvent(EventQueue.java:691)
at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:242)
at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:161)
at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:150)
at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:146)
at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:138)
at java.awt.EventDispatchThread.run(EventDispatchThread.java:91)
The particular line in the gc log is:
2013-01-22T22:25:34.911-0600: 3694738.202: [GC [1 CMS-initial-mark: 3754399K(3819136K)] 3800986K(4068352K), 0.0502180 secs] [Times: user=0.05 sys=0.00, real=0.05 secs]
but I don't see anything obviously wrong with this.
I can provide the full GC log file if that helps.
due to a missing space between the end ] of a detailed GCEvent and the following duration the HP Jmeter console cannot import GCViewer exported (sample) GC logs.
This is usefull, as GCViewer can parse absolute datestamps and therefore make the files again useable by HP.
There should be a line indicating the usage and allocation of the perm gen space if the information is present in the gc log.
It would be very handy if using the -XX:+PrintGCDateStamps allowed showing the date in the chart.
This will make it easier to find corresponding events in my application logs
using GCViewer 1.31-RC5
The following import warnings appear when reading a JDK1.6 or JDK1.7 file with -XX:CMSStatistics=2:
Information [DataReaderFactory]: File format: Sun 1.6.x
Information [DataReaderSun1_6_0]: Reading Sun / Oracle 1.4.x / 1.5.x / 1.6.x / 1.7.x format...
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 3: Finished cms space scanning in 0th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 4: Finished cms space scanning in 1th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 5: Finished perm space scanning in 1th thread: 0.042 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 6: Finished perm space scanning in 0th thread: 0.047 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 7: Finished work stealing in 0th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 8: Finished work stealing in 1th thread: 0.005 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 11: (cardTable: 1054 cards, re-scanned 1054 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 14: (cardTable: 922 cards, re-scanned 922 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 15: (cardTable: 667 cards, re-scanned 667 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 16: (cardTable: 127 cards, re-scanned 127 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 17: (cardTable: 186 cards, re-scanned 186 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 18: (cardTable: 44 cards, re-scanned 44 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 19: (cardTable: 1767 cards, re-scanned 1767 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 20: (cardTable: 670 cards, re-scanned 670 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 21: (cardTable: 341 cards, re-scanned 341 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 22: (cardTable: 709 cards, re-scanned 709 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 23: (cardTable: 879 cards, re-scanned 879 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 24: (cardTable: 340 cards, re-scanned 340 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 25: (cardTable: 1047 cards, re-scanned 1047 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 26: (cardTable: 1649 cards, re-scanned 1649 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 27: (cardTable: 1949 cards, re-scanned 1949 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 28: (cardTable: 1497 cards, re-scanned 1497 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 29: (cardTable: 913 cards, re-scanned 913 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 30: (cardTable: 907 cards, re-scanned 907 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 31: (cardTable: 1688 cards, re-scanned 1688 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 32: (cardTable: 1075 cards, re-scanned 1075 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 33: (cardTable: 316 cards, re-scanned 316 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 34: (cardTable: 391 cards, re-scanned 391 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 35: (cardTable: 1514 cards, re-scanned 1514 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 36: (cardTable: 820 cards, re-scanned 820 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 37: (cardTable: 566 cards, re-scanned 566 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 38: (cardTable: 23 cards, re-scanned 23 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 39: (cardTable: 362 cards, re-scanned 362 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 40: (cardTable: 397 cards, re-scanned 397 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 41: (cardTable: 1053 cards, re-scanned 1053 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 42: (cardTable: 1887 cards, re-scanned 1887 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 43: (cardTable: 1682 cards, re-scanned 1682 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 44: (cardTable: 2194 cards, re-scanned 2194 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 45: (cardTable: 1437 cards, re-scanned 1437 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 46: (cardTable: 946 cards, re-scanned 946 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 47: (cardTable: 830 cards, re-scanned 830 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 48: (cardTable: 677 cards, re-scanned 677 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 49: (cardTable: 266 cards, re-scanned 266 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 50: (cardTable: 1940 cards, re-scanned 1940 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 51: [37 iterations, 2 waits, 34678 cards)] 2012-08-23T17:42:23.373-0800: [CMS-concurrent-abortable-preclean: 4.350/5.107 secs] (CMS-concurrent-abortable-preclean yielded 426685 times)
Warnung [AbstractDataReaderSun]: unknown memoryunit 't' in line 2012-08-23T17:42:23.373-0800: [GC[YG occupancy: 341381 K (3774912 K)][Rescan (parallel) (Survivor:571chunks) Finished young gen rescan work in 2th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NumberFormatException: 2012-08-23T17:42:23.373-0800: [GC[YG occupancy: 341381 K (3774912 K)][Rescan (parallel) (Survivor:571chunks) Finished young gen rescan work in 2th thread: 0.001 sec) Line 53: 2012-08-23T17:42:23.373-0800: [GC[YG occupancy: 341381 K (3774912 K)][Rescan (parallel) (Survivor:571chunks) Finished young gen rescan work in 2th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 54: Finished young gen rescan work in 0th thread: 0.002 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 55: Finished young gen rescan work in 3th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 56: Finished young gen rescan work in 4th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 57: Finished remaining root rescan work in 2th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 58: Finished dirty card rescan work in 2th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 59: Finished remaining root rescan work in 3th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 60: Finished dirty card rescan work in 3th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 61: Finished remaining root rescan work in 0th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 62: Finished dirty card rescan work in 0th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 63: Finished remaining root rescan work in 4th thread: 0.002 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 64: Finished dirty card rescan work in 4th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 65: Finished young gen rescan work in 1th thread: 0.011 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 66: Finished remaining root rescan work in 1th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 67: Finished dirty card rescan work in 1th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 68: Finished young gen rescan work in 7th thread: 0.027 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 69: Finished remaining root rescan work in 7th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 70: Finished dirty card rescan work in 7th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 71: Finished young gen rescan work in 6th thread: 0.065 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 72: Finished remaining root rescan work in 6th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 73: Finished dirty card rescan work in 6th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 74: Finished young gen rescan work in 5th thread: 0.068 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 75: Finished remaining root rescan work in 5th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 76: Finished dirty card rescan work in 5th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 77: Finished work stealing in 5th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 78: Finished work stealing in 6th thread: 0.003 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 79: Finished work stealing in 3th thread: 0.066 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 80: Finished work stealing in 0th thread: 0.066 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 81: Finished work stealing in 4th thread: 0.065 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 82: Finished work stealing in 7th thread: 0.041 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 83: Finished work stealing in 1th thread: 0.058 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 84: Finished work stealing in 2th thread: 0.066 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 85: , 0.0692650 secs][weak refs processing, 0.0000150 secs][class unloading, 0.0066440 secs][scrub symbol & string tables, 0.0049270 secs] [1 CMS-remark: 0K(4194304K)] 341381K(7969216K), 0.0816280 secs] [Times: user=0.52 sys=0.00, real=0.09 secs]
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 93: Finished cms space scanning in 0th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 94: Finished cms space scanning in 1th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 95: Finished perm space scanning in 0th thread: 0.236 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 96: Finished perm space scanning in 1th thread: 0.240 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 97: Finished work stealing in 1th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 98: Finished work stealing in 0th thread: 0.003 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 101: (cardTable: 38176 cards, re-scanned 38176 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 104: (cardTable: 14401 cards, re-scanned 14401 cards, 1 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 106: (cardTable: 777 cards, re-scanned 70061 cards, 2 iterations)
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 107: [2 iterations, 0 waits, 84462 cards)] 2012-08-23T17:43:13.231-0800: [CMS-concurrent-abortable-preclean: 4.885/5.810 secs] (CMS-concurrent-abortable-preclean yielded 226971 times)
Warnung [AbstractDataReaderSun]: unknown memoryunit 't' in line 2012-08-23T17:43:13.233-0800: [GC[YG occupancy: 402755 K (3774912 K)][Rescan (parallel) (Survivor:21chunks) Finished young gen rescan work in 3th thread: 0.048 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NumberFormatException: 2012-08-23T17:43:13.233-0800: [GC[YG occupancy: 402755 K (3774912 K)][Rescan (parallel) (Survivor:21chunks) Finished young gen rescan work in 3th thread: 0.048 sec) Line 109: 2012-08-23T17:43:13.233-0800: [GC[YG occupancy: 402755 K (3774912 K)][Rescan (parallel) (Survivor:21chunks) Finished young gen rescan work in 3th thread: 0.048 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 110: Finished young gen rescan work in 2th thread: 0.049 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 111: Finished young gen rescan work in 6th thread: 0.049 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 112: Finished young gen rescan work in 4th thread: 0.049 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 113: Finished young gen rescan work in 0th thread: 0.049 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 114: Finished young gen rescan work in 7th thread: 0.048 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 115: Finished remaining root rescan work in 4th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 116: Finished remaining root rescan work in 3th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 117: Finished remaining root rescan work in 2th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 118: Finished remaining root rescan work in 7th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 119: Finished dirty card rescan work in 2th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 120: Finished dirty card rescan work in 4th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 121: Finished dirty card rescan work in 7th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 122: Finished dirty card rescan work in 3th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 123: Finished remaining root rescan work in 0th thread: 0.001 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 124: Finished dirty card rescan work in 0th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 125: Finished young gen rescan work in 1th thread: 0.050 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 126: Finished remaining root rescan work in 1th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 127: Finished dirty card rescan work in 1th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 128: Finished remaining root rescan work in 6th thread: 0.003 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 129: Finished dirty card rescan work in 6th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 130: Finished young gen rescan work in 5th thread: 0.058 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 131: Finished remaining root rescan work in 5th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 132: Finished dirty card rescan work in 5th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 133: Finished work stealing in 5th thread: 0.000 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 134: Finished work stealing in 3th thread: 0.009 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 135: Finished work stealing in 7th thread: 0.009 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 136: Finished work stealing in 6th thread: 0.007 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 137: Finished work stealing in 4th thread: 0.009 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 138: Finished work stealing in 0th thread: 0.009 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 139: Finished work stealing in 2th thread: 0.009 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 140: Finished work stealing in 1th thread: 0.009 sec
Warnung [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 141: , 0.0594580 secs][weak refs processing, 0.0000180 secs][class unloading, 0.0141700 secs][scrub symbol & string tables, 0.0189250 secs] [1 CMS-remark: 0K(4194304K)] 402755K(7969216K), 0.0957690 secs] [Times: user=0.50 sys=0.00, real=0.09 secs]
Information [DataReaderSun1_6_0]: Done reading.
Using the master branch of gcviewer with the two lines for old/young I love it. However when I switch of the pink old gen the chart gets redrawn, and while redraw the follwojng exception happens:
Exception in thread "AWT-EventQueue-0" java.lang.NullPointerException
at com.tagtraum.perf.gcviewer.renderer.UsedYoungRenderer.computePolygon(UsedYoungRenderer.java:50)
at com.tagtraum.perf.gcviewer.renderer.PolygonChartRenderer.paintComponent(PolygonChartRenderer.java:56)
at com.tagtraum.perf.gcviewer.ChartRenderer.paintComponent(ChartRenderer.java:53)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JViewport.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JLayeredPane.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JLayeredPane.paint(Unknown Source)
at com.tagtraum.perf.gcviewer.DesktopPane.paint(DesktopPane.java:93)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JLayeredPane.paint(Unknown Source)
at javax.swing.JComponent.paintChildren(Unknown Source)
at javax.swing.JComponent.paint(Unknown Source)
at javax.swing.JComponent.paintToOffscreen(Unknown Source)
at javax.swing.RepaintManager$PaintManager.paintDoubleBuffered(Unknown Source)
at javax.swing.RepaintManager$PaintManager.paint(Unknown Source)
at javax.swing.RepaintManager.paint(Unknown Source)
at javax.swing.JComponent._paintImmediately(Unknown Source)
at javax.swing.JComponent.paintImmediately(Unknown Source)
at javax.swing.RepaintManager.paintDirtyRegions(Unknown Source)
at javax.swing.RepaintManager.paintDirtyRegions(Unknown Source)
at javax.swing.RepaintManager.prePaintDirtyRegions(Unknown Source)
at javax.swing.RepaintManager.access$700(Unknown Source)
at javax.swing.RepaintManager$ProcessingRunnable.run(Unknown Source)
at java.awt.event.InvocationEvent.dispatch(Unknown Source)
at java.awt.EventQueue.dispatchEventImpl(Unknown Source)
at java.awt.EventQueue.access$200(Unknown Source)
at java.awt.EventQueue$3.run(Unknown Source)
at java.awt.EventQueue$3.run(Unknown Source)
at java.security.AccessController.doPrivileged(Native Method)
at java.security.ProtectionDomain$1.doIntersectionPrivilege(Unknown Source)
at java.awt.EventQueue.dispatchEvent(Unknown Source)
at java.awt.EventDispatchThread.pumpOneEventForFilters(Unknown Source)
at java.awt.EventDispatchThread.pumpEventsForFilter(Unknown Source)
at java.awt.EventDispatchThread.pumpEventsForHierarchy(Unknown Source)
at java.awt.EventDispatchThread.pumpEvents(Unknown Source)
at java.awt.EventDispatchThread.pumpEvents(Unknown Source)
at java.awt.EventDispatchThread.run(Unknown Source)
I get this with my ecki/GCViewer/topic-csv2 branch but I think the code is equal to this version here: https://github.com/chewiebug/GCViewer/tree/54956ab6b87c723c3549cdbb4c621f2a6112ed9c
GCViewer should be have a command line only mode so that no interaction is required to process a gc log file. This mode would allow the use of gcviewer in automated scripts.
Add option to support export of graph as image.
Sometimes it would be interesting to see the metrics for a specific part of a gc log file. E.g. metrics before and after a full gc where the gc algorithm changed the sizing of the generations. This would help to find out if the sizing before or after was better and should be aimed for.
Setting the start and end time of analysis inside log with one of the following options:
I often have quite big log files, so I tested, whether I could read them as gzip'd files.
A small modification of DataReaderFactory.getDataReader() does the job :-)
public DataReader getDataReader(InputStream inStream) throws IOException {
BufferedInputStream in = new BufferedInputStream(inStream, FOUR_KB);
if (in.markSupported()) {
if (readUShortAndReset(in) == GZIPInputStream.GZIP_MAGIC) {
in = new BufferedInputStream(new GZIPInputStream(in, FOUR_KB), FOUR_KB);
}
}
private static int readUShortAndReset(final InputStream in) throws IOException {
in.mark(2);
final int b = in.read();
final int result = ((int)in.read() << 8) | b;
in.reset();
return result;
}
GCViewer 1.29 can't parse log files written with option -XX:+PrintGCDateStamps. It should parse them and use the date when using absolute time as labels for timeline.
Sample log entry:
2011-10-05T04:23:39.427+0200: 19.845: [GC 19.845: [ParNew: 93184K->5483K(104832K), 0.0384413 secs] 93184K->5483K(1036928K), 0.0388082 secs] [Times: user=0.41 sys=0.06, real=0.04 secs]
performance of chart for certain large gc logfiles should be improved, scrolling takes seconds to move from one position of the scrollbar to the next. The more scrolling moves to the right of a chart the more performance degrades. Hiding display of total / tenured / young heap improves performance.
Sample log files showing this issue are mentioned in issue 22
There is already an open issue to support command line. Using that feature, we should be able to export the graph in addition to the CSV data.
Get the following errors when opening GC files (generated using JVM options -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+PrintGCDateStamps -XX:+PrintAdaptiveSizePolicy)
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Full GC (System)AdaptiveSizeStart' Line 4: 2012-02-28T09:31:39.265+0000: 2.431: [Full GC (System)AdaptiveSizeStart: 2.490 collection: 2
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NumberFormatException: For input string: "AdaptiveSizeStop") Line 5: AdaptiveSizeStop: collection: 2
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NumberFormatException: For input string: "[PSYoungGen") Line 6: [PSYoungGen: 6368K->0K(917504K)] [PSOldGen: 0K->6117K(2097152K)] 6368K->6117K(3014656K) [PSPermGen: 15508K->15508K(786432K)], 0.0586670 secs] [Times: user=0.06 sys=0.00, real=0.06 secs]
http://www.oracle.com/technetwork/java/javase/gc-tuning-6-140523.html mentions the -XX:CMSInitiatingOccupancyFraction=<N> to influence when CMS algorithm triggers a concurrent collection. GCViewer should evaluate the log file and calculate the value when concurrent collections really are started.
When opening my GC log file generated with Sun's latest JDK 7u7 x64 -server VM for a JVM using 52Gig heap and using these memory/gc-relevant flags to generate the log, I see these parsing errors:
-Xms36g
-Xmx52g
-XX:+UseG1GC
-XX:MaxGCPauseMillis=100
-XX:+PrintGCDetails
-XX:+PrintGCDateStamps
-XX:+HeapDumpOnOutOfMemoryError
-Xloggc:/var/log/solr/GCLogFile.log
---snip---
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: 'GC concurrent-mark-reset-for-overflow' Line 68: 2012-09-20T03:43:57.037+0000: 632.417: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 2239: 2012-09-20T06:28:46.360+0000: 10521.741: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 2366: 2012-09-20T06:44:34.556+0000: 11469.937: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 2683: 2012-09-20T07:13:17.039+0000: 13192.420: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 2936: 2012-09-20T07:33:32.203+0000: 14407.583: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 3265: 2012-09-20T07:50:24.275+0000: 15419.656: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 3938: 2012-09-20T08:39:00.973+0000: 18336.354: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 3981: 2012-09-20T08:42:16.018+0000: 18531.399: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 4066: 2012-09-20T08:49:38.845+0000: 18974.226: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 5520: 2012-09-20T09:55:22.710+0000: 22918.091: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 6796: 2012-09-20T10:20:28.803+0000: 24424.183: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 6881: 2012-09-20T10:21:41.374+0000: 24496.755: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 8090: 2012-09-20T10:38:39.537+0000: 25514.917: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 8343: 2012-09-20T10:42:03.562+0000: 25718.943: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 10990: 2012-09-20T11:10:43.833+0000: 27439.213: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 11327: 2012-09-20T11:13:35.124+0000: 27610.505: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 11538: 2012-09-20T11:15:14.974+0000: 27710.355: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 13135: 2012-09-20T11:26:43.243+0000: 28398.624: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 13766: 2012-09-20T11:31:16.286+0000: 28671.667: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 14271: 2012-09-20T11:34:24.338+0000: 28859.719: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 18087: 2012-09-20T11:56:12.001+0000: 30167.382: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 21196: 2012-09-20T12:13:01.467+0000: 31176.847: [GC concurrent-mark-reset-for-overflow]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'GC concurrent-mark-reset-for-overflow' Line 22534: 2012-09-20T12:20:24.562+0000: 31619.943: [GC concurrent-mark-reset-for-overflow]
INFO [DataReaderSun1_6_0G1]: Done reading.
---snip---
Hi.
It seems to be an unsupported GClog format found.
java version "1.6.0_22"
OpenJDK Runtime Environment (IcedTea6 1.10.6) (rhel-1.25.1.10.6.0.1.el5_8-x86_64)
OpenJDK 64-Bit Server VM (build 20.0-b11, mixed mode)
JVM parameters
-verbose:gc -XX:+PrintTenuringDistribution -XX:+PrintGCDetails -XX:+PrintGCTimeStamps
GCViewer log:
INFO [DataReaderFacade]: GCViewer version 1.32-SNAPSHOT (2013-03-11 14:42)
INFO [DataReaderFactory]: File format: Sun 1.6.x
INFO [DataReaderSun1_6_0]: Reading Sun / Oracle 1.4.x / 1.5.x / 1.6.x / 1.7.x format...
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NumberFormatException: For input string: "188744K->13345K(4063232K).") Line 1: 3.141: [GCDesired survivor size 134217728 bytes, new threshold 7 (max 2) [PSYoungGen: 188744K->13345K(917504K)] 188744K->13345K(4063232K), 0.0285820 secs] [Times: user=0.06 sys=0.01, real=0.03 secs]
GC log example:
3.141: [GCDesired survivor size 134217728 bytes, new threshold 7 (max 2) [PSYoungGen: 188744K->13345K(917504K)] 188744K->13345K(4063232K), 0.0285820 secs] [Times: user=0.06 sys=0.01, real=0.03 secs]
3.170: [Full GC (System) [PSYoungGen: 13345K->0K(917504K)] [PSOldGen: 0K->13159K(3145728K)] 13345K->13159K(4063232K) [PSPermGen: 14997K->14997K(262144K)], 0.0944050 secs] [Times: user=0.09 sys=0.01, real=0.10 secs]
4.083: [GCDesired survivor size 134217728 bytes, new threshold 7 (max 2) [PSYoungGen: 786432K->25297K(917504K)] 799591K->38457K(4063232K), 0.0444010 secs] [Times: user=0.07 sys=0.01, real=0.04 secs]
Exception rased here
AbstractDataReaderSun.java
protected void parsePause(GCEvent event, String line, ParsePosition pos)
246 event.setPause(Double.parseDouble(line.substring(pos.getIndex(), end).replace(',', '.')));Code line substring(pos.getIndex(), end).replace(',', '.')) returns "188744K->13345K(4063232K)." but I think "0.0285820" is expected.
Run java 1.6 u31 server mode
with these GC switches
-XX:+UseConcMarkSweepGC"
-XX:+UseParNewGC"
-XX:GCTimeRatio=2"
-XX:+CMSScavengeBeforeRemark
-XX:+CMSClassUnloadingEnabled
INFO [DataReaderFactory]: File format: Sun 1.6.x
INFO [DataReaderSun1_6_0]: Reading Sun / Oracle 1.4.x / 1.5.x / 1.6.x / 1.7.x format...
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 12: 8.823: [Rescan (parallel) , 0.0179610 secs]8.841: [weak refs processing, 0.0003610 secs]8.841: [class unloading, 0.0089450 secs]8.850: [scrub symbol & string tables, 0.0054990 secs] [1 CMS-remark: 0K(6733056K)] 16217K(8247936K), 0.0795250 secs] [Times: user=0.24 sys=0.02, real=0.08 secs]
INFO [DataReaderSun1_6_0]: Done reading.
No doubt you are aware Java 7 is due for release. I have noticed GCViewer does not work very well when using Garbage First (-XX:+UseG1GC) collector.
The x-axis of the graph shows time in duration from startup, just like the gc.log. It would be cool if I could tell you what time the app started, and you can turn those duration offsets in to actual clock times.
eg, if the application started at 10pm, you woudl change the label saying 5h to say 3:00 AM. This makes it easier to compare two GC files side by side.
Having a histogram would be very helpful.
I hacked together something quickly using JFreeChart to do this - but someone who is better with Swing could likely do it much better.
JFreeChart makes it easy to do the histogram part - you could also have it have different colors for the different kind of pause events.
https://github.com/ryangardner/GCViewer/commit/1926f74d65aa1004bce40c9441db90ce894b7f74
GCViewer shows several metrics in a few tabs concerning the pauses found in the gc log. However there is no information about the detailed types of events that were found.
Add a new tab showing a few figures for every event type that was found in the log file.
The following GC logfile is generated from IBM Java 6 SR5 on AIX, it has two main problems:
https://mft.seeburger.de/portal-seefx/~public/ca4ab842-2ff5-457c-b6b6-f5e458e3adf0?download
In general, this is a really useful app. Thanks!
One minor niggle though is that the app does not seem to be able to parse logs when concurrent collection is used instead of STW collection. Is there any way to send over the gc log input?
I see the following errors on startup.
INFO [DataReaderFactory]: File format: Sun 1.6.x
INFO [DataReaderSun1_6_0]: Reading Sun / Oracle 1.4.x / 1.5.x / 1.6.x / 1.7.x format...
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Application time' Line 599: 2012-08-23T04:31:51.269-0400: Application time: 0.0000610 seconds
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Application time' Line 1197: 2012-08-23T04:32:38.692-0400: Application time: 0.0001990 seconds
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '.' Line 1771: 2012-08-23T04:42:09.574-0400: 725.583Application time: 0.0002480 seconds
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NullPointerException) Line 1772: : [CMS-concurrent-sweep-start]
INFO [DataReaderSun1_6_0]: Done reading.
I think that ability to focus on a time range of the log can be very beneficial.
Many times the general throughput may seem OK but over a shorter period of you can see that there is a big problem.
From GUI perspective you may add two time selectors start time and end time.
Hey!
Trying to tune an app, logged GC with -XX:+PrintGCDetails -XX:-UseAdaptiveSizePolicy -XX:+PrintAdaptiveSizePolicy -XX:+PrintGCDateStamps -Xloggc:[...].
INFO [DataReaderFactory]: File format: Sun 1.6.x
INFO [DataReaderSun1_6_0]: Reading Sun / Oracle 1.4.x / 1.5.x / 1.6.x / 1.7.x format...
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 462: 2012-09-26T18:23:54.092+0200: 6591.672: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3994640 promoted: 49152 overflow: false [PSYoungGen: 783920K->3901K(786304K)] 1036641K->256670K(1042304K), 0.0078830 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NumberFormatException: For input string: "260017K->111440K(1042304K)") Line 529: 2012-09-26T18:34:25.956+0200: 7223.537: [GC2012-09-26T18:34:25.964+0200: 7223.545: [Full GC [PSYoungGen: 4064K->0K(786304K)] [ParOldGen: 255953K->111440K(256000K)] 260017K->111440K(1042304K) [PSPermGen: 142360K->138587K(262144K)], 1.2956780 secs] [Times: user=4.32 sys=0.02, real=1.30 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 1154: 2012-09-26T20:08:16.521+0200: 12854.102: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3866624 promoted: 49152 overflow: false [PSYoungGen: 784000K->3776K(786304K)] 925980K->145804K(1042304K), 0.0068860 secs] [Times: user=0.03 sys=0.00, real=0.00 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 1614: 2012-09-26T21:17:48.435+0200: 17026.016: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3932160 promoted: 49152 overflow: false [PSYoungGen: 783872K->3840K(786304K)] 948627K->168643K(1042304K), 0.0060840 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 2074: 2012-09-26T22:29:15.085+0200: 21312.665: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3801088 promoted: 49152 overflow: false [PSYoungGen: 783808K->3712K(786304K)] 971314K->191266K(1042304K), 0.0073270 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 2534: 2012-09-26T23:41:40.907+0200: 25658.488: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3899392 promoted: 49152 overflow: false [PSYoungGen: 783840K->3808K(786304K)] 993664K->213680K(1042304K), 0.0062760 secs] [Times: user=0.03 sys=0.00, real=0.00 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 2993: 2012-09-27T00:55:27.250+0200: 30084.831: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3932160 promoted: 40960 overflow: false [PSYoungGen: 783808K->3840K(786304K)] 1015958K->236030K(1042304K), 0.0067370 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 3222: 2012-09-27T01:32:25.304+0200: 32302.885: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3964928 promoted: 40960 overflow: false [PSYoungGen: 783864K->3872K(786304K)] 1026982K->247030K(1042304K), 0.0069120 secs] [Times: user=0.04 sys=0.00, real=0.00 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 3451: 2012-09-27T02:09:51.757+0200: 34549.337: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3932160 promoted: 49152 overflow: false [PSYoungGen: 783872K->3840K(786304K)] 1038197K->258213K(1042304K), 0.0071970 secs] [Times: user=0.04 sys=0.00, real=0.00 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NumberFormatException: For input string: "259765K->114749K(1042304K)") Line 3485: 2012-09-27T02:15:26.242+0200: 34883.822: [GC2012-09-27T02:15:26.249+0200: 34883.829: [Full GC [PSYoungGen: 3808K->0K(786304K)] [ParOldGen: 255957K->114749K(256000K)] 259765K->114749K(1042304K) [PSPermGen: 143531K->139011K(262144K)], 0.5934780 secs] [Times: user=1.82 sys=0.01, real=0.59 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 3681: 2012-09-27T02:44:37.901+0200: 36635.481: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 4014096 promoted: 40960 overflow: false [PSYoungGen: 783872K->3920K(786304K)] 907269K->127357K(1042304K), 0.0056990 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 4141: 2012-09-27T03:53:37.575+0200: 40775.156: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3929104 promoted: 49152 overflow: false [PSYoungGen: 783904K->3837K(786304K)] 928926K->148907K(1042304K), 0.0057890 secs] [Times: user=0.03 sys=0.00, real=0.00 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 4601: 2012-09-27T05:03:04.944+0200: 44942.525: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3964928 promoted: 49152 overflow: false [PSYoungGen: 783872K->3872K(786304K)] 949992K->170040K(1042304K), 0.0061710 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 5061: 2012-09-27T06:13:24.398+0200: 49161.978: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3899392 promoted: 40960 overflow: false [PSYoungGen: 783837K->3808K(786304K)] 971070K->191081K(1042304K), 0.0065200 secs] [Times: user=0.04 sys=0.00, real=0.00 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 5521: 2012-09-27T07:23:58.066+0200: 53395.646: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 3997696 promoted: 40960 overflow: false [PSYoungGen: 783872K->3904K(786304K)] 991872K->211944K(1042304K), 0.0070690 secs] [Times: user=0.04 sys=0.00, real=0.00 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 5980: 2012-09-27T08:35:10.220+0200: 57667.801: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 4227088 promoted: 49152 overflow: false [PSYoungGen: 784192K->4128K(786304K)] 1012880K->232864K(1042304K), 0.0068950 secs] [Times: user=0.04 sys=0.00, real=0.01 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry (java.lang.NumberFormatException: For input string: "1014212K->1040157K(1042304K).") Line 6003: 2012-09-27T08:38:47.257+0200: 57884.837: [GC2012-09-27T08:38:56.052+0200: 57893.633: [GC-- [PSYoungGen: 784160K->784160K(786304K)] 1014212K->1040157K(1042304K), 0.3464400 secs] [Times: user=0.41 sys=0.41, real=0.34 secs]
WARNING [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.ParseException: problem resetting stream (java.io.IOException: Mark invalid) Line 6015: 2012-09-27T10:06:38.415+0200: 63155.996: [GCAdaptiveSizePolicy::compute_survivor_space_size_and_thresh: survived: 6389792 promoted: 7036960 overflow: true [PSYoungGen: 786272K->6240K(786304K)] 983913K->210753K(1042304K), 0.0312370 secs]
Best regards, Kai
Hello!
I have a log which shows that application was stopped for more than 1second.
-bash-3.2$ grep "stopped" gc.log |grep -v "0."
Total time for which application threads were stopped: 1.0452510 seconds
Total time for which application threads were stopped: 1.2077760 seconds
Total time for which application threads were stopped: 1.8804600 seconds
Total time for which application threads were stopped: 1.7116780 seconds
...
But for some reason GCViewer Y-axis maximum value is 0.12second. If I don't grep the log it seems that everything was fine though really it wasn't.
Here is the log - http://db.tt/dr8mjtou
And the picture - http://db.tt/AbaYsm6p
Thanks!
I recently had to parse some GC logs produced with JDK 1.7.0_12-ea-b08. Upon loading the file in GCViewer, a bunch of NumberFormatExceptions were reported. I tracked this down to the fact that the following format is currently not supported:
[Eden: 118.0M(118.0M)->0.0B(112.0M) Survivors: 10.0M->16.0M Heap: 548.6M(640.0M)->440.6M(640.0M)]
After some debugging, I noticed that DataReaderSun1_6_0G1#parseDetails calls AbstractDataReaderSun#setMemoryExtended which attempts to parse the values as integers using NumberParser#parseInt. Clearly that fails due to the presence of decimal separators. Also, in AbstractDataReaderSun#setMemoryExtended the for-loop that determines the length of the post-memory number (line 140) assumes that all characters are digits (so not a dot).
It'd be great if GCViewer would support this!
(Locally I added an ugly hack to NumberParser#parseInt in order work around these things (that was faster than generating a new GC log ;)), but these changes are not suitable for sharing. (If you really want me to I can paste them here, though...).)
Semi-relatedly, I noticed that only one of the six methods in NumberParser is used. It might be worthwhile to determine how much of a speedup the NumberParser provides over Java's built-in API. If the difference is not very significant, I'd drop that class entirely and simply fix this issue with some calls to String#substring and Double#parseDouble instead. (And a small change to AbstractDataReaderSun#getMemoryInKiloByte.) If you agree with that approach I'd be willing to make the changes and open a pull request.
Limit analysis to part of the log. Some times you want to focus on a certain part of the log. Can a mechanism to choose time range be added ?
Hello,
I am trying to analyse a GC log and I get some UnknownGcTypeException exception
GC options are the following:
-Xms2560m -Xmx2560m -XX:PermSize=512m -XX:MaxPermSize=512m -Xmn1024m -XX:SurvivorRatio=4 -XX:TargetSurvivorRatio=80 -XX:Paral
lelGCThreads=20 -XX:+UseParNewGC -XX:+UseConcMarkSweepGC -XX:+CMSParallelRemarkEnabled -XX:+CMSScavengeBeforeRemark -XX:+DisableExplicitGC
Error log is below:
INFO [DataReaderFactory]: File format: Sun 1.4.x
INFO [DataReaderSun1_6_0]: Reading Sun / Oracle 1.4.x / 1.5.x / 1.6.x / 1.7.x format...
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 77: 1308.672: [Scavenge-Before-Remark1308.672: [Full GC 1308.672: [ParNew: 193561K->33300K(873856K), 0.0543916 secs] 980585K->822232K(2446720K), 0.0546355 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 78: , 0.0546659 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 79: 1308.727: [Rescan (parallel) , 0.0488635 secs]1308.776: [weak refs processing, 0.6351856 secs] [1 CMS-remark: 788932K(1572864K)] 822232K(2446720K), 0.7397633 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 155: 2560.032: [Scavenge-Before-Remark2560.032: [Full GC 2560.032: [ParNew: 473235K->137317K(873856K), 0.2633344 secs] 1163810K->874747K(2446720K), 0.2991274 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 156: , 0.2993084 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 157: 2560.331: [Rescan (parallel) , 0.1676805 secs]2560.499: [weak refs processing, 0.3533432 secs] [1 CMS-remark: 737429K(1572864K)] 874747K(2446720K), 0.8372736 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 173: 2610.012: [Scavenge-Before-Remark2610.012: [Full GC 2610.012: [ParNew: 444921K->124188K(873856K), 0.0722482 secs] 1183218K->862485K(2446720K), 0.0725347 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 174: , 0.0725624 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 175: 2610.085: [Rescan (parallel) , 0.0621148 secs]2610.147: [weak refs processing, 0.3221777 secs] [1 CMS-remark: 738297K(1572864K)] 862485K(2446720K), 0.4573945 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 187: 2616.477: [Scavenge-Before-Remark2616.477: [Full GC 2616.477: [ParNew: 393744K->119534K(873856K), 0.0671962 secs] 1103662K->829452K(2446720K), 0.0674536 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 188: , 0.0674859 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 189: 2616.544: [Rescan (parallel) , 0.0531379 secs]2616.597: [weak refs processing, 0.1255454 secs] [1 CMS-remark: 709918K(1572864K)] 829452K(2446720K), 0.2464452 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 201: 2622.566: [Scavenge-Before-Remark2622.566: [Full GC 2622.566: [ParNew: 399309K->126656K(873856K), 0.0701272 secs] 1108754K->836102K(2446720K), 0.0703788 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 202: , 0.0704076 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 203: 2622.636: [Rescan (parallel) , 0.0507181 secs]2622.687: [weak refs processing, 0.1191370 secs] [1 CMS-remark: 709445K(1572864K)] 836102K(2446720K), 0.2405217 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 215: 2629.253: [Scavenge-Before-Remark2629.253: [Full GC 2629.253: [ParNew: 442369K->143920K(873856K), 0.0780283 secs] 1150737K->852289K(2446720K), 0.0783489 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 216: , 0.0783818 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 217: 2629.331: [Rescan (parallel) , 0.0555681 secs]2629.387: [weak refs processing, 0.1176002 secs] [1 CMS-remark: 708368K(1572864K)] 852289K(2446720K), 0.2518210 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 229: 2635.466: [Scavenge-Before-Remark2635.466: [Full GC 2635.466: [ParNew: 459695K->152125K(873856K), 0.0890294 secs] 1167826K->866797K(2446720K), 0.0893222 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 230: , 0.0893643 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 231: 2635.555: [Rescan (parallel) , 0.0649924 secs]2635.620: [weak refs processing, 0.1144237 secs] [1 CMS-remark: 714672K(1572864K)] 866797K(2446720K), 0.2690987 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 243: 2641.412: [Scavenge-Before-Remark2641.412: [Full GC 2641.412: [ParNew: 551236K->149176K(873856K), 0.0891998 secs] 1265851K->880415K(2446720K), 0.0894295 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 244: , 0.0894604 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 245: 2641.502: [Rescan (parallel) , 0.0556457 secs]2641.557: [weak refs processing, 0.1091818 secs] [1 CMS-remark: 731238K(1572864K)] 880415K(2446720K), 0.2545326 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 257: 2647.304: [Scavenge-Before-Remark2647.304: [Full GC 2647.304: [ParNew: 472441K->134857K(873856K), 0.0881405 secs] 1203212K->895298K(2446720K), 0.0884200 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 258: , 0.0884503 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 259: 2647.392: [Rescan (parallel) , 0.0546898 secs]2647.447: [weak refs processing, 0.1211880 secs] [1 CMS-remark: 760440K(1572864K)] 895298K(2446720K), 0.2646099 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 271: 2653.744: [Scavenge-Before-Remark2653.744: [Full GC 2653.744: [ParNew: 387493K->147786K(873856K), 0.0819389 secs] 1142629K->902921K(2446720K), 0.0822033 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 272: , 0.0822364 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 273: 2653.826: [Rescan (parallel) , 0.0518830 secs]2653.878: [weak refs processing, 0.1207197 secs] [1 CMS-remark: 755135K(1572864K)] 902921K(2446720K), 0.2551300 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 285: 2659.948: [Scavenge-Before-Remark2659.948: [Full GC 2659.948: [ParNew: 401472K->150701K(873856K), 0.0865770 secs] 1156325K->916072K(2446720K), 0.0868207 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 286: , 0.0868508 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 287: 2660.035: [Rescan (parallel) , 0.0523586 secs]2660.087: [weak refs processing, 0.1157930 secs] [1 CMS-remark: 765371K(1572864K)] 916072K(2446720K), 0.2553485 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 299: 2665.762: [Scavenge-Before-Remark2665.762: [Full GC 2665.763: [ParNew: 566700K->156813K(873856K), 0.1183655 secs] 1331745K->951514K(2446720K), 0.1185993 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 300: , 0.1186300 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 301: 2665.881: [Rescan (parallel) , 0.1156421 secs]2665.997: [weak refs processing, 0.2763637 secs] [1 CMS-remark: 794700K(1572864K)] 951514K(2446720K), 0.5109151 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 314: 2672.594: [Scavenge-Before-Remark2672.594: [Full GC 2672.594: [ParNew: 180523K->139927K(873856K), 0.1073046 secs] 992481K->979616K(2446720K), 0.1075373 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 315: , 0.1075641 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 316: 2672.701: [Rescan (parallel) , 0.4374177 secs]2673.139: [weak refs processing, 0.1575410 secs] [1 CMS-remark: 839689K(1572864K)] 979616K(2446720K), 0.7031697 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 328: 2679.646: [Scavenge-Before-Remark2679.646: [Full GC 2679.646: [ParNew: 499851K->119250K(873856K), 0.0653728 secs] 1335159K->954558K(2446720K), 0.0656733 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 329: , 0.0657040 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 330: 2679.712: [Rescan (parallel) , 0.0459752 secs]2679.758: [weak refs processing, 0.2716084 secs] [1 CMS-remark: 835308K(1572864K)] 954558K(2446720K), 0.3835521 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 342: 2686.111: [Scavenge-Before-Remark2686.111: [Full GC 2686.111: [ParNew: 475811K->128829K(873856K), 0.0708517 secs] 1310732K->963750K(2446720K), 0.0711132 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 343: , 0.0711441 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 344: 2686.182: [Rescan (parallel) , 0.0457221 secs]2686.228: [weak refs processing, 0.1013933 secs] [1 CMS-remark: 834921K(1572864K)] 963750K(2446720K), 0.2185464 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 356: 2692.040: [Scavenge-Before-Remark2692.040: [Full GC 2692.040: [ParNew: 274117K->139527K(873856K), 0.0727106 secs] 1108969K->974379K(2446720K), 0.0729834 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 357: , 0.0730143 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 358: 2692.113: [Rescan (parallel) , 0.0532521 secs]2692.166: [weak refs processing, 0.1019428 secs] [1 CMS-remark: 834851K(1572864K)] 974379K(2446720K), 0.2284624 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 370: 2698.831: [Scavenge-Before-Remark2698.831: [Full GC 2698.831: [ParNew: 553996K->154518K(873856K), 0.0863550 secs] 1388575K->989097K(2446720K), 0.0866670 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 371: , 0.0867006 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 372: 2698.918: [Rescan (parallel) , 0.0603655 secs]2698.978: [weak refs processing, 0.1037809 secs] [1 CMS-remark: 834578K(1572864K)] 989097K(2446720K), 0.2511741 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 384: 2705.060: [Scavenge-Before-Remark2705.060: [Full GC 2705.060: [ParNew: 437869K->134018K(873856K), 0.0972784 secs] 1272402K->997269K(2446720K), 0.0975554 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 385: , 0.0975874 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 386: 2705.158: [Rescan (parallel) , 0.0589218 secs]2705.217: [weak refs processing, 0.2636736 secs] [1 CMS-remark: 863251K(1572864K)] 997269K(2446720K), 0.4204617 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 398: 2711.334: [Scavenge-Before-Remark2711.334: [Full GC 2711.334: [ParNew: 527170K->147071K(873856K), 0.0806044 secs] 1390321K->1010222K(2446720K), 0.0808766 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 399: , 0.0809074 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 400: 2711.415: [Rescan (parallel) , 0.0719107 secs]2711.487: [weak refs processing, 0.1003880 secs] [1 CMS-remark: 863151K(1572864K)] 1010222K(2446720K), 0.2535174 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 412: 2718.451: [Scavenge-Before-Remark2718.451: [Full GC 2718.451: [ParNew: 499051K->148642K(873856K), 0.1302858 secs] 1362064K->1026903K(2446720K), 0.1308197 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 413: , 0.1308567 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 414: 2718.582: [Rescan (parallel) , 0.0758221 secs]2718.658: [weak refs processing, 0.1126503 secs] [1 CMS-remark: 878260K(1572864K)] 1026903K(2446720K), 0.3196362 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 426: 2725.073: [Scavenge-Before-Remark2725.073: [Full GC 2725.073: [ParNew: 685215K->143166K(873856K), 0.0996978 secs] 1563371K->1034205K(2446720K), 0.1000634 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 427: , 0.1001031 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 428: 2725.173: [Rescan (parallel) , 0.0640064 secs]2725.237: [weak refs processing, 0.1081031 secs] [1 CMS-remark: 891038K(1572864K)] 1034205K(2446720K), 0.2725058 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 440: 2731.396: [Scavenge-Before-Remark2731.396: [Full GC 2731.397: [ParNew: 500220K->150899K(873856K), 0.1126110 secs] 1390695K->1054977K(2446720K), 0.1128998 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 441: , 0.1129762 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 442: 2731.509: [Rescan (parallel) , 0.0684534 secs]2731.578: [weak refs processing, 0.1030151 secs] [1 CMS-remark: 904077K(1572864K)] 1054977K(2446720K), 0.2847382 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 454: 2738.014: [Scavenge-Before-Remark2738.014: [Full GC 2738.014: [ParNew: 770200K->149699K(873856K), 0.1017642 secs] 1673675K->1067544K(2446720K), 0.1020263 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 455: , 0.1020874 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 456: 2738.116: [Rescan (parallel) , 0.0715531 secs]2738.188: [weak refs processing, 0.1066517 secs] [1 CMS-remark: 917844K(1572864K)] 1067544K(2446720K), 0.2806047 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 468: 2743.113: [Scavenge-Before-Remark2743.113: [Full GC 2743.114: [ParNew: 491676K->141323K(873856K), 0.1034806 secs] 1408858K->1074783K(2446720K), 0.1037579 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 469: , 0.1037896 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 470: 2743.217: [Rescan (parallel) , 0.0604310 secs]2743.278: [weak refs processing, 0.1042659 secs] [1 CMS-remark: 933460K(1572864K)] 1074783K(2446720K), 0.2687672 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 482: 2748.984: [Scavenge-Before-Remark2748.984: [Full GC 2748.984: [ParNew: 375662K->148130K(873856K), 0.1089865 secs] 1307918K->1092747K(2446720K), 0.1092546 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 483: , 0.1092855 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 484: 2749.093: [Rescan (parallel) , 0.0639693 secs]2749.158: [weak refs processing, 0.2794590 secs] [1 CMS-remark: 944617K(1572864K)] 1092747K(2446720K), 0.4530715 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 496: 2755.763: [Scavenge-Before-Remark2755.763: [Full GC 2755.763: [ParNew: 624057K->161127K(873856K), 0.0965538 secs] 1568288K->1115132K(2446720K), 0.0968121 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 497: , 0.0968427 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 498: 2755.860: [Rescan (parallel) , 0.0574673 secs]2755.917: [weak refs processing, 0.1050856 secs] [1 CMS-remark: 954005K(1572864K)] 1115132K(2446720K), 0.2597116 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 510: 2761.033: [Scavenge-Before-Remark2761.033: [Full GC 2761.033: [ParNew: 357619K->140001K(873856K), 0.1204892 secs] 1311242K->1117955K(2446720K), 0.1207710 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 511: , 0.1208015 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 512: 2761.154: [Rescan (parallel) , 0.0535974 secs]2761.208: [weak refs processing, 0.2716218 secs] [1 CMS-remark: 977954K(1572864K)] 1117955K(2446720K), 0.4463300 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 524: 2767.358: [Scavenge-Before-Remark2767.358: [Full GC 2767.358: [ParNew: 440437K->149246K(873856K), 0.0839093 secs] 1417616K->1134630K(2446720K), 0.0842013 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 525: , 0.0842323 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 526: 2767.442: [Rescan (parallel) , 0.0648347 secs]2767.507: [weak refs processing, 0.1060468 secs] [1 CMS-remark: 985384K(1572864K)] 1134630K(2446720K), 0.2554131 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 538: 2773.416: [Scavenge-Before-Remark2773.416: [Full GC 2773.417: [ParNew: 494515K->155705K(873856K), 0.0923059 secs] 1479789K->1150927K(2446720K), 0.0925562 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 539: , 0.0925878 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 540: 2773.509: [Rescan (parallel) , 0.0687451 secs]2773.578: [weak refs processing, 0.1013088 secs] [1 CMS-remark: 995222K(1572864K)] 1150927K(2446720K), 0.2628792 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 552: 2779.835: [Scavenge-Before-Remark2779.835: [Full GC 2779.835: [ParNew: 729224K->161815K(873856K), 0.1075464 secs] 1724119K->1173741K(2446720K), 0.1078402 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 553: , 0.1078698 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 554: 2779.943: [Rescan (parallel) , 0.0739317 secs]2780.017: [weak refs processing, 0.1020529 secs] [1 CMS-remark: 1011925K(1572864K)] 1173741K(2446720K), 0.2841041 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 566: 2786.204: [Scavenge-Before-Remark2786.204: [Full GC 2786.204: [ParNew: 504828K->128510K(873856K), 0.1081105 secs] 1515755K->1170895K(2446720K), 0.1083840 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 567: , 0.1084151 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 568: 2786.313: [Rescan (parallel) , 0.0544303 secs]2786.367: [weak refs processing, 0.1030000 secs] [1 CMS-remark: 1042385K(1572864K)] 1170895K(2446720K), 0.2661077 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 580: 2792.437: [Scavenge-Before-Remark2792.437: [Full GC 2792.437: [ParNew: 539609K->147380K(873856K), 0.0816407 secs] 1580987K->1188758K(2446720K), 0.0819190 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 581: , 0.0819496 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 582: 2792.519: [Rescan (parallel) , 0.0674715 secs]2792.587: [weak refs processing, 0.1037073 secs] [1 CMS-remark: 1041377K(1572864K)] 1188758K(2446720K), 0.2533976 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 594: 2798.724: [Scavenge-Before-Remark2798.724: [Full GC 2798.724: [ParNew: 540928K->147092K(873856K), 0.0867785 secs] 1581722K->1200445K(2446720K), 0.0870163 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 595: , 0.0870461 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 596: 2798.811: [Rescan (parallel) , 0.0665667 secs]2798.878: [weak refs processing, 0.0998792 secs] [1 CMS-remark: 1053353K(1572864K)] 1200445K(2446720K), 0.2537141 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 608: 2804.943: [Scavenge-Before-Remark2804.943: [Full GC 2804.943: [ParNew: 778930K->155253K(873856K), 0.0931031 secs] 1832089K->1218307K(2446720K), 0.0933426 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 609: , 0.0933728 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 610: 2805.036: [Rescan (parallel) , 0.0803775 secs]2805.117: [weak refs processing, 0.1025267 secs] [1 CMS-remark: 1063054K(1572864K)] 1218307K(2446720K), 0.2765731 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 622: 2811.124: [Scavenge-Before-Remark2811.124: [Full GC 2811.124: [ParNew: 459240K->151043K(873856K), 0.0997535 secs] 1520515K->1229448K(2446720K), 0.0999949 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 623: , 0.1000243 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 624: 2811.224: [Rescan (parallel) , 0.0733864 secs]2811.297: [weak refs processing, 0.1074539 secs] [1 CMS-remark: 1078404K(1572864K)] 1229448K(2446720K), 0.2811230 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 636: 2816.345: [Scavenge-Before-Remark2816.345: [Full GC 2816.345: [ParNew: 773324K->156126K(873856K), 0.1206856 secs] 1851282K->1253192K(2446720K), 0.1209271 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 637: , 0.1209558 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 638: 2816.466: [Rescan (parallel) , 0.0700309 secs]2816.536: [weak refs processing, 0.1016760 secs] [1 CMS-remark: 1097066K(1572864K)] 1253192K(2446720K), 0.2929566 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 650: 2822.704: [Scavenge-Before-Remark2822.704: [Full GC 2822.704: [ParNew: 515352K->154645K(873856K), 0.1030660 secs] 1610500K->1272670K(2446720K), 0.1033310 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 651: , 0.1033601 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 652: 2822.807: [Rescan (parallel) , 0.0600114 secs]2822.867: [weak refs processing, 0.0992275 secs] [1 CMS-remark: 1118024K(1572864K)] 1272670K(2446720K), 0.2628688 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 665: 2829.534: [Scavenge-Before-Remark2829.534: [Full GC 2829.534: [ParNew: 504722K->149874K(873856K), 0.1544415 secs] 1648729K->1338407K(2446720K), 0.1547200 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 666: , 0.1547498 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 667: 2829.689: [Rescan (parallel) , 0.1487430 secs]2829.837: [weak refs processing, 0.0989631 secs] [1 CMS-remark: 1188533K(1572864K)] 1338407K(2446720K), 0.4026980 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 680: 2836.784: [Scavenge-Before-Remark2836.784: [Full GC 2836.784: [ParNew: 292939K->112695K(873856K), 0.0681518 secs] 1492582K->1323102K(2446720K), 0.0683931 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 681: , 0.0684223 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 682: 2836.852: [Rescan (parallel) , 0.0351516 secs]2836.887: [weak refs processing, 0.0921405 secs] [1 CMS-remark: 1210406K(1572864K)] 1323102K(2446720K), 0.1960270 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 694: 2842.762: [Scavenge-Before-Remark2842.762: [Full GC 2842.762: [ParNew: 426383K->174720K(873856K), 0.2821999 secs] 1630704K->1397565K(2446720K), 0.2824507 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 695: , 0.2824817 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 696: 2843.045: [Rescan (parallel) , 0.3226060 secs]2843.368: [weak refs processing, 0.2548219 secs] [1 CMS-remark: 1222845K(1572864K)] 1397565K(2446720K), 0.8601378 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 708: 2847.982: [Scavenge-Before-Remark2847.982: [Full GC 2847.982: [ParNew: 627885K->174720K(873856K), 0.2882685 secs] 1849512K->1446113K(2446720K), 0.2885773 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 709: , 0.2885970 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 710: 2848.271: [Rescan (parallel) , 0.5563095 secs]2848.827: [weak refs processing, 0.0992482 secs] [1 CMS-remark: 1271393K(1572864K)] 1446113K(2446720K), 0.9444252 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 722: 2854.736: [Scavenge-Before-Remark2854.736: [Full GC 2854.736: [ParNew: 580949K->56597K(873856K), 3.0393220 secs] 1810563K->1385803K(2446720K), 3.0396156 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 723: , 3.0396469 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 724: 2857.776: [Rescan (parallel) , 0.2010859 secs]2857.977: [weak refs processing, 0.0842790 secs] [1 CMS-remark: 1329206K(1572864K)] 1385803K(2446720K), 3.3252858 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 736: 2863.723: [Scavenge-Before-Remark2863.723: [Full GC 2863.723: [ParNew: 641909K->84618K(873856K), 1.1227607 secs] 1958837K->1401546K(2446720K), 1.1230721 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 737: , 1.1231845 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 738: 2864.846: [Rescan (parallel) , 0.2103673 secs]2865.056: [weak refs processing, 0.0809668 secs] [1 CMS-remark: 1316927K(1572864K)] 1401546K(2446720K), 1.4148402 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 750: 2869.852: [Scavenge-Before-Remark2869.852: [Full GC 2869.852: [ParNew: 447234K->72332K(873856K), 1.0974966 secs] 1760491K->1385588K(2446720K), 1.0978036 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 751: , 1.0978337 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 752: 2870.950: [Rescan (parallel) , 0.1861595 secs]2871.136: [weak refs processing, 0.0812781 secs] [1 CMS-remark: 1313256K(1572864K)] 1385588K(2446720K), 1.3655636 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 765: 2879.223: [Scavenge-Before-Remark2879.223: [Full GC 2879.223: [ParNew: 341574K->81554K(873856K), 1.1082151 secs] 1652821K->1392800K(2446720K), 1.1085411 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 766: , 1.1085722 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 767: 2880.331: [Rescan (parallel) , 0.1855686 secs]2880.517: [weak refs processing, 0.0814680 secs] [1 CMS-remark: 1311246K(1572864K)] 1392800K(2446720K), 1.3758915 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 779: 2887.162: [Scavenge-Before-Remark2887.162: [Full GC 2887.162: [ParNew: 525110K->88034K(873856K), 1.1015961 secs] 1835230K->1398154K(2446720K), 1.1018857 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 780: , 1.1019161 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 781: 2888.264: [Rescan (parallel) , 0.1730579 secs]2888.437: [weak refs processing, 0.0854202 secs] [1 CMS-remark: 1310119K(1572864K)] 1398154K(2446720K), 1.3606485 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 793: 2895.195: [Scavenge-Before-Remark2895.195: [Full GC 2895.195: [ParNew: 567655K->81544K(873856K), 1.1024638 secs] 1877123K->1391011K(2446720K), 1.1027386 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 794: , 1.1027706 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 795: 2896.298: [Rescan (parallel) , 0.1989932 secs]2896.497: [weak refs processing, 0.1024512 secs] [1 CMS-remark: 1309467K(1572864K)] 1391011K(2446720K), 1.4045189 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 807: 2903.045: [Scavenge-Before-Remark2903.045: [Full GC 2903.045: [ParNew: 385703K->83748K(873856K), 1.1051400 secs] 1694537K->1392583K(2446720K), 1.1054341 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 808: , 1.1054629 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 809: 2904.150: [Rescan (parallel) , 0.1656979 secs]2904.316: [weak refs processing, 0.0849167 secs] [1 CMS-remark: 1308834K(1572864K)] 1392583K(2446720K), 1.3563185 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 822: 2911.437: [Scavenge-Before-Remark2911.437: [Full GC 2911.437: [ParNew: 159405K->117084K(873856K), 1.1370310 secs] 1467693K->1425372K(2446720K), 1.1372898 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 823: , 1.1373196 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 824: 2912.574: [Rescan (parallel) , 0.2641891 secs]2912.839: [weak refs processing, 0.0818371 secs] [1 CMS-remark: 1308287K(1572864K)] 1425372K(2446720K), 1.4836267 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 837: 2921.395: [Scavenge-Before-Remark2921.395: [Full GC 2921.395: [ParNew: 674933K->100261K(873856K), 1.1964090 secs] 1981067K->1441484K(2446720K), 1.1966795 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 838: , 1.1967101 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 839: 2922.591: [Rescan (parallel) , 0.1757469 secs]2922.767: [weak refs processing, 0.0839977 secs] [1 CMS-remark: 1341223K(1572864K)] 1441484K(2446720K), 1.4567353 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 852: 2929.312: [Scavenge-Before-Remark2929.312: [Full GC 2929.312: [ParNew: 87097K->60019K(873856K), 0.0495231 secs] 1427832K->1400754K(2446720K), 0.0497735 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 853: , 0.0498056 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 854: 2929.362: [Rescan (parallel) , 0.0447090 secs]2929.407: [weak refs processing, 0.0822532 secs] [1 CMS-remark: 1340735K(1572864K)] 1400754K(2446720K), 0.1770471 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 1081: 4641.373: [Scavenge-Before-Remark4641.373: [Full GC 4641.373: [ParNew: 234801K->30012K(873856K), 0.0336452 secs] 933583K->729039K(2446720K), 0.0339209 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 1082: , 0.0339521 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 1083: 4641.407: [Rescan (parallel) , 0.0488137 secs]4641.456: [weak refs processing, 0.3550351 secs] [1 CMS-remark: 699027K(1572864K)] 729039K(2446720K), 0.4384864 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 1271: 6190.493: [Scavenge-Before-Remark6190.493: [Full GC 6190.493: [ParNew: 335940K->16431K(873856K), 0.0200558 secs] 1035949K->717583K(2446720K), 0.0203178 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 1272: , 0.0203511 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 1273: 6190.514: [Rescan (parallel) , 0.0228765 secs]6190.537: [weak refs processing, 0.3858552 secs] [1 CMS-remark: 701152K(1572864K)] 717583K(2446720K), 0.4300632 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 1493: 12672.107: [Scavenge-Before-Remark12672.107: [Full GC 12672.107: [ParNew: 99959K->17706K(873856K), 0.0200388 secs] 798696K->716698K(2446720K), 0.0202679 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 1494: , 0.0202998 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 1495: 12672.127: [Rescan (parallel) , 0.0303466 secs]12672.158: [weak refs processing, 0.2932487 secs] [1 CMS-remark: 698991K(1572864K)] 716698K(2446720K), 0.3449143 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 1724: 14584.784: [Scavenge-Before-Remark14584.785: [Full GC 14584.785: [ParNew: 162450K->31083K(873856K), 0.0303224 secs] 861337K->730287K(2446720K), 0.0306454 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 1725: , 0.0306814 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 1726: 14584.815: [Rescan (parallel) , 0.0322185 secs]14584.848: [weak refs processing, 0.3730145 secs] [1 CMS-remark: 699203K(1572864K)] 730287K(2446720K), 0.4367329 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 1897: 16114.623: [Scavenge-Before-Remark16114.623: [Full GC 16114.623: [ParNew: 390252K->26463K(873856K), 0.0264099 secs] 1089856K->726337K(2446720K), 0.0266541 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 1898: , 0.0266863 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 1899: 16114.649: [Rescan (parallel) , 0.0259006 secs]16114.675: [weak refs processing, 0.3053898 secs] [1 CMS-remark: 699874K(1572864K)] 726337K(2446720K), 0.3586860 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 2093: 17757.283: [Scavenge-Before-Remark17757.283: [Full GC 17757.284: [ParNew: 323098K->31146K(873856K), 0.0329078 secs] 1023622K->732074K(2446720K), 0.0331892 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 2094: , 0.0332230 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 2095: 17757.317: [Rescan (parallel) , 0.0402489 secs]17757.357: [weak refs processing, 0.3196479 secs] [1 CMS-remark: 700927K(1572864K)] 732074K(2446720K), 0.3936795 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 2263: 18982.097: [Scavenge-Before-Remark18982.097: [Full GC 18982.097: [ParNew: 467761K->67661K(873856K), 0.2710475 secs] 1181257K->815420K(2446720K), 0.2712990 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 2264: , 0.2713302 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 2265: 18982.368: [Rescan (parallel) , 0.0786668 secs]18982.447: [weak refs processing, 0.4121220 secs] [1 CMS-remark: 747758K(1572864K)] 815420K(2446720K), 0.7627401 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 2298: 19185.497: [Scavenge-Before-Remark19185.497: [Full GC 19185.497: [ParNew: 499507K->33520K(873856K), 0.0410831 secs] 1197289K->732028K(2446720K), 0.0413908 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 2299: , 0.0414319 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 2300: 19185.538: [Rescan (parallel) , 0.0602305 secs]19185.599: [weak refs processing, 0.3855187 secs] [1 CMS-remark: 698507K(1572864K)] 732028K(2446720K), 0.4876461 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Scavenge-Before-Remark' Line 2455: 20683.787: [Scavenge-Before-Remark20683.787: [Full GC 20683.787: [ParNew: 238898K->56419K(873856K), 0.0376454 secs] 937771K->755445K(2446720K), 0.0378986 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: '' Line 2456: , 0.0379292 secs]
ATTENTION [DataReaderSun1_6_0]: com.tagtraum.perf.gcviewer.imp.UnknownGcTypeException: Unknown gc type: 'Rescan (parallel) ' Line 2457: 20683.825: [Rescan (parallel) , 0.0223784 secs]20683.847: [weak refs processing, 0.3605779 secs] [1 CMS-remark: 699026K(1572864K)] 755445K(2446720K), 0.4214394 secs]
INFO [DataReaderSun1_6_0]: Done reading.
When using G1 with the PrintGCDateStamps an parsing error occures. Using the default option with time since JVM restart everything works fine.
Errorlog:
INFO [DataReaderFacade]: GCViewer version 1.32-SNAPSHOT (2012-12-09 16:40)
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.ParseException: Error parsing entry. Line 1: 2013-01-17T13:40:06.537+0000: [GC pause (young), 0.01019300 secs]
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: java.lang.NumberFormatException: For input string: "[Parallel Time" Line 2: [Parallel Time: 9.3 ms]
...
Example:
2013-01-17T13:40:06.537+0000: [GC pause (young), 0.01019300 secs]
[Parallel Time: 9.3 ms]
[GC Worker Start Time (ms): 1375.6 1375.7 1379.8 1379.8 1379.9 1379.9 1380.0 1380.0
Avg: 1378.8, Min: 1375.6, Max: 1380.0, Diff: 4.4]
[Update RS (ms): 0.3 0.1 0.3 0.5 0.6 1.4 0.2 0.5
Avg: 0.5, Min: 0.1, Max: 1.4, Diff: 1.4]
[Processed Buffers : 1 1 3 1 2 1 1 3
Sum: 13, Avg: 1, Min: 1, Max: 3, Diff: 2]
[Ext Root Scanning (ms): 4.9 5.9 1.2 1.2 1.0 1.0 1.3 1.1
Avg: 2.2, Min: 1.0, Max: 5.9, Diff: 4.9]
[Mark Stack Scanning (ms): 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0
Avg: 0.0, Min: 0.0, Max: 0.0, Diff: 0.0]
[Scan RS (ms): 0.2 0.5 0.3 0.2 0.4 0.0 0.4 0.2
Avg: 0.3, Min: 0.0, Max: 0.5, Diff: 0.5]
[Object Copy (ms): 3.3 2.3 2.8 2.6 2.5 2.0 2.5 2.6
Avg: 2.6, Min: 2.0, Max: 3.3, Diff: 1.3]
[Termination (ms): 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0
Avg: 0.0, Min: 0.0, Max: 0.0, Diff: 0.0]
[Termination Attempts : 1 1 2 1 2 2 3 1
Sum: 13, Avg: 1, Min: 1, Max: 3, Diff: 2]
[GC Worker End Time (ms): 1384.4 1384.4 1384.4 1384.4 1384.4 1384.4 1384.4 1384.4
Avg: 1384.4, Min: 1384.4, Max: 1384.4, Diff: 0.0]
[GC Worker Times (ms): 8.8 8.7 4.6 4.6 4.5 4.5 4.4 4.4
Avg: 5.6, Min: 4.4, Max: 8.8, Diff: 4.4]
[Other: 3.8 ms]
[Clear CT: 0.7 ms]
[Other: 0.2 ms]
[Choose CSet: 0.0 ms]
[ 11M->7217K(5120M)]
[Times: user=0.04 sys=0.01, real=0.01 secs]
Example which works:
0.522: [GC pause (young), 0.00891500 secs]
[Parallel Time: 8.0 ms]
[GC Worker Start Time (ms): 1703.7 1703.8 1703.8 1703.8 1703.9 1704.0 1704.0 1704.0
Avg: 1703.9, Min: 1703.7, Max: 1704.0, Diff: 0.3]
[Update RS (ms): 1.0 0.7 1.0 0.6 0.9 0.9 1.1 0.8
Avg: 0.8, Min: 0.6, Max: 1.1, Diff: 0.5]
[Processed Buffers : 2 1 2 1 2 2 2 2
Sum: 14, Avg: 1, Min: 1, Max: 2, Diff: 1]
[Ext Root Scanning (ms): 1.3 1.6 0.8 1.4 1.1 1.0 1.0 1.0
Avg: 1.1, Min: 0.8, Max: 1.6, Diff: 0.8]
[Mark Stack Scanning (ms): 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0
Avg: 0.0, Min: 0.0, Max: 0.0, Diff: 0.0]
[Scan RS (ms): 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0
Avg: 0.0, Min: 0.0, Max: 0.0, Diff: 0.0]
[Object Copy (ms): 2.2 1.0 1.5 4.1 1.3 1.3 1.0 1.3
Avg: 1.7, Min: 1.0, Max: 4.1, Diff: 3.0]
[Termination (ms): 1.9 2.9 2.9 0.0 2.9 2.9 2.9 4.1
Avg: 2.5, Min: 0.0, Max: 4.1, Diff: 4.1]
[Termination Attempts : 1 1 1 1 2 1 2 2
Sum: 11, Avg: 1, Min: 1, Max: 2, Diff: 1]
[GC Worker End Time (ms): 1710.0 1710.0 1710.0 1710.0 1710.0 1710.0 1710.0 1711.2
Avg: 1710.1, Min: 1710.0, Max: 1711.2, Diff: 1.3]
[GC Worker Times (ms): 6.3 6.2 6.2 6.1 6.1 6.0 6.0 7.2
Avg: 6.3, Min: 6.0, Max: 7.2, Diff: 1.2]
[Other: 1.8 ms]
[Clear CT: 0.7 ms]
[Other: 0.2 ms]
[Choose CSet: 0.0 ms]
[ 27M->8564K(5120M)]
[Times: user=0.03 sys=0.02, real=0.01 secs]
hi,
I wonder whether the following is the correct way using isSignificant() method:
213: exportValue(out, "avgFullGCPauseIsSig", isSignificant(model.getFullGCPause().average(), model.getFullGCPause().standardDeviation()));
my version is 1.32
212: if (fullGCDataAvailable) {
213: exportValue(out, "avgFullGCPauseIsSig", isSignificant(model.getFullGCPause().average(), model.getPause().standardDeviation()));
214: exportValue(out, "avgFullGCPause", pauseFormatter.format(model.getFullGCPause().average()), "s");
215: exportValue(out, "avgFullGCPause\u03c3", pauseFormatter.format(model.getFullGCPause().standardDeviation()), "s");
216: }
217: else {
218: exportValue(out, "avgFullGCPause", "n.a.", "s");
219: }
regards
elsewhere-
Java 7 supports Rolling GC logs files with the parameters
-XX:+UseGCLogFileRotation
-XX:GCLogFileSize=M
-XX:NumberOfGCLogFiles=
the files will be suffexed with .1 .2 .3 etc....
GCViewer should be able to read all the files at once and merge them in correct order.
Add a checkbox to load all related files or add a popup dialog to ask if you want to load all related log files.
The "memory" tab in GCViewer 1.29 shows some information about memory consumption recorded in the gc log file.
It would be interesting to have more information (if the log file contains it):
Is GCViewer supports JRockit 1.5's GC logs ? Coz i am not able view GC logs generated on one of my application running on JRockit 1.5.
-Xverboselog:gc.log
-verbose:memory
-Xverbose:gcpause,gcreport
-Xverbosetimestamp
[memory ][Sat Oct 27 20:01:49 2012][23355] Running with 32 bit heap and compressed references supporting 32GB heap.
[memory ][Sat Oct 27 20:01:49 2012][23355] GC mode: Garbage collection optimized for short pausetimes, strategy: Generational Concurrent Mark & Sweep.
[memory ][Sat Oct 27 20:01:49 2012][23355] Heap size: 25165824KB, maximal heap size: 25165824KB, nursery size: 4194304KB.
[memory ][Sat Oct 27 20:01:49 2012][23355] -: KB->KB (KB), ms, sum of pauses ms.
[memory ][Sat Oct 27 20:01:49 2012][23355] - start time of collection (seconds since jvm start).
[memory ][Sat Oct 27 20:01:49 2012][23355] - OC (old collection) or YC (young collection).
[memory ][Sat Oct 27 20:01:49 2012][23355] - end time of collection (seconds since jvm start).
[memory ][Sat Oct 27 20:01:49 2012][23355] - memory used by objects before collection (KB).
[memory ][Sat Oct 27 20:01:49 2012][23355] - memory used by objects after collection (KB).
[memory ][Sat Oct 27 20:01:49 2012][23355] - size of heap after collection (KB).
[memory ][Sat Oct 27 20:01:49 2012][23355] - total time of collection (milliseconds).
[memory ][Sat Oct 27 20:01:49 2012][23355] - total sum of pauses during collection (milliseconds).
[memory ][Sat Oct 27 20:01:49 2012][23355] Run with -Xverbose:gcpause to see individual phases.
[gcpause][Sat Oct 27 20:04:38 2012][23355] [YC#1] [---] 468.690 ms (168.027000-168.495000) YC
[gcpause][Sat Oct 27 20:04:38 2012][23355] [YC#1] [con] 0.004 ms (168.027000-168.027000) YC:PreGC
[gcpause][Sat Oct 27 20:04:38 2012][23355] [YC#1] [pau] 468.590 ms (168.027000-168.495000) YC:Main
[gcpause][Sat Oct 27 20:04:38 2012][23355] [YC#1] [con] 0.010 ms (168.495000-168.495000) YC:PostGC
[memory ][Sat Oct 27 20:04:38 2012][23355] [YC#1] 168.027-168.495: YC 4263587KB->1265249KB (25165824KB), 0.469 s, sum of pauses 468.590 ms, longest pause 468.590 ms.
[gcpause][Sat Oct 27 20:04:38 2012][23355] Thread "[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'" id=29 idx=0x60 tid=23444 was in TLA-fetch 468.827 ms from 168.027 s (1 GCs).
[gcpause][Sat Oct 27 20:06:20 2012][23355] [YC#2] [---] 645.670 ms (270.685000-271.330000) YC
[gcpause][Sat Oct 27 20:06:20 2012][23355] [YC#2] [con] 0.003 ms (270.685000-270.685000) YC:PreGC
[gcpause][Sat Oct 27 20:06:20 2012][23355] [YC#2] [pau] 645.545 ms (270.685000-271.330000) YC:Main
[gcpause][Sat Oct 27 20:06:20 2012][23355] [YC#2] [con] 0.030 ms (271.330000-271.330000) YC:PostGC
[memory ][Sat Oct 27 20:06:20 2012][23355] [YC#2] 270.685-271.330: YC 4435053KB->1543400KB (25165824KB), 0.646 s, sum of pauses 645.545 ms, longest pause 645.545 ms.
[gcpause][Sat Oct 27 20:06:20 2012][23355] Thread "[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'" id=29 idx=0x60 tid=23444 was in TLA-fetch 646.349 ms from 270.684 s (1 GCs).
[gcpause][Sat Oct 27 20:08:25 2012][23355] [YC#3] [---] 612.496 ms (394.948000-395.561000) YC
[gcpause][Sat Oct 27 20:08:25 2012][23355] [YC#3] [con] 0.003 ms (394.948000-394.948000) YC:PreGC
[gcpause][Sat Oct 27 20:08:25 2012][23355] [YC#3] [pau] 612.295 ms (394.949000-395.561000) YC:Main
[gcpause][Sat Oct 27 20:08:25 2012][23355] [YC#3] [con] 0.068 ms (395.561000-395.561000) YC:PostGC
[memory ][Sat Oct 27 20:08:25 2012][23355] [YC#3] 394.948-395.561: YC 4696729KB->1891349KB (25165824KB), 0.612 s, sum of pauses 612.295 ms, longest pause 612.295 ms.
[gcpause][Sat Oct 27 20:08:25 2012][23355] Thread "[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'" id=29 idx=0x60 tid=23444 was in TLA-fetch 612.736 ms from 394.948 s (1 GCs).
[gcpause][Sat Oct 27 20:09:19 2012][23355] [YC#4] [---] 333.239 ms (449.237000-449.570000) YC
[gcpause][Sat Oct 27 20:09:19 2012][23355] [YC#4] [con] 0.003 ms (449.237000-449.237000) YC:PreGC
[gcpause][Sat Oct 27 20:09:19 2012][23355] [YC#4] [pau] 333.123 ms (449.237000-449.570000) YC:Main
[gcpause][Sat Oct 27 20:09:19 2012][23355] [YC#4] [con] 0.038 ms (449.570000-449.570000) YC:PostGC
Regards,
Ramesh
When parsing the G1 log file it does not handle long strings.
Sample Error
INFO [DataReaderFacade]: GCViewer version 1.32 (2013-03-14 18:27)
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.ParseException: java.lang.StringIndexOutOfBoundsException: String index out of range: 40 Line 23954: 2903.505: [Full GC 10176M->1074M(10240M)
WARNING [DataReaderSun1_6_0G1]: com.tagtraum.perf.gcviewer.imp.ParseException: Error parsing entry. Line 23957: , 3.6988510 secs]
Please let me know a means to provide the complete log for you.
-XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+PrintTenuringDistribution -XX:+PrintGCApplicationConcurrentTime -XX:+PrintGCApplicationStoppedTime -Xloggc:gc.log -verbose:gc -XX:+PrintGC
the above is my JVM settings along with GC logging and algo. Sometimes when i import GC logs its working fine but sometimes its saying "Failed to recognize file format"
The log file size is 15 MB but different component produced 140 MB file which is working fine with same settings.
Can you please let me know whether i have properly used JVM settings ?
JDK 1.6.0.27
Would it be possible to add code to read the GC log from an URL specified on the command line as opposed to file ? This would be nice as I wish to webstart GCviewer with a specific gc log stored on a web server ...
Something like (this works for me) in GCViewerGUI ...
public static void main(final String[] args) {
if (args.length > 1)
usage();
else {
// try {
// for (LookAndFeelInfo info : UIManager.getInstalledLookAndFeels()) {
// if ("Nimbus".equals(info.getName())) {
// UIManager.setLookAndFeel(info.getClassName());
// break;
// }
// }
// } catch (Exception e) {
// // If Nimbus is not available, you can set the GUI to another look and feel.
// }
SwingUtilities.invokeLater(new Runnable() {
public void run() {
final GCViewerGui viewer = new GCViewerGui();
System.out.println ("args len = " + args.length);
if (args.length == 1) {
if (args [0].startsWith("http"))
{
String urlstr = args [0].replace('\\', '/');
URL url=null;
try {
url = new URL (urlstr);
} catch (MalformedURLException e) {
// TODO Auto-generated catch block
e.printStackTrace();
}
URL [] urls = new URL [1];
urls [0] = url;
viewer.open (urls);
}
else
viewer.open(new File[] {new File(args[0])});
}
}
});
}
}
The "view" menu allows to configure the amount of information displayed in the chart. This configuration should be remembered in the next session. A properties file persisting the information is written, but gcviewer does not restore the information saved in the properties file.
React
A declarative, efficient, and flexible JavaScript library for building user interfaces.
🖖 Vue.js is a progressive, incrementally-adoptable JavaScript framework for building UI on the web.
Typescript
TypeScript is a superset of JavaScript that compiles to clean JavaScript output.
An Open Source Machine Learning Framework for Everyone
Django
The Web framework for perfectionists with deadlines.
Laravel
A PHP framework for web artisans
Bring data to life with SVG, Canvas and HTML. 📊📈🎉
JavaScript (JS) is a lightweight interpreted programming language with first-class functions.
Some thing interesting about web. New door for the world.
A server is a program made to process requests and deliver data to clients.
Machine learning is a way of modeling and interpreting data that allows a piece of software to respond intelligently.
Some thing interesting about visualization, use data art
Some thing interesting about game, make everyone happy.
Facebook
We are working to build community through open source technology. NB: members must have two-factor auth.
Microsoft
Open source projects and samples from Microsoft.
Google
Google ❤️ Open Source for everyone.
Alibaba
Alibaba Open Source for everyone
D3
Data-Driven Documents codes.
Tencent
China tencent open source team.