Abnomalities in garbage collection

Discussion in 'Java' started by angsapurai@gmail.com, May 1, 2007.

  1. Guest

    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]
    , May 1, 2007
    #1
    1. Advertising

  2. Esmond Pitt Guest

    wrote:

    > 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?
    Esmond Pitt, May 2, 2007
    #2
    1. Advertising

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

It takes just 2 minutes to sign up (and it's free!). Just click the sign up button to choose a username and then you can ask your own questions on the forum.
Similar Threads
  1. Laser Lu

    Garbage Collection and Manage Code?

    Laser Lu, Jan 26, 2004, in forum: ASP .Net
    Replies:
    5
    Views:
    712
    Gaurav Khanna [C# MVP]
    Jan 27, 2004
  2. Cheung, Jeffrey Jing-Yen
    Replies:
    3
    Views:
    1,549
    Cheung, Jeffrey Jing-Yen
    Jul 10, 2003
  3. IDoNothing

    Garbage Collection in ASP.NET

    IDoNothing, Jan 9, 2004, in forum: ASP .Net
    Replies:
    2
    Views:
    8,365
    cjohn
    Aug 20, 2009
  4. Replies:
    1
    Views:
    440
    mrstephengross
    Jul 25, 2005
  5. Øyvind Isaksen
    Replies:
    1
    Views:
    966
    Øyvind Isaksen
    May 18, 2007
Loading...

Share This Page