View Single Post
  #1 (permalink)  
Old 11-16-2009, 06:29 PM
wpram wpram is offline
Member
 
Posts: 14
Unhappy mailbox service not running after upgrade from 5.x to 6.x

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!
Reply With Quote