[SOLVED] Incoming Emails get deferred

[andersen_hc]

Hi,

I'm having a slight issue with Zimbra.

We currently have 3 servers hosted at a data center.
1. Web Server
2. Database Server
3. Mail Server (Zimbra, which I just installed yesterday)

Each server has a public IP address, the mail server having one which I will call 66.77.88.99.
It's internal IP is 192.168.1.2.
I will also refer to my site as example.com.

Also, I made sure our ISP have opened ports 25, 80, 143, etc... to 66.77.88.99 to the public.

I installed Zimbra and set the hostname to mail.example.com. I updated our DNS records at www.exampleDNSHostingService.com to point the MX record to mail.example.com.

dig mx example.com shows
=====================
; <<>> DiG 9.3.4 <<>> mx example.com
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 10056
;; flags: qr rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 2, ADDITIONAL: 1

;; QUESTION SECTION:
example.com. IN MX

;; ANSWER SECTION:
example.com. 75190 IN MX 10 mail.example.com.

;; AUTHORITY SECTION:
example.com. 55215 IN NS ns2.exampleDNSHostingService.com.
example.com. 55215 IN NS ns1.exampleDNSHostingService.com.

;; ADDITIONAL SECTION:
mail.example.com. 58237 IN A 66.77.88.99

;; Query time: 48 msec
;; SERVER: 192.168.1.254#53(192.168.1.254)
;; WHEN: Wed Oct 24 17:57:01 2007
;; MSG SIZE rcvd: 122
======================



I set up a test account: testuser@example.com

I am able to send emails from testuser@example.com

However, when I send emails from an external account, ie. sendinguser@email.com, it sits in the Zimbra Mail Queue under the deferred section.

When I highlight it, it says:

-------------------------
Sender: testuser@email.com
From host: localhost.localdomain
From domain: email.com
From IP: 127.0.0.1
Recipients: sendinguser@example.com
To domain: example.com
Content filter: undefined
Size: 2831
Reason: connect to mail.example.com[66.77.88.99]: connection timed out
-------------------------

I'm not sure what I need to do next.

It seems the server is getting it ok, so i don't think there are any problems with how our mx records are configured. I can "telnet mail.example.com 25" quite happily from my computer.

I read something about setting up split-DNS/Bind locally on the mail server, but I'm not sure if this is related or not.

Any suggestions?

[jholder]

What does
dig a mail.example.com

show? If it's not your public IP, then it's wrong.

[andersen_hc]

Thanks for the reply

I get:


; <<>> DiG 9.3.4 <<>> mail.example.com
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 53807
;; flags: qr rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 0

;; QUESTION SECTION:
;mail.example.com. IN A

;; ANSWER SECTION:
mail.example.com. 57614 IN A 66.77.88.99

;; Query time: 0 msec
;; SERVER: 192.168.1.254#53(192.168.1.254)
;; WHEN: Wed Oct 24 18:07:24 2007
;; MSG SIZE rcvd: 57



Is this good?

[jholder]

Well, something's not right on your firewall. I can't see it.

jholder-mac:~ jholder$ telnet 66.77.88.99 25
Trying 66.77.88.99...
^C
jholder-mac:~ jholder$ ping 66.77.88.99
PING 66.77.88.99 (66.77.88.99): 56 data bytes
^C
--- 66.77.88.99 ping statistics ---
3 packets transmitted, 0 packets received, 100% packet loss
jholder-mac:~ jholder$

[andersen_hc]

Sorry jholder, I was just using a dummy ip address. the real one is --.--.--.-- and the domain is --.--.--

[jholder]

ha, funny

Can you post your /var/log/zimbra.log?

[andersen_hc]

Its a bit large at this point (from previous postfix setup), so i've tailed it for what i recon is relevant info:

hope thats ok



Its a bit large at this point (from previous postfix setup), so i've tailed it for what i recon is relevant info:

Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: antispam: Running 
Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: antivirus: Running 
Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: ldap: Running 
Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: logger: Running 
Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: mailbox: Running 
Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: mta: Running 
Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: snmp: Running 
Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: spell: Running 
Oct 24 18:04:04 mail zimbramon[10727]: 10727:info: 2007-10-24 18:04:01, STATUS: mail.--.com: stats: Running 
Oct 24 18:04:04 mail CRON[10726]: (pam_unix) session closed for user zimbra
Oct 24 18:04:59 mail sshd[10985]: Accepted publickey for zimbra from 192.168.1.2 port 46396 ssh2
Oct 24 18:04:59 mail sshd[10988]: (pam_unix) session opened for user zimbra by (uid=0)
Oct 24 18:04:59 mail sshd[10988]: Received disconnect from 192.168.1.2: 11: Closed due to user request.
Oct 24 18:04:59 mail sshd[10988]: (pam_unix) session closed for user zimbra
Oct 24 18:05:32 mail amavis[25352]: (25352-01) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071024T180532-25352: <hans@--.com> -> <test@--.com> SIZE=2874 BODY=7BIT Received: from mail.--.com ([127.0.0.1]) by localhost (mail.--.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <test@--.com>; Wed, 24 Oct 2007 18:05:32 +0100 (BST)
Oct 24 18:05:32 mail amavis[25349]: (25349-01) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071024T180532-25349: <test@--.com> -> <test@--.com> SIZE=2953 BODY=7BIT Received: from mail.--.com ([127.0.0.1]) by localhost (mail.--.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <test@--.com>; Wed, 24 Oct 2007 18:05:32 +0100 (BST)
Oct 24 18:05:32 mail amavis[25349]: (25349-01) Checking: hezR8JErvtdZ [127.0.0.1] <test@--.com> -> <test@--.com>
Oct 24 18:05:32 mail amavis[25352]: (25352-01) Checking: XDMWrnvVXpSY [127.0.0.1] <hans@--.com> -> <test@--.com>
Oct 24 18:05:32 mail amavis[25349]: (25349-01) cached d8e8fca2dc0f896fd7cb4cb0031ba249 from <test@--.com> (0,0)
Oct 24 18:05:32 mail amavis[25352]: (25352-01) cached e3fba65b71bc9426a7fe17bfaf280245 from <hans@--.com> (0,0)
Oct 24 18:05:32 mail clamd[25554]: SelfCheck: Database status OK. 
Oct 24 18:05:32 mail amavis[25353]: (25353-03) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071024T165911-25353: <test@--.com> -> <hans@--.com> SIZE=2958 BODY=7BIT Received: from mail.--.com ([127.0.0.1]) by localhost (mail.--.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <hans@--.com>; Wed, 24 Oct 2007 18:05:32 +0100 (BST)
Oct 24 18:05:32 mail amavis[25353]: (25353-03) Checking: Nu15SAb5p6mt [127.0.0.1] <test@--.com> -> <hans@--.com>
Oct 24 18:05:32 mail amavis[25353]: (25353-03) cached 833fc4f79ef58fd74272b5464136243c from <test@--.com> (0,0)
Oct 24 18:05:32 mail amavis[25350]: (25350-01) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071024T180532-25350: <test@gmail.com> -> <hans@--.com> SIZE=5952 BODY=7BIT Received: from mail.--.com ([127.0.0.1]) by localhost (mail.--.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <hans@--.com>; Wed, 24 Oct 2007 18:05:32 +0100 (BST)
Oct 24 18:05:32 mail amavis[25345]: (25345-01) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071024T180532-25345: <test@gmail.com> -> <hans@--.com> SIZE=6551 BODY=7BIT Received: from mail.--.com ([127.0.0.1]) by localhost (mail.--.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <hans@--.com>; Wed, 24 Oct 2007 18:05:32 +0100 (BST)
Oct 24 18:05:32 mail amavis[25345]: (25345-01) Checking: 3gWA67NFVpHu [127.0.0.1] <test@gmail.com> -> <hans@--.com>
Oct 24 18:05:32 mail amavis[25345]: (25345-01) cached dee35b3194e77c3d6879bc89132c0966 from <test@gmail.com> (0,0)
Oct 24 18:05:32 mail amavis[25350]: (25350-01) Checking: wXL4mTIrdHcl [127.0.0.1] <test@gmail.com> -> <hans@--.com>
Oct 24 18:05:32 mail amavis[25350]: (25350-01) cached bde8aa91ad47bcece777ee66379959f4 from <test@gmail.com> (0,0)
Oct 24 18:05:34 mail amavis[25350]: (25350-01) FWD via SMTP: <test@gmail.com> -> <hans@--.com>,BODY=7BIT 250 2.6.0 Ok, id=25350-01, from MTA([127.0.0.1]:10025): 250 Ok: queued as ED1CA1EC067
Oct 24 18:05:34 mail amavis[25345]: (25345-01) FWD via SMTP: <test@gmail.com> -> <hans@--.com>,BODY=7BIT 250 2.6.0 Ok, id=25345-01, from MTA([127.0.0.1]:10025): 250 Ok: queued as EFAE91EC068
Oct 24 18:05:34 mail amavis[25350]: (25350-01) Passed CLEAN, LOCAL [127.0.0.1] [64.233.162.234] <test@gmail.com> -> <hans@--.com>, Message-ID: <3c02c6420710240859j5f926946w9b148c0a11ff8e20@mail.gmail.com>, mail_id: wXL4mTIrdHcl, Hits: -2.338, size: 5944, queued_as: ED1CA1EC067, 2458 ms
Oct 24 18:05:35 mail amavis[25345]: (25345-01) Passed CLEAN, LOCAL [127.0.0.1] [64.233.162.231] <test@gmail.com> -> <hans@--.com>, Message-ID: <3c02c6420710240807t567b5414xaaaff5cc916e5408@mail.gmail.com>, mail_id: 3gWA67NFVpHu, Hits: -2.381, size: 6543, queued_as: EFAE91EC068, 2480 ms
Oct 24 18:05:35 mail amavis[25347]: (25347-02) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071024T171432-25347: <hans@--.com> -> <hans@--.com> SIZE=2868 BODY=7BIT Received: from mail.--.com ([127.0.0.1]) by localhost (mail.--.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <hans@--.com>; Wed, 24 Oct 2007 18:05:35 +0100 (BST)
Oct 24 18:05:35 mail amavis[25347]: (25347-02) Checking: ui5F7BOvJOJs [127.0.0.1] <hans@--.com> -> <hans@--.com>
Oct 24 18:05:35 mail amavis[25349]: (25349-01) FWD via SMTP: <test@--.com> -> <test@--.com>,BODY=7BIT 250 2.6.0 Ok, id=25349-01, from MTA([127.0.0.1]:10025): 250 Ok: queued as 9E3A11EC05F
Oct 24 18:05:35 mail amavis[25349]: (25349-01) Passed CLEAN, LOCAL [127.0.0.1] [207.97.245.110] <test@--.com> -> <test@--.com>, Message-ID: <471F5CB4.1050308@--.com>, mail_id: hezR8JErvtdZ, Hits: -0.501, size: 2953, queued_as: 9E3A11EC05F, 3361 ms
Oct 24 18:05:35 mail amavis[25352]: (25352-01) FWD via SMTP: <hans@--.com> -> <test@--.com>,BODY=7BIT 250 2.6.0 Ok, id=25352-01, from MTA([127.0.0.1]:10025): 250 Ok: queued as AF47E1EC063
Oct 24 18:05:35 mail amavis[25352]: (25352-01) Passed CLEAN, LOCAL [127.0.0.1] [207.97.245.110] <hans@--.com> -> <test@--.com>, Message-ID: <471F6F6C.3020104@--.com>, mail_id: XDMWrnvVXpSY, Hits: -2.599, size: 2874, queued_as: AF47E1EC063, 3441 ms
Oct 24 18:05:35 mail amavis[25353]: (25353-03) FWD via SMTP: <test@--.com> -> <hans@--.com>,BODY=7BIT 250 2.6.0 Ok, id=25353-03, from MTA([127.0.0.1]:10025): 250 Ok: queued as B765E1EC064
Oct 24 18:05:35 mail amavis[25353]: (25353-03) Passed CLEAN, LOCAL [127.0.0.1] [207.97.245.114] <test@--.com> -> <hans@--.com>, Message-ID: <471F5D32.7030907@--.com>, mail_id: Nu15SAb5p6mt, Hits: -1.594, size: 2958, queued_as: B765E1EC064, 3348 ms
Oct 24 18:05:38 mail amavis[25347]: (25347-02) FWD via SMTP: <hans@--.com> -> <hans@--.com>,BODY=7BIT 250 2.6.0 Ok, id=25347-02, from MTA([127.0.0.1]:10025): 250 Ok: queued as 3DB381EC057
Oct 24 18:05:38 mail amavis[25347]: (25347-02) Passed CLEAN, LOCAL [127.0.0.1] [207.97.245.114] <hans@--.com> -> <hans@--.com>, Message-ID: <471F6F6C.3020104@--.com>, mail_id: ui5F7BOvJOJs, Hits: -2.599, size: 2868, queued_as: 3DB381EC057, 3251 ms

[jholder]

Can you attach them using the manage attachements button? Here's what would help:
/var/log/zimbra.log
/opt/zimbra/log/mailbox.log

[andersen_hc]

heh, let me see if i can get it right this time

-
-

thanks again for your help

[andersen_hc]

I think i may have a lead on what the problem is. Because the ISP keeps a tight firewall, we have to request specific ports to be exposed to the public ip, and i came across someone who mentioned that port 7025 (lmtp) has to be open, otherwise you will get the same issue as i did.

won't be able to test this until a few hours from now (takes them a while to respond to firewall change requests), so hopefully that will be the solution...

does it sound reasonable?