Manage Mail Queues problem

[mmadavejones]

Hi all,

I'm having a problem on the manage mail queues screen. It is showing me that I have a message in the deferred queue, but I'm not able to see any details about that message. Also the date Updated displays as 12/31/1969. I've attached a screenshot in hopes that this will provide a clue to someone as to what may be causing this.

Thanks,

Dave

[jholder]

Hello, and welcome to the Zimbra Forums!

Would you mind updating your userprofile with your OS and Zimbra version?

Also, I have to ask the obvious question:
Is your clock set right?

[mmadavejones]

Ok, updated my profile, and here is the output from the date command on my box. OS is RHEL4.

[zimbra@mail2 ~]$ date
Mon Jul 16 13:20:20 EDT 2007

[Runner]

I have the same problem. In my logwatch from the system I can see that I have a undelivrable message in the queue but when I go to the Admin UI I get the same things as your screen.

[jholder]

Do you see anything in /var/log/zimbra.log?

Specifically look for deferred messages.

[Runner]

Do you see anything in /var/log/zimbra.log?

Specifically look for deferred messages.

var/log/zimbra.log

Oct 12 04:02:11 tycho amavis[20507]: (20507-01) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071012T040211-20507: -> SIZE=11271 Received: from tycho.mydomain.com ([127.0.0.1]) by localhost (tycho.mydomain.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for ; Fri, 12 Oct 2007 04:02:11 -0400 (EDT)
Oct 12 04:02:12 tycho amavis[20507]: (20507-01) Checking: REinD86MAdjz [127.0.0.1] ->
Oct 12 04:02:12 tycho clamd[20560]: SelfCheck: Database status OK.
Oct 12 04:02:14 tycho postfix/smtpd[14901]: connect from localhost[127.0.0.1]
Oct 12 04:02:14 tycho postfix/smtpd[14901]: 29FD31EC4015: client=localhost[127.0.0.1]
Oct 12 04:02:14 tycho postfix/cleanup[14593]: 29FD31EC4015: message-id=<200710120802.l9C827ha014324@tycho.mydomain.com>
Oct 12 04:02:14 tycho postfix/qmgr[20678]: 29FD31EC4015: from=, size=11906, nrcpt=1 (queue active)
Oct 12 04:02:14 tycho amavis[20507]: (20507-01) FWD via SMTP: -> ,BODY=7BIT 250 2.6.0 Ok, id=20507-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 29FD31EC4015
Oct 12 04:02:14 tycho postfix/smtpd[14901]: disconnect from localhost[127.0.0.1]
Oct 12 04:02:14 tycho amavis[20507]: (20507-01) Passed CLEAN, LOCAL [127.0.0.1] [127.0.0.1] -> , Message-ID: <200710120802.l9C827ha014324@tycho.mydomain.com>, mail_id: REinD86MAdjz, Hits: -4.398, size: 11247, queued_as: 29FD31EC4015, 2314 ms
Oct 12 04:02:14 tycho postfix/smtp[14594]: CEBBE1EC4013: to=, orig_to=, relay=127.0.0.1[127.0.0.1]:10024, delay=2.4, delays=0.05/0.01/0.01/2.3, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 29FD31EC4015)
Oct 12 04:02:14 tycho postfix/qmgr[20678]: CEBBE1EC4013: removed
Oct 12 04:02:14 tycho postfix/lmtp[14919]: 29FD31EC4015: to=, relay=tycho.mydomain.com[192.168.1.49]:7025, delay=0.35, delays=0.02/0.03/0/0.29, dsn=2.1.5, status=sent (250 2.1.5 OK)
Oct 12 04:02:14 tycho postfix/qmgr[20678]: 29FD31EC4015: removed
Oct 12 04:04:03 tycho zmmailboxdmgr[15392]: status requested
Oct 12 04:04:03 tycho zmmailboxdmgr[15392]: status OK
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: antispam: Running
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: antivirus: Running
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: ldap: Running
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: logger: Running
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: mailbox: Running
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: mta: Running
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: snmp: Running
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: spell: Running
Oct 12 04:04:03 tycho zimbramon[15219]: 15219:info: 2007-10-12 04:04:01, STATUS: tycho.mydomain.com: stats: Running
Oct 12 04:06:03 tycho zmmailboxdmgr[15795]: status requested
Oct 12 04:06:03 tycho zmmailboxdmgr[15795]: status OK

But my logwatch from the system shows a message in te postfix queue which is undeliverable, host refuses connection.

--------------------- postfix Begin ------------------------



69385 bytes transferred
24 messages sent
4 messages removed from queue

SASL Authenticated messages from: 1 Host(s), 1 Time(s)

TLS Connections from: 1 Host(s), 1 Time(s)

**Unmatched Entries**

9DACF1EC4014: to=, relay=none, delay=134827, delays=134827/0.01/0/0, dsn=4.4.1, status=deferred (connect to harley.mydomain.com[192.168.1.47]: Connection refused)
7BCE71EC4013: to=, relay=127.0.0.1[127.0.0.1]:10024, delay=0.68, delays=0.09/0.01/0.01/0.57, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 1F4511EC4015)
1F4511EC4015: to=, relay=tycho.mydomain.com[192.168.1.49]:7025, delay=0.1, delays=0.01/0.01/0/0.07, dsn=2.1.5, status=sent (250 2.1.5 OK)
9DACF1EC4014: to=, relay=none, delay=139027, delays=139027/0.02/0/0, dsn=4.4.1, status=deferred (connect to harley.mydomain.com[192.168.1.47]: Connection refused)

zimbra => root
------------------------------------------------------------------------------
/opt/zimbra/libexec/zmmailboxdmgr - 725 Times.
/opt/zimbra/libexec/zmmtastatus - 722 Times.
/opt/zimbra/libexec/zmqstat
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h queue_directory
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h hash_queue_depth
/opt/zimbra/libexec/zmqstat
/opt/zimbra/libexec/zmqstat deferred
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h queue_directory
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h hash_queue_depth
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h queue_directory
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h hash_queue_depth
/opt/zimbra/postfix-2.4.3.3/sbin/postalias /etc/aliases
/opt/zimbra/postfix-2.4.3.3/sbin/postfix stop
/opt/zimbra/libexec/zmslapd -l LOCAL0 -4 -u zimbra -h \
ldap://tycho.mydomain.com:389 -f /opt/zimbra/conf/slapd.conf
/opt/zimbra/postfix-2.4.3.3/sbin/postalias /etc/aliases
/opt/zimbra/postfix-2.4.3.3/sbin/postfix start norewrite
/opt/zimbra/libexec/zmqstat
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h queue_directory
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h hash_queue_depth
/opt/zimbra/libexec/zmqstat
/opt/zimbra/libexec/zmqstat deferred
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h queue_directory
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h hash_queue_depth
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h queue_directory
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h hash_queue_depth
/opt/zimbra/libexec/zmqstat
/opt/zimbra/libexec/zmqstat deferred
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h queue_directory
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h queue_directory
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h hash_queue_depth
/opt/zimbra/postfix-2.4.3.3/sbin/postconf -h hash_queue_depth

[dwmtractor]

9DACF1EC4014: to=, relay=none, delay=134827, delays=134827/0.01/0/0, dsn=4.4.1, status=deferred (connect to harley.mydomain.com[192.168.1.47]: Connection refused)

Am I correct in guessing that harley.mydomain.com is your zimbra mailserver, or is it another SMTP server that you're relaying thru?

I wonder if you don't have your "allowed networks" for postfix set up correctly.

[Acidcrawl]

I'm having the same problem with the Manage Mail Queue. In /opt/zimbra/log/mailbox.log I see the following error:

2007-10-12 10:40:53,110 ERROR [{RemoteManager: mail.xxx.net->zimbra@mail.xxx.net:22}-zmqstat deferred] [] rmgmt - error when scanning mail queue deferred on ho
st mail.xxx.net
java.io.IOException: Cannot create directory: /opt/zimbra/jetty-6.1.5/work/zimbra/f408aa89-52ca-492c-adb5-68de54a79774-deferred
at org.apache.lucene.store.FSDirectory.getDirectory(F SDirectory.java:175)
at org.apache.lucene.store.FSDirectory.getDirectory(F SDirectory.java:157)
at org.apache.lucene.index.IndexWriter.init(IndexWrit er.java:352)
at org.apache.lucene.index.IndexWriter.(IndexWriter.java:256)
at com.zimbra.cs.rmgmt.RemoteMailQueue.clearIndexInte rnal(RemoteMailQueue.java:223)
at com.zimbra.cs.rmgmt.RemoteMailQueue$QueueHandler.r ead(RemoteMailQueue.java:178)
at com.zimbra.cs.rmgmt.RemoteManager.executeBackgroun d0(RemoteManager.java:98)
at com.zimbra.cs.rmgmt.RemoteManager.access$000(Remot eManager.java:35)
at com.zimbra.cs.rmgmt.RemoteManager$1.run(RemoteMana ger.java:111)
at java.lang.Thread.run(Thread.java:619)

So I went in and made the /opt/zimbra/jetty-6.1.5/work/zimbra folder world writable.

I'm now getting the following error:

2007-10-12 10:47:44,658 ERROR [{RemoteManager: mail.xxx.net->zimbra@mail.xxx.net:22}-zmqstat deferred] [] rmgmt - error when scanning mail queue deferred on ho
st mail.xxx.net
java.lang.NumberFormatException: For input string: "1192207653 725246"
at java.lang.NumberFormatException.forInputString(Num berFormatException.java:48)
at java.lang.Long.parseLong(Long.java:412)
at java.lang.Long.parseLong(Long.java:461)
at com.zimbra.cs.rmgmt.RemoteMailQueue$QueueItemVisit or.handle(RemoteMailQueue.java:126)
at com.zimbra.cs.rmgmt.RemoteResultParser.parse(Remot eResultParser.java:55)
at com.zimbra.cs.rmgmt.RemoteResultParser.parse(Remot eResultParser.java:68)
at com.zimbra.cs.rmgmt.RemoteMailQueue$QueueHandler.r ead(RemoteMailQueue.java:181)
at com.zimbra.cs.rmgmt.RemoteManager.executeBackgroun d0(RemoteManager.java:98)
at com.zimbra.cs.rmgmt.RemoteManager.access$000(Remot eManager.java:35)
at com.zimbra.cs.rmgmt.RemoteManager$1.run(RemoteMana ger.java:111)
at java.lang.Thread.run(Thread.java:619)

I'm completely confused as to why this is happening, is there any chance that my version of the JDK installed is wrong.

OS: FC 7
Zimbra Version: 5.0.0_RC1_1538

[Runner]

Am I correct in guessing that harley.mydomain.com is your zimbra mailserver, or is it another SMTP server that you're relaying thru?

I wonder if you don't have your "allowed networks" for postfix set up correctly.

It was a Mindquarry server that had its own SMTP server on it when I brought it up. I disable it and refer it to my Zimbra server tycho but the Postfix is holding the message in the queue and when I go to the Admin UI I can not flush it and I can not find anything that tells me how to dump the message.

[Runner]

from my /opt/zimbta/log/mailbox.log

2007-10-11 13:51:37,472 ERROR [{RemoteManager: tycho.mydomain.com->zimbra@tycho.mydomain.com:22}-zmqstat deferred] [] rmgmt - error when scanning mail queue deferred on host tycho.mydomain.com
java.lang.NumberFormatException: For input string: "1191942590 643858"
at java.lang.NumberFormatException.forInputString(Num berFormatException.java:48)
at java.lang.Long.parseLong(Long.java:412)
at java.lang.Long.parseLong(Long.java:461)
at com.zimbra.cs.rmgmt.RemoteMailQueue$QueueItemVisit or.handle(RemoteMailQueue.java:126)
at com.zimbra.cs.rmgmt.RemoteResultParser.parse(Remot eResultParser.java:55)
at com.zimbra.cs.rmgmt.RemoteResultParser.parse(Remot eResultParser.java:68)
at com.zimbra.cs.rmgmt.RemoteMailQueue$QueueHandler.r ead(RemoteMailQueue.java:181)
at com.zimbra.cs.rmgmt.RemoteManager.executeBackgroun d0(RemoteManager.java:98)
at com.zimbra.cs.rmgmt.RemoteManager.access$000(Remot eManager.java:35)
at com.zimbra.cs.rmgmt.RemoteManager$1.run(RemoteMana ger.java:111)
at java.lang.Thread.run(Thread.java:619)
2007-10-11 13:51:43,682 ERROR [{RemoteManager: tycho.mydomian.com->zimbra@tycho.mydomain.com:22}-zmqstat deferred] [] rmgmt - error when scanning mail queue deferred on host tycho.mydomain.com
java.lang.NumberFormatException: For input string: "1191942590 643858"
at java.lang.NumberFormatException.forInputString(Num berFormatException.java:48)