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 Staff Member ISPConfig Developer

    Which Tutorial did you use to install this server?
     

Share This Page