Skip to main content

Abnormal GC Pauses - Upwards of 2 minutes.

1 reply [Last post]
jvermast
Offline
Joined: 2009-01-27
Points: 0

We are experiencing an issue in one of our environments with major pauses during Garbage Collection. From the looks of things, our entire application pauses for over 2 minutes at a time, no garbage collection occurs. When the application recovers our DB Connection Pool is maxxed due to the queue of requests that piled up during the pause.

Users will then lose their sessions and be kicked back to the login screen. Here is a GC Log screenshot of what it looks like when the event occurs:
http://i39.tinypic.com/35c4ok3.jpg

System Information:
SunOne Application Server 8.2
Java 1.5. b18
Sun Microsystems sun4v SPARC Enterprise T5120
Memory size: 16256 Megabytes

Here are my GC settings:
-server
-verbose:gc
-Xloggc:../logs/gc.log
-Xms2500m -Xmx2500m
-XX:NewRatio=2
-Xss256k
-XX:+PrintClassHistogram
-XX:+PrintGCDetails
-XX:+PrintGCTimeStamps
-XX:+UseParallelOldGC
-XX:MaxPermSize=256m
-XX:ParallelGCThreads=8
-XX:PermSize=128m

Any assistance would be fantastic. Everyone I have shown this screenshot has said right away that they have no idea what it could be.

Here is a snippet from the GC.log during the offending time:
509276.872: [Full GC [PSYoungGen: 10373K->0K(592384K)] [ParOldGen: 1699326K->767692K(1708032K)] 1709699K->767692K(2300416K) [PSPermGen: 91223K->91130K(131072K)], 6.1604603 secs]
509292.464: [GC [PSYoungGen: 581952K->12140K(587456K)] 1349644K->779833K(2295488K), 0.1599130 secs]
509300.971: [GC [PSYoungGen: 583710K->11179K(572864K)] 1351403K->786245K(2280896K), 0.0934322 secs]
509310.314: [GC [PSYoungGen: 572843K->7121K(560896K)] 1347909K->787298K(2268928K), 0.0441010 secs]
509319.101: [GC [PSYoungGen: 559090K->10751K(643520K)] 1339267K->794536K(2351552K), 0.1103073 secs]
509326.776: [GC [PSYoungGen: 634239K->9748K(623232K)] 1418024K->797424K(2331264K), 0.0543522 secs]
509343.635: [GC [PSYoungGen: 621716K->13509K(614400K)] 1409392K->806282K(2322432K), 0.0550241 secs]
509353.665: [GC [PSYoungGen: 614341K->10576K(602304K)] 1407114K->808861K(2310336K), 0.0835244 secs]
509366.839: [GC [PSYoungGen: 600592K->13033K(592576K)] 1398877K->816454K(2300608K), 0.0598400 secs]
509378.742: [GC [PSYoungGen: 592553K->11473K(580928K)] 1395974K->821658K(2288960K), 0.0528978 secs]
509389.420: [GC [PSYoungGen: 580753K->11008K(570432K)] 1390938K->826129K(2278464K), 0.0521038 secs]
509400.761: [GC [PSYoungGen: 570368K->8176K(561856K)] 1385489K->828348K(2269888K), 0.0438311 secs]
509413.104: [GC [PSYoungGen: 557936K->11845K(613440K)] 1378108K->835653K(2321472K), 0.2489665 secs]
509425.151: [GC [PSYoungGen: 607941K->11198K(655296K)] 1431749K->839581K(2363328K), 0.0810131 secs]
509437.621: [GC [PSYoungGen: 649523K->12336K(638720K)] 1477906K->845709K(2346752K), 0.0776768 secs]
509449.813: [GC [PSYoungGen: 638701K->8761K(642368K)] 1472074K->848329K(2350400K), 0.1306923 secs]
509460.929: [GC [PSYoungGen: 642361K->10034K(631808K)] 1481929K->853312K(2339840K), 0.0453905 secs]
509473.676: [GC [PSYoungGen: 631792K->11769K(626112K)] 1475070K->860041K(2334144K), 0.0556043 secs]
509485.737: [GC [PSYoungGen: 622057K->13321K(612544K)] 1470329K->866177K(2320576K), 0.0999077 secs]
509498.401: [GC [PSYoungGen: 612489K->7102K(595456K)] 1465345K->868482K(2303488K), 0.0490934 secs]
509511.171: [GC [PSYoungGen: 595454K->11528K(589440K)] 1456834K->876216K(2297472K), 0.0846838 secs]
509526.274: [GC [PSYoungGen: 589384K->9050K(580608K)] 1454072K->878557K(2288640K), 0.0511991 secs]
509540.130: [GC [PSYoungGen: 576730K->9424K(567296K)] 1446237K->882639K(2275328K), 0.0476115 secs]
509551.861: [GC [PSYoungGen: 567248K->10293K(560640K)] 1440463K->888569K(2268672K), 0.0547945 secs]
509563.071: [GC [PSYoungGen: 558517K->10159K(549120K)] 1436793K->891307K(2257152K), 0.0605731 secs]
509572.751: [GC [PSYoungGen: 549103K->9660K(540864K)] 1430251K->894610K(2248896K), 0.0493503 secs]
509583.088: [GC [PSYoungGen: 539580K->9057K(530240K)] 1424530K->897860K(2238272K), 0.0502441 secs]
509590.291: [GC [PSYoungGen: 530205K->10864K(523584K)] 1419008K->903085K(2231616K), 0.0506513 secs]
509597.971: [GC [PSYoungGen: 523440K->11412K(515712K)] 1415661K->906866K(2223744K), 0.0620862 secs]
509606.401: [GC [PSYoungGen: 515668K->8440K(507648K)] 1411122K->907956K(2215680K), 0.0487037 secs]
509615.878: [GC [PSYoungGen: 504632K->10109K(578624K)] 1404148K->912843K(2286656K), 0.1345037 secs]
509626.849: [GC [PSYoungGen: 573437K->8016K(564416K)] 1476171K->915150K(2272448K), 0.0532455 secs]
509640.811: [GC [PSYoungGen: 561616K->7608K(551744K)] 1468750K->917868K(2259776K), 0.0472207 secs]
509650.998: [GC [PSYoungGen: 551407K->8927K(544960K)] 1461667K->922819K(2252992K), 0.0422214 secs]
509664.143: [GC [PSYoungGen: 543839K->7794K(533760K)] 1457731K->924639K(2241792K), 0.0572145 secs]
509674.781: [GC [PSYoungGen: 533746K->9208K(526592K)] 1450591K->928904K(2234624K), 0.0466913 secs]
509683.871: [GC [PSYoungGen: 526456K->8118K(516928K)] 1446152K->932108K(2224960K), 0.0571613 secs]
509860.970: [GC [PSYoungGen: 516849K->12093K(512704K)] 1440839K->960865K(2220736K), 0.0954328 secs]
509863.471: [GC [PSYoungGen: 512694K->21597K(541504K)] 1461466K->977603K(2249536K), 0.0813583 secs]
509866.311: [GC [PSYoungGen: 539357K->17908K(608384K)] 1495363K->984697K(2316416K), 0.2047494 secs]
509870.751: [GC [PSYoungGen: 600141K->19986K(608832K)] 1566929K->995489K(2316864K), 0.1210839 secs]
509877.941: [GC [PSYoungGen: 602314K->13006K(711168K)] 1577817K->996228K(2419200K), 0.0910670 secs]
509890.925: [GC [PSYoungGen: 700750K->15126K(714944K)] 1683972K->1005212K(2422976K), 0.1084546 secs]
509904.559: [GC [PSYoungGen: 703638K->15701K(829632K)] 1693724K->1014053K(2537664K), 0.1155248 secs]
509919.184: [GC [PSYoungGen: 819769K->18667K(830528K)] 1818121K->1025179K(2538560K), 0.1337472 secs]
509934.164: [GC [PSYoungGen: 824235K->14430K(831104K)] 1830747K->1030064K(2539136K), 0.0564195 secs]
509952.540: [GC [PSYoungGen: 820062K->15976K(830592K)] 1835696K->1039852K(2538624K), 0.0577576 secs]
509968.143: [GC [PSYoungGen: 822056K->15707K(831552K)] 1845932K->1046961K(2539584K), 0.0523031 secs]
509981.511: [GC [PSYoungGen: 823323K->16966K(807232K)] 1854577K->1057373K(2515264K), 0.0738204 secs]
509996.264: [GC [PSYoungGen: 807174K->13884K(787264K)] 1847581K->1063901K(2495296K), 0.0560018 secs]
510011.155: [GC [PSYoungGen: 787244K->16115K(773248K)] 1837262K->1074467K(2481280K), 0.0766050 secs]
510026.703: [GC [PSYoungGen: 773235K->13197K(755840K)] 1831587K->1078874K(2463872K), 0.0644422 secs]
510040.709: [GC [PSYoungGen: 754546K->14792K(740992K)] 1820223K->1086992K(2449024K), 0.0729400 secs]
510053.640: [GC [PSYoungGen: 740936K->12829K(724608K)] 1813136K->1091802K(2432640K), 0.0573964 secs]
510065.448: [GC [PSYoungGen: 724253K->14910K(712064K)] 1803226K->1100137K(2420096K), 0.0639861 secs]
510077.301: [GC [PSYoungGen: 712062K->13636K(698496K)] 1797289K->1107310K(2406528K), 0.0632824 secs]
510091.046: [GC [PSYoungGen: 696964K->13359K(683328K)] 1790638K->1113524K(2391360K), 0.0634234 secs]
510104.611: [GC [PSYoungGen: 683311K->13861K(671424K)] 1783476K->1122166K(2379456K), 0.1593169 secs]
510116.411: [GC [PSYoungGen: 670885K->14007K(658496K)] 1779190K->1129283K(2366528K), 0.0708938 secs]
510128.871: [GC [PSYoungGen: 658487K->13652K(648512K)] 1773763K->1136559K(2356544K), 0.0569376 secs]
510139.261: [GC [PSYoungGen: 645972K->13208K(633792K)] 1768879K->1143670K(2341824K), 0.0678717 secs]
510148.213: [GC [PSYoungGen: 633752K->12311K(624320K)] 1764214K->1149272K(2332352K), 0.0572956 secs]
510157.501: [GC [PSYoungGen: 621463K->10949K(609088K)] 1758424K->1154163K(2317120K), 0.0623645 secs]
510165.941: [GC [PSYoungGen: 608998K->11244K(599616K)] 1752212K->1160900K(2307648K), 0.0647646 secs]
510174.834: [GC [PSYoungGen: 598572K->10089K(587008K)] 1748228K->1165184K(2295040K), 0.0540640 secs]
510184.921: [GC [PSYoungGen: 586984K->11348K(677504K)] 1742079K->1171820K(2385536K), 0.1570217 secs]
510197.641: [GC [PSYoungGen: 677268K->17294K(670464K)] 1837740K->1183723K(2378496K), 0.0677614 secs]
510208.372: [GC [PSYoungGen: 670414K->11002K(654080K)] 1836843K->1185391K(2362112K), 0.0470629 secs]
510219.818: [GC [PSYoungGen: 651706K->10588K(639296K)] 1826095K->1191322K(2347328K), 0.0637762 secs]
510228.601: [GC [PSYoungGen: 639260K->13472K(634304K)] 1819994K->1199834K(2342336K), 0.0498516 secs]
510237.208: [GC [PSYoungGen: 630496K->12485K(618240K)] 1816858K->1206271K(2326272K), 0.0685876 secs]
510246.705: [GC [PSYoungGen: 618181K->9851K(604544K)] 1811967K->1214265K(2312576K), 0.0506149 secs]
510257.953: [GC [PSYoungGen: 604539K->12043K(596160K)] 1808953K->1222110K(2304192K), 0.0652025 secs]
510267.551: [GC [PSYoungGen: 596107K->12640K(590208K)] 1806174K->1229301K(2298240K), 0.0639278 secs]
510279.948: [GC [PSYoungGen: 586316K->10200K(573888K)] 1802977K->1234265K(2281920K), 0.0675205 secs]
510291.882: [GC [PSYoungGen: 573831K->11329K(567488K)] 1797897K->1241731K(2275520K), 0.0581383 secs]
510302.621: [GC [PSYoungGen: 565249K->12053K(556544K)] 1795651K->1249079K(2264576K), 0.0707236 secs]
510315.751: [GC [PSYoungGen: 556501K->12665K(550016K)] 1793527K->1255194K(2258048K), 0.0590941 secs]
510325.993: [GC [PSYoungGen: 547894K->12397K(538688K)] 1790423K->1261322K(2246720K), 0.0691237 secs]
510336.954: [GC [PSYoungGen: 538669K->12273K(529856K)] 1787594K->1268435K(2237888K), 0.0671255 secs]
510345.388: [GC [PSYoungGen: 529841K->10782K(519936K)] 1786003K->1271957K(2227968K), 0.0598086 secs]
510355.574: [GC [PSYoungGen: 519902K->10202K(512512K)] 1781077K->1276939K(2220544K), 0.0642662 secs]
510366.021: [GC [PSYoungGen: 511066K->14641K(507520K)] 1777803K->1286377K(2215552K), 0.0726206 secs]
510373.973: [GC [PSYoungGen: 507505K->11286K(497408K)] 1779241K->1289000K(2205440K), 0.0914205 secs]
510380.556: [GC [PSYoungGen: 496342K->13965K(491520K)] 1774056K->1297146K(2199552K), 0.0656294 secs]
510386.771: [GC [PSYoungGen: 491465K->10869K(558720K)] 1774647K->1300824K(2266752K), 0.0595080 secs]
510396.325: [GC [PSYoungGen: 557365K->11663K(548928K)] 1847320K->1306258K(2256960K), 0.0611569 secs]
510405.912: [GC [PSYoungGen: 548879K->14868K(546624K)] 1843474K->1314487K(2254656K), 0.0634810 secs]
510417.412: [GC [PSYoungGen: 543060K->10332K(529792K)] 1842679K->1316749K(2237824K), 0.0964732 secs]
510426.616: [GC [PSYoungGen: 529743K->10491K(541952K)] 1836160K->1322474K(2249984K), 0.1870976 secs]
510434.533: [GC [PSYoungGen: 541947K->11191K(550656K)] 1853930K->1327774K(2258688K), 0.0577517 secs]
510441.951: [GC [PSYoungGen: 542647K->11264K(636608K)] 1859230K->1333265K(2344640K), 0.0892275 secs]
510451.444: [GC [PSYoungGen: 628992K->11312K(636480K)] 1950993K->1338398K(2344512K), 0.2149106 secs]
510459.831: [GC [PSYoungGen: 629232K->10888K(735488K)] 1956318K->1343702K(2443520K), 0.0773902 secs]
510472.021: [GC [PSYoungGen: 732280K->16974K(739840K)] 2065094K->1353367K(2447872K), 0.2996933 secs]
510481.798: [GC [PSYoungGen: 738869K->13768K(837312K)] 2075262K->1356536K(2545344K), 0.0933115 secs]
510491.814: [GC [PSYoungGen: 832008K->17582K(835840K)] 2174776K->1365945K(2543872K), 0.1151763 secs]
510502.571: [GC [PSYoungGen: 835822K->15170K(836032K)] 2184185K->1371711K(2544064K), 0.1387490 secs]
510517.804: [GC [PSYoungGen: 831298K->14412K(836096K)] 2187839K->1377064K(2544128K), 0.0664238 secs]
510533.571: [GC [PSYoungGen: 830540K->19637K(825536K)] 2193192K->1392673K(2533568K), 0.1684848 secs]
510546.831: [GC [PSYoungGen: 825525K->24142K(830080K)] 2198561K->1403010K(2538112K), 0.0647374 secs]
510560.762: [GC [PSYoungGen: 830030K->12561K(828288K)] 2208898K->1398347K(2536320K), 0.0539601 secs]
510576.975: [GC [PSYoungGen: 814865K->13011K(798080K)] 2200651K->1405399K(2506112K), 0.0704159 secs]
510590.546: [GC [PSYoungGen: 798035K->16901K(814016K)] 2190423K->1415629K(2522048K), 0.1600477 secs]
510605.501: [GC [PSYoungGen: 806597K->13150K(786048K)] 2205325K->1419028K(2494080K), 0.0802628 secs]
510618.741: [GC [PSYoungGen: 786014K->11854K(768832K)] 2191892K->1422485K(2476864K), 0.0671559 secs]
510632.763: [GC [PSYoungGen: 768462K->11758K(752640K)] 2179093K->1427596K(2460672K), 0.0653882 secs]
510643.683: [GC [PSYoungGen: 752622K->11103K(737664K)] 2168460K->1431208K(2445696K), 0.0587704 secs]
510658.315: [GC [PSYoungGen: 736733K->10416K(721344K)] 2156839K->1435708K(2429376K), 0.1934847 secs]
510676.061: [GC [PSYoungGen: 721313K->10827K(708160K)] 2146605K->1439912K(2416192K), 0.0707037 secs]
510689.083: [GC [PSYoungGen: 707531K->11676K(694656K)] 2136616K->1445411K(2402688K), 0.0651027 secs]
510699.661: [GC [PSYoungGen: 694620K->12384K(744000K)] 2128355K->1451699K(2452032K), 0.1467486 secs]
510711.411: [GC [PSYoungGen: 733728K->11617K(718400K)] 2173043K->1455356K(2426432K), 0.0745740 secs]
510726.176: [GC [PSYoungGen: 718369K->9989K(705152K)] 2162108K->1458546K(2413184K), 0.0581559 secs]
510741.921: [GC [PSYoungGen: 702661K->12187K(691264K)] 2151218K->1464923K(2399296K), 0.0707546 secs]
510758.471: [GC [PSYoungGen: 691227K->10005K(679424K)] 2143963K->1468465K(2387456K), 0.0638043 secs]
510771.702: [GC [PSYoungGen: 675797K->9788K(662784K)] 2134257K->1472278K(2370816K), 0.0710477 secs]
510788.351: [GC [PSYoungGen: 662774K->10035K(654592K)] 2125264K->1475935K(2362624K), 0.0532897 secs]
510806.136: [GC [PSYoungGen: 650549K->9782K(638336K)] 2116449K->1480734K(2346368K), 0.0661425 secs]
510821.525: [GC [PSYoungGen: 638325K->10561K(629632K)] 2109277K->1485612K(2337664K), 0.0597259 secs]
510833.030: [GC [PSYoungGen: 627455K->10260K(615872K)] 2102506K->1489655K(2323904K), 0.0618324 secs]
510846.960: [GC [PSYoungGen: 615828K->8772K(605056K)] 2095223K->1491662K(2313088K), 0.0546284 secs]
510861.978: [GC [PSYoungGen: 603396K->11544K(595584K)] 2086286K->1498670K(2303616K), 0.0792540 secs]
510872.087: [GC [PSYoungGen: 595544K->8007K(585600K)] 2082670K->1500076K(2293632K), 0.0581928 secs]
510886.731: [GC [PSYoungGen: 581639K->8160K(571776K)] 2073708K->1503857K(2279808K), 0.0563475 secs]
510899.943: [GC [PSYoungGen: 571744K->7159K(562816K)] 2067441K->1508236K(2270848K), 0.0649340 secs]
510913.735: [GC [PSYoungGen: 561015K->6183K(550592K)] 2062092K->1509757K(2258624K), 0.0634983 secs]
510926.641: [GC [PSYoungGen: 550567K->6987K(544128K)] 2054141K->1513296K(2252160K), 0.0566977 secs]
510940.687: [GC [PSYoungGen: 541978K->6489K(532736K)] 2048286K->1514971K(2240768K), 0.1662036 secs]
510955.846: [GC [PSYoungGen: 532697K->10149K(530112K)] 2041179K->1520586K(2238144K), 0.0581903 secs]
510967.765: [GC [PSYoungGen: 527653K->9167K(518272K)] 2038090K->1522903K(2226304K), 0.0625675 secs]
510976.930: [GC [PSYoungGen: 518217K->8947K(511936K)] 2032016K->1525782K(2219968K), 0.0495120 secs]
510986.341: [GC [PSYoungGen: 509747K->8722K(501568K)] 2026582K->1528382K(2209600K), 0.0568809 secs]
510996.897: [GC [PSYoungGen: 501522K->7285K(494144K)] 2021182K->1529735K(2202176K), 0.0572571 secs]
511006.577: [GC [PSYoungGen: 492277K->8612K(486080K)] 2014727K->1533653K(2194112K), 0.0905559 secs]
511014.863: [GC [PSYoungGen: 486052K->10025K(482048K)] 2011093K->1538218K(2190080K), 0.0479436 secs]
511023.484: [GC [PSYoungGen: 480105K->10516K(473472K)] 2008298K->1542564K(2181504K), 0.0695160 secs]
511035.040: [GC [PSYoungGen: 473428K->6663K(464832K)] 2005476K->1542018K(2172864K), 0.0501387 secs]
511048.701: [GC [PSYoungGen: 462599K->6299K(455424K)] 1997954K->1545449K(2163456K), 0.0571148 secs]
511057.981: [GC [PSYoungGen: 455387K->9276K(531648K)] 1994537K->1550524K(2239680K), 0.1544669 secs]
511065.857: [GC [PSYoungGen: 527676K->8236K(559872K)] 2068924K->1552063K(2267904K), 0.0552028 secs]
511074.536: [GC [PSYoungGen: 554540K->8948K(613888K)] 2098367K->1554977K(2321920K), 0.0752543 secs]
511089.211: [GC [PSYoungGen: 609652K->7545K(597440K)] 2155681K->1556427K(2305472K), 0.0740674 secs]
511103.978: [GC [PSYoungGen: 597433K->7113K(589632K)] 2146315K->1558941K(2297664K), 0.0517465 secs]
511119.592: [GC [PSYoungGen: 586466K->7688K(576960K)] 2138295K->1562164K(2284992K), 0.0558486 secs]
511303.241: [GC [PSYoungGen: 576887K->9009K(568320K)] 2131363K->1592886K(2276352K), 0.1095441 secs]
511305.521: [GC [PSYoungGen: 568305K->22910K(587392K)] 2152182K->1613512K(2295424K), 0.0925681 secs]
511308.921: [GC [PSYoungGen: 587390K->18385K(683776K)] 2177992K->1619484K(2391808K), 0.1293295 secs]
511315.824: [GC [PSYoungGen: 673583K->15426K(684160K)] 2274683K->1624731K(2392192K), 0.1346940 secs]
511326.562: [GC [PSYoungGen: 670658K->15965K(793984K)] 2279963K->1632977K(2502016K), 0.1038475 secs]
511339.289: [GC [PSYoungGen: 786013K->22713K(800000K)] 2403025K->1648160K(2508032K), 0.1585476 secs]
511350.963: [GC [PSYoungGen: 795321K->15029K(828224K)] 2420768K->1654075K(2536256K), 0.2174071 secs]
511363.381: [GC [PSYoungGen: 813977K->15898K(826816K)] 2453023K->1662507K(2534848K), 0.0696002 secs]
511379.862: [GC [PSYoungGen: 814874K->12374K(828224K)] 2461483K->1666640K(2536256K), 0.0597691 secs]
511397.161: [GC [PSYoungGen: 813718K->13342K(829184K)] 2467984K->1674586K(2537216K), 0.1525267 secs]
511410.451: [GC [PSYoungGen: 816342K->16645K(829888K)] 2477586K->1684661K(2537920K), 0.1791605 secs]
511427.019: [GC [PSYoungGen: 821253K->14619K(830784K)] 2489269K->1691252K(2538816K), 0.0718644 secs]
511440.807: [GC [PSYoungGen: 820443K->14823K(831104K)] 2497076K->1698701K(2539136K), 0.0606193 secs]
511459.916: [GC [PSYoungGen: 821927K->15390K(805120K)] 2505805K->1708391K(2513152K), 0.0745878 secs]
511473.978: [GC [PSYoungGen: 805086K->11192K(784064K)] 2498087K->1713995K(2492096K), 0.0724302 secs]
511474.051: [Full GC [PSYoungGen: 11192K->0K(784064K)] [ParOldGen: 1702802K->522673K(1568768K)] 1713995K->522673K(2352832K) [PSPermGen: 91247K->91165K(131072K)], 6.8081279 secs]
511504.390: [GC [PSYoungGen: 772864K->14539K(770944K)] 1295537K->537212K(2339712K), 0.0482677 secs]
511521.219: [GC [PSYoungGen: 770891K->13814K(755456K)] 1293564K->544603K(2324224K), 0.0903774 secs]
511543.271: [GC [PSYoungGen: 754148K->13436K(738368K)] 1284937K->550864K(2307136K), 0.0610266 secs]
511564.191: [GC [PSYoungGen: 738364K->14504K(725696K)] 1275792K->559234K(2294464K), 0.0543849 secs]
511582.700: [GC [PSYoungGen: 724456K->13669K(709184K)] 1269186K->565565K(2277952K), 0.0621953 secs]
511600.421: [GC [PSYoungGen: 709106K->13388K(698880K)] 1261002K->573036K(2267648K), 0.0423250 secs]
511618.121: [GC [PSYoungGen: 694860K->12193K(680128K)] 1254508K->579762K(2248896K), 0.0642176 secs]
511629.993: [GC [PSYoungGen: 680097K->13115K(667904K)] 1247666K->587541K(2236672K), 0.0468732 secs]
511645.313: [GC [PSYoungGen: 667899K->10405K(652544K)] 1242325K->591951K(2221312K), 0.0819674 secs]
511657.435: [GC [PSYoungGen: 652517K->12944K(643200K)] 1234063K->600412K(2211968K), 0.0529254 secs]
511672.730: [GC [PSYoungGen: 642768K->12143K(630080K)] 1230236K->604671K(2198848K), 0.0811480 secs]
511685.541: [GC [PSYoungGen: 630063K->12829K(621184K)] 1222591K->610445K(2189952K), 0.0447609 secs]
511697.949: [GC [PSYoungGen: 619165K->13493K(608640K)] 1216781K->618574K(2177408K), 0.0621923 secs]
511709.556: [GC [PSYoungGen: 608629K->10956K(596096K)] 1213710K->622894K(2164864K), 0.0481202 secs]
511721.524: [GC [PSYoungGen: 595212K->10900K(584640K)] 1207150K->628461K(2153408K), 0.0561256 secs]
511732.654: [GC [PSYoungGen: 584596K->14120K(579392K)] 1202157K->637571K(2148160K), 0.0599327 secs]
511744.746: [GC [PSYoungGen: 577576K->11727K(565312K)] 1201027K->640476K(2134080K), 0.0514709 secs]
511753.659: [GC [PSYoungGen: 565158K->11067K(558976K)] 1193908K->646206K(2127744K), 0.0398130 secs]
511765.121: [GC [PSYoungGen: 554939K->13628K(548160K)] 1190078K->653997K(2116928K), 0.0580402 secs]
511776.949: [GC [PSYoungGen: 548156K->12210K(537664K)] 1188525K->660309K(2106432K), 0.0484139 secs]
511785.051: [GC [PSYoungGen: 537650K->11802K(528448K)] 1185749K->666246K(2097216K), 0.0565797 secs]
511795.934: [GC [PSYoungGen: 528410K->8868K(520128K)] 1182854K->667911K(2088896K), 0.0450502 secs]
511809.384: [GC [PSYoungGen: 516900K->11774K(511424K)] 1175943K->674795K(2080192K), 0.1386438 secs]
511820.245: [GC [PSYoungGen: 511422K->8763K(503296K)] 1174443K->677646K(2072064K), 0.0483928 secs]
511831.021: [GC [PSYoungGen: 500283K->10377K(494016K)] 1169166K->683992K(2062784K), 0.0461233 secs]
511847.104: [GC [PSYoungGen: 493961K->8505K(487360K)] 1167576K->687279K(2056128K), 0.0438125 secs]
511858.193: [GC [PSYoungGen: 484409K->8455K(476928K)] 1163183K->691956K(2045696K), 0.0552129 secs]
511868.667: [GC [PSYoungGen: 476843K->10587K(472320K)] 1160344K->698097K(2041088K), 0.0525783 secs]
511880.389: [GC [PSYoungGen: 471701K->7989K(462016K)] 1159212K->699602K(2030784K), 0.0418740 secs]
511895.363: [GC [PSYoungGen: 461945K->11948K(461696K)] 1153558K->707041K(2030464K), 0.0513214 secs]
511904.646: [GC [PSYoungGen: 459052K->9592K(449984K)] 1154145K->710195K(2018752K), 0.1711122 secs]
511911.841: [GC [PSYoungGen: 449976K->12706K(448384K)] 1150579K->718698K(2017152K), 0.0356504 secs]
511917.920: [GC [PSYoungGen: 446498K->9763K(437184K)] 1152490K->720651K(2005952K), 0.0513618 secs]
511925.284: [GC [PSYoungGen: 437155K->9167K(502400K)] 1148043K->724176K(2071168K), 0.1102343 secs]
511933.632: [GC [PSYoungGen: 502287K->11707K(560512K)] 1217296K->730630K(2129280K), 0.0492612 secs]
511941.340: [GC [PSYoungGen: 555195K->9592K(585728K)] 1274118K->734858K(2154496K), 0.0834332 secs]
511954.280: [GC [PSYoungGen: 578594K->9491K(568448K)] 1303860K->738802K(2137216K), 0.0550830 secs]
511962.439: [GC [PSYoungGen: 568403K->10369K(560384K)] 1297714K->743608K(2129152K), 0.0449363 secs]
511971.537: [GC [PSYoungGen: 559437K->10500K(574208K)] 1292676K->748382K(2142976K), 0.1290482 secs]
511981.212: [GC [PSYoungGen: 568839K->11803K(652544K)] 1306720K->753858K(2221312K), 0.0782279 secs]
511995.413: [GC [PSYoungGen: 649179K->9788K(635008K)] 1391234K->756414K(2203776K), 0.0652289 secs]
512010.178: [GC [PSYoungGen: 635004K->8182K(621632K)] 1381630K->763155K(2190400K), 0.0477283 secs]
512024.690: [GC [PSYoungGen: 621622K->10019K(612096K)] 1376595K->768683K(2180864K), 0.0439202 secs]
512037.377: [GC [PSYoungGen: 612067K->9635K(603136K)] 1370731K->772926K(2171904K), 0.1268361 secs]
512047.521: [GC [PSYoungGen: 600611K->10265K(590528K)] 1363902K->778610K(2159296K), 0.0795824 secs]
512057.921: [GC [PSYoungGen: 590466K->13182K(583296K)] 1358811K->785902K(2152064K), 0.0484122 secs]
512070.230: [GC [PSYoungGen: 582974K->8717K(568448K)] 1355694K->787460K(2137216K), 0.1350271 secs]
512083.510: [GC [PSYoungGen: 568397K->10933K(560832K)] 1347140K->793652K(2129600K), 0.0405162 secs]
512094.569: [GC [PSYoungGen: 560821K->11289K(551680K)] 1343540K->799374K(2120448K), 0.0472555 secs]
512105.821: [GC [PSYoungGen: 551641K->9458K(541376K)] 1339726K->802806K(2110144K), 0.0476129 secs]
512114.531: [GC [PSYoungGen: 540530K->10471K(532544K)] 1333878K->807906K(2101312K), 0.0497979 secs]
512126.524: [GC [PSYoungGen: 532519K->8971K(522880K)] 1329954K->810774K(2091648K), 0.0512158 secs]
512144.090: [GC [PSYoungGen: 522251K->9299K(514112K)] 1324054K->815293K(2082880K), 0.0580635 secs]
512153.891: [GC [PSYoungGen: 514067K->11857K(510336K)] 1320061K->822127K(2079104K), 0.0492804 secs]
512162.950: [GC [PSYoungGen: 508353K->11226K(499712K)] 1318679K->827543K(2068480K), 0.0623809 secs]
512170.837: [GC [PSYoungGen: 499674K->7295K(487936K)] 1315991K->829949K(2056704K), 0.0428832 secs]
512179.721: [GC [PSYoungGen: 487935K->10666K(483712K)] 1310589K->836795K(2052480K), 0.0542473 secs]
512187.891: [GC [PSYoungGen: 483690K->10541K(476864K)] 1309819K->840463K(2045632K), 0.0432782 secs]
512197.021: [GC [PSYoungGen: 476141K->11306K(469696K)] 1306063K->845642K(2038464K), 0.0622016 secs]
512205.231: [GC [PSYoungGen: 469674K->8335K(461760K)] 1304010K->847285K(2030528K), 0.0399218 secs]
512214.056: [GC [PSYoungGen: 459663K->8933K(453440K)] 1298613K->851419K(2022208K), 0.0528902 secs]
512223.301: [GC [PSYoungGen: 453413K->11117K(450240K)] 1295899K->857947K(2019008K), 0.0492292 secs]
512230.431: [GC [PSYoungGen: 448941K->7110K(438464K)] 1295771K->858253K(2007232K), 0.0484221 secs]
512241.667: [GC [PSYoungGen: 438406K->9599K(434560K)] 1289549K->864722K(2003328K), 0.0398398 secs]
512249.713: [GC [PSYoungGen: 434559K->7978K(426752K)] 1289682K->867823K(1995520K), 0.0547488 secs]
512259.549: [GC [PSYoungGen: 426730K->9875K(423424K)] 1286575K->872595K(1992192K), 0.0452487 secs]
512507.329: [GC [PSYoungGen: 422567K->15039K(421888K)] 1285287K->900628K(1990656K), 0.1100179 secs]
512509.341: [GC [PSYoungGen: 421887K->6835K(482560K)] 1307476K->913748K(2051328K), 0.0900505 secs]
512511.931: [GC [PSYoungGen: 482477K->20052K(507392K)] 1389390K->931357K(2076160K), 0.0817880 secs]
512516.321: [GC [PSYoungGen: 498260K->17677K(593344K)] 1409565K->938485K(2162112K), 0.1497001 secs]
512522.477: [GC [PSYoungGen: 581214K->17200K(592832K)] 1502021K->946181K(2161600K), 0.1155737 secs]
512532.153: [GC [PSYoungGen: 580784K->19173K(687936K)] 1509765K->957055K(2256704K), 0.1525402 secs]
512546.221: [GC [PSYoungGen: 682661K->22164K(692288K)] 1620543K->971203K(2261056K), 0.1490072 secs]
512556.819: [GC [PSYoungGen: 685844K->17791K(809792K)] 1634883K->980580K(2378560K), 0.2169564 secs]
512567.931: [GC [PSYoungGen: 797935K->16445K(810048K)] 1760724K->988565K(2378816K), 0.1203993 secs]
512579.118: [GC [PSYoungGen: 797693K->17489K(827264K)] 1769813K->998044K(2396032K), 0.1032074 secs]
512591.414: [GC [PSYoungGen: 817138K->19511K(828480K)] 1797693K->1008518K(2397248K), 0.0911204 secs]
512607.556: [GC [PSYoungGen: 821175K->15732K(829248K)] 1810182K->1013392K(2398016K), 0.0539751 secs]
512619.710: [GC [PSYoungGen: 818100K->14900K(829184K)] 1815760K->1020954K(2397952K), 0.0626734 secs]
512633.046: [GC [PSYoungGen: 817972K->17313K(829952K)] 1824026K->1030463K(2398720K), 0.0544884 secs]
512646.631: [GC [PSYoungGen: 821729K->15621K(802496K)] 1834879K->1038605K(2371264K), 0.0694685 secs]
512658.531: [GC [PSYoungGen: 802393K->14200K(784000K)] 1825377K->1048684K(2352768K), 0.0701808 secs]
512674.631: [GC [PSYoungGen: 783992K->13929K(767296K)] 1818476K->1055338K(2336064K), 0.0738797 secs]
512686.977: [GC [PSYoungGen: 767273K->15691K(755840K)] 1808682K->1063736K(2324608K), 0.0711308 secs]
512697.767: [GC [PSYoungGen: 753163K->14792K(789248K)] 1801208K->1070417K(2358016K), 0.1906048 secs]
512710.152: [GC [PSYoungGen: 778184K->13708K(830208K)] 1833809K->1077290K(2398976K), 0.0964974 secs]
512721.636: [GC [PSYoungGen: 818572K->16546K(830720K)] 1882154K->1086167K(2399488K), 0.0772341 secs]
512733.711: [GC [PSYoungGen: 822242K->16812K(808128K)] 1891863K->1093251K(2376896K), 0.0568232 secs]
512748.358: [GC [PSYoungGen: 804844K->15672K(786688K)] 1881283K->1100411K(2355456K), 0.0673529 secs]
512759.424: [GC [PSYoungGen: 786680K->15038K(769600K)] 1871419K->1108919K(2338368K), 0.0664875 secs]
512769.261: [GC [PSYoungGen: 769598K->15757K(754432K)] 1863479K->1117375K(2323200K), 0.0704995 secs]
512779.121: [GC [PSYoungGen: 754381K->14240K(798912K)] 1855999K->1121820K(2367680K), 0.1306573 secs]
512790.736: [GC [PSYoungGen: 790816K->15835K(775808K)] 1898396K->1130098K(2344576K), 0.0664574 secs]
512803.415: [GC [PSYoungGen: 775771K->17494K(762368K)] 1890034K->1139709K(2331136K), 0.0651577 secs]
512816.504: [GC [PSYoungGen: 761302K->15801K(744064K)] 1883517K->1147578K(2312832K), 0.0815989 secs]
512830.028: [GC [PSYoungGen: 744006K->14204K(727744K)] 1875783K->1152499K(2296512K), 0.1358406 secs]
512843.161: [GC [PSYoungGen: 727420K->14414K(713088K)] 1865715K->1159835K(2281856K), 0.0697726 secs]
512854.879: [GC [PSYoungGen: 713038K->14197K(699136K)] 1858459K->1166710K(2267904K), 0.0575580 secs]
512869.819: [GC [PSYoungGen: 698710K->11611K(682560K)] 1851222K->1171492K(2251328K), 0.0622481 secs]
512883.324: [GC [PSYoungGen: 682523K->10144K(669888K)] 1842404K->1176319K(2238656K), 0.0538970 secs]
512896.562: [GC [PSYoungGen: 667872K->13463K(658432K)] 1834047K->1184833K(2227200K), 0.0653256 secs]
512905.721: [GC [PSYoungGen: 658391K->15003K(650496K)] 1829761K->1193146K(2219264K), 0.0630582 secs]
512914.561: [GC [PSYoungGen: 647515K->13301K(633792K)] 1825658K->1197692K(2202560K), 0.0754023 secs]
512923.143: [GC [PSYoungGen: 633781K->13051K(621888K)] 1818172K->1204512K(2190656K), 0.0575841 secs]
512932.311: [GC [PSYoungGen: 621667K->13720K(611328K)] 1813128K->1211779K(2180096K), 0.0705881 secs]
512944.868: [GC [PSYoungGen: 611288K->14744K(605376K)] 1809347K->1218727K(2174144K), 0.0626037 secs]
512951.390: [GC [PSYoungGen: 601368K->15302K(591360K)] 1805351K->1225978K(2160128K), 0.0748581 secs]
512958.941: [GC [PSYoungGen: 591107K->10879K(678464K)] 1801784K->1230434K(2247232K), 0.1897493 secs]
512971.011: [GC [PSYoungGen: 678463K->16653K(689536K)] 1898018K->1241356K(2258304K), 0.0639797 secs]
512983.032: [GC [PSYoungGen: 684237K->12358K(794112K)] 1908940K->1245176K(2362880K), 0.0983729 secs]
512998.121: [GC [PSYoungGen: 784765K->13211K(797504K)] 2017583K->1251995K(2366272K), 0.1114565 secs]
513012.246: [GC [PSYoungGen: 789019K->13657K(834368K)] 2027803K->1258081K(2403136K), 0.1163614 secs]
513028.151: [GC [PSYoungGen: 826649K->15456K(834688K)] 2071073K->1265656K(2403456K), 0.1393498 secs]
513040.946: [GC [PSYoungGen: 829088K->15559K(835008K)] 2079288K->1273481K(2403776K), 0.0644071 secs]
513053.135: [GC [PSYoungGen: 829955K->19176K(834432K)] 2087877K->1283952K(2403200K), 0.1091589 secs]
513067.402: [GC [PSYoungGen: 834408K->15785K(814400K)] 2099184K->1288064K(2383168K), 0.0592029 secs]
513077.315: [GC [PSYoungGen: 813033K->15636K(795584K)] 2085312K->1295909K(2364352K), 0.0799257 secs]
513089.602: [GC [PSYoungGen: 795540K->9532K(812864K)] 2075813K->1303537K(2381632K), 0.1507003 secs]
513102.992: [GC [PSYoungGen: 812860K->14261K(826048K)] 2106865K->1313590K(2394816K), 0.1580178 secs]
513114.911: [GC [PSYoungGen: 817589K->16788K(833344K)] 2116918K->1323010K(2402112K), 0.1172727 secs]
513128.255: [GC [PSYoungGen: 827732K->16330K(833664K)] 2133954K->1328592K(2402432K), 0.0591656 secs]
513142.921: [GC [PSYoungGen: 827978K->15490K(834048K)] 2140240K->1336105K(2402816K), 0.0938233 secs]
513156.200: [GC [PSYoungGen: 828020K->17714K(812352K)] 2148635K->1343984K(2381120K), 0.0774904 secs]
513172.332: [GC [PSYoungGen: 812338K->8511K(785856K)] 2138608K->1345055K(2354624K), 0.1389360 secs]
513187.651: [GC [PSYoungGen: 785790K->15470K(776128K)] 2122334K->1356097K(2344896K), 0.0608059 secs]
513201.211: [GC [PSYoungGen: 776110K->19002K(764096K)] 2116737K->1366448K(2332864K), 0.0692811 secs]
513213.639: [GC [PSYoungGen: 763514K->21748K(750656K)] 2110960K->1380317K(2319424K), 0.0796895 secs]
513232.063: [GC [PSYoungGen: 750629K->5372K(719168K)] 2109198K->1378755K(2287936K), 0.0575621 secs]
513245.385: [GC [PSYoungGen: 719164K->14263K(713472K)] 2092547K->1390153K(2282240K), 0.1473824 secs]
513258.491: [GC [PSYoungGen: 713463K->12243K(699776K)] 2089353K->1394294K(2268544K), 0.0694370 secs]
513272.484: [GC [PSYoungGen: 697363K->12145K(683648K)] 2079414K->1398933K(2252416K), 0.0673409 secs]
513284.457: [GC [PSYoungGen: 683630K->13438K(672960K)] 2070418K->1405377K(2241728K), 0.0644003 secs]
513296.182: [GC [PSYoungGen: 671678K->12154K(657600K)] 2063617K->1409472K(2226368K), 0.0687116 secs]
513307.806: [GC [PSYoungGen: 657594K->12848K(647104K)] 2054912K->1415489K(2215872K), 0.0674629 secs]
513318.571: [GC [PSYoungGen: 645872K->10863K(631872K)] 2048513K->1418751K(2200640K), 0.0641233 secs]
513329.387: [GC [PSYoungGen: 631855K->13364K(622784K)] 2039743K->1426122K(2191552K), 0.0636672 secs]
513338.690: [GC [PSYoungGen: 622648K->14233K(699968K)] 2035406K->1431422K(2268736K), 0.1567836 secs]
513353.991: [GC [PSYoungGen: 689177K->14909K(677632K)] 2106366K->1436622K(2246400K), 0.0946249 secs]
513367.968: [GC [PSYoungGen: 676541K->23284K(722176K)] 2098254K->1450568K(2290944K), 0.1289835 secs]
513383.481: [GC [PSYoungGen: 722164K->11598K(700096K)] 2149448K->1443954K(2268864K), 0.0810109 secs]
513399.057: [GC [PSYoungGen: 696398K->13464K(684672K)] 2128754K->1450675K(2253440K), 0.1040880 secs]
513410.561: [GC [PSYoungGen: 684632K->13566K(672256K)] 2121843K->1455341K(2241024K), 0.0993347 secs]

Message was edited by: jvermast

Reply viewing options

Select your preferred way to display the comments and click "Save settings" to activate your changes.
peter__lawrey
Offline
Joined: 2005-11-01
Points: 0

The longest GC pause time is 6 seconds. Any pause much longer than that is not due to a GC. Instead it is more likely due to a bug in your code or a connection timeout.

I suggest you get a thread dump of the server which it has a long pause and look to see what the program is doing.