unable to restart zimbra after reboot

[droefs]

Hello Everybody!

Last week I made a neat multi-server install of Zimbra on Debian. Everything was running extra smootchie, untill a power-outage over the weekend made the three machines reboot.

Nothing is coming back up Zimbra-wise. If I #netstat -at none of the expected servers is showing. This is what my investigation brought me so far:

/etc/rc*.d/ scripts are pointing at an inexistent /etc/init.d/zimbra

If I try the Zimbra-CLI, this is what I get:

# su - zimbra
$ zmcontrol start
Host langoest.ugent.be
$ zmcontrol status
Cannot determine services - exiting
$ zmcontrol stop
Host langoest.ugent.be
Stopping antispam...Done
Stopping antivirus...Done
Stopping logger...Done
Stopping mailbox...FAILED
Error: tomcat not running (pid 3670), stale pid file?
Killing mysqld with pid 3725 /opt/zimbra/bin/mysql.server: line 58: kill: (3725) - No such process
.............................. gave up waiting!
Stopping snmp...Done
Stopping spell...Done

So I gave it a try starting different components manually:

# /opt/zimbra/httpd/bin/httpd -k start
# netstat -at | grep www
tcp6 0 0 *:www *:* LISTEN
# tail /opt/zimbra/httpd/logs/access_log

# /opt/zimbra/mysql/bin/mysqld
060410 16:19:34 [ERROR] Can't find messagefile '/usr/local/mysql/share/mysql/english/errmsg.sys'
060410 16:19:34 [ERROR] Aborting

# su - zimbra
$ /opt/zimbra/tomcat/bin/startup.sh
Using CATALINA_BASE: /opt/zimbra/tomcat
Using CATALINA_HOME: /opt/zimbra/tomcat
Using CATALINA_TMPDIR: /opt/zimbra/tomcat/temp
Using JRE_HOME: /opt/zimbra/java
$ exit
# netstat -at | grep localhost
tcp6 0 0 localhost:8005 *:* LISTEN

This brings up some stuff, but it's not really clear to me what good it will do me.
# less /var/log/zimbra.log
Gives me two kind of lines, some that say something like

Apr 11 11:10:03 langoest zimbramon[25012]: 25012:info: 2006-04-11 11:10:02, DISK: langoest.ugent.be: dev: /dev/sda1, mp: /, tot: 3706, avail: 3358

And others:

Apr 11 11:10:04 langoest zimbramon[25008]: 25008:info: 2006-04-11 11:10:02, STATUS: : Cannot: determine

Which I find quite alarming. (The same information is found with # grep zimbra /var/log/messages.)

Where do I take it from here? All help is greatly appreciated.

Thanks

DRoefs - Ghent University

[KevinH]

Do you have any firewall (iptables) or SELinux running?

Try:

ps -ef | grep zimbra

See what is running. Then on the LDAP server run:

su - zimbra
zmcontrol start

Then try:

telnet 0 389

[droefs]

Thanks for the reply, this is what I got out of it:

First check the processes on the Zimbra Store:

langoest:~# /opt/zimbra/httpd/bin/httpd -k stop
langoest:~# ps -ef | grep zimbra
zimbra 25211 1 0 11:15 ? 00:00:01 /opt/zimbra/java/bin/java -Djava.endorsed.dirs=/opt/zimbra/tomcat/common/endorsed -classpath :/opt/zimbra/tomcat/bin/bootstrap.jar:/opt/zimbra/tomcat/bin/commons-logging-api.jar -Dcatalina.base=/opt/zimbra/tomcat -Dcatalina.home=/opt/zimbra/tomcat -Djava.io.tmpdir=/opt/zimbra/tomcat/temp org.apache.catalina.startup.Bootstrap start
root 31305 1530 0 15:47 pts/0 00:00:00 grep zimbra

Then I tried starting up the services on the three machines in the same order as they were installed: 1=LDAP (garnaal), 2=MTA (krab), 3=Store (langoest).

1.LDAP server:

garnaal:/etc# su - zimbra
zimbra@garnaal:~$ zmcontrol start
Host garnaal.ugent.be
Starting ldap...Done.
zimbra@garnaal:~$ zmcontrol status
Host garnaal.ugent.be
ldap Running

Surprise surprise, this one does start!

2.MTA server:

zimbra@krab:~$ zmcontrol start
Host krab.ugent.be
Starting antispam...Done.
Starting antivirus...Done.
Starting mta...FAILED
getService: sasl
getService: webxml
getService: mailbox
getService: perdition
DO: /opt/zimbra/postfix/sbin/postconf -e content_filter='smtp-amavis :[127.0.0.1]:10024'
DO: /opt/zimbra/postfix/sbin/postconf -e myhostname='krab.ugent.be'
DO: /opt/zimbra/postfix/sbin/postconf -e recipient_delimiter=''
DO: /opt/zimbra/postfix/sbin/postconf -e smtpd_sasl_auth_enable='yes '
DO: /opt/zimbra/postfix/sbin/postconf -e smtpd_tls_auth_only='yes'
DO: /opt/zimbra/postfix/sbin/postconf -e disable_dns_lookups='no'
DO: /opt/zimbra/postfix/sbin/postconf -e message_size_limit='1024000 0'
DO: /opt/zimbra/postfix/sbin/postconf -e relayhost=''
DO: /opt/zimbra/postfix/sbin/postconf -e smtpd_recipient_restriction s='reject_non_fqdn_recipient, permit_sasl_authenticated, permit_myne tworks, reject_invalid_hostname, reject_non_fqdn_sender, reject_unau th_destination, permit'
saslauthd[2245] :set_auth_mech : failed to initialize mechanism zi mbra

zimbra@krab:~$ zmcontrol status
Host krab.ugent.be
antispam Running
antivirus Running
mta Stopped

Darn. That doesn't work.

3. Mail Store

langoest:~# su - zimbra
zimbra@langoest:~$ zmcontrol start
Host langoest.ugent.be
Starting logger...Done.
Starting mailbox...Done.
Starting spell...Done.
zimbra@langoest:~$ zmcontrol status
Host langoest.ugent.be
logger Running
mailbox Running
spell Running

Woohoo! Much better than before. I still get a "connection refused" when trying to open the admin web interface on langoest.ugent.be:7071, though.

Telneting the LDAP server from the Mail Store (langoest:~# telnet garnaal 389) works.

Thanks a lot! Now, how do I fix the MTA and the webinterface?

PS: no iptables or SELinux; the whole setup worked just fine before the brute power cycling occured.

[KevinH]

What's in /var/log/zimbra.log on the MTA host?

[droefs]

"$ zmcontrol start" causes these entries:

Apr 11 17:19:03 krab zimbramon[12402]: 12402:info: Starting services
Apr 11 17:19:05 krab zimbramon[12402]: 12402:info: Starting antispam
Apr 11 17:19:17 krab zimbramon[12402]: 12402:info: Starting antivirus
Apr 11 17:19:18 krab amavis[12648]: starting. /opt/zimbra/amavisd/sbin/amavisd at krab.ugent.be amavisd-new-2.3.3 (20050822), Unicode aware
Apr 11 17:19:18 krab amavis[12648]: user=1000, EUID: 1000 (1000); group=, EGID: 106 107 106 5 (106 107 106 5)
Apr 11 17:19:18 krab amavis[12648]: Perl version 5.008004
Apr 11 17:19:19 krab amavis[12668]: Net::Server: Process Backgrounded
Apr 11 17:19:19 krab amavis[12668]: Net::Server: 2006/04/11-17:19:19 Amavis (type Net::Server::PreForkSimple) starting! pid(12668)
Apr 11 17:19:19 krab amavis[12668]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Apr 11 17:19:19 krab amavis[12668]: Net::Server: Group Not Defined. Defaulting to EGID '106 107 106 5'
Apr 11 17:19:19 krab amavis[12668]: Net::Server: User Not Defined. Defaulting to EUID '1000'
Apr 11 17:19:19 krab amavis[12668]: Module Amavis::Conf 2.043
Apr 11 17:19:19 krab amavis[12668]: Module Archive::Tar 1.26
Apr 11 17:19:19 krab amavis[12668]: Module Archive::Zip 1.16
Apr 11 17:19:19 krab amavis[12668]: Module BerkeleyDB 0.27
Apr 11 17:19:19 krab amavis[12668]: Module Compress::Zlib 1.41
Apr 11 17:19:19 krab amavis[12668]: Module Convert::TNEF 0.17
Apr 11 17:19:19 krab amavis[12668]: Module Convert::UUlib 1.06
Apr 11 17:19:19 krab amavis[12668]: Module DBD::mysql 3.0002
Apr 11 17:19:19 krab amavis[12668]: Module DBI 1.50
Apr 11 17:19:19 krab amavis[12668]: Module DB_File 1.814
Apr 11 17:19:19 krab amavis[12668]: Module MIME::Entity 5.419
Apr 11 17:19:19 krab amavis[12668]: Module MIME::Parser 5.419
Apr 11 17:19:19 krab amavis[12668]: Module MIME::Tools 5.419
Apr 11 17:19:19 krab amavis[12668]: Module Mail::Header 1.71
Apr 11 17:19:19 krab amavis[12668]: Module Mail::Internet 1.71
Apr 11 17:19:19 krab amavis[12668]: Module Mail::SpamAssassin 3.001000
Apr 11 17:19:19 krab amavis[12668]: Module Net::Cmd 2.24
Apr 11 17:19:19 krab amavis[12668]: Module Net:NS 0.55
Apr 11 17:19:19 krab amavis[12668]: Module Net::LDAP 0.33
Apr 11 17:19:19 krab amavis[12668]: Module Net::SMTP 2.26
Apr 11 17:19:19 krab amavis[12668]: Module Net::Server 0.90
Apr 11 17:19:19 krab amavis[12668]: Module Time::HiRes 1.86
Apr 11 17:19:19 krab amavis[12668]: Module Unix::Syslog 0.99
Apr 11 17:19:19 krab amavis[12668]: Amavis:B code loaded
Apr 11 17:19:19 krab amavis[12668]: Amavis::Cache code loaded
Apr 11 17:19:19 krab amavis[12668]: SQL base code NOT loaded
Apr 11 17:19:19 krab amavis[12668]: SQL::Log code NOT loaded
Apr 11 17:19:19 krab amavis[12668]: SQL::Quarantine NOT loaded
Apr 11 17:19:19 krab amavis[12668]: Lookup::SQL code NOT loaded
Apr 11 17:19:19 krab amavis[12668]: Lookup::LDAP code loaded
Apr 11 17:19:19 krab amavis[12668]: AM.PDP prot code NOT loaded
Apr 11 17:19:19 krab amavis[12668]: SMTP-in prot code loaded
Apr 11 17:19:19 krab amavis[12668]: ANTI-VIRUS code loaded
Apr 11 17:19:19 krab amavis[12668]: ANTI-SPAM code loaded
Apr 11 17:19:19 krab amavis[12668]: Unpackers code loaded
Apr 11 17:19:19 krab amavis[12668]: Found $file at /usr/bin/file
Apr 11 17:19:19 krab amavis[12668]: No $dspam, not using it
Apr 11 17:19:19 krab amavis[12668]: Internal decoder for .mail
Apr 11 17:19:19 krab amavis[12668]: Internal decoder for .asc
Apr 11 17:19:19 krab amavis[12668]: Internal decoder for .uue
Apr 11 17:19:19 krab amavis[12668]: Internal decoder for .hqx
Apr 11 17:19:19 krab amavis[12668]: Internal decoder for .ync
Apr 11 17:19:19 krab amavis[12668]: No decoder for .F tried: unfreeze, freeze -d, melt, fcat
Apr 11 17:19:19 krab amavis[12668]: Found decoder for .Z at /bin/uncompress
Apr 11 17:19:19 krab amavis[12668]: Internal decoder for .gz
Apr 11 17:19:19 krab amavis[12668]: Found decoder for .bz2 at /usr/bin/bzip2 -d
Apr 11 17:19:19 krab amavis[12668]: No decoder for .lzo tried: lzop -d
Apr 11 17:19:19 krab amavis[12668]: No decoder for .rpm tried: rpm2cpio.pl, rpm2cpio
Apr 11 17:19:19 krab amavis[12668]: Found decoder for .cpio at /bin/cpio
Apr 11 17:19:19 krab amavis[12668]: Found decoder for .tar at /bin/cpio
Apr 11 17:19:19 krab amavis[12668]: Found decoder for .deb at /usr/bin/ar
Apr 11 17:19:19 krab amavis[12668]: Internal decoder for .zip
Apr 11 17:19:19 krab amavis[12668]: No decoder for .rar tried: rar, unrar
Apr 11 17:19:19 krab amavis[12668]: No decoder for .arj tried: arj, unarj
Apr 11 17:19:19 krab amavis[12668]: No decoder for .arc tried: nomarch, arc
Apr 11 17:19:19 krab amavis[12668]: No decoder for .zoo tried: zoo
Apr 11 17:19:19 krab amavis[12668]: No decoder for .lha tried: lha
Apr 11 17:19:19 krab amavis[12668]: No decoder for .cab tried: cabextract
Apr 11 17:19:19 krab amavis[12668]: No decoder for .tnef tried: tnef
Apr 11 17:19:19 krab amavis[12668]: Internal decoder for .tnef
Apr 11 17:19:19 krab amavis[12668]: No decoder for .exe tried: rar, unrar; lha; arj, unarj
Apr 11 17:19:19 krab amavis[12668]: Using internal av scanner code for (primary) ClamAV-clamd
Apr 11 17:19:19 krab amavis[12668]: Creating db in /opt/zimbra/amavisd/db/; BerkeleyDB 0.27, libdb 4.2
Apr 11 17:19:19 krab amavis[12668]: SpamControl: initializing Mail::SpamAssassin
Apr 11 17:19:20 krab amavis[12668]: SpamControl: done
Apr 11 17:19:28 krab clamd[12818]: Daemon started.
Apr 11 17:19:28 krab clamd[12818]: clamd daemon 0.87.1 (OS: linux-gnu, ARCH: i386, CPU: i686)
Apr 11 17:19:28 krab clamd[12818]: Log file size limited to 20971520 bytes.
Apr 11 17:19:28 krab clamd[12818]: Reading databases from /opt/zimbra/clamav/db
Apr 11 17:19:29 krab zimbramon[12402]: 12402:info: Starting mta
Apr 11 17:19:30 krab clamd[12818]: Protecting against 50557 viruses.
Apr 11 17:19:30 krab clamd[12838]: Bound to port 3310
Apr 11 17:19:30 krab clamd[12838]: Setting connection queue length to 15
Apr 11 17:19:30 krab clamd[12838]: Archive: Archived file size limit set to 104857600 bytes.
Apr 11 17:19:30 krab clamd[12838]: Archive: Recursion level limit set to 8.
Apr 11 17:19:30 krab clamd[12838]: Archive: Files limit set to 1000.
Apr 11 17:19:30 krab clamd[12838]: Archive: Compression ratio limit set to 250.
Apr 11 17:19:30 krab clamd[12838]: Archive support enabled.
Apr 11 17:19:30 krab clamd[12838]: Archive: RAR support disabled.
Apr 11 17:19:30 krab clamd[12838]: Portable Executable support enabled.
Apr 11 17:19:30 krab clamd[12838]: Mail files support enabled.
Apr 11 17:19:30 krab clamd[12838]: OLE2 support enabled.
Apr 11 17:19:30 krab clamd[12838]: HTML support enabled.
Apr 11 17:19:30 krab clamd[12838]: Self checking every 1800 seconds.
Apr 11 17:19:40 krab postfix/postfix-script: warning: not owned by root: /opt/zimbra/postfix-2.2.5/conf/main.cf
Apr 11 17:19:40 krab postfix/postfix-script: starting the Postfix mail system
Apr 11 17:19:40 krab postfix/master[13024]: fatal: bind 0.0.0.0 port 25: Address already in use
Apr 11 17:20:01 krab postfix/postqueue[13042]: fatal: Connect to the Postfix showq service: Permission denied
Apr 11 17:20:02 krab zimbramon[13037]: 13037:info: 2006-04-11 17:20:01, DISK: krab.ugent.be: dev: /dev/sda1, mp: /, tot: 3706, avail: 3358
Apr 11 17:20:02 krab zimbramon[13037]: 13037:info: 2006-04-11 17:20:01, DISK: krab.ugent.be: dev: /dev/sda9, mp: /home, tot: 3838, avail: 3767
Apr 11 17:20:02 krab zimbramon[13037]: 13037:info: 2006-04-11 17:20:01, DISK: krab.ugent.be: dev: /dev/sda10, mp: /opt, tot: 50102, avail: 49029
Apr 11 17:20:02 krab zimbramon[13037]: 13037:info: 2006-04-11 17:20:01, DISK: krab.ugent.be: dev: /dev/sda7, mp: /tmp, tot: 803, avail: 787
Apr 11 17:20:02 krab zimbramon[13037]: 13037:info: 2006-04-11 17:20:01, DISK: krab.ugent.be: dev: /dev/sda8, mp: /usr, tot: 4108, avail: 3490
Apr 11 17:20:02 krab zimbramon[13037]: 13037:info: 2006-04-11 17:20:01, DISK: krab.ugent.be: dev: /dev/sda6, mp: /var, tot: 5366, avail: 4983
Apr 11 17:20:02 krab zimbramon[13036]: 13036:info: 2006-04-11 17:20:01, QUEUE: 0 0
Apr 11 17:20:03 krab postfix/postqueue[13144]: fatal: Connect to the Postfix showq service: Permission denied
Apr 11 17:20:04 krab zimbramon[13038]: 13038:info: 2006-04-11 17:20:01, STATUS: krab.ugent.be: antispam: Running
Apr 11 17:20:04 krab zimbramon[13038]: 13038:info: 2006-04-11 17:20:01, STATUS: krab.ugent.be: antivirus: Running
Apr 11 17:20:04 krab zimbramon[13038]: 13038:info: 2006-04-11 17:20:01, STATUS: krab.ugent.be: mta: Stopped

"$ zmcontrol status" yields:

Apr 11 17:21:31 krab postfix/postqueue[13414]: fatal: Connect to the Postfix showq service: Permission denied

And "$ zmcontrol stop":

Apr 11 17:22:49 krab zimbramon[13640]: 13640:info: Stopping services
Apr 11 17:22:49 krab zimbramon[13640]: 13640:info: Stopping antispam
Apr 11 17:22:49 krab amavis[12668]: Net::Server: 2006/04/11-17:22:49 Server closing!
Apr 11 17:22:51 krab zimbramon[13640]: 13640:info: Stopping antivirus
Apr 11 17:22:51 krab clamd[12838]: Pid file removed.
Apr 11 17:22:51 krab clamd[12838]: Exiting (clean)
Apr 11 17:22:51 krab clamd[12838]: --- Stopped at Tue Apr 11 17:22:51 2006
Apr 11 17:22:51 krab zimbramon[13640]: 13640:info: Stopping ldap
Apr 11 17:22:51 krab zimbramon[13640]: 13640:info: Stopping logger
Apr 11 17:22:51 krab zimbramon[13640]: 13640:info: Stopping mailbox
Apr 11 17:22:51 krab zimbramon[13640]: 13640:info: Stopping mta
Apr 11 17:22:52 krab postfix/postfix-script: fatal: the Postfix mail system is not running
Apr 11 17:22:52 krab zimbramon[13640]: 13640:info: Stopping snmp
Apr 11 17:22:52 krab zimbramon[13640]: 13640:info: Stopping spel

l

[KevinH]

Seems the root cause is postfix not starting...

try

sh - x ./bin/postfix start

[droefs]

zimbra@krab:~$ sh -x ./bin/postfix start
++ dirname ./bin/postfix
+ progdir=./bin
++ dirname ./bin/postfix
+ source ./bin/zmshutil
+ zmsetvars zimbra_home zimbra_server_hostname
+ '[' xzimbra_home = x-f ']'
+ '[' x = xtrue ']'
+ zmlocalconfig=/opt/zimbra/bin/zmlocalconfig
+ '[' '!' -x /opt/zimbra/bin/zmlocalconfig ']'
++ /opt/zimbra/bin/zmlocalconfig -q -m export
+ eval export 'av_notify_user='\''admin@zimbra.ugent.be'\'';' export 'ldap_connect_pool_debug='\''false'\'';' export 'ldap_connect_pool_initsize='\''1'\'';' export 'ldap_connect_pool_maxsize='\''25'\'';' export 'ldap_connect_pool_prefsize='\''0'\'';' export 'ldap_connect_pool_timeout='\''120000'\'';' export 'ldap_connect_timeout='\''10000'\'';' export 'ldap_host='\'''\'';' export 'ldap_is_master='\''false'\'';' export 'ldap_master_url='\''ldap://garnaal.ugent.be:389'\'';' export 'ldap_port='\'''\'';' export 'ldap_root_password='\''XXXXX'\'';' export 'ldap_url='\''ldap://garnaal.ugent.be'\'';' export 'logger_mysql_bind_address='\''localhost'\'';' export 'logger_mysql_data_directory='\''/opt/zimbra/logger/db/data'\'';' export 'logger_mysql_directory='\''/opt/zimbra/logger/mysql'\'';' export 'logger_mysql_mycnf='\''/opt/zimbra/conf/my.logger.cnf'\'';' export 'logger_mysql_pidfile='\''/opt/zimbra/logger/db/mysql.pid'\'';' export 'logger_mysql_port='\''7307'\'';' export 'logger_mysql_socket='\''/opt/zimbra/logger/db/mysql.sock'\'';' export 'mysql_bind_address='\''localhost'\'';' export 'mysql_data_directory='\''/opt/zimbra/db/data'\'';' export 'mysql_directory='\''/opt/zimbra/mysql'\'';' export 'mysql_innodb_log_buffer_size='\''8388608'\'';' export 'mysql_innodb_log_file_size='\''104857600'\'';' export 'mysql_memory_percent='\''40'\'';' export 'mysql_mycnf='\''/opt/zimbra/conf/my.cnf'\'';' export 'mysql_pidfile='\''/opt/zimbra/db/mysql.pid'\'';' export 'mysql_port='\''7306'\'';' export 'mysql_read_buffer_size='\''1048576'\'';' export 'mysql_root_password='\''zimbra'\'';' export 'mysql_socket='\''/opt/zimbra/db/mysql.sock'\'';' export 'mysql_sort_buffer_size='\''1048576'\'';' export 'mysql_table_cache='\''500'\'';' export 'nio_imap_enable='\''false'\'';' export 'nio_imap_log_buffers='\''false'\'';' export 'ssl_allow_untrusted_certs='\''TRUE'\'';' export 'stats_img_folder='\''/opt/zimbra/zimbramon/rrdtool/work'\'';' export 'tomcat_directory='\''/opt/zimbra/tomcat'\'';' export 'tomcat_java_heap_memory_percent='\''30'\'';' export 'tomcat_java_home='\''/opt/zimbra/java'\'';' export 'tomcat_java_options='\''-client' '-XX:NewRatio=2'\'';' export 'tomcat_keystore='\''/opt/zimbra/tomcat/conf/keystore'\'';' export 'tomcat_pidfile='\''/opt/zimbra/log/tomcat.pid'\'';' export 'wiki_enabled='\''false'\'';' export 'wiki_user='\''wiki'\'';' export 'zimbra_db_directory='\''/opt/zimbra/db'\'';' export 'zimbra_extension_directory='\''/opt/zimbra/lib/ext'\'';' export 'zimbra_gid='\''106'\'';' export 'zimbra_home='\''/opt/zimbra'\'';' export 'zimbra_index_directory='\''/opt/zimbra/index'\'';' export 'zimbra_index_idle_flush_time='\''600'\'';' export 'zimbra_index_lru_size='\''100'\'';' export 'zimbra_index_max_uncommitted_operations='\''200'\ '';' export 'zimbra_java_home='\''/opt/zimbra/java'\'';' export 'zimbra_ldap_password='\''z1mbr4'\'';' export 'zimbra_ldap_userdn='\''uid=zimbra,cn=admins,cn=zi mbra'\'';' export 'zimbra_log4j_properties='\''/opt/zimbra/conf/log4j.properties'\'';' export 'zimbra_log_directory='\''/opt/zimbra/log'\'';' export 'zimbra_logger_mysql_password='\''zimbra'\'';' export 'zimbra_mtareport_max_recipients='\''50'\'';' export 'zimbra_mtareport_max_senders='\''50'\'';' export 'zimbra_mysql_password='\''zimbra'\'';' export 'zimbra_mysql_user='\''zimbra'\'';' export 'zimbra_server_hostname='\''krab.ugent.be'\'';' export 'zimbra_store_directory='\''/opt/zimbra/store'\'';' export 'zimbra_store_sweeper_max_age='\''480'\'';' export 'zimbra_uid='\''1000'\'';' export 'zimbra_user='\''zimbra'\'';' export 'zimlet_directory='\''/opt/zimbra/tomcat/webapps/service/zimlet'\'';'
++ export av_notify_user=admin@zimbra.ugent.be
++ av_notify_user=admin@zimbra.ugent.be
++ export ldap_connect_pool_debug=false
++ ldap_connect_pool_debug=false
++ export ldap_connect_pool_initsize=1
++ ldap_connect_pool_initsize=1
++ export ldap_connect_pool_maxsize=25
++ ldap_connect_pool_maxsize=25
++ export ldap_connect_pool_prefsize=0
++ ldap_connect_pool_prefsize=0
++ export ldap_connect_pool_timeout=120000
++ ldap_connect_pool_timeout=120000
++ export ldap_connect_timeout=10000
++ ldap_connect_timeout=10000
++ export ldap_host=
++ ldap_host=
++ export ldap_is_master=false
++ ldap_is_master=false
++ export ldap_master_url=ldap://garnaal.ugent.be:389
++ ldap_master_url=ldap://garnaal.ugent.be:389
++ export ldap_port=
++ ldap_port=
++ export ldap_root_password=z1mbr4
++ ldap_root_password=z1mbr4
++ export ldap_url=ldap://garnaal.ugent.be
++ ldap_url=ldap://garnaal.ugent.be
++ export logger_mysql_bind_address=localhost
++ logger_mysql_bind_address=localhost
++ export logger_mysql_data_directory=/opt/zimbra/logger/db/data
++ logger_mysql_data_directory=/opt/zimbra/logger/db/data
++ export logger_mysql_directory=/opt/zimbra/logger/mysql
++ logger_mysql_directory=/opt/zimbra/logger/mysql
++ export logger_mysql_mycnf=/opt/zimbra/conf/my.logger.cnf
++ logger_mysql_mycnf=/opt/zimbra/conf/my.logger.cnf
++ export logger_mysql_pidfile=/opt/zimbra/logger/db/mysql.pid
++ logger_mysql_pidfile=/opt/zimbra/logger/db/mysql.pid
++ export logger_mysql_port=7307
++ logger_mysql_port=7307
++ export logger_mysql_socket=/opt/zimbra/logger/db/mysql.sock
++ logger_mysql_socket=/opt/zimbra/logger/db/mysql.sock
++ export mysql_bind_address=localhost
++ mysql_bind_address=localhost
++ export mysql_data_directory=/opt/zimbra/db/data
++ mysql_data_directory=/opt/zimbra/db/data
++ export mysql_directory=/opt/zimbra/mysql
++ mysql_directory=/opt/zimbra/mysql
++ export mysql_innodb_log_buffer_size=8388608
++ mysql_innodb_log_buffer_size=8388608
++ export mysql_innodb_log_file_size=104857600
++ mysql_innodb_log_file_size=104857600
++ export mysql_memory_percent=40
++ mysql_memory_percent=40
++ export mysql_mycnf=/opt/zimbra/conf/my.cnf
++ mysql_mycnf=/opt/zimbra/conf/my.cnf
++ export mysql_pidfile=/opt/zimbra/db/mysql.pid
++ mysql_pidfile=/opt/zimbra/db/mysql.pid
++ export mysql_port=7306
++ mysql_port=7306
++ export mysql_read_buffer_size=1048576
++ mysql_read_buffer_size=1048576
++ export mysql_root_password=zimbra
++ mysql_root_password=zimbra
++ export mysql_socket=/opt/zimbra/db/mysql.sock
++ mysql_socket=/opt/zimbra/db/mysql.sock
++ export mysql_sort_buffer_size=1048576
++ mysql_sort_buffer_size=1048576
++ export mysql_table_cache=500
++ mysql_table_cache=500
++ export nio_imap_enable=false
++ nio_imap_enable=false
++ export nio_imap_log_buffers=false
++ nio_imap_log_buffers=false
++ export ssl_allow_untrusted_certs=TRUE
++ ssl_allow_untrusted_certs=TRUE
++ export stats_img_folder=/opt/zimbra/zimbramon/rrdtool/work
++ stats_img_folder=/opt/zimbra/zimbramon/rrdtool/work
++ export tomcat_directory=/opt/zimbra/tomcat
++ tomcat_directory=/opt/zimbra/tomcat
++ export tomcat_java_heap_memory_percent=30
++ tomcat_java_heap_memory_percent=30
++ export tomcat_java_home=/opt/zimbra/java
++ tomcat_java_home=/opt/zimbra/java
++ export 'tomcat_java_options=-client -XX:NewRatio=2'
++ tomcat_java_options=-client -XX:NewRatio=2
++ export tomcat_keystore=/opt/zimbra/tomcat/conf/keystore
++ tomcat_keystore=/opt/zimbra/tomcat/conf/keystore
++ export tomcat_pidfile=/opt/zimbra/log/tomcat.pid
++ tomcat_pidfile=/opt/zimbra/log/tomcat.pid
++ export wiki_enabled=false
++ wiki_enabled=false
++ export wiki_user=wiki
++ wiki_user=wiki
++ export zimbra_db_directory=/opt/zimbra/db
++ zimbra_db_directory=/opt/zimbra/db
++ export zimbra_extension_directory=/opt/zimbra/lib/ext
++ zimbra_extension_directory=/opt/zimbra/lib/ext
++ export zimbra_gid=106
++ zimbra_gid=106
++ export zimbra_home=/opt/zimbra
++ zimbra_home=/opt/zimbra
++ export zimbra_index_directory=/opt/zimbra/index
++ zimbra_index_directory=/opt/zimbra/index
++ export zimbra_index_idle_flush_time=600
++ zimbra_index_idle_flush_time=600
++ export zimbra_index_lru_size=100
++ zimbra_index_lru_size=100
++ export zimbra_index_max_uncommitted_operations=200
++ zimbra_index_max_uncommitted_operations=200
++ export zimbra_java_home=/opt/zimbra/java
++ zimbra_java_home=/opt/zimbra/java
++ export zimbra_ldap_password=z1mbr4
++ zimbra_ldap_password=z1mbr4
++ export zimbra_ldap_userdn=uid=zimbra,cn=admins,cn=zimbra
++ zimbra_ldap_userdn=uid=zimbra,cn=admins,cn=zimbra
++ export zimbra_log4j_properties=/opt/zimbra/conf/log4j.properties
++ zimbra_log4j_properties=/opt/zimbra/conf/log4j.properties
++ export zimbra_log_directory=/opt/zimbra/log
++ zimbra_log_directory=/opt/zimbra/log
++ export zimbra_logger_mysql_password=zimbra
++ zimbra_logger_mysql_password=zimbra
++ export zimbra_mtareport_max_recipients=50
++ zimbra_mtareport_max_recipients=50
++ export zimbra_mtareport_max_senders=50
++ zimbra_mtareport_max_senders=50
++ export zimbra_mysql_password=zimbra
++ zimbra_mysql_password=zimbra
++ export zimbra_mysql_user=zimbra
++ zimbra_mysql_user=zimbra
++ export zimbra_server_hostname=krab.ugent.be
++ zimbra_server_hostname=krab.ugent.be
++ export zimbra_store_directory=/opt/zimbra/store
++ zimbra_store_directory=/opt/zimbra/store
++ export zimbra_store_sweeper_max_age=480
++ zimbra_store_sweeper_max_age=480
++ export zimbra_uid=1000
++ zimbra_uid=1000
++ export zimbra_user=zimbra
++ zimbra_user=zimbra
++ export zimlet_directory=/opt/zimbra/tomcat/webapps/service/zimlet
++ zimlet_directory=/opt/zimbra/tomcat/webapps/service/zimlet
+ export zmsetvars=true
+ zmsetvars=true
+ '[' start = start -o start = reload ']'
+ '[' x = x ']'
+ rewriteconfig
+ /opt/zimbra/libexec/zmmtaconfig mta
+ /opt/zimbra/postfix-2.2.5/sbin/postmap /opt/zimbra/conf/postfix_header_checks
+ sudo /opt/zimbra/postfix-2.2.5/sbin/postsuper -r ALL
+ '[' start = status ']'
+ sudo /opt/zimbra/postfix-2.2.5/sbin/postalias /etc/aliases
+ sudo /opt/zimbra/postfix-2.2.5/sbin/postfix start
postfix/postfix-script: warning: not owned by root: /opt/zimbra/postfix-2.2.5/conf/main.cf
postfix/postfix-script: starting the Postfix mail system

So postfix seems to start fine manually. Trying to "$ zmcontrol start" gives the same errors as earlier though.

I keep on getting these log-messages:

Apr 11 18:08:03 krab postfix/postqueue[17426]: fatal: Connect to the Postfix showq service: Permission denied
Apr 11 18:08:04 krab zimbramon[17360]: 17360:info: 2006-04-11 18:08:01, STATUS: krab.ugent.be: antispam: Running
Apr 11 18:08:04 krab zimbramon[17360]: 17360:info: 2006-04-11 18:08:01, STATUS: krab.ugent.be: antivirus: Running
Apr 11 18:08:04 krab zimbramon[17360]: 17360:info: 2006-04-11 18:08:01, STATUS: krab.ugent.be: mta: Stopped

[KevinH]

Does it actually start?

ps -ef | grep postfix

Are you running all these commands as zimbra user?

[droefs]

No, it doesn't; it just seems like Postfix is starting succesfully.

krab:/bin# ps -ef | grep postfix
krab:/bin# ps -ef | grep zimbra
zimbra 15426 1 0 17:57 pts/1 00:00:00 /usr/bin/perl /opt/zimbra/libexec/zmmtaconfig
zimbra 15630 1 0 17:58 ? 00:00:00 amavisd (master)
zimbra 15646 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15647 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15648 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15649 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15650 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15654 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15655 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15656 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15657 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15658 15630 0 17:58 ? 00:00:00 amavisd (virgin child)
zimbra 15847 1 0 17:58 ? 00:00:00 /opt/zimbra/clamav/bin/freshclam --config-file=/opt/zimbra/conf/freshclam.conf -d --checks=12
zimbra 15867 1 0 17:58 ? 00:00:00 /opt/zimbra/clamav/sbin/clamd --config-file /opt/zimbra/conf/clamd.conf
root 19573 12246 0 18:22 pts/1 00:00:00 grep zimbra

All commands listed above starting with a $ were executed as user zimbra, the ones with # as root.

krab:/opt/zimbra# sh -x ./bin/postfix start

yields the same output as running it as user zimbra, and still there's no Postfix running.

[KevinH]

Don't run the zimbra commands as root. If you did/have you should run zmfixperm.sh as root to correct things. Then retest.