You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@spamassassin.apache.org by SRH-Lists <gi...@333tech.com> on 2004/04/28 18:07:17 UTC

Run Away SA with > 100 open file handles

I posted earlier about needing to up the allowed open files to prevent
SA (or amavis) from entering a race condition.  Some further poking
around led me to this oddity.  I am not sure if this is the cause of the
problem (too many open files) or perhaps hitting the open file limit
causes SA to do this.

Here are some interesting fstat results:

Normal SA child (actually amavisd-new, but debugging shows that it is
the SA part of it that is dieing):
filter   perl5.8.3  16845   wd /var     1792920 drwxr-x---   r     5632
filter   perl5.8.3  16845    0 /        227797 crw-rw-rw-   r     null
filter   perl5.8.3  16845    1 /        227797 crw-rw-rw-   w     null
filter   perl5.8.3  16845    2 /        227797 crw-rw-rw-   w     null
filter   perl5.8.3  16845    3* unix dgram 0xd1098640 <-> 0xd0f78fc0
filter   perl5.8.3  16845    4* internet stream tcp 0xd109c008
127.0.0.1:10024
filter   perl5.8.3  16845    5 /var     1916792 -rw-r-----  rw     1005
filter   perl5.8.3  16845    6 /         62495 -rw-r-----   w        0
filter   perl5.8.3  16845    8* unix stream 0xd10988c0 <-> 0xd10981c0
filter   perl5.8.3  16845    9 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  16845   10 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  16845   11 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  16845   12 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  16845   13 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  16845   14 /         62488 -rw-------   w 20065893
filter   perl5.8.3  16845   15 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  16845   16 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  16845   17 /var     1793024 -rw-------   r  5144576

Not too bad.  The 10878976 is bayes_toks and the 5144576 is bayes_seen
(why are they each open 4 times?)

Here is the same output from a amavisd child that has "run away":
---cut for brevity---
filter   perl5.8.3  15050    9 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  15050   10 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  15050   11 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  15050   12 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  15050   13 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  15050   14 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  15050   15 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  15050   16 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  15050   17 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  15050   18 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  15050   19 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  15050   20 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  15050   21 /var     1792951 -rw-------   r 10878976
filter   perl5.8.3  15050   22 /var     1793024 -rw-------   r  5144576
filter   perl5.8.3  15050   23 /var     1792951 -rw-------   r 10878976
---repeat alot more---

54 handles for each bayes_toks and bayes_seen.  A total of around 120
total files for this one pid.

wierd, huh?

-steve