Spoke too soon.... after rebooting I cannot get Dovecot to start back up, and I'm getting all sorts of errors. I captured the mail.log just after a reboot:
Code:
Feb 22 22:47:54 foo dovecot: master: Fatal: service(pop3) access(/usr/lib/dovecot/pop3) failed: No such file or directory
Feb 22 22:47:55 foo amavis[2178]: starting. /usr/sbin/amavisd-new at foo.mydomain.com amavisd-new-2.6.5 (20110407), Unicode aware
Feb 22 22:47:55 foo amavis[2178]: Perl version 5.014002
Feb 22 22:47:56 foo amavis[2459]: Net::Server: Group Not Defined. Defaulting to EGID '120 120'
Feb 22 22:47:56 foo amavis[2459]: Net::Server: User Not Defined. Defaulting to EUID '112'
Feb 22 22:47:56 foo amavis[2459]: Module Amavis::Conf 2.208
Feb 22 22:47:56 foo amavis[2459]: Module Archive::Zip 1.30
Feb 22 22:47:56 foo amavis[2459]: Module BerkeleyDB 0.49
Feb 22 22:47:56 foo amavis[2459]: Module Compress::Zlib 2.033
Feb 22 22:47:56 foo amavis[2459]: Module Convert::TNEF 0.17
Feb 22 22:47:56 foo amavis[2459]: Module Convert::UUlib 1.4
Feb 22 22:47:56 foo amavis[2459]: Module Crypt::OpenSSL::RSA 0.27
Feb 22 22:47:56 foo amavis[2459]: Module DB_File 1.821
Feb 22 22:47:56 foo amavis[2459]: Module Digest::MD5 2.51
Feb 22 22:47:56 foo amavis[2459]: Module Digest::SHA 5.61
Feb 22 22:47:56 foo amavis[2459]: Module IO::Socket::INET6 2.69
Feb 22 22:47:56 foo amavis[2459]: Module MIME::Entity 5.502
Feb 22 22:47:56 foo amavis[2459]: Module MIME::Parser 5.502
Feb 22 22:47:56 foo amavis[2459]: Module MIME::Tools 5.502
Feb 22 22:47:56 foo amavis[2459]: Module Mail::DKIM::Signer 0.39
Feb 22 22:47:56 foo amavis[2459]: Module Mail::DKIM::Verifier 0.39
Feb 22 22:47:56 foo amavis[2459]: Module Mail::Header 2.08
Feb 22 22:47:56 foo amavis[2459]: Module Mail::Internet 2.08
Feb 22 22:47:56 foo amavis[2459]: Module Mail::SPF v2.008
Feb 22 22:47:56 foo amavis[2459]: Module Mail::SpamAssassin 3.003002
Feb 22 22:47:56 foo amavis[2459]: Module Net::DNS 0.66
Feb 22 22:47:56 foo amavis[2459]: Module Net::Server 0.99
Feb 22 22:47:56 foo amavis[2459]: Module NetAddr::IP 4.058
Feb 22 22:47:56 foo amavis[2459]: Module Razor2::Client::Version 2.84
Feb 22 22:47:56 foo amavis[2459]: Module Socket6 0.23
Feb 22 22:47:56 foo amavis[2459]: Module Time::HiRes 1.972101
Feb 22 22:47:56 foo amavis[2459]: Module URI 1.59
Feb 22 22:47:56 foo amavis[2459]: Module Unix::Syslog 1.1
Feb 22 22:47:56 foo amavis[2459]: Amavis::DB code loaded
Feb 22 22:47:56 foo amavis[2459]: Amavis::Cache code loaded
Feb 22 22:47:56 foo amavis[2459]: SQL base code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: SQL::Log code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: SQL::Quarantine NOT loaded
Feb 22 22:47:56 foo amavis[2459]: Lookup::SQL code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: Lookup::LDAP code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: AM.PDP-in proto code loaded
Feb 22 22:47:56 foo amavis[2459]: SMTP-in proto code loaded
Feb 22 22:47:56 foo amavis[2459]: Courier proto code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: SMTP-out proto code loaded
Feb 22 22:47:56 foo amavis[2459]: Pipe-out proto code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: BSMTP-out proto code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: Local-out proto code loaded
Feb 22 22:47:56 foo amavis[2459]: OS_Fingerprint code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: ANTI-VIRUS code loaded
Feb 22 22:47:56 foo amavis[2459]: ANTI-SPAM code loaded
Feb 22 22:47:56 foo amavis[2459]: ANTI-SPAM-EXT code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: ANTI-SPAM-C code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: ANTI-SPAM-SA code loaded
Feb 22 22:47:56 foo amavis[2459]: Unpackers code loaded
Feb 22 22:47:56 foo amavis[2459]: DKIM code loaded
Feb 22 22:47:56 foo amavis[2459]: Tools code NOT loaded
Feb 22 22:47:56 foo amavis[2459]: Found $file at /usr/bin/file
Feb 22 22:47:56 foo amavis[2459]: No $altermime, not using it
Feb 22 22:47:56 foo amavis[2459]: Internal decoder for .mail
Feb 22 22:47:56 foo amavis[2459]: No decoder for .F
Feb 22 22:47:56 foo amavis[2459]: Found decoder for .Z at /bin/uncompress
Feb 22 22:47:56 foo amavis[2459]: Internal decoder for .gz
Feb 22 22:47:56 foo amavis[2459]: Found decoder for .bz2 at /bin/bzip2 -d
Feb 22 22:47:56 foo amavis[2459]: Found decoder for .lzo at /usr/bin/lzop -d
Feb 22 22:47:56 foo amavis[2459]: No decoder for .rpm tried: rpm2cpio.pl, rpm2cpio
Feb 22 22:47:56 foo amavis[2459]: Found decoder for .cpio at /bin/pax
Feb 22 22:47:56 foo amavis[2459]: Found decoder for .tar at /bin/pax
Feb 22 22:47:56 foo amavis[2459]: Found decoder for .deb at /usr/bin/ar
Feb 22 22:47:56 foo amavis[2459]: Internal decoder for .zip
Feb 22 22:47:56 foo amavis[2459]: No decoder for .7z tried: 7zr, 7za, 7z
Feb 22 22:47:56 foo amavis[2459]: No decoder for .rar tried: unrar-free
Feb 22 22:47:56 foo amavis[2459]: No decoder for .arj tried: arj, unarj
Feb 22 22:47:56 foo amavis[2459]: Found decoder for .arc at /usr/bin/nomarch
Feb 22 22:47:56 foo amavis[2459]: Found decoder for .zoo at /usr/bin/zoo
Feb 22 22:47:56 foo amavis[2459]: No decoder for .lha
Feb 22 22:47:56 foo amavis[2459]: No decoder for .doc tried: ripole
Feb 22 22:47:56 foo amavis[2459]: No decoder for .cab tried: cabextract
Feb 22 22:47:56 foo amavis[2459]: No decoder for .tnef
Feb 22 22:47:56 foo amavis[2459]: Internal decoder for .tnef
Feb 22 22:47:56 foo amavis[2459]: No decoder for .exe tried: unrar-free; arj, unarj
Feb 22 22:47:56 foo amavis[2459]: Using primary internal av scanner code for ClamAV-clamd
Feb 22 22:47:56 foo amavis[2459]: Found secondary av scanner ClamAV-clamscan at /usr/bin/clamscan
Feb 22 22:47:56 foo amavis[2459]: Creating db in /var/lib/amavis/db/; BerkeleyDB 0.49, libdb 5.1
Feb 22 22:48:04 foo authdaemond: modules="authmysql", daemons=5
Feb 22 22:48:04 foo authdaemond: Installing libauthmysql
Feb 22 22:48:04 foo authdaemond: Installation complete: authmysql
Feb 22 22:48:05 foo postfix/master[3114]: daemon started -- version 2.9.3, configuration /etc/postfix
Feb 22 22:48:05 foo postfix/qmgr[3130]: C9A60C0D8: from=<root@mydomain.com>, size=387, nrcpt=1 (queue active)
Feb 22 22:48:05 foo postfix/qmgr[3130]: 5B8DFC0E1: from=<root@mydomain.com>, size=363, nrcpt=1 (queue active)
Feb 22 22:47:30 foo postfix/smtpd[3333]: connect from localhost.localdomain[127.0.0.1]
Feb 22 22:47:30 foo postfix/smtpd[3333]: warning: SASL: Connect to private/auth failed: Connection refused
Feb 22 22:47:30 foo postfix/smtpd[3333]: fatal: no SASL authentication mechanisms
Feb 22 22:47:31 foo postfix/master[3114]: warning: process /usr/lib/postfix/smtpd pid 3333 exit status 1
Feb 22 22:47:31 foo postfix/master[3114]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling
Feb 22 22:47:31 foo amavis[3324]: (03324-01) (!)FWD via SMTP: <root@mydomain.com> -> <comments@mydomain.com>, 451 4.5.0 From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03324-01
Feb 22 22:47:31 foo amavis[3324]: (03324-01) Blocked MTA-BLOCKED, <root@mydomain.com> -> <comments@mydomain.com>, Message-ID: <20130222053725.C9A60C0D8@foo.mydomain.com>, mail_id: PAXKMWe6XuEk, Hits: -0.001, size: 387, 3227 ms
Feb 22 22:47:31 foo postfix/smtp[3133]: C9A60C0D8: to=<comments@mydomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=79806, delays=79840/0.04/0/3.2, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=03324-01 - Temporary MTA failure on relaying, From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03324-01 (in reply to end of DATA command))
Feb 22 22:48:10 foo amavis[3325]: (03325-01) (!)rw_loop: leaving rw loop, no progress, last event (select) 35.035 s ago
Feb 22 22:48:10 foo amavis[3325]: (03325-01) (!)FWD via SMTP: <root@mydomain.com> -> <connie@mydomain.com>, 451 4.5.0 From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03325-01
Feb 22 22:48:10 foo amavis[3325]: (03325-01) Blocked MTA-BLOCKED, <root@mydomain.com> -> <connie@mydomain.com>, Message-ID: <20130222055315.5B8DFC0E1@foo.mydomain.com>, mail_id: LkQt8+vCdVP6, Hits: -0.001, size: 363, 41287 ms
Feb 22 22:48:10 foo postfix/smtp[3148]: 5B8DFC0E1: to=<connie@mydomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=78895, delays=78890/0.05/0/41, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=03325-01 - Temporary MTA failure on relaying, From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03325-01 (in reply to end of DATA command))
Feb 22 22:48:15 foo amavis[3325]: (03325-01) (!)rw_loop: leaving rw loop, no progress, last event (select) 5.005 s ago
Feb 22 22:48:31 foo postfix/smtpd[3338]: connect from localhost.localdomain[127.0.0.1]
Feb 22 22:48:31 foo postfix/smtpd[3338]: warning: SASL: Connect to private/auth failed: Connection refused
Feb 22 22:48:31 foo postfix/smtpd[3338]: fatal: no SASL authentication mechanisms
Feb 22 22:48:32 foo postfix/master[3114]: warning: process /usr/lib/postfix/smtpd pid 3338 exit status 1
Feb 22 22:48:32 foo postfix/master[3114]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling
Feb 22 22:51:36 foo postfix/qmgr[3130]: C9A60C0D8: from=<root@mydomain.com>, size=387, nrcpt=1 (queue active)
Feb 22 22:51:36 foo postfix/qmgr[3130]: 5B8DFC0E1: from=<root@mydomain.com>, size=363, nrcpt=1 (queue active)
Feb 22 22:51:36 foo dccproc[3938]: no DCC answer from dcc1.dcc-servers.net (2001:470:1f05:10ed::29,6277) after 0 ms
Feb 22 22:51:36 foo dccproc[3939]: no DCC answer from dcc2.dcc-servers.net (2001:4978:230::3,6277) after 0 ms
Feb 22 22:51:41 foo postfix/smtpd[3941]: connect from localhost.localdomain[127.0.0.1]
Feb 22 22:51:41 foo postfix/smtpd[3941]: warning: SASL: Connect to private/auth failed: Connection refused
Feb 22 22:51:41 foo postfix/smtpd[3941]: fatal: no SASL authentication mechanisms
Feb 22 22:51:41 foo postfix/smtpd[3944]: connect from localhost.localdomain[127.0.0.1]
Feb 22 22:51:41 foo postfix/smtpd[3944]: warning: SASL: Connect to private/auth failed: Connection refused
Feb 22 22:51:41 foo postfix/smtpd[3944]: fatal: no SASL authentication mechanisms
Feb 22 22:51:42 foo postfix/master[3114]: warning: process /usr/lib/postfix/smtpd pid 3941 exit status 1
Feb 22 22:51:42 foo postfix/master[3114]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling
Feb 22 22:51:42 foo amavis[3324]: (03324-02) (!)FWD via SMTP: <root@mydomain.com> -> <comments@mydomain.com>, 451 4.5.0 From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03324-02
Feb 22 22:51:42 foo amavis[3324]: (03324-02) Blocked MTA-BLOCKED, <root@mydomain.com> -> <comments@mydomain.com>, Message-ID: <20130222053725.C9A60C0D8@foo.mydomain.com>, mail_id: 6RaLSFLtgYlp, Hits: -0.001, size: 387, 5911 ms
Feb 22 22:51:42 foo postfix/smtp[3922]: C9A60C0D8: to=<comments@mydomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=80056, delays=80050/0.01/0/5.9, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=03324-02 - Temporary MTA failure on relaying, From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03324-02 (in reply to end of DATA command))
Feb 22 22:51:42 foo postfix/master[3114]: warning: process /usr/lib/postfix/smtpd pid 3944 exit status 1
Feb 22 22:51:42 foo amavis[3325]: (03325-02) (!)FWD via SMTP: <root@mydomain.com> -> <connie@mydomain.com>, 451 4.5.0 From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03325-02
Feb 22 22:51:42 foo amavis[3325]: (03325-02) Blocked MTA-BLOCKED, <root@mydomain.com> -> <connie@mydomain.com>, Message-ID: <20130222055315.5B8DFC0E1@foo.mydomain.com>, mail_id: TlP2jYiKZy32, Hits: -0.001, size: 363, 6266 ms
Feb 22 22:51:42 foo postfix/smtp[3924]: 5B8DFC0E1: to=<connie@mydomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=79107, delays=79101/0.01/0/6.3, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=03325-02 - Temporary MTA failure on relaying, From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03325-02 (in reply to end of DATA command))
Feb 22 23:17:01 foo postfix/pickup[3129]: 434DE17318: uid=0 from=<root>
Feb 22 23:17:01 foo postfix/cleanup[4029]: 434DE17318: message-id=<20130223041701.434DE17318@foo.mydomain.com>
Feb 22 23:17:01 foo postfix/qmgr[3130]: 434DE17318: from=<root@mydomain.com>, size=640, nrcpt=1 (queue active)
Feb 22 23:17:09 foo postfix/smtpd[4038]: connect from localhost.localdomain[127.0.0.1]
Feb 22 23:17:09 foo postfix/smtpd[4038]: warning: SASL: Connect to private/auth failed: Connection refused
Feb 22 23:17:09 foo postfix/smtpd[4038]: fatal: no SASL authentication mechanisms
Feb 22 23:17:10 foo postfix/master[3114]: warning: process /usr/lib/postfix/smtpd pid 4038 exit status 1
Feb 22 23:17:10 foo postfix/master[3114]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling
Feb 22 23:17:10 foo amavis[3324]: (03324-03) (!)FWD via SMTP: <root@mydomain.com> -> <root@mydomain.com>, 451 4.5.0 From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03324-03
Feb 22 23:17:10 foo amavis[3324]: (03324-03) Blocked MTA-BLOCKED, <root@mydomain.com> -> <root@mydomain.com>, Message-ID: <20130223041701.434DE17318@foo.mydomain.com>, mail_id: rtHqHxEHX2x9, Hits: -0.001, size: 640, 8928 ms
Feb 22 23:17:10 foo postfix/smtp[4033]: 434DE17318: to=<root@mydomain.com>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=9.1, delays=0.16/0.01/0/8.9, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=03324-03 - Temporary MTA failure on relaying, From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03324-03 (in reply to end of DATA command))
Feb 22 23:23:05 foo postfix/qmgr[3130]: 434DE17318: from=<root@mydomain.com>, size=640, nrcpt=1 (queue active)
Feb 22 23:23:08 foo postfix/smtpd[4045]: connect from localhost.localdomain[127.0.0.1]
Feb 22 23:23:08 foo postfix/smtpd[4045]: warning: SASL: Connect to private/auth failed: Connection refused
Feb 22 23:23:08 foo postfix/smtpd[4045]: fatal: no SASL authentication mechanisms
Feb 22 23:23:09 foo postfix/master[3114]: warning: process /usr/lib/postfix/smtpd pid 4045 exit status 1
Feb 22 23:23:09 foo postfix/master[3114]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling
Feb 22 23:23:09 foo amavis[3325]: (03325-03) (!)FWD via SMTP: <root@mydomain.com> -> <root@mydomain.com>, 451 4.5.0 From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03325-03
Feb 22 23:23:09 foo amavis[3325]: (03325-03) Blocked MTA-BLOCKED, <root@mydomain.com> -> <root@mydomain.com>, Message-ID: <20130223041701.434DE17318@foo.mydomain.com>, mail_id: U3L9sPkegigV, Hits: -0.001, size: 640, 3663 ms
Feb 22 23:23:09 foo postfix/smtp[4040]: 434DE17318: to=<root@mydomain.com>, orig_to=<root>, relay=127.0.0.1[127.0.0.1]:10024, delay=368, delays=364/0.01/0/3.7, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 id=03325-03 - Temporary MTA failure on relaying, From MTA([127.0.0.1]:10025) during fwd-connect (Negative greeting: at (eval 98) line 596.): id=03325-03 (in reply to end of DATA command))
Feb 22 23:29:37 foo dovecot: master: Fatal: service(pop3) access(/usr/lib/dovecot/pop3) failed: No such file or directory
Feb 22 23:29:38 foo amavis[2179]: starting. /usr/sbin/amavisd-new at foo.mydomain.com amavisd-new-2.6.5 (20110407), Unicode aware
Feb 22 23:29:38 foo amavis[2179]: Perl version 5.014002
Feb 22 23:29:39 foo amavis[2473]: Net::Server: Group Not Defined. Defaulting to EGID '120 120'
Feb 22 23:29:39 foo amavis[2473]: Net::Server: User Not Defined. Defaulting to EUID '112'
Feb 22 23:29:39 foo amavis[2473]: Module Amavis::Conf 2.208
Feb 22 23:29:39 foo amavis[2473]: Module Archive::Zip 1.30
Feb 22 23:29:39 foo amavis[2473]: Module BerkeleyDB 0.49
Feb 22 23:29:39 foo amavis[2473]: Module Compress::Zlib 2.033
Feb 22 23:29:39 foo amavis[2473]: Module Convert::TNEF 0.17
Feb 22 23:29:39 foo amavis[2473]: Module Convert::UUlib 1.4
Feb 22 23:29:39 foo amavis[2473]: Module Crypt::OpenSSL::RSA 0.27
Feb 22 23:29:39 foo amavis[2473]: Module DB_File 1.821
Feb 22 23:29:39 foo amavis[2473]: Module Digest::MD5 2.51
Feb 22 23:29:39 foo amavis[2473]: Module Digest::SHA 5.61
Feb 22 23:29:39 foo amavis[2473]: Module IO::Socket::INET6 2.69
Feb 22 23:29:39 foo amavis[2473]: Module MIME::Entity 5.502
Feb 22 23:29:39 foo amavis[2473]: Module MIME::Parser 5.502
Feb 22 23:29:39 foo amavis[2473]: Module MIME::Tools 5.502
Feb 22 23:29:39 foo amavis[2473]: Module Mail::DKIM::Signer 0.39
Feb 22 23:29:39 foo amavis[2473]: Module Mail::DKIM::Verifier 0.39
Feb 22 23:29:39 foo amavis[2473]: Module Mail::Header 2.08
Feb 22 23:29:39 foo amavis[2473]: Module Mail::Internet 2.08
Feb 22 23:29:39 foo amavis[2473]: Module Mail::SPF v2.008
Feb 22 23:29:39 foo amavis[2473]: Module Mail::SpamAssassin 3.003002
Feb 22 23:29:39 foo amavis[2473]: Module Net::DNS 0.66
Feb 22 23:29:39 foo amavis[2473]: Module Net::Server 0.99
Feb 22 23:29:39 foo amavis[2473]: Module NetAddr::IP 4.058
Feb 22 23:29:39 foo amavis[2473]: Module Razor2::Client::Version 2.84
Feb 22 23:29:39 foo amavis[2473]: Module Socket6 0.23
Feb 22 23:29:39 foo amavis[2473]: Module Time::HiRes 1.972101
Feb 22 23:29:39 foo amavis[2473]: Module URI 1.59
Feb 22 23:29:39 foo amavis[2473]: Module Unix::Syslog 1.1
Feb 22 23:29:39 foo amavis[2473]: Amavis::DB code loaded
Feb 22 23:29:39 foo amavis[2473]: Amavis::Cache code loaded
Feb 22 23:29:39 foo amavis[2473]: SQL base code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: SQL::Log code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: SQL::Quarantine NOT loaded
Feb 22 23:29:39 foo amavis[2473]: Lookup::SQL code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: Lookup::LDAP code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: AM.PDP-in proto code loaded
Feb 22 23:29:39 foo amavis[2473]: SMTP-in proto code loaded
Feb 22 23:29:39 foo amavis[2473]: Courier proto code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: SMTP-out proto code loaded
Feb 22 23:29:39 foo amavis[2473]: Pipe-out proto code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: BSMTP-out proto code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: Local-out proto code loaded
Feb 22 23:29:39 foo amavis[2473]: OS_Fingerprint code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: ANTI-VIRUS code loaded
Feb 22 23:29:39 foo amavis[2473]: ANTI-SPAM code loaded
Feb 22 23:29:39 foo amavis[2473]: ANTI-SPAM-EXT code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: ANTI-SPAM-C code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: ANTI-SPAM-SA code loaded
Feb 22 23:29:39 foo amavis[2473]: Unpackers code loaded
Feb 22 23:29:39 foo amavis[2473]: DKIM code loaded
Feb 22 23:29:39 foo amavis[2473]: Tools code NOT loaded
Feb 22 23:29:39 foo amavis[2473]: Found $file at /usr/bin/file
Feb 22 23:29:39 foo amavis[2473]: No $altermime, not using it
Feb 22 23:29:39 foo amavis[2473]: Internal decoder for .mail
Feb 22 23:29:39 foo amavis[2473]: No decoder for .F
Feb 22 23:29:39 foo amavis[2473]: Found decoder for .Z at /bin/uncompress
Feb 22 23:29:39 foo amavis[2473]: Internal decoder for .gz
Feb 22 23:29:39 foo amavis[2473]: Found decoder for .bz2 at /bin/bzip2 -d
Feb 22 23:29:39 foo amavis[2473]: Found decoder for .lzo at /usr/bin/lzop -d
Feb 22 23:29:39 foo amavis[2473]: No decoder for .rpm tried: rpm2cpio.pl, rpm2cpio
Feb 22 23:29:39 foo amavis[2473]: Found decoder for .cpio at /bin/pax
Feb 22 23:29:39 foo amavis[2473]: Found decoder for .tar at /bin/pax
Feb 22 23:29:39 foo amavis[2473]: Found decoder for .deb at /usr/bin/ar
Feb 22 23:29:39 foo amavis[2473]: Internal decoder for .zip
Feb 22 23:29:39 foo amavis[2473]: No decoder for .7z tried: 7zr, 7za, 7z
Feb 22 23:29:39 foo amavis[2473]: No decoder for .rar tried: unrar-free
Feb 22 23:29:39 foo amavis[2473]: No decoder for .arj tried: arj, unarj
Feb 22 23:29:39 foo amavis[2473]: Found decoder for .arc at /usr/bin/nomarch
Feb 22 23:29:39 foo amavis[2473]: Found decoder for .zoo at /usr/bin/zoo
Feb 22 23:29:39 foo amavis[2473]: No decoder for .lha
Feb 22 23:29:39 foo amavis[2473]: No decoder for .doc tried: ripole
Feb 22 23:29:39 foo amavis[2473]: No decoder for .cab tried: cabextract
Feb 22 23:29:39 foo amavis[2473]: No decoder for .tnef
Feb 22 23:29:39 foo amavis[2473]: Internal decoder for .tnef
Feb 22 23:29:39 foo amavis[2473]: No decoder for .exe tried: unrar-free; arj, unarj
Feb 22 23:29:39 foo amavis[2473]: Using primary internal av scanner code for ClamAV-clamd
Feb 22 23:29:39 foo amavis[2473]: Found secondary av scanner ClamAV-clamscan at /usr/bin/clamscan
Feb 22 23:29:39 foo amavis[2473]: Creating db in /var/lib/amavis/db/; BerkeleyDB 0.49, libdb 5.1
Feb 22 23:29:47 foo authdaemond: modules="authmysql", daemons=5
Feb 22 23:29:47 foo authdaemond: Installing libauthmysql
Feb 22 23:29:47 foo authdaemond: Installation complete: authmysql
Feb 22 23:29:05 foo postfix/master[3124]: daemon started -- version 2.9.3, configuration /etc/postfix
The interesting bit is the last two lines - 23:29:47 followed by 23:29:05. I read that there is a backwards time bug in Dovecot but don't know if it's related.
This system worked fine (except for SquirrelMail, which had a broken IMAP connection when I tried to login. But running mailx form the shell did exactly what I wanted it to do.
This is extremely frustrating.