Re: [AMaViS-user] amavis-milter: (mlfi_eom) smfi_addheader failed

SourceForge Headquarters 1320 Columbia Street Suite 310 San Diego, CA 92101 +1 (858) 422-6466

Hello,

Thanks a lot for your answer.

The problem happens once or twice a day on the previously mentioned hardware 
platform. We have another mail gateway (single PproIII 1.4GHz + 1Go RAM) with 
the same software config and no amavis-milter crash during the last week.

Le mercredi 14 Avril 2004 18:18, Mark Martinec a écrit :
> Philippe,
>
> | With amavisd-new-20030616-p9+sendmail-milter on a Debian/testing,
> | amavisd gets locked after the error message:
> |   amavis-milter[22124]: (mlfi_eom) smfi_addheader failed
> |   amavis-milter[22124]: (mlfi_cleanup) unlink failed: Bad address
> |   sm-mta[7537]: i3ANoAiS007537: Milter (milter-amavis): init failed to
> | open sm-mta[7537]: i3ANoAiS007537: Milter: initialization failed, temp
> | failing commands
> | I tried to tune spamassassin timeout and features without success.
> | The hardware platform is a dual PII 200Mhz + 512Mo RAM.
> | This error happens during day or night independently of the gateway load.
>
> Try to increase amavis-milter verbosity by adding flags -vvv

I just did it ...

> to it command line, and also, show log timestamps. It may tell
> whether the problem is connected to some large delays in processing
> or slow mail reception, or not.
>
> You may try this version:
>   http://www.ijs.si/software/amavisd/amavis-milter.c
> it adds some more tests, but is otherwise similar to the one in P9.
>
>   Mark
Here is a copy of last night crash report. This one seems to be linked with 
SA_TIMEOUT instead of smfi_addheader.

Security Violations
=-=-=-=-=-=-=-=-=-=
Apr 14 03:39:05 atlas named[14833]: client 212.137.64.13#42494: query (cache) 
denied
Apr 14 03:39:14 atlas named[17461]: client 212.137.64.13#42494: query (cache) 
denied
Apr 14 03:39:14 atlas named[14833]: client 212.137.65.14#32782: query (cache) 
denied
Apr 14 03:39:14 atlas named[17461]: client 194.6.79.162#53: query (cache) 
denied
Apr 14 03:39:14 atlas named[14833]: client 194.6.79.163#53: query (cache) 
denied
Apr 14 03:39:14 atlas named[17461]: client 212.137.45.104#34058: query (cache) 
denied
Apr 14 03:39:26 atlas sm-mta[19897]: i3E1TOf3019897: Milter: data, reject=451 
4.7.1 Please try again later
Apr 14 03:40:36 atlas amavis-milter[899]: (mlfi_cleanup) unlink failed: Bad 
address
Apr 14 03:45:24 atlas named[17461]: client 128.2.220.152#31111: query (cache) 
denied
Apr 14 03:56:39 atlas sm-mta[9037]: i3E1kcCs009037: Milter (milter-amavis): 
init failed to open
Apr 14 03:56:39 atlas sm-mta[9037]: i3E1kcCs009037: Milter: initialization 
failed, temp failing commands
Apr 14 03:57:04 atlas sm-mta[25557]: i3E1l4r6025557: Milter (milter-amavis): 
init failed to open
Apr 14 03:57:04 atlas sm-mta[25557]: i3E1l4r6025557: Milter: initialization 
failed, temp failing commands
Apr 14 03:58:44 atlas sm-mta[2070]: i3E1mixX002070: Milter (milter-amavis): 
init failed to open
Apr 14 03:58:44 atlas sm-mta[2070]: i3E1mixX002070: Milter: initialization 
failed, temp failing commands
Apr 14 03:58:44 atlas sm-mta[2070]: i3E1mixX002070: saphir.info.iut-tlse3.fr 
[194.214.192.2] did not issue MAIL/EXPN/VRFY/ETRN during connection to MTA
Apr 14 03:59:57 atlas sm-mta[31527]: i3E1nvWO031527: Milter (milter-amavis): 
init failed to open
Apr 14 03:59:57 atlas sm-mta[31527]: i3E1nvWO031527: Milter: initialization 
failed, temp failing commands
Apr 14 03:59:57 atlas sm-mta[31527]: i3E1nvWO031527: saphir.info.iut-tlse3.fr 
[194.214.192.2] did not issue MAIL/EXPN/VRFY/ETRN during connection to MTA
Apr 14 04:01:11 atlas sm-mta[18537]: i3E1pBXd018537: Milter (milter-amavis): 
init failed to open
Apr 14 04:01:11 atlas sm-mta[18537]: i3E1pBXd018537: Milter: initialization 
failed, temp failing commands
Apr 14 04:01:11 atlas sm-mta[18537]: i3E1pBXd018537: hermes.medcost.fr 
[212.155.174.4] did not issue MAIL/EXPN/VRFY/ETRN during connection to MTA

System Events
=-=-=-=-=-=-=
Apr 14 03:12:18 atlas ntpd[28455]: sendto(193.170.141.4): Bad file descriptor
Apr 14 03:29:21 atlas ntpd[28455]: sendto(193.170.141.4): Bad file descriptor
avr 14 03:29:56 atlas amavis[7134]: (i3E1TOf3019897) SA TIMED OUT, backtrace: 
at /usr/share/perl5/Mail/SpamAssassin/BayesStore.pm line 573\n\teval {...} 
called at /usr/share/perl5/Mail/SpamAssassin/BayesStore.pm line 
573\n\tMail::SpamAssassin::BayesStore::expire_old_tokens_trapped('Mail::SpamAssassin::BayesStore=HASH(0x98b4590)','undef') 
called at /usr/share/perl5/Mail/SpamAssassin/BayesStore.pm line 463\n\teval 
{...} called at /usr/share/perl5/Mail/SpamAssassin/BayesStore.pm line 
460\n\tMail::SpamAssassin::BayesStore::expire_old_tokens('Mail::SpamAssassin::BayesStore=HASH(0x98b4590)','undef') 
called at /usr/share/perl5/Mail/SpamAssassin/Bayes.pm line 
861\n\tMail::SpamAssassin::Bayes::sync('Mail::SpamAssassin::Bayes=HASH(0x983c58c)',1,1) 
called at /usr/share/perl5/Mail/SpamAssassin/Bayes.pm line 
1103\n\tMail::SpamAssassin::Bayes::opportunistic_calls('Mail::SpamAssassin::Bayes=HASH(0x983c58c)') 
called at /usr/share/perl5/Mail/SpamAssassin/Bayes.pm l...
avr 14 03:35:24 atlas amavis[16911]: (i3E1ZHFs009868) Sophie av-scanner 
FAILED: Too many retries to talk to /var/run/amavis/sophie.sock (Can't 
connect to UNIX socket /var/run/amavis/sophie.sock: Aucun fichier ou 
r\351pertoire de ce type) at (eval 37) line 180.
avr 14 03:37:40 atlas amavis[16911]: (i3E1bc33000204) Sophie: Can't send to 
socket /var/run/amavis/sophie.sock: Noeud final de transport n'est pas 
connect\351, retrying (1)
Apr 14 03:39:26 atlas sm-mta[19897]: i3E1TOf3019897: Milter (milter-amavis): 
timeout before data read
Apr 14 03:39:26 atlas sm-mta[19897]: i3E1TOf3019897: Milter (milter-amavis): 
to error state
Apr 14 03:46:25 atlas ntpd[28455]: sendto(193.170.141.4): Bad file descriptor
Apr 14 03:49:31 atlas clamd[9792]: SelfCheck: Database status OK.
Apr 14 03:56:39 atlas sm-mta[9037]: i3E1kcCs009037: Milter (milter-amavis): 
timeout before data read
Apr 14 03:56:39 atlas sm-mta[9037]: i3E1kcCs009037: Milter (milter-amavis): to 
error state
Apr 14 03:56:39 atlas sm-mta[9037]: i3E1kcCs009037: Milter (milter-amavis): to 
error state
Apr 14 03:57:04 atlas sm-mta[25557]: i3E1l4r6025557: Milter (milter-amavis): 
timeout before data read
Apr 14 03:57:04 atlas sm-mta[25557]: i3E1l4r6025557: Milter (milter-amavis): 
to error state
Apr 14 03:57:04 atlas sm-mta[25557]: i3E1l4r6025557: Milter (milter-amavis): 
to error state
Apr 14 03:58:44 atlas sm-mta[2070]: i3E1mixX002070: Milter (milter-amavis): 
timeout before data read
Apr 14 03:58:44 atlas sm-mta[2070]: i3E1mixX002070: Milter (milter-amavis): to 
error state
Apr 14 03:58:44 atlas sm-mta[2070]: i3E1mixX002070: Milter (milter-amavis): to 
error state
Apr 14 03:59:57 atlas sm-mta[31527]: i3E1nvWO031527: Milter (milter-amavis): 
timeout before data read
Apr 14 03:59:57 atlas sm-mta[31527]: i3E1nvWO031527: Milter (milter-amavis): 
to error state
Apr 14 03:59:57 atlas sm-mta[31527]: i3E1nvWO031527: Milter (milter-amavis): 
to error state
Apr 14 04:01:11 atlas sm-mta[18537]: i3E1pBXd018537: Milter (milter-amavis): 
timeout before data read
Apr 14 04:01:11 atlas sm-mta[18537]: i3E1pBXd018537: Milter (milter-amavis): 
to error state
Apr 14 04:01:11 atlas sm-mta[18537]: i3E1pBXd018537: Milter (milter-amavis): 
to error state
-- 
- Philippe
< I U T 'A'  Paul Sabatier >
phi...@iu...
Enseignant/Chargé de mission Systèmes & Réseau
115 Route de Narbonne - 31077 Toulouse Cedex
</>
< G N U / Linux >
phi...@li...
[Projet inetdoc] : http://www.linux-france.org/prj/inetdoc
[Guide RNIS] : http://www.linux-france.org/article/connex/RNIS
</>