search

mgm3746 / garbagecat

Parses Java garbage collection logging and analyzes collectors, safepoint triggers, JVM version, JVM options, and OS information and reports error/warn/info level analysis and recommendations to support JVM tuning and troubleshooting for OpenJDK derivatives: (e.g. Adoptium, Azul, Microsoft, Oracle, Red Hat, etc.).
https://github.com/mgm3746/garbagecat/wiki
Eclipse Public License 1.0
211 stars 50 forks source link

Unidentified loglines part 2 #176

Closed wolframhaussig closed 2 years ago

wolframhaussig commented 2 years ago

@mgm3746 Thank you a lot for you work, I can read a lot of the GC logs now. Just two types of lines are still causing me problems:

[2022-08-03T06:58:41.313+0000][1863][gc,start    ] GC(0) Pause Young (Normal) (G1 Evacuation Pause)
[2022-08-03T06:58:41.321+0000][1863][gc,metaspace] GC(0) Metaspace: 19460K(19840K)->19460K(19840K) NonClass: 17082K(17280K)->17082K(17280K) Class: 2378K(2560K)->2378K(2560K) 615M->23M(12288M) 7,870ms User=0,04s Sys=0,00s Real=0,01s
[2022-08-03T06:58:41.907+0000][1863][gc,start    ] GC(1) Pause Young (Concurrent Start) (Metadata GC Threshold)
[2022-08-03T06:58:41.914+0000][1863][gc,metaspace] GC(1) Metaspace: 21014K(21504K)->21014K(21504K) NonClass: 18417K(18688K)->18417K(18688K) Class: 2596K(2816K)->2596K(2816K) 415M->26M(12288M) 6,426ms User=0,02s Sys=0,00s Real=0,01s
[2022-08-03T06:58:48.658+0000][1863][gc,start    ] GC(3) Pause Young (Normal) (G1 Evacuation Pause)
[2022-08-03T06:58:48.677+0000][1863][gc,metaspace] GC(3) Metaspace: 31150K(32128K)->31150K(32128K) NonClass: 27164K(27776K)->27164K(27776K) Class: 3986K(4352K)->3986K(4352K) 610M->51M(12288M) 18,835ms User=0,10s Sys=0,00s Real=0,02s
[2022-08-03T06:58:48.925+0000][1863][gc,start    ] GC(4) Pause Young (Concurrent Start) (Metadata GC Threshold)
[2022-08-03T06:58:48.941+0000][1863][gc,metaspace] GC(4) Metaspace: 35079K(35968K)->35079K(35968K) NonClass: 30429K(30976K)->30429K(30976K) Class: 4650K(4992K)->4650K(4992K) 83M->54M(12288M) 15,397ms User=0,07s Sys=0,00s Real=0,01s
[2022-08-03T06:58:50.928+0000][1863][gc,start    ] GC(6) Pause Young (Concurrent Start) (Metadata GC Threshold)
[2022-08-03T06:58:50.953+0000][1863][gc,metaspace] GC(6) Metaspace: 59428K(60608K)->59428K(60608K) NonClass: 51352K(52032K)->51352K(52032K) Class: 8076K(8576K)->8076K(8576K) 462M->64M(12288M) 25,021ms User=0,07s Sys=0,00s Real=0,03s
[2022-08-03T06:58:54.821+0000][1863][gc,start    ] GC(8) Pause Young (Normal) (G1 Evacuation Pause)
[2022-08-03T06:58:54.849+0000][1863][gc,metaspace] GC(8) Metaspace: 93357K(94784K)->93357K(94784K) NonClass: 80540K(81408K)->80540K(81408K) Class: 12816K(13376K)->12816K(13376K) 624M->84M(12288M) 27,153ms User=0,16s Sys=0,00s Real=0,02s
[2022-08-03T06:58:55.704+0000][1863][gc,start    ] GC(9) Pause Young (Normal) (G1 Evacuation Pause)
[2022-08-03T06:58:55.730+0000][1863][gc,metaspace] GC(9) Metaspace: 95219K(96640K)->95219K(96640K) NonClass: 82166K(83008K)->82166K(83008K) Class: 13052K(13632K)->13052K(13632K) 628M->88M(12288M) 25,848ms User=0,12s Sys=0,00s Real=0,02s

I used a snapshot build of garbagecat and run it with java -jar garbagecat-4.0.1-SNAPSHOT.jar -l gc.log -p

mgm3746 commented 2 years ago

Hi, please zip and attach the complete gc logging.

wolframhaussig commented 2 years ago

Sure, here it is: gc.zip

mgm3746 commented 2 years ago

Thank you for reporting this. I have added the additional JDK17 G1 patterns. If you hit any more issues, please open a new issue and zip/attach the gc logging.