Unable to load spamassassin

Marcio Merlone <[email protected]>
Newsgroups gmane.mail.virus.amavis.user
Organization A1 Engenharia
Message-ID <[email protected]>
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*
lmpx.com only provides a reader for public news (NNTP) servers. It is not affiliated with the servers or forums shown here and is not responsible for the content of articles, which is written by their respective authors.