All pastes #803974 Raw Edit

Stuff

public text v1 · immutable
#803974 ·published 2007-12-02 23:56 UTC
rendered paste body
 cat /opt/zimbra/log/mailbox.log
2007-12-02 14:00:26,264 INFO  [main] [] soap - Servlet SoapServlet starting up
2007-12-02 14:00:26,386 INFO  [main] [] soap - Adding service AccountService to SoapServlet
2007-12-02 14:00:26,578 INFO  [main] [] soap - Adding service MailService to SoapServlet
2007-12-02 14:00:26,693 INFO  [main] [] soap - Adding service IMService to SoapServlet
2007-12-02 14:00:26,701 INFO  [main] [] misc - version=4.5.7_GA_1319.UBUNTU6 release=20070914180722 builddate=20070914-1808 buildhost=build-ubuntu
2007-12-02 14:00:26,702 INFO  [main] [] misc - LANG environment is set to: en_US.UTF-8
2007-12-02 14:00:26,731 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_calendar_force_utc) was null, returning empty string
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_validate_outgoing_icalendar) was null, returning empty string
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_disable_conversation) was null, returning empty string
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_disable_filter) was null, returning empty string
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_disable_message_analysis) was null, returning empty string
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_disable_mime_part_extraction) was null, returning empty string
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_disable_indexing) was null, returning empty string
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_disable_objects) was null, returning empty string
2007-12-02 14:00:27,190 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_separately) was null, returning empty string
2007-12-02 14:00:27,191 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_together) was null, returning empty string
2007-12-02 14:00:27,191 WARN  [main] [] misc - LC.get(debug_disable_mailbox_group) was null, returning empty string
2007-12-02 14:00:27,220 INFO  [main] [] extensions - Loading extensions from /opt/zimbra/lib/ext
2007-12-02 14:00:27,230 INFO  [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zimbra/lib/ext/zimbraldaputils/zimbraldaputils.jar
2007-12-02 14:00:27,231 INFO  [main] [] extensions - extension com.zimbra.clam.ClamScanner found in /opt/zimbra/lib/ext/clamscanner/clamscanner.jar
2007-12-02 14:00:27,231 INFO  [main] [] extensions - Initializing extensions
2007-12-02 14:00:27,344 INFO  [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@14b9a74
2007-12-02 14:00:27,351 INFO  [main] [] ClamScanner - attachment scan is disabled
2007-12-02 14:00:27,351 INFO  [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@4133b0
2007-12-02 14:00:27,378 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread starting
2007-12-02 14:00:27,427 INFO  [main] [] RedoLogManager - Starting pre-startup crash recovery
2007-12-02 14:00:27,860 INFO  [main] [] RedoPlayer - No uncommitted transactions to redo
2007-12-02 14:00:27,887 INFO  [main] [] RedoLogManager - Finished pre-startup crash recovery
2007-12-02 14:00:27,973 INFO  [NOTIFY-7035-Server] [] misc - starting server loop
2007-12-02 14:00:28,119 INFO  [Pop3Server] [] TcpServer/110 - starting accept loop
2007-12-02 14:00:28,120 INFO  [LmtpServer] [] TcpServer/7025 - starting accept loop
2007-12-02 14:00:28,265 INFO  [Pop3Server] [] TcpServer/995 - starting accept loop
2007-12-02 14:00:28,279 INFO  [ImapServer] [] TcpServer/143 - starting accept loop
2007-12-02 14:00:28,280 INFO  [ImapSSLServer] [] TcpServer/993 - starting accept loop
2007-12-02 14:00:28,499 INFO  [main] [] soap - Servlet AdminServlet starting up
2007-12-02 14:00:29,226 INFO  [main] [] soap - Adding service AdminService to AdminServlet
2007-12-02 14:00:29,251 INFO  [main] [] soap - Adding service AccountService to AdminServlet
2007-12-02 14:00:29,251 INFO  [main] [] soap - Adding service MailService to AdminServlet
2007-12-02 14:00:29,251 INFO  [main] [] soap - Adding service IMService to AdminServlet
2007-12-02 14:00:29,251 INFO  [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2007-12-02 14:00:29,262 INFO  [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-12-02 14:00:29,312 INFO  [main] [] mailbox - Servlet UserServlet starting up
2007-12-02 14:00:29,314 INFO  [main] [] account - Servlet PreAuthServlet starting up
2007-12-02 14:00:29,320 INFO  [main] [] ContentServlet - Servlet ContentServlet starting up
2007-12-02 14:00:29,328 INFO  [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-12-02 14:00:29,332 INFO  [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-12-02 14:00:33,594 INFO  [http-80-Processor100] [ip=65.103.68.176;ua=zclient/5.0;] cache - Initializing folder and tag caches for mailbox 15
2007-12-02 14:00:33,745 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-12-02 14:01:09,637 WARN  [http-80-Processor98] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -               PRIORITY 0 ==> 1000
2007-12-02 14:01:09,638 WARN  [http-80-Processor98] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -               PRIORITY 1 ==> 1000
2007-12-02 14:01:09,638 WARN  [http-80-Processor98] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -               PRIORITY 2 ==> 1000
2007-12-02 14:01:09,638 WARN  [http-80-Processor98] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -               PRIORITY 3 ==> 1000
2007-12-02 14:01:09,638 WARN  [http-80-Processor98] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -               PRIORITY 4 ==> 1000
2007-12-02 14:01:09,875 INFO  [http-80-Processor98] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] JMSession - SMTP Server: localhost
2007-12-02 14:01:13,815 INFO  [http-80-Processor97] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] GetCalendarItem - <GetCalendarItem id=2961> LC(mbox=783a8b91-981c-4e82-b622-eb3ada440c67)
2007-12-02 14:01:17,802 INFO  [http-80-Processor98] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] GetCalendarItem - <GetCalendarItem id=2956> LC(mbox=783a8b91-981c-4e82-b622-eb3ada440c67)
2007-12-02 14:01:18,717 INFO  [http-80-Processor97] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] GetCalendarItem - <GetCalendarItem id=3154> LC(mbox=783a8b91-981c-4e82-b622-eb3ada440c67)
2007-12-02 14:01:19,534 INFO  [http-80-Processor93] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] GetCalendarItem - <GetCalendarItem id=2959> LC(mbox=783a8b91-981c-4e82-b622-eb3ada440c67)
2007-12-02 14:01:41,446 INFO  [http-7071-Processor50] [ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] cache - Initializing folder and tag caches for mailbox 2
2007-12-02 14:02:17,402 INFO  [http-7071-Processor49] [name=admin@mailer.thecornbread.com;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] SoapEngine - handler exception
com.zimbra.common.service.ServiceException: system failure: exception during auth {RemoteManager: mailer.thecornbread.com->zimbra@mailer.thecornbread.com:22}
        at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:175)
        at com.zimbra.cs.rmgmt.RemoteManager.getSession(RemoteManager.java:197)
        at com.zimbra.cs.rmgmt.RemoteManager.execute(RemoteManager.java:134)
        at com.zimbra.cs.service.admin.GetMailQueueInfo.handle(GetMailQueueInfo.java:56)
        at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:276)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:173)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:95)
        at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:221)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:709)
        at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:162)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869)
        at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:667)
        at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
        at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80)
        at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684)
        at java.lang.Thread.run(Thread.java:619)
Caused by: java.io.IOException: auth failed
        at com.zimbra.cs.rmgmt.RemoteManager.getSession(RemoteManager.java:190)
        ... 24 more
2007-12-02 14:02:17,586 ERROR [{RemoteManager: mailer.thecornbread.com->zimbra@mailer.thecornbread.com:22}-zmqstat deferred] [] rmgmt - error when scanning mail queue deferred on host mailer.thecornbread.com
com.zimbra.common.service.ServiceException: system failure: exception during auth {RemoteManager: mailer.thecornbread.com->zimbra@mailer.thecornbread.com:22}
        at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:175)
        at com.zimbra.cs.rmgmt.RemoteManager.getSession(RemoteManager.java:197)
        at com.zimbra.cs.rmgmt.RemoteManager.executeBackground0(RemoteManager.java:98)
        at com.zimbra.cs.rmgmt.RemoteManager.access$000(RemoteManager.java:45)
        at com.zimbra.cs.rmgmt.RemoteManager$1.run(RemoteManager.java:121)
        at java.lang.Thread.run(Thread.java:619)
Caused by: java.io.IOException: auth failed
        at com.zimbra.cs.rmgmt.RemoteManager.getSession(RemoteManager.java:190)
        ... 4 more
2007-12-02 14:02:36,230 INFO  [http-80-Processor91] [name=becky@insitevt.com;aname=admin@mailer.thecornbread.com;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Initializing folder and tag caches for mailbox 10
2007-12-02 14:02:37,174 INFO  [http-80-Processor94] [name=becky@insitevt.com;aname=admin@mailer.thecornbread.com;mid=10;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Initializing folder and tag caches for mailbox 12
2007-12-02 14:02:39,609 INFO  [http-80-Processor94] [name=becky@insitevt.com;aname=admin@mailer.thecornbread.com;mid=10;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Loading flagset cache
2007-12-02 14:02:39,611 INFO  [http-80-Processor94] [name=becky@insitevt.com;aname=admin@mailer.thecornbread.com;mid=10;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Loading tagset cache
2007-12-02 14:03:03,753 INFO  [http-80-Processor91] [name=indrani@peacefulescape.com;aname=admin@mailer.thecornbread.com;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Initializing folder and tag caches for mailbox 11
2007-12-02 14:03:04,024 FATAL [http-80-Processor91] [name=indrani@peacefulescape.com;aname=admin@mailer.thecornbread.com;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] mailbox - Unable to commit database transaction.  Forcing server to abort.
com.zimbra.common.service.ServiceException: system failure: committing database transaction
        at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:175)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:163)
        at com.zimbra.cs.mailbox.Mailbox.endTransaction(Mailbox.java:4997)
        at com.zimbra.cs.mailbox.Mailbox.getConfig(Mailbox.java:941)
        at com.zimbra.cs.mailbox.Mailbox.<init>(Mailbox.java:386)
        at com.zimbra.cs.mailbox.MailboxManager.instantiateMailbox(MailboxManager.java:355)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:306)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:239)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:201)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:175)
        at com.zimbra.cs.session.Session.<init>(Session.java:72)
        at com.zimbra.cs.session.SoapSession.<init>(SoapSession.java:76)
        at com.zimbra.cs.session.SessionCache.getNewSession(SessionCache.java:86)
        at com.zimbra.soap.ZimbraSoapContext.getSession(ZimbraSoapContext.java:450)
        at com.zimbra.soap.DocumentHandler.getSession(DocumentHandler.java:199)
        at com.zimbra.soap.DocumentHandler.getSession(DocumentHandler.java:182)
        at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:269)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:173)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:95)
        at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:221)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:709)
        at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:162)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869)
        at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:667)
        at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
        at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80)
        at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684)
        at java.lang.Thread.run(Thread.java:619)
Caused by: com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException: Communications link failure during commit(). Transaction resolution unknown.
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:888)
        at com.mysql.jdbc.Connection.commit(Connection.java:2246)
        at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:248)
        at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.commit(PoolingDataSource.java:244)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:161)
        ... 36 more
2007-12-02 14:03:05,214 INFO  [Thread-165] [] StatsImageServlet - Servlet StatsImageServlet shutting down
2007-12-02 14:03:05,215 INFO  [Thread-165] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2007-12-02 14:03:05,215 INFO  [Thread-165] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2007-12-02 14:03:07,587 INFO  [Thread-165] [] soap - Servlet SoapServlet shutting down
2007-12-02 14:03:07,587 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer initiating shutdown
2007-12-02 14:03:07,587 INFO  [LmtpServer] [] TcpServer/7025 - finished accept loop
2007-12-02 14:03:07,589 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer shutting down idle thread pool
2007-12-02 14:03:07,590 INFO  [Thread-165] [] TcpServer/110 - Pop3Server initiating shutdown
2007-12-02 14:03:07,590 INFO  [Thread-165] [] TcpServer/110 - Pop3Server shutting down idle thread pool
2007-12-02 14:03:07,591 INFO  [Thread-165] [] TcpServer/995 - Pop3Server initiating shutdown
2007-12-02 14:03:07,591 INFO  [Thread-165] [] TcpServer/995 - Pop3Server shutting down idle thread pool
2007-12-02 14:03:07,591 INFO  [Thread-165] [] TcpServer/143 - ImapServer initiating shutdown
2007-12-02 14:03:07,590 INFO  [Pop3Server] [] TcpServer/110 - finished accept loop
2007-12-02 14:03:07,592 INFO  [Thread-165] [] TcpServer/143 - ImapServer shutting down idle thread pool
2007-12-02 14:03:07,592 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer initiating shutdown
2007-12-02 14:03:07,593 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer shutting down idle thread pool
2007-12-02 14:03:07,592 INFO  [Pop3Server] [] TcpServer/995 - finished accept loop
2007-12-02 14:03:07,596 INFO  [NOTIFY-7035-Server] [] misc - spurious wakeup [1] [no attachment] interest=ACCEPT ready= key=122c9df poolSize=0 queueSize=0
2007-12-02 14:03:07,593 INFO  [ImapServer] [] TcpServer/143 - finished accept loop
2007-12-02 14:03:07,593 INFO  [ImapSSLServer] [] TcpServer/993 - finished accept loop
2007-12-02 14:03:07,597 INFO  [NOTIFY-7035-Server] [] misc - shutting down thread pool
2007-12-02 14:03:07,597 INFO  [NOTIFY-7035-Server] [] misc - waiting for thread pool to shutdown
2007-12-02 14:03:07,597 INFO  [NOTIFY-7035-Server] [] misc - done waiting for thread pool to shutdown
2007-12-02 14:03:07,597 INFO  [NOTIFY-7035-Server] [] misc - closing all selection keys
2007-12-02 14:03:07,598 INFO  [NOTIFY-7035-Server] [] misc - closed selector
2007-12-02 14:03:07,598 INFO  [Thread-165] [] session - shutdown: clearing SessionCache
2007-12-02 14:03:07,599 INFO  [NOTIFY-7035-Server] [] misc - ended server loop
2007-12-02 14:03:07,599 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread exiting
2007-12-02 14:03:07,599 INFO  [Thread-165] [] MailboxIndex - Flushing all open index writers
2007-12-02 14:03:07,668 INFO  [Thread-165] [] RedoLogManager - Logged: 2 items, 26.5ms/item
2007-12-02 14:03:07,668 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread exiting
2007-12-02 14:03:07,668 INFO  [Thread-165] [] extensions - Destroying extensions
2007-12-02 14:03:07,695 INFO  [Thread-165] [] extensions - Destroyed extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@4133b0
2007-12-02 14:03:07,695 INFO  [Thread-165] [] extensions - Destroyed extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@14b9a74
2007-12-02 14:03:07,695 INFO  [Thread-165] [] soap - Servlet AdminServlet shutting down
2007-12-02 14:03:10,067 INFO  [Thread-165] [] mailbox - Servlet UserServlet shutting down
2007-12-02 14:03:10,068 INFO  [Thread-165] [] account - Servlet PreAuthServlet shutting down
2007-12-02 14:03:10,068 INFO  [Thread-165] [] ContentServlet - Servlet ContentServlet shutting down
2007-12-02 14:03:17,743 INFO  [main] [] soap - Servlet SoapServlet starting up
2007-12-02 14:03:17,778 INFO  [main] [] soap - Adding service AccountService to SoapServlet
2007-12-02 14:03:17,861 INFO  [main] [] soap - Adding service MailService to SoapServlet
2007-12-02 14:03:17,946 INFO  [main] [] soap - Adding service IMService to SoapServlet
2007-12-02 14:03:17,953 INFO  [main] [] misc - version=4.5.7_GA_1319.UBUNTU6 release=20070914180722 builddate=20070914-1808 buildhost=build-ubuntu
2007-12-02 14:03:17,954 INFO  [main] [] misc - LANG environment is set to: en_US.UTF-8
2007-12-02 14:03:17,976 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-12-02 14:03:18,361 WARN  [main] [] misc - LC.get(debug_calendar_force_utc) was null, returning empty string
2007-12-02 14:03:18,361 WARN  [main] [] misc - LC.get(debug_validate_outgoing_icalendar) was null, returning empty string
2007-12-02 14:03:18,361 WARN  [main] [] misc - LC.get(debug_disable_conversation) was null, returning empty string
2007-12-02 14:03:18,361 WARN  [main] [] misc - LC.get(debug_disable_filter) was null, returning empty string
2007-12-02 14:03:18,361 WARN  [main] [] misc - LC.get(debug_disable_message_analysis) was null, returning empty string
2007-12-02 14:03:18,362 WARN  [main] [] misc - LC.get(debug_disable_mime_part_extraction) was null, returning empty string
2007-12-02 14:03:18,362 WARN  [main] [] misc - LC.get(debug_disable_indexing) was null, returning empty string
2007-12-02 14:03:18,362 WARN  [main] [] misc - LC.get(debug_disable_objects) was null, returning empty string
2007-12-02 14:03:18,362 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_separately) was null, returning empty string
2007-12-02 14:03:18,362 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_together) was null, returning empty string
2007-12-02 14:03:18,362 WARN  [main] [] misc - LC.get(debug_disable_mailbox_group) was null, returning empty string
2007-12-02 14:03:18,390 INFO  [main] [] extensions - Loading extensions from /opt/zimbra/lib/ext
2007-12-02 14:03:18,392 INFO  [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zimbra/lib/ext/zimbraldaputils/zimbraldaputils.jar
2007-12-02 14:03:18,393 INFO  [main] [] extensions - extension com.zimbra.clam.ClamScanner found in /opt/zimbra/lib/ext/clamscanner/clamscanner.jar
2007-12-02 14:03:18,393 INFO  [main] [] extensions - Initializing extensions
2007-12-02 14:03:18,492 INFO  [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@147e668
2007-12-02 14:03:18,500 INFO  [main] [] ClamScanner - attachment scan is disabled
2007-12-02 14:03:18,500 INFO  [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@1c5ddd3
2007-12-02 14:03:18,536 INFO  [main] [] RedoLogManager - Starting pre-startup crash recovery
2007-12-02 14:03:18,543 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread starting
2007-12-02 14:03:18,583 INFO  [main] [] RedoPlayer - No uncommitted transactions to redo
2007-12-02 14:03:18,607 INFO  [main] [] RedoLogManager - Finished pre-startup crash recovery
2007-12-02 14:03:18,647 INFO  [NOTIFY-7035-Server] [] misc - starting server loop
2007-12-02 14:03:18,869 INFO  [Pop3Server] [] TcpServer/110 - starting accept loop
2007-12-02 14:03:18,869 INFO  [LmtpServer] [] TcpServer/7025 - starting accept loop
2007-12-02 14:03:18,876 INFO  [Pop3Server] [] TcpServer/995 - starting accept loop
2007-12-02 14:03:18,887 INFO  [ImapServer] [] TcpServer/143 - starting accept loop
2007-12-02 14:03:18,913 INFO  [main] [] soap - Servlet AdminServlet starting up
2007-12-02 14:03:18,996 INFO  [main] [] soap - Adding service AdminService to AdminServlet
2007-12-02 14:03:19,016 INFO  [ImapSSLServer] [] TcpServer/993 - starting accept loop
2007-12-02 14:03:19,022 INFO  [main] [] soap - Adding service AccountService to AdminServlet
2007-12-02 14:03:19,022 INFO  [main] [] soap - Adding service MailService to AdminServlet
2007-12-02 14:03:19,022 INFO  [main] [] soap - Adding service IMService to AdminServlet
2007-12-02 14:03:19,022 INFO  [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2007-12-02 14:03:19,032 INFO  [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-12-02 14:03:19,084 INFO  [main] [] mailbox - Servlet UserServlet starting up
2007-12-02 14:03:19,086 INFO  [main] [] account - Servlet PreAuthServlet starting up
2007-12-02 14:03:19,090 INFO  [main] [] ContentServlet - Servlet ContentServlet starting up
2007-12-02 14:03:19,098 INFO  [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-12-02 14:03:19,101 INFO  [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-12-02 14:03:19,531 INFO  [http-80-Processor100] [name=barbara@peacefulescape.com;ip=65.103.68.176;] cache - Initializing folder and tag caches for mailbox 15
2007-12-02 14:03:19,632 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;] op -            PRIORITY 0 ==> 1000
2007-12-02 14:03:19,632 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;] op -            PRIORITY 1 ==> 1000
2007-12-02 14:03:19,632 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;] op -            PRIORITY 2 ==> 1000
2007-12-02 14:03:19,632 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;] op -            PRIORITY 3 ==> 1000
2007-12-02 14:03:19,632 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;] op -            PRIORITY 4 ==> 1000
2007-12-02 14:03:19,645 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-12-02 14:03:28,242 INFO  [http-7071-Processor50] [] ZimbraSoapContext - requested session no longer exists: 1
2007-12-02 14:03:28,252 INFO  [http-7071-Processor50] [name=admin@mailer.thecornbread.com;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] cache - Initializing folder and tag caches for mailbox 2
2007-12-02 14:03:34,168 INFO  [http-80-Processor99] [name=jean@peacefulescape.com;aname=admin@mailer.thecornbread.com;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Initializing folder and tag caches for mailbox 18
2007-12-02 14:03:37,600 INFO  [http-80-Processor99] [name=jean@peacefulescape.com;aname=admin@mailer.thecornbread.com;mid=18;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Loading flagset cache
2007-12-02 14:03:37,603 INFO  [http-80-Processor99] [name=jean@peacefulescape.com;aname=admin@mailer.thecornbread.com;mid=18;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Loading tagset cache
2007-12-02 14:03:38,976 INFO  [http-80-Processor99] [name=jean@peacefulescape.com;aname=admin@mailer.thecornbread.com;mid=18;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] JMSession - SMTP Server: localhost
2007-12-02 14:04:10,236 INFO  [http-80-Processor100] [name=janet@peacefulescape.com;aname=admin@mailer.thecornbread.com;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Initializing folder and tag caches for mailbox 16
2007-12-02 14:04:13,263 INFO  [http-80-Processor99] [name=janet@peacefulescape.com;aname=admin@mailer.thecornbread.com;mid=16;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Loading flagset cache
2007-12-02 14:04:13,265 INFO  [http-80-Processor99] [name=janet@peacefulescape.com;aname=admin@mailer.thecornbread.com;mid=16;ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/4.5.7_GA_1319.UBUNTU6;] cache - Loading tagset cache
2007-12-02 14:13:58,387 INFO  [Timer-0] [] SessionCache - Removed 1 idle sessions (SoapSession: 1).  2 active sessions remain.
2007-12-02 14:14:58,407 INFO  [Timer-0] [] SessionCache - Removed 2 idle sessions (AdminSession: 1, SoapSession: 1).  0 active sessions remain.
2007-12-02 14:17:17,424 INFO  [LmtpServer-1] [] LmtpHandler - [192.168.0.17] connected
2007-12-02 14:17:17,445 INFO  [LmtpServer-3] [] LmtpHandler - [192.168.0.17] connected
2007-12-02 14:17:17,445 INFO  [LmtpServer-2] [] LmtpHandler - [192.168.0.17] connected
2007-12-02 14:17:17,496 INFO  [LmtpServer-1] [] cache - Initializing folder and tag caches for mailbox 14
2007-12-02 14:17:17,787 FATAL [LmtpServer-1] [] mailbox - Unable to commit database transaction.  Forcing server to abort.
com.zimbra.common.service.ServiceException: system failure: committing database transaction
        at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:175)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:163)
        at com.zimbra.cs.mailbox.Mailbox.endTransaction(Mailbox.java:4997)
        at com.zimbra.cs.mailbox.Mailbox.getConfig(Mailbox.java:941)
        at com.zimbra.cs.mailbox.Mailbox.<init>(Mailbox.java:386)
        at com.zimbra.cs.mailbox.MailboxManager.instantiateMailbox(MailboxManager.java:355)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:306)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:239)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:201)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:175)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccount(MailboxManager.java:160)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliverMessageToLocalMailboxes(ZimbraLmtpBackend.java:229)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliver(ZimbraLmtpBackend.java:122)
        at com.zimbra.cs.lmtpserver.LmtpHandler.doDATA(LmtpHandler.java:420)
        at com.zimbra.cs.lmtpserver.LmtpHandler.processCommand(LmtpHandler.java:197)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:619)
Caused by: com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException: Communications link failure during commit(). Transaction resolution unknown.
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:888)
        at com.mysql.jdbc.Connection.commit(Connection.java:2246)
        at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:248)
        at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.commit(PoolingDataSource.java:244)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:161)
        ... 17 more
2007-12-02 14:17:18,899 INFO  [Thread-165] [] StatsImageServlet - Servlet StatsImageServlet shutting down
2007-12-02 14:17:18,899 INFO  [Thread-165] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2007-12-02 14:17:18,899 INFO  [Thread-165] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2007-12-02 14:17:18,900 INFO  [Thread-165] [] soap - Servlet SoapServlet shutting down
2007-12-02 14:17:18,900 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer initiating shutdown
2007-12-02 14:17:18,900 INFO  [LmtpServer] [] TcpServer/7025 - finished accept loop
2007-12-02 14:17:18,907 INFO  [Thread-165] [] ProtocolHandler - graceful shutdown requested
2007-12-02 14:17:18,908 INFO  [Thread-165] [] ProtocolHandler - graceful shutdown requested
2007-12-02 14:17:18,908 INFO  [Thread-165] [] ProtocolHandler - graceful shutdown requested
2007-12-02 14:17:18,909 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer waiting 10 seconds for thread pool shutdown
2007-12-02 14:17:28,924 INFO  [Thread-165] [] ProtocolHandler - hard shutdown requested
2007-12-02 14:17:28,927 INFO  [Thread-165] [] ProtocolHandler - hard shutdown requested
2007-12-02 14:17:28,930 INFO  [Thread-165] [] ProtocolHandler - hard shutdown requested
2007-12-02 14:17:28,945 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer shutdown complete
2007-12-02 14:17:28,945 INFO  [Thread-165] [] TcpServer/110 - Pop3Server initiating shutdown
2007-12-02 14:17:28,945 INFO  [Pop3Server] [] TcpServer/110 - finished accept loop
2007-12-02 14:17:28,946 INFO  [Thread-165] [] TcpServer/110 - Pop3Server shutting down idle thread pool
2007-12-02 14:17:28,947 INFO  [Thread-165] [] TcpServer/995 - Pop3Server initiating shutdown
2007-12-02 14:17:28,947 INFO  [Pop3Server] [] TcpServer/995 - finished accept loop
2007-12-02 14:17:28,948 INFO  [Thread-165] [] TcpServer/995 - Pop3Server shutting down idle thread pool
2007-12-02 14:17:28,948 INFO  [Thread-165] [] TcpServer/143 - ImapServer initiating shutdown
2007-12-02 14:17:28,948 INFO  [ImapServer] [] TcpServer/143 - finished accept loop
2007-12-02 14:17:28,949 INFO  [Thread-165] [] TcpServer/143 - ImapServer shutting down idle thread pool
2007-12-02 14:17:28,949 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer initiating shutdown
2007-12-02 14:17:28,949 INFO  [ImapSSLServer] [] TcpServer/993 - finished accept loop
2007-12-02 14:17:28,950 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer shutting down idle thread pool
2007-12-02 14:17:33,973 INFO  [main] [] soap - Servlet SoapServlet starting up
2007-12-02 14:17:34,002 INFO  [main] [] soap - Adding service AccountService to SoapServlet
2007-12-02 14:17:34,087 INFO  [main] [] soap - Adding service MailService to SoapServlet
2007-12-02 14:17:34,172 INFO  [main] [] soap - Adding service IMService to SoapServlet
2007-12-02 14:17:34,179 INFO  [main] [] misc - version=4.5.7_GA_1319.UBUNTU6 release=20070914180722 builddate=20070914-1808 buildhost=build-ubuntu
2007-12-02 14:17:34,180 INFO  [main] [] misc - LANG environment is set to: en_US.UTF-8
2007-12-02 14:17:34,203 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_calendar_force_utc) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_validate_outgoing_icalendar) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_disable_conversation) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_disable_filter) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_disable_message_analysis) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_disable_mime_part_extraction) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_disable_indexing) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_disable_objects) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_separately) was null, returning empty string
2007-12-02 14:17:34,587 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_together) was null, returning empty string
2007-12-02 14:17:34,588 WARN  [main] [] misc - LC.get(debug_disable_mailbox_group) was null, returning empty string
2007-12-02 14:17:34,613 INFO  [main] [] extensions - Loading extensions from /opt/zimbra/lib/ext
2007-12-02 14:17:34,616 INFO  [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zimbra/lib/ext/zimbraldaputils/zimbraldaputils.jar
2007-12-02 14:17:34,616 INFO  [main] [] extensions - extension com.zimbra.clam.ClamScanner found in /opt/zimbra/lib/ext/clamscanner/clamscanner.jar
2007-12-02 14:17:34,616 INFO  [main] [] extensions - Initializing extensions
2007-12-02 14:17:34,721 INFO  [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@167c5e3
2007-12-02 14:17:34,729 INFO  [main] [] ClamScanner - attachment scan is disabled
2007-12-02 14:17:34,729 INFO  [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@ac06d4
2007-12-02 14:17:34,765 INFO  [main] [] RedoLogManager - Starting pre-startup crash recovery
2007-12-02 14:17:34,772 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread starting
2007-12-02 14:17:34,801 INFO  [main] [] RedoPlayer - No uncommitted transactions to redo
2007-12-02 14:17:34,827 INFO  [main] [] RedoLogManager - Finished pre-startup crash recovery
2007-12-02 14:17:34,873 INFO  [NOTIFY-7035-Server] [] misc - starting server loop
2007-12-02 14:17:35,078 INFO  [Pop3Server] [] TcpServer/995 - starting accept loop
2007-12-02 14:17:35,086 INFO  [LmtpServer] [] TcpServer/7025 - starting accept loop
2007-12-02 14:17:35,087 INFO  [Pop3Server] [] TcpServer/110 - starting accept loop
2007-12-02 14:17:35,096 INFO  [ImapServer] [] TcpServer/143 - starting accept loop
2007-12-02 14:17:35,103 INFO  [ImapSSLServer] [] TcpServer/993 - starting accept loop
2007-12-02 14:17:35,121 INFO  [main] [] soap - Servlet AdminServlet starting up
2007-12-02 14:17:35,198 INFO  [main] [] soap - Adding service AdminService to AdminServlet
2007-12-02 14:17:35,222 INFO  [main] [] soap - Adding service AccountService to AdminServlet
2007-12-02 14:17:35,222 INFO  [main] [] soap - Adding service MailService to AdminServlet
2007-12-02 14:17:35,222 INFO  [main] [] soap - Adding service IMService to AdminServlet
2007-12-02 14:17:35,223 INFO  [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2007-12-02 14:17:35,232 INFO  [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-12-02 14:17:35,285 INFO  [main] [] mailbox - Servlet UserServlet starting up
2007-12-02 14:17:35,288 INFO  [main] [] account - Servlet PreAuthServlet starting up
2007-12-02 14:17:35,293 INFO  [main] [] ContentServlet - Servlet ContentServlet starting up
2007-12-02 14:17:35,300 INFO  [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-12-02 14:17:35,304 INFO  [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-12-02 14:17:59,591 INFO  [http-80-Processor100] [name=barbara@peacefulescape.com;ip=65.103.68.176;ua=Zimbra Desktop/dev;] cache - Initializing folder and tag caches for mailbox 15
2007-12-02 14:17:59,663 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-12-02 14:17:59,669 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 0 ==> 1000
2007-12-02 14:17:59,669 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 1 ==> 1000
2007-12-02 14:17:59,669 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 2 ==> 1000
2007-12-02 14:17:59,669 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 3 ==> 1000
2007-12-02 14:17:59,669 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 4 ==> 1000
2007-12-02 14:38:15,730 INFO  [http-80-Processor100] [ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] cache - Initializing folder and tag caches for mailbox 14
2007-12-02 14:38:16,180 FATAL [http-80-Processor100] [ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] mailbox - Unable to commit database transaction.  Forcing server to abort.
com.zimbra.common.service.ServiceException: system failure: committing database transaction
        at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:175)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:163)
        at com.zimbra.cs.mailbox.Mailbox.endTransaction(Mailbox.java:4997)
        at com.zimbra.cs.mailbox.Mailbox.getConfig(Mailbox.java:941)
        at com.zimbra.cs.mailbox.Mailbox.<init>(Mailbox.java:386)
        at com.zimbra.cs.mailbox.MailboxManager.instantiateMailbox(MailboxManager.java:355)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:306)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:239)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:201)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:175)
        at com.zimbra.soap.DocumentHandler.getRequestedMailbox(DocumentHandler.java:95)
        at com.zimbra.soap.ZimbraSoapContext.generateResponseHeader(ZimbraSoapContext.java:499)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:206)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:95)
        at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:221)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:709)
        at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:162)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869)
        at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:667)
        at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
        at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80)
        at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684)
        at java.lang.Thread.run(Thread.java:619)
Caused by: com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException: Communications link failure during commit(). Transaction resolution unknown.
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:888)
        at com.mysql.jdbc.Connection.commit(Connection.java:2246)
        at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:248)
        at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.commit(PoolingDataSource.java:244)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:161)
        ... 31 more
2007-12-02 14:38:17,253 INFO  [Thread-165] [] StatsImageServlet - Servlet StatsImageServlet shutting down
2007-12-02 14:38:17,254 INFO  [Thread-165] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2007-12-02 14:38:17,256 INFO  [Thread-165] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2007-12-02 14:38:19,575 INFO  [Thread-165] [] soap - Servlet SoapServlet shutting down
2007-12-02 14:38:19,575 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer initiating shutdown
2007-12-02 14:38:19,576 INFO  [LmtpServer] [] TcpServer/7025 - finished accept loop
2007-12-02 14:38:19,582 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer shutting down idle thread pool
2007-12-02 14:38:19,583 INFO  [Thread-165] [] TcpServer/110 - Pop3Server initiating shutdown
2007-12-02 14:38:19,584 INFO  [Pop3Server] [] TcpServer/110 - finished accept loop
2007-12-02 14:38:19,584 INFO  [Thread-165] [] TcpServer/110 - Pop3Server shutting down idle thread pool
2007-12-02 14:38:19,584 INFO  [Thread-165] [] TcpServer/995 - Pop3Server initiating shutdown
2007-12-02 14:38:19,585 INFO  [Pop3Server] [] TcpServer/995 - finished accept loop
2007-12-02 14:38:19,586 INFO  [Thread-165] [] TcpServer/995 - Pop3Server shutting down idle thread pool
2007-12-02 14:38:19,586 INFO  [Thread-165] [] TcpServer/143 - ImapServer initiating shutdown
2007-12-02 14:38:19,587 INFO  [ImapServer] [] TcpServer/143 - finished accept loop
2007-12-02 14:38:19,588 INFO  [Thread-165] [] TcpServer/143 - ImapServer shutting down idle thread pool
2007-12-02 14:38:19,588 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer initiating shutdown
2007-12-02 14:38:19,589 INFO  [ImapSSLServer] [] TcpServer/993 - finished accept loop
2007-12-02 14:38:19,590 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer shutting down idle thread pool
2007-12-02 14:38:19,596 INFO  [NOTIFY-7035-Server] [] misc - spurious wakeup [1] [no attachment] interest=ACCEPT ready= key=1296d1d poolSize=0 queueSize=0
2007-12-02 14:38:19,596 INFO  [NOTIFY-7035-Server] [] misc - shutting down thread pool
2007-12-02 14:38:19,596 INFO  [NOTIFY-7035-Server] [] misc - waiting for thread pool to shutdown
2007-12-02 14:38:19,596 INFO  [NOTIFY-7035-Server] [] misc - done waiting for thread pool to shutdown
2007-12-02 14:38:19,597 INFO  [NOTIFY-7035-Server] [] misc - closing all selection keys
2007-12-02 14:38:19,598 INFO  [NOTIFY-7035-Server] [] misc - closed selector
2007-12-02 14:38:19,608 INFO  [Thread-165] [] session - shutdown: clearing SessionCache
2007-12-02 14:38:19,608 INFO  [NOTIFY-7035-Server] [] misc - ended server loop
2007-12-02 14:38:19,608 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread exiting
2007-12-02 14:38:19,609 INFO  [Thread-165] [] MailboxIndex - Flushing all open index writers
2007-12-02 14:38:19,696 INFO  [Thread-165] [] RedoLogManager - Logged: 0 items, 0.0ms/item
2007-12-02 14:38:19,696 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread exiting
2007-12-02 14:38:19,696 INFO  [Thread-165] [] extensions - Destroying extensions
2007-12-02 14:38:19,702 INFO  [Thread-165] [] extensions - Destroyed extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@ac06d4
2007-12-02 14:38:19,703 INFO  [Thread-165] [] extensions - Destroyed extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@167c5e3
2007-12-02 14:38:19,704 INFO  [Thread-165] [] soap - Servlet AdminServlet shutting down
2007-12-02 14:38:19,705 INFO  [Thread-165] [] mailbox - Servlet UserServlet shutting down
2007-12-02 14:38:19,705 INFO  [Thread-165] [] account - Servlet PreAuthServlet shutting down
2007-12-02 14:38:19,706 INFO  [Thread-165] [] ContentServlet - Servlet ContentServlet shutting down
2007-12-02 14:38:24,972 INFO  [main] [] soap - Servlet SoapServlet starting up
2007-12-02 14:38:25,007 INFO  [main] [] soap - Adding service AccountService to SoapServlet
2007-12-02 14:38:25,088 INFO  [main] [] soap - Adding service MailService to SoapServlet
2007-12-02 14:38:25,173 INFO  [main] [] soap - Adding service IMService to SoapServlet
2007-12-02 14:38:25,180 INFO  [main] [] misc - version=4.5.7_GA_1319.UBUNTU6 release=20070914180722 builddate=20070914-1808 buildhost=build-ubuntu
2007-12-02 14:38:25,181 INFO  [main] [] misc - LANG environment is set to: en_US.UTF-8
2007-12-02 14:38:25,202 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-12-02 14:38:25,586 WARN  [main] [] misc - LC.get(debug_calendar_force_utc) was null, returning empty string
2007-12-02 14:38:25,586 WARN  [main] [] misc - LC.get(debug_validate_outgoing_icalendar) was null, returning empty string
2007-12-02 14:38:25,586 WARN  [main] [] misc - LC.get(debug_disable_conversation) was null, returning empty string
2007-12-02 14:38:25,587 WARN  [main] [] misc - LC.get(debug_disable_filter) was null, returning empty string
2007-12-02 14:38:25,587 WARN  [main] [] misc - LC.get(debug_disable_message_analysis) was null, returning empty string
2007-12-02 14:38:25,587 WARN  [main] [] misc - LC.get(debug_disable_mime_part_extraction) was null, returning empty string
2007-12-02 14:38:25,587 WARN  [main] [] misc - LC.get(debug_disable_indexing) was null, returning empty string
2007-12-02 14:38:25,587 WARN  [main] [] misc - LC.get(debug_disable_objects) was null, returning empty string
2007-12-02 14:38:25,587 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_separately) was null, returning empty string
2007-12-02 14:38:25,587 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_together) was null, returning empty string
2007-12-02 14:38:25,587 WARN  [main] [] misc - LC.get(debug_disable_mailbox_group) was null, returning empty string
2007-12-02 14:38:25,614 INFO  [main] [] extensions - Loading extensions from /opt/zimbra/lib/ext
2007-12-02 14:38:25,616 INFO  [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zimbra/lib/ext/zimbraldaputils/zimbraldaputils.jar
2007-12-02 14:38:25,617 INFO  [main] [] extensions - extension com.zimbra.clam.ClamScanner found in /opt/zimbra/lib/ext/clamscanner/clamscanner.jar
2007-12-02 14:38:25,617 INFO  [main] [] extensions - Initializing extensions
2007-12-02 14:38:25,718 INFO  [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@147e668
2007-12-02 14:38:25,725 INFO  [main] [] ClamScanner - attachment scan is disabled
2007-12-02 14:38:25,725 INFO  [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@1c5ddd3
2007-12-02 14:38:25,755 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread starting
2007-12-02 14:38:25,762 INFO  [main] [] RedoLogManager - Starting pre-startup crash recovery
2007-12-02 14:38:25,793 INFO  [main] [] RedoPlayer - No uncommitted transactions to redo
2007-12-02 14:38:25,816 INFO  [main] [] RedoLogManager - Finished pre-startup crash recovery
2007-12-02 14:38:25,863 INFO  [NOTIFY-7035-Server] [] misc - starting server loop
2007-12-02 14:38:26,073 INFO  [LmtpServer] [] TcpServer/7025 - starting accept loop
2007-12-02 14:38:26,074 INFO  [Pop3Server] [] TcpServer/995 - starting accept loop
2007-12-02 14:38:26,081 INFO  [Pop3Server] [] TcpServer/110 - starting accept loop
2007-12-02 14:38:26,091 INFO  [ImapServer] [] TcpServer/143 - starting accept loop
2007-12-02 14:38:26,095 INFO  [ImapSSLServer] [] TcpServer/993 - starting accept loop
2007-12-02 14:38:26,116 INFO  [main] [] soap - Servlet AdminServlet starting up
2007-12-02 14:38:26,199 INFO  [main] [] soap - Adding service AdminService to AdminServlet
2007-12-02 14:38:26,222 INFO  [main] [] soap - Adding service AccountService to AdminServlet
2007-12-02 14:38:26,223 INFO  [main] [] soap - Adding service MailService to AdminServlet
2007-12-02 14:38:26,223 INFO  [main] [] soap - Adding service IMService to AdminServlet
2007-12-02 14:38:26,223 INFO  [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2007-12-02 14:38:26,233 INFO  [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-12-02 14:38:26,285 INFO  [main] [] mailbox - Servlet UserServlet starting up
2007-12-02 14:38:26,288 INFO  [main] [] account - Servlet PreAuthServlet starting up
2007-12-02 14:38:26,292 INFO  [main] [] ContentServlet - Servlet ContentServlet starting up
2007-12-02 14:38:26,299 INFO  [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-12-02 14:38:26,303 INFO  [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-12-02 14:38:32,769 INFO  [http-80-Processor100] [ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] cache - Initializing folder and tag caches for mailbox 14
2007-12-02 14:38:33,148 FATAL [http-80-Processor100] [ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] mailbox - Unable to commit database transaction.  Forcing server to abort.
com.zimbra.common.service.ServiceException: system failure: committing database transaction
        at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:175)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:163)
        at com.zimbra.cs.mailbox.Mailbox.endTransaction(Mailbox.java:4997)
        at com.zimbra.cs.mailbox.Mailbox.getConfig(Mailbox.java:941)
        at com.zimbra.cs.mailbox.Mailbox.<init>(Mailbox.java:386)
        at com.zimbra.cs.mailbox.MailboxManager.instantiateMailbox(MailboxManager.java:355)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:306)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:239)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:201)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:175)
        at com.zimbra.soap.DocumentHandler.getRequestedMailbox(DocumentHandler.java:95)
        at com.zimbra.soap.ZimbraSoapContext.generateResponseHeader(ZimbraSoapContext.java:499)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:206)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:95)
        at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:221)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:709)
        at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:162)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869)
        at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:667)
        at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
        at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80)
        at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684)
        at java.lang.Thread.run(Thread.java:619)
Caused by: com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException: Communications link failure during commit(). Transaction resolution unknown.
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:888)
        at com.mysql.jdbc.Connection.commit(Connection.java:2246)
        at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:248)
        at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.commit(PoolingDataSource.java:244)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:161)
        ... 31 more
2007-12-02 14:38:34,213 INFO  [Thread-165] [] StatsImageServlet - Servlet StatsImageServlet shutting down
2007-12-02 14:38:34,214 INFO  [Thread-165] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2007-12-02 14:38:34,223 INFO  [Thread-165] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2007-12-02 14:38:36,534 INFO  [Thread-165] [] soap - Servlet SoapServlet shutting down
2007-12-02 14:38:36,535 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer initiating shutdown
2007-12-02 14:38:36,536 INFO  [LmtpServer] [] TcpServer/7025 - finished accept loop
2007-12-02 14:38:36,541 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer shutting down idle thread pool
2007-12-02 14:38:36,541 INFO  [Thread-165] [] TcpServer/110 - Pop3Server initiating shutdown
2007-12-02 14:38:36,541 INFO  [Pop3Server] [] TcpServer/110 - finished accept loop
2007-12-02 14:38:36,542 INFO  [Thread-165] [] TcpServer/110 - Pop3Server shutting down idle thread pool
2007-12-02 14:38:36,542 INFO  [Thread-165] [] TcpServer/995 - Pop3Server initiating shutdown
2007-12-02 14:38:36,542 INFO  [Pop3Server] [] TcpServer/995 - finished accept loop
2007-12-02 14:38:36,543 INFO  [Thread-165] [] TcpServer/995 - Pop3Server shutting down idle thread pool
2007-12-02 14:38:36,543 INFO  [Thread-165] [] TcpServer/143 - ImapServer initiating shutdown
2007-12-02 14:38:36,543 INFO  [ImapServer] [] TcpServer/143 - finished accept loop
2007-12-02 14:38:36,544 INFO  [Thread-165] [] TcpServer/143 - ImapServer shutting down idle thread pool
2007-12-02 14:38:36,544 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer initiating shutdown
2007-12-02 14:38:36,545 INFO  [ImapSSLServer] [] TcpServer/993 - finished accept loop
2007-12-02 14:38:36,545 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer shutting down idle thread pool
2007-12-02 14:38:36,550 INFO  [NOTIFY-7035-Server] [] misc - spurious wakeup [1] [no attachment] interest=ACCEPT ready= key=6691da poolSize=0 queueSize=0
2007-12-02 14:38:36,550 INFO  [NOTIFY-7035-Server] [] misc - shutting down thread pool
2007-12-02 14:38:36,550 INFO  [NOTIFY-7035-Server] [] misc - waiting for thread pool to shutdown
2007-12-02 14:38:36,551 INFO  [NOTIFY-7035-Server] [] misc - done waiting for thread pool to shutdown
2007-12-02 14:38:36,551 INFO  [NOTIFY-7035-Server] [] misc - closing all selection keys
2007-12-02 14:38:36,552 INFO  [NOTIFY-7035-Server] [] misc - closed selector
2007-12-02 14:38:36,552 INFO  [NOTIFY-7035-Server] [] misc - ended server loop
2007-12-02 14:38:36,561 INFO  [Thread-165] [] session - shutdown: clearing SessionCache
2007-12-02 14:38:36,561 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread exiting
2007-12-02 14:38:36,562 INFO  [Thread-165] [] MailboxIndex - Flushing all open index writers
2007-12-02 14:38:36,596 INFO  [Thread-165] [] RedoLogManager - Logged: 0 items, 0.0ms/item
2007-12-02 14:38:36,600 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-12-02 14:38:36,600 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread exiting
2007-12-02 14:38:36,601 INFO  [Thread-165] [] extensions - Destroying extensions
2007-12-02 14:38:36,608 INFO  [Thread-165] [] extensions - Destroyed extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@1c5ddd3
2007-12-02 14:38:36,608 INFO  [Thread-165] [] extensions - Destroyed extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@147e668
2007-12-02 14:38:36,609 INFO  [Thread-165] [] soap - Servlet AdminServlet shutting down
2007-12-02 14:38:36,610 INFO  [Thread-165] [] mailbox - Servlet UserServlet shutting down
2007-12-02 14:38:36,611 INFO  [Thread-165] [] account - Servlet PreAuthServlet shutting down
2007-12-02 14:38:36,611 INFO  [Thread-165] [] ContentServlet - Servlet ContentServlet shutting down
2007-12-02 15:50:39,410 INFO  [main] [] soap - Servlet SoapServlet starting up
2007-12-02 15:50:39,640 INFO  [main] [] soap - Adding service AccountService to SoapServlet
2007-12-02 15:50:39,736 INFO  [main] [] soap - Adding service MailService to SoapServlet
2007-12-02 15:50:39,861 INFO  [main] [] soap - Adding service IMService to SoapServlet
2007-12-02 15:50:39,870 INFO  [main] [] misc - version=4.5.7_GA_1319.UBUNTU6 release=20070914180722 builddate=20070914-1808 buildhost=build-ubuntu
2007-12-02 15:50:39,872 INFO  [main] [] misc - LANG environment is set to: C
2007-12-02 15:50:40,491 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-12-02 15:50:41,090 WARN  [main] [] misc - LC.get(debug_calendar_force_utc) was null, returning empty string
2007-12-02 15:50:41,090 WARN  [main] [] misc - LC.get(debug_validate_outgoing_icalendar) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_conversation) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_filter) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_message_analysis) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_mime_part_extraction) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_indexing) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_objects) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_separately) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_together) was null, returning empty string
2007-12-02 15:50:41,091 WARN  [main] [] misc - LC.get(debug_disable_mailbox_group) was null, returning empty string
2007-12-02 15:50:41,128 INFO  [main] [] extensions - Loading extensions from /opt/zimbra/lib/ext
2007-12-02 15:50:41,140 INFO  [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zimbra/lib/ext/zimbraldaputils/zimbraldaputils.jar
2007-12-02 15:50:41,141 INFO  [main] [] extensions - extension com.zimbra.clam.ClamScanner found in /opt/zimbra/lib/ext/clamscanner/clamscanner.jar
2007-12-02 15:50:41,141 INFO  [main] [] extensions - Initializing extensions
2007-12-02 15:50:41,249 INFO  [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@1015a9e
2007-12-02 15:50:41,256 INFO  [main] [] ClamScanner - attachment scan is disabled
2007-12-02 15:50:41,257 INFO  [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@142c778
2007-12-02 15:50:41,287 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread starting
2007-12-02 15:50:41,322 INFO  [main] [] RedoLogManager - Starting pre-startup crash recovery
2007-12-02 15:50:41,386 INFO  [main] [] RedoPlayer - No uncommitted transactions to redo
2007-12-02 15:50:41,411 INFO  [main] [] RedoLogManager - Finished pre-startup crash recovery
2007-12-02 15:50:41,519 INFO  [NOTIFY-7035-Server] [] misc - starting server loop
2007-12-02 15:50:41,602 INFO  [Pop3Server] [] TcpServer/110 - starting accept loop
2007-12-02 15:50:41,603 INFO  [LmtpServer] [] TcpServer/7025 - starting accept loop
2007-12-02 15:50:41,930 INFO  [Pop3Server] [] TcpServer/995 - starting accept loop
2007-12-02 15:50:42,114 INFO  [ImapServer] [] TcpServer/143 - starting accept loop
2007-12-02 15:50:42,118 INFO  [ImapSSLServer] [] TcpServer/993 - starting accept loop
2007-12-02 15:50:42,170 INFO  [main] [] soap - Servlet AdminServlet starting up
2007-12-02 15:50:42,256 INFO  [main] [] soap - Adding service AdminService to AdminServlet
2007-12-02 15:50:42,282 INFO  [main] [] soap - Adding service AccountService to AdminServlet
2007-12-02 15:50:42,282 INFO  [main] [] soap - Adding service MailService to AdminServlet
2007-12-02 15:50:42,282 INFO  [main] [] soap - Adding service IMService to AdminServlet
2007-12-02 15:50:42,282 INFO  [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2007-12-02 15:50:42,293 INFO  [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-12-02 15:50:42,350 INFO  [main] [] mailbox - Servlet UserServlet starting up
2007-12-02 15:50:42,353 INFO  [main] [] account - Servlet PreAuthServlet starting up
2007-12-02 15:50:42,358 INFO  [main] [] ContentServlet - Servlet ContentServlet starting up
2007-12-02 15:50:42,366 INFO  [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-12-02 15:50:42,369 INFO  [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-12-02 15:50:43,313 INFO  [http-80-Processor100] [ip=65.103.68.176;ua=zclient/5.0;] cache - Initializing folder and tag caches for mailbox 15
2007-12-02 15:50:43,482 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-12-02 15:50:48,998 INFO  [LmtpServer-1] [] LmtpHandler - [192.168.0.17] connected
2007-12-02 15:50:49,210 INFO  [LmtpServer-4] [] LmtpHandler - [192.168.0.17] connected
2007-12-02 15:50:49,210 INFO  [LmtpServer-3] [] LmtpHandler - [192.168.0.17] connected
2007-12-02 15:50:49,210 INFO  [LmtpServer-2] [] LmtpHandler - [192.168.0.17] connected
2007-12-02 15:50:49,210 INFO  [LmtpServer-5] [] LmtpHandler - [192.168.0.17] connected
2007-12-02 15:50:49,415 INFO  [LmtpServer-3] [] JMSession - SMTP Server: localhost
2007-12-02 15:50:49,479 INFO  [LmtpServer-1] [] cache - Initializing folder and tag caches for mailbox 14
2007-12-02 15:50:49,670 INFO  [LmtpServer-3] [] zimlet - Loaded class com.zimbra.cs.zimlet.handler.NANPHandler
2007-12-02 15:50:49,673 INFO  [LmtpServer-3] [] zimlet - Loaded class com.zimbra.cs.zimlet.handler.RegexHandler
2007-12-02 15:50:49,992 FATAL [LmtpServer-1] [] mailbox - Unable to commit database transaction.  Forcing server to abort.
com.zimbra.common.service.ServiceException: system failure: committing database transaction
        at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:175)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:163)
        at com.zimbra.cs.mailbox.Mailbox.endTransaction(Mailbox.java:4997)
        at com.zimbra.cs.mailbox.Mailbox.getConfig(Mailbox.java:941)
        at com.zimbra.cs.mailbox.Mailbox.<init>(Mailbox.java:386)
        at com.zimbra.cs.mailbox.MailboxManager.instantiateMailbox(MailboxManager.java:355)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:306)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:239)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:201)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:175)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccount(MailboxManager.java:160)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliverMessageToLocalMailboxes(ZimbraLmtpBackend.java:229)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliver(ZimbraLmtpBackend.java:122)
        at com.zimbra.cs.lmtpserver.LmtpHandler.doDATA(LmtpHandler.java:420)
        at com.zimbra.cs.lmtpserver.LmtpHandler.processCommand(LmtpHandler.java:197)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:619)
Caused by: com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException: Communications link failure during commit(). Transaction resolution unknown.
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:888)
        at com.mysql.jdbc.Connection.commit(Connection.java:2246)
        at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:248)
        at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.commit(PoolingDataSource.java:244)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:161)
        ... 17 more
2007-12-02 15:50:51,047 INFO  [Thread-165] [] StatsImageServlet - Servlet StatsImageServlet shutting down
2007-12-02 15:50:51,048 INFO  [Thread-165] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2007-12-02 15:50:51,050 INFO  [Thread-165] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2007-12-02 15:50:51,051 INFO  [Thread-165] [] soap - Servlet SoapServlet shutting down
2007-12-02 15:50:51,052 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer initiating shutdown
2007-12-02 15:50:51,052 INFO  [LmtpServer] [] TcpServer/7025 - finished accept loop
2007-12-02 15:50:51,074 INFO  [Thread-165] [] ProtocolHandler - graceful shutdown requested
2007-12-02 15:50:51,074 INFO  [Thread-165] [] ProtocolHandler - graceful shutdown requested
2007-12-02 15:50:51,075 INFO  [Thread-165] [] ProtocolHandler - graceful shutdown requested
2007-12-02 15:50:51,075 INFO  [Thread-165] [] ProtocolHandler - graceful shutdown requested
2007-12-02 15:50:51,075 INFO  [Thread-165] [] ProtocolHandler - graceful shutdown requested
2007-12-02 15:50:51,075 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer waiting 10 seconds for thread pool shutdown
2007-12-02 15:50:52,769 INFO  [LmtpServer-3] [name=barbara@peacefulescape.com;] mailbox - Added message id=3240 digest=6LrHUEv9Wr,C1VCB0J,bdsnsoEs= mailbox=15 rcpt=barbara@peacefulescape.com
2007-12-02 15:50:52,793 INFO  [LmtpServer-3] [] ProtocolHandler - Handler exiting normally
2007-12-02 15:50:52,870 INFO  [LmtpServer-5] [name=barbara@peacefulescape.com;] mailbox - Added message id=3241 digest=WluPQ,TwAgEpTeP1,,n9KqYTMjQ= mailbox=15 rcpt=barbara@peacefulescape.com
2007-12-02 15:50:52,871 INFO  [LmtpServer-5] [] ProtocolHandler - Handler exiting normally
2007-12-02 15:51:01,087 INFO  [Thread-165] [] ProtocolHandler - hard shutdown requested
2007-12-02 15:51:01,089 INFO  [Thread-165] [] ProtocolHandler - hard shutdown requested
2007-12-02 15:51:01,091 INFO  [Thread-165] [] ProtocolHandler - hard shutdown requested
2007-12-02 15:51:01,092 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer shutdown complete
2007-12-02 15:51:01,092 INFO  [Thread-165] [] TcpServer/110 - Pop3Server initiating shutdown
2007-12-02 15:51:01,093 INFO  [Pop3Server] [] TcpServer/110 - finished accept loop
2007-12-02 15:51:06,594 INFO  [main] [] soap - Servlet SoapServlet starting up
2007-12-02 15:51:06,626 INFO  [main] [] soap - Adding service AccountService to SoapServlet
2007-12-02 15:51:06,709 INFO  [main] [] soap - Adding service MailService to SoapServlet
2007-12-02 15:51:06,795 INFO  [main] [] soap - Adding service IMService to SoapServlet
2007-12-02 15:51:06,802 INFO  [main] [] misc - version=4.5.7_GA_1319.UBUNTU6 release=20070914180722 builddate=20070914-1808 buildhost=build-ubuntu
2007-12-02 15:51:06,804 INFO  [main] [] misc - LANG environment is set to: C
2007-12-02 15:51:06,825 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-12-02 15:51:07,215 WARN  [main] [] misc - LC.get(debug_calendar_force_utc) was null, returning empty string
2007-12-02 15:51:07,215 WARN  [main] [] misc - LC.get(debug_validate_outgoing_icalendar) was null, returning empty string
2007-12-02 15:51:07,215 WARN  [main] [] misc - LC.get(debug_disable_conversation) was null, returning empty string
2007-12-02 15:51:07,215 WARN  [main] [] misc - LC.get(debug_disable_filter) was null, returning empty string
2007-12-02 15:51:07,215 WARN  [main] [] misc - LC.get(debug_disable_message_analysis) was null, returning empty string
2007-12-02 15:51:07,215 WARN  [main] [] misc - LC.get(debug_disable_mime_part_extraction) was null, returning empty string
2007-12-02 15:51:07,215 WARN  [main] [] misc - LC.get(debug_disable_indexing) was null, returning empty string
2007-12-02 15:51:07,215 WARN  [main] [] misc - LC.get(debug_disable_objects) was null, returning empty string
2007-12-02 15:51:07,216 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_separately) was null, returning empty string
2007-12-02 15:51:07,216 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_together) was null, returning empty string
2007-12-02 15:51:07,216 WARN  [main] [] misc - LC.get(debug_disable_mailbox_group) was null, returning empty string
2007-12-02 15:51:07,223 INFO  [main] [] extensions - Loading extensions from /opt/zimbra/lib/ext
2007-12-02 15:51:07,225 INFO  [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zimbra/lib/ext/zimbraldaputils/zimbraldaputils.jar
2007-12-02 15:51:07,226 INFO  [main] [] extensions - extension com.zimbra.clam.ClamScanner found in /opt/zimbra/lib/ext/clamscanner/clamscanner.jar
2007-12-02 15:51:07,226 INFO  [main] [] extensions - Initializing extensions
2007-12-02 15:51:07,332 INFO  [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@bafdff
2007-12-02 15:51:07,340 INFO  [main] [] ClamScanner - attachment scan is disabled
2007-12-02 15:51:07,340 INFO  [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@1f47ae8
2007-12-02 15:51:07,353 INFO  [main] [] MailboxIndex - Found index lock file lucene-2ab686b00add4ab0bd8a0678bf103846-write.lock from previous crash.  Deleting...
2007-12-02 15:51:07,376 INFO  [main] [] RedoLogManager - Starting pre-startup crash recovery
2007-12-02 15:51:07,377 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread starting
2007-12-02 15:51:07,409 INFO  [main] [] RedoPlayer - Redoing 2 uncommitted transactions
2007-12-02 15:51:07,409 INFO  [main] [] RedoPlayer - Deferring crash recovery to after startup: txn 1196639441.2 [IndexItem] ver=1.12, tstamp=1196639449864, mailbox=15, id=3240, type=5
2007-12-02 15:51:07,409 INFO  [main] [] RedoPlayer - Deferring crash recovery to after startup: txn 1196639441.4 [IndexItem] ver=1.12, tstamp=1196639452769, mailbox=15, id=3241, type=5
2007-12-02 15:51:07,409 INFO  [main] [] MailboxIndex - Flushing all open index writers
2007-12-02 15:51:07,441 INFO  [main] [] RedoLogManager - Finished pre-startup crash recovery
2007-12-02 15:51:07,550 INFO  [PostStartupCrashRecovery] [] RedoLogManager - Starting post-startup crash recovery
2007-12-02 15:51:07,567 INFO  [PostStartupCrashRecovery] [] RedoLogManager - REDOING: txn 1196639441.2 [IndexItem] ver=1.12, tstamp=1196639449864, mailbox=15, id=3240, type=5
2007-12-02 15:51:07,577 INFO  [NOTIFY-7035-Server] [] misc - starting server loop
2007-12-02 15:51:07,775 INFO  [LmtpServer] [] TcpServer/7025 - starting accept loop
2007-12-02 15:51:07,787 INFO  [Pop3Server] [] TcpServer/110 - starting accept loop
2007-12-02 15:51:07,797 INFO  [Pop3Server] [] TcpServer/995 - starting accept loop
2007-12-02 15:51:07,805 INFO  [ImapServer] [] TcpServer/143 - starting accept loop
2007-12-02 15:51:07,829 INFO  [ImapSSLServer] [] TcpServer/993 - starting accept loop
2007-12-02 15:51:07,896 INFO  [main] [] soap - Servlet AdminServlet starting up
2007-12-02 15:51:07,981 INFO  [main] [] soap - Adding service AdminService to AdminServlet
2007-12-02 15:51:08,004 INFO  [main] [] soap - Adding service AccountService to AdminServlet
2007-12-02 15:51:08,005 INFO  [main] [] soap - Adding service MailService to AdminServlet
2007-12-02 15:51:08,005 INFO  [main] [] soap - Adding service IMService to AdminServlet
2007-12-02 15:51:08,005 INFO  [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2007-12-02 15:51:08,015 INFO  [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-12-02 15:51:08,060 INFO  [main] [] mailbox - Servlet UserServlet starting up
2007-12-02 15:51:08,062 INFO  [main] [] account - Servlet PreAuthServlet starting up
2007-12-02 15:51:08,066 INFO  [main] [] ContentServlet - Servlet ContentServlet starting up
2007-12-02 15:51:08,075 INFO  [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-12-02 15:51:08,079 INFO  [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-12-02 15:51:08,515 INFO  [PostStartupCrashRecovery] [] cache - Initializing folder and tag caches for mailbox 15
2007-12-02 15:51:08,576 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-12-02 15:51:08,618 INFO  [PostStartupCrashRecovery] [] JMSession - SMTP Server: localhost
2007-12-02 15:51:08,795 INFO  [PostStartupCrashRecovery] [] zimlet - Loaded class com.zimbra.cs.zimlet.handler.NANPHandler
2007-12-02 15:51:08,799 INFO  [PostStartupCrashRecovery] [] zimlet - Loaded class com.zimbra.cs.zimlet.handler.RegexHandler
2007-12-02 15:51:08,929 INFO  [PostStartupCrashRecovery] [] RedoLogManager - REDOING: txn 1196639441.4 [IndexItem] ver=1.12, tstamp=1196639452769, mailbox=15, id=3241, type=5
2007-12-02 15:51:09,009 INFO  [PostStartupCrashRecovery] [] MailboxIndex - Flushing all open index writers
2007-12-02 15:51:09,063 INFO  [PostStartupCrashRecovery] [] RedoLogManager - Finished post-startup crash recovery
2007-12-02 15:52:04,321 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 0 ==> 1000
2007-12-02 15:52:04,321 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 1 ==> 1000
2007-12-02 15:52:04,321 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 2 ==> 1000
2007-12-02 15:52:04,321 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 3 ==> 1000
2007-12-02 15:52:04,321 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 4 ==> 1000
2007-12-02 15:52:24,913 INFO  [http-80-Processor100] [ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] cache - Initializing folder and tag caches for mailbox 14
2007-12-02 15:52:25,246 FATAL [http-80-Processor100] [ip=192.168.0.104;ua=ZimbraWebClient - FF2.0 (Linux)/undefined;] mailbox - Unable to commit database transaction.  Forcing server to abort.
com.zimbra.common.service.ServiceException: system failure: committing database transaction
        at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:175)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:163)
        at com.zimbra.cs.mailbox.Mailbox.endTransaction(Mailbox.java:4997)
        at com.zimbra.cs.mailbox.Mailbox.getConfig(Mailbox.java:941)
        at com.zimbra.cs.mailbox.Mailbox.<init>(Mailbox.java:386)
        at com.zimbra.cs.mailbox.MailboxManager.instantiateMailbox(MailboxManager.java:355)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:306)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxById(MailboxManager.java:239)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:201)
        at com.zimbra.cs.mailbox.MailboxManager.getMailboxByAccountId(MailboxManager.java:175)
        at com.zimbra.soap.DocumentHandler.getRequestedMailbox(DocumentHandler.java:95)
        at com.zimbra.soap.ZimbraSoapContext.generateResponseHeader(ZimbraSoapContext.java:499)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:206)
        at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:95)
        at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:221)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:709)
        at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:162)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869)
        at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:667)
        at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
        at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80)
        at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684)
        at java.lang.Thread.run(Thread.java:619)
Caused by: com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException: Communications link failure during commit(). Transaction resolution unknown.
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:888)
        at com.mysql.jdbc.Connection.commit(Connection.java:2246)
        at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:248)
        at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.commit(PoolingDataSource.java:244)
        at com.zimbra.cs.db.DbPool$Connection.commit(DbPool.java:161)
        ... 31 more
2007-12-02 15:52:26,312 INFO  [Thread-165] [] StatsImageServlet - Servlet StatsImageServlet shutting down
2007-12-02 15:52:26,313 INFO  [Thread-165] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2007-12-02 15:52:26,315 INFO  [Thread-165] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2007-12-02 15:52:28,627 INFO  [Thread-165] [] soap - Servlet SoapServlet shutting down
2007-12-02 15:52:28,627 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer initiating shutdown
2007-12-02 15:52:28,629 INFO  [LmtpServer] [] TcpServer/7025 - finished accept loop
2007-12-02 15:52:28,697 INFO  [Thread-165] [] TcpServer/7025 - LmtpServer shutting down idle thread pool
2007-12-02 15:52:28,698 INFO  [Thread-165] [] TcpServer/110 - Pop3Server initiating shutdown
2007-12-02 15:52:28,698 INFO  [Pop3Server] [] TcpServer/110 - finished accept loop
2007-12-02 15:52:28,737 INFO  [Thread-165] [] TcpServer/110 - Pop3Server shutting down idle thread pool
2007-12-02 15:52:28,737 INFO  [Thread-165] [] TcpServer/995 - Pop3Server initiating shutdown
2007-12-02 15:52:28,740 INFO  [Pop3Server] [] TcpServer/995 - finished accept loop
2007-12-02 15:52:28,908 INFO  [Thread-165] [] TcpServer/995 - Pop3Server shutting down idle thread pool
2007-12-02 15:52:28,908 INFO  [Thread-165] [] TcpServer/143 - ImapServer initiating shutdown
2007-12-02 15:52:28,908 INFO  [ImapServer] [] TcpServer/143 - finished accept loop
2007-12-02 15:52:28,962 INFO  [Thread-165] [] TcpServer/143 - ImapServer shutting down idle thread pool
2007-12-02 15:52:28,962 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer initiating shutdown
2007-12-02 15:52:28,962 INFO  [ImapSSLServer] [] TcpServer/993 - finished accept loop
2007-12-02 15:52:28,963 INFO  [Thread-165] [] TcpServer/993 - ImapSSLServer shutting down idle thread pool
2007-12-02 15:52:28,966 INFO  [NOTIFY-7035-Server] [] misc - spurious wakeup [1] [no attachment] interest=ACCEPT ready= key=e580e1 poolSize=0 queueSize=0
2007-12-02 15:52:28,966 INFO  [NOTIFY-7035-Server] [] misc - shutting down thread pool
2007-12-02 15:52:28,966 INFO  [NOTIFY-7035-Server] [] misc - waiting for thread pool to shutdown
2007-12-02 15:52:28,967 INFO  [NOTIFY-7035-Server] [] misc - done waiting for thread pool to shutdown
2007-12-02 15:52:28,967 INFO  [NOTIFY-7035-Server] [] misc - closing all selection keys
2007-12-02 15:52:28,967 INFO  [NOTIFY-7035-Server] [] misc - closed selector
2007-12-02 15:52:28,972 INFO  [Thread-165] [] session - shutdown: clearing SessionCache
2007-12-02 15:52:28,973 INFO  [NOTIFY-7035-Server] [] misc - ended server loop
2007-12-02 15:52:28,973 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread exiting
2007-12-02 15:52:28,973 INFO  [Thread-165] [] MailboxIndex - Flushing all open index writers
2007-12-02 15:52:29,068 INFO  [Thread-165] [] RedoLogManager - Logged: 7 items, 21.429ms/item
2007-12-02 15:52:29,068 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread exiting
2007-12-02 15:52:29,068 INFO  [Thread-165] [] extensions - Destroying extensions
2007-12-02 15:52:29,089 INFO  [Thread-165] [] extensions - Destroyed extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@1f47ae8
2007-12-02 15:52:29,090 INFO  [Thread-165] [] extensions - Destroyed extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@bafdff
2007-12-02 15:52:29,091 INFO  [Thread-165] [] soap - Servlet AdminServlet shutting down
2007-12-02 15:52:29,092 INFO  [Thread-165] [] mailbox - Servlet UserServlet shutting down
2007-12-02 15:52:29,092 INFO  [Thread-165] [] account - Servlet PreAuthServlet shutting down
2007-12-02 15:52:29,093 INFO  [Thread-165] [] ContentServlet - Servlet ContentServlet shutting down
2007-12-02 15:52:34,620 INFO  [main] [] soap - Servlet SoapServlet starting up
2007-12-02 15:52:34,651 INFO  [main] [] soap - Adding service AccountService to SoapServlet
2007-12-02 15:52:34,730 INFO  [main] [] soap - Adding service MailService to SoapServlet
2007-12-02 15:52:34,812 INFO  [main] [] soap - Adding service IMService to SoapServlet
2007-12-02 15:52:34,819 INFO  [main] [] misc - version=4.5.7_GA_1319.UBUNTU6 release=20070914180722 builddate=20070914-1808 buildhost=build-ubuntu
2007-12-02 15:52:34,820 INFO  [main] [] misc - LANG environment is set to: C
2007-12-02 15:52:34,842 INFO  [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-12-02 15:52:35,215 WARN  [main] [] misc - LC.get(debug_calendar_force_utc) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_validate_outgoing_icalendar) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_conversation) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_filter) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_message_analysis) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_mime_part_extraction) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_indexing) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_objects) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_separately) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_indexing_attachments_together) was null, returning empty string
2007-12-02 15:52:35,216 WARN  [main] [] misc - LC.get(debug_disable_mailbox_group) was null, returning empty string
2007-12-02 15:52:35,241 INFO  [main] [] extensions - Loading extensions from /opt/zimbra/lib/ext
2007-12-02 15:52:35,243 INFO  [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zimbra/lib/ext/zimbraldaputils/zimbraldaputils.jar
2007-12-02 15:52:35,244 INFO  [main] [] extensions - extension com.zimbra.clam.ClamScanner found in /opt/zimbra/lib/ext/clamscanner/clamscanner.jar
2007-12-02 15:52:35,244 INFO  [main] [] extensions - Initializing extensions
2007-12-02 15:52:35,342 INFO  [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@167c5e3
2007-12-02 15:52:35,349 INFO  [main] [] ClamScanner - attachment scan is disabled
2007-12-02 15:52:35,349 INFO  [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScanner@com.zimbra.cs.extension.ZimbraExtensionClassLoader@1664a9b
2007-12-02 15:52:35,386 INFO  [main] [] RedoLogManager - Starting pre-startup crash recovery
2007-12-02 15:52:35,391 INFO  [IndexWritersSweeperThread] [] MailboxIndex - IndexWritersSweeperThread thread starting
2007-12-02 15:52:35,423 INFO  [main] [] RedoPlayer - No uncommitted transactions to redo
2007-12-02 15:52:35,449 INFO  [main] [] RedoLogManager - Finished pre-startup crash recovery
2007-12-02 15:52:35,486 INFO  [NOTIFY-7035-Server] [] misc - starting server loop
2007-12-02 15:52:35,505 INFO  [LmtpServer] [] TcpServer/7025 - starting accept loop
2007-12-02 15:52:35,513 INFO  [Pop3Server] [] TcpServer/110 - starting accept loop
2007-12-02 15:52:35,697 INFO  [ImapServer] [] TcpServer/143 - starting accept loop
2007-12-02 15:52:35,722 INFO  [ImapSSLServer] [] TcpServer/993 - starting accept loop
2007-12-02 15:52:35,723 INFO  [main] [] soap - Servlet AdminServlet starting up
2007-12-02 15:52:35,812 INFO  [main] [] soap - Adding service AdminService to AdminServlet
2007-12-02 15:52:35,835 INFO  [main] [] soap - Adding service AccountService to AdminServlet
2007-12-02 15:52:35,835 INFO  [main] [] soap - Adding service MailService to AdminServlet
2007-12-02 15:52:35,836 INFO  [main] [] soap - Adding service IMService to AdminServlet
2007-12-02 15:52:35,836 INFO  [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2007-12-02 15:52:35,845 INFO  [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-12-02 15:52:35,900 INFO  [main] [] mailbox - Servlet UserServlet starting up
2007-12-02 15:52:35,902 INFO  [main] [] account - Servlet PreAuthServlet starting up
2007-12-02 15:52:35,907 INFO  [main] [] ContentServlet - Servlet ContentServlet starting up
2007-12-02 15:52:35,914 INFO  [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-12-02 15:52:35,918 INFO  [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-12-02 15:52:35,967 INFO  [Pop3Server] [] TcpServer/995 - starting accept loop
2007-12-02 15:53:09,478 INFO  [http-80-Processor100] [name=barbara@peacefulescape.com;ip=65.103.68.176;ua=Zimbra Desktop/dev;] cache - Initializing folder and tag caches for mailbox 15
2007-12-02 15:53:09,545 INFO  [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-12-02 15:53:09,553 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 0 ==> 1000
2007-12-02 15:53:09,554 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 1 ==> 1000
2007-12-02 15:53:09,554 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 2 ==> 1000
2007-12-02 15:53:09,554 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 3 ==> 1000
2007-12-02 15:53:09,554 WARN  [http-80-Processor100] [name=barbara@peacefulescape.com;mid=15;ip=65.103.68.176;ua=Zimbra Desktop/dev;] op -              PRIORITY 4 ==> 1000