Hello, back again with problems after updates.. On a stable centos 5.4, running ispconfig 3.0.2.1: -I updated the system with yum update --exclude="postfix" --disablerepo=kbs-CentOS-Testing -I did a freshclam after removing the duplicated files (i saw the error during the update) -I updated ispconfig to 3.0.2.2, using all default options. -I ran sa-update I have tried all of these steps in many different orders, rebooting very often. In this order I am near to a stable system again. However, postfix will not run. When I start postfix by hand, it does work. Updating ispconfig first and then running yum for instance, results in an unusable system. I'll include a piece of maillog after i ran /etc/postfix start (before that there's only pop3d entries) I suspect it has something to do with the following line: Jul 29 16:52:18 mf-vm-deepspace2 amavis[2324]: (02324-01) (!!)WARN: all primary virus scanners failed, considering backups but I don't know what to do about it, as I already tried sa-update. Can anyone tell me what I'm doing wrong? Thanks a lot! Jul 29 16:51:55 mf-vm-deepspace2 sendmail[3030]: alias database /etc/aliases rebuilt by root Jul 29 16:51:55 mf-vm-deepspace2 sendmail[3030]: /etc/aliases: 77 aliases, longest 10 bytes, 779 bytes total Jul 29 16:51:56 mf-vm-deepspace2 postfix/postfix-script: starting the Postfix mail system Jul 29 16:51:56 mf-vm-deepspace2 postfix/master[3076]: daemon started -- version 2.3.3, configuration /etc/postfix Jul 29 16:52:17 mf-vm-deepspace2 postfix/smtpd[3119]: connect from relay0.transip.com[80.69.67.21] Jul 29 16:52:18 mf-vm-deepspace2 postfix/smtpd[3119]: 20DA110F3E02: client=relay0.transip.com[80.69.67.21] Jul 29 16:52:18 mf-vm-deepspace2 postfix/cleanup[3132]: 20DA110F3E02: message-id=<010401cb2f2c$89d204e0$9d760ea0$@nl> Jul 29 16:52:18 mf-vm-deepspace2 postfix/smtpd[3119]: disconnect from relay0.transip.com[80.69.67.21] Jul 29 16:52:18 mf-vm-deepspace2 postfix/qmgr[3078]: 20DA110F3E02: from=<[email protected]>, size=3705, nrcpt=1 (queue active) Jul 29 16:52:18 mf-vm-deepspace2 amavis[2324]: (02324-01) (!!)WARN: all primary virus scanners failed, considering backups Jul 29 16:52:28 mf-vm-deepspace2 postfix/smtpd[3138]: connect from unknown[127.0.0.1] Jul 29 14:52:28 mf-vm-deepspace2 postfix/smtpd[3138]: ADAD110F3E04: client=unknown[127.0.0.1] Jul 29 16:52:28 mf-vm-deepspace2 postfix/cleanup[3132]: ADAD110F3E04: message-id=<010401cb2f2c$89d204e0$9d760ea0$@nl> Jul 29 16:52:28 mf-vm-deepspace2 postfix/qmgr[3078]: ADAD110F3E04: from=<[email protected]>, size=4193, nrcpt=1 (queue active) Jul 29 16:52:28 mf-vm-deepspace2 amavis[2324]: (02324-01) Passed CLEAN, [80.69.67.21] [83.161.234.240] <[email protected]> -> <[email protected]>, Message-ID: <010401cb2f2c$89d204e0$9d760ea0$@nl>, mail_id: 9xGI-L5zY7cl, Hits: -0.699, size: 3704, queued_as: ADAD110F3E04, 10623 ms Jul 29 16:52:28 mf-vm-deepspace2 postfix/smtp[3133]: 20DA110F3E02: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=11, delays=0.1/0.03/0.05/11, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=02324-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as ADAD110F3E04) Jul 29 16:52:28 mf-vm-deepspace2 postfix/qmgr[3078]: 20DA110F3E02: removed Jul 29 16:52:28 mf-vm-deepspace2 postfix/pipe[3140]: ADAD110F3E04: to=<[email protected]>, relay=maildrop, delay=0.28, delays=0.04/0.05/0/0.19, dsn=2.0.0, status=sent (delivered via maildrop service) Jul 29 16:52:28 mf-vm-deepspace2 postfix/qmgr[3078]: ADAD110F3E04: removed Jul 29 16:52:51 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 16:52:51 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[63908] Jul 29 16:52:51 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[63908], top=0, retr=4153, rcvd=40, sent=4451, time=0
According to this log, postfix is running. Please restart postfix, if it failed, post the log entrys that have been added to the mail log. This order does not work as yum overwrites the configuration and makes the system unusable. The other way, updating centos first and then ispconfig works as ispconfig trys to repair the files that yum has overwritten.
Logfile I am sorry i misinterpreted your reply, so my reply is kind ofuseless. However it does show what happens before postfix is started. The log above is what happens after I start postfix. when i restart postfix i get a fail and an ok, after that the system runs fine. No errors in the log, only what I posted above. Could it be something to do with amavis? Here's maillog after a reboot: at 18:05:38 (server time) I sent a message, it never arrived. Jul 29 17:54:46 mf-vm-deepspace2 authdaemond: modules="authmysql", daemons=5 Jul 29 17:54:46 mf-vm-deepspace2 authdaemond: Installing libauthmysql Jul 29 17:54:47 mf-vm-deepspace2 authdaemond: Installation complete: authmysql Jul 29 17:55:07 mf-vm-deepspace2 amavis[2190]: starting. /usr/sbin/amavisd at mf-vm-deepspace2.deepspace.nl amavisd-new-2.6.4 (20090625), Unicode aware, LANG="en_US.UTF-8" Jul 29 17:55:07 mf-vm-deepspace2 amavis[2190]: Perl version 5.008008 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Net::Server: Group Not Defined. Defaulting to EGID '106 106' Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Net::Server: User Not Defined. Defaulting to EUID '102' Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Amavis::Conf 2.207 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Archive::Zip 1.30 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module BerkeleyDB 0.41 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Compress::Zlib 2.024 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Convert::TNEF 0.17 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Convert::UUlib 1.12 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Crypt::OpenSSL::RSA 0.25 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module DBD::mysql 4.014 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module DBI 1.611 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module DB_File 1.814 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Digest::MD5 2.36 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Digest::SHA 5.48 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Digest::SHA1 2.12 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module IO::Socket::INET6 2.57 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module MIME::Entity 5.420 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module MIME:arser 5.420 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module MIME::Tools 5.420 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Mail:KIM::Signer 0.37 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Mail:KIM::Verifier 0.37 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Mail::Header 2.06 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Mail::Internet 2.06 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Mail::SPF v2.006 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Mail::SpamAssassin 3.003001 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Net:NS 0.66 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Net::Server 0.97 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module NetAddr::IP 4.027 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Razor2::Client::Version 2.84 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Socket6 0.23 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Time::HiRes 1.9717 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module URI 1.35 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Module Unix::Syslog 1.1 Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Amavis:B code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Amavis::Cache code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: SQL base code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: SQL::Log code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: SQL::Quarantine NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Lookup::SQL code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Lookup::LDAP code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: AM.PDP-in proto code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: SMTP-in proto code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Courier proto code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: SMTP-out proto code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Pipe-out proto code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: BSMTP-out proto code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Local-out proto code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: OS_Fingerprint code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: ANTI-VIRUS code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: ANTI-SPAM code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: ANTI-SPAM-EXT code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: ANTI-SPAM-C code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: ANTI-SPAM-SA code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Unpackers code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: DKIM code loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Tools code NOT loaded Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found $file at /usr/bin/file Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: No $altermime, not using it Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Internal decoder for .mail Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Internal decoder for .asc Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Internal decoder for .uue Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Internal decoder for .hqx Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Internal decoder for .ync Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .F at /usr/bin/unfreeze Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .Z at /usr/bin/uncompress Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .gz at /usr/bin/gzip -d Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .bz2 at /usr/bin/bzip2 -d Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .lzo at /usr/bin/lzop -d Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .rpm at /usr/bin/rpm2cpio Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .cpio at /usr/bin/pax Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .tar at /usr/bin/pax Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .deb at /usr/bin/ar Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Internal decoder for .zip Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .7z at /usr/bin/7za Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .rar at /usr/bin/unrar Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .arj at /usr/bin/arj Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .arc at /usr/bin/nomarch Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .zoo at /usr/bin/zoo Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .lha at /usr/bin/lha Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .cab at /usr/bin/cabextract Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: No decoder for .tnef tried: tnef Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Internal decoder for .tnef Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found decoder for .exe at /usr/bin/unrar; /usr/bin/lha; /usr/bin/arj Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Found secondary av scanner ClamAV-clamscan at /usr/bin/clamscan Jul 29 17:55:08 mf-vm-deepspace2 amavis[2191]: Creating db in /var/amavis/db/; BerkeleyDB 0.41, libdb 4.3 Jul 29 17:55:11 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 17:55:11 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49716] Jul 29 17:55:11 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49716], top=0, retr=0, rcvd=12, sent=39, time=0 Jul 29 17:56:16 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 17:56:16 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49738] Jul 29 17:56:16 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49738], top=0, retr=0, rcvd=12, sent=39, time=0 Jul 29 17:57:19 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 17:57:19 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49751] Jul 29 17:57:19 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49751], top=0, retr=0, rcvd=12, sent=39, time=0 Jul 29 17:58:23 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 17:58:24 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49767] Jul 29 17:58:24 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49767], top=0, retr=0, rcvd=12, sent=39, time=0 Jul 29 17:59:27 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 17:59:27 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49789] Jul 29 17:59:28 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49789], top=0, retr=0, rcvd=12, sent=39, time=1 Jul 29 18:00:02 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:127.0.0.1] Jul 29 18:00:02 mf-vm-deepspace2 pop3d: Disconnected, ip=[::ffff:127.0.0.1] Jul 29 18:00:02 mf-vm-deepspace2 imapd: Connection, ip=[::ffff:127.0.0.1] Jul 29 18:00:02 mf-vm-deepspace2 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0 Jul 29 18:00:31 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 18:00:32 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49816] Jul 29 18:00:32 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49816], top=0, retr=0, rcvd=12, sent=39, time=1 Jul 29 18:01:35 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 18:01:36 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49833] Jul 29 18:01:36 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49833], top=0, retr=0, rcvd=12, sent=39, time=0 Jul 29 18:02:39 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 18:02:39 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49856] Jul 29 18:02:39 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49856], top=0, retr=0, rcvd=12, sent=39, time=0 Jul 29 18:03:43 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 18:03:43 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[49928] Jul 29 18:03:43 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[49928], top=0, retr=0, rcvd=12, sent=39, time=0 Jul 29 18:04:50 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 18:04:50 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[50006] Jul 29 18:04:50 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[50006], top=0, retr=0, rcvd=12, sent=39, time=0 Jul 29 18:05:05 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:127.0.0.1] Jul 29 18:05:05 mf-vm-deepspace2 pop3d: Disconnected, ip=[::ffff:127.0.0.1] Jul 29 18:05:05 mf-vm-deepspace2 imapd: Connection, ip=[::ffff:127.0.0.1] Jul 29 18:05:05 mf-vm-deepspace2 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0 Jul 29 18:05:59 mf-vm-deepspace2 pop3d: Connection, ip=[::ffff:83.161.234.240] Jul 29 18:06:00 mf-vm-deepspace2 pop3d: LOGIN, [email protected], ip=[::ffff:83.161.234.240], port=[50097] Jul 29 18:06:00 mf-vm-deepspace2 pop3d: LOGOUT, [email protected], ip=[::ffff:83.161.234.240], port=[50097], top=0, retr=0, rcvd=12, sent=39, time=0
Postfix running I realize it was just a matter of postfix not being set to start at boot. Was it the yum update that disabled it then, I wonder. I am also still worried about the amisvd error message, or is this not a problem? This fixed the problem for me: [root@mf-vm- init.d]# chkconfig postfix --list service postfix supports chkconfig, but is not referenced in any runlevel (run 'chkconfig --add postfix') [root@mf-vm- init.d]# chkconfig --add postfix [root@mf-vm- init.d]# chkconfig postfix --list postfix 0 : off 1 : off 2 : on 3 : on 4 : on 5 : on 6 : off [root@mf-vm- init.d]# reboot
Yes, thats yum. ISPConfig is not able to enable or disable the startup of postfix. Regarding the amavisd error, run these commands (see perfect server guide): Code: mkdir /var/run/amavisd /var/spool/amavisd /var/spool/amavisd/tmp /var/spool/amavisd/db chown amavis /var/run/amavisd /var/spool/amavisd /var/spool/amavisd/tmp /var/spool/amavisd/db ln -s /var/run/clamav/clamd.sock /var/spool/amavisd/clamd.sock
I see, so the following, Code: yum update --exclude="postfix" --disablerepo=kbs-CentOS-Testing ,is not sufficient for preventing yum to keep it's hands off of postfix. Is there a way of finding out what update caused postfix to be turned off? So I can try to prevent this from happening again? Thanks Florisjan
Maybe a CentOS dev can help you with that. I'am not that familar with the details of yum. Centos is causing me too much problems when you install updates, so I use only Debian for my production systems.
