[SOLVED] Postfix unavailable - queue down

[pmona]

I have this same problem. Last night my server stopped sending/receiving mail. I have gone through this post and many other and have not yet found an answer for this problem. I could not find anything in the logs which indicated a problem. Below is output from the commands requested in this and other posts.



[zimbra@zimbra ~]$ cat /etc/hosts
# Do not remove the following line, or various programs
# that require network functionality will fail.
127.0.0.1 localhost.localdomain localhost
172.20.1.4 zimbra.monafamily.com zimbra
[zimbra@zimbra ~]$ cat /etc/resolv.conf
search monafamily.com
nameserver 172.20.1.4
#nameserver 172.20.1.1
#nameserver 172.20.1.2
[zimbra@zimbra ~]$ dig monafamily.com mx

; <<>> DiG 9.3.6-P1-RedHat-9.3.6-4.P1.el5_4.1 <<>> monafamily.com mx
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 26221
;; flags: qr aa rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 2, ADDITIONAL: 2

;; QUESTION SECTION:
;monafamily.com. IN MX

;; ANSWER SECTION:
monafamily.com. 86400 IN MX 10 zimbra.monafamily.com.

;; AUTHORITY SECTION:
monafamily.com. 86400 IN NS ns1.commpartners.us.
monafamily.com. 86400 IN NS ns1.monafamily.com.

;; ADDITIONAL SECTION:
zimbra.monafamily.com. 86400 IN A 172.20.1.4
ns1.commpartners.us. 42686 IN A 204.14.36.99

;; Query time: 1 msec
;; SERVER: 172.20.1.4#53(172.20.1.4)
;; WHEN: Wed Jan 20 10:07:52 2010
;; MSG SIZE rcvd: 138

[zimbra@zimbra ~]$ dig monafamily.com any

; <<>> DiG 9.3.6-P1-RedHat-9.3.6-4.P1.el5_4.1 <<>> monafamily.com any
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 7616
;; flags: qr aa rd ra; QUERY: 1, ANSWER: 5, AUTHORITY: 0, ADDITIONAL: 2

;; QUESTION SECTION:
;monafamily.com. IN ANY

;; ANSWER SECTION:
monafamily.com. 86400 IN SOA monafamily.com. webmaster.monafamily.com. 2009123100 3600 600 604800 3600
monafamily.com. 86400 IN NS ns1.monafamily.com.
monafamily.com. 86400 IN NS ns1.commpartners.us.
monafamily.com. 86400 IN MX 10 zimbra.monafamily.com.
monafamily.com. 86400 IN A 172.20.1.4

;; ADDITIONAL SECTION:
ns1.commpartners.us. 42680 IN A 204.14.36.99
zimbra.monafamily.com. 86400 IN A 172.20.1.4

;; Query time: 1 msec
;; SERVER: 172.20.1.4#53(172.20.1.4)
;; WHEN: Wed Jan 20 10:07:58 2010
;; MSG SIZE rcvd: 200

[zimbra@zimbra ~]$ host `hostname`
zimbra.monafamily.com has address 172.20.1.4
[zimbra@zimbra ~]$
[zimbra@zimbra ~]$ zmcontrol -v


Release 6.0.4_GA_2038.RHEL5_20091214191028 CentOS5 FOSS edition.
[zimbra@zimbra ~]$ postfix check
postfix/postfix-script: warning: not owned by root: /opt/zimbra/data/postfix/spool
postfix/postfix-script: warning: not owned by root: /opt/zimbra/postfix-2.6.5.2z/conf/main.cf
postfix/postfix-script: warning: not owned by root: /opt/zimbra/postfix-2.6.5.2z/conf/master.cf
postfix/postfix-script: warning: not owned by root: /opt/zimbra/postfix-2.6.5.2z/conf/master.cf.in
[zimbra@zimbra ~]$
[zimbra@zimbra ~]$ ps aux | grep zimbra
avahi 2431 0.0 0.0 2600 1376 ? Ss 09:59 0:00 avahi-daemon: running [zimbra.local]
zimbra 2547 0.2 1.4 137744 30240 ? Ssl 09:59 0:01 /opt/zimbra/openldap/sbin/slapd -l LOCAL0 -4 -u zimbra -h ldap://zimbra.monafamily.com:389 ldapi:/// -F /opt/zimbra/data/ldap/config
zimbra 4450 0.0 0.2 8792 5896 ? Ss 10:00 0:00 /opt/zimbra/libexec/logswatch --config-file=/opt/zimbra/conf/logswatchrc --use-cpan-file-tail --pid-file=/opt/zimbra/log/logswatch.pid --daemon --script-dir=/opt/zimbra/data/tmp -t /var/log/zimbra-stats.log
zimbra 4451 0.0 0.2 18792 5804 ? S 10:00 0:00 /usr/bin/perl /opt/zimbra/libexec/zmlogger
zimbra 4568 0.0 0.1 7800 3928 ? S 10:00 0:00 zmlogger: zmrrdfetch: server
zimbra 4577 0.1 0.3 10068 7420 ? S 10:00 0:00 /usr/bin/perl /opt/zimbra/libexec/zmmtaconfig
zimbra 4594 0.0 0.0 2560 1112 ? S 10:00 0:00 /bin/sh /opt/zimbra/mysql/bin/mysqld_safe --defaults-file=/opt/zimbra/conf/my.cnf --external-locking --log-error=/opt/zimbra/log/mysql_error.log --ledir=/opt/zimbra/mysql/libexec
zimbra 4734 0.4 4.0 287976 83852 ? Sl 10:00 0:02 /opt/zimbra/mysql/libexec/mysqld --defaults-file=/opt/zimbra/conf/my.cnf --basedir=/opt/zimbra/mysql --datadir=/opt/zimbra/db/data --pid-file=/opt/zimbra/db/mysql.pid --skip-external-locking --port=7306 --socket=/opt/zimbra/db/mysql.sock --external-locking
root 6822 0.0 0.0 1660 388 ? Ss 10:02 0:00 /opt/zimbra/libexec/zmmailboxdmgr start -server -Djava.awt.headless=true -XX:+UseConcMarkSweepGC -XX:NewRatio=2 -XX:PermSize=128m -XX:MaxPermSize=128m -XX:SoftRefLRUPolicyMSPerMB=1 -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+PrintGCApplicationStoppedTime -Xss256k -Xms810m -Xmx810m -Xmn202m
zimbra 6823 29.3 16.6 1154860 346232 ? Sl 10:02 2:12 /opt/zimbra/java/bin/java -server -Djava.awt.headless=true -XX:+UseConcMarkSweepGC -XX:NewRatio=2 -XX:PermSize=128m -XX:MaxPermSize=128m -XX:SoftRefLRUPolicyMSPerMB=1 -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+PrintGCApplicationStoppedTime -Xss256k -Xms810m -Xmx810m -Xmn202m -Djava.io.tmpdir=/opt/zimbra/mailboxd/work -Djava.library.path=/opt/zimbra/lib -Djava.endorsed.dirs=/opt/zimbra/mailboxd/common/endorsed -Dzimbra.config=/opt/zimbra/conf/localconfig.xml -Djetty.home=/opt/zimbra/mailboxd -DSTART=/opt/zimbra/mailboxd/etc/start.config -jar /opt/zimbra/mailboxd/start.jar /opt/zimbra/mailboxd/etc/jetty.properties /opt/zimbra/mailboxd/etc/jetty-setuid.xml /opt/zimbra/mailboxd/etc/jetty.xml
zimbra 7024 0.3 2.5 62088 52704 ? Ss 10:02 0:01 amavisd (master)
zimbra 7071 0.0 2.4 62088 51328 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7072 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7073 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7074 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7075 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7076 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7079 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7080 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7081 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7082 0.0 2.4 62088 51300 ? S 10:02 0:00 amavisd (virgin child)
zimbra 7118 0.0 0.0 3116 1356 ? Ss 10:02 0:00 /opt/zimbra/clamav/bin/freshclam --config-file=/opt/zimbra/conf/freshclam.conf --quiet --daemon --checks=12
zimbra 7432 0.0 4.2 100536 87932 ? Ssl 10:02 0:00 /opt/zimbra/clamav/sbin/clamd --config-file=/opt/zimbra/conf/clamd.conf
zimbra 7456 0.0 0.2 6748 4828 ? S 10:02 0:00 /usr/bin/perl /opt/zimbra/libexec/swatch --config-file=/opt/zimbra/conf/swatchrc --use-cpan-file-tail --script-dir=/opt/zimbra/data/tmp -t /var/log/zimbra.log
zimbra 7476 0.0 0.3 8796 6724 ? S 10:02 0:00 /usr/bin/perl /opt/zimbra/data/tmp/.swatch_script.7456
zimbra 7514 0.0 0.3 12304 6684 ? Ss 10:02 0:00 /opt/zimbra/httpd-2.2.14/bin/httpd -k start -f /opt/zimbra/conf/httpd.conf
zimbra 7529 0.0 0.3 14796 7716 ? S 10:02 0:00 /opt/zimbra/httpd-2.2.14/bin/httpd -k start -f /opt/zimbra/conf/httpd.conf
zimbra 7530 0.0 0.2 12304 6056 ? S 10:02 0:00 /opt/zimbra/httpd-2.2.14/bin/httpd -k start -f /opt/zimbra/conf/httpd.conf
zimbra 7531 0.0 0.2 12304 6056 ? S 10:02 0:00 /opt/zimbra/httpd-2.2.14/bin/httpd -k start -f /opt/zimbra/conf/httpd.conf
zimbra 7532 0.0 0.2 12304 6056 ? S 10:02 0:00 /opt/zimbra/httpd-2.2.14/bin/httpd -k start -f /opt/zimbra/conf/httpd.conf
zimbra 7533 0.0 0.2 12304 6056 ? S 10:02 0:00 /opt/zimbra/httpd-2.2.14/bin/httpd -k start -f /opt/zimbra/conf/httpd.conf
zimbra 7647 0.0 0.0 7436 804 ? Ss 10:02 0:00 /opt/zimbra/cyrus-sasl/sbin/saslauthd -r -a zimbra
zimbra 7651 0.0 0.0 7436 552 ? S 10:02 0:00 /opt/zimbra/cyrus-sasl/sbin/saslauthd -r -a zimbra
zimbra 7652 0.0 0.0 7436 540 ? S 10:02 0:00 /opt/zimbra/cyrus-sasl/sbin/saslauthd -r -a zimbra
zimbra 7653 0.0 0.0 7436 540 ? S 10:02 0:00 /opt/zimbra/cyrus-sasl/sbin/saslauthd -r -a zimbra
zimbra 7654 0.0 0.0 7436 540 ? S 10:02 0:00 /opt/zimbra/cyrus-sasl/sbin/saslauthd -r -a zimbra
zimbra 7990 0.0 0.2 6684 4792 ? S 10:03 0:00 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-proc
zimbra 7992 0.0 0.2 6432 4580 ? S 10:03 0:00 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-cpu
zimbra 7994 0.0 0.2 6432 4584 ? S 10:03 0:00 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-vm
zimbra 7996 0.0 0.2 6432 4652 ? S 10:03 0:00 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-io -x
zimbra 7998 0.0 0.2 6432 4452 ? S 10:03 0:00 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-df
zimbra 8000 0.0 0.2 6432 4584 ? S 10:03 0:00 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-io
zimbra 8002 0.0 0.2 6428 4516 ? S 10:03 0:00 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-fd
zimbra 8004 0.4 0.2 7132 5180 ? S 10:03 0:01 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-allprocs
zimbra 8006 0.0 0.2 6568 4696 ? S 10:03 0:00 /usr/bin/perl -w? /opt/zimbra/libexec/zmstat-mysql
zimbra 8109 0.0 0.2 6432 4556 ? S 10:03 0:00 /usr/bin/perl -w /opt/zimbra/libexec/zmstat-mtaqueue
zimbra 8698 0.0 0.0 1688 456 ? S 10:03 0:00 /usr/bin/vmstat -n -S K 30
zimbra 8699 0.0 0.0 1700 612 ? S 10:03 0:00 /usr/bin/iostat -d -k 30
zimbra 8704 0.0 0.0 1700 644 ? S 10:03 0:00 /usr/bin/iostat -d -k -x 30
root 9822 0.0 0.0 4920 1268 pts/0 S 10:04 0:00 su - zimbra
zimbra 9823 0.0 0.0 4660 1504 pts/0 S 10:04 0:00 -bash
zimbra 11508 0.0 0.2 12304 6056 ? S 10:06 0:00 /opt/zimbra/httpd-2.2.14/bin/httpd -k start -f /opt/zimbra/conf/httpd.conf
zimbra 13815 3.3 0.7 411048 15804 ? Sl 10:09 0:00 /opt/zimbra/java/bin/java -XX:ErrorFile=/opt/zimbra/log -client -Xmx256m -Dzimbra.home=/opt/zimbra -Djava.library.path=/opt/zimbra/lib -Djava.ext.dirs=/opt/zimbra/java/jre/lib/ext:/opt/zimbra/lib/jars:/opt/zimbra/lib/ext-common:/opt/zimbra/lib/ext/clamscanner com.zimbra.cs.account.ProvUtil -l gs zimbra.monafamily.com
zimbra 13987 0.0 0.0 2184 820 pts/0 R+ 10:09 0:00 ps aux
zimbra 13988 0.0 0.0 1844 476 pts/0 R+ 10:09 0:00 grep zimbra
[zimbra@zimbra ~]$
[zimbra@zimbra ~]$ zmmtactl stop
Stopping zmmtaconfig...done.
postfix/postfix-script: fatal: the Postfix mail system is not running
Stopping saslauthd...done.
[zimbra@zimbra ~]$ zmmtactl start
Rewriting configuration files...done.
Starting zmmtaconfig...done.
Starting saslauthd...done.
[zimbra@zimbra ~]$

[pmona]

I should have also noted that I tried to reinstall using install -s but there was no change.

When attempting to send mail, I get the following error:

An unknown error (mail.TRY_AGAIN) has occurred.msg: try again: Unable to connect to the MTA
code: mail.TRY_AGAIN
method: SendMsgRequest
detail: soap:Receiver
trace: btpool0-12://monafamily.com:8010/service/soap/SendMsgRequest:1264025039274:f029875c8986b6e7
request: Body: { SendMsgRequest: { _jsns: "urn:zimbraMail", m: { e: { 0: { a: "pmona@commpartners.us", t: "t" }, 1: { a: "pmona@monafamily.com", p: "Paul Mona", t: "f" } }, id: "928323", idnt: "028db9fc-a9d8-4c42-a900-3e487498ddd0", irt: { _content: "<25808596.32.1263962479624.JavaMail.root@zimb ra>" }, mp: { 0: { content: { _content: "test" }, ct: "text/plain" } }, su: { _content: "test" } }, suid: 1264024901067 } }, Header: { context: { _jsns: "urn:zimbra", account: { _content: "pmona@monafamily.com", by: "name" }, authToken: "(removed)", session: { _content: 11, id: 11 }, userAgent: { name: "ZimbraWebClient - FF3.0 (Win)", version: "6.0.4_GA_2038.RHEL5" } } }

[uxbod]

When you restarted the MTA please check /var/log/zimbra.log for any error messages and post them please.

[pmona]

I did a stop and start while tailing the zimbra log.


[root@zimbra ~]# su - zimbra
[zimbra@zimbra ~]$ zmcontrol stop
Host zimbra.monafamily.com
Stopping stats...Done.
Stopping mta...Done.
Stopping spell...Done.
Stopping snmp...Done.
Stopping archiving...Done.
Stopping antivirus...Done.
Stopping antispam...Done.
Stopping imapproxy...Done.
Stopping memcached...Done.
Stopping mailbox...Done.
Stopping logger...Done.
Stopping ldap...Done.
[zimbra@zimbra ~]$ zmcontrol start
Host zimbra.monafamily.com
Starting ldap...Done.
Starting logger...Done.
Starting mailbox...Done.
Starting antispam...Done.
Starting antivirus...Done.
Starting snmp...Done.
Starting spell...Done.
Starting mta...Done.
Starting stats...Done.
[zimbra@zimbra ~]$ zmcontrol status
Host zimbra.monafamily.com
antispam Running
antivirus Running
ldap Running
logger Running
mailbox Running
mta Running
snmp Running
spell Running
stats Running
[zimbra@zimbra ~]$


Jan 20 17:28:26 zimbra zimbramon[21228]: 21228:info: Stopping services initiated by zmcontrol
Jan 20 17:28:26 zimbra zimbramon[21228]: 21228:info: Stopping stats via zmcontrol
Jan 20 17:28:26 zimbra zimbramon[21228]: 21228:info: Stopping mta via zmcontrol
Jan 20 17:28:28 zimbra postfix/postfix-script[21317]: fatal: the Postfix mail system is not running
Jan 20 17:28:28 zimbra saslauthd[31836]: server_exit : master exited: 31836
Jan 20 17:28:29 zimbra zimbramon[21228]: 21228:info: Stopping spell via zmcontrol
Jan 20 17:28:29 zimbra zimbramon[21228]: 21228:info: Stopping snmp via zmcontrol
Jan 20 17:28:30 zimbra zimbramon[21228]: 21228:info: Stopping archiving via zmcontrol
Jan 20 17:28:30 zimbra amavis[22797]: Net::Server: 2010/01/20-17:28:30 Server closing!
Jan 20 17:28:36 zimbra zimbramon[21228]: 21228:info: Stopping antivirus via zmcontrol
Jan 20 17:28:36 zimbra clamd[22933]: Pid file removed.
Jan 20 17:28:36 zimbra clamd[22933]: --- Stopped at Wed Jan 20 17:28:36 2010
Jan 20 17:28:38 zimbra zimbramon[21228]: 21228:info: Stopping antispam via zmcontrol
Jan 20 17:28:39 zimbra zimbramon[21228]: 21228:info: Stopping imapproxy via zmcontrol
Jan 20 17:28:39 zimbra zimbramon[21228]: 21228:info: Stopping memcached via zmcontrol
Jan 20 17:28:39 zimbra zimbramon[21228]: 21228:info: Stopping mailbox via zmcontrol
Jan 20 17:28:39 zimbra zmmailboxdmgr[21527]: status requested
Jan 20 17:28:39 zimbra zmmailboxdmgr[21527]: status OK
Jan 20 17:28:41 zimbra zmmailboxdmgr[21532]: stop requested
Jan 20 17:28:41 zimbra zmmailboxdmgr[21532]: waiting for manager process 22721 to die
Jan 20 17:28:41 zimbra zmmailboxdmgr[22721]: shutdown requested, sending TERM signal to 22722
Jan 20 17:28:44 zimbra zmmailboxdmgr[22721]: mailboxd/JVM process exited (waitpid expected 22722 got 22722)
Jan 20 17:28:44 zimbra zmmailboxdmgr[22721]: manager woke up from wait on mailboxd/JVM with pid 22722
Jan 20 17:28:45 zimbra zmmailboxdmgr[21532]: manager process 22721 died, shutdown completed
Jan 20 17:28:49 zimbra zimbramon[21228]: 21228:info: Stopping convertd via zmcontrol
Jan 20 17:28:49 zimbra zimbramon[21228]: 21228:info: Stopping logger via zmcontrol
Jan 20 17:28:51 zimbra zimbramon[21228]: 21228:info: Stopping ldap via zmcontrol
Jan 20 17:28:51 zimbra slapd[18544]: daemon: shutdown requested and initiated.
Jan 20 17:28:51 zimbra slapd[18544]: slapd shutdown: waiting for 0 operations/tasks to finish
Jan 20 17:28:51 zimbra slapd[18544]: slapd stopped.
Jan 20 17:29:37 zimbra zimbramon[21591]: 21591:info: Starting services initiated by zmcontrol
Jan 20 17:29:37 zimbra slapd[21629]: @(#) $OpenLDAP: slapd 2.4.20 (Dec 4 2009 09:06:48) $ build@build10.lab.zimbra.com:/home/build/p4/GNR-604/ThirdParty/openldap/openldap-2.4.20.4z/servers/slapd
Jan 20 17:29:37 zimbra slapd[21630]: slapd starting
Jan 20 17:29:48 zimbra zimbramon[21591]: 21591:info: Rewriting configs antispam amavis antivirus amavis webxml mailbox amavis antispam antivirus mta sasl
Jan 21 01:30:02 zimbra postfix/postqueue[22194]: fatal: Queue report unavailable - mail system is down
Jan 20 17:30:02 zimbra zimbramon[22170]: 22170:info: 2010-01-20 17:30:02, QUEUE: 0 0
Jan 20 17:30:02 zimbra sendmail[22195]: o0L1U2nC022195: from=zimbra, size=381, class=0, nrcpts=1, msgid=<201001210130.o0L1U2nC022195@zimbra.monafami ly.com>, relay=zimbra@localhost
Jan 20 17:30:02 zimbra sendmail[22195]: o0L1U2nC022195: to=zimbra, ctladdr=zimbra (501/501), delay=00:00:00, xdelay=00:00:00, mailer=relay, pri=30381, relay=[127.0.0.1] [127.0.0.1], dsn=4.0.0, stat=Deferred: Connection refused by [127.0.0.1]
Jan 20 17:30:21 zimbra zmmailboxdmgr[22910]: status requested
Jan 20 17:30:21 zimbra zmmailboxdmgr[22910]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
Jan 20 17:30:21 zimbra zmmailboxdmgr[22910]: assuming no other instance is running
Jan 20 17:30:21 zimbra zmmailboxdmgr[22910]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Jan 20 17:30:21 zimbra zmmailboxdmgr[22910]: assuming no other instance is running
Jan 20 17:30:21 zimbra zmmailboxdmgr[22910]: no manager process is running
Jan 20 17:30:37 zimbra zimbramon[21591]: 21591:info: Starting logger via zmcontrol
Jan 20 17:30:44 zimbra zimbramon[21591]: 21591:info: Starting mailbox via zmcontrol
Jan 20 17:30:45 zimbra zimbramon[23661]: 23661:info: zmmtaconfig: zmmtaconfig started on zimbra.monafamily.com with loglevel=3 pid=23661
Jan 20 17:32:05 zimbra zmmailboxdmgr[25882]: status requested
Jan 20 17:32:05 zimbra zmmailboxdmgr[25882]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
Jan 20 17:32:05 zimbra zmmailboxdmgr[25882]: assuming no other instance is running
Jan 20 17:32:05 zimbra zmmailboxdmgr[25882]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Jan 20 17:32:05 zimbra zmmailboxdmgr[25882]: assuming no other instance is running
Jan 20 17:32:05 zimbra zmmailboxdmgr[25882]: no manager process is running
Jan 20 17:32:05 zimbra zmmailboxdmgr[25900]: start requested
Jan 20 17:32:05 zimbra zmmailboxdmgr[25900]: checking if another instance of manager is already running
Jan 20 17:32:05 zimbra zmmailboxdmgr[25900]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
Jan 20 17:32:05 zimbra zmmailboxdmgr[25900]: assuming no other instance is running
Jan 20 17:32:05 zimbra zmmailboxdmgr[25900]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Jan 20 17:32:05 zimbra zmmailboxdmgr[25900]: assuming no other instance is running
Jan 20 17:32:05 zimbra zmmailboxdmgr[25901]: wrote manager pid 25901 to /opt/zimbra/log/zmmailboxd_manager.pid
Jan 20 17:32:05 zimbra zmmailboxdmgr[25902]: wrote java pid 25902 to /opt/zimbra/log/zmmailboxd_java.pid
Jan 20 17:32:05 zimbra zmmailboxdmgr[25901]: manager started mailboxd/JVM with pid 25902
Jan 20 17:32:05 zimbra zimbramon[21591]: 21591:info: Starting antispam via zmcontrol
Jan 20 17:32:11 zimbra amavis[25989]: starting. /opt/zimbra/amavisd/sbin/amavisd at zimbra.monafamily.com amavisd-new-2.6.4 (20090625), Unicode aware, LC_ALL="C", LANG="en_US.UTF-8"
Jan 20 17:32:11 zimbra amavis[25989]: user=501, EUID: 501 (501); group=, EGID: 501 502 501 5 4 (501 502 501 5 4)
Jan 20 17:32:11 zimbra amavis[25989]: Perl version 5.008008
Jan 20 17:32:14 zimbra amavis[25989]: SpamControl: init_pre_chroot on SpamAssassin done
Jan 20 17:32:14 zimbra amavis[26055]: Net::Server: Process Backgrounded
Jan 20 17:32:14 zimbra amavis[26055]: Net::Server: 2010/01/20-17:32:14 Amavis (type Net::Server::PreForkSimple) starting! pid(26055)
Jan 20 17:32:14 zimbra zimbramon[21591]: 21591:info: Starting antivirus via zmcontrol
Jan 20 17:32:14 zimbra amavis[26055]: Net::Server: Binding to UNIX socket file /opt/zimbra/data/amavisd/amavisd.sock using SOCK_STREAM
Jan 20 17:32:14 zimbra amavis[26055]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Jan 20 17:32:14 zimbra amavis[26055]: Net::Server: Group Not Defined. Defaulting to EGID '501 502 501 5 4'
Jan 20 17:32:14 zimbra amavis[26055]: Net::Server: User Not Defined. Defaulting to EUID '501'
Jan 20 17:32:14 zimbra amavis[26055]: Module Amavis::Conf 2.207
Jan 20 17:32:14 zimbra amavis[26055]: Module Archive::Zip 1.30
Jan 20 17:32:14 zimbra amavis[26055]: Module Compress::Zlib 1.42
Jan 20 17:32:14 zimbra amavis[26055]: Module Convert::TNEF 0.17
Jan 20 17:32:14 zimbra amavis[26055]: Module Convert::UUlib 1.32
Jan 20 17:32:14 zimbra amavis[26055]: Module Crypt::OpenSSL::RSA 0.25
Jan 20 17:32:14 zimbra amavis[26055]: Module DBD::mysql 4.013
Jan 20 17:32:14 zimbra amavis[26055]: Module DBI 1.609
Jan 20 17:32:14 zimbra amavis[26055]: Module DB_File 1.82
Jan 20 17:32:14 zimbra amavis[26055]: Module Digest::MD5 2.36
Jan 20 17:32:14 zimbra amavis[26055]: Module Digest::SHA 5.47
Jan 20 17:32:14 zimbra amavis[26055]: Module Digest::SHA1 2.12
Jan 20 17:32:14 zimbra amavis[26055]: Module IO::Socket::INET6 2.56
Jan 20 17:32:14 zimbra amavis[26055]: Module MIME::Entity 5.427
Jan 20 17:32:14 zimbra amavis[26055]: Module MIME::Parser 5.427
Jan 20 17:32:14 zimbra amavis[26055]: Module MIME::Tools 5.427
Jan 20 17:32:14 zimbra amavis[26055]: Module Mail:KIM::Signer 0.37
Jan 20 17:32:14 zimbra amavis[26055]: Module Mail:KIM::Verifier 0.37
Jan 20 17:32:14 zimbra amavis[26055]: Module Mail::Header 2.04
Jan 20 17:32:14 zimbra amavis[26055]: Module Mail::Internet 2.04
Jan 20 17:32:14 zimbra amavis[26055]: Module Mail::SpamAssassin 3.002005
Jan 20 17:32:14 zimbra amavis[26055]: Module Net:NS 0.65
Jan 20 17:32:14 zimbra amavis[26055]: Module Net::LDAP 0.39
Jan 20 17:32:14 zimbra amavis[26055]: Module Net::Server 0.97
Jan 20 17:32:14 zimbra amavis[26055]: Module Socket6 0.23
Jan 20 17:32:14 zimbra amavis[26055]: Module Time::HiRes 1.9719
Jan 20 17:32:14 zimbra amavis[26055]: Module URI 1.40
Jan 20 17:32:14 zimbra amavis[26055]: Module Unix::Syslog 1.1
Jan 20 17:32:14 zimbra amavis[26055]: Amavis:B code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: Amavis::Cache code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: SQL base code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: SQL::Log code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: SQL::Quarantine NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: Lookup::SQL code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: Lookup::LDAP code loaded
Jan 20 17:32:14 zimbra amavis[26055]: AM.PDP-in proto code loaded
Jan 20 17:32:14 zimbra amavis[26055]: SMTP-in proto code loaded
Jan 20 17:32:14 zimbra amavis[26055]: Courier proto code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: SMTP-out proto code loaded
Jan 20 17:32:14 zimbra amavis[26055]: Pipe-out proto code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: BSMTP-out proto code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: Local-out proto code loaded
Jan 20 17:32:14 zimbra amavis[26055]: OS_Fingerprint code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: ANTI-VIRUS code loaded
Jan 20 17:32:14 zimbra amavis[26055]: ANTI-SPAM code loaded
Jan 20 17:32:14 zimbra amavis[26055]: ANTI-SPAM-EXT code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: ANTI-SPAM-C code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: ANTI-SPAM-SA code loaded
Jan 20 17:32:14 zimbra amavis[26055]: Unpackers code loaded
Jan 20 17:32:14 zimbra amavis[26055]: DKIM code loaded
Jan 20 17:32:14 zimbra amavis[26055]: Tools code NOT loaded
Jan 20 17:32:14 zimbra amavis[26055]: Found $file at /usr/bin/file
Jan 20 17:32:14 zimbra amavis[26055]: No $altermime, not using it
Jan 20 17:32:14 zimbra amavis[26055]: Internal decoder for .mail
Jan 20 17:32:14 zimbra amavis[26055]: Internal decoder for .asc
Jan 20 17:32:14 zimbra amavis[26055]: Internal decoder for .uue
Jan 20 17:32:14 zimbra amavis[26055]: Internal decoder for .hqx
Jan 20 17:32:14 zimbra amavis[26055]: Internal decoder for .ync
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .F tried: unfreeze, freeze -d, melt, fcat
Jan 20 17:32:14 zimbra amavis[26055]: Found decoder for .Z at /usr/bin/gzip -d
Jan 20 17:32:14 zimbra amavis[26055]: Found decoder for .gz at /usr/bin/gzip -d
Jan 20 17:32:14 zimbra amavis[26055]: Found decoder for .bz2 at /usr/bin/bzip2 -d
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .lzo tried: lzop -d
Jan 20 17:32:14 zimbra amavis[26055]: Found decoder for .rpm at /usr/bin/rpm2cpio
Jan 20 17:32:14 zimbra amavis[26055]: Found decoder for .cpio at /usr/bin/pax
Jan 20 17:32:14 zimbra amavis[26055]: Found decoder for .tar at /usr/bin/pax
Jan 20 17:32:14 zimbra amavis[26055]: Found decoder for .deb at /usr/bin/ar
Jan 20 17:32:14 zimbra amavis[26055]: Internal decoder for .zip
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .7z tried: 7zr, 7za, 7z
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .rar tried: rar, unrar
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .arj tried: arj, unarj
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .arc tried: nomarch, arc
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .zoo tried: zoo, unzoo
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .lha tried: lha
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .cab tried: cabextract
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .tnef tried: tnef
Jan 20 17:32:14 zimbra amavis[26055]: Internal decoder for .tnef
Jan 20 17:32:14 zimbra amavis[26055]: No decoder for .exe tried: rar, unrar; lha; arj, unarj
Jan 20 17:32:14 zimbra amavis[26055]: Using primary internal av scanner code for ClamAV-clamd
Jan 20 17:32:15 zimbra amavis[26055]: initializing Mail::SpamAssassin
Jan 20 17:32:16 zimbra clamd[26137]: clamd daemon 0.95.3-broken-compiler (OS: linux-gnu, ARCH: i386, CPU: i686)
Jan 20 17:32:16 zimbra clamd[26137]: Log file size limited to 20971520 bytes.
Jan 20 17:32:16 zimbra clamd[26137]: Reading databases from /opt/zimbra/data/clamav/db
Jan 20 17:32:16 zimbra clamd[26137]: Not loading PUA signatures.
Jan 20 17:32:20 zimbra amavis[26055]: SpamControl: init_pre_fork on SpamAssassin done
Jan 20 17:32:27 zimbra zmmailboxdmgr[26325]: status requested
Jan 20 17:32:27 zimbra zmmailboxdmgr[26325]: status OK
Jan 20 17:32:31 zimbra clamd[26137]: Loaded 701211 signatures.
Jan 20 17:32:31 zimbra clamd[26137]: TCP: Bound to port 3310
Jan 20 17:32:31 zimbra clamd[26137]: TCP: Setting connection queue length to 15
Jan 20 17:32:31 zimbra clamd[26390]: Limits: Global size limit set to 10240000 bytes.
Jan 20 17:32:31 zimbra clamd[26390]: Limits: File size limit set to 10240000 bytes.
Jan 20 17:32:31 zimbra clamd[26390]: Limits: Recursion level limit set to 16.
Jan 20 17:32:31 zimbra clamd[26390]: Limits: Files limit set to 10000.
Jan 20 17:32:31 zimbra clamd[26390]: Archive support enabled.
Jan 20 17:32:31 zimbra clamd[26390]: Archive: Blocking encrypted archives.
Jan 20 17:32:31 zimbra clamd[26390]: Algorithmic detection enabled.
Jan 20 17:32:31 zimbra clamd[26390]: Portable Executable support enabled.
Jan 20 17:32:31 zimbra clamd[26390]: ELF support enabled.
Jan 20 17:32:31 zimbra clamd[26390]: Mail files support enabled.
Jan 20 17:32:31 zimbra clamd[26390]: OLE2 support enabled.
Jan 20 17:32:31 zimbra clamd[26390]: PDF support enabled.
Jan 20 17:32:31 zimbra clamd[26390]: HTML support enabled.
Jan 20 17:32:31 zimbra clamd[26390]: Self checking every 600 seconds.
Jan 20 17:32:31 zimbra zimbramon[21591]: 21591:info: Starting snmp via zmcontrol
Jan 20 17:32:31 zimbra zimbramon[21591]: 21591:info: Starting spell via zmcontrol
Jan 20 17:32:32 zimbra zimbramon[21591]: 21591:info: Starting mta via zmcontrol
Jan 20 17:32:40 zimbra zimbramon[21591]: 21591:info: Starting stats via zmcontrol
Jan 20 17:32:40 zimbra saslauthd[26519]: detach_tty : master pid is: 26519
Jan 20 17:32:40 zimbra saslauthd[26519]: ipc_init : listening on socket: /opt/zimbra/cyrus-sasl-2.1.23.3z/state/mux

[root@zimbra log]#

[pmona]

Just so it is clear, this server was up and working with out a problem. The last email I sent was at 10:31 AM 1/19/2010. I tried sending another email around 7:50 PM but it kept failing. Though I couldn't send, I was still receiving email. I then stopped and started zimbra. After doing so, I could no longer receive nor send. I'm attaching the log from yesterday.

[uxbod]

Jan 19 19:58:11 zimbra postfix/qmgr[2008]: DBA8F8D8006: from=<>, size=6632, nrcpt=1 (queue active)
Jan 19 19:58:14 zimbra postfix/lmtp[11217]: connect to voodoo.monafamily.com[172.20.1.1]:7025: No route to host
Jan 19 19:58:14 zimbra postfix/lmtp[11217]: DBA8F8D8006: to=<spam.1ps50aa0@monafamily.com>, relay=none, delay=101210, delays=101207/0.22/3/0, dsn=4.4.1, status=deferred (connect to voodoo.monafamily.com[172.20.1.1]:7025: No route to host)
Jan 19 19:58:16 zimbra zmmailboxdmgr[11440]: status requested

Were any changes made as it would appear that a LMTP request is being made to a different server ?

[pmona]

voodoo.monafamily.com 172.20.1.1 is another server on my network, but it hasn't been powered on for more than a month now. I don't know how or why anything would change on the zimbra box to point to that.
Is there some config I should look at and change?

[uxbod]

Okay, looks like you SPAM training account was on the other server! To fix that you will need to do

su - zimbra
zmprov ma  spam.1ps50aa0@monafamily.com zimbraMailTransport lmtp:`zmhostname`:7025

It would be best to check that the spam training account is correct using

su - zimbra
zmprov gacf zimbraSpamIsSpamAccount

[pmona]

I ran the check first to see what it was, but the other command failed.

[zimbra@zimbra ~]$ zmprov gacf zimbraSpamIsSpamAccount
zimbraSpamIsSpamAccount: spam.1ps50aa0@monafamily.com
[zimbra@zimbra ~]$
[zimbra@zimbra ~]$
[zimbra@zimbra ~]$ zmprov ma spam.1ps50aa0@monafamily.com zimbraMailTransport lmtp:`zmhostname`:7025
ERROR: service.FAILURE (system failure: java.lang.NullPointerException)
[zimbra@zimbra ~]$

[uxbod]

Okay it probably does not like the command expansion. As zimbra user execute zmhostname, and then use that in the command instead of `zmhostname`. Would you also post

zmcontrol status
ps aux | grep postfix
netstat -an | grep 25