GC 日志分析 - Serial GC(串行 GC)

@bingoogolapple 2017-07-27 03:50:18发表于 bingoogolapple/bingoogolapple.github.io Java

  • java -jar -Xms2g -Xmx2g -Xss256k -XX:+UseSerialGC -Xloggc:./log/SerialGC.log -XX:+PrintGC -XX:+PrintGCTimeStamps -XX:+PrintGCDateStamps -XX:+PrintGCDetails build/libs/tuning-1.0.jar
Java HotSpot(TM) 64-Bit Server VM (25.131-b11) for bsd-amd64 JRE (1.8.0_131-b11), built on Mar 15 2017 01:32:22 by "java_re" with gcc 4.2.1 (Based on Apple Inc. build 5658) (LLVM build 2336.11.00)
Memory: 4k page, physical 8388608k(212688k free)

/proc/meminfo:

CommandLine flags: -XX:InitialHeapSize=2147483648 -XX:MaxHeapSize=2147483648 -XX:+PrintGC -XX:+PrintGCDateStamps -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:ThreadStackSize=256 -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseSerialGC

2017-07-27T13:50:34.741-0800: 2.379: [GC (Allocation Failure)
  2017-07-27T13:50:34.741-0800: 2.379: [DefNew: 559232K->69888K(629120K), 1.4259384 secs]
  559232K->527812K(2027264K),
1.4261279 secs]
[Times: user=0.21 sys=0.64, real=1.42 secs]

2017-07-27T13:50:36.830-0800: 4.467: [GC (Allocation Failure)
 2017-07-27T13:50:36.830-0800: 4.467: [DefNew: 629120K->69862K(629120K), 1.0482919 secs]
 1087044K->1075373K(2027264K),
1.0484195 secs]
[Times: user=0.24 sys=0.47, real=1.04 secs]

2017-07-27T13:50:39.020-0800: 6.658: [GC (Allocation Failure)
  2017-07-27T13:50:39.020-0800: 6.658: [DefNew: 629094K->629094K(629120K), 0.0000261 secs]
  2017-07-27T13:50:39.020-0800: 6.658: [Tenured: 1005511K->1352301K(1398144K), 2.9364904 secs]
  1634605K->1352301K(2027264K),
  [Metaspace: 3143K->3143K(1056768K)],
2.9381694 secs]
[Times: user=0.54 sys=1.19, real=2.93 secs]

2017-07-27T13:50:42.085-0800: 9.722: [GC (Allocation Failure)
  2017-07-27T13:50:42.085-0800: 9.722: [DefNew: 559232K->559232K(629120K), 0.0000406 secs]
  2017-07-27T13:50:42.085-0800: 9.722: [Tenured: 1352301K->1398042K(1398144K), 1.5665746 secs]
  1911533K->1505982K(2027264K),
  [Metaspace: 3144K->3144K(1056768K)],
1.5667670 secs]
[Times: user=0.66 sys=0.06, real=1.57 secs]

2017-07-27T13:50:43.887-0800: 11.524: [Full GC (Allocation Failure)
  2017-07-27T13:50:43.887-0800: 11.525: [Tenured: 1398042K->1397963K(1398144K), 0.8454010 secs]
  2026966K->1598176K(2027264K),
  [Metaspace: 3145K->3145K(1056768K)],
0.8455380 secs]
[Times: user=0.59 sys=0.01, real=0.85 secs]

2017-07-27T13:50:44.824-0800: 12.462: [Full GC (Allocation Failure)
  2017-07-27T13:50:44.825-0800: 12.462: [Tenured: 1397963K->1398079K(1398144K), 0.6756190 secs]
  2027040K->1649395K(2027264K),
  [Metaspace: 3145K->3145K(1056768K)],
0.6757408 secs]
[Times: user=0.55 sys=0.02, real=0.68 secs]

2017-07-27T13:50:46.323-0800: 13.960: [Full GC (Allocation Failure)
  2017-07-27T13:50:46.323-0800: 13.960: [Tenured: 1398079K->1398138K(1398144K), 0.5022550 secs]
  2027056K->1729295K(2027264K),
  [Metaspace: 3158K->3158K(1056768K)],
0.5024000 secs]
[Times: user=0.47 sys=0.01, real=0.50 secs]

2017-07-27T13:50:47.519-0800: 15.156: [Full GC (Allocation Failure)
  2017-07-27T13:50:47.519-0800: 15.156: [Tenured: 1398138K->1397989K(1398144K), 0.6711842 secs]
  2027166K->1852817K(2027264K),
  [Metaspace: 3158K->3158K(1056768K)],
0.6713084 secs]
[Times: user=0.49 sys=0.01, real=0.68 secs]

Heap
 def new generation   total 629120K, used 495211K [0x0000000740000000, 0x000000076aaa0000, 0x000000076aaa0000)
  eden space 559232K,  88% used [0x0000000740000000, 0x000000075e39af20, 0x0000000762220000)
  from space 69888K,   0% used [0x0000000762220000, 0x0000000762220000, 0x0000000766660000)
  to   space 69888K,   0% used [0x0000000766660000, 0x0000000766660000, 0x000000076aaa0000)
 tenured generation   total 1398144K, used 1397989K [0x000000076aaa0000, 0x00000007c0000000, 0x00000007c0000000)
   the space 1398144K,  99% used [0x000000076aaa0000, 0x00000007bffd96d0, 0x00000007bffd9800, 0x00000007c0000000)
 Metaspace       used 3174K, capacity 4486K, committed 4864K, reserved 1056768K
  class space    used 354K, capacity 386K, committed 512K, reserved 1048576K