Unable to load spamassassin

[Marcio Merlone]

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 at 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*
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.amavis.org/pipermail/amavis-users/attachments/20200330/af56d67c/attachment.htm>