Hello all,
After having run without any problems for at least a week java CPU usage for our Tomcat server went to 99%...
Looking at what the garbage collector was doing I saw the following:
(what follows is just a small extract from the log file...)
<AF[136804]: Allocation Failure. need 536 bytes, 456 ms since last AF>
<AF[136804]: managing allocation failure, action=1 (0/306811880) (16138744/16147992)>
<GC(136804): GC cycle started Tue Jun 15 10:36:18 2004
<GC(136804): freed 267965920 bytes, 87% free (284104664/322959872), in 68 ms>
<GC(136804): mark: 53 ms, sweep: 15 ms, compact: 0 ms>
<GC(136804): refs: soft 0 (age >= 32), weak 0, final 0, phantom 0>
<AF[136804]: completed in 80 ms>
<AF[136805]: Allocation Failure. need 536 bytes, 470 ms since last AF>
<AF[136805]: managing allocation failure, action=1 (0/306811880) (16138744/16147992)>
<GC(136805): GC cycle started Tue Jun 15 10:36:19 2004
<GC(136805): freed 267965640 bytes, 87% free (284104384/322959872), in 55 ms>
<GC(136805): mark: 41 ms, sweep: 14 ms, compact: 0 ms>
<GC(136805): refs: soft 0 (age >= 32), weak 0, final 0, phantom 0>
<AF[136805]: completed in 68 ms>
<AF[136806]: Allocation Failure. need 536 bytes, 454 ms since last AF>
<AF[136806]: managing allocation failure, action=1 (0/306811880) (16138744/16147992)>
<GC(136806): GC cycle started Tue Jun 15 10:36:19 2004
<GC(136806): freed 267965632 bytes, 87% free (284104376/322959872), in 70 ms>
<GC(136806): mark: 56 ms, sweep: 14 ms, compact: 0 ms>
<GC(136806): refs: soft 0 (age >= 32), weak 0, final 0, phantom 0>
<AF[136806]: completed in 72 ms>
<AF[136807]: Allocation Failure. need 536 bytes, 453 ms since last AF>
<AF[136807]: managing allocation failure, action=1 (0/306811880) (16138744/16147992)>
<GC(136807): GC cycle started Tue Jun 15 10:36:20 2004
<GC(136807): freed 267965056 bytes, 87% free (284103800/322959872), in 55 ms>
<GC(136807): mark: 42 ms, sweep: 13 ms, compact: 0 ms>
<GC(136807): refs: soft 0 (age >= 32), weak 0, final 0, phantom 0>
<AF[136807]: completed in 67 ms>
<AF[136808]: Allocation Failure. need 536 bytes, 430 ms since last AF>
<AF[136808]: managing allocation failure, action=1 (0/306811880) (16138744/16147992)>
<GC(136808): GC cycle started Tue Jun 15 10:36:20 2004
<GC(136808): freed 267965224 bytes, 87% free (284103968/322959872), in 53 ms>
<GC(136808): mark: 39 ms, sweep: 14 ms, compact: 0 ms>
<GC(136808): refs: soft 0 (age >= 32), weak 0, final 0, phantom 0>
<AF[136808]: completed in 55 ms>
<AF[136809]: Allocation Failure. need 536 bytes, 508 ms since last AF>
<AF[136809]: managing allocation failure, action=1 (0/306811880) (16138744/16147992)>
<GC(136809): GC cycle started Tue Jun 15 10:36:21 2004
<GC(136809): freed 267965552 bytes, 87% free (284104296/322959872), in 89 ms>
<GC(136809): mark: 74 ms, sweep: 15 ms, compact: 0 ms>
<GC(136809): refs: soft 0 (age >= 32), weak 0, final 0, phantom 0>
<AF[136809]: completed in 101 ms>
This had been going on for hours. An allocation failure of 536 bytes, a sweep that frees 87% and then half a second later, again an allocation failure.
What is it that is going on here?
Krist
-- ------------------------------------------------------------------------ Krist van Besien Bern, Switzerland ------------------------------------------------------------------------
--------------------------------------------------------------------- To unsubscribe, e-mail: [EMAIL PROTECTED] For additional commands, e-mail: [EMAIL PROTECTED]
