Code:
CompilerOracle: exclude com/zimbra/cs/session/SessionMap putAndPrune
CompilerOracle: exclude com/zimbra/cs/mailbox/MailItem delete
0 INFO [main] log - Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog
Zimbra server reserving server socket port=110 bindaddr=null ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=true
Zimbra server reserving server socket port=143 bindaddr=null ssl=false
Zimbra server reserving server socket port=993 bindaddr=null ssl=true
Zimbra server reserving server socket port=7025 bindaddr=null ssl=false
2437 WARN [main] log - Config error at
2437 WARN [main] log - Config error at
2440 WARN [main] log - EXCEPTION
java.lang.reflect.InvocationTargetException
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.mortbay.xml.XmlConfiguration.call(XmlConfiguration.java:534)
at org.mortbay.xml.XmlConfiguration.configure(XmlConfiguration.java:244)
at org.mortbay.xml.XmlConfiguration.refObj(XmlConfiguration.java:638)
at org.mortbay.xml.XmlConfiguration.configure(XmlConfiguration.java:252)
at org.mortbay.xml.XmlConfiguration.configure(XmlConfiguration.java:206)
at org.mortbay.xml.XmlConfiguration.main(XmlConfiguration.java:968)
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.mortbay.start.Main.invokeMain(Main.java:183)
at org.mortbay.start.Main.start(Main.java:497)
at org.mortbay.start.Main.main(Main.java:115)
Caused by: java.net.BindException: Address already in use
at sun.nio.ch.Net.bind(Native Method)
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:119)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:59)
at org.mortbay.jetty.nio.SelectChannelConnector.open(SelectChannelConnector.java:211)
... 17 more
2448 WARN [main] log - Nested in java.lang.reflect.InvocationTargetException:
java.net.BindException: Address already in use
at sun.nio.ch.Net.bind(Native Method)
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:119)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:59)
at org.mortbay.jetty.nio.SelectChannelConnector.open(SelectChannelConnector.java:211)
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.mortbay.xml.XmlConfiguration.call(XmlConfiguration.java:534)
at org.mortbay.xml.XmlConfiguration.configure(XmlConfiguration.java:244)
at org.mortbay.xml.XmlConfiguration.refObj(XmlConfiguration.java:638)
at org.mortbay.xml.XmlConfiguration.configure(XmlConfiguration.java:252)
Code:
Nov 26 21:28:17 server01 zimbramon[16504]: 16504:info: Starting services initiated by zmcontrol
Nov 26 21:28:20 server01 zimbramon[16504]: 16504:info: Rewriting configs antispam amavis antivirus amavis webxml mailbox amavis antispam antivirus mta sasl
Nov 26 21:28:38 server01 zimbramon[16504]: 16504:info: Starting logger via zmcontrol
Nov 26 21:28:38 server01 zimbramon[16504]: 16504:info: Starting mailbox via zmcontrol
Nov 26 21:28:39 server01 zimbramon[16988]: 16988:info: zmmtaconfig: zmmtaconfig started on server01.domain.net with loglevel=3 pid=16988
Nov 26 21:29:39 server01 zmmailboxdmgr[17776]: status requested
Nov 26 21:29:39 server01 zmmailboxdmgr[17776]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
Nov 26 21:29:39 server01 zmmailboxdmgr[17776]: assuming no other instance is running
Nov 26 21:29:39 server01 zmmailboxdmgr[17776]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 26 21:29:39 server01 zmmailboxdmgr[17776]: assuming no other instance is running
Nov 26 21:29:39 server01 zmmailboxdmgr[17776]: no manager process is running
Nov 26 21:29:39 server01 zmmailboxdmgr[17783]: start requested
Nov 26 21:29:39 server01 zmmailboxdmgr[17783]: checking if another instance of manager is already running
Nov 26 21:29:39 server01 zmmailboxdmgr[17783]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
Nov 26 21:29:39 server01 zmmailboxdmgr[17783]: assuming no other instance is running
Nov 26 21:29:39 server01 zmmailboxdmgr[17783]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 26 21:29:39 server01 zmmailboxdmgr[17783]: assuming no other instance is running
Nov 26 21:29:39 server01 zmmailboxdmgr[17784]: wrote manager pid 17784 to /opt/zimbra/log/zmmailboxd_manager.pid
Nov 26 21:29:39 server01 zmmailboxdmgr[17784]: manager started mailboxd/JVM with pid 17785
Nov 26 21:29:39 server01 zmmailboxdmgr[17785]: wrote java pid 17785 to /opt/zimbra/log/zmmailboxd_java.pid
Nov 26 21:29:39 server01 zimbramon[16504]: 16504:info: Starting antispam via zmcontrol
Nov 26 21:29:45 server01 amavis[17842]: starting. /opt/zimbra/amavisd/sbin/amavisd at server01.domain.net amavisd-new-2.5.4 (20080312), Unicode aware, LANG="C"
Nov 26 21:29:45 server01 amavis[17842]: user=501, EUID: 501 (501); group=, EGID: 501 502 501 5 4 (501 502 501 5 4)
Nov 26 21:29:45 server01 amavis[17842]: Perl version 5.008008
Nov 26 21:29:46 server01 zmmailboxdmgr[17784]: manager woke up from wait on mailboxd/JVM with pid 17785
Nov 26 21:29:46 server01 zmmailboxdmgr[17850]: wrote java pid 17850 to /opt/zimbra/log/zmmailboxd_java.pid
Nov 26 21:29:46 server01 zmmailboxdmgr[17784]: manager started mailboxd/JVM with pid 17850
Nov 26 21:29:47 server01 amavis[17842]: SpamControl: init_pre_chroot done
Nov 26 21:29:47 server01 amavis[17862]: Net::Server: Process Backgrounded
Nov 26 21:29:47 server01 amavis[17862]: Net::Server: 2008/11/26-21:29:47 Amavis (type Net::Server::PreForkSimple) starting! pid(17862)
Nov 26 21:29:47 server01 amavis[17862]: Net::Server: Binding to UNIX socket file /opt/zimbra/data/amavisd/amavisd.sock using SOCK_STREAM
Nov 26 21:29:47 server01 amavis[17862]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Nov 26 21:29:47 server01 amavis[17862]: Net::Server: Group Not Defined. Defaulting to EGID '501 502 501 5 4'
Nov 26 21:29:47 server01 amavis[17862]: Net::Server: User Not Defined. Defaulting to EUID '501'
Nov 26 21:29:47 server01 amavis[17862]: Module Amavis::Conf 2.094
Nov 26 21:29:47 server01 amavis[17862]: Module Archive::Zip 1.23
Nov 26 21:29:47 server01 amavis[17862]: Module Compress::Zlib 1.42
Nov 26 21:29:47 server01 amavis[17862]: Module Convert::TNEF 0.17
Nov 26 21:29:47 server01 amavis[17862]: Module Convert::UUlib 1.11
Nov 26 21:29:47 server01 amavis[17862]: Module DBD::mysql 4.007
Nov 26 21:29:47 server01 amavis[17862]: Module DBI 1.605
Nov 26 21:29:47 server01 amavis[17862]: Module DB_File 1.817
Nov 26 21:29:47 server01 amavis[17862]: Module Digest::MD5 2.36
Nov 26 21:29:47 server01 amavis[17862]: Module Digest::SHA1 2.11
Nov 26 21:29:47 server01 amavis[17862]: Module IO::Socket::INET6 2.51
Nov 26 21:29:47 server01 amavis[17862]: Module MIME::Entity 5.426
Nov 26 21:29:47 server01 amavis[17862]: Module MIME::Parser 5.426
Nov 26 21:29:47 server01 amavis[17862]: Module MIME::Tools 5.426
Nov 26 21:29:47 server01 amavis[17862]: Module Mail::Header 2.03
Nov 26 21:29:47 server01 amavis[17862]: Module Mail::Internet 2.03
Nov 26 21:29:47 server01 amavis[17862]: Module Mail::SpamAssassin 3.002005
Nov 26 21:29:47 server01 amavis[17862]: Module Net::DNS 0.63
Nov 26 21:29:47 server01 amavis[17862]: Module Net::LDAP 0.36
Nov 26 21:29:47 server01 amavis[17862]: Module Net::Server 0.97
Nov 26 21:29:47 server01 amavis[17862]: Module Time::HiRes 1.9715
Nov 26 21:29:47 server01 amavis[17862]: Module URI 1.37
Nov 26 21:29:47 server01 amavis[17862]: Module Unix::Syslog 1.1
Nov 26 21:29:47 server01 amavis[17862]: Amavis::DB code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: Amavis::Cache code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: SQL base code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: SQL::Log code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: SQL::Quarantine NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: Lookup::SQL code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: Lookup::LDAP code loaded
Nov 26 21:29:47 server01 amavis[17862]: AM.PDP-in proto code loaded
Nov 26 21:29:47 server01 amavis[17862]: SMTP-in proto code loaded
Nov 26 21:29:47 server01 amavis[17862]: Courier proto code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: SMTP-out proto code loaded
Nov 26 21:29:47 server01 amavis[17862]: Pipe-out proto code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: BSMTP-out proto code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: Local-out proto code loaded
Nov 26 21:29:47 server01 amavis[17862]: OS_Fingerprint code NOT loaded
Nov 26 21:29:47 server01 amavis[17862]: ANTI-VIRUS code loaded
Nov 26 21:29:47 server01 amavis[17862]: ANTI-SPAM code loaded
Nov 26 21:29:47 server01 amavis[17862]: ANTI-SPAM-SA code loaded
Nov 26 21:29:47 server01 amavis[17862]: Unpackers code loaded
Nov 26 21:29:47 server01 amavis[17862]: Found $file at /usr/bin/file
Nov 26 21:29:47 server01 amavis[17862]: No $dspam, not using it
Nov 26 21:29:47 server01 amavis[17862]: No $altermime, not using it
Nov 26 21:29:47 server01 amavis[17862]: Internal decoder for .mail
Nov 26 21:29:47 server01 amavis[17862]: Internal decoder for .asc
Nov 26 21:29:47 server01 amavis[17862]: Internal decoder for .uue
Nov 26 21:29:47 server01 amavis[17862]: Internal decoder for .hqx
Nov 26 21:29:47 server01 amavis[17862]: Internal decoder for .ync
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .F tried: unfreeze, freeze -d, melt, fcat
Nov 26 21:29:47 server01 amavis[17862]: Found decoder for .Z at /usr/bin/gzip -d
Nov 26 21:29:47 server01 amavis[17862]: Found decoder for .gz at /usr/bin/gzip -d
Nov 26 21:29:47 server01 amavis[17862]: Found decoder for .bz2 at /usr/bin/bzip2 -d
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .lzo tried: lzop -d
Nov 26 21:29:47 server01 amavis[17862]: Found decoder for .rpm at /usr/bin/rpm2cpio
Nov 26 21:29:47 server01 amavis[17862]: Found decoder for .cpio at /usr/bin/pax
Nov 26 21:29:47 server01 amavis[17862]: Found decoder for .tar at /usr/bin/pax
Nov 26 21:29:47 server01 amavis[17862]: Found decoder for .deb at /usr/bin/ar
Nov 26 21:29:47 server01 amavis[17862]: Internal decoder for .zip
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .7z tried: 7zr, 7za, 7z
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .rar tried: rar, unrar
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .arj tried: arj, unarj
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .arc tried: nomarch, arc
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .zoo tried: zoo, unzoo
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .lha tried: lha
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .cab tried: cabextract
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .tnef tried: tnef
Nov 26 21:29:47 server01 amavis[17862]: Internal decoder for .tnef
Nov 26 21:29:47 server01 amavis[17862]: No decoder for .exe tried: rar, unrar; lha; arj, unarj
Nov 26 21:29:47 server01 amavis[17862]: Using primary internal av scanner code for ClamAV-clamd
Nov 26 21:29:47 server01 amavis[17862]: SpamControl: initializing Mail::SpamAssassin
Nov 26 21:29:48 server01 zimbramon[16504]: 16504:info: Starting antivirus via zmcontrol
Nov 26 21:29:51 server01 zmmailboxdmgr[17784]: manager woke up from wait on mailboxd/JVM with pid 17850
Nov 26 21:29:51 server01 zmmailboxdmgr[17784]: mailboxd/JVM exited twice in 5 seconds (tolerance=60)
Nov 26 21:29:51 server01 clamd[17927]: clamd daemon 0.93.3 (OS: linux-gnu, ARCH: i386, CPU: i686)
Nov 26 21:29:51 server01 clamd[17927]: Log file size limited to 20971520 bytes.
Nov 26 21:29:51 server01 clamd[17927]: Reading databases from /opt/zimbra/data/clamav/db
Nov 26 21:29:51 server01 clamd[17927]: Not loading PUA signatures.
Nov 26 21:29:52 server01 amavis[17862]: SpamControl: init_pre_fork done
Nov 26 21:30:01 server01 clamd[17927]: Loaded 344241 signatures.
Nov 26 21:30:01 server01 clamd[17927]: TCP: Bound to port 3310
Nov 26 21:30:01 server01 clamd[17927]: TCP: Setting connection queue length to 15
Nov 26 21:30:01 server01 clamd[17941]: Limits: Global size limit set to 10240000 bytes.
Nov 26 21:30:01 server01 clamd[17941]: Limits: File size limit set to 10240000 bytes.
Nov 26 21:30:01 server01 clamd[17941]: Limits: Recursion level limit set to 16.
Nov 26 21:30:01 server01 clamd[17941]: Limits: Files limit set to 10000.
Nov 26 21:30:01 server01 clamd[17941]: Archive support enabled.
Nov 26 21:30:01 server01 clamd[17941]: Archive: Blocking encrypted archives.
Nov 26 21:30:01 server01 clamd[17941]: Algorithmic detection enabled.
Nov 26 21:30:01 server01 clamd[17941]: Portable Executable support enabled.
Nov 26 21:30:01 server01 clamd[17941]: ELF support enabled.
Nov 26 21:30:01 server01 clamd[17941]: Mail files support enabled.
Nov 26 21:30:01 server01 clamd[17941]: OLE2 support enabled.
Nov 26 21:30:01 server01 clamd[17941]: PDF support disabled.
Nov 26 21:30:01 server01 clamd[17941]: HTML support enabled.
Nov 26 21:30:01 server01 clamd[17941]: Self checking every 1800 seconds.
Nov 26 21:30:01 server01 zimbramon[16504]: 16504:info: Starting snmp via zmcontrol
Nov 26 21:30:02 server01 zimbramon[16504]: 16504:info: Starting spell via zmcontrol
Nov 26 21:30:07 server01 zimbramon[16504]: 16504:info: Starting mta via zmcontrol
Nov 26 21:30:45 server01 zmmailboxdmgr[18563]: status requested
Nov 26 21:30:45 server01 zmmailboxdmgr[18563]: stale pid 17784 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 26 21:30:45 server01 zmmailboxdmgr[18563]: assuming no other instance is running
Nov 26 21:30:45 server01 zmmailboxdmgr[18563]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 26 21:30:45 server01 zmmailboxdmgr[18563]: assuming no other instance is running
Nov 26 21:30:45 server01 zmmailboxdmgr[18563]: no manager process is running
Nov 26 21:30:47 server01 zmmailboxdmgr[18624]: status requested
Nov 26 21:30:47 server01 zmmailboxdmgr[18624]: stale pid 17784 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 26 21:30:47 server01 zmmailboxdmgr[18624]: assuming no other instance is running
Nov 26 21:30:47 server01 zmmailboxdmgr[18624]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 26 21:30:47 server01 zmmailboxdmgr[18624]: assuming no other instance is running
Nov 26 21:30:47 server01 zmmailboxdmgr[18624]: no manager process is running
Nov 26 21:30:48 server01 postfix/postfix-script[18749]: warning: not owned by root: /opt/zimbra/postfix-2.4.7.5z/conf/main.cf
Nov 26 21:30:48 server01 postfix/postfix-script[18763]: starting the Postfix mail system
Nov 26 21:30:48 server01 postfix/master[18764]: daemon started -- version 2.4.7, configuration /opt/zimbra/postfix-2.4.7.5z/conf
Nov 26 21:30:48 server01 zimbramon[16504]: 16504:info: Starting stats via zmcontrol
Nov 26 21:30:48 server01 saslauthd[18772]: detach_tty : master pid is: 18772
Nov 26 21:30:48 server01 saslauthd[18772]: ipc_init : listening on socket: /opt/zimbra/cyrus-sasl-2.1.22.3z/state/mux
Nov 26 21:30:49 server01 clamd[17941]: Reading databases from /opt/zimbra/data/clamav/db
Nov 26 21:31:03 server01 clamd[17941]: Database correctly reloaded (468786 signatures)
Nov 26 21:31:08 server01 zmmailboxdmgr[19370]: status requested
Nov 26 21:31:08 server01 zmmailboxdmgr[19370]: stale pid 17784 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 26 21:31:51 server01 zmmailboxdmgr[20463]: assuming no other instance is running
Nov 26 21:31:51 server01 zmmailboxdmgr[20463]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 26 21:31:51 server01 zmmailboxdmgr[20463]: assuming no other instance is running
Nov 26 21:31:51 server01 zmmailboxdmgr[20463]: no manager process is running
Nov 26 21:32:18 server01 zimbramon[16988]: 16988:err: Service status change: server01.domain.net sasl changed from stopped to running
Nov 26 21:32:20 server01 postfix/pickup[18771]: 223966681E4: uid=501 from=
Nov 26 21:32:20 server01 postfix/cleanup[20870]: 223966681E4: message-id=<20081127053220.223966681E4@server01.domain.net>
Nov 26 21:32:20 server01 postfix/qmgr[18770]: 223966681E4: from=, size=511, nrcpt=1 (queue active)
Nov 26 21:32:20 server01 zimbramon[16988]: 16988:err: Service status change: server01.domain.net stats changed from stopped to running
Nov 26 21:32:21 server01 amavis[17930]: (17930-01) ESMTP::10024 /opt/zimbra/data/amavisd/tmp/amavis-20081126T213220-17930: -> ->
Nov 26 21:32:21 server01 postfix/pickup[18771]: 8B4066681EB: uid=501 from=
Nov 26 21:32:21 server01 postfix/cleanup[20870]: 8B4066681EB: message-id=<20081127053221.8B4066681EB@server01.domain.net>
Nov 26 21:32:21 server01 postfix/qmgr[18770]: 8B4066681EB: from=, size=513, nrcpt=1 (queue active)
Nov 26 21:32:21 server01 amavis[17931]: (17931-01) ESMTP::10024 /opt/zimbra/data/amavisd/tmp/amavis-20081126T213221-17931: -> ->
Nov 26 21:32:22 server01 postfix/smtpd[20890]: connect from localhost.localdomain[127.0.0.1]
Nov 26 21:32:22 server01 postfix/smtpd[20890]: 8915D6681F1: client=localhost.localdomain[127.0.0.1]
Nov 26 21:32:22 server01 postfix/cleanup[20870]: 8915D6681F1: message-id=<20081127053220.223966681E4@server01.domain.net>
Nov 26 21:32:22 server01 postfix/smtpd[20907]: connect from localhost.localdomain[127.0.0.1]