Inital Synch issue (7.1.2)
Posted: Fri Aug 19, 2011 5:08 am
On Wednesday we upgraded to Zimbra 7.1.2 (Release 7.1.2_GA_3268.RHEL5_64_20110804120428 CentOS5_64 NETWORK edition.)
A customer tries now to Install the new ZCO on a few systems. But there are problems with the initial synch. It stops after some time with an error. After starting outlook again it goes on (in most cases) but stops again after a while.
When the inital synch is done (After some outlook restarts) it seems to run normaly. This can take a lot of restarts on a large account.
This is what I found in the mailbox.log:
[QUOTE]
2011-08-19 11:30:29,049 WARN [btpool0-2114://zimbra.xxx.net/service/user/~/?fmt=zip&meta=1&zlv=4&list=53108,53103,53102,53080,53077,53074,53072,53070,53061,53058,53057,53034,53032,53023,53017,53013,53001,52952,52943,52936,52932,52914,52905,52904,52893,52892,52881,52875,52832,52817,52767,52740,52739,52700,52694,52693,52688,52676,52659,52657,52650,52611,52610,52608,52607,52606,52605,52604,52603,52600,52596,52585,52554,52552,52551,52536,52535,52525,52522,52518,52509,52501,52500,52499,52493,52487,52484,52478,52453,52452,52444,52438,52355,52354,52335,52327,52323,52320,52305,52304,52303,52299,52260,52243,52231,52220,52219,52210,52209,52205,52204,52202,52144,52141,52139,52107,52104,52095,52094,52093] [name=name-of-user@customer.com;mid=589;ip=145.253.178.126;] misc - Archive Formatter warning: com.zimbra.common.service.ServiceException: system failure: archive error
ExceptionId:btpool0-2114://zimbra.xxx.net/service/user/~/?fmt=zip&meta=1&zlv=4&list=53108,53103,53102,53080,53077,53074,53072,53070,53061,53058,53057,53034,53032,53023,53017,53013,53001,52952,52943,52936,52932,52914,52905,52904,52893,52892,52881,52875,52832,52817,52767,52740,52739,52700,52694,52693,52688,52676,52659,52657,52650,52611,52610,52608,52607,52606,52605,52604,52603,52600,52596,52585,52554,52552,52551,52536,52535,52525,52522,52518,52509,52501,52500,52499,52493,52487,52484,52478,52453,52452,52444,52438,52355,52354,52335,52327,52323,52320,52305,52304,52303,52299,52260,52243,52231,52220,52219,52210,52209,52205,52204,52202,52144,52141,52139,52107,52104,52095,52094,52093:1313746229049:75c0b0915b45ee5c
Code:service.FAILURE: org.mortbay.jetty.EofException: timeout
org.mortbay.jetty.EofException: timeout
at org.mortbay.jetty.AbstractGenerator$Output.blockForOutput(AbstractGenerator.java:548)
at org.mortbay.jetty.AbstractGenerator$Output.flush(AbstractGenerator.java:572)
at org.mortbay.jetty.HttpConnection$Output.flush(HttpConnection.java:1013)
at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:651)
at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:580)
at com.zimbra.common.util.zip.ZipOutputStream.writeOut(ZipOutputStream.java:993)
at com.zimbra.common.util.zip.ZipOutputStream.deflate(ZipOutputStream.java:636)
at com.zimbra.common.util.zip.ZipOutputStream.write(ZipOutputStream.java:525)
at com.zimbra.cs.service.formatter.ZipFormatter$ZipArchiveOutputStream.write(ZipFormatter.java:106)
at com.zimbra.cs.service.formatter.ArchiveFormatter.saveItem(ArchiveFormatter.java:602)
at com.zimbra.cs.service.formatter.ArchiveFormatter.formatCallback(ArchiveFormatter.java:258)
at com.zimbra.cs.service.formatter.Formatter.format(Formatter.java:128)
at com.zimbra.cs.service.UserServlet.doAuthGet(UserServlet.java:469)
at com.zimbra.cs.service.UserServlet.doGet(UserServlet.java:274)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:705">HttpServlet.service(HttpServlet.java:705)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:208)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:814">HttpServlet.service(HttpServlet.java:814)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:511)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1166)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:79)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
at org.mortbay.servlet.UserAgentFilter.doFilter(UserAgentFilter.java:81)
at org.mortbay.servlet.GzipFilter.doFilter(GzipFilter.java:155)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:218)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:422)
at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.handler.rewrite.RewriteHandler.handle(RewriteHandler.java:230)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.handler.DebugHandler.handle(DebugHandler.java:77)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.Server.handle(Server.java:326)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:543)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:929)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:549)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:405)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:451)
2011-08-19 11:30:29,049 WARN [btpool0-2114://zimbra.xxx.net/service/user/~/?fmt=zip&meta=1&zlv=4&list=53108,53103,53102,53080,53077,53074,53072,53070,53061,53058,53057,53034,53032,53023,53017,53013,53001,52952,52943,52936,52932,52914,52905,52904,52893,52892,52881,52875,52832,52817,52767,52740,52739,52700,52694,52693,52688,52676,52659,52657,52650,52611,52610,52608,52607,52606,52605,52604,52603,52600,52596,52585,52554,52552,52551,52536,52535,52525,52522,52518,52509,52501,52500,52499,52493,52487,52484,52478,52453,52452,52444,52438,52355,52354,52335,52327,52323,52320,52305,52304,52303,52299,52260,52243,52231,52220,52219,52210,52209,52205,52204,52202,52144,52141,52139,52107,52104,52095,52094,52093] [name=name-of-user@customer.com;mid=589;ip=145.253.178.126;] log - Committed before 204 No data found
2011-08-19 11:30:29,049 ERROR [btpool0-2114://zimbra.xxx.net/service/user/~/?fmt=zip&meta=1&zlv=4&list=53108,53103,53102,53080,53077,53074,53072,53070,53061,53058,53057,53034,53032,53023,53017,53013,53001,52952,52943,52936,52932,52914,52905,52904,52893,52892,52881,52875,52832,52817,52767,52740,52739,52700,52694,52693,52688,52676,52659,52657,52650,52611,52610,52608,52607,52606,52605,52604,52603,52600,52596,52585,52554,52552,52551,52536,52535,52525,52522,52518,52509,52501,52500,52499,52493,52487,52484,52478,52453,52452,52444,52438,52355,52354,52335,52327,52323,52320,52305,52304,52303,52299,52260,52243,52231,52220,52219,52210,52209,52205,52204,52202,52144,52141,52139,52107,52104,52095,52094,52093] [] log - /service/user/~/
java.lang.IllegalStateException: Committed
at org.mortbay.jetty.Response.resetBuffer(Response.java:1023)
at org.mortbay.jetty.Response.sendError(Response.java:240)
at com.zimbra.cs.service.UserServlet.doGet(UserServlet.java:287)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:705">HttpServlet.service(HttpServlet.java:705)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:208)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:814">HttpServlet.service(HttpServlet.java:814)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:511)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1166)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:79)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
at org.mortbay.servlet.UserAgentFilter.doFilter(UserAgentFilter.java:81)
at org.mortbay.servlet.GzipFilter.doFilter(GzipFilter.java:155)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:218)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:422)
at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.handler.rewrite.RewriteHandler.handle(RewriteHandler.java:230)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.handler.DebugHandler.handle(DebugHandler.java:77)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.Server.handle(Server.java:326)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:543)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:929)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:549)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:405)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:451)
[/QUOTE]
Has anybody seen something like this? We didn't tried to synch with the old version of ZCO until now.
A customer tries now to Install the new ZCO on a few systems. But there are problems with the initial synch. It stops after some time with an error. After starting outlook again it goes on (in most cases) but stops again after a while.
When the inital synch is done (After some outlook restarts) it seems to run normaly. This can take a lot of restarts on a large account.
This is what I found in the mailbox.log:
[QUOTE]
2011-08-19 11:30:29,049 WARN [btpool0-2114://zimbra.xxx.net/service/user/~/?fmt=zip&meta=1&zlv=4&list=53108,53103,53102,53080,53077,53074,53072,53070,53061,53058,53057,53034,53032,53023,53017,53013,53001,52952,52943,52936,52932,52914,52905,52904,52893,52892,52881,52875,52832,52817,52767,52740,52739,52700,52694,52693,52688,52676,52659,52657,52650,52611,52610,52608,52607,52606,52605,52604,52603,52600,52596,52585,52554,52552,52551,52536,52535,52525,52522,52518,52509,52501,52500,52499,52493,52487,52484,52478,52453,52452,52444,52438,52355,52354,52335,52327,52323,52320,52305,52304,52303,52299,52260,52243,52231,52220,52219,52210,52209,52205,52204,52202,52144,52141,52139,52107,52104,52095,52094,52093] [name=name-of-user@customer.com;mid=589;ip=145.253.178.126;] misc - Archive Formatter warning: com.zimbra.common.service.ServiceException: system failure: archive error
ExceptionId:btpool0-2114://zimbra.xxx.net/service/user/~/?fmt=zip&meta=1&zlv=4&list=53108,53103,53102,53080,53077,53074,53072,53070,53061,53058,53057,53034,53032,53023,53017,53013,53001,52952,52943,52936,52932,52914,52905,52904,52893,52892,52881,52875,52832,52817,52767,52740,52739,52700,52694,52693,52688,52676,52659,52657,52650,52611,52610,52608,52607,52606,52605,52604,52603,52600,52596,52585,52554,52552,52551,52536,52535,52525,52522,52518,52509,52501,52500,52499,52493,52487,52484,52478,52453,52452,52444,52438,52355,52354,52335,52327,52323,52320,52305,52304,52303,52299,52260,52243,52231,52220,52219,52210,52209,52205,52204,52202,52144,52141,52139,52107,52104,52095,52094,52093:1313746229049:75c0b0915b45ee5c
Code:service.FAILURE: org.mortbay.jetty.EofException: timeout
org.mortbay.jetty.EofException: timeout
at org.mortbay.jetty.AbstractGenerator$Output.blockForOutput(AbstractGenerator.java:548)
at org.mortbay.jetty.AbstractGenerator$Output.flush(AbstractGenerator.java:572)
at org.mortbay.jetty.HttpConnection$Output.flush(HttpConnection.java:1013)
at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:651)
at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:580)
at com.zimbra.common.util.zip.ZipOutputStream.writeOut(ZipOutputStream.java:993)
at com.zimbra.common.util.zip.ZipOutputStream.deflate(ZipOutputStream.java:636)
at com.zimbra.common.util.zip.ZipOutputStream.write(ZipOutputStream.java:525)
at com.zimbra.cs.service.formatter.ZipFormatter$ZipArchiveOutputStream.write(ZipFormatter.java:106)
at com.zimbra.cs.service.formatter.ArchiveFormatter.saveItem(ArchiveFormatter.java:602)
at com.zimbra.cs.service.formatter.ArchiveFormatter.formatCallback(ArchiveFormatter.java:258)
at com.zimbra.cs.service.formatter.Formatter.format(Formatter.java:128)
at com.zimbra.cs.service.UserServlet.doAuthGet(UserServlet.java:469)
at com.zimbra.cs.service.UserServlet.doGet(UserServlet.java:274)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:705">HttpServlet.service(HttpServlet.java:705)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:208)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:814">HttpServlet.service(HttpServlet.java:814)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:511)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1166)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:79)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
at org.mortbay.servlet.UserAgentFilter.doFilter(UserAgentFilter.java:81)
at org.mortbay.servlet.GzipFilter.doFilter(GzipFilter.java:155)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:218)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:422)
at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.handler.rewrite.RewriteHandler.handle(RewriteHandler.java:230)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.handler.DebugHandler.handle(DebugHandler.java:77)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.Server.handle(Server.java:326)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:543)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:929)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:549)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:405)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:451)
2011-08-19 11:30:29,049 WARN [btpool0-2114://zimbra.xxx.net/service/user/~/?fmt=zip&meta=1&zlv=4&list=53108,53103,53102,53080,53077,53074,53072,53070,53061,53058,53057,53034,53032,53023,53017,53013,53001,52952,52943,52936,52932,52914,52905,52904,52893,52892,52881,52875,52832,52817,52767,52740,52739,52700,52694,52693,52688,52676,52659,52657,52650,52611,52610,52608,52607,52606,52605,52604,52603,52600,52596,52585,52554,52552,52551,52536,52535,52525,52522,52518,52509,52501,52500,52499,52493,52487,52484,52478,52453,52452,52444,52438,52355,52354,52335,52327,52323,52320,52305,52304,52303,52299,52260,52243,52231,52220,52219,52210,52209,52205,52204,52202,52144,52141,52139,52107,52104,52095,52094,52093] [name=name-of-user@customer.com;mid=589;ip=145.253.178.126;] log - Committed before 204 No data found
2011-08-19 11:30:29,049 ERROR [btpool0-2114://zimbra.xxx.net/service/user/~/?fmt=zip&meta=1&zlv=4&list=53108,53103,53102,53080,53077,53074,53072,53070,53061,53058,53057,53034,53032,53023,53017,53013,53001,52952,52943,52936,52932,52914,52905,52904,52893,52892,52881,52875,52832,52817,52767,52740,52739,52700,52694,52693,52688,52676,52659,52657,52650,52611,52610,52608,52607,52606,52605,52604,52603,52600,52596,52585,52554,52552,52551,52536,52535,52525,52522,52518,52509,52501,52500,52499,52493,52487,52484,52478,52453,52452,52444,52438,52355,52354,52335,52327,52323,52320,52305,52304,52303,52299,52260,52243,52231,52220,52219,52210,52209,52205,52204,52202,52144,52141,52139,52107,52104,52095,52094,52093] [] log - /service/user/~/
java.lang.IllegalStateException: Committed
at org.mortbay.jetty.Response.resetBuffer(Response.java:1023)
at org.mortbay.jetty.Response.sendError(Response.java:240)
at com.zimbra.cs.service.UserServlet.doGet(UserServlet.java:287)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:705">HttpServlet.service(HttpServlet.java:705)
at com.zimbra.cs.servlet.ZimbraServlet.service(ZimbraServlet.java:208)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:814">HttpServlet.service(HttpServlet.java:814)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:511)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1166)
at com.zimbra.cs.servlet.SetHeaderFilter.doFilter(SetHeaderFilter.java:79)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
at org.mortbay.servlet.UserAgentFilter.doFilter(UserAgentFilter.java:81)
at org.mortbay.servlet.GzipFilter.doFilter(GzipFilter.java:155)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:218)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:422)
at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.handler.rewrite.RewriteHandler.handle(RewriteHandler.java:230)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.handler.DebugHandler.handle(DebugHandler.java:77)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.Server.handle(Server.java:326)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:543)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:929)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:549)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:405)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:451)
[/QUOTE]
Has anybody seen something like this? We didn't tried to synch with the old version of ZCO until now.