On Tue, Feb 18, 2014 at 02:52:12AM +0000, Vaughan, Jesse wrote: > I have been having problems with mail not being delivered and I > don't know why... In this particular instance an email from > cschofi...@cox.net wasn't delivered.. anyone know why?
Please carefully requests for help for material errors, the actual address appears to be different: cschofie...@cox.net > I had smtpd -v turned on in the log.. the log is here: Do NOT turn "-v" unless asked to by someone who can justify why it is needed, this just makes it much harder to see the relevant data. > http://www.restonlinux.com/maillog.txt This boils down to two deliveries via amavis to your maildrop LDA both successful. Both carry the same message-id, and have the same size, they look like duplicates, and may have been consolidated by Courier IMAP. EF2F17788B2: message-id=<53026510.7080...@cox.net> F420B7788B3: message-id=<53026510.7080...@cox.net> Everything looks normal, except perhaps the high delay in the incoming queue both before and after the amavis filter. For the pre-filter delay: you seem to be using an RBL that is not responsive, and may cause clients to disconnect due to high delays: Feb 17 14:37:57 mail postfix/smtpd[14259]: generic_checks: name=reject_rbl_client Feb 17 14:37:57 mail postfix/smtpd[14259]: reject_rbl: Client host 68.230.241.216 Feb 17 14:38:07 mail postfix/smtpd[14259]: dns_query: 216.241.230.68.opm.blitzed.org (A): Host not found, try again Feb 17 14:38:07 mail postfix/smtpd[14259]: warning: 216.241.230.68.opm.blitzed.org: RBL lookup error: Host or domain name not found. Name service error for name=216.241.230.68.opm.blitzed.org type=A: Host not found, try again Paying attention to such warnings may be wise. For the after queue delay: slow amavis RBL lookups enabled? Feb 17 14:38:23 mail amavis[14254]: (14254-03) Passed CLEAN, [68.230.241.216] [98.169.62.249] <cschofie...@cox.net> -> <jrv...@restonlinux.com>, Message-ID: <53026510.7080...@cox.net>, mail_id: 4046SmN5F+37, Hits: -2.453, size: 189387, queued_as: 532797788B4, 15035 ms The post-filter smtp server logs no delay in enqueueing the message, so the 15 seconds delay is entirely in amavisd. Feb 17 14:38:23 mail postfix/smtpd[14127]: connect from unknown[127.0.0.1] Feb 17 14:38:23 mail postfix/smtpd[14127]: 532797788B4: client=unknown[127.0.0.1] Feb 17 14:38:23 mail postfix/smtpd[14127]: disconnect from unknown[127.0.0.1] The transaction logs are below: Feb 17 14:38:07 mail postfix/smtpd[14259]: EF2F17788B2: client=eastrmfepo201.cox.net[68.230.241.216] Feb 17 14:38:08 mail postfix/cleanup[14222]: EF2F17788B2: message-id=<53026510.7080...@cox.net> Feb 17 14:38:08 mail postfix/qmgr[19042]: EF2F17788B2: from=<cschofie...@cox.net>, size=189387, nrcpt=1 (queue active) Feb 17 14:38:23 mail postfix/smtp[14371]: EF2F17788B2: to=<jrv...@restonlinux.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=26, delays=11/0.02/0/15, dsn=2.0.0, status=sent (250 2.0.0 from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 532797788B4) Feb 17 14:38:23 mail postfix/qmgr[19042]: EF2F17788B2: removed Feb 17 14:38:23 mail postfix/smtpd[14127]: 532797788B4: client=unknown[127.0.0.1] Feb 17 14:38:23 mail postfix/cleanup[14222]: 532797788B4: message-id=<53026510.7080...@cox.net> Feb 17 14:38:23 mail postfix/qmgr[19042]: 532797788B4: from=<cschofie...@cox.net>, size=189826, nrcpt=1 (queue active) Feb 17 14:38:23 mail postfix/pipe[14248]: 532797788B4: to=<je...@restonlinux.com>, orig_to=<jrv...@restonlinux.com>, relay=maildrop, delay=0.07, delays=0.02/0/0/0.04, dsn=2.0.0, status=sent (delivered via maildrop service) Feb 17 14:38:23 mail postfix/qmgr[19042]: 532797788B4: removed Feb 17 14:38:08 mail postfix/smtpd[14361]: F420B7788B3: client=eastrmfepo201.cox.net[68.230.241.216] Feb 17 14:38:08 mail postfix/cleanup[14370]: F420B7788B3: message-id=<53026510.7080...@cox.net> Feb 17 14:38:08 mail postfix/qmgr[19042]: F420B7788B3: from=<cschofie...@cox.net>, size=189387, nrcpt=1 (queue active) Feb 17 14:38:23 mail postfix/smtp[14229]: F420B7788B3: to=<for...@restonlinux.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=26, delays=11/0/0.01/15, dsn=2.0.0, status=sent (250 2.0.0 from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 5F2A17788B5) Feb 17 14:38:23 mail postfix/qmgr[19042]: F420B7788B3: removed Feb 17 14:38:23 mail postfix/smtpd[14127]: 532797788B4: client=unknown[127.0.0.1] Feb 17 14:38:23 mail postfix/smtpd[14127]: 5F2A17788B5: client=unknown[127.0.0.1] Feb 17 14:38:23 mail postfix/cleanup[14370]: 5F2A17788B5: message-id=<53026510.7080...@cox.net> Feb 17 14:38:23 mail postfix/qmgr[19042]: 5F2A17788B5: from=<cschofie...@cox.net>, size=189826, nrcpt=1 (queue active) Feb 17 14:38:23 mail postfix/pipe[14248]: 5F2A17788B5: to=<for...@restonlinux.com>, relay=maildrop, delay=0.05, delays=0.02/0/0/0.02, dsn=2.0.0, status=sent (delivered via maildrop service) Feb 17 14:38:23 mail postfix/qmgr[19042]: 5F2A17788B5: removed -- Viktor.
