amavisd not running

Discussion in 'General' started by fbnewtz, Nov 16, 2012.

  1. fbnewtz

    fbnewtz New Member

    For some reason amavisd will not run on my system. I have turned debug logging up to 1000 and this is what I get when I attempt to start amavisd:

    Code:
    Nov 15 23:53:30 server amavis[2944]: logging initialized, log level 1000, syslog: amavis.mail
    Nov 15 23:53:30 server amavis[2944]: run_command: [2945] /usr/bin/uptime </dev/null 2>/dev/null
    Nov 15 23:53:30 server amavis[2945]: open_on_specific_fd: target fd0 closing, to become < /dev/null
    Nov 15 23:53:30 server amavis[2945]: open_on_specific_fd: target fd1 closing, to become > &=5
    Nov 15 23:53:30 server amavis[2945]: open_on_specific_fd: target fd1 dup2 from fd5 > &=5
    Nov 15 23:53:30 server amavis[2945]: open_on_specific_fd: source fd5 closed
    Nov 15 23:53:30 server amavis[2945]: open_on_specific_fd: target fd2 closing, to become > /dev/null
    Nov 15 23:53:30 server amavis[2944]: system uptime 0 2:08:00:  23:53:30 up  2:08,  1 user,  load average: 0.02, 0.09, 0.08
    Nov 15 23:53:30 server amavis[2944]: Valid PID file (younger than sys uptime 0 2:08:00)
    Nov 15 23:53:30 server amavis[2944]: starting.  /usr/sbin/amavisd at server.minecrafthostingcompany.com amavisd-new-2.6.4 (20090625), Unicode aware, LANG="en_US.UTF-8"
    Nov 15 23:53:30 server amavis[2944]: user=, EUID: 496 (496);  group=, EGID: 493 493 (493 493)
    Nov 15 23:53:30 server amavis[2944]: Perl version               5.010001
    Nov 15 23:53:30 server amavis[2944]: SpamControl: attempting to load scanner SpamAssassin, module Amavis::SpamControl::SpamAssassin
    Nov 15 23:53:30 server amavis[2944]: SpamControl: scanner SpamAssassin, module Amavis::SpamControl::SpamAssassin
    Nov 15 23:53:30 server amavis[2944]: INFO: SA version: 3.3.2, 3.003002, no optional modules: Net::CIDR::Lite Sys::Hostname::Long IP::Country::Fast Image::Info Image::Info::GIF Image::Info::JPEG Image::Info::PNG Image::Info::TIFF
    Nov 15 23:53:30 server amavis[2944]: SpamControl: init_pre_chroot on SpamAssassin done
    Nov 15 23:53:30 server amavis[2944]: _WARN: Pid_file "/var/run/amavisd/amavisd.pid" already exists.  Overwriting!
    Nov 15 23:53:30 server amavis[2946]: Net::Server: Process Backgrounded
    Nov 15 23:53:30 server amavis[2946]: Net::Server: 2012/11/15-23:53:30 Amavis (type Net::Server::PreForkSimple) starting! pid(2946)
    Nov 15 23:53:30 server amavis[2946]: Net::Server: Binding to UNIX socket file "/var/spool/amavisd/amavisd.sock"
    Nov 15 23:53:30 server amavis[2946]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1 with IPv4
    Nov 15 23:53:30 server amavis[2946]: Net::Server: Group Not Defined.  Defaulting to EGID '493 493'
    Nov 15 23:53:30 server amavis[2946]: Net::Server: User Not Defined.  Defaulting to EUID '496'
    Nov 15 23:53:30 server amavis[2946]: Net::Server: Setting up serialization via flock
    Nov 15 23:53:30 server amavis[2946]: after_chroot_init: EUID: 496 (496);  EGID: 493 493 (493 493)
    Nov 15 23:53:30 server amavis[2946]: config files read: /etc/amavisd/amavisd.conf
    Nov 15 23:53:30 server amavis[2946]: Module Amavis::Conf        2.207
    Nov 15 23:53:30 server amavis[2946]: Module Archive::Zip        1.30
    Nov 15 23:53:30 server amavis[2946]: Module BerkeleyDB          0.51
    Nov 15 23:53:30 server amavis[2946]: Module Compress::Zlib      2.058
    Nov 15 23:53:30 server amavis[2946]: Module Convert::TNEF       0.18
    Nov 15 23:53:30 server amavis[2946]: Module Convert::UUlib      1.4
    Nov 15 23:53:30 server amavis[2946]: Module Crypt::OpenSSL::RSA 0.28
    Nov 15 23:53:30 server amavis[2946]: Module DBD::mysql          4.022
    Nov 15 23:53:30 server amavis[2946]: Module DBI                 1.622
    Nov 15 23:53:30 server amavis[2946]: Module DB_File             1.827
    Nov 15 23:53:30 server amavis[2946]: Module Digest::MD5         2.52
    Nov 15 23:53:30 server amavis[2946]: Module Digest::SHA         5.73
    Nov 15 23:53:30 server amavis[2946]: Module IO::Socket::INET6   2.69
    Nov 15 23:53:30 server amavis[2946]: Module MIME::Entity        5.503
    Nov 15 23:53:30 server amavis[2946]: Module MIME::Parser        5.503
    Nov 15 23:53:30 server amavis[2946]: Module MIME::Tools         5.503
    Nov 15 23:53:30 server amavis[2946]: Module Mail::DKIM::Verifier 0.39
    Nov 15 23:53:30 server amavis[2946]: Module Mail::Header        2.11
    Nov 15 23:53:30 server amavis[2946]: Module Mail::Internet      2.11
    Nov 15 23:53:30 server amavis[2946]: Module Mail::SPF           v2.008
    Nov 15 23:53:30 server amavis[2946]: Module Mail::SpamAssassin  3.003002
    Nov 15 23:53:30 server amavis[2946]: Module Net::DNS            0.68
    Nov 15 23:53:30 server amavis[2946]: Module Net::Server         2.006
    Nov 15 23:53:30 server amavis[2946]: Module NetAddr::IP         4.066
    Nov 15 23:53:30 server amavis[2946]: Module Razor2::Client::Version 2.84
    Nov 15 23:53:30 server amavis[2946]: Module Socket6             0.23
    Nov 15 23:53:30 server amavis[2946]: Module Time::HiRes         1.9725
    Nov 15 23:53:30 server amavis[2946]: Module URI                 1.60
    Nov 15 23:53:30 server amavis[2946]: Module Unix::Syslog        1.1
    Nov 15 23:53:30 server amavis[2946]: Amavis::DB code      loaded
    Nov 15 23:53:30 server amavis[2946]: Amavis::Cache code   loaded
    Nov 15 23:53:30 server amavis[2946]: SQL base code        loaded
    Nov 15 23:53:30 server amavis[2946]: SQL::Log code        NOT loaded
    Nov 15 23:53:30 server amavis[2946]: SQL::Quarantine      NOT loaded
    Nov 15 23:53:30 server amavis[2946]: Lookup::SQL code     loaded
    Nov 15 23:53:30 server amavis[2946]: Lookup::LDAP code    NOT loaded
    Nov 15 23:53:30 server amavis[2946]: AM.PDP-in proto code loaded
    Nov 15 23:53:30 server amavis[2946]: SMTP-in proto code   loaded
    Nov 15 23:53:30 server amavis[2946]: Courier proto code   NOT loaded
    Nov 15 23:53:30 server amavis[2946]: SMTP-out proto code  loaded
    Nov 15 23:53:30 server amavis[2946]: Pipe-out proto code  NOT loaded
    Nov 15 23:53:30 server amavis[2946]: BSMTP-out proto code NOT loaded
    Nov 15 23:53:30 server amavis[2946]: Local-out proto code loaded
    Nov 15 23:53:30 server amavis[2946]: OS_Fingerprint code  NOT loaded
    Nov 15 23:53:30 server amavis[2946]: ANTI-VIRUS code      loaded
    Nov 15 23:53:30 server amavis[2946]: ANTI-SPAM code       loaded
    Nov 15 23:53:30 server amavis[2946]: ANTI-SPAM-EXT code   NOT loaded
    Nov 15 23:53:30 server amavis[2946]: ANTI-SPAM-C code     NOT loaded
    Nov 15 23:53:30 server amavis[2946]: ANTI-SPAM-SA code    loaded
    Nov 15 23:53:30 server amavis[2946]: Unpackers code       loaded
    Nov 15 23:53:30 server amavis[2946]: DKIM code            NOT loaded
    Nov 15 23:53:30 server amavis[2946]: Tools code           NOT loaded
    Nov 15 23:53:30 server amavis[2946]: Found $file            at /usr/bin/file
    Nov 15 23:53:30 server amavis[2946]: Found $altermime       at /usr/bin/altermime
    Nov 15 23:53:30 server amavis[2946]: Internal decoder for .mail
    Nov 15 23:53:30 server amavis[2946]: Internal decoder for .asc
    Nov 15 23:53:30 server amavis[2946]: Internal decoder for .uue
    Nov 15 23:53:30 server amavis[2946]: Internal decoder for .hqx
    Nov 15 23:53:30 server amavis[2946]: Internal decoder for .ync
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .F    at /usr/bin/unfreeze
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .Z    at /usr/bin/gzip -d
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .gz   at /usr/bin/gzip -d
    Nov 15 23:53:30 server amavis[2946]: Internal decoder for .gz   (backup, not used)
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .bz2  at /usr/bin/bzip2 -d
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .lzo  at /usr/bin/lzop -d
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .rpm  at /usr/bin/rpm2cpio
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .cpio at /bin/cpio
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .tar  at /bin/cpio
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .deb  at /usr/bin/ar
    Nov 15 23:53:30 server amavis[2946]: Internal decoder for .zip
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .7z   at /usr/bin/7za
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .rar  at /usr/bin/rar
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .arj  at /usr/bin/arj
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .arc  at /usr/bin/nomarch
    Nov 15 23:53:30 server amavis[2946]: No decoder for       .zoo  tried: zoo, unzoo
    Nov 15 23:53:30 server amavis[2946]: No decoder for       .lha  tried: lha
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .cab  at /usr/bin/cabextract
    Nov 15 23:53:30 server amavis[2946]: No decoder for       .tnef tried: tnef
    Nov 15 23:53:30 server amavis[2946]: Internal decoder for .tnef
    Nov 15 23:53:30 server amavis[2946]: Found decoder for    .exe  at /usr/bin/rar; /usr/bin/arj
    Nov 15 23:53:30 server amavis[2946]: Using primary internal av scanner code for ClamAV-clamd
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: KasperskyLab AVP - aveclient
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: KasperskyLab AntiViral Toolkit Pro (AVP)
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: KasperskyLab AVPDaemonClient
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: CentralCommand Vexira (new) vascan
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: Avira AntiVir
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: Command AntiVirus for Linux
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: Symantec CarrierScan via Symantec CommandLineScanner
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: Symantec AntiVirus Scan Engine
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: F-Secure Antivirus for Linux servers
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: CAI InoculateIT
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: CAI eTrust Antivirus
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: MkS_Vir for Linux (beta)
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: MkS_Vir daemon
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: ESET NOD32 Linux Mail Server - command line interface
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: ESET NOD32 for Linux File servers
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: Norman Virus Control v5 / Linux
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: Panda CommandLineSecure 9 for Linux
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: NAI McAfee AntiVirus (uvscan)
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: VirusBuster
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: CyberSoft VFind
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: avast! Antivirus
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: Ikarus AntiVirus for Linux
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: BitDefender
    Nov 15 23:53:30 server amavis[2946]: No primary av scanner: ArcaVir for Linux
    Nov 15 23:53:30 server amavis[2946]: Found secondary av scanner ClamAV-clamscan at /usr/bin/clamscan
    Nov 15 23:53:30 server amavis[2946]: No secondary av scanner: FRISK F-Prot Antivirus
    Nov 15 23:53:30 server amavis[2946]: No secondary av scanner: Trend Micro FileScanner
    Nov 15 23:53:30 server amavis[2946]: No secondary av scanner: drweb - DrWeb Antivirus
    Nov 15 23:53:30 server amavis[2946]: No secondary av scanner: Kaspersky Antivirus v5.5
    Nov 15 23:53:30 server amavis[2946]: Using internal spam scanner code for SpamAssassin
    Nov 15 23:53:30 server amavis[2946]: Creating db in /var/spool/amavisd/db/; BerkeleyDB 0.51, libdb 4.7
    Nov 15 23:53:31 server amavis[2946]: initializing Mail::SpamAssassin
    Nov 15 23:53:31 server amavis[2946]: SpamAssassin debug facilities: info
    Nov 15 23:53:31 server amavis[2946]: (!!)TROUBLE in pre_loop_hook: config: no rules were found!  Do you need to run 'sa-update'?
    Nov 15 23:53:31 server amavis[2946]: (!)_DIE: Suicide () TROUBLE in pre_loop_hook: config: no rules were found!  Do you need to run 'sa-update'?
    
    sa-update runs just fine. Here is a debug log output from sa-update

    Code:
    [root@server /]# sa-update -D -v
    Subroutine IO::Socket::INET6::sockaddr_in6 redefined at /usr/share/perl5/Exporter.pm line 67.
     at /usr/share/perl5/IO/Socket/INET6.pm line 21
    Nov 16 00:25:42.547 [5203] dbg: logger: adding facilities: all
    Nov 16 00:25:42.547 [5203] dbg: logger: logging level is DBG
    Nov 16 00:25:42.547 [5203] dbg: generic: SpamAssassin version 3.3.1
    Nov 16 00:25:42.547 [5203] dbg: generic: Perl 5.010001, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin
    Nov 16 00:25:42.547 [5203] dbg: config: timing enabled
    Nov 16 00:25:42.548 [5203] dbg: config: score set 0 chosen.
    Nov 16 00:25:42.552 [5203] dbg: dns: is Net::DNS::Resolver available? yes
    Nov 16 00:25:42.552 [5203] dbg: dns: Net::DNS version: 0.68
    Nov 16 00:25:42.553 [5203] dbg: generic: sa-update version svn917659
    Nov 16 00:25:42.553 [5203] dbg: generic: using update directory: /var/lib/spamassassin/3.003001
    Nov 16 00:25:42.654 [5203] dbg: diag: perl platform: 5.010001 linux
    Nov 16 00:25:42.654 [5203] dbg: diag: [...] module installed: Digest::SHA1, version 2.13
    Nov 16 00:25:42.654 [5203] dbg: diag: [...] module installed: HTML::Parser, version 3.69
    Nov 16 00:25:42.654 [5203] dbg: diag: [...] module installed: Net::DNS, version 0.68
    Nov 16 00:25:42.654 [5203] dbg: diag: [...] module installed: NetAddr::IP, version 4.066
    Nov 16 00:25:42.654 [5203] dbg: diag: [...] module installed: Time::HiRes, version 1.9725
    Nov 16 00:25:42.654 [5203] dbg: diag: [...] module installed: Archive::Tar, version 1.58
    Nov 16 00:25:42.654 [5203] dbg: diag: [...] module installed: IO::Zlib, version 1.09
    Nov 16 00:25:42.654 [5203] dbg: diag: [...] module installed: Digest::SHA1, version 2.13
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: MIME::Base64, version 3.13
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: DB_File, version 1.827
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: Net::SMTP, version 2.31
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: Mail::SPF, version v2.008
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module not installed: IP::Country::Fast ('require' failed)
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: Razor2::Client::Agent, version 2.84
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module not installed: Net::Ident ('require' failed)
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: IO::Socket::INET6, version 2.56
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: IO::Socket::SSL, version 1.31
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: Compress::Zlib, version 2.058
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: Mail::DKIM, version 0.37
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: DBI, version 1.622
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: Getopt::Long, version 2.38
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: LWP::UserAgent, version 5.833
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: HTTP::Date, version 5.831
    Nov 16 00:25:42.655 [5203] dbg: diag: [...] module installed: Encode::Detect, version 1.01
    Nov 16 00:25:42.656 [5203] dbg: gpg: Searching for 'gpg'
    Nov 16 00:25:42.656 [5203] dbg: util: current PATH is: /usr/lib/courier-imap/sbin:/usr/lib/courier-imap/bin:/sbin:/bin:/usr/sbin:/usr/bin
    Nov 16 00:25:42.656 [5203] dbg: util: executable for gpg was found at /usr/bin/gpg
    Nov 16 00:25:42.656 [5203] dbg: gpg: found /usr/bin/gpg
    Nov 16 00:25:42.656 [5203] dbg: gpg: release trusted key id list: 5E541DC959CB8BAC7C78DFDC4056A61A5244EC45 26C900A46DD40CD5AD24F6D7DEE01987265FA05B 0C2B1D7175B852C64B3CDC716C55397824F434CE
    Nov 16 00:25:42.658 [5203] dbg: channel: attempting channel updates.spamassassin.org
    Nov 16 00:25:42.658 [5203] dbg: channel: update directory /var/lib/spamassassin/3.003001/updates_spamassassin_org
    Nov 16 00:25:42.658 [5203] dbg: channel: channel cf file /var/lib/spamassassin/3.003001/updates_spamassassin_org.cf
    Nov 16 00:25:42.658 [5203] dbg: channel: channel pre file /var/lib/spamassassin/3.003001/updates_spamassassin_org.pre
    Nov 16 00:25:42.658 [5203] dbg: channel: metadata version = 1409116
    Nov 16 00:25:42.683 [5203] dbg: dns: 1.3.3.updates.spamassassin.org => 1409116, parsed as 1409116
    Nov 16 00:25:42.684 [5203] dbg: channel: current version is 1409116, new version is 1409116, skipping channel
    Nov 16 00:25:42.684 [5203] dbg: diag: updates complete, exiting with code 1
    Update finished, no fresh updates were available
    
    Any help is greatly appreciated.
     
  2. till

    till Super Moderator

    Which Tutorial did you use to install this server?
     

Share This Page