postfix is not running

[tabster]

Hi,

I installed ZCS 5.0.10 on my Debian machine. The only problem I currently have, is that postfix cannot be started. When typing "postfix start" as user zimbra I get the following:

zimbra@mailhost:~$ postfix start
postfix/postfix-script: warning: not owned by root: /opt/zimbra/postfix-2.4.7.5z/conf/main.cf
postfix/postfix-script: starting the Postfix mail system

I can change the owner of the main.cf-file to rootostfix as all other files in that directory or to root:root, but after running "postfix start" again, the owner is "zimbra:zimbra" and postfix is not running (as zmcontrol status says).

How can I solve this?

[phoenix]

Have a look in the log files and see what errors are in there. The ownership of that file is not a problem and not the reason postfix isn't starting. What does the output of the following show:

zmcontrol status

[travisb]

Do you have something else running on port 25? What do your logs show? /var/log/zimbra.log

[tabster]

zimbra@mailhost:~$ zmcontrol status
Host mailhost.xxMYDOMAINxx.de
        antispam                Running
        antivirus               Running
        ldap                    Running
        logger                  Running
        mailbox                 Running
        mta                     Stopped
                postfix is not running
                saslauthd is running with pid 16100
        snmp                    Running
        spell                   Running
        stats                   Running

/var/log/zimbra.log shows:

Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: antispam: Running
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: antivirus: Running
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: ldap: Running
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: logger: Running
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: mailbox: Running
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: mta: Stopped
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: saslauthd: is running with pid 16100
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: snmp: Running
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: spell: Running
Nov 27 18:36:12 mailhost zimbramon[31108]: 31108:info: 2008-11-27 18:36:01, STATUS: mailhost.xxMYDOMAINxx.de: stats: Running
Nov 27 18:36:12 mailhost CRON[31107]: (pam_unix) session closed for user zimbra
Nov 27 18:36:30 mailhost postfix/postqueue[31492]: fatal: Queue report unavailable - mail system is down
Nov 27 18:36:36 mailhost zmmailboxdmgr[31594]: status requested
Nov 27 18:36:36 mailhost zmmailboxdmgr[31594]: status OK
Nov 27 18:36:37 mailhost zmmailboxdmgr[31648]: status requested
Nov 27 18:36:37 mailhost zmmailboxdmgr[31648]: status OK

[phoenix]

That log output just tells the status of the system, the same as the zmcontrol does - what else is in the logs prior to the info you've posted?. What's the answer to the question travisb asked?

[tabster]

I don´t think I have another software using port 25. I only installed a basic system with apache and mysql. Is there an easy was to find out if there is a software listening on port 25?

I don´t know exactly what information from the log file could be helpful, but the length of messages here is also limited. So I copied something here. I hope, nothing important is missing:

Nov 27 17:51:46 mailhost slapd[9624]: @(#) $OpenLDAP: slapd 2.3.43 (Aug 20 2008 12:03:08) $ ^Iroot@build-debian-etch:/home/build/p4/FRANKLIN/ThirdParty/openldap/openldap-2.3.43.5z/servers/slapd 
Nov 27 17:51:47 mailhost slapd[9625]: slapd starting 
[...]
Nov 27 17:57:07 mailhost su[13269]: (pam_unix) session closed for user zimbra
Nov 27 17:57:11 mailhost su[13305]: (pam_unix) session opened for user zimbra by (uid=0)
Nov 27 17:57:12 mailhost zimbramon[13306]: 13306:info: Starting services initiated by zmcontrol 
Nov 27 17:57:17 mailhost zimbramon[13306]: 13306:info: Rewriting configs  antispam amavis antivirus amavis   webxml mailbox amavis antispam antivirus mta sasl    
Nov 27 17:57:44 mailhost zimbramon[13306]: 13306:info: Starting logger via zmcontrol 
Nov 27 17:57:45 mailhost zimbramon[13306]: 13306:info: Starting mailbox via zmcontrol 
Nov 27 17:57:46 mailhost zimbramon[13723]: 13723:info: zmmtaconfig: zmmtaconfig started on mailhost.xxMYDOMAINxx.de with loglevel=3 pid=13723 
Nov 27 17:58:01 mailhost CRON[13922]: (pam_unix) session opened for user zimbra by (uid=0)
Nov 27 17:58:23 mailhost zmmailboxdmgr[14203]: status requested
Nov 27 17:58:23 mailhost zmmailboxdmgr[14203]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
Nov 27 17:58:23 mailhost zmmailboxdmgr[14203]: assuming no other instance is running
Nov 27 17:58:23 mailhost zmmailboxdmgr[14203]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 27 17:58:23 mailhost zmmailboxdmgr[14203]: assuming no other instance is running
Nov 27 17:58:23 mailhost zmmailboxdmgr[14203]: no manager process is running
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: antispam: Stopped 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: antivirus: Stopped 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: ldap: Running 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: logger: Running 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: mailbox: Stopped 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: mta: Stopped 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: snmp: Stopped 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: spell: Stopped 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: Apache: not running.  No PID file found. 
Nov 27 17:58:33 mailhost zimbramon[13923]: 13923:info: 2008-11-27 17:58:01, STATUS: mailhost.xxMYDOMAINxx.de: stats: Stopped 
Nov 27 17:58:33 mailhost CRON[13922]: (pam_unix) session closed for user zimbra
Nov 27 17:59:25 mailhost zmmailboxdmgr[14721]: status requested
Nov 27 17:59:25 mailhost zmmailboxdmgr[14721]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
Nov 27 17:59:25 mailhost zmmailboxdmgr[14721]: assuming no other instance is running
Nov 27 17:59:25 mailhost zmmailboxdmgr[14721]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 27 17:59:25 mailhost zmmailboxdmgr[14721]: assuming no other instance is running
Nov 27 17:59:25 mailhost zmmailboxdmgr[14721]: no manager process is running
Nov 27 17:59:25 mailhost zmmailboxdmgr[14728]: start requested
Nov 27 17:59:25 mailhost zmmailboxdmgr[14728]: checking if another instance of manager is already running
Nov 27 17:59:25 mailhost zmmailboxdmgr[14728]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
Nov 27 17:59:25 mailhost zmmailboxdmgr[14728]: assuming no other instance is running
Nov 27 17:59:25 mailhost zmmailboxdmgr[14728]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 27 17:59:25 mailhost zmmailboxdmgr[14728]: assuming no other instance is running
Nov 27 17:59:25 mailhost zmmailboxdmgr[14729]: wrote manager pid 14729 to /opt/zimbra/log/zmmailboxd_manager.pid
Nov 27 17:59:25 mailhost zimbramon[13306]: 13306:info: Starting antispam via zmcontrol 
Nov 27 17:59:25 mailhost zmmailboxdmgr[14730]: wrote java pid 14730 to /opt/zimbra/log/zmmailboxd_java.pid
Nov 27 17:59:25 mailhost zmmailboxdmgr[14729]: manager started mailboxd/JVM with pid 14730
Nov 27 17:59:27 mailhost zimbramon[14738]: 14738:info: zmmtaconfig: zmmtaconfig already running at 13723 
Nov 27 17:59:34 mailhost amavis[14783]: starting.  /opt/zimbra/amavisd/sbin/amavisd at mailhost.xxMYDOMAINxx.de amavisd-new-2.5.4 (20080312), Unicode aware, LANG="C"
Nov 27 17:59:34 mailhost amavis[14783]: user=1000, EUID: 1000 (1000);  group=, EGID: 1001 1002 1001 5 4 (1001 1002 1001 5 4)
Nov 27 17:59:34 mailhost amavis[14783]: Perl version               5.008008
Nov 27 17:59:38 mailhost amavis[14783]: SpamControl: init_pre_chroot done
Nov 27 17:59:38 mailhost amavis[14801]: Net::Server: Process Backgrounded
Nov 27 17:59:38 mailhost amavis[14801]: Net::Server: 2008/11/27-17:59:38 Amavis (type Net::Server::PreForkSimple) starting! pid(14801)
Nov 27 17:59:38 mailhost amavis[14801]: Net::Server: Binding to UNIX socket file /opt/zimbra/data/amavisd/amavisd.sock using SOCK_STREAM
Nov 27 17:59:38 mailhost amavis[14801]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Nov 27 17:59:38 mailhost amavis[14801]: Net::Server: Group Not Defined.  Defaulting to EGID '1001 1002 1001 5 4'
Nov 27 17:59:38 mailhost amavis[14801]: Net::Server: User Not Defined.  Defaulting to EUID '1000'
Nov 27 17:59:38 mailhost amavis[14801]: Module Amavis::Conf        2.094
Nov 27 17:59:38 mailhost amavis[14801]: Module Archive::Zip        1.23
Nov 27 17:59:38 mailhost amavis[14801]: Module Compress::Zlib      1.42
Nov 27 17:59:38 mailhost amavis[14801]: Module Convert::TNEF       0.17
Nov 27 17:59:38 mailhost amavis[14801]: Module Convert::UUlib      1.11
Nov 27 17:59:38 mailhost amavis[14801]: Module DBD::mysql          4.007
Nov 27 17:59:38 mailhost amavis[14801]: Module DBI                 1.605
Nov 27 17:59:38 mailhost amavis[14801]: Module DB_File             1.817
Nov 27 17:59:38 mailhost amavis[14801]: Module Digest::MD5         2.36
Nov 27 17:59:38 mailhost amavis[14801]: Module Digest::SHA1        2.11
Nov 27 17:59:38 mailhost amavis[14801]: Module MIME::Entity        5.426
Nov 27 17:59:38 mailhost amavis[14801]: Module MIME::Parser        5.426
Nov 27 17:59:38 mailhost amavis[14801]: Module MIME::Tools         5.426
Nov 27 17:59:38 mailhost amavis[14801]: Module Mail::Header        2.03
Nov 27 17:59:38 mailhost amavis[14801]: Module Mail::Internet      2.03
Nov 27 17:59:38 mailhost amavis[14801]: Module Mail::SpamAssassin  3.002005
Nov 27 17:59:38 mailhost amavis[14801]: Module Net::DNS            0.63
Nov 27 17:59:38 mailhost amavis[14801]: Module Net::LDAP           0.36
Nov 27 17:59:38 mailhost amavis[14801]: Module Net::Server         0.97
Nov 27 17:59:38 mailhost amavis[14801]: Module Time::HiRes         1.9715
Nov 27 17:59:38 mailhost amavis[14801]: Module URI                 1.37
Nov 27 17:59:38 mailhost amavis[14801]: Module Unix::Syslog        1.1
Nov 27 17:59:38 mailhost amavis[14801]: Amavis::DB code      NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: Amavis::Cache code   NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: SQL base code        NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: SQL::Log code        NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: SQL::Quarantine      NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: Lookup::SQL code     NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: Lookup::LDAP code    loaded
Nov 27 17:59:38 mailhost amavis[14801]: AM.PDP-in proto code loaded
Nov 27 17:59:38 mailhost amavis[14801]: SMTP-in proto code   loaded
Nov 27 17:59:38 mailhost amavis[14801]: Courier proto code   NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: SMTP-out proto code  loaded
Nov 27 17:59:38 mailhost amavis[14801]: Pipe-out proto code  NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: BSMTP-out proto code NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: Local-out proto code loaded
Nov 27 17:59:38 mailhost amavis[14801]: OS_Fingerprint code  NOT loaded
Nov 27 17:59:38 mailhost amavis[14801]: ANTI-VIRUS code      loaded
Nov 27 17:59:38 mailhost amavis[14801]: ANTI-SPAM code       loaded
Nov 27 17:59:38 mailhost amavis[14801]: ANTI-SPAM-SA code    loaded
Nov 27 17:59:38 mailhost amavis[14801]: Unpackers code       loaded
Nov 27 17:59:38 mailhost amavis[14801]: Found $file            at /usr/bin/file
Nov 27 17:59:38 mailhost amavis[14801]: No $dspam,             not using it
Nov 27 17:59:38 mailhost amavis[14801]: No $altermime,         not using it
Nov 27 17:59:38 mailhost amavis[14801]: Internal decoder for .mail
Nov 27 17:59:38 mailhost amavis[14801]: Internal decoder for .asc 
Nov 27 17:59:38 mailhost amavis[14801]: Internal decoder for .uue 
Nov 27 17:59:38 mailhost amavis[14801]: Internal decoder for .hqx 
Nov 27 17:59:38 mailhost amavis[14801]: Internal decoder for .ync 
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .F    tried: unfreeze, freeze -d, melt, fcat
Nov 27 17:59:38 mailhost amavis[14801]: Found decoder for    .Z    at /bin/uncompress
Nov 27 17:59:38 mailhost amavis[14801]: Found decoder for    .gz   at /bin/gzip -d
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .bz2  tried: bzip2 -d
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .lzo  tried: lzop -d
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .rpm  tried: rpm2cpio.pl, rpm2cpio
Nov 27 17:59:38 mailhost amavis[14801]: Found decoder for    .cpio at /bin/cpio
Nov 27 17:59:38 mailhost amavis[14801]: Found decoder for    .tar  at /bin/cpio
Nov 27 17:59:38 mailhost amavis[14801]: Found decoder for    .deb  at /usr/bin/ar
Nov 27 17:59:38 mailhost amavis[14801]: Internal decoder for .zip 
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .7z   tried: 7zr, 7za, 7z
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .rar  tried: rar, unrar
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .arj  tried: arj, unarj
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .arc  tried: nomarch, arc
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .zoo  tried: zoo, unzoo
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .lha  tried: lha
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .cab  tried: cabextract
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .tnef tried: tnef
Nov 27 17:59:38 mailhost amavis[14801]: Internal decoder for .tnef
Nov 27 17:59:38 mailhost amavis[14801]: No decoder for       .exe  tried: rar, unrar; lha; arj, unarj
Nov 27 17:59:38 mailhost amavis[14801]: Using primary internal av scanner code for ClamAV-clamd
Nov 27 17:59:38 mailhost amavis[14801]: SpamControl: initializing Mail::SpamAssassin
Nov 27 17:59:42 mailhost zimbramon[13306]: 13306:info: Starting antivirus via zmcontrol 
Nov 27 17:59:42 mailhost amavis[14801]: SpamControl: init_pre_fork done
Nov 27 17:59:46 mailhost zimbramon[14847]: 14847:info: zmmtaconfig: zmmtaconfig already running at 13723 
Nov 27 17:59:50 mailhost clamd[14930]: clamd daemon 0.93.3 (OS: linux-gnu, ARCH: i386, CPU: i686) 
Nov 27 17:59:50 mailhost clamd[14930]: Log file size limited to 20971520 bytes. 
Nov 27 17:59:50 mailhost clamd[14930]: Reading databases from /opt/zimbra/data/clamav/db 
Nov 27 17:59:50 mailhost clamd[14930]: Not loading PUA signatures. 
Nov 27 18:00:02 mailhost CRON[14982]: (pam_unix) session opened for user zimbra by (uid=0)
Nov 27 18:00:02 mailhost CRON[14983]: (pam_unix) session opened for user zimbra by (uid=0)
Nov 27 18:00:02 mailhost CRON[14984]: (pam_unix) session opened for user zimbra by (uid=0)
Nov 27 18:00:02 mailhost CRON[14985]: (pam_unix) session opened for user zimbra by (uid=0)
Nov 27 18:00:03 mailhost CRON[14986]: (pam_unix) session opened for user zimbra by (uid=0)
Nov 27 18:00:03 mailhost CRON[14984]: (pam_unix) session closed for user zimbra
Nov 27 18:00:06 mailhost postfix/postqueue[15025]: fatal: Queue report unavailable - mail system is down
Nov 27 18:00:07 mailhost zimbramon[14987]: 14987:info: 2008-11-27 18:00:03, QUEUE: 0 0 
Nov 27 18:00:07 mailhost CRON[14982]: (pam_unix) session closed for user zimbra
Nov 27 18:00:12 mailhost zimbramon[14991]: 14991:info: 2008-11-27 18:00:03, DISK: mailhost.xxMYDOMAINxx.de: dev: /dev/hda1, mp: /, tot: 3845, avail: 2020 
Nov 27 18:00:12 mailhost zimbramon[14991]: 14991:info: 2008-11-27 18:00:03, DISK: mailhost.xxMYDOMAINxx.de: dev: /dev/hda2, mp: /opt, tot: 13602, avail: 11610 
Nov 27 18:00:12 mailhost CRON[14985]: (pam_unix) session closed for user zimbra
Nov 27 18:00:15 mailhost clamd[14930]: Loaded 344241 signatures. 
Nov 27 18:00:15 mailhost clamd[14930]: TCP: Bound to port 3310 
Nov 27 18:00:15 mailhost clamd[14930]: TCP: Setting connection queue length to 15 
Nov 27 18:00:15 mailhost clamd[15154]: Limits: Global size limit set to 104857600 bytes. 
Nov 27 18:00:15 mailhost clamd[15154]: Limits: File size limit set to 104857600 bytes. 
Nov 27 18:00:15 mailhost clamd[15154]: Limits: Recursion level limit set to 16. 
Nov 27 18:00:15 mailhost clamd[15154]: Limits: Files limit set to 10000. 
Nov 27 18:00:15 mailhost clamd[15154]: Archive support enabled. 
Nov 27 18:00:15 mailhost clamd[15154]: Archive: Blocking encrypted archives. 
Nov 27 18:00:15 mailhost clamd[15154]: Algorithmic detection enabled. 
Nov 27 18:00:15 mailhost clamd[15154]: Portable Executable support enabled. 
Nov 27 18:00:15 mailhost clamd[15154]: ELF support enabled. 
Nov 27 18:00:15 mailhost clamd[15154]: Mail files support enabled. 
Nov 27 18:00:15 mailhost clamd[15154]: OLE2 support enabled. 
Nov 27 18:00:15 mailhost clamd[15154]: PDF support disabled. 
Nov 27 18:00:15 mailhost clamd[15154]: HTML support enabled. 
Nov 27 18:00:15 mailhost clamd[15154]: Self checking every 1800 seconds. 
Nov 27 18:00:20 mailhost zimbramon[13306]: 13306:info: Starting snmp via zmcontrol 
Nov 27 18:00:21 mailhost zimbramon[13306]: 13306:info: Starting spell via zmcontrol 
Nov 27 18:00:31 mailhost zimbramon[13306]: 13306:info: Starting mta via zmcontrol 
Nov 27 18:00:38 mailhost zimbramon[15272]: 15272:info: zmmtaconfig: zmmtaconfig already running at 13723 
Nov 27 18:00:50 mailhost CRON[14983]: (pam_unix) session closed for user zimbra
Nov 27 18:00:50 mailhost zmmailboxdmgr[15479]: status requested
Nov 27 18:00:50 mailhost zmmailboxdmgr[15479]: status OK

[gnyce]

netstat -an|grep 25

will tell you if anything is listening on port 25. If there is... you'll need to obviously stop it (and keep it from starting again next reboot).

[phoenix]

Do the following:

su - zimbra
zmcontrol stop

telnet localhost 25

If you get a response from that then you have another mail server running. I don't know debian but check which mail server is installed and stop it, you should also stop it's service from starting at boot time.

[tabster]

Okay, there was exim4 installed. I uninstalled it and now I get:

zimbra@mailhost:~$ zmcontrol status
Host mailhost.xxMYDOMAINxx.de
        antispam                Running
        antivirus               Running
        ldap                    Running
        logger                  Running
        mailbox                 Running
        mta                     Running
        snmp                    Running
        spell                   Running
        stats                   Running

So it should work now. Thank you!

[phoenix]

Yes, that looks fine - enjoy.