Errors delivering mail with zcs-NETWORK-3.0.0_GA_156

[billybofh]

I've just installed a new test copy of zimbra to try and tried sending some test messages on the local box. I'm seeing the following error over and over in the zimbra.log :

2006-02-14 10:48:05,355 WARN  [LmtpServer-5] [] ParsedMessage - Message had parse errors in 1 parts (Message-Id: <200602141048.k1EAm3IA029313@plab329-02.elec.gla.ac.uk>, Subject: froop)
2006-02-14 10:48:05,355 WARN  [LmtpServer-5] [] ZimbraLmtpBackend - Exception delivering mail (temporary failure)
com.zimbra.cs.service.ServiceException: system failure: failed to analyze part
        at com.zimbra.cs.service.ServiceException.FAILURE(ServiceException.java:131)
        at com.zimbra.cs.mime.ParsedMessage.analyzeMessage(ParsedMessage.java:419)
        at com.zimbra.cs.mime.ParsedMessage.analyze(ParsedMessage.java:155)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliverMessageToLocalMailboxes(ZimbraLmtpBackend.java:185)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliver(ZimbraLmtpBackend.java:109)
        at com.zimbra.cs.lmtpserver.LmtpHandler.doDATA(LmtpHandler.java:420)
        at com.zimbra.cs.lmtpserver.LmtpHandler.processCommand(LmtpHandler.java:197)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:595)
Caused by: com.zimbra.cs.convert.ConversionException: Cannot connect to convertd
        at com.zimbra.cs.convert.SocketTransformationStub.doExtract(SocketTransformationStub.java:103)
        at com.zimbra.cs.convert.SocketTransformationStub.extract(SocketTransformationStub.java:151)
        at com.zimbra.cs.mime.handler.DefaultHandler.getContentImpl(DefaultHandler.java:85)
        at com.zimbra.cs.mime.MimeHandler.getContent(MimeHandler.java:254)
        at com.zimbra.cs.mime.ParsedMessage.analyzePart(ParsedMessage.java:492)
        at com.zimbra.cs.mime.ParsedMessage.analyzeMessage(ParsedMessage.java:410)
        ... 9 more
Caused by: java.net.ConnectException: Connection refused
        at java.net.PlainSocketImpl.socketConnect(Native Method)
        at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
        at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
        at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:364)
        at java.net.Socket.connect(Socket.java:507)
        at java.net.Socket.connect(Socket.java:457)
        at java.net.Socket.<init>(Socket.java:365)
        at java.net.Socket.<init>(Socket.java:178)
        at com.zimbra.cs.convert.TransformationClient.connect(TransformationClient.java:46)
        at com.zimbra.cs.convert.TransformationClient.reconnect(TransformationClient.java:194)
        at com.zimbra.cs.convert.SocketTransformationStub.doExtract(SocketTransformationStub.java:98)
        ... 14 more
2006-02-14 10:48:05,356 INFO  [LmtpServer-5] [] LmtpHandler - [130.209.177.102] 451 4.0.0 Temporary message delivery failure try again (DATA)

The test email messages are all of the form :

Received: from localhost (localhost.localdomain [127.0.0.1])
	by xxxxxx (Postfix) with ESMTP id 0D5F4A3D22
	for <user@xxxxxx>; Tue, 14 Feb 2006 10:48:18 +0000 (GMT)
Received: from xxxxx ([127.0.0.1])
 by localhost (xxxxx [127.0.0.1]) (amavisd-new, port 10024)
 with ESMTP id 28991-01 for <user@xxxxx>;
 Tue, 14 Feb 2006 10:48:17 +0000 (GMT)
Received: from xxxxx (localhost.localdomain [127.0.0.1])
	by xxxxx (Postfix) with ESMTP id E33C0A3D13
	for <user@xxxxxx>; Tue, 14 Feb 2006 10:48:17 +0000 (GMT)
Received: (from root@localhost)
	by xxxxxx (8.13.1/8.13.1/Submit) id k1EAmHg2029588
	for user@xxxxxxxxx; Tue, 14 Feb 2006 10:48:17 GMT
Date: Tue, 14 Feb 2006 10:48:17 GMT
From: root <root@xxxxxx>
Message-Id: <200602141048.k1EAmHg2029588@xxxxxx>
To: user@xxxxxx
Subject: froop
X-Virus-Scanned: amavisd-new at 
X-Spam-Status: No, score=-4.369 tagged_above=-10 required=6.6
 tests=[ALL_TRUSTED=-1.8, AWL=0.030, BAYES_00=-2.599]
X-Spam-Score: -4.369
X-Spam-Level: 

 word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word word

The messages do seem to be delivered (eventually) but there is a long, long delay in them arriving.

[billybofh]

As an additional worry - if I leave the test script running tfor about 30 minutes hen the system load is building to 60+ .....

[billybofh]

Another little test... I restarted the machine and then did a simple :

echo "hello" | mailx -s 'qqqq' user@xxxxxx

And get this in zimbra.log :

2006-02-14 13:15:45,707 WARN  [Convertd-2] [] VerityEngine - Verity filter error
com.verity.api.filter.FilterException: Error loading shared library kvfilter.
        at com.verity.api.filter.Filter.getStreamDocFormatInfo(Native Method)
        at com.verity.api.filter.Filter.getDocFormatInfo(Unknown Source)
        at com.zimbra.cs.convertd.VerityEngine.extract(VerityEngine.java:233)
        at com.zimbra.cs.convertd.TransformationHandler.doTextExtraction(TransformationHandler.java:194)
        at com.zimbra.cs.convertd.TransformationHandler.processCommand(TransformationHandler.java:102)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:595)
2006-02-14 13:15:45,708 WARN  [LmtpServer-17] [] ParsedMessage - Parse error on MIME part 1 (text/plain, Message-ID: <200602141315.k1EDFjVu016969@xxxxxx>)
com.zimbra.cs.mime.MimeHandlerException: cannot extract text
        at com.zimbra.cs.mime.handler.DefaultHandler.getContentImpl(DefaultHandler.java:92)
        at com.zimbra.cs.mime.MimeHandler.getContent(MimeHandler.java:254)
        at com.zimbra.cs.mime.ParsedMessage.analyzePart(ParsedMessage.java:492)
        at com.zimbra.cs.mime.ParsedMessage.analyzeMessage(ParsedMessage.java:410)
        at com.zimbra.cs.mime.ParsedMessage.analyze(ParsedMessage.java:155)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliverMessageToLocalMailboxes(ZimbraLmtpBackend.java:185)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliver(ZimbraLmtpBackend.java:109)
        at com.zimbra.cs.lmtpserver.LmtpHandler.doDATA(LmtpHandler.java:420)
        at com.zimbra.cs.lmtpserver.LmtpHandler.processCommand(LmtpHandler.java:197)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:595)
Caused by: com.zimbra.cs.convert.ConversionException: Error loading shared library kvfilter.
        at com.zimbra.cs.convert.TransformationClient.extract(TransformationClient.java:166)
        at com.zimbra.cs.convert.SocketTransformationStub.doExtract(SocketTransformationStub.java:95)
        at com.zimbra.cs.convert.SocketTransformationStub.extract(SocketTransformationStub.java:151)
        at com.zimbra.cs.mime.handler.DefaultHandler.getContentImpl(DefaultHandler.java:85)
        ... 12 more
2006-02-14 13:15:45,751 WARN  [Convertd-2] [] VerityEngine - Verity filter error
com.verity.api.filter.FilterException: Error loading shared library kvfilter.
        at com.verity.api.filter.Filter.getStreamDocFormatInfo(Native Method)
        at com.verity.api.filter.Filter.getDocFormatInfo(Unknown Source)
        at com.zimbra.cs.convertd.VerityEngine.extract(VerityEngine.java:233)
        at com.zimbra.cs.convertd.TransformationHandler.doTextExtraction(TransformationHandler.java:194)
        at com.zimbra.cs.convertd.TransformationHandler.processCommand(TransformationHandler.java:102)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:595)
2006-02-14 13:15:45,792 WARN  [LmtpServer-17] [] ParsedMessage - Parse error on MIME part 2 (message/delivery-status, Message-ID: <200602141315.k1EDFjVu016969@xxxxxxx>)
com.zimbra.cs.mime.MimeHandlerException: cannot extract text
        at com.zimbra.cs.mime.handler.DefaultHandler.getContentImpl(DefaultHandler.java:92)
        at com.zimbra.cs.mime.MimeHandler.getContent(MimeHandler.java:254)
        at com.zimbra.cs.mime.ParsedMessage.analyzePart(ParsedMessage.java:492)
        at com.zimbra.cs.mime.ParsedMessage.analyzeMessage(ParsedMessage.java:410)
        at com.zimbra.cs.mime.ParsedMessage.analyze(ParsedMessage.java:155)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliverMessageToLocalMailboxes(ZimbraLmtpBackend.java:185)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliver(ZimbraLmtpBackend.java:109)
        at com.zimbra.cs.lmtpserver.LmtpHandler.doDATA(LmtpHandler.java:420)
        at com.zimbra.cs.lmtpserver.LmtpHandler.processCommand(LmtpHandler.java:197)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:595)
Caused by: com.zimbra.cs.convert.ConversionException: Error loading shared library kvfilter.
        at com.zimbra.cs.convert.TransformationClient.extract(TransformationClient.java:166)
        at com.zimbra.cs.convert.SocketTransformationStub.doExtract(SocketTransformationStub.java:95)
        at com.zimbra.cs.convert.SocketTransformationStub.extract(SocketTransformationStub.java:151)
        at com.zimbra.cs.mime.handler.DefaultHandler.getContentImpl(DefaultHandler.java:85)
        ... 12 more
2006-02-14 13:15:45,834 WARN  [Convertd-2] [] VerityEngine - Verity filter error
com.verity.api.filter.FilterException: Error loading shared library kvfilter.
        at com.verity.api.filter.Filter.getStreamDocFormatInfo(Native Method)
        at com.verity.api.filter.Filter.getDocFormatInfo(Unknown Source)
        at com.zimbra.cs.convertd.VerityEngine.extract(VerityEngine.java:233)
        at com.zimbra.cs.convertd.TransformationHandler.doTextExtraction(TransformationHandler.java:194)
        at com.zimbra.cs.convertd.TransformationHandler.processCommand(TransformationHandler.java:102)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:595)
2006-02-14 13:15:45,874 WARN  [LmtpServer-17] [] ParsedMessage - Parse error on MIME part 3.1 (text/plain, Message-ID: <200602141315.k1EDFjVu016969@xxxxxxxx>)
com.zimbra.cs.mime.MimeHandlerException: cannot extract text
        at com.zimbra.cs.mime.handler.DefaultHandler.getContentImpl(DefaultHandler.java:92)
        at com.zimbra.cs.mime.MimeHandler.getContent(MimeHandler.java:254)
        at com.zimbra.cs.mime.ParsedMessage.analyzePart(ParsedMessage.java:492)
        at com.zimbra.cs.mime.ParsedMessage.analyzeMessage(ParsedMessage.java:410)
        at com.zimbra.cs.mime.ParsedMessage.analyze(ParsedMessage.java:155)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliverMessageToLocalMailboxes(ZimbraLmtpBackend.java:185)
        at com.zimbra.cs.lmtpserver.ZimbraLmtpBackend.deliver(ZimbraLmtpBackend.java:109)
        at com.zimbra.cs.lmtpserver.LmtpHandler.doDATA(LmtpHandler.java:420)
        at com.zimbra.cs.lmtpserver.LmtpHandler.processCommand(LmtpHandler.java:197)
        at com.zimbra.cs.tcpserver.ProtocolHandler.processConnection(ProtocolHandler.java:231)
        at com.zimbra.cs.tcpserver.ProtocolHandler.run(ProtocolHandler.java:198)
        at EDU.oswego.cs.dl.util.concurrent.PooledExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Thread.java:595)
Caused by: com.zimbra.cs.convert.ConversionException: Error loading shared library kvfilter.
        at com.zimbra.cs.convert.TransformationClient.extract(TransformationClient.java:166)
        at com.zimbra.cs.convert.SocketTransformationStub.doExtract(SocketTransformationStub.java:95)
        at com.zimbra.cs.convert.SocketTransformationStub.extract(SocketTransformationStub.java:151)
        at com.zimbra.cs.mime.handler.DefaultHandler.getContentImpl(DefaultHandler.java:85)
        ... 12 more
2006-02-14 13:15:45,875 WARN  [LmtpServer-17] [] ParsedMessage - Message had parse errors in 3 parts (Message-Id: <200602141315.k1EDFjVu016969@xxxxxx>, Subject: Returned mail: see transcript for details)
2006-02-14 13:15:45,881 INFO  [LmtpServer-17] [name=admin@xxxxxxxxx;] FileBlobStore - Stored size=3204 wrote=3204 path=/opt/zimbra/store/incoming/1139922018761-155.msg vol=1 digest=0507aKCeLRoUQG0reTv+f0VJMTI=
2006-02-14 13:15:45,881 INFO  [LmtpServer-17] [name=admin@xxxxxxxxx;] FileBlobStore - Renamed id=261 mbox=1 oldpath=/opt/zimbra/store/incoming/1139922018761-155.msg newpath=/opt/zimbra/store/0/1/msg/0/261-101.msg
2006-02-14 13:15:45,898 INFO  [LmtpServer-17] [name=admin@xxxxxxxx;] mailbox - Added message id=261 digest=0507aKCeLRoUQG0reTv+f0VJMTI= mailbox=1 rcpt=admin@xxxxxxxxxx
2006-02-14 13:15:58,015 INFO  [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)

[KevinH]

Network Edition? Seems your missing a couple pkgs:

HTML Attachment Translation

Add the 2 pkgs ref'd in comment #4 and restart zimbra.

[billybofh]

Ah-ha - that fixed it :-) Thanks :-)


Billy.