I have shutdown Zimbra, restarted the server, ran fsck (which reported no error).
The IP 88.88.88.198 is the IP of the Zimbra server.
Let's check the ports 10024 and 10025.
First, telnet to those ports. The port 10024 shows amavisd running:
Code:
root@zoo /opt/zimbra/log> nc 88.88.88.198 10024
220 [88.88.88.198] ESMTP amavisd-new service ready
root@zoo /opt/zimbra/log>
The port 10025 however shows no response and disconnects after a couple of seconds.
Code:
root@zoo /opt/zimbra/log> nc 88.88.88.198 10025
root@zoo /opt/zimbra/log>
Now let's check the open files using those ports.
Port 10025:
Code:
root@zoo /opt/zimbra/log> lsof|grep 10025
master 3215 root 101u IPv4 7908 TCP zoo.zcsdomain.com:10025 (LISTEN)
Port 10024:
Code:
root@zoo /opt/zimbra/log> lsof|grep 10024
amavisd 3172 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3183 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3184 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3185 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3186 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3187 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3188 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3189 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3190 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3191 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
amavisd 3192 zimbra 5u IPv4 7676 TCP zoo.zcsdomain.com:10024 (LISTEN)
Also the netstat output for 10024:
Code:
root@zoo /opt/zimbra/log> netstat -an|grep 10024
tcp 0 0 88.88.88.198:10024 0.0.0.0:* LISTEN
And 10025:
Code:
root@zoo /opt/zimbra/log> netstat -an|grep 10025
tcp 0 0 88.88.88.198:10025 0.0.0.0:* LISTEN
Here is the logs output when I try to send a mail from a Zimbra user to an external email address. Gathered with tail -f /opt/zimbra/log/*.log /var/log/zimbra.log
Code:
==> audit.log <==
2007-07-17 20:28:30,253 INFO [http-443-Processor99] [ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/undefined;] security - cmd=Auth; account=emx@zcsdomain.com; protocol=soap;
==> myslow.log <==
# Time: 070717 20:28:30
# User@Host: zimbra[zimbra] @ zoo.zcsdomain.com [88.88.88.198]
# Query_time: 0 Lock_time: 0 Rows_sent: 2 Rows_examined: 2
SELECT * FROM volume;
# User@Host: zimbra[zimbra] @ zoo.zcsdomain.com [88.88.88.198]
# Query_time: 0 Lock_time: 0 Rows_sent: 1 Rows_examined: 1
SELECT message_volume_id, secondary_message_volume_id, index_volume_id FROM current_volumes;
==> /var/log/zimbra.log <==
Jul 17 20:28:30 zoo slapd[2726]: is_entry_objectclass("", "2.5.6.1") no objectClass attribute
==> mailbox.log <==
2007-07-17 20:28:30,662 INFO [http-443-Processor99] [ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/undefined;] cache - Initializing folder and tag caches for mailbox 5
2007-07-17 20:28:30,784 INFO [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-07-17 20:28:37,253 WARN [http-443-Processor99] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] op - PRIORITY 0 ==> 1000
2007-07-17 20:28:37,253 WARN [http-443-Processor99] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] op - PRIORITY 1 ==> 1000
2007-07-17 20:28:37,254 WARN [http-443-Processor99] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] op - PRIORITY 2 ==> 1000
2007-07-17 20:28:37,254 WARN [http-443-Processor99] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] op - PRIORITY 3 ==> 1000
2007-07-17 20:28:37,254 WARN [http-443-Processor99] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] op - PRIORITY 4 ==> 1000
==> /var/log/zimbra.log <==
Jul 17 20:28:30 zoo last message repeated 2 times
Jul 17 20:28:38 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraCalResType) index_param failed (18)
Jul 17 20:28:38 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraAccountStatus) index_param failed (18)
Jul 17 20:28:38 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraAccountStatus) index_param failed (18)
Jul 17 20:28:38 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraCalResType) index_param failed (18)
Jul 17 20:28:38 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraAccountStatus) index_param failed (18)
==> mailbox.log <==
2007-07-17 20:28:41,116 INFO [http-443-Processor99] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] cache - Loading flagset cache
2007-07-17 20:28:41,122 INFO [http-443-Processor99] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] cache - Loading tagset cache
2007-07-17 20:29:07,869 INFO [http-443-Processor100] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] SendMsg - LC(mbox=7a5a5ca3-855c-49ca-987a-5738d4859d9f, sessions=[1])
2007-07-17 20:29:07,943 INFO [http-443-Processor100] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] JMSession - SMTP Server: zoo.zcsdomain.com
2007-07-17 20:29:08,200 INFO [http-443-Processor100] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] zimlet - Loaded class com.zimbra.cs.zimlet.handler.NANPHandler
2007-07-17 20:29:08,204 INFO [http-443-Processor100] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] zimlet - Loaded class com.zimbra.cs.zimlet.handler.RegexHandler
2007-07-17 20:29:08,583 INFO [http-443-Processor100] [name=emx@zcsdomain.com;mid=5;ip=213.79.39.134;ua=ZimbraWebClient - FF2.0 (Win)/4.5.6_GA_1044.DEBIAN3.1;] mailbox - Added message id=260 digest=Em2Fjj7US4na3Bkn8frpSLRhoz0= mailbox=5 rcpt=:API:
==> /var/log/zimbra.log <==
Jul 17 20:28:38 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraAccountStatus) index_param failed (18)
Jul 17 20:29:08 zoo slapd[2726]: is_entry_objectclass("", "2.5.6.1") no objectClass attribute
Jul 17 20:29:08 zoo postfix/smtpd[5085]: connect from zoo.zcsdomain.com[88.88.88.198]
Jul 17 20:29:08 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraDomainType) index_param failed (18)
Jul 17 20:29:08 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraMailStatus) index_param failed (18)
Jul 17 20:29:08 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraDomainType) index_param failed (18)
Jul 17 20:29:08 zoo slapd[2726]: <= bdb_equality_candidates: (zimbraMailStatus) index_param failed (18)
Jul 17 20:29:08 zoo postfix/smtpd[5085]: C95B354828B: client=zoo.zcsdomain.com[88.88.88.198]
Jul 17 20:29:08 zoo postfix/cleanup[5088]: C95B354828B: message-id=<19426064.01184704148055.JavaMail.root@zoo.zcsdomain.com>
Jul 17 20:29:08 zoo postfix/smtpd[5085]: disconnect from zoo.zcsdomain.com[88.88.88.198]
Jul 17 20:29:08 zoo postfix/qmgr[3223]: C95B354828B: from=, size=546, nrcpt=1 (queue active)
Jul 17 20:29:08 zoo last message repeated 3 times
Jul 17 20:29:08 zoo slapd[2726]: is_entry_objectclass("", "2.5.6.1") no objectClass attribute
Jul 17 20:29:08 zoo amavis[3192]: (03192-03) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20070717T202908-03192: -> SIZE=546 Received: from zoo.zcsdomain.com ([88.88.88.198]) by localhost (zoo.zcsdomain.com [88.88.88.198]) (amavisd-new, port 10024) with ESMTP for ; Tue, 17 Jul 2007 20:29:08 +0000 (UTC)
Jul 17 20:29:08 zoo amavis[3192]: (03192-03) Checking: eLACCsShQJmR [88.88.88.198] ->
Jul 17 20:29:12 zoo slapd[2726]: is_entry_objectclass("", "2.5.6.1") no objectClass attribute
Jul 17 20:29:12 zoo postfix/smtpd[5092]: connect from zoo.zcsdomain.com[88.88.88.198]
Jul 17 20:29:12 zoo postfix/smtpd[5092]: fatal: non-null host address bits in "88.88.88.198/27", perhaps you should use "88.88.88.192/27" instead
Jul 17 20:29:13 zoo amavis[3192]: (03192-03) (!)FWD via SMTP: -> , 450 4.4.1 Can't connect to 88.88.88.198 port 10025, (Bad file descriptor) at (eval 51) line 145, line 41., MTA([88.88.88.198]:10025), id=03192-03
Jul 17 20:29:13 zoo amavis[3192]: (03192-03) Blocked TEMPFAIL, [88.88.88.198] [88.88.88.198] -> , Message-ID: <19426064.01184704148055.JavaMail.root@zoo.zcsdomain.com>, mail_id: eLACCsShQJmR, Hits: -2.292, 4920 ms
Jul 17 20:29:13 zoo postfix/master[3215]: warning: process /opt/zimbra/postfix-2.2.9/libexec/smtpd pid 5092 exit status 1
Jul 17 20:29:13 zoo postfix/master[3215]: warning: /opt/zimbra/postfix-2.2.9/libexec/smtpd: bad command startup -- throttling
Jul 17 20:29:13 zoo postfix/smtp[5089]: C95B354828B: to=, relay=88.88.88.198[88.88.88.198], delay=5, status=deferred (host 88.88.88.198[88.88.88.198] said: 450 4.4.1 Can't connect to 88.88.88.198 port 10025, (Bad file descriptor) at (eval 51) line 145,
==> mailbox.log <==
2007-07-17 20:29:29,385 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=1, closed=0, after=1 (0ms) Looking closely at the log I notice the following entry:
Code:
Jul 17 20:29:12 zoo postfix/smtpd[5092]: fatal: non-null host address bits in "88.88.88.198/27", perhaps you should use "88.88.88.192/27" instead
Now it becomes clear that I should not have put the netmask /27 in the postfix config file master.cf, as shown below:
Code:
-o mynetworks=127.0.0.0/8,88.88.88.198/27
I remove the offending entry and now the master.cf config files shows the correct line:
Code:
-o mynetworks=127.0.0.0/8,88.88.88.198
After this I restart Zimbra with zmcontrol. The 'Bad file descriptor' problem is gone 
Problem solved.
LinkBack URL
About LinkBacks
Bad file descriptor?
Same problem shown in web UI
'Bad file descriptor' problem solved
