Investigated zimbra.log and found the first time the error message appeared
At the beginning of the log the server is booted,
after 10:59:24 the first entry with the error message appeared
(strangly the log reports ~ 09.00 o'clock,
the server wasn't powered up at that time). Quote:
Apr 13 10:58:14 mail zimbramon[2481]: 2481:info: Starting services
Apr 13 10:58:15 mail zmmailboxdmgr[2637]: status requested
Apr 13 10:58:15 mail zmmailboxdmgr[2637]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Apr 13 10:58:15 mail zmmailboxdmgr[2637]: assuming no other instance is running
Apr 13 10:58:15 mail zmmailboxdmgr[2637]: no manager process is running
Apr 13 10:58:15 mail slapd[2638]: @(#) $OpenLDAP: slapd 2.3.41 (Feb 27 2008 16:00:34) $ build@build10.re.zimbra.com:/home/build/p4/main/ThirdParty/openldap/openldap-2.3.41.6z/servers/slapd
Apr 13 10:58:16 mail slapd[2671]: slapd starting
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: antispam: Stopped
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: antivirus: Stopped
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: ldap: Stopped
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: logger: Stopped
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: mailbox: Stopped
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: mta: Stopped
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: snmp: Stopped
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: spell: Stopped
Apr 13 10:58:17 mail zimbramon[2303]: 2303:info: 2008-04-13 10:58:05, STATUS: mail.kavula.com: stats: Stopped
Apr 13 10:58:17 mail sendmail[2446]: m3D8wD2n002446: from=zimbra, size=429, class=0, nrcpts=1, msgid=<200804130858.m3D8wD2n002446@mail.kavula.com >, relay=zimbra@localhost
Apr 13 10:58:17 mail sendmail[2446]: m3D8wD2n002446: to=zimbra, ctladdr=zimbra (501/501), delay=00:00:04, xdelay=00:00:00, mailer=relay, pri=30429, relay=[127.0.0.1] [127.0.0.1], dsn=4.0.0, stat=Deferred: Connection refused by [127.0.0.1]
Apr 13 10:58:23 mail zimbramon[2481]: 2481:info: Rewriting configs antispam amavis antivirus amavis webxml mailbox amavis antispam antivirus mta sasl
Apr 13 10:58:34 mail zimbramon[2481]: 2481:info: Starting logger
Apr 13 10:58:35 mail zimbramon[2481]: 2481:info: Starting mailbox
Apr 13 10:58:35 mail zimbramon[3065]: 3065:info: zmmtaconfig: zmmtaconfig started on mail.kavula.com with loglevel=3 pid=3065
Apr 13 10:58:59 mail zmmailboxdmgr[3499]: status requested
Apr 13 10:58:59 mail zmmailboxdmgr[3499]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Apr 13 10:58:59 mail zmmailboxdmgr[3499]: assuming no other instance is running
Apr 13 10:58:59 mail zmmailboxdmgr[3499]: no manager process is running
Apr 13 10:59:00 mail zmmailboxdmgr[3506]: start requested
Apr 13 10:59:00 mail zmmailboxdmgr[3506]: checking if another instance of manager is already running
Apr 13 10:59:00 mail zmmailboxdmgr[3506]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Apr 13 10:59:00 mail zmmailboxdmgr[3506]: assuming no other instance is running
Apr 13 10:59:00 mail zmmailboxdmgr[3507]: wrote manager pid 3507 to /opt/zimbra/log/zmmailboxd.pid
Apr 13 10:59:00 mail zmmailboxdmgr[3507]: manager started mailboxd/JVM with pid 3508
Apr 13 10:59:00 mail zimbramon[2481]: 2481:info: Starting antispam
Apr 13 10:59:00 mail zimbramon[3539]: 3539:info: zmmtaconfig: zmmtaconfig already running at 3065
Apr 13 10:59:05 mail amavis[3572]: starting. /opt/zimbra/amavisd/sbin/amavisd at mail.kavula.com amavisd-new-2.5.2 (20070627), Unicode aware, LANG="de_DE.UTF-8"
Apr 13 10:59:05 mail amavis[3572]: user=501, EUID: 501 (501); group=, EGID: 501 502 501 5 4 (501 502 501 5 4)
Apr 13 10:59:05 mail amavis[3572]: Perl version 5.008008
Apr 13 10:59:08 mail amavis[3572]: SpamControl: init_pre_chroot done
Apr 13 10:59:08 mail amavis[3597]: Net::Server: Process Backgrounded
Apr 13 10:59:08 mail amavis[3597]: Net::Server: 2008/04/13-10:59:08 Amavis (type Net::Server::PreForkSimple) starting! pid(3597)
Apr 13 10:59:08 mail amavis[3597]: Net::Server: Binding to UNIX socket file /opt/zimbra/data/amavisd/amavisd.sock using SOCK_STREAM
Apr 13 10:59:08 mail amavis[3597]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Apr 13 10:59:08 mail amavis[3597]: Net::Server: Group Not Defined. Defaulting to EGID '501 502 501 5 4'
Apr 13 10:59:08 mail amavis[3597]: Net::Server: User Not Defined. Defaulting to EUID '501'
Apr 13 10:59:08 mail amavis[3597]: Module Amavis::Conf 2.091
Apr 13 10:59:08 mail amavis[3597]: Module Archive::Zip 1.23
Apr 13 10:59:08 mail amavis[3597]: Module Compress::Zlib 1.42
Apr 13 10:59:08 mail amavis[3597]: Module Convert::TNEF 0.17
Apr 13 10:59:08 mail amavis[3597]: Module Convert::UUlib 1.09
Apr 13 10:59:08 mail amavis[3597]: Module DBD::mysql 4.006
Apr 13 10:59:08 mail amavis[3597]: Module DBI 1.602
Apr 13 10:59:08 mail amavis[3597]: Module DB_File 1.816
Apr 13 10:59:08 mail amavis[3597]: Module Digest::MD5 2.36
Apr 13 10:59:08 mail amavis[3597]: Module Digest::SHA1 2.11
Apr 13 10:59:08 mail amavis[3597]: Module MIME::Entity 5.423
Apr 13 10:59:08 mail amavis[3597]: Module MIME::Parser 5.423
Apr 13 10:59:08 mail amavis[3597]: Module MIME::Tools 5.423
Apr 13 10:59:08 mail amavis[3597]: Module Mail::Header 2.02
Apr 13 10:59:08 mail amavis[3597]: Module Mail::Internet 2.02
Apr 13 10:59:08 mail amavis[3597]: Module Mail::SpamAssassin 3.002004
Apr 13 10:59:08 mail amavis[3597]: Module Net: NS 0.63
Apr 13 10:59:08 mail amavis[3597]: Module Net::LDAP 0.34
Apr 13 10:59:08 mail amavis[3597]: Module Net::Server 0.97
Apr 13 10:59:08 mail amavis[3597]: Module Time::HiRes 1.9712
Apr 13 10:59:08 mail amavis[3597]: Module URI 1.35
Apr 13 10:59:08 mail amavis[3597]: Module Unix::Syslog 1.0
Apr 13 10:59:08 mail amavis[3597]: Amavis: B code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: Amavis::Cache code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: SQL base code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: SQL::Log code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: SQL::Quarantine NOT loaded
Apr 13 10:59:08 mail amavis[3597]: Lookup::SQL code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: Lookup::LDAP code loaded
Apr 13 10:59:08 mail amavis[3597]: AM.PDP-in proto code loaded
Apr 13 10:59:08 mail amavis[3597]: SMTP-in proto code loaded
Apr 13 10:59:08 mail amavis[3597]: Courier proto code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: SMTP-out proto code loaded
Apr 13 10:59:08 mail amavis[3597]: Pipe-out proto code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: BSMTP-out proto code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: Local-out proto code loaded
Apr 13 10:59:08 mail amavis[3597]: OS_Fingerprint code NOT loaded
Apr 13 10:59:08 mail amavis[3597]: ANTI-VIRUS code loaded
Apr 13 10:59:08 mail amavis[3597]: ANTI-SPAM code loaded
Apr 13 10:59:08 mail amavis[3597]: ANTI-SPAM-SA code loaded
Apr 13 10:59:08 mail amavis[3597]: Unpackers code loaded
Apr 13 10:59:08 mail amavis[3597]: Found $file at /usr/bin/file
Apr 13 10:59:08 mail amavis[3597]: No $dspam, not using it
Apr 13 10:59:08 mail amavis[3597]: No $altermime, not using it
Apr 13 10:59:08 mail amavis[3597]: Internal decoder for .mail
Apr 13 10:59:08 mail amavis[3597]: Internal decoder for .asc
Apr 13 10:59:08 mail amavis[3597]: Internal decoder for .uue
Apr 13 10:59:08 mail amavis[3597]: Internal decoder for .hqx
Apr 13 10:59:08 mail amavis[3597]: Internal decoder for .ync
Apr 13 10:59:08 mail amavis[3597]: No decoder for .F tried: unfreeze, freeze -d, melt, fcat
Apr 13 10:59:08 mail amavis[3597]: Found decoder for .Z at /usr/bin/gzip -d
Apr 13 10:59:08 mail amavis[3597]: Found decoder for .gz at /usr/bin/gzip -d
Apr 13 10:59:08 mail amavis[3597]: Found decoder for .bz2 at /usr/bin/bzip2 -d
Apr 13 10:59:08 mail amavis[3597]: No decoder for .lzo tried: lzop -d
Apr 13 10:59:08 mail amavis[3597]: Found decoder for .rpm at /usr/bin/rpm2cpio
Apr 13 10:59:08 mail amavis[3597]: Found decoder for .cpio at /usr/bin/pax
Apr 13 10:59:08 mail amavis[3597]: Found decoder for .tar at /usr/bin/pax
Apr 13 10:59:08 mail amavis[3597]: Found decoder for .deb at /usr/bin/ar
Apr 13 10:59:08 mail amavis[3597]: Internal decoder for .zip
Apr 13 10:59:08 mail amavis[3597]: No decoder for .7z tried: 7zr, 7za, 7z
Apr 13 10:59:08 mail amavis[3597]: No decoder for .rar tried: rar, unrar
Apr 13 10:59:08 mail amavis[3597]: No decoder for .arj tried: arj, unarj
Apr 13 10:59:08 mail amavis[3597]: No decoder for .arc tried: nomarch, arc
Apr 13 10:59:08 mail amavis[3597]: No decoder for .zoo tried: zoo, unzoo
Apr 13 10:59:08 mail amavis[3597]: No decoder for .lha tried: lha
Apr 13 10:59:08 mail amavis[3597]: No decoder for .cab tried: cabextract
Apr 13 10:59:08 mail amavis[3597]: No decoder for .tnef tried: tnef
Apr 13 10:59:08 mail amavis[3597]: Internal decoder for .tnef
Apr 13 10:59:08 mail amavis[3597]: No decoder for .exe tried: rar, unrar; lha; arj, unarj
Apr 13 10:59:08 mail amavis[3597]: Using primary internal av scanner code for ClamAV-clamd
Apr 13 10:59:08 mail amavis[3597]: SpamControl: initializing Mail::SpamAssassin
Apr 13 10:59:10 mail zimbramon[2481]: 2481:info: Starting antivirus
Apr 13 10:59:12 mail amavis[3597]: SpamControl: init_pre_fork done
Apr 13 10:59:12 mail zimbramon[3639]: 3639:info: zmmtaconfig: zmmtaconfig already running at 3065
Apr 13 10:59:13 mail clamd[3711]: clamd daemon 0.92.1 (OS: linux-gnu, ARCH: i386, CPU: i686)
Apr 13 10:59:13 mail clamd[3711]: Log file size limited to 20971520 bytes.
Apr 13 10:59:13 mail clamd[3711]: Reading databases from /opt/zimbra/data/clamav/db
Apr 13 10:59:13 mail clamd[3711]: Not loading PUA signatures.
Apr 13 10:59:21 mail clamd[3711]: Loaded 424976 signatures.
Apr 13 10:59:21 mail clamd[3711]: Bound to tcp port 3310
Apr 13 10:59:21 mail clamd[3711]: Setting connection queue length to 15
Apr 13 10:59:21 mail clamd[3711]: Archive: Archived file size limit set to 104857600 bytes.
Apr 13 10:59:21 mail clamd[3711]: Archive: Recursion level limit set to 8.
Apr 13 10:59:21 mail clamd[3711]: Archive: Files limit set to 1000.
Apr 13 10:59:21 mail clamd[3711]: Archive: Compression ratio limit set to 250.
Apr 13 10:59:21 mail clamd[3711]: Archive support enabled.
Apr 13 10:59:21 mail clamd[3711]: Archive: Blocking encrypted archives.
Apr 13 10:59:21 mail clamd[3711]: Algorithmic detection enabled.
Apr 13 10:59:21 mail clamd[3711]: Portable Executable support enabled.
Apr 13 10:59:21 mail clamd[3711]: ELF support enabled.
Apr 13 10:59:21 mail clamd[3711]: Mail files support enabled.
Apr 13 10:59:21 mail clamd[3711]: Mail: Recursion level limit set to 64.
Apr 13 10:59:22 mail clamd[3711]: OLE2 support enabled.
Apr 13 10:59:22 mail clamd[3711]: PDF support disabled.
Apr 13 10:59:22 mail clamd[3711]: HTML support enabled.
Apr 13 10:59:22 mail clamd[3711]: Self checking every 1800 seconds.
Apr 13 10:59:22 mail zimbramon[2481]: 2481:info: Starting snmp
Apr 13 10:59:22 mail zimbramon[2481]: 2481:info: Starting spell
Apr 13 10:59:23 mail zimbramon[2481]: 2481:info: Starting mta
Apr 13 10:59:24 mail zimbramon[3775]: 3775:info: zmmtaconfig: zmmtaconfig already running at 3065
Apr 13 10:59:24 mail saslauthd[3839]: detach_tty : master pid is: 3839
Apr 13 10:59:24 mail saslauthd[3839]: ipc_init : listening on socket: /opt/zimbra/cyrus-sasl-2.1.22.3z/state/mux
Apr 13 10:59:24 mail zimbramon[2481]: 2481:info: Starting stats
Apr 13 08:59:31 mail postfix/postqueue[4154]: fatal: Queue report unavailable - mail system is down
Apr 13 09:00:00 mail postfix/postqueue[4264]: fatal: Queue report unavailable - mail system is down
Apr 13 09:00:01 mail postfix/postqueue[4315]: fatal: Queue report unavailable - mail system is down
Apr 13 11:00:01 mail zimbramon[4292]: 4292:info: 2008-04-13 11:00:01, QUEUE: 0 0
Apr 13 11:00:01 mail sendmail[4316]: m3D901bP004316: from=zimbra, size=379, class=0, nrcpts=1, msgid=<200804130900.m3D901bP004316@mail.kavula.com >, relay=zimbra@localhost
Apr 13 11:00:01 mail sendmail[4316]: m3D901bP004316: to=zimbra, ctladdr=zimbra (501/501), delay=00:00:00, xdelay=00:00:00, mailer=relay, pri=30379, relay=[127.0.0.1] [127.0.0.1], dsn=4.0.0, stat=Deferred: Connection refused by [127.0.0.1]
Apr 13 11:00:03 mail sendmail[4301]: m3D901WE004301: from=root, size=438, class=0, nrcpts=1, msgid=<200804130900.m3D901WE004301@mail.kavula.com >, relay=root@localhost
Apr 13 11:00:03 mail sendmail[4301]: m3D901WE004301: to=root, ctladdr=root (0/0), delay=00:00:02, xdelay=00:00:00, mailer=relay, pri=30438, relay=[127.0.0.1] [127.0.0.1], dsn=4.0.0, stat=Deferred: Connection refused by [127.0.0.1]
Apr 13 11:00:03 mail zimbramon[4297]: 4297:info: 2008-04-13 11:00:01, DISK: mail.kavula.com: dev: /dev/sdb1, mp: /, tot: 19376, avail: 14293
Apr 13 11:00:03 mail zimbramon[4297]: 4297:err: Disk warning: mail.kavula.com /dev/sdb2 at 86%
Apr 13 11:00:03 mail zimbramon[4297]: 4297:info: 2008-04-13 11:00:01, DISK: mail.kavula.com: dev: /dev/sda1, mp: /mnt/backup, tot: 469453, avail: 68987
Apr 13 11:00:09 mail zmmailboxdmgr[4604]: status requested
Apr 13 11:00:09 mail zmmailboxdmgr[4604]: status OK
Apr 13 11:00:10 mail zmmailboxdmgr[4737]: status requested
Apr 13 11:00:10 mail zmmailboxdmgr[4737]: status OK
Apr 13 11:00:11 mail zmmailboxdmgr[4777]: status requested
Apr 13 11:00:11 mail zmmailboxdmgr[4777]: status OK
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: antispam: Running
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: antivirus: Running
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: ldap: Running
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: logger: Running
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: mailbox: Running
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: mta: Running
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: snmp: Running
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: spell: Running
Apr 13 11:00:12 mail zimbramon[4299]: 4299:info: 2008-04-13 11:00:01, STATUS: mail.kavula.com: stats: Running
Apr 13 09:00:30 mail postfix/postqueue[4917]: fatal: Queue report unavailable - mail system is down
Apr 13 09:01:00 mail postfix/postqueue[4927]: fatal: Queue report unavailable - mail system is down
Apr 13 11:01:22 mail zmmailboxdmgr[5131]: status requested
Apr 13 11:01:22 mail zmmailboxdmgr[5131]: status OK
Apr 13 11:01:23 mail zmmailboxdmgr[5179]: status requested
Apr 13 11:01:23 mail zmmailboxdmgr[5179]: status OK
Apr 13 09:01:30 mail postfix/postqueue[5243]: fatal: Queue report unavailable - mail system is down
Apr 13 09:02:00 mail postfix/postqueue[5252]: fatal: Queue report unavailable - mail system is down
| |