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 - 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 - 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 - 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 - 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.(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.(Session.java:72) at com.zimbra.cs.session.SoapSession.(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.(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.(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.(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.(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.(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