Abnomalities in garbage collection

A

angsapurai

Hi,

I need your help in analysing the garbage collection stats that I have
below.
The application has been suffering poor performance lately.
Just need any ideas/pointers on how to spot abnomalities in the stats.
Thanks.......

0.000: [GC 0.000: [DefNew: 54911K->1664K(56576K), 0.0734087 secs]
54911K->3769K(522624K), 0.0735928 secs]
1.779: [GC 1.779: [DefNew: 56574K->1664K(56576K), 0.0595569 secs]
58680K->6358K(522624K), 0.0596786 secs]
13.395: [Full GC 13.395: [Tenured: 4694K->11252K(466048K), 0.2706545
secs] 56000K->11252K(522624K), [Perm : 8191K->8191K(8192K)], 0.2707766
secs]
21.208: [GC 21.208: [DefNew: 54911K->1664K(56576K), 0.1050124 secs]
66164K->19763K(522624K), 0.1051361 secs]
23.799: [GC 23.799: [DefNew: 56575K->1664K(56576K), 0.1558598 secs]
74675K->29604K(522624K), 0.1559848 secs]
27.046: [Full GC 27.046: [Tenured: 27940K->33613K(466048K), 0.4761944
secs] 62925K->33613K(522624K), [Perm : 12287K->12287K(12288K)],
0.4763144 secs]
35.068: [GC 35.068: [DefNew: 54911K->1664K(56576K), 0.1575305 secs]
88524K->46820K(522624K), 0.1576522 secs]
36.549: [Full GC 36.549: [Tenured: 45156K->48925K(466048K), 0.6199032
secs] 65221K->48925K(522624K), [Perm : 16383K->16383K(16384K)],
0.6200537 secs]
80.386: [GC 80.386: [DefNew: 54911K->1664K(56576K), 0.1050469 secs]
103837K->61680K(522624K), 0.1052653 secs]
131.855: [Full GC 131.856: [Tenured: 60016K->57433K(466048K),
0.8884727 secs] 93214K->57433K(522624K), [Perm : 20476K-
20417K(20480K)], 0.8885931 secs]
176.171: [GC 176.171: [DefNew: 54911K->1664K(56576K), 0.0508995 secs]
112345K->63242K(522624K), 0.0510178 secs]
231.209: [GC 231.209: [DefNew: 56576K->1664K(56576K), 0.0689882 secs]
118154K->68523K(522624K), 0.0690884 secs]
264.633: [Full GC 264.633: [Tenured: 66859K->74166K(466048K),
0.7692325 secs] 115298K->74166K(522624K), [Perm : 24575K-
24575K(24576K)], 0.7693558 secs]
315.274: [GC 315.274: [DefNew: 54911K->1664K(56576K), 0.0670180 secs]
129077K->78637K(522624K), 0.0671217 secs]
370.185: [GC 370.185: [DefNew: 56575K->1664K(56576K), 0.0778997 secs]
133548K->82011K(522624K), 0.0780027 secs]
432.938: [GC 432.938: [DefNew: 56575K->1664K(56576K), 0.1228309 secs]
136923K->85341K(522624K), 0.1229372 secs]
524.962: [GC 524.962: [DefNew: 56575K->1664K(56576K), 0.1034583 secs]
140253K->90995K(522624K), 0.1035709 secs]
557.987: [GC 557.987: [DefNew: 56575K->1664K(56576K), 0.0737350 secs]
145907K->96448K(522624K), 0.0738399 secs]
573.692: [GC 573.692: [DefNew: 56528K->1664K(56576K), 0.0576918 secs]
151313K->100193K(522624K), 0.0577851 secs]
589.102: [GC 589.102: [DefNew: 56575K->1664K(56576K), 0.0878892 secs]
155105K->103123K(522624K), 0.0880057 secs]
611.959: [GC 611.959: [DefNew: 56575K->1664K(56576K), 0.0524661 secs]
158035K->106936K(522624K), 0.0525695 secs]
618.733: [GC 618.734: [DefNew: 56564K->1513K(56576K), 0.0324815 secs]
161836K->108315K(522624K), 0.0326152 secs]
651.952: [GC 651.952: [DefNew: 56425K->1664K(56576K), 0.0421597 secs]
163227K->110501K(522624K), 0.0422888 secs]
679.505: [GC 679.505: [DefNew: 55769K->1664K(56576K), 0.0572077 secs]
164607K->113795K(522624K), 0.0573127 secs]
729.514: [GC 729.514: [DefNew: 56164K->1664K(56576K), 0.0414103 secs]
168296K->117098K(522624K), 0.0415348 secs]
798.227: [GC 798.227: [DefNew: 56575K->1664K(56576K), 0.0310887 secs]
172010K->120053K(522624K), 0.0312006 secs]
827.480: [GC 827.480: [DefNew: 56575K->1664K(56576K), 0.0356223 secs]
174965K->121845K(522624K), 0.0357287 secs]
861.390: [GC 861.391: [DefNew: 56575K->1664K(56576K), 0.0365398 secs]
176757K->123675K(522624K), 0.0429123 secs]
895.024: [GC 895.024: [DefNew: 56576K->1664K(56576K), 0.0331740 secs]
178587K->125333K(522624K), 0.0333008 secs]
942.885: [GC 942.885: [DefNew: 56575K->1664K(56576K), 0.0233624 secs]
180245K->126734K(522624K), 0.0234657 secs]
990.118: [GC 990.118: [DefNew: 56575K->1664K(56576K), 0.0396421 secs]
181646K->128732K(522624K), 0.0397654 secs]
991.915: [Full GC 991.915: [Tenured: 127068K->117391K(466048K),
0.8646993 secs] 132263K->117391K(522624K), [Perm : 28671K-
28671K(28672K)], 0.8648323 secs]
1025.811: [GC 1025.811: [DefNew: 54911K->1664K(56576K), 0.0457054
secs] 172303K->120443K(522624K), 0.0458180 secs]
1071.186: [GC 1071.186: [DefNew: 56575K->1663K(56576K), 0.0246691
secs] 175355K->122419K(522624K), 0.0248062 secs]
1106.789: [GC 1106.789: [DefNew: 56575K->1664K(56576K), 0.0293193
secs] 177331K->124943K(522624K), 0.0294978 secs]
1134.789: [GC 1134.789: [DefNew: 56576K->1664K(56576K), 0.0811730
secs] 179855K->127915K(522624K), 0.0812807 secs]
1163.856: [GC 1163.856: [DefNew: 56090K->1664K(56576K), 0.1971787
secs] 182342K->131272K(522624K), 0.1972812 secs]
1173.956: [GC 1173.956: [DefNew: 56534K->1638K(56576K), 0.0565529
secs] 186142K->132588K(522624K), 0.0566600 secs]
1220.469: [GC 1220.469: [DefNew: 56550K->1664K(56576K), 0.0480882
secs] 187500K->135697K(522624K), 0.0481944 secs]
1257.497: [GC 1257.498: [DefNew: 56573K->1663K(56576K), 0.0301115
secs] 190607K->137734K(522624K), 0.0302342 secs]
1302.912: [GC 1302.912: [DefNew: 56575K->1664K(56576K), 0.0602092
secs] 192646K->142181K(522624K), 0.0603345 secs]
1334.626: [GC 1334.626: [DefNew: 56569K->1664K(56576K), 0.0795162
secs] 197086K->146272K(522624K), 0.0796202 secs]
1399.685: [GC 1399.685: [DefNew: 56567K->1664K(56576K), 0.0481038
secs] 201175K->149971K(522624K), 0.0482135 secs]
1419.123: [GC 1419.123: [DefNew: 56542K->1664K(56576K), 0.0311069
secs] 204850K->151671K(522624K), 0.0313076 secs]
1442.274: [GC 1442.274: [DefNew: 56571K->1664K(56576K), 0.0845711
secs] 206578K->153577K(522624K), 0.0846978 secs]
1468.170: [GC 1468.170: [DefNew: 56576K->1663K(56576K), 0.0294006
secs] 208489K->155223K(522624K), 0.0295257 secs]
1520.653: [GC 1520.653: [DefNew: 56575K->1664K(56576K), 0.0575370
secs] 210135K->159265K(522624K), 0.0576625 secs]
1549.967: [GC 1549.967: [DefNew: 56575K->1664K(56576K), 0.0529297
secs] 214177K->162192K(522624K), 0.0530695 secs]
1578.754: [GC 1578.754: [DefNew: 56575K->1664K(56576K), 0.0617784
secs] 217104K->165750K(522624K), 0.0619010 secs]
1604.714: [GC 1604.714: [DefNew: 56571K->1664K(56576K), 0.0393907
secs] 220657K->167944K(522624K), 0.0395163 secs]
1637.763: [GC 1637.763: [DefNew: 56575K->1664K(56576K), 0.0665301
secs] 222856K->172147K(522624K), 0.0666380 secs]
1662.814: [GC 1662.814: [DefNew: 56575K->1664K(56576K), 0.0752687
secs] 227059K->175970K(522624K), 0.0753955 secs]
1684.026: [GC 1684.026: [DefNew: 56575K->1664K(56576K), 0.0704122
secs] 230882K->179356K(522624K), 0.0705762 secs]
1703.068: [GC 1703.068: [DefNew: 56575K->1664K(56576K), 0.0777745
secs] 234268K->182991K(522624K), 0.0778975 secs]
1711.740: [GC 1711.740: [DefNew: 56330K->1664K(56576K), 0.0618018
secs] 237657K->186058K(522624K), 0.0619135 secs]
1746.115: [GC 1746.115: [DefNew: 56559K->1664K(56576K), 0.0364305
secs] 240953K->188831K(522624K), 0.0365376 secs]
1762.954: [GC 1762.954: [DefNew: 56576K->1664K(56576K), 0.0424266
secs] 243743K->191148K(522624K), 0.0425337 secs]
1769.962: [GC 1769.962: [DefNew: 56569K->766K(56576K), 0.1591911 secs]
246053K->191575K(522624K), 0.1593184 secs]
1773.997: [GC 1773.997: [DefNew: 55677K->711K(56576K), 0.1372976 secs]
246487K->191520K(522624K), 0.1374049 secs]
1778.641: [GC 1778.641: [DefNew: 55400K->691K(56576K), 0.0109834 secs]
246210K->191500K(522624K), 0.0110953 secs]
1780.570: [GC 1780.584: [DefNew: 55258K->616K(56576K), 0.0105768 secs]
246067K->191425K(522624K), 0.0107136 secs]
1781.888: [GC 1781.888: [DefNew: 54558K->587K(56576K), 0.0101388 secs]
245367K->191397K(522624K), 0.0103050 secs]
1838.756: [GC 1838.756: [DefNew: 55499K->1664K(56576K), 0.0371874
secs] 246309K->195085K(522624K), 0.0373164 secs]
1881.886: [GC 1881.886: [DefNew: 56576K->1664K(56576K), 0.0726941
secs] 249997K->199500K(522624K), 0.0727996 secs]
1904.280: [GC 1904.280: [DefNew: 56576K->1664K(56576K), 0.1583908
secs] 254412K->205324K(522624K), 0.1584962 secs]
1924.104: [GC 1924.104: [DefNew: 56271K->1664K(56576K), 0.0900777
secs] 259931K->209223K(522624K), 0.0902185 secs]
1932.918: [GC 1932.918: [DefNew: 56575K->1664K(56576K), 0.0808788
secs] 264135K->212631K(522624K), 0.0810054 secs]
1976.260: [GC 1976.260: [DefNew: 56576K->1664K(56576K), 0.0514692
secs] 267543K->215504K(522624K), 0.0515948 secs]
2004.463: [GC 2004.463: [DefNew: 56565K->1664K(56576K), 0.0767589
secs] 270405K->219225K(522624K), 0.0768832 secs]
2035.791: [GC 2035.791: [DefNew: 56575K->1664K(56576K), 0.0507820
secs] 274137K->221686K(522624K), 0.0508914 secs]
2097.562: [GC 2097.562: [DefNew: 56575K->1664K(56576K), 0.0489815
secs] 276598K->225298K(522624K), 0.0492700 secs]
2118.601: [GC 2118.601: [DefNew: 56575K->1664K(56576K), 0.0686050
secs] 280210K->228656K(522624K), 0.0687298 secs]
2141.642: [GC 2141.642: [DefNew: 55704K->1664K(56576K), 0.1276123
secs] 282697K->234728K(522624K), 0.1277375 secs]
2182.391: [GC 2182.391: [DefNew: 56575K->1664K(56576K), 0.1416242
secs] 289640K->238120K(522624K), 0.1417331 secs]
2199.433: [GC 2199.433: [DefNew: 56575K->1664K(56576K), 0.1891759
secs] 293032K->242363K(522624K), 0.1893338 secs]
2215.445: [GC 2215.445: [DefNew: 56575K->1664K(56576K), 0.0609179
secs] 297275K->245104K(522624K), 0.0610241 secs]
2226.698: [GC 2226.698: [DefNew: 56575K->1664K(56576K), 0.1548134
secs] 300016K->249920K(522624K), 0.1549392 secs]
2254.213: [GC 2254.213: [DefNew: 56575K->1664K(56576K), 0.0811518
secs] 304832K->253429K(522624K), 0.0812587 secs]
2270.558: [GC 2270.558: [DefNew: 56575K->1664K(56576K), 0.1113175
secs] 308341K->257975K(522624K), 0.1114612 secs]
2285.252: [GC 2285.252: [DefNew: 56576K->1664K(56576K), 0.1361949
secs] 312887K->261548K(522624K), 0.1363231 secs]
2302.567: [GC 2302.568: [DefNew: 55673K->1664K(56576K), 0.0592011
secs] 315557K->264002K(522624K), 0.0593260 secs]
2335.472: [GC 2335.472: [DefNew: 56575K->1664K(56576K), 0.1318573
secs] 318914K->268816K(522624K), 0.1319748 secs]
2353.688: [GC 2353.688: [DefNew: 56202K->1664K(56576K), 0.0956834
secs] 323354K->273191K(522624K), 0.0957891 secs]
2377.268: [GC 2377.268: [DefNew: 56575K->1664K(56576K), 0.0716462
secs] 328103K->276584K(522624K), 0.0717958 secs]
2397.294: [GC 2397.294: [DefNew: 56575K->1664K(56576K), 0.0786196
secs] 331496K->280482K(522624K), 0.0787298 secs]
2416.259: [GC 2416.259: [DefNew: 56576K->1664K(56576K), 0.0574909
secs] 335394K->282982K(522624K), 0.0576150 secs]
2425.736: [GC 2425.737: [DefNew: 56573K->1664K(56576K), 0.1086472
secs] 337891K->287812K(522624K), 0.1087710 secs]
2433.739: [GC 2433.739: [DefNew: 56320K->1225K(56576K), 0.0341194
secs] 342469K->288771K(522624K), 0.0342448 secs]
2459.372: [GC 2459.372: [DefNew: 56137K->1664K(56576K), 0.0543480
secs] 343683K->291502K(522624K), 0.0544560 secs]
2481.234: [GC 2481.234: [DefNew: 56576K->1664K(56576K), 0.0775443
secs] 346414K->295385K(522624K), 0.0776664 secs]
2509.303: [GC 2509.303: [DefNew: 56573K->1664K(56576K), 0.0942458
secs] 350294K->300044K(522624K), 0.0943526 secs]
2520.243: [GC 2520.243: [DefNew: 56575K->1664K(56576K), 0.0899388
secs] 354955K->303854K(522624K), 0.0900432 secs]
2540.562: [GC 2540.563: [DefNew: 56575K->1664K(56576K), 0.0531239
secs] 358766K->306579K(522624K), 0.0532313 secs]
2556.287: [GC 2556.287: [DefNew: 56575K->1664K(56576K), 0.0849096
secs] 361491K->309168K(522624K), 0.0850137 secs]
2593.520: [GC 2593.520: [DefNew: 56575K->1664K(56576K), 0.1447448
secs] 364080K->312685K(522624K), 0.1448874 secs]
2626.258: [GC 2626.258: [DefNew: 56575K->1664K(56576K), 0.0865545
secs] 367597K->317584K(522624K), 0.0866826 secs]
2655.100: [GC 2655.100: [DefNew: 56575K->1664K(56576K), 0.1842978
secs] 372496K->323858K(522624K), 0.1844079 secs]
2691.326: [GC 2691.326: [DefNew: 56575K->1664K(56576K), 0.0775044
secs] 378770K->328164K(522624K), 0.0776106 secs]
2707.117: [GC 2707.117: [DefNew: 56575K->1664K(56576K), 0.1148404
secs] 383076K->333387K(522624K), 0.1149644 secs]
2732.962: [GC 2732.962: [DefNew: 56575K->1664K(56576K), 0.1076362
secs] 388299K->339123K(522624K), 0.1077462 secs]
2787.660: [GC 2787.660: [DefNew: 56575K->1664K(56576K), 0.0481374
secs] 394034K->342587K(522624K), 0.0482367 secs]
2811.863: [GC 2811.863: [DefNew: 56575K->1664K(56576K), 0.0711220
secs] 397499K->346192K(522624K), 0.0712192 secs]
2821.831: [GC 2821.831: [DefNew: 56295K->1664K(56576K), 0.0873039
secs] 400823K->349909K(522624K), 0.0874295 secs]
2840.028: [GC 2840.028: [DefNew: 56575K->1664K(56576K), 0.0508301
secs] 404821K->352187K(522624K), 0.0509550 secs]
2869.621: [GC 2869.621: [DefNew: 56575K->1664K(56576K), 0.0478104
secs] 407099K->355133K(522624K), 0.0479156 secs]
2876.396: [GC 2876.396: [DefNew: 55760K->1664K(56576K), 0.0858876
secs] 409230K->358563K(522624K), 0.0859955 secs]
2907.963: [GC 2907.963: [DefNew: 56575K->1664K(56576K), 0.0472892
secs] 413475K->360945K(522624K), 0.0473950 secs]
2928.248: [GC 2928.248: [DefNew: 56576K->1664K(56576K), 0.0639952
secs] 415857K->364283K(522624K), 0.0641033 secs]
2958.355: [GC 2958.355: [DefNew: 55859K->1664K(56576K), 0.0686785
secs] 418478K->368008K(522624K), 0.0687866 secs]
2999.488: [GC 2999.488: [DefNew: 56575K->1664K(56576K), 0.0500876
secs] 422920K->371473K(522624K), 0.0501958 secs]
3049.294: [GC 3049.294: [DefNew: 56575K->1664K(56576K), 0.0433435
secs] 426385K->374599K(522624K), 0.0434523 secs]
3097.088: [GC 3097.088: [DefNew: 56576K->1664K(56576K), 0.0771578
secs] 429511K->377471K(522624K), 0.0772879 secs]
3119.671: [GC 3119.671: [DefNew: 56575K->1664K(56576K), 0.1560402
secs] 432383K->382242K(522624K), 0.1561598 secs]
3142.967: [GC 3142.967: [DefNew: 56570K->1664K(56576K), 0.0997390
secs] 437149K->386968K(522624K), 0.0998434 secs]
3178.349: [GC 3178.349: [DefNew: 56575K->1664K(56576K), 0.0866288
secs] 441879K->391717K(522624K), 0.0867587 secs]
3234.397: [GC 3234.397: [DefNew: 56575K->1664K(56576K), 0.0402494
secs] 446628K->394724K(522624K), 0.0404876 secs]
3297.361: [GC 3297.362: [DefNew: 56575K->1664K(56576K), 0.1206803
secs] 449636K->402101K(522624K), 0.1208291 secs]
3331.547: [GC 3331.547: [DefNew: 56575K->1664K(56576K), 0.1577863
secs] 457013K->408860K(522624K), 0.1578992 secs]
3374.374: [GC 3374.374: [DefNew: 56560K->1664K(56576K), 0.0620132
secs] 463756K->412109K(522624K), 0.0621564 secs]
3405.992: [GC 3405.992: [DefNew: 56575K->56575K(56576K), 0.0000221
secs]3405.993: [Tenured: 410445K->207265K(466048K), 2.1687345 secs]
467021K->207265K(522624K), 2.1689142 secs]
3424.745: [GC 3424.745: [DefNew: 54467K->1664K(56576K), 0.1070408
secs] 261733K->210546K(522624K), 0.1071548 secs]
3428.670: [GC 3428.670: [DefNew: 55959K->455K(56576K), 0.0250063 secs]
264842K->210926K(522624K), 0.0251288 secs]
3444.160: [GC 3444.160: [DefNew: 55357K->1664K(56576K), 0.0727252
secs] 265829K->212394K(522624K), 0.0728431 secs]
3501.712: [GC 3501.713: [DefNew: 56575K->1664K(56576K), 0.0318700
secs] 267306K->216117K(522624K), 0.0319912 secs]
3524.788: [GC 3524.788: [DefNew: 56576K->1664K(56576K), 0.1378397
secs] 271029K->224534K(522624K), 0.1379702 secs]
3564.194: [GC 3564.195: [DefNew: 56575K->1664K(56576K), 0.0410949
secs] 279446K->227585K(522624K), 0.0412049 secs]
3588.634: [GC 3588.634: [DefNew: 56575K->1664K(56576K), 0.1376143
secs] 282496K->232970K(522624K), 0.1377443 secs]
3612.195: [GC 3612.195: [DefNew: 56575K->1664K(56576K), 0.0910037
secs] 287882K->238585K(522624K), 0.0911261 secs]
3635.109: [GC 3635.109: [DefNew: 56575K->1664K(56576K), 0.0832009
secs] 293497K->240643K(522624K), 0.0833149 secs]
3652.835: [GC 3652.835: [DefNew: 56571K->1664K(56576K), 0.0920178
secs] 295551K->245095K(522624K), 0.0921134 secs]
3668.062: [GC 3668.062: [DefNew: 56576K->1664K(56576K), 0.0850423
secs] 300007K->249804K(522624K), 0.0852358 secs]
3695.449: [GC 3695.449: [DefNew: 56575K->1663K(56576K), 0.0447438
secs] 304716K->252584K(522624K), 0.0448575 secs]
3712.513: [GC 3712.513: [DefNew: 56575K->1664K(56576K), 0.0854420
secs] 307495K->257295K(522624K), 0.0855490 secs]
3731.694: [GC 3731.694: [DefNew: 56574K->1664K(56576K), 0.0489690
secs] 312205K->260497K(522624K), 0.0490786 secs]
 
E

Esmond Pitt

3374.374: [GC 3374.374: [DefNew: 56560K->1664K(56576K), 0.0620132
secs] 463756K->412109K(522624K), 0.0621564 secs]
3405.992: [GC 3405.992: [DefNew: 56575K->56575K(56576K), 0.0000221
secs]3405.993: [Tenured: 410445K->207265K(466048K), 2.1687345 secs]
467021K->207265K(522624K), 2.1689142 secs]

In between these two GC runs your program released 256 megabytes or so.
That's a real lot of memory to use and a real lot to release over a
short space of time. How come?
 

Ask a Question

Want to reply to this thread or ask your own question?

You'll need to choose a username for the site, which only take a couple of moments. After that, you can post your question and our members will help you out.

Ask a Question

Members online

No members online now.

Forum statistics

Threads
473,767
Messages
2,569,571
Members
45,045
Latest member
DRCM

Latest Threads

Top