Hi,
My amavis is having trouble with my spamassassin setup. If I enable
@bypass_spam_checks_maps amavis never finishes to load. Running as
amavis user:
root@mx1:~# su - amavis -s /bin/bash
$ id
uid=118(amavis) gid=124(amavis)
groups=124(amavis),122(clamav),125(debian-spamd)
$ amavisd-new debug 2>&1 | tee -a amavis.log
Mar 30 18:57:22.052 mx1.a1.ind.br /usr/sbin/amavisd-new[919]: logging
initialized, log level 0, syslog: amavis.mail
Mar 30 18:57:22.052 mx1.a1.ind.br /usr/sbin/amavisd-new[919]: sd_notify
(no socket): STATUS=Config files have been read, modules loaded.
Mar 30 18:57:22.053 mx1.a1.ind.br /usr/sbin/amavisd-new[919]: starting.
/usr/sbin/amavisd-new at mx1.a1.ind.br amavisd-new-2.11.0 (20160426),
Unicode aware, LANG="en_US.UTF-8"
(...)
Mar 30 18:57:22.272 mx1.a1.ind.br /usr/sbin/amavisd-new[919]:
initializing Mail::SpamAssassin (0)
Mar 30 18:57:22.272 mx1.a1.ind.br /usr/sbin/amavisd-new[919]:
SpamAssassin debug facilities: info
(Notice: exact 10 minutes later!)
Mar 30 19:02:24.005 mx1.a1.ind.br /usr/sbin/amavisd-new[919]: SA info:
check: exceeded time limit in
Mail::SpamAssassin::Plugin::Check::_eval_tests_type13_pri0_set1,
skipping further tests
Mar 30 19:02:24.013 mx1.a1.ind.br /usr/sbin/amavisd-new[919]:
SpamAssassin loaded plugins: AskDNS, AutoLearnThreshold, Bayes,
BodyEval, Check, DKIM, DNSEval, FreeMail, HTMLEval, HTTPSMismatch,
Hashcash, HeaderEval, ImageInfo, MIMEEval, MIMEHeader, Pyzor, Razor2,
RelayEval, ReplaceTags, SPF, SpamCop, URIDNSBL, URIDetail, URIEval,
VBounce, WLBLEval, WhiteListSubject
Mar 30 19:02:24.014 mx1.a1.ind.br /usr/sbin/amavisd-new[919]:
SpamControl: init_pre_fork on SpamAssassin done
Mar 30 19:02:24.016 mx1.a1.ind.br /usr/sbin/amavisd-new[919]: extra
modules loaded after daemonizing/chrooting: /etc/perl/Net/libnet.cfg,
/usr/lib/x86_64-linux-gnu/perl5/5.26/auto/Net/SSLeay/autosplit.ix,
/usr/lib/x86_64-linux-gnu/perl5/5.26/auto/Net/SSLeay/randomize.al,
IO/Socket/SSL.pm, IO/Socket/SSL/PublicSuffix.pm,
Mail/SpamAssassin/Plugin/FreeMail.pm,
Mail/SpamAssassin/Plugin/SpamCop.pm, Net/Cmd.pm, Net/Config.pm,
Net/SMTP.pm, Net/SSLeay.pm
Mar 30 19:02:24.018 mx1.a1.ind.br /usr/sbin/amavisd-new[919]: sd_notify
(no socket): READY=1\nSTATUS=Initialization done.
Mar 30 19:02:24.019 mx1.a1.ind.br /usr/sbin/amavisd-new[919]:
Net::Server: Beginning prefork (10 processes)
Mar 30 19:02:24.019 mx1.a1.ind.br /usr/sbin/amavisd-new[919]: sd_notify
(no socket): STATUS=Starting child process(es), ready for work.
Mar 30 19:02:24.019 mx1.a1.ind.br /usr/sbin/amavisd-new[919]:
Net::Server: Starting "10" children
Mar 30 19:02:24.023 mx1.a1.ind.br /usr/sbin/amavisd-new[6786]:
Net::Server: Child Preforked (6786)
Mar 30 19:02:24.023 mx1.a1.ind.br /usr/sbin/amavisd-new[6786]: entered
child_init_hook
Mar 30 19:02:24.025 mx1.a1.ind.br /usr/sbin/amavisd-new[6787]:
Net::Server: Child Preforked (6787)
Mar 30 19:02:24.026 mx1.a1.ind.br /usr/sbin/amavisd-new[6787]: entered
child_init_hook
Mar 30 19:02:24.027 mx1.a1.ind.br /usr/sbin/amavisd-new[6788]:
Net::Server: Child Preforked (6788)
Mar 30 19:02:24.027 mx1.a1.ind.br /usr/sbin/amavisd-new[6788]: entered
child_init_hook
Mar 30 19:02:24.028 mx1.a1.ind.br /usr/sbin/amavisd-new[6786]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.030 mx1.a1.ind.br /usr/sbin/amavisd-new[6787]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.029 mx1.a1.ind.br /usr/sbin/amavisd-new[6789]:
Net::Server: Child Preforked (6789)
Mar 30 19:02:24.030 mx1.a1.ind.br /usr/sbin/amavisd-new[6789]: entered
child_init_hook
Mar 30 19:02:24.031 mx1.a1.ind.br /usr/sbin/amavisd-new[6790]:
Net::Server: Child Preforked (6790)
Mar 30 19:02:24.031 mx1.a1.ind.br /usr/sbin/amavisd-new[6790]: entered
child_init_hook
Mar 30 19:02:24.032 mx1.a1.ind.br /usr/sbin/amavisd-new[6788]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.033 mx1.a1.ind.br /usr/sbin/amavisd-new[6791]:
Net::Server: Child Preforked (6791)
Mar 30 19:02:24.033 mx1.a1.ind.br /usr/sbin/amavisd-new[6791]: entered
child_init_hook
Mar 30 19:02:24.034 mx1.a1.ind.br /usr/sbin/amavisd-new[6789]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.035 mx1.a1.ind.br /usr/sbin/amavisd-new[6790]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.035 mx1.a1.ind.br /usr/sbin/amavisd-new[6792]:
Net::Server: Child Preforked (6792)
Mar 30 19:02:24.036 mx1.a1.ind.br /usr/sbin/amavisd-new[6792]: entered
child_init_hook
Mar 30 19:02:24.036 mx1.a1.ind.br /usr/sbin/amavisd-new[6793]:
Net::Server: Child Preforked (6793)
Mar 30 19:02:24.036 mx1.a1.ind.br /usr/sbin/amavisd-new[6793]: entered
child_init_hook
Mar 30 19:02:24.038 mx1.a1.ind.br /usr/sbin/amavisd-new[6791]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.039 mx1.a1.ind.br /usr/sbin/amavisd-new[919]:
Net::Server: Parent ready for children.
Mar 30 19:02:24.039 mx1.a1.ind.br /usr/sbin/amavisd-new[6794]:
Net::Server: Child Preforked (6794)
Mar 30 19:02:24.040 mx1.a1.ind.br /usr/sbin/amavisd-new[6794]: entered
child_init_hook
Mar 30 19:02:24.040 mx1.a1.ind.br /usr/sbin/amavisd-new[6792]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.040 mx1.a1.ind.br /usr/sbin/amavisd-new[6793]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.040 mx1.a1.ind.br /usr/sbin/amavisd-new[6795]:
Net::Server: Child Preforked (6795)
Mar 30 19:02:24.041 mx1.a1.ind.br /usr/sbin/amavisd-new[6795]: entered
child_init_hook
Mar 30 19:02:24.044 mx1.a1.ind.br /usr/sbin/amavisd-new[6794]:
SpamControl: init_child on SpamAssassin done
Mar 30 19:02:24.044 mx1.a1.ind.br /usr/sbin/amavisd-new[6795]:
SpamControl: init_child on SpamAssassin done
$ cat amavis.log
<5>starting. /usr/sbin/amavisd-new at mx1.a1.ind.br amavisd-new-2.11.0
(20160426), Unicode aware, LANG="en_US.UTF-8"
<5>Net::Server: Group Not Defined. Defaulting to EGID '124 122 124 125'
<5>Net::Server: User Not Defined. Defaulting to EUID '118'
<5>No $altermime, not using it
<5>No ext program for .lrz, tried: lrzip -q -k -d -o -, lrzcat -q -k
<5>No ext program for .lzo, tried: lzop -d
<5>No ext program for .lz4, tried: lz4c -d
<5>No ext program for .rpm, tried: rpm2cpio.pl, rpm2cpio
<5>No ext program for .arj, tried: arj, unarj
<5>No ext program for .arc, tried: nomarch, arc
<5>No ext program for .zoo, tried: zoo
<5>No ext program for .doc, tried: ripole
<5>No ext program for .zip, tried: 7za, 7z
<5>No ext program for .kmz, tried: 7za, 7z
<5>No ext program for .jar, tried: 7z
<5>No ext program for .arj, tried: 7z
<5>No ext program for .swf, tried: 7z
<5>No ext program for .lha, tried: 7z
<5>No ext program for .iso, tried: 7z
<5>No ext program for .rpm, tried: 7z
<5>No decoder for .F
<5>No decoder for .arc
<5>No decoder for .arj
<5>No decoder for .doc
<5>No decoder for .iso
<5>No decoder for .jar
<5>No decoder for .lha
<5>No decoder for .lrz
<5>No decoder for .lz4
<5>No decoder for .lzo
<5>No decoder for .rpm
<5>No decoder for .swf
<5>No decoder for .zoo
<5>Using primary internal av scanner code for ClamAV-clamd
<5>Found secondary av scanner ClamAV-clamscan at /usr/bin/clamscan
(10 minutes)
<5>Net::Server: Ran out of accept retries!
<4>(!)_DIE: refcnt: fd -1 < 0
refcnt: fd -1 < 0
<5>Net::Server: Ran out of accept retries!
<4>(!)_DIE: refcnt: fd -1 < 0
refcnt: fd -1 < 0
^C$
$
Already purged amavis and spamassassin, removed all custom and /var/lib
files related to both, no luck. Only choice is disable
@bypass_spam_checks_maps which makes no sense.
Any hint? Perhaps some leftover I forgot to remove somewhere during the
purge?
--
*Marcio Merlone*
