mailbox service not running after upgrade from 5.x to 6.x

[wpram]

Hi,

I'm using Zimbra Open Source edition and upgrading to 6.0. Mailbox service is not running (from zmcontrol status) after upgrading to 6.0.

I have to revert back to my backup copy to go back to 5.0.

Here's a copy of log file:


Sep 10 19:10:44 mail zimbramon[20662]: 20662:info: Starting services initiated by zmcontrol
Sep 10 19:10:44 mail slapd[20703]: @(#) $OpenLDAP: slapd 2.4.17 (Aug 2 2009 17:06:46) $ ^Iroot@build13.lab.zimbra.com:/home/build/p4/main/ThirdParty/openldap/openldap-2.4.17.8z/servers/slapd
Sep 10 19:10:44 mail slapd[20704]: slapd starting
Sep 10 19:10:52 mail zimbramon[20662]: 20662:info: Rewriting configs antispam amavis antivirus amavis webxml mailbox amavis antispam antivirus mta sasl
Sep 10 19:11:10 mail zimbramon[20662]: 20662:info: Starting logger via zmcontrol
Sep 10 19:11:11 mail zimbramon[20662]: 20662:info: Starting mailbox via zmcontrol
Sep 10 19:11:11 mail zimbramon[22105]: 22105:info: zmmtaconfig: zmmtaconfig started on mail.crescenthotel.com.au with loglevel=3 pid=22105
Sep 10 19:11:38 mail zmmailboxdmgr[24633]: status requested
Sep 10 19:11:38 mail zmmailboxdmgr[24633]: stale pid 19501 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Sep 10 19:11:38 mail zmmailboxdmgr[24633]: assuming no other instance is running
Sep 10 19:11:38 mail zmmailboxdmgr[24633]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Sep 10 19:11:38 mail zmmailboxdmgr[24633]: assuming no other instance is running
Sep 10 19:11:38 mail zmmailboxdmgr[24633]: no manager process is running
Sep 10 19:11:38 mail zmmailboxdmgr[24640]: start requested
Sep 10 19:11:38 mail zmmailboxdmgr[24640]: checking if another instance of manager is already running
Sep 10 19:11:38 mail zmmailboxdmgr[24640]: stale pid 19501 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Sep 10 19:11:38 mail zmmailboxdmgr[24640]: assuming no other instance is running
Sep 10 19:11:38 mail zmmailboxdmgr[24640]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Sep 10 19:11:38 mail zmmailboxdmgr[24640]: assuming no other instance is running
Sep 10 19:11:38 mail zmmailboxdmgr[24641]: wrote manager pid 24641 to /opt/zimbra/log/zmmailboxd_manager.pid
Sep 10 19:11:38 mail zmmailboxdmgr[24642]: wrote java pid 24642 to /opt/zimbra/log/zmmailboxd_java.pid
Sep 10 19:11:38 mail zimbramon[20662]: 20662:info: Starting memcached via zmcontrol
Sep 10 19:11:38 mail zmmailboxdmgr[24641]: manager started mailboxd/JVM with pid 24642
Sep 10 19:11:38 mail zimbramon[20662]: 20662:info: Starting antispam via zmcontrol
Sep 10 19:11:39 mail amavis[24681]: starting. /opt/zimbra/amavisd/sbin/amavisd at mail.crescenthotel.com.au amavisd-new-2.6.4 (20090625), Unicode aware, LC_ALL="C", LANG="C"
Sep 10 19:11:39 mail amavis[24681]: user=1001, EUID: 1001 (1001); group=, EGID: 1004 1005 1004 5 4 (1004 1005 1004 5 4)
Sep 10 19:11:39 mail amavis[24681]: Perl version 5.008008
Sep 10 19:11:39 mail amavis[24681]: SpamControl: init_pre_chroot on SpamAssassin done
Sep 10 19:11:39 mail amavis[24683]: Net::Server: Process Backgrounded
Sep 10 19:11:39 mail amavis[24683]: Net::Server: 2009/09/10-19:11:39 Amavis (type Net::Server::PreForkSimple) starting! pid(24683)
Sep 10 19:11:39 mail amavis[24683]: Net::Server: Binding to UNIX socket file /opt/zimbra/data/amavisd/amavisd.sock using SOCK_STREAM
Sep 10 19:11:39 mail amavis[24683]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Sep 10 19:11:39 mail amavis[24683]: Net::Server: Group Not Defined. Defaulting to EGID '1004 1005 1004 5 4'
Sep 10 19:11:39 mail amavis[24683]: Net::Server: User Not Defined. Defaulting to EUID '1001'
Sep 10 19:11:39 mail amavis[24683]: Module Amavis::Conf 2.207
Sep 10 19:11:39 mail amavis[24683]: Module Archive::Zip 1.30
Sep 10 19:11:39 mail amavis[24683]: Module Compress::Zlib 1.42
Sep 10 19:11:39 mail amavis[24683]: Module Convert::TNEF 0.17
Sep 10 19:11:39 mail amavis[24683]: Module Convert::UUlib 1.12
Sep 10 19:11:39 mail amavis[24683]: Module Crypt::OpenSSL::RSA 0.25
Sep 10 19:11:39 mail amavis[24683]: Module DBD::mysql 4.012
Sep 10 19:11:39 mail amavis[24683]: Module DBI 1.609
Sep 10 19:11:39 mail amavis[24683]: Module DB_File 1.82
Sep 10 19:11:39 mail amavis[24683]: Module Digest::MD5 2.36
Sep 10 19:11:39 mail amavis[24683]: Module Digest::SHA 5.47
Sep 10 19:11:39 mail amavis[24683]: Module Digest::SHA1 2.12
Sep 10 19:11:39 mail amavis[24683]: Module IO::Socket::INET6 2.56
Sep 10 19:11:39 mail amavis[24683]: Module MIME::Entity 5.427
Sep 10 19:11:39 mail amavis[24683]: Module MIME::Parser 5.427
Sep 10 19:11:39 mail amavis[24683]: Module MIME::Tools 5.427
Sep 10 19:11:39 mail amavis[24683]: Module Mail:KIM::Signer 0.36
Sep 10 19:11:39 mail amavis[24683]: Module Mail:KIM::Verifier 0.36
Sep 10 19:11:39 mail amavis[24683]: Module Mail::Header 2.04
Sep 10 19:11:39 mail amavis[24683]: Module Mail::Internet 2.04
Sep 10 19:11:39 mail amavis[24683]: Module Mail::SpamAssassin 3.002005
Sep 10 19:11:39 mail amavis[24683]: Module Net:NS 0.65
Sep 10 19:11:39 mail amavis[24683]: Module Net::LDAP 0.39
Sep 10 19:11:39 mail amavis[24683]: Module Net::Server 0.97
Sep 10 19:11:39 mail amavis[24683]: Module Socket6 0.23
Sep 10 19:11:39 mail amavis[24683]: Module Time::HiRes 1.9719
Sep 10 19:11:39 mail amavis[24683]: Module URI 1.38
Sep 10 19:11:39 mail amavis[24683]: Module Unix::Syslog 1.1
Sep 10 19:11:39 mail amavis[24683]: Amavis:B code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: Amavis::Cache code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: SQL base code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: SQL::Log code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: SQL::Quarantine NOT loaded
Sep 10 19:11:39 mail amavis[24683]: Lookup::SQL code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: Lookup::LDAP code loaded
Sep 10 19:11:39 mail amavis[24683]: AM.PDP-in proto code loaded
Sep 10 19:11:39 mail amavis[24683]: SMTP-in proto code loaded
Sep 10 19:11:39 mail amavis[24683]: Courier proto code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: SMTP-out proto code loaded
Sep 10 19:11:39 mail amavis[24683]: Pipe-out proto code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: BSMTP-out proto code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: Local-out proto code loaded
Sep 10 19:11:39 mail amavis[24683]: OS_Fingerprint code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: ANTI-VIRUS code loaded
Sep 10 19:11:39 mail amavis[24683]: ANTI-SPAM code loaded
Sep 10 19:11:39 mail amavis[24683]: ANTI-SPAM-EXT code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: ANTI-SPAM-C code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: ANTI-SPAM-SA code loaded
Sep 10 19:11:39 mail amavis[24683]: Unpackers code loaded
Sep 10 19:11:39 mail amavis[24683]: DKIM code loaded
Sep 10 19:11:39 mail amavis[24683]: Tools code NOT loaded
Sep 10 19:11:39 mail amavis[24683]: Found $file at /usr/bin/file
Sep 10 19:11:39 mail amavis[24683]: No $altermime, not using it
Sep 10 19:11:39 mail amavis[24683]: Internal decoder for .mail
Sep 10 19:11:39 mail amavis[24683]: Internal decoder for .asc
Sep 10 19:11:39 mail amavis[24683]: Internal decoder for .uue
Sep 10 19:11:39 mail amavis[24683]: Internal decoder for .hqx
Sep 10 19:11:39 mail amavis[24683]: Internal decoder for .ync
Sep 10 19:11:39 mail amavis[24683]: No decoder for .F tried: unfreeze, freeze -d, melt, fcat
Sep 10 19:11:39 mail amavis[24683]: Found decoder for .Z at /bin/uncompress
Sep 10 19:11:39 mail amavis[24683]: Found decoder for .gz at /bin/gzip -d
Sep 10 19:11:39 mail amavis[24683]: Found decoder for .bz2 at /bin/bzip2 -d
Sep 10 19:11:39 mail amavis[24683]: No decoder for .lzo tried: lzop -d
Sep 10 19:11:39 mail amavis[24683]: No decoder for .rpm tried: rpm2cpio.pl, rpm2cpio
Sep 10 19:11:39 mail amavis[24683]: Found decoder for .cpio at /bin/cpio
Sep 10 19:11:39 mail amavis[24683]: Found decoder for .tar at /bin/cpio
Sep 10 19:11:39 mail amavis[24683]: Found decoder for .deb at /usr/bin/ar
Sep 10 19:11:39 mail amavis[24683]: Internal decoder for .zip
Sep 10 19:11:39 mail amavis[24683]: No decoder for .7z tried: 7zr, 7za, 7z
Sep 10 19:11:39 mail amavis[24683]: No decoder for .rar tried: rar, unrar
Sep 10 19:11:39 mail amavis[24683]: No decoder for .arj tried: arj, unarj
Sep 10 19:11:39 mail amavis[24683]: No decoder for .arc tried: nomarch, arc
Sep 10 19:11:39 mail amavis[24683]: No decoder for .zoo tried: zoo, unzoo
Sep 10 19:11:39 mail amavis[24683]: No decoder for .lha tried: lha
Sep 10 19:11:39 mail amavis[24683]: No decoder for .cab tried: cabextract
Sep 10 19:11:39 mail amavis[24683]: No decoder for .tnef tried: tnef
Sep 10 19:11:39 mail amavis[24683]: Internal decoder for .tnef
Sep 10 19:11:39 mail amavis[24683]: No decoder for .exe tried: rar, unrar; lha; arj, unarj
Sep 10 19:11:39 mail amavis[24683]: Using primary internal av scanner code for ClamAV-clamd
Sep 10 19:11:39 mail amavis[24683]: initializing Mail::SpamAssassin
Sep 10 19:11:39 mail zmmailboxdmgr[24641]: manager woke up from wait on mailboxd/JVM with pid 24642
Sep 10 19:11:39 mail zmmailboxdmgr[24684]: wrote java pid 24684 to /opt/zimbra/log/zmmailboxd_java.pid
Sep 10 19:11:39 mail zmmailboxdmgr[24641]: manager started mailboxd/JVM with pid 24684
Sep 10 19:11:39 mail zimbramon[20662]: 20662:info: Starting antivirus via zmcontrol
Sep 10 19:11:40 mail amavis[24683]: SpamControl: init_pre_fork on SpamAssassin done
Sep 10 19:11:40 mail clamd[24736]: clamd daemon 0.95.2-broken-compiler (OS: linux-gnu, ARCH: i386, CPU: i686)
Sep 10 19:11:40 mail clamd[24736]: Log file size limited to 20971520 bytes.
Sep 10 19:11:40 mail clamd[24736]: Reading databases from /opt/zimbra/data/clamav/db
Sep 10 19:11:40 mail clamd[24736]: Not loading PUA signatures.
Sep 10 19:11:40 mail zmmailboxdmgr[24641]: manager woke up from wait on mailboxd/JVM with pid 24684
Sep 10 19:11:40 mail zmmailboxdmgr[24641]: mailboxd/JVM exited twice in 1 seconds (tolerance=60)
Sep 10 19:11:42 mail clamd[24736]: Loaded 621913 signatures.
Sep 10 19:11:42 mail clamd[24736]: TCP: Bound to port 3310
Sep 10 19:11:42 mail clamd[24736]: TCP: Setting connection queue length to 15
Sep 10 19:11:42 mail clamd[24739]: Limits: Global size limit set to 20480000 bytes.
Sep 10 19:11:42 mail clamd[24739]: Limits: File size limit set to 20480000 bytes.
Sep 10 19:11:42 mail clamd[24739]: Limits: Recursion level limit set to 16.
Sep 10 19:11:42 mail clamd[24739]: Limits: Files limit set to 10000.
Sep 10 19:11:42 mail clamd[24739]: Archive support enabled.
Sep 10 19:11:42 mail clamd[24739]: Archive: Blocking encrypted archives.
Sep 10 19:11:42 mail clamd[24739]: Algorithmic detection enabled.
Sep 10 19:11:42 mail clamd[24739]: Portable Executable support enabled.
Sep 10 19:11:42 mail clamd[24739]: ELF support enabled.
Sep 10 19:11:42 mail clamd[24739]: Mail files support enabled.
Sep 10 19:11:42 mail clamd[24739]: OLE2 support enabled.
Sep 10 19:11:42 mail clamd[24739]: PDF support enabled.
Sep 10 19:11:42 mail clamd[24739]: HTML support enabled.
Sep 10 19:11:42 mail clamd[24739]: Self checking every 600 seconds.
Sep 10 19:11:45 mail zimbramon[20662]: 20662:info: Starting snmp via zmcontrol
Sep 10 19:11:45 mail zimbramon[20662]: 20662:info: Starting spell via zmcontrol
Sep 10 19:11:45 mail zimbramon[20662]: 20662:info: Starting mta via zmcontrol
Sep 10 19:12:01 mail CRON[25869]: (pam_unix) session opened for user zimbra by (uid=0)
Sep 10 19:12:05 mail zmmailboxdmgr[26217]: status requested
Sep 10 19:12:05 mail zmmailboxdmgr[26217]: stale pid 24641 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Sep 10 19:12:05 mail zmmailboxdmgr[26217]: assuming no other instance is running
Sep 10 19:12:05 mail zmmailboxdmgr[26217]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Sep 10 19:12:05 mail zmmailboxdmgr[26217]: assuming no other instance is running
Sep 10 19:12:05 mail zmmailboxdmgr[26217]: no manager process is running
Sep 10 19:12:06 mail CRON[25869]: (pam_unix) session closed for user zimbra
Sep 10 19:12:07 mail postfix/postfix-script[26364]: warning: not owned by root: /opt/zimbra/data/postfix/spool
Sep 10 19:12:07 mail postfix/postfix-script[26371]: warning: not owned by root: /opt/zimbra/postfix-2.6.2.2z/conf/main.cf
Sep 10 19:12:07 mail postfix/postfix-script[26372]: warning: not owned by root: /opt/zimbra/postfix-2.6.2.2z/conf/master.cf
Sep 10 19:12:07 mail postfix/postfix-script[26373]: warning: not owned by root: /opt/zimbra/postfix-2.6.2.2z/conf/master.cf.in
Sep 10 19:12:07 mail postfix/postfix-script[26387]: starting the Postfix mail system
Sep 10 19:12:07 mail postfix/master[26388]: daemon started -- version 2.6.2, configuration /opt/zimbra/postfix-2.6.2.2z/conf
Sep 10 19:12:07 mail postfix/qmgr[26394]: 4F52734DC658: from=<alex110@mail.ru>, size=2131, nrcpt=1 (queue active)
Sep 10 19:12:07 mail postfix/qmgr[26394]: 8D02C34DC656: from=<chris@crescenthotel.com.au>, size=7041, nrcpt=1 (queue active)
Sep 10 19:12:07 mail postfix/qmgr[26394]: AA6F534DC65A: from=<TL-ZZc80299981s9384003072@campaign.raywhite.com>, size=3165, nrcpt=1 (queue active)
Sep 10 19:12:07 mail postfix/lmtp[26399]: connect to mail.crescenthotel.com.au[192.168.1.22]:7025: Connection refused
Sep 10 19:12:07 mail postfix/lmtp[26400]: connect to mail.crescenthotel.com.au[192.168.1.22]:7025: Connection refused
Sep 10 19:12:07 mail postfix/lmtp[26402]: connect to mail.crescenthotel.com.au[192.168.1.22]:7025: Connection refused
Sep 10 19:12:07 mail postfix/lmtp[26400]: 8D02C34DC656: to=<chris@crescenthotel.com.au>, relay=none, delay=1213, delays=1213/0.02/0/0, dsn=4.4.1, status=deferred (connect to mail.crescenthotel.com.au[192.168.1.22]:7025: Connection refused)
Sep 10 19:12:07 mail saslauthd[26396]: detach_tty : master pid is: 26396
Sep 10 19:12:07 mail saslauthd[26396]: ipc_init : listening on socket: /opt/zimbra/cyrus-sasl-2.1.23.3z/state/mux
Sep 10 19:12:07 mail postfix/lmtp[26402]: AA6F534DC65A: to=<sue@thenassergroup.com>, relay=none, delay=679, delays=679/0.03/0/0, dsn=4.4.1, status=deferred (connect to mail.crescenthotel.com.au[192.168.1.22]:7025: Connection refused)
Sep 10 19:12:07 mail postfix/lmtp[26399]: 4F52734DC658: to=<sue@thenassergroup.com>, relay=none, delay=720, delays=720/0.04/0/0, dsn=4.4.1, status=deferred (connect to mail.crescenthotel.com.au[192.168.1.22]:7025: Connection refused)
Sep 10 19:12:07 mail zimbramon[20662]: 20662:info: Starting stats via zmcontrol
Sep 10 19:12:49 mail zmmailboxdmgr[28321]: status requested
Sep 10 19:12:49 mail zmmailboxdmgr[28321]: stale pid 24641 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Sep 10 19:12:49 mail zmmailboxdmgr[28321]: assuming no other instance is running
Sep 10 19:12:49 mail zmmailboxdmgr[28321]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Sep 10 19:12:49 mail zmmailboxdmgr[28321]: assuming no other instance is running
Sep 10 19:12:49 mail zmmailboxdmgr[28321]: no manager process is running
Sep 10 19:12:49 mail zmmailboxdmgr[28381]: status requested
Sep 10 19:12:49 mail zmmailboxdmgr[28381]: stale pid 24641 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Sep 10 19:12:49 mail zmmailboxdmgr[28381]: assuming no other instance is running
Sep 10 19:12:49 mail zmmailboxdmgr[28381]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Sep 10 19:12:49 mail zmmailboxdmgr[28381]: assuming no other instance is running
Sep 10 19:12:49 mail zmmailboxdmgr[28381]: no manager process is running

Thanks!

[phoenix]

Originally Posted by wpram

Sep 10 19:11:38 mail zimbramon[20662]: 20662:info: Starting memcached via zmcontrol

You seem to have enabled the proxy & memcached during the update, it's not needed on a single-server Zimbra installation. Either don't enable it during the upgrade or disable it with these instructions: imapproxy won't start after upgrade

[wpram]

Hi,

Thanks for the reply.

I just upgraded again to 6.0 (we had to revert to 5.0 because this is a production system) and I made sure that memcached is not installed.

$ zmproxyctl status
zmnginxctl is not running

The mailbox service still cannot run. When I run zmcontrol status - it returns

$ zmcontrol status
Host mail.crescenthotel.com.au
antispam Running
antivirus Running
ldap Running
logger Running
mailbox Stopped
zmmailboxdctl is not running.
mta Running
snmp Running
spell Running
stats Running

Thanks again for taking the time to help me. Much appreciated

[wpram]

I checked /tmp/zmsetup and I found a lot of error about unable to bind to ldap.

Tue Nov 17 21:33:17 2009 Checking ldap on mail.crescenthotel.com.au:389
Tue Nov 17 21:33:17 2009 Unable to bind to ldap://mail.crescenthotel.com.au:389 with user uid=zmnginx,cn=appacc
ts,cn=zimbra:

Is this relevant?

[phoenix]

Post the output of the following commands (run on the Zimbra server):

cat /etc/hosts
cat /etc/resolv.conf
dig yourdomain.com any
dig yourdomain.com mx
host `hostname` <-- use that exact command with backticks not single quotes

[wpram]

# cat /etc/hosts
127.0.0.1	localhost
192.168.1.22	mail.crescenthotel.com.au mail
# The following lines are desirable for IPv6 capable hosts
::1     ip6-localhost ip6-loopback
fe00::0 ip6-localnet
ff00::0 ip6-mcastprefix
ff02::1 ip6-allnodes
ff02::2 ip6-allrouters
ff02::3 ip6-allhosts

# cat /etc/resolv.conf
search www.crescenthotel.com.au
nameserver 192.168.1.22

# dig crescenthotel.com.au any

; <<>> DiG 9.3.2 <<>> crescenthotel.com.au any
;; global options:  printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 21264
;; flags: qr aa rd ra; QUERY: 1, ANSWER: 4, AUTHORITY: 0, ADDITIONAL: 1

;; QUESTION SECTION:
;crescenthotel.com.au.		IN	ANY

;; ANSWER SECTION:
crescenthotel.com.au.	3600	IN	SOA	ns.crescenthotel.com.au. admin.crescenthotel.com.au. 2007062701 28800 7200 2419200 86400
crescenthotel.com.au.	3600	IN	NS	ns.crescenthotel.com.au.crescenthotel.com.au.
crescenthotel.com.au.	3600	IN	MX	10 mail.crescenthotel.com.au.
crescenthotel.com.au.	3600	IN	A	192.168.1.22

;; ADDITIONAL SECTION:
mail.crescenthotel.com.au. 3600	IN	A	192.168.1.22

;; Query time: 17 msec
;; SERVER: 192.168.1.22#53(192.168.1.22)
;; WHEN: Tue Nov 17 22:34:45 2009
;; MSG SIZE  rcvd: 174

# dig crescenthotel.com.au mx

; <<>> DiG 9.3.2 <<>> crescenthotel.com.au mx
;; global options:  printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 55835
;; flags: qr aa rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 1, ADDITIONAL: 1

;; QUESTION SECTION:
;crescenthotel.com.au.		IN	MX

;; ANSWER SECTION:
crescenthotel.com.au.	3600	IN	MX	10 mail.crescenthotel.com.au.

;; AUTHORITY SECTION:
crescenthotel.com.au.	3600	IN	NS	ns.crescenthotel.com.au.crescenthotel.com.au.

;; ADDITIONAL SECTION:
mail.crescenthotel.com.au. 3600	IN	A	192.168.1.22

;; Query time: 0 msec
;; SERVER: 192.168.1.22#53(192.168.1.22)
;; WHEN: Tue Nov 17 22:35:06 2009
;; MSG SIZE  rcvd: 113

# host `hostname`
mail.crescenthotel.com.au has address 192.168.1.22

[phoenix]

That would appear to be mostly OK except for the hosts file, it should look like this:

127.0.0.1	localhost.localdomain localhost
192.168.1.22	mail.crescenthotel.com.au mail

Make that change then try the upgrade again by shutting down Zimbra with 'zmcontrol stop', see if any services are still running (kill them all if they are) then run the upgrade script. When you've done that post the upgrade log file plus the Zimbra log where the failure to start occurs if you're still having problems.

Are you sure that you're answering 'no' to the question about memcached & the proxy during the upgrade (they should be two separate questions)?

[wpram]

Hi,

Yes, I'm positive I said no to both question (proxy and memcached).

I've edited the hosts file and run install.sh again to upgrade.

When I started Zimbra $ zmcontrol start

zimbra@mail:~$ zmcontrol start
Host mail.crescenthotel.com.au
Starting logger...Done.
Starting mailbox...Done.
Starting antispam...Done.
Starting antivirus...Done.
Starting snmp...Done.
Starting spell...Done.
Starting mta...Done.
Starting stats...Done.


But then when I looked at the status:

Host mail.crescenthotel.com.au
antispam Running
antivirus Running
ldap Running
logger Running
mailbox Stopped
zmmailboxdctl is not running.
mta Running
snmp Running
spell Running
stats Running

[wpram]

zimbra.log When I stop the system

Nov 17 23:17:56 mail clamd[5663]: --- Stopped at Tue Nov 17 23:17:56 2009
Nov 17 23:17:58 mail zimbramon[24685]: 24685:info: Stopping antispam via zmcontrol
Nov 17 23:17:58 mail zimbramon[24685]: 24685:info: Stopping imapproxy via zmcontrol
Nov 17 23:17:58 mail zimbramon[24685]: 24685:info: Stopping memcached via zmcontrol
Nov 17 23:17:58 mail zimbramon[24685]: 24685:info: Stopping mailbox via zmcontrol
Nov 17 23:17:58 mail zmmailboxdmgr[25348]: status requested
Nov 17 23:17:58 mail zmmailboxdmgr[25348]: stale pid 5573 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 17 23:17:58 mail zmmailboxdmgr[25348]: assuming no other instance is running
Nov 17 23:17:58 mail zmmailboxdmgr[25348]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 17 23:17:58 mail zmmailboxdmgr[25348]: assuming no other instance is running
Nov 17 23:17:58 mail zmmailboxdmgr[25348]: no manager process is running
Nov 17 23:18:00 mail zimbramon[24685]: 24685:info: Stopping convertd via zmcontrol
Nov 17 23:18:00 mail zimbramon[24685]: 24685:info: Stopping logger via zmcontrol
Nov 17 23:18:01 mail CRON[25394]: (pam_unix) session opened for user zimbra by (uid=0)
Nov 17 23:18:02 mail zimbramon[24685]: 24685:info: Stopping ldap via zmcontrol
Nov 17 23:18:02 mail slapd[28672]: daemon: shutdown requested and initiated.
Nov 17 23:18:02 mail slapd[28672]: slapd shutdown: waiting for 0 operations/tasks to finish
Nov 17 23:18:02 mail slapd[28672]: slapd stopped.
Nov 17 23:18:04 mail zmmailboxdmgr[25658]: status requested
Nov 17 23:18:04 mail zmmailboxdmgr[25658]: stale pid 5573 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 17 23:18:04 mail zmmailboxdmgr[25658]: assuming no other instance is running
Nov 17 23:18:04 mail zmmailboxdmgr[25658]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 17 23:18:04 mail zmmailboxdmgr[25658]: assuming no other instance is running
Nov 17 23:18:04 mail zmmailboxdmgr[25658]: no manager process is running

This is the (ERROR) log when I start zimbra via zmcontrol

Nov 17 23:20:01 mail postfix/postqueue[26122]: fatal: Queue report unavailable - mail system is down

Nov 17 23:20:10 mail zmmailboxdmgr[26765]: status requested
Nov 17 23:20:10 mail zmmailboxdmgr[26765]: stale pid 5573 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 17 23:20:10 mail zmmailboxdmgr[26765]: assuming no other instance is running
Nov 17 23:20:10 mail zmmailboxdmgr[26765]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 17 23:20:10 mail zmmailboxdmgr[26765]: assuming no other instance is running
Nov 17 23:20:10 mail zmmailboxdmgr[26765]: no manager process is running

Nov 17 23:20:25 mail zimbramon[25750]: 25750:info: Starting logger via zmcontrol
Nov 17 23:20:29 mail zimbramon[25750]: 25750:info: Starting mailbox via zmcontrol
Nov 17 23:20:29 mail zimbramon[27849]: 27849:info: zmmtaconfig: zmmtaconfig started on mail.crescenthotel.com.au with loglevel=3 pid=27849
Nov 17 23:20:56 mail zmmailboxdmgr[30206]: status requested
Nov 17 23:20:56 mail zmmailboxdmgr[30206]: stale pid 5573 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 17 23:20:56 mail zmmailboxdmgr[30206]: assuming no other instance is running
Nov 17 23:20:56 mail zmmailboxdmgr[30206]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 17 23:20:56 mail zmmailboxdmgr[30206]: assuming no other instance is running
Nov 17 23:20:56 mail zmmailboxdmgr[30206]: no manager process is running
Nov 17 23:20:56 mail zmmailboxdmgr[30213]: start requested
Nov 17 23:20:56 mail zmmailboxdmgr[30213]: checking if another instance of manager is already running
Nov 17 23:20:56 mail zmmailboxdmgr[30213]: stale pid 5573 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 17 23:20:56 mail zmmailboxdmgr[30213]: assuming no other instance is running
Nov 17 23:20:56 mail zmmailboxdmgr[30213]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 17 23:20:56 mail zmmailboxdmgr[30213]: assuming no other instance is running
Nov 17 23:20:56 mail zmmailboxdmgr[30214]: wrote manager pid 30214 to /opt/zimbra/log/zmmailboxd_manager.pid
Nov 17 23:20:56 mail zmmailboxdmgr[30215]: wrote java pid 30215 to /opt/zimbra/log/zmmailboxd_java.pid
Nov 17 23:20:56 mail zmmailboxdmgr[30214]: manager started mailboxd/JVM with pid 30215

[phoenix]

When you ran the commands earlier for checking the proxy did you run all the commands and what was the output of the following:

zmprov gs `zmhostname` | grep zimbraServiceEnabled
zmprov gs `zmhostname` | grep zimbraServiceInstalled

Do you still have the Zimbra 6 installed? You should shutdown Zimbra then remove the pid file shown in this error message:

Nov 17 23:17:58 mail zmmailboxdmgr[25348]: stale pid 5573 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process

When you've done that restart Zimbra.