I tried to destroy and recreate the user account, reconfigure the pop external source but on one account and on only on that one the problem is still there.
The problem survived also a server and os move (I migrated the installation from an ubuntu server to a centos one, following a good guide here in the forum).
I'm posting what is logged on
/opt/zimbra/log/mailbox.log in the hope that someone can help me solve the problem. I've "cleaned" the log replacing real names with fake ones.
Code:
2011-01-07 00:06:14,918 INFO [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] cache - initializing folder and tag caches for mailbox 8
2011-01-07 00:06:14,921 INFO [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] filter - Unable to file message to Inbox/Extra FOLDER/Folder Name. Filing to /Inbox instead.
com.zimbra.common.service.ServiceException: system failure: Unable to add message
ExceptionId:ScheduledTask-4:1294355174918:e25e14d8a8584bda
Code:service.FAILURE
at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:248)
at com.zimbra.cs.filter.FilterUtil.addMessage(FilterUtil.java:289)
at com.zimbra.cs.filter.IncomingMessageHandler.fileInto(IncomingMessageHandler.java:83)
at com.zimbra.cs.filter.ZimbraMailAdapter.fileInto(ZimbraMailAdapter.java:323)
at com.zimbra.cs.filter.ZimbraMailAdapter.executeActions(ZimbraMailAdapter.java:200)
at org.apache.jsieve.SieveFactory.evaluate(SieveFactory.java:164)
at com.zimbra.cs.filter.RuleManager.applyRulesToIncomingMessage(RuleManager.java:349)<
at com.zimbra.cs.datasource.Pop3Sync.fetchAndAddMessage(Pop3Sync.java:250)
at com.zimbra.cs.datasource.Pop3Sync.fetchAndDeleteMessages(Pop3Sync.java:198)
at com.zimbra.cs.datasource.Pop3Sync.importData(Pop3Sync.java:142)
at com.zimbra.cs.datasource.DataSourceManager.importData(DataSourceManager.java:254)
at com.zimbra.cs.datasource.DataSourceManager.importData(DataSourceManager.java:214)
at com.zimbra.cs.datasource.DataSourceTask.call(DataSourceTask.java:82)
at com.zimbra.cs.datasource.DataSourceTask.call(DataSourceTask.java:28)
at com.zimbra.common.util.TaskScheduler$TaskRunner.call(TaskScheduler.java:96)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:206)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by: java.io.IOException: /opt/zimbra/store/incoming/1294352341438-247.msg does not exist.
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:170)
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:163)
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:44)
at com.zimbra.cs.mailbox.Mailbox.addMessageInternal(Mailbox.java:4688)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4432)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4365)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4313)
at com.zimbra.cs.filter.FilterUtil.addMessage(FilterUtil.java:282)
... 20 more
2011-01-07 00:06:14,923 INFO [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] mailop - Adding Message: id=81419, Message-ID=<002a01cbac29$13d390e0$2f31a8c0@aname.locale>, parentId=81059, folderId=2, folderName=Inbox.
2011-01-07 00:06:14,925 ERROR [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] jsieve - Evaluation failed. Reason: null
2011-01-07 00:06:14,925 INFO [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] cache - initializing folder and tag caches for mailbox 8
2011-01-07 00:06:14,929 WARN [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] filter - An error occurred while processing filter rules. Filing message to /Inbox.
com.zimbra.cs.filter.ZimbraSieveException
at com.zimbra.cs.filter.ZimbraMailAdapter.executeActions(ZimbraMailAdapter.java:225)
at org.apache.jsieve.SieveFactory.evaluate(SieveFactory.java:164)
at com.zimbra.cs.filter.RuleManager.applyRulesToIncomingMessage(RuleManager.java:349)
at com.zimbra.cs.datasource.Pop3Sync.fetchAndAddMessage(Pop3Sync.java:250)
at com.zimbra.cs.datasource.Pop3Sync.fetchAndDeleteMessages(Pop3Sync.java:198)
at com.zimbra.cs.datasource.Pop3Sync.importData(Pop3Sync.java:142)
at com.zimbra.cs.datasource.DataSourceManager.importData(DataSourceManager.java:254)
at com.zimbra.cs.datasource.DataSourceManager.importData(DataSourceManager.java:214)
at com.zimbra.cs.datasource.DataSourceTask.call(DataSourceTask.java:82)
at com.zimbra.cs.datasource.DataSourceTask.call(DataSourceTask.java:28)
at com.zimbra.common.util.TaskScheduler$TaskRunner.call(TaskScheduler.java:96)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:206)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by: com.zimbra.common.service.ServiceException: system failure: Unable to add incoming message
ExceptionId:ScheduledTask-4:1294355174925:e25e14d8a8584bda
Code:service.FAILURE
at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:248)
at com.zimbra.cs.filter.IncomingMessageHandler.addMessage(IncomingMessageHandler.java:116)
at com.zimbra.cs.filter.IncomingMessageHandler.explicitKeep(IncomingMessageHandler.java:77)
at com.zimbra.cs.filter.ZimbraMailAdapter.explicitKeep(ZimbraMailAdapter.java:304)
at com.zimbra.cs.filter.ZimbraMailAdapter.executeActions(ZimbraMailAdapter.java:205)
... 17 more
Caused by: java.io.IOException: /opt/zimbra/store/incoming/1294352341438-247.msg does not exist.
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:170)
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:163)
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:44)
at com.zimbra.cs.mailbox.Mailbox.addMessageInternal(Mailbox.java:4688)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4432)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4365)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4313)
at com.zimbra.cs.filter.IncomingMessageHandler.addMessage(IncomingMessageHandler.java:113)
... 20 more
2011-01-07 00:06:14,931 INFO [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] mailop - Adding Message: id=81419, Message-ID=<002a01cbac29$13d390e0$2f31a8c0@aname.locale>, parentId=81059, folderId=2, folderName=Inbox.
2011-01-07 00:06:14,985 INFO [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] datasource - Import completed for data source 'DATASOURCE_NAME'
2011-01-07 00:06:14,985 WARN [ScheduledTask-4] [name=username@mydomain.com;mid=8;ds=DATASOURCE_NAME;] datasource - Scheduled DataSource import failed.
com.zimbra.common.service.ServiceException: system failure: Unable to add incoming message
ExceptionId:ScheduledTask-4:1294355174933:e25e14d8a8584bda
Code:service.FAILURE
at com.zimbra.common.service.ServiceException.FAILURE(ServiceException.java:248)
at com.zimbra.cs.filter.IncomingMessageHandler.addMessage(IncomingMessageHandler.java:116)
at com.zimbra.cs.filter.IncomingMessageHandler.implicitKeep(IncomingMessageHandler.java:106)
at com.zimbra.cs.filter.ZimbraMailAdapter.doDefaultFiling(ZimbraMailAdapter.java:283)
at com.zimbra.cs.filter.RuleManager.applyRulesToIncomingMessage(RuleManager.java:364)
at com.zimbra.cs.datasource.Pop3Sync.fetchAndAddMessage(Pop3Sync.java:250)
at com.zimbra.cs.datasource.Pop3Sync.fetchAndDeleteMessages(Pop3Sync.java:198)
at com.zimbra.cs.datasource.Pop3Sync.importData(Pop3Sync.java:142)
at com.zimbra.cs.datasource.DataSourceManager.importData(DataSourceManager.java:254)
at com.zimbra.cs.datasource.DataSourceManager.importData(DataSourceManager.java:214)
at com.zimbra.cs.datasource.DataSourceTask.call(DataSourceTask.java:82)
at com.zimbra.cs.datasource.DataSourceTask.call(DataSourceTask.java:28)
at com.zimbra.common.util.TaskScheduler$TaskRunner.call(TaskScheduler.java:96)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:206)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by: java.io.IOException: /opt/zimbra/store/incoming/1294352341438-247.msg does not exist.
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:170)
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:163)
at com.zimbra.cs.store.file.FileBlobStore.link(FileBlobStore.java:44)
at com.zimbra.cs.mailbox.Mailbox.addMessageInternal(Mailbox.java:4688)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4432)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4365)
at com.zimbra.cs.mailbox.Mailbox.addMessage(Mailbox.java:4313)
at com.zimbra.cs.filter.IncomingMessageHandler.addMessage(IncomingMessageHandler.java:113)
... 18 more
2011-01-07 00:06:42,680 INFO [MailboxPurge] [name=ham.9j6plubn@zimbra.mydomain.com;mid=6;] purge - Purging messages.
2011-01-07 00:07:44,742 INFO [MailboxPurge] [name=username@mydomain.com;mid=8;] purge - Purging messages.
To get the account with the problem to work again, I need to log into the webmail of the external serverice where I connect using the pop account and delete the message which keeps being downloaded producing the logs above. After that, I need to restart the whole zimbra service (it's a problem for the other users, since this can happen even more than 5 times in a day!) and then zimbra seems to work again as expected... until the next blocking message.
If this info can be of any help, the server runs on a virtual machine inside a esx system with two xeons reserved and 4GB of RAM.
Many thanks in advance to anyone who can help!