mysql.server is not running

[Oswald-Kolle]

Originally Posted by wannabetenor

Yeah, looks like things are crashing.


I'd guess you don't have enough RAM. 512 defenetly isn't enough. It really depends on the server's load.

I'd say to run the zmfixperms script located in the libexec dir, and try again.

OKay - I did it again...
as zimbra: zmcontrol stop
as root: zmfixperms
as zimbra: zmcontrol start

No running system...
Logs:

Jan 27 20:48:05 XDOMAINX zmtomcatmgr[15593]: status requested
Jan 27 20:48:05 XDOMAINX zmtomcatmgr[15593]: status OK
Jan 27 20:48:05 XDOMAINX zimbramon[15505]: 15505:info: 2007-01-27 20:48:01, STATUS:
 XDOMAINX.eu: antispam: Running
Jan 27 20:48:05 XDOMAINX zimbramon[15505]: 15505:info: 2007-01-27 20:48:01, STATUS:
 XDOMAINX.eu: antivirus: Running
Jan 27 20:48:05 XDOMAINX zimbramon[15505]: 15505:info: 2007-01-27 20:48:01, STATUS:
 XDOMAINX.eu: ldap: Running
Jan 27 20:48:05 XDOMAINX zimbramon[15505]: 15505:info: 2007-01-27 20:48:01, STATUS:
 XDOMAINX.eu: logger: Running
Jan 27 20:48:05 XDOMAINX zimbramon[15505]: 15505:info: 2007-01-27 20:48:01, STATUS:
 XDOMAINX.eu: mailbox: Stopped
Jan 27 20:48:05 XDOMAINX zimbramon[15505]: 15505:info: 2007-01-27 20:48:01, STATUS:
 XDOMAINX.eu: mta: Running
Jan 27 20:48:05 XDOMAINX zimbramon[15505]: 15505:info: 2007-01-27 20:48:01, STATUS:
 XDOMAINX.eu: snmp: Running
Jan 27 20:48:05 XDOMAINX zimbramon[15505]: 15505:info: 2007-01-27 20:48:01, STATUS:
 XDOMAINX.eu: spell: Running
Jan 27 20:49:04 XDOMAINX zimbramon[15679]: 15679:info: Stopping services
Jan 27 20:49:04 XDOMAINX zimbramon[15679]: 15679:info: Stopping mta
Jan 27 20:49:04 XDOMAINX postfix/postfix-script: stopping the Postfix mail system
Jan 27 20:49:04 XDOMAINX postfix/master[13327]: terminating on signal 15
Jan 27 20:49:05 XDOMAINX zimbramon[15679]: 15679:info: Stopping spell
Jan 27 20:49:05 XDOMAINX zimbramon[15679]: 15679:info: Stopping snmp
Jan 27 20:49:05 XDOMAINX zimbramon[15679]: 15679:info: Stopping antivirus
Jan 27 20:49:05 XDOMAINX clamd[13348]: Pid file removed.
Jan 27 20:49:05 XDOMAINX clamd[13348]: Exiting (clean)
Jan 27 20:49:05 XDOMAINX clamd[13348]: --- Stopped at Sat Jan 27 20:49:05 2007
Jan 27 20:49:05 XDOMAINX zimbramon[15679]: 15679:info: Stopping antispam
Jan 27 20:49:05 XDOMAINX amavis[13320]: Net::Server: 2007/01/27-20:49:05 Server closing!
Jan 27 20:49:07 XDOMAINX zimbramon[15679]: 15679:info: Stopping imapproxy
Jan 27 20:49:12 XDOMAINX zimbramon[15679]: 15679:info: Stopping mailbox
Jan 27 20:49:12 XDOMAINX zmtomcatmgr[15793]: threaddump requested
Jan 27 20:49:12 XDOMAINX zmtomcatmgr[13204]: sending SIQUIT to tomcat/JVM process 13205
Jan 27 20:49:15 XDOMAINX zmtomcatmgr[15800]: stop requested
Jan 27 20:49:15 XDOMAINX zmtomcatmgr[13204]: shutdown requested, sending TERM signal to 13205
Jan 27 20:49:15 XDOMAINX zmtomcatmgr[15800]: waiting for manager process 13204 to die
Jan 27 20:49:17 XDOMAINX zmtomcatmgr[13204]: tomcat/JVM process exited (waitpid expected 13205 got 13205)
Jan 27 20:49:17 XDOMAINX zmtomcatmgr[13204]: manager woke up from wait on tomcat/JVM with pid 13205
Jan 27 20:49:17 XDOMAINX zmtomcatmgr[15800]: manager process 13204 died, shutdown completed
Jan 27 20:49:21 XDOMAINX zimbramon[15679]: 15679:info: Stopping logger
Jan 27 20:49:24 XDOMAINX zimbramon[15679]: 15679:info: Stopping ldap
Jan 27 20:50:01 XDOMAINX postfix/postqueue[15909]: fatal: Queue report unavailable
- mail system is down
Jan 27 20:50:02 XDOMAINX zimbramon[15878]: 15878:info: 2007-01-27 20:50:01, QUEUE:
0 0
Jan 27 20:50:04 XDOMAINX zimbramon[15882]: 15882:info: 2007-01-27 20:50:01, DISK: XDOMAINX.eu: dev: /dev/hda1, mp: /, tot: 16772, avail: 6277
Jan 27 20:50:07 XDOMAINX zimbramon[15884]: 15884:info: 2007-01-27 20:50:01, STATUS:
 : Cannot: determine
Jan 27 20:50:19 XDOMAINX zimbramon[16271]: 16271:info: Starting services
Jan 27 20:50:20 XDOMAINX slapd[16306]: @(#) $OpenLDAP: slapd 2.3.21 (Jan 11 2007 15:17:42) $ ^Iroot@build-debian.liquidsys.com:/home/build/p4/FRANK/ThirdParty/openldap/openldap-2.3.21/servers/slapd
Jan 27 20:50:25 XDOMAINX zimbramon[16271]: 16271:info: Rewriting configs  antispam
antivirus   webxml mailbox antispam antivirus mta sasl
Jan 27 20:50:37 XDOMAINX zimbramon[16271]: 16271:info: Starting logger
Jan 27 20:50:38 XDOMAINX zimbramon[16271]: 16271:info: Starting mailbox
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16701]: status requested
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16701]: file /opt/zimbra/log/zmtomcatmgr.pid does not exist
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16701]: assuming no other instance is running
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16701]: no manager process is running
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16708]: start requested
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16708]: checking if another instance of manager is already running
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16708]: file /opt/zimbra/log/zmtomcatmgr.pid does not exist
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16708]: assuming no other instance is running
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16709]: wrote manager pid 16709 to /opt/zimbra/log/zmtomcatmgr.pid
Jan 27 20:50:59 XDOMAINX zmtomcatmgr[16709]: manager started tomcat/JVM with pid 16710
Jan 27 20:50:59 XDOMAINX zimbramon[16271]: 16271:info: Starting antispam
Jan 27 20:50:59 XDOMAINX zimbramon[16271]: 16271:info: Starting antivirus
Jan 27 20:50:59 XDOMAINX clamd[16746]: Daemon started.
Jan 27 20:50:59 XDOMAINX clamd[16746]: clamd daemon 0.88.5 (OS: linux-gnu, ARCH: i386, CPU: i686)
Jan 27 20:50:59 XDOMAINX clamd[16746]: Log file size limited to 20971520 bytes.
Jan 27 20:50:59 XDOMAINX clamd[16746]: Reading databases from /opt/zimbra/clamav/db

Jan 27 20:50:59 XDOMAINX zimbramon[16271]: 16271:info: Starting snmp
Jan 27 20:51:00 XDOMAINX zimbramon[16271]: 16271:info: Starting spell
Jan 27 20:51:01 XDOMAINX zimbramon[16271]: 16271:info: Starting mta
Jan 27 20:51:02 XDOMAINX postfix/postqueue[16778]: fatal: Queue report unavailable
- mail system is down
Jan 27 20:51:06 XDOMAINX amavis[16727]: starting.  /opt/zimbra/amavisd/sbin/amavisd
 at XDOMAINX.eu amavisd-new-2.4.3 (20060930), Unicode aware, LANG=C
Jan 27 20:51:06 XDOMAINX amavis[16727]: user=1000, EUID: 1000 (1000);  group=, EGID: 1002 1003 1002 5 4 (1002 1003 1002 5 4); log_level=1
Jan 27 20:51:06 XDOMAINX amavis[16727]: Perl version               5.008004
Jan 27 20:51:08 XDOMAINX amavis[16727]: SpamControl: init_pre_chroot done
Jan 27 20:51:09 XDOMAINX amavis[16801]: Net::Server: Process Backgrounded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Net::Server: 2007/01/27-20:51:09 Amavis (type Net::Server::PreForkSimple) starting! pid(16801)
Jan 27 20:51:09 XDOMAINX amavis[16801]: Net::Server: Binding to UNIX socket file /opt/zimbra/amavisd/amavisd.sock using SOCK_STREAM
Jan 27 20:51:09 XDOMAINX amavis[16801]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Jan 27 20:51:09 XDOMAINX amavis[16801]: Net::Server: Group Not Defined.  Defaulting
 to EGID '1002 1003 1002 5 4'
Jan 27 20:51:09 XDOMAINX amavis[16801]: Net::Server: User Not Defined.  Defaulting
to EUID '1000'
Jan 27 20:51:09 XDOMAINX postfix/postfix-script: warning: not owned by root: /opt/zimbra/postfix-2.2.9/conf/main.cf
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Amavis::Conf        2.072
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Archive::Tar        1.30
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Archive::Zip        1.18
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module BerkeleyDB          0.31
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Compress::Zlib      1.42
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Convert::TNEF       0.17
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Convert::UUlib      1.08
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module DBD::mysql          4.001
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module DBI                 1.53
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module DB_File             1.814
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Digest::MD5         2.33
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module MIME::Entity        5.420
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module MIME::Parser        5.420
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module MIME::Tools         5.420
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Mail::Header        1.74
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Mail::Internet      1.74
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Mail::SpamAssassin  3.001007
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Net::Cmd            2.26
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Net::DNS            0.59
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Net::LDAP           0.33
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Net::SMTP           2.29
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Net::Server         0.94
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Time::HiRes         1.9704
Jan 27 20:51:09 XDOMAINX amavis[16801]: Module Unix::Syslog        0.99
Jan 27 20:51:09 XDOMAINX amavis[16801]: Amavis::DB code      loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Amavis::Cache code   loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: SQL base code        NOT loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: SQL::Log code        NOT loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: SQL::Quarantine      NOT loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Lookup::SQL code     NOT loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Lookup::LDAP code    loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: AM.PDP-in proto code loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: SMTP-in proto code   loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Courier proto code   NOT loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: SMTP-out proto code  loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Pipe-out proto code  NOT loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: BSMTP-out proto code NOT loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Local-out proto code loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: OS_Fingerprint code  NOT loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: ANTI-VIRUS code      loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: ANTI-SPAM code       loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: ANTI-SPAM-SA code    loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Unpackers code       loaded
Jan 27 20:51:09 XDOMAINX amavis[16801]: Found $file            at /usr/bin/file
Jan 27 20:51:09 XDOMAINX amavis[16801]: No $dspam,             not using it
Jan 27 20:51:09 XDOMAINX amavis[16801]: Internal decoder for .mail
Jan 27 20:51:09 XDOMAINX amavis[16801]: Internal decoder for .asc
Jan 27 20:51:09 XDOMAINX amavis[16801]: Internal decoder for .uue
Jan 27 20:51:09 XDOMAINX amavis[16801]: Internal decoder for .hqx
Jan 27 20:51:09 XDOMAINX amavis[16801]: Internal decoder for .ync
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .F    tried: unfreeze,
 freeze -d, melt, fcat
Jan 27 20:51:09 XDOMAINX amavis[16801]: Found decoder for    .Z    at /bin/uncompress
Jan 27 20:51:09 XDOMAINX amavis[16801]: Internal decoder for .gz
Jan 27 20:51:09 XDOMAINX amavis[16801]: Found decoder for    .bz2  at /usr/bin/bzip2 -d
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .lzo  tried: lzop -d
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .rpm  tried: rpm2cpio.pl, rpm2cpio
Jan 27 20:51:09 XDOMAINX amavis[16801]: Found decoder for    .cpio at /bin/cpio
Jan 27 20:51:09 XDOMAINX amavis[16801]: Found decoder for    .tar  at /bin/cpio
Jan 27 20:51:09 XDOMAINX amavis[16801]: Found decoder for    .deb  at /usr/bin/ar
Jan 27 20:51:09 XDOMAINX amavis[16801]: Internal decoder for .zip
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .rar  tried: rar, unrar
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .arj  tried: arj, unarj
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .arc  tried: nomarch,
arc
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .zoo  tried: zoo
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .lha  tried: lha
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .cab  tried: cabextract
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .tnef tried: tnef
Jan 27 20:51:09 XDOMAINX amavis[16801]: Internal decoder for .tnef
Jan 27 20:51:09 XDOMAINX amavis[16801]: No decoder for       .exe  tried: rar, unrar; lha; arj, unarj
Jan 27 20:51:09 XDOMAINX amavis[16801]: Using internal av scanner code for (primary) ClamAV-clamd
Jan 27 20:51:09 XDOMAINX amavis[16801]: Creating db in /opt/zimbra/amavisd/db/; BerkeleyDB 0.31, libdb 4.2
Jan 27 20:51:10 XDOMAINX postfix/postfix-script: warning: /usr/lib/sendmail and /usr/sbin/sendmail differ
Jan 27 20:51:10 XDOMAINX postfix/postfix-script: warning: Replace one by a symbolic
 link to the other
Jan 27 20:51:10 XDOMAINX postfix/postfix-script: starting the Postfix mail system
Jan 27 20:51:10 XDOMAINX postfix/master[16831]: daemon started -- version 2.2.9, configuration /opt/zimbra/postfix-2.2.9/conf
Jan 27 20:51:10 XDOMAINX postfix/qmgr[16833]: 2ADCC93622: from=, size=1118, nrcpt=2 (queue active)
Jan 27 20:51:10 XDOMAINX postfix/trivial-rewrite[16842]: warning: do not list domain XDOMAINX.eu in BOTH mydestination and virtual_mailbox_domains
Jan 27 20:51:13 XDOMAINX postfix/pickup[16832]: 54F39B7F65: uid=1001 from=
orig_id=DD4CAB7F44
Jan 27 20:51:13 XDOMAINX postfix/cleanup[16834]: 54F39B7F65: message-id=<20070127195112.54F39B7F65@XDOMAINX.eu>
Jan 27 20:51:13 XDOMAINX clamd[16746]: Protecting against 87366 viruses.
Jan 27 20:51:13 XDOMAINX clamd[16850]: Bound to port 3310
Jan 27 20:51:13 XDOMAINX clamd[16850]: Setting connection queue length to 15
Jan 27 20:51:13 XDOMAINX clamd[16850]: Archive: Archived file size limit set to 104857600 bytes.
Jan 27 20:51:13 XDOMAINX clamd[16850]: Archive: Recursion level limit set to 8.
Jan 27 20:51:13 XDOMAINX clamd[16850]: Archive: Files limit set to 1000.
Jan 27 20:51:13 XDOMAINX clamd[16850]: Archive: Compression ratio limit set to 250.

Jan 27 20:51:13 XDOMAINX clamd[16850]: Archive support enabled.
Jan 27 20:51:13 XDOMAINX clamd[16850]: Archive: RAR support disabled.
Jan 27 20:51:13 XDOMAINX clamd[16850]: Portable Executable support enabled.
Jan 27 20:51:13 XDOMAINX clamd[16850]: Mail files support enabled.
Jan 27 20:51:13 XDOMAINX clamd[16850]: OLE2 support enabled.
Jan 27 20:51:13 XDOMAINX clamd[16850]: HTML support enabled.
Jan 27 20:51:13 XDOMAINX clamd[16850]: Self checking every 1800 seconds.
Jan 27 20:51:14 XDOMAINX postfix/qmgr[16833]: 54F39B7F65: from=, size=511, nrcpt=1 (queue active)
Jan 27 20:51:14 XDOMAINX amavis[16801]: SpamControl: initializing Mail::SpamAssassin
Jan 27 20:51:16 XDOMAINX postfix/lmtp[16844]: 2ADCC93622: to=, relay=none, delay=4541, status=deferred (connect to XDOMAINX.eu[84.16.233.240]: Connection refused)
Jan 27 20:51:17 XDOMAINX amavis[16801]: SpamControl: init_pre_fork done
Jan 27 20:51:18 XDOMAINX amavis[16855]: (16855-01) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20070127T205118-16855:  ->  SIZE=511 Received: from XDOMAINX.eu ([127.0.0.1]) by localhost (XDOMAINX.eu [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for ; Sat, 27 Jan 2007 20:51:18 +0100 (CET)
Jan 27 20:51:18 XDOMAINX amavis[16855]: (16855-01) Checking: 4ajlEGEjjexI  -> 
Jan 27 20:51:27 XDOMAINX zmtomcatmgr[17212]: status requested
Jan 27 20:51:27 XDOMAINX zmtomcatmgr[17212]: status OK
Jan 27 20:51:31 XDOMAINX postfix/smtpd[17239]: warning: cannot get certificate from
 file /opt/zimbra/conf/smtpd.crt
Jan 27 20:51:31 XDOMAINX postfix/smtpd[17239]: warning: TLS library problem: 17239:error:02001002:system library:fopen:No such file or directory:bss_file.c:278:fopen('/opt/zimbra/conf/smtpd.crt','r'):
Jan 27 20:51:31 XDOMAINX postfix/smtpd[17239]: warning: TLS library problem: 17239:error:20074002:BIO routines:FILE_CTRL:system lib:bss_file.c:280:
Jan 27 20:51:31 XDOMAINX postfix/smtpd[17239]: warning: TLS library problem: 17239:error:140DC002:SSL routines:SSL_CTX_use_certificate_chain_file:system lib:ssl_rsa.c:760:
Jan 27 20:51:31 XDOMAINX postfix/smtpd[17239]: cannot load RSA certificate and key
data
Jan 27 20:51:31 XDOMAINX postfix/smtpd[17239]: connect from localhost[127.0.0.1]
Jan 27 20:51:31 XDOMAINX postfix/trivial-rewrite[16842]: warning: do not list domain XDOMAINX.eu in BOTH mydestination and virtual_mailbox_domains
Jan 27 20:51:31 XDOMAINX postfix/smtpd[17239]: D5F00B7F44: client=localhost[127.0.0.1]
Jan 27 20:51:31 XDOMAINX postfix/cleanup[16834]: D5F00B7F44: message-id=<20070127195112.54F39B7F65@XDOMAINX.eu>
Jan 27 20:51:31 XDOMAINX postfix/smtpd[17239]: disconnect from localhost[127.0.0.1]Jan 27 20:51:31 XDOMAINX amavis[16855]: (16855-01) FWD via SMTP: 
-> , BODY=8BITMIME 250 2.6.0 Ok, id=16855-01, from MTA([127.0.0.1]:10025): 250 Ok: queued as D5F00B7F44
Jan 27 20:51:32 XDOMAINX postfix/qmgr[16833]: D5F00B7F44: from=, size=1043, nrcpt=1 (queue active)
Jan 27 20:51:32 XDOMAINX postfix/trivial-rewrite[16842]: warning: do not list domain XDOMAINX.eu in BOTH mydestination and virtual_mailbox_domains
Jan 27 20:51:32 XDOMAINX amavis[16855]: (16855-01) Passed CLEAN, 
-> , Message-ID: <20070127195112.54F39B7F65@XDOMAINX.eu>, mail_id:
 4ajlEGEjjexI, Hits: -2.6, queued_as: D5F00B7F44, 14023 ms
Jan 27 20:51:32 XDOMAINX postfix/smtp[16851]: 54F39B7F65: to=, orig_to=, relay=127.0.0.1[127.0.0.1], delay=90, status=sent (250 2.6.0 Ok,
id=16855-01, from MTA([127.0.0.1]:10025): 250 Ok: queued as D5F00B7F44)
Jan 27 20:51:32 XDOMAINX postfix/qmgr[16833]: 54F39B7F65: removed
Jan 27 20:51:32 XDOMAINX postfix/local[17241]: D5F00B7F44: to=, relay=local, delay=1, status=sent (delivered to mailbox)
Jan 27 20:51:32 XDOMAINX postfix/qmgr[16833]: D5F00B7F44: removed

[jholder]

cat /opt/zimbra/log/mailbox.log

[Oswald-Kolle]

Originally Posted by wannabetenor

cat /opt/zimbra/log/mailbox.log

Here it is... (not the complete file of course)

2007-01-27 20:51:21,126 INFO  [main] [] misc - LANG environment is set to: C
2007-01-27 20:51:21,230 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-01-27 20:51:21,897 ERROR [main] [] Versions - DB Version Mismatch: ours="36" from DB="26"
2007-01-27 20:51:21,901 FATAL [main] [] soap - Unable to start servlet
java.lang.RuntimeException: Data version mismatch.  Reinitialize or upgrade the
backend data store.
        at com.zimbra.cs.util.Zimbra.startup(Zimbra.java:97)
        at com.zimbra.soap.SoapServlet.init(SoapServlet.java:115)
        at javax.servlet.GenericServlet.init(GenericServlet.java:211)
        at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1105)
        at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:932)
        at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:3915)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4176)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:759)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:739)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:524)
        at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:910)
        at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:873)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:474)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1118)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:310)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1020)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:718)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1012)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:442)
        at org.apache.catalina.core.StandardService.start(StandardService.java:450)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:700)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:551)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:275)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:406)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at com.zimbra.cs.launcher.TomcatLauncher.start(TomcatLauncher.java:52)
        at com.zimbra.cs.launcher.TomcatLauncher.main(TomcatLauncher.java:74)
2007-01-27 20:51:21,907 INFO  [main] [] [/service] - Marking servlet SoapServlet
 as unavailable
2007-01-27 20:51:21,908 ERROR [main] [] [/service] - Servlet /service threw load() exception
javax.servlet.UnavailableException: Data version mismatch.  Reinitialize or upgrade the backend data store.
        at com.zimbra.soap.SoapServlet.init(SoapServlet.java:118)
        at javax.servlet.GenericServlet.init(GenericServlet.java:211)
        at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1105)
        at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:932)
        at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:3915)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4176)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:759)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:739)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:524)
        at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:910)
        at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:873)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:474)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1118)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:310)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1020)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:718)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1012)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:442)
        at org.apache.catalina.core.StandardService.start(StandardService.java:450)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:700)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:551)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:275)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:406)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at com.zimbra.cs.launcher.TomcatLauncher.start(TomcatLauncher.java:52)
        at com.zimbra.cs.launcher.TomcatLauncher.main(TomcatLauncher.java:74)
2007-01-27 20:51:21,940 INFO  [main] [] soap - Servlet AdminServlet starting up
2007-01-27 20:51:22,200 INFO  [main] [] soap - Adding service AdminService to AdminServlet
2007-01-27 20:51:22,270 INFO  [main] [] soap - Adding service AccountService to
AdminServlet
2007-01-27 20:51:22,271 INFO  [main] [] soap - Adding service MailService to AdminServlet
2007-01-27 20:51:22,271 INFO  [main] [] soap - Adding service IMService to AdminServlet
2007-01-27 20:51:22,272 INFO  [main] [] misc - version=4.5.0_GA_612.DEBIAN3.1 release=20070115195930 builddate=20070115-1959 buildhost=build-debian.liquidsys.com
2007-01-27 20:51:22,272 INFO  [main] [] misc - LANG environment is set to: C
2007-01-27 20:51:22,272 ERROR [main] [] Versions - DB Version Mismatch: ours="36" from DB="26"
2007-01-27 20:51:22,272 FATAL [main] [] soap - Unable to start servlet
java.lang.RuntimeException: Data version mismatch.  Reinitialize or upgrade the
backend data store.
        at com.zimbra.cs.util.Zimbra.startup(Zimbra.java:97)
        at com.zimbra.soap.SoapServlet.init(SoapServlet.java:115)
        at javax.servlet.GenericServlet.init(GenericServlet.java:211)
        at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1105)
        at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:932)
        at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:3915)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4176)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:759)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:739)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:524)
        at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:910)
        at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:873)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:474)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1118)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:310)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1020)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:718)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1012)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:442)
        at org.apache.catalina.core.StandardService.start(StandardService.java:450)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:700)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:551)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:275)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:406)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at com.zimbra.cs.launcher.TomcatLauncher.start(TomcatLauncher.java:52)
        at com.zimbra.cs.launcher.TomcatLauncher.main(TomcatLauncher.java:74)
2007-01-27 20:51:22,274 INFO  [main] [] [/service] - Marking servlet AdminServlet as unavailable
2007-01-27 20:51:22,274 ERROR [main] [] [/service] - Servlet /service threw load() exception
javax.servlet.UnavailableException: Data version mismatch.  Reinitialize or upgrade the backend data store.
        at com.zimbra.soap.SoapServlet.init(SoapServlet.java:118)
        at javax.servlet.GenericServlet.init(GenericServlet.java:211)
        at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1105)
        at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:932)
        at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:3915)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4176)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:759)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:739)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:524)
        at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:910)
        at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:873)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:474)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1118)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:310)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1020)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:718)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1012)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:442)
        at org.apache.catalina.core.StandardService.start(StandardService.java:450)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:700)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:551)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:275)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:406)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at com.zimbra.cs.launcher.TomcatLauncher.start(TomcatLauncher.java:52)
        at com.zimbra.cs.launcher.TomcatLauncher.main(TomcatLauncher.java:74)
2007-01-27 20:51:22,287 INFO  [main] [] ContentServlet - Servlet ContentServlet
starting up
2007-01-27 20:51:22,293 INFO  [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-01-27 20:51:22,300 INFO  [main] [] account - Servlet PreAuthServlet starting up
2007-01-27 20:51:22,436 INFO  [main] [] mailbox - Servlet UserServlet starting up
2007-01-27 20:51:22,456 INFO  [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-01-27 20:51:22,466 INFO  [main] [] StatsImageServlet - Servlet StatsImageServlet starting up

[jholder]

2007-01-27 20:51:21,230 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-01-27 20:51:21,897 ERROR [main] [] Versions - DB Version Mismatch: ours="36" from DB="26"
2007-01-27 20:51:21,901 FATAL [main] [] soap - Unable to start servlet
java.lang.RuntimeException: Data version mismatch.  Reinitialize or upgrade the
backend data store.

There's your problem.
This can be a very bad error, so please make sure you have a good backup!

I'd suggest re-running the upgrade script. . .but make sure you have a backup!

[Oswald-Kolle]

I rerun the update script, but it always fails at the same place (starting mysql)...

It seems like my newest backup doesn't work - I now try a clean/new installation and then replace the database-files with the old ones and copy the mail-data in the new (empty) folders... I hope this will work...
I cannot understand, why the newest backup doesn't work - i made it right after stopping the zimbra-mailserver... :-( It seems like there had already been an error before... :-(

[Oswald-Kolle]

Well... I made a clean new installation... (seems like there is a new database-structure in the 4.5 version so I haven't even copied the old date...)

Is there any way to save/import the old calendar and contact data? I don't have the uptodate export of the contacts and the calendar doesn't offer an export/import function :-(
Is there a possibility to get these informations out of the database-files of my backup?

And one other thing now:
There is a cron error now:

error: error running shared postrotate script for /var/log/mysql.log /var/log/mysql/mysql.log /var/log/mysql.err /var/log/mysql/mysql.err /var/log/mysql/mysql-slow.log
run-parts: /etc/cron.daily/logrotate exited with return code 1
/etc/cron.daily/mysql-server-41:
Unknown problem retrieving MySQL master log filenames in /etc/cron.daily/mysql-server-41.
mysql: /usr/lib/libmysqlclient.so.14: no version information available (required by mysql)
ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: YES)
run-parts: /etc/cron.daily/mysql-server-41 exited with return code 1

Do I have to set the root password myself and in the "OLD_PASSWORD" type?

[rudal]

hmm i have a different error result actually:
Host zimbra.domain.com
antispam Running
antivirus Running
ldap Running
logger Running
mailbox Stopped
mysql.server is not running
mta Running
snmp Running
spell Running

under /var/log/zimbra.log:

Apr 30 22:19:45 zimbra zimbramon[5388]: 5388:info: Starting services
Apr 30 22:19:47 zimbra zimbramon[5388]: 5388:info: Rewriting configs  antispam antivirus   webxml mailbox antispam antivirus mta sasl   
Apr 30 22:19:52 zimbra zimbramon[5388]: 5388:info: Starting logger 
Apr 30 22:19:52 zimbra zimbramon[5388]: 5388:info: Starting mailbox 
Apr 30 22:19:59 zimbra zmtomcatmgr[6082]: status requested
Apr 30 22:19:59 zimbra zmtomcatmgr[6082]: status OK
Apr 30 22:19:59 zimbra zimbramon[5388]: 5388:info: Starting antispam 
Apr 30 22:20:00 zimbra zimbramon[5388]: 5388:info: Starting antivirus 
Apr 30 22:20:00 zimbra zimbramon[5388]: 5388:info: Starting snmp 
Apr 30 22:20:01 zimbra zimbramon[5388]: 5388:info: Starting spell 
Apr 30 22:20:01 zimbra zimbramon[5388]: 5388:info: Starting mta 
Apr 30 22:20:02 zimbra zimbramon[7485]: 7485:info: 2007-04-30 22:20:02, QUEUE: 0 0 
Apr 30 22:20:05 zimbra zmtomcatmgr[7729]: status requested
Apr 30 22:20:05 zimbra zmtomcatmgr[7729]: status OK
Apr 30 22:20:06 zimbra zimbramon[7486]: 7486:info: 2007-04-30 22:20:02, STATUS: zimbra.domain.com: antispam: Running 
Apr 30 22:20:06 zimbra zimbramon[7486]: 7486:info: 2007-04-30 22:20:02, STATUS: zimbra.domain.com: antivirus: Running 
Apr 30 22:20:06 zimbra zimbramon[7486]: 7486:info: 2007-04-30 22:20:02, STATUS: zimbra.domain.com: ldap: Running 
Apr 30 22:20:06 zimbra zimbramon[7486]: 7486:info: 2007-04-30 22:20:02, STATUS: zimbra.domain.com: logger: Running 
Apr 30 22:20:06 zimbra zimbramon[7486]: 7486:info: 2007-04-30 22:20:02, STATUS: zimbra.domain.com: mailbox: Stopped 
Apr 30 22:20:06 zimbra zimbramon[7486]: 7486:info: 2007-04-30 22:20:02, STATUS: zimbra.domain.com: mta: Running 
Apr 30 22:20:06 zimbra zimbramon[7486]: 7486:info: 2007-04-30 22:20:02, STATUS: zimbra.domain.com: snmp: Running 
Apr 30 22:20:06 zimbra zimbramon[7486]: 7486:info: 2007-04-30 22:20:02, STATUS: zimbra.domain.com: spell: Running

"mailbox: Stopped" confuses me. and no error log.

in /opt/zimbra/log/mailbox.log - nothing printed out.
All I got is the one that I tried to login as the admin:

2007-04-30 22:08:09,218 INFO [http-7071-Processor50] [ua=ZimbraWebClient - FF2.0 (Win)/undefined;ip=x.x.x.x;] mailbox - Creating database mboxgroup1
2007-04-30 22:08:10,214 INFO [http-7071-Processor50] [ua=ZimbraWebClient - FF2.0 (Win)/undefined;ip=x.x.x.x;] cache - Initializing folder and tag caches for mailbox 1
2007-04-30 22:08:10,273 INFO [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-04-30 22:20:29,698 INFO [Timer-0] [] SessionCache - Removed 1 idle sessions (AdminSession: 1). 0 active sessions remain.

ps axu | grep mysql

15808 pts/0    S      0:00 /bin/sh /opt/zimbra/logger/mysql/bin/mysqld_safe --defaults-file=/opt/zimbra/conf/my.logger.cnf --ledir=/opt/zimbra/logger/mysql/libexec
15873 pts/0    Sl     0:00 /opt/zimbra/logger/mysql/libexec/mysqld --defaults-file=/opt/zimbra/conf/my.logger.cnf --basedir=/opt/zimbra/logger/mysql --datadir=/opt/zimbra/logger/db/data --pid-file=/opt/zimbra/logger/db/mysql.pid --skip-external-locking --port=7307 --socket=/opt/zimbra/logger/db/mysql.sock
24111 pts/0    S      0:00 /bin/sh /opt/zimbra/mysql/bin/mysqld_safe --defaults-file=/opt/zimbra/conf/my.cnf --ledir=/opt/zimbra/mysql/libexec
24144 pts/0    Sl     0:02 /opt/zimbra/mysql/libexec/mysqld --defaults-file=/opt/zimbra/conf/my.cnf --basedir=/opt/zimbra/mysql --datadir=/opt/zimbra/db/data --pid-file=/opt/zimbra/db/mysql.pid --skip-external-locking --port=7306 --socket=/opt/zimbra/db/mysql.sock
20445 pts/2    S+     0:00 grep mysql

[zimbra@zimbra ~]$ mysql mysql
Reading table information for completion of table and column names
You can turn off this feature to get a quicker startup with -A

Welcome to the MySQL monitor.  Commands end with ; or \g.
Your MySQL connection id is 19
Server version: 5.0.33-log Source distribution

Type 'help;' or '\h' for help. Type '\c' to clear the buffer.

mysql>

[jholder]

Look in
/opt/zimbra/db/data/(hostname).err
for anything in there.