Results 1 to 10 of 29

Thread: unable to restart zimbra after reboot

Hybrid View

  1. #1
    Join Date
    Mar 2006
    Posts
    12
    Rep Power
    9

    Default unable to restart zimbra after reboot

    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

  2. #2
    Join Date
    Aug 2005
    Location
    San Mateo, CA
    Posts
    4,789
    Rep Power
    19

    Default

    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
    Looking for new beta users -> Co-Founder of Acompli. Previously worked at Zimbra (and Yahoo! & VMware) since 2005.

  3. #3
    Join Date
    Mar 2006
    Posts
    12
    Rep Power
    9

    Default

    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.

  4. #4
    Join Date
    Aug 2005
    Location
    San Mateo, CA
    Posts
    4,789
    Rep Power
    19

    Default

    What's in /var/log/zimbra.log on the MTA host?
    Looking for new beta users -> Co-Founder of Acompli. Previously worked at Zimbra (and Yahoo! & VMware) since 2005.

  5. #5
    Join Date
    Mar 2006
    Posts
    12
    Rep Power
    9

    Default

    "$ 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

  6. #6
    Join Date
    Aug 2005
    Location
    San Mateo, CA
    Posts
    4,789
    Rep Power
    19

    Default

    Seems the root cause is postfix not starting...

    try

    sh - x ./bin/postfix start
    Looking for new beta users -> Co-Founder of Acompli. Previously worked at Zimbra (and Yahoo! & VMware) since 2005.

Similar Threads

  1. Replies: 31
    Last Post: 12-15-2007, 08:05 PM
  2. Zimbra shutdowns every n hours.
    By Andrewb in forum Administrators
    Replies: 13
    Last Post: 08-14-2007, 08:55 AM
  3. 3.1 on FC4 problems
    By cohnhead in forum Installation
    Replies: 8
    Last Post: 05-26-2006, 11:16 AM
  4. port 7071 not listening OS X install
    By leeimber in forum Installation
    Replies: 7
    Last Post: 03-21-2006, 09:47 AM
  5. Monitoring : Data not yet avalaible
    By s3nz3x in forum Installation
    Replies: 7
    Last Post: 11-30-2005, 06:18 PM

Posting Permissions

  • You may not post new threads
  • You may not post replies
  • You may not post attachments
  • You may not edit your posts
  •