New here, but been a long time Zimbra user.
Our deployment is extremely small (less than 10 mailboxes) and we use Thunderbird/Lightning, K-9 Mail and DavDroid. Only four mailboxes are used regularly with only one large in size mailbox that was never an issue until the upgrade.
Experiencing the same issue since upgrading from 8.7.1 to 8.8.6, using the suggested cron job helps, although some times Zimbra gets locked withing the 3 hour range.
However a this point we're considering a downgrade, the restarts take the server offline for almost 10min every time, what's the safest way to do this without impacting the mailboxes?
Code: Select all
zimbra@myserver:/root$ tail -f /opt/zimbra/log/mailbox.log
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:17:54,871 WARN [Timer-Zimbra] [] imap - skipping error while trying to remove session ImapSession{id=2326, authAcct=c8acf2e7-5331-4f7e-9a79-7af63ba358a5, lastAccessed=2018-02-07 12:03:55,271, cleanedUp=true, addedToCache=false, path=Archives/2017, folderId=c8acf2e7-5331-4f7e-9a79-7af63ba358a5:53326, handler=null}
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.detach(ImapListener.java:546)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.quietRemoveSession(ImapSessionManager.java:244)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:199)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:18:29,809 INFO [ImapSSLServer-281] [] imap - dropping connection for user userex@domain2.com (LOGOUT)
2018-02-07 13:18:34,417 INFO [ImapSSLServer-281] [] imap - dropping connection for user userex@domain2.com (LOGOUT)
2018-02-07 13:18:40,854 INFO [MailboxPurge] [name=galsync@domain2.com;mid=3;] purge - Purging messages.
2018-02-07 13:18:51,965 INFO [qtp1595953398-866:http://localhost:8080/service/soap/AuthRequest] [name=userex@domain1.com;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - AuthRequest elapsed=8
^N2018-02-07 13:19:24,873 ERROR [Timer-Zimbra] [] mailbox - Failed to lock mailbox
Write Lock Owner - ImapSSLServer-8 prio=5 id=276 state=TERMINATED
Lock Waiter - qtp1595953398-866:http://localhost:8080/service/soap/GetInfoRequest prio=5 id=866 state=TIMED_WAITING
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.locks.ReentrantReadWriteLock$ReadLock.tryLock(ReentrantReadWriteLock.java:871)
at com.zimbra.cs.mailbox.MailboxLock.tryLockWithTimeout(MailboxLock.java:116)
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:194)
at com.zimbra.cs.mailbox.Mailbox.getLastSoapAccessTime(Mailbox.java:1448)
at com.zimbra.cs.service.account.GetInfo.handle(GetInfo.java:189)
at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:607)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:460)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:273)
at com.zimbra.soap.SoapServlet.doWork(SoapServlet.java:303)
at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:213)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:206)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1685)
at com.zimbra.cs.servlet.CsrfFilter.doFilter(CsrfFilter.java:169)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.RequestStringFilter.doFilter(RequestStringFilter.java:54)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:59)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ETagHeaderFilter.doFilter(ETagHeaderFilter.java:47)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ContextPathBasedThreadPoolBalancerFilter.doFilter(ContextPathBasedThreadPoolBalancerFilter.java:107)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraQoSFilter.doFilter(ZimbraQoSFilter.java:116)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraInvalidLoginFilter.doFilter(ZimbraInvalidLoginFilter.java:117)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlets.DoSFilter.doFilterChain(DoSFilter.java:473)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:318)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:288)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:524)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:109)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:318)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437)
at org.eclipse.jetty.server.handler.DebugHandler.handle(DebugHandler.java:84)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:748)
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.unload(ImapListener.java:580)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:195)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:19:24,874 WARN [Timer-Zimbra] [] imap - error serializing session; clearing ImapSession{id=2324, authAcct=c8acf2e7-5331-4f7e-9a79-7af63ba358a5, lastAccessed=2018-02-07 12:03:55,269, cleanedUp=true, addedToCache=false, path=Travel, folderId=c8acf2e7-5331-4f7e-9a79-7af63ba358a5:2223, handler=null}
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.unload(ImapListener.java:580)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:195)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:19:40,861 INFO [MailboxPurge] [name=userex@domain1.com;mid=4;] purge - Purging messages.
2018-02-07 13:20:06,213 INFO [qtp1595953398-1030:https:https://localhost:7071/service/admin/soap/AuthRequest] [name=zimbra;ua=zmprov/8.8.6_GA_1906;] soap - AuthRequest elapsed=6
2018-02-07 13:20:11,517 INFO [qtp1595953398-993:https:https://localhost:7071/service/admin/soap/GetAllServersRequest] [name=zimbra;ua=zmprov/8.8.6_GA_1906;] soap - GetAllServersRequest elapsed=26
2018-02-07 13:20:21,982 ERROR [qtp1595953398-866:http://localhost:8080/service/soap/GetInfoRequest] [name=userex@domain1.com;mid=4;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] mailbox - Failed to lock mailbox
Write Lock Owner - ImapSSLServer-8 prio=5 id=276 state=TERMINATED
Lock Waiter - MailboxPurge prio=5 id=64 state=TIMED_WAITING
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireNanos(AbstractQueuedSynchronizer.java:934)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireNanos(AbstractQueuedSynchronizer.java:1247)
at java.util.concurrent.locks.ReentrantReadWriteLock$WriteLock.tryLock(ReentrantReadWriteLock.java:1115)
at com.zimbra.cs.mailbox.MailboxLock.tryLockWithTimeout(MailboxLock.java:114)
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:194)
at com.zimbra.cs.mailbox.Mailbox.beginTransaction(Mailbox.java:1759)
at com.zimbra.cs.mailbox.Mailbox.beginTransaction(Mailbox.java:1740)
at com.zimbra.cs.mailbox.Mailbox.purgeMessages(Mailbox.java:8884)
at com.zimbra.cs.mailbox.PurgeThread.run(PurgeThread.java:150)
Lock Waiter - Timer-Zimbra daemon prio=5 id=30 state=TIMED_WAITING
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireNanos(AbstractQueuedSynchronizer.java:934)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireNanos(AbstractQueuedSynchronizer.java:1247)
at java.util.concurrent.locks.ReentrantReadWriteLock$WriteLock.tryLock(ReentrantReadWriteLock.java:1115)
at com.zimbra.cs.mailbox.MailboxLock.tryLockWithTimeout(MailboxLock.java:114)
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:194)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.detach(ImapListener.java:546)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.quietRemoveSession(ImapSessionManager.java:244)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:199)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.getLastSoapAccessTime(Mailbox.java:1448)
at com.zimbra.cs.service.account.GetInfo.handle(GetInfo.java:189)
at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:607)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:460)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:273)
at com.zimbra.soap.SoapServlet.doWork(SoapServlet.java:303)
at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:213)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:206)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1685)
at com.zimbra.cs.servlet.CsrfFilter.doFilter(CsrfFilter.java:169)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.RequestStringFilter.doFilter(RequestStringFilter.java:54)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:59)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ETagHeaderFilter.doFilter(ETagHeaderFilter.java:47)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ContextPathBasedThreadPoolBalancerFilter.doFilter(ContextPathBasedThreadPoolBalancerFilter.java:107)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraQoSFilter.doFilter(ZimbraQoSFilter.java:116)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraInvalidLoginFilter.doFilter(ZimbraInvalidLoginFilter.java:117)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlets.DoSFilter.doFilterChain(DoSFilter.java:473)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:318)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:288)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:524)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:109)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:318)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437)
at org.eclipse.jetty.server.handler.DebugHandler.handle(DebugHandler.java:84)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:748)
2018-02-07 13:20:21,983 WARN [qtp1595953398-866:http://localhost:8080/service/soap/GetInfoRequest] [name=userex@domain1.com;mid=4;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - handler exception
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.getLastSoapAccessTime(Mailbox.java:1448)
at com.zimbra.cs.service.account.GetInfo.handle(GetInfo.java:189)
at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:607)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:460)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:273)
at com.zimbra.soap.SoapServlet.doWork(SoapServlet.java:303)
at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:213)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:206)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1685)
at com.zimbra.cs.servlet.CsrfFilter.doFilter(CsrfFilter.java:169)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.RequestStringFilter.doFilter(RequestStringFilter.java:54)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:59)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ETagHeaderFilter.doFilter(ETagHeaderFilter.java:47)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ContextPathBasedThreadPoolBalancerFilter.doFilter(ContextPathBasedThreadPoolBalancerFilter.java:107)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraQoSFilter.doFilter(ZimbraQoSFilter.java:116)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraInvalidLoginFilter.doFilter(ZimbraInvalidLoginFilter.java:117)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlets.DoSFilter.doFilterChain(DoSFilter.java:473)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:318)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:288)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:524)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:109)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:318)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437)
at org.eclipse.jetty.server.handler.DebugHandler.handle(DebugHandler.java:84)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:748)
2018-02-07 13:20:21,984 INFO [qtp1595953398-866:http://localhost:8080/service/soap/GetInfoRequest] [name=userex@domain1.com;mid=4;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=90003
2018-02-07 13:20:22,014 INFO [qtp1595953398-1021:http://localhost:8080/service/soap/GetAvailableLocalesRequest] [name=userex@domain1.com;mid=4;ua=ZCS/8.8.6_GA_1906;] soap - GetAvailableLocalesRequest elapsed=8
2018-02-07 13:20:22,036 INFO [qtp1595953398-1030:https:https://localhost:7071/service/admin/soap/GetDomainInfoRequest] [ua=ZCS/8.8.6_GA_1906;] soap - GetDomainInfoRequest elapsed=0
2018-02-07 13:20:22,060 INFO [qtp1595953398-993:http://localhost:8080/service/soap/AuthRequest] [name=userex@domain1.com;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - AuthRequest elapsed=1
2018-02-07 13:20:54,875 ERROR [Timer-Zimbra] [] mailbox - Failed to lock mailbox
Write Lock Owner - ImapSSLServer-8 prio=5 id=276 state=TERMINATED
Lock Waiter - qtp1595953398-1021:http://localhost:8080/service/soap/GetFolderRequest prio=5 id=1021 state=TIMED_WAITING
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.locks.ReentrantReadWriteLock$ReadLock.tryLock(ReentrantReadWriteLock.java:871)
at com.zimbra.cs.mailbox.MailboxLock.tryLockWithTimeout(MailboxLock.java:116)
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:194)
at com.zimbra.cs.mailbox.Mailbox.getFolderTree(Mailbox.java:4295)
at com.zimbra.cs.service.mail.GetFolder.handle(GetFolder.java:113)
at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:607)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:460)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:273)
at com.zimbra.soap.SoapServlet.doWork(SoapServlet.java:303)
at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:213)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:206)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1685)
at com.zimbra.cs.servlet.CsrfFilter.doFilter(CsrfFilter.java:169)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.RequestStringFilter.doFilter(RequestStringFilter.java:54)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:59)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ETagHeaderFilter.doFilter(ETagHeaderFilter.java:47)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ContextPathBasedThreadPoolBalancerFilter.doFilter(ContextPathBasedThreadPoolBalancerFilter.java:107)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraQoSFilter.doFilter(ZimbraQoSFilter.java:116)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraInvalidLoginFilter.doFilter(ZimbraInvalidLoginFilter.java:117)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlets.DoSFilter.doFilterChain(DoSFilter.java:473)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:318)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:288)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:524)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:109)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:318)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437)
at org.eclipse.jetty.server.handler.DebugHandler.handle(DebugHandler.java:84)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:748)
Lock Waiter - MailboxPurge prio=5 id=64 state=TIMED_WAITING
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireNanos(AbstractQueuedSynchronizer.java:934)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireNanos(AbstractQueuedSynchronizer.java:1247)
at java.util.concurrent.locks.ReentrantReadWriteLock$WriteLock.tryLock(ReentrantReadWriteLock.java:1115)
at com.zimbra.cs.mailbox.MailboxLock.tryLockWithTimeout(MailboxLock.java:114)
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:194)
at com.zimbra.cs.mailbox.Mailbox.beginTransaction(Mailbox.java:1759)
at com.zimbra.cs.mailbox.Mailbox.beginTransaction(Mailbox.java:1740)
at com.zimbra.cs.mailbox.Mailbox.purgeMessages(Mailbox.java:8884)
at com.zimbra.cs.mailbox.PurgeThread.run(PurgeThread.java:150)
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.detach(ImapListener.java:546)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.quietRemoveSession(ImapSessionManager.java:244)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:199)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:20:54,876 WARN [Timer-Zimbra] [] imap - skipping error while trying to remove session ImapSession{id=2324, authAcct=c8acf2e7-5331-4f7e-9a79-7af63ba358a5, lastAccessed=2018-02-07 12:03:55,269, cleanedUp=true, addedToCache=false, path=Travel, folderId=c8acf2e7-5331-4f7e-9a79-7af63ba358a5:2223, handler=null}
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.detach(ImapListener.java:546)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.quietRemoveSession(ImapSessionManager.java:244)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:199)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:21:10,882 ERROR [MailboxPurge] [name=userex@domain1.com;mid=4;] mailbox - Failed to lock mailbox
Write Lock Owner - ImapSSLServer-8 prio=5 id=276 state=TERMINATED
Lock Waiter - Timer-Zimbra daemon prio=5 id=30 state=TIMED_WAITING
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireNanos(AbstractQueuedSynchronizer.java:934)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireNanos(AbstractQueuedSynchronizer.java:1247)
at java.util.concurrent.locks.ReentrantReadWriteLock$WriteLock.tryLock(ReentrantReadWriteLock.java:1115)
at com.zimbra.cs.mailbox.MailboxLock.tryLockWithTimeout(MailboxLock.java:114)
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:194)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.unload(ImapListener.java:580)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:195)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
Lock Waiter - qtp1595953398-1021:http://localhost:8080/service/soap/GetFolderRequest prio=5 id=1021 state=TIMED_WAITING
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.locks.ReentrantReadWriteLock$ReadLock.tryLock(ReentrantReadWriteLock.java:871)
at com.zimbra.cs.mailbox.MailboxLock.tryLockWithTimeout(MailboxLock.java:116)
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:194)
at com.zimbra.cs.mailbox.Mailbox.getFolderTree(Mailbox.java:4295)
at com.zimbra.cs.service.mail.GetFolder.handle(GetFolder.java:113)
at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:607)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:460)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:273)
at com.zimbra.soap.SoapServlet.doWork(SoapServlet.java:303)
at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:213)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:206)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1685)
at com.zimbra.cs.servlet.CsrfFilter.doFilter(CsrfFilter.java:169)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.RequestStringFilter.doFilter(RequestStringFilter.java:54)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:59)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ETagHeaderFilter.doFilter(ETagHeaderFilter.java:47)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ContextPathBasedThreadPoolBalancerFilter.doFilter(ContextPathBasedThreadPoolBalancerFilter.java:107)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraQoSFilter.doFilter(ZimbraQoSFilter.java:116)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraInvalidLoginFilter.doFilter(ZimbraInvalidLoginFilter.java:117)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlets.DoSFilter.doFilterChain(DoSFilter.java:473)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:318)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:288)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:524)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:109)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:318)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437)
at org.eclipse.jetty.server.handler.DebugHandler.handle(DebugHandler.java:84)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:748)
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.beginTransaction(Mailbox.java:1759)
at com.zimbra.cs.mailbox.Mailbox.beginTransaction(Mailbox.java:1740)
at com.zimbra.cs.mailbox.Mailbox.purgeMessages(Mailbox.java:8884)
at com.zimbra.cs.mailbox.PurgeThread.run(PurgeThread.java:150)
2018-02-07 13:21:10,883 WARN [MailboxPurge] [name=userex@domain1.com;mid=4;] mailbox - transaction canceled because of lock failure
2018-02-07 13:21:10,883 WARN [MailboxPurge] [name=userex@domain1.com;mid=4;] purge - Unable to purge mailbox 4
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.beginTransaction(Mailbox.java:1759)
at com.zimbra.cs.mailbox.Mailbox.beginTransaction(Mailbox.java:1740)
at com.zimbra.cs.mailbox.Mailbox.purgeMessages(Mailbox.java:8884)
at com.zimbra.cs.mailbox.PurgeThread.run(PurgeThread.java:150)
2018-02-07 13:21:28,092 INFO [qtp1595953398-866:https:https://localhost:7071/service/admin/soap/GetDomainInfoRequest] [ua=ZCS/8.8.6_GA_1906;] soap - GetDomainInfoRequest elapsed=1
2018-02-07 13:21:28,134 INFO [qtp1595953398-866:https:https://localhost:7071/service/admin/soap/GetDomainInfoRequest] [ua=ZCS/8.8.6_GA_1906;] soap - GetDomainInfoRequest elapsed=1
2018-02-07 13:21:28,329 INFO [qtp1595953398-1037:https:https://localhost:7071/service/admin/soap/GetDomainInfoRequest] [ua=ZCS/8.8.6_GA_1906;] soap - GetDomainInfoRequest elapsed=1
2018-02-07 13:21:52,076 ERROR [qtp1595953398-1021:http://localhost:8080/service/soap/GetFolderRequest] [name=userex@domain1.com;mid=4;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] mailbox - Failed to lock mailbox
Write Lock Owner - ImapSSLServer-8 prio=5 id=276 state=TERMINATED
Lock Waiter - Timer-Zimbra daemon prio=5 id=30 state=TIMED_WAITING
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireNanos(AbstractQueuedSynchronizer.java:934)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireNanos(AbstractQueuedSynchronizer.java:1247)
at java.util.concurrent.locks.ReentrantReadWriteLock$WriteLock.tryLock(ReentrantReadWriteLock.java:1115)
at com.zimbra.cs.mailbox.MailboxLock.tryLockWithTimeout(MailboxLock.java:114)
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:194)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.unload(ImapListener.java:580)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:195)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.getFolderTree(Mailbox.java:4295)
at com.zimbra.cs.service.mail.GetFolder.handle(GetFolder.java:113)
at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:607)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:460)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:273)
at com.zimbra.soap.SoapServlet.doWork(SoapServlet.java:303)
at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:213)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:206)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1685)
at com.zimbra.cs.servlet.CsrfFilter.doFilter(CsrfFilter.java:169)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.RequestStringFilter.doFilter(RequestStringFilter.java:54)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:59)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ETagHeaderFilter.doFilter(ETagHeaderFilter.java:47)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ContextPathBasedThreadPoolBalancerFilter.doFilter(ContextPathBasedThreadPoolBalancerFilter.java:107)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraQoSFilter.doFilter(ZimbraQoSFilter.java:116)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraInvalidLoginFilter.doFilter(ZimbraInvalidLoginFilter.java:117)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlets.DoSFilter.doFilterChain(DoSFilter.java:473)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:318)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:288)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:524)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:109)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:318)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437)
at org.eclipse.jetty.server.handler.DebugHandler.handle(DebugHandler.java:84)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:748)
2018-02-07 13:21:52,077 WARN [qtp1595953398-1021:http://localhost:8080/service/soap/GetFolderRequest] [name=userex@domain1.com;mid=4;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - handler exception
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.getFolderTree(Mailbox.java:4295)
at com.zimbra.cs.service.mail.GetFolder.handle(GetFolder.java:113)
at com.zimbra.soap.SoapEngine.dispatchRequest(SoapEngine.java:607)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:460)
at com.zimbra.soap.SoapEngine.dispatch(SoapEngine.java:273)
at com.zimbra.soap.SoapServlet.doWork(SoapServlet.java:303)
at com.zimbra.soap.SoapServlet.doPost(SoapServlet.java:213)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:206)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1685)
at com.zimbra.cs.servlet.CsrfFilter.doFilter(CsrfFilter.java:169)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.RequestStringFilter.doFilter(RequestStringFilter.java:54)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:59)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ETagHeaderFilter.doFilter(ETagHeaderFilter.java:47)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ContextPathBasedThreadPoolBalancerFilter.doFilter(ContextPathBasedThreadPoolBalancerFilter.java:107)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraQoSFilter.doFilter(ZimbraQoSFilter.java:116)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at com.zimbra.cs.servlet.ZimbraInvalidLoginFilter.doFilter(ZimbraInvalidLoginFilter.java:117)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlets.DoSFilter.doFilterChain(DoSFilter.java:473)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:318)
at org.eclipse.jetty.servlets.DoSFilter.doFilter(DoSFilter.java:288)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:524)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:109)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:318)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:437)
at org.eclipse.jetty.server.handler.DebugHandler.handle(DebugHandler.java:84)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:748)
2018-02-07 13:21:52,077 INFO [qtp1595953398-1021:http://localhost:8080/service/soap/GetFolderRequest] [name=userex@domain1.com;mid=4;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetFolderRequest elapsed=90002
2018-02-07 13:22:10,883 INFO [MailboxPurge] [name=userex@domain2.com;mid=5;] purge - Purging messages.
2018-02-07 13:22:24,876 ERROR [Timer-Zimbra] [] mailbox - Failed to lock mailbox
Write Lock Owner - ImapSSLServer-8 prio=5 id=276 state=TERMINATED
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.unload(ImapListener.java:580)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:195)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:22:24,877 WARN [Timer-Zimbra] [] imap - error serializing session; clearing ImapSession{id=2323, authAcct=c8acf2e7-5331-4f7e-9a79-7af63ba358a5, lastAccessed=2018-02-07 12:03:55,264, cleanedUp=true, addedToCache=false, path=Norwell/Sinagogue, folderId=c8acf2e7-5331-4f7e-9a79-7af63ba358a5:50560, handler=null}
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.unload(ImapListener.java:580)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:195)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:22:55,183 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/AuthRequest] [name=hb@domain2.com;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - AuthRequest elapsed=3
2018-02-07 13:22:55,200 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,202 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=2
2018-02-07 13:22:55,208 INFO [qtp1595953398-1043:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,208 INFO [qtp1595953398-1043:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,213 INFO [qtp1595953398-1044:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,213 INFO [qtp1595953398-1044:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,216 INFO [qtp1595953398-1044:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,216 INFO [qtp1595953398-1044:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,219 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,219 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,222 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,222 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,225 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,225 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,228 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,228 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,234 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,234 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,237 INFO [qtp1595953398-1044:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,237 INFO [qtp1595953398-1044:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:22:55,239 INFO [qtp1595953398-1043:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,240 INFO [qtp1595953398-1043:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:22:55,245 INFO [qtp1595953398-1045:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,246 INFO [qtp1595953398-1045:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:22:55,249 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,249 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,252 INFO [qtp1595953398-1044:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,252 INFO [qtp1595953398-1044:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,255 INFO [qtp1595953398-1043:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:22:55,255 INFO [qtp1595953398-1043:http://localhost:8080/service/soap/GetInfoRequest] [oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:22:55,268 INFO [qtp1595953398-1043:http://localhost:8080/service/soap/GetInfoRequest] [name=hb@domain2.com;mid=11;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=2
2018-02-07 13:22:56,827 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/SearchRequest] [name=hb@domain2.com;mid=11;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - SearchRequest elapsed=5
2018-02-07 13:23:03,598 INFO [qtp1595953398-1045:http://localhost:8080/service/soap/SearchRequest] [name=hb@domain2.com;mid=11;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - SearchRequest elapsed=8
2018-02-07 13:23:05,641 INFO [qtp1595953398-1036:http://localhost:8080/service/soap/GetMsgRequest] [name=hb@domain2.com;mid=11;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetMsgRequest elapsed=12
2018-02-07 13:23:07,036 INFO [qtp1595953398-1045:http://localhost:8080/service/soap/GetMsgRequest] [name=hb@domain2.com;mid=11;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - GetMsgRequest elapsed=4
2018-02-07 13:23:10,923 INFO [MailboxPurge] [name=gabriel@domain2.com;mid=8;] purge - Purging messages.
2018-02-07 13:23:13,084 INFO [qtp1595953398-1042:https:https://myserver.domain1.com:5960/dav/norwell@domain2.com/Tasks/] [aname=norwell@domain2.com;ip=192.168.1.100;port=59657;ua=DAVdroid/1.9.10-ose (1/19/18;; dav4android;; okhttp3) Android/6.0.1;] FileUploadServlet - saveUpload(): received Upload: { accountId=a4af9644-511b-43ed-ae07-b523d91ce477, time=Wed Feb 07 13:23:13 EST 2018, size=227, uploadId=478b65de-ba0b-48d9-b81a-46f49ed1f249:660dc4fd-7536-4c91-9bda-e128a17604d3, name=null, path=null }
2018-02-07 13:23:13,088 INFO [qtp1595953398-1042:https:https://myserver.domain1.com:5960/dav/norwell@domain2.com/Tasks/] [name=norwell@domain2.com;aname=norwell@domain2.com;ip=192.168.1.100;port=59657;ua=DAVdroid/1.9.10-ose (1/19/18;; dav4android;; okhttp3) Android/6.0.1;] dav - DavServlet operation PROPFIND to /home/norwell@domain2.com/Tasks/ (depth: zero) finished in 5ms
2018-02-07 13:23:25,615 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/SendMsgRequest] [name=hb@domain2.com;mid=11;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] smtp - Sending message to MTA at localhost: Message-ID=<199780128.81.1518027805577.JavaMail.zimbra@domain2.com>, origMsgId=36b72047-787a-4f6d-ad6d-2ef6d3f89b25:10265, replyType=r
2018-02-07 13:23:25,670 INFO [qtp1595953398-1030:http://localhost:8080/service/soap/SendMsgRequest] [name=hb@domain2.com;mid=11;oip=192.168.1.100;ua=zclient/8.8.6_GA_1906;] soap - SendMsgRequest elapsed=100
2018-02-07 13:23:44,230 INFO [qtp1595953398-1046:https:https://myserver.domain1.com:8443/service/soap/AuthRequest] [name=hb@domain2.com;ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - AuthRequest elapsed=2
2018-02-07 13:23:44,241 INFO [qtp1595953398-1045:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,242 INFO [qtp1595953398-1045:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:23:44,251 INFO [qtp1595953398-1030:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,251 INFO [qtp1595953398-1030:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:23:44,257 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,257 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:23:44,260 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,260 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:23:44,265 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,265 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:23:44,268 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,270 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=2
2018-02-07 13:23:44,274 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,276 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=2
2018-02-07 13:23:44,279 INFO [qtp1595953398-1046:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,280 INFO [qtp1595953398-1046:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:23:44,283 INFO [qtp1595953398-1045:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,283 INFO [qtp1595953398-1045:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:23:44,286 INFO [qtp1595953398-1030:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,286 INFO [qtp1595953398-1030:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:23:44,288 INFO [qtp1595953398-1030:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,289 INFO [qtp1595953398-1030:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:23:44,291 INFO [qtp1595953398-1046:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,291 INFO [qtp1595953398-1046:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:23:44,294 INFO [qtp1595953398-1045:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,294 INFO [qtp1595953398-1045:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:23:44,298 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,298 INFO [qtp1595953398-1052:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=1
2018-02-07 13:23:44,301 INFO [qtp1595953398-1030:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] SoapEngine - no valid authtoken present: cannot dispatch request
2018-02-07 13:23:44,301 INFO [qtp1595953398-1030:https:https://myserver.domain1.com:8443/service/soap/GetInfoRequest] [ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - GetInfoRequest elapsed=0
2018-02-07 13:23:44,311 INFO [qtp1595953398-1046:https:https://myserver.domain1.com:8443/service/soap/EndSessionRequest] [name=hb@domain2.com;mid=11;ip=192.168.1.100;port=60805;ua=zclient/8.8.6_GA_1906;] soap - EndSessionRequest elapsed=1
2018-02-07 13:23:44,340 INFO [qtp1595953398-1038:https:https://localhost:7071/service/admin/soap/GetDomainInfoRequest] [ua=ZCS/8.8.6_GA_1906;] soap - GetDomainInfoRequest elapsed=0
2018-02-07 13:23:44,353 INFO [qtp1595953398-1052:https:https://localhost:7071/service/admin/soap/GetDomainInfoRequest] [ua=ZCS/8.8.6_GA_1906;] soap - GetDomainInfoRequest elapsed=0
2018-02-07 13:23:44,841 INFO [qtp1595953398-1030:https:https://localhost:7071/service/admin/soap/GetDomainInfoRequest] [ua=ZCS/8.8.6_GA_1906;] soap - GetDomainInfoRequest elapsed=0
2018-02-07 13:23:54,818 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:54,841 INFO [ImapSSLServer-281] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:54,877 ERROR [Timer-Zimbra] [] mailbox - Failed to lock mailbox
Write Lock Owner - ImapSSLServer-8 prio=5 id=276 state=TERMINATED
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.detach(ImapListener.java:546)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.quietRemoveSession(ImapSessionManager.java:244)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:199)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:23:54,901 WARN [Timer-Zimbra] [] imap - skipping error while trying to remove session ImapSession{id=2323, authAcct=c8acf2e7-5331-4f7e-9a79-7af63ba358a5, lastAccessed=2018-02-07 12:03:55,264, cleanedUp=true, addedToCache=false, path=Norwell/Sinagogue, folderId=c8acf2e7-5331-4f7e-9a79-7af63ba358a5:50560, handler=null}
com.zimbra.cs.mailbox.MailboxLock$LockFailedException: timeout
at com.zimbra.cs.mailbox.MailboxLock.lock(MailboxLock.java:211)
at com.zimbra.cs.mailbox.Mailbox.lock(Mailbox.java:10424)
at com.zimbra.cs.imap.ImapListener.detach(ImapListener.java:546)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.quietRemoveSession(ImapSessionManager.java:244)
at com.zimbra.cs.imap.ImapSessionManager$SessionSerializerTask.run(ImapSessionManager.java:199)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2018-02-07 13:23:55,729 INFO [ImapSSLServer-281] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:55,736 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:55,736 INFO [ImapSSLServer-281] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:55,961 INFO [ImapSSLServer-281] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:55,961 INFO [ImapSSLServer-326] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - user userex@domain2.com authenticated, mechanism=PLAIN [TLS]
2018-02-07 13:23:55,961 INFO [ImapSSLServer-326] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:56,437 INFO [ImapSSLServer-326] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - user userex@domain2.com authenticated, mechanism=PLAIN [TLS]
2018-02-07 13:23:56,437 INFO [ImapSSLServer-326] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:23:56,792 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,052 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,052 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,068 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,069 INFO [ImapSSLServer-326] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - NAMESPACE elapsed=1
2018-02-07 13:23:57,080 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,081 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,081 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,081 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,082 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,082 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,108 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,121 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,122 INFO [ImapSSLServer-326] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,146 INFO [ImapSSLServer-328] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,146 INFO [ImapSSLServer-328] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,146 INFO [ImapSSLServer-328] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,146 INFO [ImapSSLServer-328] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,153 INFO [ImapSSLServer-329] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:23:57,154 INFO [ImapSSLServer-330] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=1
2018-02-07 13:23:57,154 INFO [ImapSSLServer-330] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,169 INFO [ImapSSLServer-332] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,174 INFO [ImapSSLServer-333] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - copying message data from serialized session: /Drafts
2018-02-07 13:23:57,174 INFO [ImapSSLServer-333] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - selected folder Drafts
2018-02-07 13:23:57,175 INFO [ImapSSLServer-333] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - EXAMINE elapsed=3
2018-02-07 13:23:57,183 INFO [ImapSSLServer-334] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,193 INFO [ImapSSLServer-335] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,197 INFO [ImapSSLServer-336] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=1
2018-02-07 13:23:57,209 INFO [ImapSSLServer-337] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,209 INFO [ImapSSLServer-337] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,209 INFO [ImapSSLServer-337] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,209 INFO [ImapSSLServer-337] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,210 INFO [ImapSSLServer-338] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - NAMESPACE elapsed=0
2018-02-07 13:23:57,210 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,210 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,211 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,211 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,211 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,211 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,211 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,211 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,211 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,212 INFO [ImapSSLServer-338] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:23:57,212 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:23:57,213 INFO [ImapSSLServer-339] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,243 INFO [ImapSSLServer-341] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:23:57,244 INFO [ImapSSLServer-342] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:23:57,245 INFO [ImapSSLServer-343] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:23:57,252 INFO [ImapSSLServer-344] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=7
2018-02-07 13:23:57,253 INFO [ImapSSLServer-345] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,253 INFO [ImapSSLServer-346] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,260 INFO [ImapSSLServer-347] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:23:57,260 INFO [ImapSSLServer-348] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,261 INFO [ImapSSLServer-349] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:23:57,264 INFO [ImapSSLServer-350] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,264 INFO [ImapSSLServer-351] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,274 INFO [ImapSSLServer-353] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:23:57,281 INFO [ImapSSLServer-354] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=1
2018-02-07 13:24:10,933 INFO [MailboxPurge] [name=hb@domain2.com;mid=11;] purge - Purging messages.
2018-02-07 13:24:55,603 INFO [ImapSSLServer-357] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:24:55,603 INFO [ImapSSLServer-357] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:24:55,604 INFO [ImapSSLServer-357] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=1
2018-02-07 13:24:55,604 INFO [ImapSSLServer-357] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:24:55,604 INFO [ImapSSLServer-357] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:24:55,606 INFO [ImapSSLServer-358] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:24:55,613 INFO [ImapSSLServer-358] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=7
2018-02-07 13:24:55,613 INFO [ImapSSLServer-359] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:24:55,613 INFO [ImapSSLServer-359] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:24:55,613 INFO [ImapSSLServer-359] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:24:55,613 INFO [ImapSSLServer-359] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:24:55,613 INFO [ImapSSLServer-359] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - ID elapsed=0
2018-02-07 13:24:55,630 INFO [ImapSSLServer-359] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - copying message data from serialized session: /Inbox
2018-02-07 13:24:55,635 INFO [ImapSSLServer-360] [name=userex@domain2.com;mid=5;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - [ search is: in:"Drafts" (item:all date:>=11/7/17 -tag:\Deleted) ]
2018-02-07 13:24:55,636 INFO [ImapSSLServer-359] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - selected folder INBOX
2018-02-07 13:24:55,644 INFO [ImapSSLServer-360] [name=userex@domain2.com;mid=5;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - UID SEARCH elapsed=12
2018-02-07 13:24:55,644 INFO [ImapSSLServer-360] [] imap - dropping connection for user userex@domain2.com (LOGOUT)
2018-02-07 13:24:55,644 INFO [ImapSSLServer-359] [name=userex@domain2.com;ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - EXAMINE elapsed=31
2018-02-07 13:24:55,645 INFO [ImapSSLServer-359] [] imap - dropping connection for user userex@domain2.com (LOGOUT)
2018-02-07 13:24:55,645 INFO [ImapSSLServer-359] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:24:55,645 INFO [ImapSSLServer-359] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:24:55,645 INFO [ImapSSLServer-359] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:24:55,646 INFO [ImapSSLServer-360] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:24:55,646 INFO [ImapSSLServer-360] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:24:55,646 INFO [ImapSSLServer-360] [ip=192.168.1.100;oip=10.10.10.10;via=192.168.1.100(nginx/1.7.1);ua=Zimbra/8.8.6_GA_1906;] imap - AUTHENTICATE elapsed=0
2018-02-07 13:25:10,944 INFO [MailboxPurge] [name=norwell@domain2.com;mid=14;] purge - Purging messages.