Amavisd silently dies at startup
Xavier Humbert <[email protected]> Sun, 22 Feb 2026 17:20:24 +0100
| Newsgroups | gmane.mail.virus.amavis.user |
|---|---|
| Message-ID | <[email protected]> |
Hi, I'm running FreeBSD 14, I recently upgraded Perl from 5.40 to 5.42 and all its dependencies as recommended in /usr/ports/UPDATING Since then amavisd silently dies when I start it. In my config, I added $log_level = 5; $sa_debug = 1; I issued the command "service amavisd start" It launches, the dies without any error message. Here is the relevant part of maillog Feb 22 17:03:39 numenor amavis[97168]: logging initialized, log level 5, syslog: amavis.mail Feb 22 17:03:39 numenor amavis[97168]: sd_notify (no socket): STATUS=Config files have been read, modules loaded. Feb 22 17:03:39 numenor amavis[97168]: starting. /usr/local/sbin/amavisd at numenor.groumpf.org amavis-2.14.0 (20250711), Unicode aware, LANG="C.UTF-8" Feb 22 17:03:39 numenor amavis[97168]: perl=5.042000, user=, EUID: 110 (110); group=(), EGID: 110 110 (110 110) Feb 22 17:03:39 numenor amavis[97168]: INFO: no optional modules: bytes_heavy.pl utf8_heavy.pl unicore::To::Lower.pl unicore::To::Upper.pl unicore::To::Fold.pl unicore::To::Title.pl unicore::To::Digit.pl unicore::lib::Nt::De.pl unicore::Heavy.pl Unix::Getrusage Feb 22 17:03:39 numenor amavis[97168]: SpamControl: scanner SpamAssassin, module Amavis::SpamControl::SpamAssassin Feb 22 17:03:40 numenor amavis[97168]: INFO: SA version: 4.0.2, 4.000002, no optional modules: Mail::SpamAssassin::AutoWhitelist Mail::SpamAssassin::Plugin::Hashcash Mail::SpamAssassin::Plugin::WhiteListSubject auto::Crypt::OpenSSL::RSA::new_public_key auto::Crypt::OpenSSL::RSA::new_key_from_parameters auto::Crypt::OpenSSL::RSA::get_key_parameters auto::Crypt::OpenSSL::RSA::import_random_seed Feb 22 17:03:40 numenor amavis[97168]: SpamControl: init_pre_chroot on SpamAssassin done Feb 22 17:03:40 numenor amavis[97168]: socket module IO::Socket::IP, protocol families available: INET, INET6 Feb 22 17:03:40 numenor amavis[97168]: will bind to /var/amavis/amavisd.sock|unix, 127.0.0.1:10024/tcp, [::1]:10024/tcp Feb 22 17:03:40 numenor amavis[97168]: sd_notify (no socket): STATUS=Transferring control to Net::Server. Feb 22 17:03:40 numenor amavis[97171]: Net::Server: Process Backgrounded Feb 22 17:03:40 numenor amavis[97171]: sd_notify (no socket): MAINPID=97171\nSTATUS=Daemonized, preparing to bind sockets. Feb 22 17:03:40 numenor amavis[97171]: Net::Server: 2026/02/22-17:03:40 Amavis (type Net::Server::PreForkSimple) starting! pid(97171) Feb 22 17:03:40 numenor amavis[97171]: Net::Server: Binding to UNIX socket file "/var/amavis/amavisd.sock" Feb 22 17:03:40 numenor amavis[97171]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1 with IPv4 Feb 22 17:03:40 numenor amavis[97171]: Net::Server: Binding to TCP port 10024 on host ::1 with IPv6 Feb 22 17:03:40 numenor amavis[97171]: sd_notify (no socket): STATUS=Sockets bound, checking user and group. Feb 22 17:03:40 numenor amavis[97171]: Net::Server: Group Not Defined. Defaulting to EGID '110 110' Feb 22 17:03:40 numenor amavis[97171]: Net::Server: User Not Defined. Defaulting to EUID '110' Feb 22 17:03:40 numenor amavis[97171]: sd_notify (no socket): STATUS=The rest of pre-fork init, finding helper programs. Feb 22 17:03:40 numenor amavis[97171]: config files read: /usr/local/etc/amavisd.conf Feb 22 17:03:40 numenor amavis[97171]: Module Amavis::Conf 2.412 Feb 22 17:03:40 numenor amavis[97171]: Module Archive::Zip 1.68 Feb 22 17:03:40 numenor amavis[97171]: Module Compress::Raw::Zlib 2.213 Feb 22 17:03:40 numenor amavis[97171]: Module Compress::Zlib 2.213 Feb 22 17:03:40 numenor amavis[97171]: Module Crypt::OpenSSL::RSA 0.35 Feb 22 17:03:40 numenor amavis[97171]: Module DB_File 1.859 Feb 22 17:03:40 numenor amavis[97171]: Module Digest::MD5 2.59 Feb 22 17:03:40 numenor amavis[97171]: Module Digest::SHA 6.04 Feb 22 17:03:40 numenor amavis[97171]: Module Digest::SHA1 2.13 Feb 22 17:03:40 numenor amavis[97171]: Module Encode 3.21 Feb 22 17:03:40 numenor amavis[97171]: Module File::Temp 0.2311 Feb 22 17:03:40 numenor amavis[97171]: Module IO::Socket::IP 0.43 Feb 22 17:03:40 numenor amavis[97171]: Module IO::Socket::SSL 2.095 Feb 22 17:03:40 numenor amavis[97171]: Module MIME::Entity 5.517 Feb 22 17:03:40 numenor amavis[97171]: Module MIME::Parser 5.517 Feb 22 17:03:40 numenor amavis[97171]: Module MIME::Tools 5.517 Feb 22 17:03:40 numenor amavis[97171]: Module Mail::DKIM::Signer 1.20240923 Feb 22 17:03:40 numenor amavis[97171]: Module Mail::DKIM::Verifier 1.20240923 Feb 22 17:03:40 numenor amavis[97171]: Module Mail::Header 2.22 Feb 22 17:03:40 numenor amavis[97171]: Module Mail::Internet 2.22 Feb 22 17:03:40 numenor amavis[97171]: Module Mail::SPF 3.20250505 Feb 22 17:03:40 numenor amavis[97171]: Module Mail::SpamAssassin 4.000002 Feb 22 17:03:40 numenor amavis[97171]: Module Net::DNS 1.53 Feb 22 17:03:40 numenor amavis[97171]: Module Net::LibIDN 0.12 Feb 22 17:03:40 numenor amavis[97171]: Module Net::LibIDN2 1.02 Feb 22 17:03:40 numenor amavis[97171]: Module Net::Patricia 1.24 Feb 22 17:03:40 numenor amavis[97171]: Module Net::SSLeay 1.94 Feb 22 17:03:40 numenor amavis[97171]: Module Net::Server 2.014 Feb 22 17:03:40 numenor amavis[97171]: Module NetAddr::IP 4.079 Feb 22 17:03:40 numenor amavis[97171]: Module Razor2::Client::Version 2.84 Feb 22 17:03:40 numenor amavis[97171]: Module Scalar::Util 1.70 Feb 22 17:03:40 numenor amavis[97171]: Module Socket 2.040 Feb 22 17:03:40 numenor amavis[97171]: Module Socket6 0.29 Feb 22 17:03:40 numenor amavis[97171]: Module Time::HiRes 1.9778 Feb 22 17:03:40 numenor amavis[97171]: Module URI 5.34 Feb 22 17:03:40 numenor amavis[97171]: Module Unix::Syslog 1.1 Feb 22 17:03:40 numenor amavis[97171]: SQL base code NOT loaded Feb 22 17:03:40 numenor amavis[97171]: SQL::Log code NOT loaded Feb 22 17:03:40 numenor amavis[97171]: SQL::Quarantine NOT loaded Feb 22 17:03:40 numenor amavis[97171]: Lookup::SQL code NOT loaded Feb 22 17:03:40 numenor amavis[97171]: Lookup::LDAP code NOT loaded Feb 22 17:03:40 numenor amavis[97171]: Found $file at /usr/local/bin/file Feb 22 17:03:40 numenor amavis[97171]: No $altermime, not using it Feb 22 17:03:40 numenor amavis[97171]: Internal decoder for .mail Feb 22 17:03:40 numenor amavis[97171]: No ext program for .F, tried: unfreeze, freeze -d, melt, fcat Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .Z at /usr/bin/uncompress Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .gz at /usr/bin/gzip -d Feb 22 17:03:40 numenor amavis[97171]: Internal decoder for .gz (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .bz2 at /usr/bin/bzip2 -d Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .xz at /usr/bin/xzdec Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .lzma at /usr/bin/xz -dc --format=lzma Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .lrz at /usr/local/bin/lrzip -q -k -d -o - Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .lzo at /usr/local/bin/lzop -d Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .lz4 at /usr/local/bin/lz4c -d Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .rpm at /usr/local/bin/rpm2cpio.pl Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .cpio at /bin/pax Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .tar at /bin/pax Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .deb at /usr/local/bin/ar Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .rar at /usr/local/bin/unrar Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .arj at /usr/local/bin/unarj Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .arc at /usr/local/bin/nomarch Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .zoo at /usr/local/bin/zoo Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .doc at /usr/local/bin/ripole Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .cab at /usr/local/bin/cabextract Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .tnef at /usr/local/bin/tnef Feb 22 17:03:40 numenor amavis[97171]: Internal decoder for .tnef (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .zip at /usr/local/bin/7z Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .kmz at /usr/local/bin/7z Feb 22 17:03:40 numenor amavis[97171]: Internal decoder for .zip (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Internal decoder for .kmz (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .7z at /usr/local/bin/7z Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .gz at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .bz2 at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .Z at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .tar at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .xz at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .lzma at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .jar at /usr/local/bin/7z Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .cpio at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .arj at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .rar at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .swf at /usr/local/bin/7z Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .lha at /usr/local/bin/7z Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .iso at /usr/local/bin/7z Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .cab at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .deb at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .rpm at /usr/local/bin/7z (backup, not used) Feb 22 17:03:40 numenor amavis[97171]: Found decoder for .exe at /usr/local/bin/unrar; /usr/local/bin/lha; /usr/local/bin/unarj Feb 22 17:03:40 numenor amavis[97171]: No decoder for .F Feb 22 17:03:40 numenor amavis[97171]: initializing Mail::SpamAssassin (0) Feb 22 17:03:40 numenor amavis[97171]: SpamAssassin debug facilities: info,all Feb 22 17:03:40 numenor amavis[97171]: SA dbg: logger: adding facilities: info, all Feb 22 17:03:40 numenor amavis[97171]: SA dbg: logger: logging level is DBG Feb 22 17:03:40 numenor amavis[97171]: SA dbg: generic: SpamAssassin version 4.0.2 Feb 22 17:03:40 numenor amavis[97171]: SA dbg: generic: Perl 5.042000, PREFIX=/usr/local, DEF_RULES_DIR=/usr/local/share/spamassassin, LOCAL_RULES_DIR=/usr/local/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/db/spamassassin Feb 22 17:03:40 numenor amavis[97171]: SA dbg: config: timing enabled Feb 22 17:03:40 numenor amavis[97171]: SA dbg: config: score set 0 chosen. All I can see when amavisd is activated in Postfix is : Feb 22 16:38:57 numenor postfix/smtpd[92467]: 6DBE91C196A: client=arnor.groumpf.org[192.168.100.148] Feb 22 16:38:57 numenor postfix/lmtp[92473]: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused Feb 22 16:38:57 numenor postfix/lmtp[92473]: 298C11C1A2B: to=<[email protected]>, relay=none, delay=0.32, delays=0.3/0.02/0/0, dsn=4.4.1, status=deferred (connect to 127.0.0.1[127.0.0.1]:10024: Connection refused) If I run the command service amavisd status, it says that amavisd is not running. Any idea how I can debug this furthermore ? Regards, Xavier -- Xavier HUMBERT - Unix/Win/MacOSX Sysadmin/Network Engineer https://www.amdh.fr