[jboss-user] [Persistence, JBoss/CMP, Hibernate, Database] - GC problem ?

[vettori]

Hi,

I have the following problem with JBoss 4.0.5GA :

I have an java app that using session beans performs some operations on entity beans.
One of this operation is to scan ALL the beans of a certain type and do an update.
The problem is the following.
On the first run of the app I can see the gc working (i suppose removing the entity beans). The memory usage is high but it's short in time.

For example the gc log is :
[INFO ][memory ] 6.229-6.420: GC 511614K->7974K (5242880K), 190.590 ms
[INFO ][memory ] 329.471: parallel nursery GC 1476387K->689203K (5242880K), 505.334 ms
[INFO ][memory ] 346.359: parallel nursery GC 1476003K->689203K (5242880K), 435.881 ms
[INFO ][memory ] 362.523: parallel nursery GC 1476003K->689075K (5242880K), 428.308 ms
[INFO ][memory ] 383.814: parallel nursery GC 1852701K->1065517K (5242880K), 434.963 ms

If I run a second time the app, the gc enter something like an infinite loop (look at the time elapsed between various call)

[INFO ][memory ] 493.644: parallel nursery GC 1852317K->1065069K (5242880K), 88.713 ms
[INFO ][memory ] 497.127: parallel nursery GC 1851869K->1064797K (5242880K), 31.096 ms
[INFO ][memory ] 500.167: parallel nursery GC 1851597K->1064493K (5242880K), 31.199 ms
[INFO ][memory ] 503.087: parallel nursery GC 1851293K->1064493K (5242880K), 28.628 ms
[INFO ][memory ] 505.881: parallel nursery GC 1851293K->1064382K (5242880K), 52.890 ms
[INFO ][memory ] 508.346: parallel nursery GC 1851182K->1064254K (5242880K), 88.999 ms
[INFO ][memory ] 510.823: parallel nursery GC 1851054K->1063870K (5242880K), 95.023 ms
[INFO ][memory ] 513.459: parallel nursery GC 1850670K->1063678K (5242880K), 86.064 ms
[INFO ][memory ] 516.031: parallel nursery GC 1850478K->1063678K (5242880K), 87.788 ms
[INFO ][memory ] 518.565: parallel nursery GC 1850478K->1063486K (5242880K), 86.779 ms
[INFO ][memory ] 521.069: parallel nursery GC 1850286K->1063102K (5242880K), 94.063 ms
[INFO ][memory ] 523.622: parallel nursery GC 1849902K->1063038K (5242880K), 88.928 ms
[INFO ][memory ] 526.108: parallel nursery GC 1849838K->1062590K (5242880K), 94.253 ms
[INFO ][memory ] 528.714: parallel nursery GC 1849390K->1062590K (5242880K), 83.759 ms
[INFO ][memory ] 531.211: parallel nursery GC 1849390K->1062590K (5242880K), 86.603 ms
[INFO ][memory ] 533.695: parallel nursery GC 1849390K->1062398K (5242880K), 91.240 ms
[INFO ][memory ] 536.522: parallel nursery GC 1849198K->1062398K (5242880K), 98.894 ms
[INFO ][memory ] 539.052: parallel nursery GC 1849198K->1062334K (5242880K), 97.539 ms
[INFO ][memory ] 541.558: parallel nursery GC 1849134K->1062014K (5242880K), 84.316 ms
[INFO ][memory ] 544.084: parallel nursery GC 1848814K->1061758K (5242880K), 86.974 ms
[INFO ][memory ] 546.661: parallel nursery GC 1848558K->1061503K (5242880K), 92.846 ms
[INFO ][memory ] 549.151: parallel nursery GC 1848303K->1061439K (5242880K), 86.954 ms
[INFO ][memory ] 551.812: parallel nursery GC 1848239K->1061183K (5242880K), 56.842 ms
[INFO ][memory ] 554.319: parallel nursery GC 1847983K->1060927K (5242880K), 77.107 ms
[INFO ][memory ] 556.832: parallel nursery GC 1847727K->1060543K (5242880K), 79.477 ms
[INFO ][memory ] 559.295: parallel nursery GC 1847343K->1060415K (5242880K), 88.774 ms
[INFO ][memory ] 561.766: parallel nursery GC 1847215K->1060223K (5242880K), 96.909 ms
[INFO ][memory ] 564.551: parallel nursery GC 1847023K->1060223K (5242880K), 40.048 ms
[INFO ][memory ] 567.003: parallel nursery GC 1847023K->1059967K (5242880K), 82.474 ms
[INFO ][memory ] 569.438: parallel nursery GC 1846767K->1059583K (5242880K), 85.952 ms
[INFO ][memory ] 572.178: parallel nursery GC 1846383K->1059455K (5242880K), 46.389 ms
[INFO ][memory ] 574.944: parallel nursery GC 1846255K->1059199K (5242880K), 31.293 ms
[INFO ][memory ] 577.722: parallel nursery GC 1845999K->1058879K (5242880K), 33.769 ms
[INFO ][memory ] 580.505: parallel nursery GC 1845679K->1058559K (5242880K), 28.919 ms
[INFO ][memory ] 583.288: parallel nursery GC 1845359K->1058339K (5242880K), 30.513 ms
[INFO ][memory ] 586.054: parallel nursery GC 1845139K->1058143K (5242880K), 32.064 ms
[INFO ][memory ] 588.831: parallel nursery GC 1844943K->1058143K (5242880K), 30.358 ms
[INFO ][memory ] 591.610: parallel nursery GC 1844943K->1058143K (5242880K), 28.526 ms
[INFO ][memory ] 594.390: parallel nursery GC 1844943K->1058065K (5242880K), 31.627 ms
[INFO ][memory ] 597.168: parallel nursery GC 1844865K->1057617K (5242880K), 35.818 ms
[INFO ][memory ] 599.941: parallel nursery GC 1844417K->1057617K (5242880K), 32.745 ms
[INFO ][memory ] 602.700: parallel nursery GC 1844417K->1057617K (5242880K), 35.947 ms
[INFO ][memory ] 605.488: parallel nursery GC 1844417K->1057425K (5242880K), 36.896 ms
[INFO ][memory ] 608.272: parallel nursery GC 1844225K->1056977K (5242880K), 33.302 ms
[INFO ][memory ] 611.056: parallel nursery GC 1843777K->1056721K (5242880K), 34.488 ms
[INFO ][memory ] 613.835: parallel nursery GC 1843521K->1056337K (5242880K), 30.651 ms
etc...


The STRANGE thing is that the gc continues running forever even after the app exited.

What's happening ???

Thank you



View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4059217#4059217

Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4059217