Forum Stats

  • 3,837,914 Users
  • 2,262,307 Discussions
  • 7,900,431 Comments

Discussions

Very long parallel scavenge, two minuses in the gclog [GC-- [PSYoungGen

Hello,

I have a JDK7u80 ParallelGC that all of a sudden goes wild, for no apparent reason.

Two examples follow of the problem occurring.

One single veeeeery long young gc, with huge system CPU taken.

After that, sometimes it recovers, other times a series of FullGC until an OOM is produced.

By the way, what is the [GC-- notation, what the two minuses stand for?

I have 16 cores, Red Hat Linux 5, and 13 parallel gc threads (default).

Are they too many?

Could it be the reason?

Thanks a lot!!

Regards

F.Costa


2016-08-26T13:00:47.263+0200: 1362734.000: [GC PSYoungGen: 1354329K->15798K(1364480K)] 3788457K->2451232K(4161024K), 0.0711400 secs Times: user=0.55 sys=0.00, real=0.08 secs
2016-08-26T13:06:52.035+0200: 1363098.772: [GC PSYoungGen: 1346486K->23978K(1364480K)] 3781920K->2461258K(4161024K), 0.0914020 secs Times: user=0.76 sys=0.01, real=0.09 secs
2016-08-26T13:14:25.826+0200: 1363552.563: [GC PSYoungGen: 1354666K->22908K(1365504K)] 3791946K->2461386K(4162048K), 0.0923660 secs Times: user=0.74 sys=0.00, real=0.09 secs
2016-08-26T13:18:43.883+0200: 1363810.620: [GC PSYoungGen: 1354620K->21590K(1364480K)] 3793098K->2462535K(4161024K), 0.0914420 secs Times: user=0.76 sys=0.00, real=0.09 secs
2016-08-26T13:26:37.111+0200: 1364283.848: [GC PSYoungGen: 1353302K->25395K(1365504K)] 3794247K->2467700K(4162048K), 0.0953620 secs Times: user=0.79 sys=0.00, real=0.10 secs
2016-08-26T13:32:48.573+0200: 1364655.310: [GC PSYoungGen: 1359155K->27290K(1361408K)] 3801460K->2470684K(4157952K), 0.1001910 secs Times: user=0.84 sys=0.00, real=0.10 secs
2016-08-26T13:38:53.073+0200: 1365019.810: [GC PSYoungGen: 1361050K->24656K(1364992K)] 3804444K->2470056K(4161536K), 0.1001970 secs Times: user=0.83 sys=0.00, real=0.10 secs
2016-08-26T13:46:45.915+0200: 1365492.651: [GC PSYoungGen: 1356368K->23069K(1364992K)] 3801768K->2470069K(4161536K), 0.0956950 secs Times: user=0.78 sys=0.00, real=0.09 secs
2016-08-26T13:51:00.475+0200: 1365747.212: [GC PSYoungGen: 1354781K->20895K(1366528K)] 3801781K->2469894K(4163072K), 0.0844810 secs Times: user=0.69 sys=0.00, real=0.08 secs
2016-08-26T13:57:36.432+0200: 1366143.168: [GC PSYoungGen: 1355167K->24047K(1366016K)] 3804166K->2474162K(4162560K), 0.0969750 secs Times: user=0.80 sys=0.00, real=0.09 secs
2016-08-26T14:02:29.313+0200: 1366436.050: [GC PSYoungGen: 1358319K->15284K(1366016K)] 3808434K->2467203K(4162560K), 0.0727570 secs Times: user=0.56 sys=0.00, real=0.07 secs
2016-08-26T14:08:03.262+0200: 1366769.998: [GC PSYoungGen: 1350068K->24810K(1366528K)] 3801987K->2478246K(4163072K), 0.0937400 secs Times: user=0.76 sys=0.00, real=0.10 secs
2016-08-26T14:08:38.434+0200: 1366805.171: [GC-- PSYoungGen: 1359594K->1359594K(1366528K)] 3813030K->4156137K(4163072K), 8.3212870 secs Times: user=11.88 sys=55.59, real=8.32 secs
2016-08-26T14:08:46.756+0200: 1366813.492: [Full GC PSYoungGen: 1359594K->0K(1366528K) ParOldGen: 2796542K->1088910K(2796544K) 4156137K->1088910K(4163072K) PSPermGen: 351403K->350858K(624128K)], 2.9298870 secs Times: user=18.49 sys=0.01, real=2.93 secs

2016-08-22T11:07:29.040+0200: 1010335.777: [GC PSYoungGen: 1347178K->23327K(1363456K)] 4029004K->2707602K(4160000K), 0.0960100 secs Times: user=0.82 sys=0.00, real=0.10 secs

2016-08-22T11:15:34.484+0200: 1010821.221: [GC PSYoungGen: 1352479K->23116K(1365504K)] 4036754K->2708203K(4162048K), 0.0912540 secs Times: user=0.78 sys=0.00, real=0.09 secs

2016-08-22T11:20:05.216+0200: 1011091.953: [GC PSYoungGen: 1355340K->19583K(1364992K)] 4040427K->2706434K(4161536K), 0.0821680 secs Times: user=0.69 sys=0.01, real=0.09 secs

2016-08-22T11:27:41.481+0200: 1011548.218: [GC PSYoungGen: 1351807K->24480K(1366016K)] 4038658K->2712003K(4162560K), 0.0916880 secs Times: user=0.79 sys=0.00, real=0.09 secs

2016-08-22T11:34:02.125+0200: 1011928.862: [GC PSYoungGen: 1358240K->26377K(1360384K)] 4045763K->2715374K(4156928K), 0.1028030 secs Times: user=0.90 sys=0.00, real=0.11 secs

2016-08-22T11:42:29.236+0200: 1012435.973: [GC PSYoungGen: 1360137K->24386K(1366016K)] 4049134K->2714677K(4162560K), 0.0907220 secs Times: user=0.77 sys=0.00, real=0.09 secs

2016-08-22T11:48:07.422+0200: 1012774.158: [GC PSYoungGen: 1357634K->22911K(1365504K)] 4047925K->2714788K(4162048K), 0.0887210 secs Times: user=0.75 sys=0.01, real=0.08 secs

2016-08-22T11:51:56.308+0200: 1013003.045: [GC PSYoungGen: 1356159K->18547K(1367040K)] 4048036K->2711991K(4163584K), 0.0845990 secs Times: user=0.66 sys=0.01, real=0.09 secs

2016-08-22T11:54:51.316+0200: 1013178.053: [GC PSYoungGen: 1354355K->11436K(1347584K)] 4047799K->2705588K(4144128K), 0.0555960 secs Times: user=0.38 sys=0.01, real=0.05 secs

2016-08-22T12:00:13.355+0200: 1013500.092: [GC PSYoungGen: 1347244K->32224K(1347584K)] 4041396K->2743562K(4144128K), 0.0999790 secs Times: user=0.97 sys=0.00, real=0.10 secs

2016-08-22T12:07:00.125+0200: 1013906.861: [GC PSYoungGen: 1347552K->20031K(1356800K)] 4058890K->2732593K(4153344K), 0.0918130 secs Times: user=0.76 sys=0.00, real=0.09 secs

2016-08-22T12:11:35.515+0200: 1014182.251: [GC-- PSYoungGen: 1335359K->1335359K(1356800K)] 4047921K->4131898K(4153344K), 4.0220370 secs Times: user=5.14 sys=11.06, real=4.02 secs

2016-08-22T12:11:39.537+0200: 1014186.274: [Full GC PSYoungGen: 1335359K->0K(1356800K) ParOldGen: 2796539K->638394K(2796544K) 4131898K->638394K(4153344K) PSPermGen: 349572K->349333K(692224K)], 3.2783670 secs Times: user=22.57 sys=0.20, real=3.28 secs

This discussion has been closed.