Is gctoolkit parsed by line? #225

lalaorya · 2022-07-15T09:27:32Z

lalaorya
Jul 15, 2022

I tried to parse the following gc log

# my vm options：-XX:+PrintHeapAtGC

{Heap before GC invocations=10 (full 2):
 PSYoungGen      total 950784K, used 888619K [0x0000000781800000, 0x00000007c0000000, 0x00000007c0000000)
  eden space 880128K, 100% used [0x0000000781800000,0x00000007b7380000,0x00000007b7380000)
  from space 70656K, 12% used [0x00000007b7380000,0x00000007b7bcad30,0x00000007bb880000)
  to   space 68096K, 0% used [0x00000007bbd80000,0x00000007bbd80000,0x00000007c0000000)
 ParOldGen       total 1517568K, used 62900K [0x00000006f8000000, 0x0000000754a00000, 0x0000000781800000)
  object space 1517568K, 4% used [0x00000006f8000000,0x00000006fbd6d020,0x0000000754a00000)
 Metaspace       used 45710K, capacity 46574K, committed 46976K, reserved 1089536K
  class space    used 6707K, capacity 6996K, committed 7040K, reserved 1048576K
2022-06-20T08:01:19.837+0800: 52.130: [GC (Allocation Failure) [PSYoungGen: 888619K->2704K(957440K)] 951519K->69776K(2475008K), 0.0106411 secs] [Times: user=0.04 sys=0.00, real=0.01 secs] 
Heap after GC invocations=10 (full 2):
 PSYoungGen      total 957440K, used 2704K [0x0000000781800000, 0x00000007c0000000, 0x00000007c0000000)
  eden space 889344K, 0% used [0x0000000781800000,0x0000000781800000,0x00000007b7c80000)
  from space 68096K, 3% used [0x00000007bbd80000,0x00000007bc024020,0x00000007c0000000)
  to   space 66560K, 0% used [0x00000007b7c80000,0x00000007b7c80000,0x00000007bbd80000)
 ParOldGen       total 1517568K, used 67072K [0x00000006f8000000, 0x0000000754a00000, 0x0000000781800000)
  object space 1517568K, 4% used [0x00000006f8000000,0x00000006fc180288,0x0000000754a00000)
 Metaspace       used 45710K, capacity 46574K, committed 46976K, reserved 1089536K
  class space    used 6707K, capacity 6996K, committed 7040K, reserved 1048576K
}

The information I want to get is the memory usage of the eden, from, and to areas before and after gc, but I didn't find a suitable jvm event. Looking at the source code I found that the parsing process is done in behavioral units, so gctoolkit can't parse this log format above.

I am not sure if this is the right idea for me and I hope I can get an answer.

karianna · 2022-07-15T11:52:54Z

karianna
Jul 15, 2022
Maintainer

@lalaorya Are you able to upload you GC log here for testing?

5 replies

lalaorya Jul 16, 2022
Author

Java HotSpot(TM) 64-Bit Server VM (25.333-b02) for bsd-amd64 JRE (1.8.0_333-b02), built on Apr 26 2022 06:41:53 by "java_re" with gcc 4.2.1 Compatible Apple LLVM 11.0.0 (clang-1100.0.33.17)
Memory: 4k page, physical 16777216k(1386400k free)

/proc/meminfo:

CommandLine flags: -XX:-BytecodeVerificationLocal -XX:-BytecodeVerificationRemote -XX:InitialHeapSize=268435456 -XX:+ManagementServer -XX:MaxHeapSize=4294967296 -XX:+PrintGC -XX:+PrintGCApplicationStoppedTime -XX:+PrintGCDateStamps -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+PrintHeapAtGC -XX:TieredStopAtLevel=1 -XX:+UnlockExperimentalVMOptions -XX:+UseCGroupMemoryLimitForHeap -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseParallelGC 
2022-06-24T14:32:57.835-0800: 0.340: Total time for which application threads were stopped: 0.0001365 seconds, Stopping threads took: 0.0000168 seconds
2022-06-24T14:32:57.836-0800: 0.341: Total time for which application threads were stopped: 0.0001340 seconds, Stopping threads took: 0.0000340 seconds
2022-06-24T14:32:58.839-0800: 1.344: Total time for which application threads were stopped: 0.0000585 seconds, Stopping threads took: 0.0000242 seconds
2022-06-24T14:33:02.922-0800: 5.428: Total time for which application threads were stopped: 0.0000934 seconds, Stopping threads took: 0.0000105 seconds
2022-06-24T14:33:02.924-0800: 5.429: Total time for which application threads were stopped: 0.0000630 seconds, Stopping threads took: 0.0000065 seconds
2022-06-24T14:33:03.014-0800: 5.520: Total time for which application threads were stopped: 0.0001324 seconds, Stopping threads took: 0.0000136 seconds
2022-06-24T14:33:03.047-0800: 5.552: Total time for which application threads were stopped: 0.0001515 seconds, Stopping threads took: 0.0000317 seconds
2022-06-24T14:33:03.318-0800: 5.824: Total time for which application threads were stopped: 0.0001641 seconds, Stopping threads took: 0.0000153 seconds
2022-06-24T14:33:04.320-0800: 6.825: Total time for which application threads were stopped: 0.0001860 seconds, Stopping threads took: 0.0000355 seconds
2022-06-24T14:33:05.324-0800: 7.830: Total time for which application threads were stopped: 0.0000643 seconds, Stopping threads took: 0.0000211 seconds
2022-06-24T14:33:06.330-0800: 8.835: Total time for which application threads were stopped: 0.0001708 seconds, Stopping threads took: 0.0000557 seconds
2022-06-24T14:33:06.969-0800: 9.474: Total time for which application threads were stopped: 0.0004268 seconds, Stopping threads took: 0.0000542 seconds
2022-06-24T14:33:07.185-0800: 9.690: Total time for which application threads were stopped: 0.0001127 seconds, Stopping threads took: 0.0000275 seconds
2022-06-24T14:33:07.695-0800: 10.200: Total time for which application threads were stopped: 0.0001337 seconds, Stopping threads took: 0.0000568 seconds
{Heap before GC invocations=1 (full 0):
 PSYoungGen      total 76288K, used 65536K [0x000000076ab00000, 0x0000000770000000, 0x00000007c0000000)
  eden space 65536K, 100% used [0x000000076ab00000,0x000000076eb00000,0x000000076eb00000)
  from space 10752K, 0% used [0x000000076f580000,0x000000076f580000,0x0000000770000000)
  to   space 10752K, 0% used [0x000000076eb00000,0x000000076eb00000,0x000000076f580000)
 ParOldGen       total 175104K, used 0K [0x00000006c0000000, 0x00000006cab00000, 0x000000076ab00000)
  object space 175104K, 0% used [0x00000006c0000000,0x00000006c0000000,0x00000006cab00000)
 Metaspace       used 13515K, capacity 14014K, committed 14208K, reserved 1060864K
  class space    used 1731K, capacity 1863K, committed 1920K, reserved 1048576K
2022-06-24T14:33:08.202-0800: 10.707: [GC (Allocation Failure) [PSYoungGen: 65536K->6724K(76288K)] 65536K->6740K(251392K), 0.0112504 secs] [Times: user=0.04 sys=0.01, real=0.01 secs] 
Heap after GC invocations=1 (full 0):
 PSYoungGen      total 76288K, used 6724K [0x000000076ab00000, 0x0000000770000000, 0x00000007c0000000)
  eden space 65536K, 0% used [0x000000076ab00000,0x000000076ab00000,0x000000076eb00000)
  from space 10752K, 62% used [0x000000076eb00000,0x000000076f1913b0,0x000000076f580000)
  to   space 10752K, 0% used [0x000000076f580000,0x000000076f580000,0x0000000770000000)
 ParOldGen       total 175104K, used 16K [0x00000006c0000000, 0x00000006cab00000, 0x000000076ab00000)
  object space 175104K, 0% used [0x00000006c0000000,0x00000006c0004000,0x00000006cab00000)
 Metaspace       used 13515K, capacity 14014K, committed 14208K, reserved 1060864K
  class space    used 1731K, capacity 1863K, committed 1920K, reserved 1048576K
}
2022-06-24T14:33:08.213-0800: 10.719: Total time for which application threads were stopped: 0.0117004 seconds, Stopping threads took: 0.0000802 seconds
2022-06-24T14:33:18.727-0800: 21.233: Total time for which application threads were stopped: 0.0000681 seconds, Stopping threads took: 0.0000067 seconds
2022-06-24T14:33:18.727-0800: 21.233: Total time for which application threads were stopped: 0.0000411 seconds, Stopping threads took: 0.0000067 seconds
2022-06-24T14:33:18.728-0800: 21.233: Total time for which application threads were stopped: 0.0000786 seconds, Stopping threads took: 0.0000531 seconds
2022-06-24T14:33:18.728-0800: 21.233: Total time for which application threads were stopped: 0.0000425 seconds, Stopping threads took: 0.0000210 seconds
2022-06-24T14:33:18.728-0800: 21.234: Total time for which application threads were stopped: 0.0000463 seconds, Stopping threads took: 0.0000087 seconds
2022-06-24T14:33:18.728-0800: 21.234: Total time for which application threads were stopped: 0.0000614 seconds, Stopping threads took: 0.0000060 seconds
2022-06-24T14:33:18.728-0800: 21.234: Total time for which application threads were stopped: 0.0000579 seconds, Stopping threads took: 0.0000038 seconds
2022-06-24T14:33:18.728-0800: 21.234: Total time for which application threads were stopped: 0.0000593 seconds, Stopping threads took: 0.0000338 seconds
2022-06-24T14:33:18.729-0800: 21.235: Total time for which application threads were stopped: 0.0000870 seconds, Stopping threads took: 0.0000157 seconds
2022-06-24T14:33:18.729-0800: 21.235: Total time for which application threads were stopped: 0.0000607 seconds, Stopping threads took: 0.0000252 seconds
2022-06-24T14:33:18.731-0800: 21.236: Total time for which application threads were stopped: 0.0000559 seconds, Stopping threads took: 0.0000115 seconds
2022-06-24T14:33:18.731-0800: 21.237: Total time for which application threads were stopped: 0.0000832 seconds, Stopping threads took: 0.0000041 seconds
2022-06-24T14:33:18.731-0800: 21.237: Total time for which application threads were stopped: 0.0000615 seconds, Stopping threads took: 0.0000048 seconds
2022-06-24T14:33:18.733-0800: 21.239: Total time for which application threads were stopped: 0.0000256 seconds, Stopping threads took: 0.0000062 seconds
2022-06-24T14:33:18.733-0800: 21.239: Total time for which application threads were stopped: 0.0000144 seconds, Stopping threads took: 0.0000032 seconds
2022-06-24T14:33:18.793-0800: 21.299: Total time for which application threads were stopped: 0.0001044 seconds, Stopping threads took: 0.0000403 seconds
2022-06-24T14:33:18.793-0800: 21.299: Total time for which application threads were stopped: 0.0000389 seconds, Stopping threads took: 0.0000156 seconds
{Heap before GC invocations=2 (full 0):
 PSYoungGen      total 76288K, used 72260K [0x000000076ab00000, 0x0000000770000000, 0x00000007c0000000)
  eden space 65536K, 100% used [0x000000076ab00000,0x000000076eb00000,0x000000076eb00000)
  from space 10752K, 62% used [0x000000076eb00000,0x000000076f1913b0,0x000000076f580000)
  to   space 10752K, 0% used [0x000000076f580000,0x000000076f580000,0x0000000770000000)
 ParOldGen       total 175104K, used 16K [0x00000006c0000000, 0x00000006cab00000, 0x000000076ab00000)
  object space 175104K, 0% used [0x00000006c0000000,0x00000006c0004000,0x00000006cab00000)
 Metaspace       used 19573K, capacity 20422K, committed 20480K, reserved 1067008K
  class space    used 2568K, capacity 2763K, committed 2816K, reserved 1048576K
2022-06-24T14:33:18.807-0800: 21.313: [GC (Allocation Failure) [PSYoungGen: 72260K->10737K(76288K)] 72276K->11380K(251392K), 0.0074429 secs] [Times: user=0.03 sys=0.01, real=0.01 secs] 
Heap after GC invocations=2 (full 0):
 PSYoungGen      total 76288K, used 10737K [0x000000076ab00000, 0x0000000770000000, 0x00000007c0000000)
  eden space 65536K, 0% used [0x000000076ab00000,0x000000076ab00000,0x000000076eb00000)
  from space 10752K, 99% used [0x000000076f580000,0x000000076fffc780,0x0000000770000000)
  to   space 10752K, 0% used [0x000000076eb00000,0x000000076eb00000,0x000000076f580000)
 ParOldGen       total 175104K, used 642K [0x00000006c0000000, 0x00000006cab00000, 0x000000076ab00000)
  object space 175104K, 0% used [0x00000006c0000000,0x00000006c00a0970,0x00000006cab00000)
 Metaspace       used 19573K, capacity 20422K, committed 20480K, reserved 1067008K
  class space    used 2568K, capacity 2763K, committed 2816K, reserved 1048576K
}
2022-06-24T14:33:18.815-0800: 21.321: Total time for which application threads were stopped: 0.0076549 seconds, Stopping threads took: 0.0000272 seconds
2022-06-24T14:33:18.815-0800: 21.321: Total time for which application threads were stopped: 0.0001446 seconds, Stopping threads took: 0.0000439 seconds
2022-06-24T14:33:18.817-0800: 21.323: Total time for which application threads were stopped: 0.0001363 seconds, Stopping threads took: 0.0000301 seconds
2022-06-24T14:33:18.817-0800: 21.323: Total time for which application threads were stopped: 0.0000526 seconds, Stopping threads took: 0.0000210 seconds
2022-06-24T14:33:18.817-0800: 21.323: Total time for which application threads were stopped: 0.0000561 seconds, Stopping threads took: 0.0000062 seconds
2022-06-24T14:33:18.817-0800: 21.323: Total time for which application threads were stopped: 0.0000288 seconds, Stopping threads took: 0.0000061 seconds
2022-06-24T14:33:18.817-0800: 21.323: Total time for which application threads were stopped: 0.0000300 seconds, Stopping threads took: 0.0000141 seconds
{Heap before GC invocations=3 (full 0):
 PSYoungGen      total 76288K, used 23057K [0x000000076ab00000, 0x0000000770000000, 0x00000007c0000000)
  eden space 65536K, 18% used [0x000000076ab00000,0x000000076b707cf0,0x000000076eb00000)
  from space 10752K, 99% used [0x000000076f580000,0x000000076fffc780,0x0000000770000000)
  to   space 10752K, 0% used [0x000000076eb00000,0x000000076eb00000,0x000000076f580000)
 ParOldGen       total 175104K, used 642K [0x00000006c0000000, 0x00000006cab00000, 0x000000076ab00000)
  object space 175104K, 0% used [0x00000006c0000000,0x00000006c00a0970,0x00000006cab00000)
 Metaspace       used 20300K, capacity 21236K, committed 21296K, reserved 1067008K
  class space    used 2668K, capacity 2884K, committed 2944K, reserved 1048576K
2022-06-24T14:33:18.895-0800: 21.401: [GC (Metadata GC Threshold) [PSYoungGen: 23057K->6628K(76288K)] 23699K->7279K(251392K), 0.0036661 secs] [Times: user=0.02 sys=0.00, real=0.00 secs] 
Heap after GC invocations=3 (full 0):
 PSYoungGen      total 76288K, used 6628K [0x000000076ab00000, 0x0000000774000000, 0x00000007c0000000)
  eden space 65536K, 0% used [0x000000076ab00000,0x000000076ab00000,0x000000076eb00000)
  from space 10752K, 61% used [0x000000076eb00000,0x000000076f1793e8,0x000000076f580000)
  to   space 10752K, 0% used [0x0000000773580000,0x0000000773580000,0x0000000774000000)
 ParOldGen       total 175104K, used 650K [0x00000006c0000000, 0x00000006cab00000, 0x000000076ab00000)
  object space 175104K, 0% used [0x00000006c0000000,0x00000006c00a2970,0x00000006cab00000)
 Metaspace       used 20300K, capacity 21236K, committed 21296K, reserved 1067008K
  class space    used 2668K, capacity 2884K, committed 2944K, reserved 1048576K
}
{Heap before GC invocations=4 (full 1):
 PSYoungGen      total 76288K, used 6628K [0x000000076ab00000, 0x0000000774000000, 0x00000007c0000000)
  eden space 65536K, 0% used [0x000000076ab00000,0x000000076ab00000,0x000000076eb00000)
  from space 10752K, 61% used [0x000000076eb00000,0x000000076f1793e8,0x000000076f580000)
  to   space 10752K, 0% used [0x0000000773580000,0x0000000773580000,0x0000000774000000)
 ParOldGen       total 175104K, used 650K [0x00000006c0000000, 0x00000006cab00000, 0x000000076ab00000)
  object space 175104K, 0% used [0x00000006c0000000,0x00000006c00a2970,0x00000006cab00000)
 Metaspace       used 20300K, capacity 21236K, committed 21296K, reserved 1067008K
  class space    used 2668K, capacity 2884K, committed 2944K, reserved 1048576K
2022-06-24T14:33:18.899-0800: 21.404: [Full GC (Metadata GC Threshold) [PSYoungGen: 6628K->0K(76288K)] [ParOldGen: 650K->6430K(77312K)] 7279K->6430K(153600K), [Metaspace: 20300K->20300K(1067008K)], 0.0201166 secs] [Times: user=0.08 sys=0.00, real=0.02 secs] 
Heap after GC invocations=4 (full 1):
 PSYoungGen      total 76288K, used 0K [0x000000076ab00000, 0x0000000774000000, 0x00000007c0000000)
  eden space 65536K, 0% used [0x000000076ab00000,0x000000076ab00000,0x000000076eb00000)
  from space 10752K, 0% used [0x000000076eb00000,0x000000076eb00000,0x000000076f580000)
  to   space 10752K, 0% used [0x0000000773580000,0x0000000773580000,0x0000000774000000)
 ParOldGen       total 77312K, used 6430K [0x00000006c0000000, 0x00000006c4b80000, 0x000000076ab00000)
  object space 77312K, 8% used [0x00000006c0000000,0x00000006c0647b00,0x00000006c4b80000)
 Metaspace       used 20300K, capacity 21236K, committed 21296K, reserved 1067008K
  class space    used 2668K, capacity 2884K, committed 2944K, reserved 1048576K
}
2022-06-24T14:33:29.282-0800: 31.788: Total time for which application threads were stopped: 0.0003220 seconds, Stopping threads took: 0.0000575 seconds
2022-06-24T14:33:29.282-0800: 31.788: Total time for which application threads were stopped: 0.0000786 seconds, Stopping threads took: 0.0000148 seconds
2022-06-24T14:33:29.283-0800: 31.789: Total time for which application threads were stopped: 0.0000870 seconds, Stopping threads took: 0.0000165 seconds
2022-06-24T14:33:29.284-0800: 31.790: Total time for which application threads were stopped: 0.0000914 seconds, Stopping threads took: 0.0000216 seconds
2022-06-24T14:33:29.284-0800: 31.790: Total time for which application threads were stopped: 0.0000577 seconds, Stopping threads took: 0.0000117 seconds
2022-06-24T14:33:40.414-0800: 42.920: Total time for which application threads were stopped: 0.1005041 seconds, Stopping threads took: 0.1004404 seconds
{Heap before GC invocations=6 (full 1):
 PSYoungGen      total 135680K, used 80213K [0x000000076ab00000, 0x0000000773e00000, 0x00000007c0000000)
  eden space 126976K, 56% used [0x000000076ab00000,0x000000076f149e50,0x0000000772700000)
  from space 8704K, 94% used [0x0000000773580000,0x0000000773d8b8d0,0x0000000773e00000)
  to   space 10752K, 0% used [0x0000000772900000,0x0000000772900000,0x0000000773380000)
 ParOldGen       total 2796544K, used 2740813K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x00000007674937e0,0x000000076ab00000)
 Metaspace       used 33059K, capacity 35032K, committed 35200K, reserved 1079296K
  class space    used 4209K, capacity 4627K, committed 4736K, reserved 1048576K
2022-06-24T14:33:41.512-0800: 44.017: [GC (Allocation Failure) [PSYoungGen: 80213K->10725K(139776K)] 2821027K->2752530K(2936320K), 0.0169000 secs] [Times: user=0.05 sys=0.01, real=0.01 secs] 
Heap after GC invocations=6 (full 1):
 PSYoungGen      total 139776K, used 10725K [0x000000076ab00000, 0x000000077b300000, 0x00000007c0000000)
  eden space 129024K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000772900000)
  from space 10752K, 99% used [0x0000000772900000,0x0000000773379560,0x0000000773380000)
  to   space 13312K, 0% used [0x000000077a600000,0x000000077a600000,0x000000077b300000)
 ParOldGen       total 2796544K, used 2741805K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x000000076758b548,0x000000076ab00000)
 Metaspace       used 33059K, capacity 35032K, committed 35200K, reserved 1079296K
  class space    used 4209K, capacity 4627K, committed 4736K, reserved 1048576K
}
{Heap before GC invocations=7 (full 1):
 PSYoungGen      total 139776K, used 10725K [0x000000076ab00000, 0x000000077b300000, 0x00000007c0000000)
  eden space 129024K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000772900000)
  from space 10752K, 99% used [0x0000000772900000,0x0000000773379560,0x0000000773380000)
  to   space 13312K, 0% used [0x000000077a600000,0x000000077a600000,0x000000077b300000)
 ParOldGen       total 2796544K, used 2741805K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x000000076758b548,0x000000076ab00000)
 Metaspace       used 33059K, capacity 35032K, committed 35200K, reserved 1079296K
  class space    used 4209K, capacity 4627K, committed 4736K, reserved 1048576K
2022-06-24T14:33:41.529-0800: 44.034: [GC (Allocation Failure) [PSYoungGen: 10725K->10709K(256512K)] 2752530K->2752515K(3053056K), 0.0096201 secs] [Times: user=0.04 sys=0.00, real=0.01 secs] 
Heap after GC invocations=7 (full 1):
 PSYoungGen      total 256512K, used 10709K [0x000000076ab00000, 0x000000077c200000, 0x00000007c0000000)
  eden space 243200K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000779880000)
  from space 13312K, 80% used [0x000000077a600000,0x000000077b075720,0x000000077b300000)
  to   space 13824K, 0% used [0x0000000779880000,0x0000000779880000,0x000000077a600000)
 ParOldGen       total 2796544K, used 2741805K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x000000076758b548,0x000000076ab00000)
 Metaspace       used 33059K, capacity 35032K, committed 35200K, reserved 1079296K
  class space    used 4209K, capacity 4627K, committed 4736K, reserved 1048576K
}
{Heap before GC invocations=8 (full 2):
 PSYoungGen      total 256512K, used 10709K [0x000000076ab00000, 0x000000077c200000, 0x00000007c0000000)
  eden space 243200K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000779880000)
  from space 13312K, 80% used [0x000000077a600000,0x000000077b075720,0x000000077b300000)
  to   space 13824K, 0% used [0x0000000779880000,0x0000000779880000,0x000000077a600000)
 ParOldGen       total 2796544K, used 2741805K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x000000076758b548,0x000000076ab00000)
 Metaspace       used 33059K, capacity 35032K, committed 35200K, reserved 1079296K
  class space    used 4209K, capacity 4627K, committed 4736K, reserved 1048576K
2022-06-24T14:33:41.539-0800: 44.044: [Full GC (Allocation Failure) [PSYoungGen: 10709K->0K(256512K)] [ParOldGen: 2741805K->16553K(114688K)] 2752515K->16553K(371200K), [Metaspace: 33059K->33059K(1079296K)], 0.3954517 secs] [Times: user=0.12 sys=0.35, real=0.40 secs] 
Heap after GC invocations=8 (full 2):
 PSYoungGen      total 256512K, used 0K [0x000000076ab00000, 0x000000077c200000, 0x00000007c0000000)
  eden space 243200K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000779880000)
  from space 13312K, 0% used [0x000000077a600000,0x000000077a600000,0x000000077b300000)
  to   space 13824K, 0% used [0x0000000779880000,0x0000000779880000,0x000000077a600000)
 ParOldGen       total 114688K, used 16553K [0x00000006c0000000, 0x00000006c7000000, 0x000000076ab00000)
  object space 114688K, 14% used [0x00000006c0000000,0x00000006c102a748,0x00000006c7000000)
 Metaspace       used 33059K, capacity 35032K, committed 35200K, reserved 1079296K
  class space    used 4209K, capacity 4627K, committed 4736K, reserved 1048576K
}
2022-06-24T14:33:41.934-0800: 44.439: Total time for which application threads were stopped: 0.4225077 seconds, Stopping threads took: 0.0000194 seconds
2022-06-24T14:33:41.991-0800: 44.496: Total time for which application threads were stopped: 0.0556356 seconds, Stopping threads took: 0.0555565 seconds
2022-06-24T14:33:41.992-0800: 44.497: Total time for which application threads were stopped: 0.0000812 seconds, Stopping threads took: 0.0000222 seconds
2022-06-24T14:33:41.992-0800: 44.497: Total time for which application threads were stopped: 0.0001250 seconds, Stopping threads took: 0.0000126 seconds
2022-06-24T14:33:41.992-0800: 44.497: Total time for which application threads were stopped: 0.0002241 seconds, Stopping threads took: 0.0001740 seconds
2022-06-24T14:33:43.050-0800: 45.554: Total time for which application threads were stopped: 0.0537625 seconds, Stopping threads took: 0.0535986 seconds
{Heap before GC invocations=9 (full 2):
 PSYoungGen      total 256512K, used 201453K [0x000000076ab00000, 0x000000077c200000, 0x00000007c0000000)
  eden space 243200K, 82% used [0x000000076ab00000,0x0000000776fbb4b0,0x0000000779880000)
  from space 13312K, 0% used [0x000000077a600000,0x000000077a600000,0x000000077b300000)
  to   space 13824K, 0% used [0x0000000779880000,0x0000000779880000,0x000000077a600000)
 ParOldGen       total 2796544K, used 2750929K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x0000000767e74428,0x000000076ab00000)
 Metaspace       used 33180K, capacity 35178K, committed 35456K, reserved 1079296K
  class space    used 4227K, capacity 4630K, committed 4736K, reserved 1048576K
2022-06-24T14:33:43.693-0800: 46.197: [GC (Allocation Failure) [PSYoungGen: 201453K->128K(257024K)] 2952382K->2751065K(3053568K), 0.0064992 secs] [Times: user=0.03 sys=0.01, real=0.01 secs] 
Heap after GC invocations=9 (full 2):
 PSYoungGen      total 257024K, used 128K [0x000000076ab00000, 0x000000078a000000, 0x00000007c0000000)
  eden space 243200K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000779880000)
  from space 13824K, 0% used [0x0000000779880000,0x00000007798a0000,0x000000077a600000)
  to   space 14848K, 0% used [0x0000000789180000,0x0000000789180000,0x000000078a000000)
 ParOldGen       total 2796544K, used 2750937K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x0000000767e76428,0x000000076ab00000)
 Metaspace       used 33180K, capacity 35178K, committed 35456K, reserved 1079296K
  class space    used 4227K, capacity 4630K, committed 4736K, reserved 1048576K
}
2022-06-24T14:33:43.700-0800: 46.204: Total time for which application threads were stopped: 0.0068819 seconds, Stopping threads took: 0.0000259 seconds
{Heap before GC invocations=10 (full 2):
 PSYoungGen      total 257024K, used 198306K [0x000000076ab00000, 0x000000078a000000, 0x00000007c0000000)
  eden space 243200K, 81% used [0x000000076ab00000,0x0000000776c88bd0,0x0000000779880000)
  from space 13824K, 0% used [0x0000000779880000,0x00000007798a0000,0x000000077a600000)
  to   space 14848K, 0% used [0x0000000789180000,0x0000000789180000,0x000000078a000000)
 ParOldGen       total 2796544K, used 2750937K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x0000000767e76428,0x000000076ab00000)
 Metaspace       used 33180K, capacity 35178K, committed 35456K, reserved 1079296K
  class space    used 4227K, capacity 4630K, committed 4736K, reserved 1048576K
2022-06-24T14:33:43.719-0800: 46.224: [GC (Allocation Failure) [PSYoungGen: 198306K->96K(497152K)] 2949243K->2751041K(3293696K), 0.0077330 secs] [Times: user=0.03 sys=0.00, real=0.01 secs] 
Heap after GC invocations=10 (full 2):
 PSYoungGen      total 497152K, used 96K [0x000000076ab00000, 0x000000078a280000, 0x00000007c0000000)
  eden space 482304K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000788200000)
  from space 14848K, 0% used [0x0000000789180000,0x0000000789198000,0x000000078a000000)
  to   space 15872K, 0% used [0x0000000788200000,0x0000000788200000,0x0000000789180000)
 ParOldGen       total 2796544K, used 2750945K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x0000000767e78428,0x000000076ab00000)
 Metaspace       used 33180K, capacity 35178K, committed 35456K, reserved 1079296K
  class space    used 4227K, capacity 4630K, committed 4736K, reserved 1048576K
}
2022-06-24T14:33:43.727-0800: 46.232: Total time for which application threads were stopped: 0.0079709 seconds, Stopping threads took: 0.0000418 seconds
{Heap before GC invocations=11 (full 2):
 PSYoungGen      total 497152K, used 402487K [0x000000076ab00000, 0x000000078a280000, 0x00000007c0000000)
  eden space 482304K, 83% used [0x000000076ab00000,0x00000007833f5f38,0x0000000788200000)
  from space 14848K, 0% used [0x0000000789180000,0x0000000789198000,0x000000078a000000)
  to   space 15872K, 0% used [0x0000000788200000,0x0000000788200000,0x0000000789180000)
 ParOldGen       total 2796544K, used 2750945K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x0000000767e78428,0x000000076ab00000)
 Metaspace       used 33180K, capacity 35178K, committed 35456K, reserved 1079296K
  class space    used 4227K, capacity 4630K, committed 4736K, reserved 1048576K
2022-06-24T14:33:43.929-0800: 46.433: [GC (Allocation Failure) [PSYoungGen: 402487K->160K(498176K)] 3153432K->2751105K(3294720K), 0.0062558 secs] [Times: user=0.02 sys=0.00, real=0.01 secs] 
Heap after GC invocations=11 (full 2):
 PSYoungGen      total 498176K, used 160K [0x000000076ab00000, 0x000000079b800000, 0x00000007c0000000)
  eden space 482304K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000788200000)
  from space 15872K, 1% used [0x0000000788200000,0x0000000788228000,0x0000000789180000)
  to   space 15872K, 0% used [0x000000079a880000,0x000000079a880000,0x000000079b800000)
 ParOldGen       total 2796544K, used 2750945K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x0000000767e78428,0x000000076ab00000)
 Metaspace       used 33180K, capacity 35178K, committed 35456K, reserved 1079296K
  class space    used 4227K, capacity 4630K, committed 4736K, reserved 1048576K
}
2022-06-24T14:33:43.935-0800: 46.439: Total time for which application threads were stopped: 0.0065390 seconds, Stopping threads took: 0.0000186 seconds
{Heap before GC invocations=12 (full 2):
 PSYoungGen      total 498176K, used 400856K [0x000000076ab00000, 0x000000079b800000, 0x00000007c0000000)
  eden space 482304K, 83% used [0x000000076ab00000,0x000000078324e048,0x0000000788200000)
  from space 15872K, 1% used [0x0000000788200000,0x0000000788228000,0x0000000789180000)
  to   space 15872K, 0% used [0x000000079a880000,0x000000079a880000,0x000000079b800000)
 ParOldGen       total 2796544K, used 2750945K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x0000000767e78428,0x000000076ab00000)
 Metaspace       used 33180K, capacity 35178K, committed 35456K, reserved 1079296K
  class space    used 4227K, capacity 4630K, committed 4736K, reserved 1048576K
2022-06-24T14:33:43.976-0800: 46.480: [GC (Allocation Failure) [PSYoungGen: 400856K->128K(783360K)] 3151801K->2751121K(3579904K), 0.0075534 secs] [Times: user=0.03 sys=0.00, real=0.01 secs] 
Heap after GC invocations=12 (full 2):
 PSYoungGen      total 783360K, used 128K [0x000000076ab00000, 0x000000079b900000, 0x00000007c0000000)
  eden space 767488K, 0% used [0x000000076ab00000,0x000000076ab00000,0x0000000799880000)
  from space 15872K, 0% used [0x000000079a880000,0x000000079a8a0000,0x000000079b800000)
  to   space 16384K, 0% used [0x0000000799880000,0x0000000799880000,0x000000079a880000)
 ParOldGen       total 2796544K, used 2750993K [0x00000006c0000000, 0x000000076ab00000, 0x000000076ab00000)
  object space 2796544K, 98% used [0x00000006c0000000,0x0000000767e84428,0x000000076ab00000)
 Metaspace       used 33180K, capacity 35178K, committed 35456K, reserved 1079296K
  class space    used 4227K, capacity 4630K, committed 4736K, reserved 1048576K
}

lalaorya Jul 16, 2022
Author

I have intercepted a part of it, which I think is enough for test

kcpeppe Jul 19, 2022
Maintainer

The PrintHeapAtGC output is ignored as the data is redundant. A summary of to/from space is in the Survivor events. Eden is always empty after an evacuating collection. The Young gen events all contain the occupancy prior to the collection. For Eden, you just need to apply the math.

karianna Jul 19, 2022
Maintainer

@kcpeppe - so we should warn the user not to use that flag if we detect it?

lalaorya Jul 21, 2022
Author

@kcpeppe Thank you for your response

kcpeppe · 2022-07-21T10:13:10Z

kcpeppe
Jul 21, 2022
Maintainer

The parsers are not bothered by things that they don’t understand so I’ve not felt a need to “warn” people but I guess one could.

…

On Thu, Jul 21, 2022 at 10:46 lalaorya ***@***.***> wrote: @kcpeppe <https://github.com/kcpeppe> Thank you for your response — Reply to this email directly, view it on GitHub <#225 (reply in thread)>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/AAE2NJZ6NUBAJHDQU4XAIQ3VVEE77ANCNFSM53U7GXBA> . You are receiving this because you were mentioned.Message ID: ***@***.***>

1 reply

karianna Jul 21, 2022
Maintainer

I think it's good for developer experience that the tool guides them to use the most effective flags.

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Is gctoolkit parsed by line? #225

{{title}}

Replies: 2 comments 6 replies

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

Is gctoolkit parsed by line? #225

lalaorya Jul 15, 2022

Replies: 2 comments · 6 replies

karianna Jul 15, 2022 Maintainer

lalaorya Jul 16, 2022 Author

lalaorya Jul 16, 2022 Author

kcpeppe Jul 19, 2022 Maintainer

karianna Jul 19, 2022 Maintainer

lalaorya Jul 21, 2022 Author

kcpeppe Jul 21, 2022 Maintainer

karianna Jul 21, 2022 Maintainer

lalaorya
Jul 15, 2022

Replies: 2 comments 6 replies

karianna
Jul 15, 2022
Maintainer

lalaorya Jul 16, 2022
Author

lalaorya Jul 16, 2022
Author

kcpeppe Jul 19, 2022
Maintainer

karianna Jul 19, 2022
Maintainer

lalaorya Jul 21, 2022
Author

kcpeppe
Jul 21, 2022
Maintainer

karianna Jul 21, 2022
Maintainer