email alerts failing

[Marc Powell]

> -----Original Message-----
> From: nagios-users-bounces at lists.sourceforge.net [mailto:nagios-users-
> bounces at lists.sourceforge.net] On Behalf Of Robert Cole
> Sent: Tuesday, September 18, 2007 6:55 PM
> To: nagios-users at lists.sourceforge.net
> Subject: [Nagios-users] email alerts failing
> 
> I've ben chasing an email issue for a while and only just today I've
put
> some time into resolving this and here is what I found.
> 
> I'm running nagios 2.9 and plugins 1.4.9 and I have a relay email
server
> setup to accept and relay email to the outside world and this works
from
> the nagios system if I run mail -s test test at test.com (real email
> address left off) without a problem but if the email is generated from
> nagios it doesn't go though.
> 
> This is an example tail -f  /var/log/messages on the nagios system
when
> a message from nagios is generated:
> 
> Sep 18 16:26:03 ms1 postfix/smtp[2691]: AFF24199CDB:
> to=<9493008152 at mms.mycingular.com>,
> relay=mail.youmail.local[172.16.1.22]:25, delay=0.09,
> delays=0.06/0/0.01/0.02, dsn=2.0.0, status=sent (250 2.0.0
> l8INLUvu012152 Message accepted for delivery)
> Sep 18 16:26:03 ms1 postfix/smtp[2691]: name_mask: resource
> Sep 18 16:26:03 ms1 postfix/smtp[2691]: name_mask: software
> Sep 18 16:26:03 ms1 postfix/qmgr[2479]: AFF24199CDB: removed
> Sep 18 16:26:03 ms1 postfix/pickup[2480]: C308F199CDB: uid=101
> from=<nagios>
> Sep 18 16:26:03 ms1 postfix/cleanup[2684]: C308F199CDB:
> message-id=<20070918232603.C308F199CDB at ms1.youmail.local>
> Sep 18 16:26:03 ms1 postfix/qmgr[2479]: C308F199CDB:
> from=<nagios at ms1.youmail.local>, size=683, nrcpt=1 (queue active)
> Sep 18 16:26:03 ms1 postfix/smtp[2691]: <
> mail.youmail.local[172.16.1.22]: 220 mail001.youmail.com ESMTP
Sendmail
> 8.13.8/8.13.8; Tue, 18 Sep 2007 16:21:30 -0700
> Sep 18 16:26:03 ms1 postfix/smtp[2691]: >
> mail.youmail.local[172.16.1.22]: EHLO ms1.youmail.local
> Sep 18 16:26:03 ms1 postfix/smtp[2691]: <
> mail.youmail.local[172.16.1.22]: 250-mail001.youmail.com Hello


> Sep 18 16:26:03 ms1 postfix/smtp[2691]: <
> mail.youmail.local[172.16.1.22]: 250 2.0.0 l8INLUgm012155 Message
> accepted for delivery

Great, mail001.youmail.com accepted the message with queue-id
l8INLUgm012155. It's now responsible for delivering it and nagios is out
of the picture. 
 
> Here is the other side of that on the mail server itself:
> 
> Sep 18 16:23:19 mail001 sendmail[12235]: l8INNJkx012235:
> ms1.youmail.local [10.1.1.28] did not issue MAIL/EXPN/VRFY/ETRN during
> connection to MTA
> Sep 18 16:28:19 mail001 sendmail[12380]: l8INSJK8012380:
> ms1.youmail.local [10.1.1.28] did not issue MAIL/EXPN/VRFY/ETRN during
> connection to MTA
> Sep 18 16:33:19 mail001 sendmail[12606]: l8INXJXp012606:
> ms1.youmail.local [10.1.1.28] did not issue MAIL/EXPN/VRFY/ETRN during
> connection to MTA
> Sep 18 16:33:29 mail001 sendmail[12613]: l8INXTLn012613:
> from=<nagios at ms1.youmail.local>, size=649, class=0, nrcpts=1,
> msgid=<20070918233803.534C5199CDB at ms1.youmail.local>, proto=ESMTP,
> daemon=MTA, relay=ms1.youmail.local [10.1.1.28]
> Sep 18 16:33:30 mail001 sendmail[12616]: l8INXTaH012616:
> from=<nagios at ms1.youmail.local>, size=661, class=0, nrcpts=1,
> msgid=<20070918233803.67732199CF6 at ms1.youmail.local>, proto=ESMTP,
> daemon=MTA, relay=ms1.youmail.local [10.1.1.28]
> Sep 18 16:33:30 mail001 sendmail[12616]: l8INXTaJ012616:
> from=<nagios at ms1.youmail.local>, size=661, class=0, nrcpts=1,
> msgid=<20070918233803.7DC04199CDB at ms1.youmail.local>, proto=ESMTP,
> daemon=MTA, relay=ms1.youmail.local [10.1.1.28]
> Sep 18 16:33:30 mail001 sendmail[12616]: l8INXTaL012616:
> from=<nagios at ms1.youmail.local>, size=661, class=0, nrcpts=1,
> msgid=<20070918233803.9110D199CF6 at ms1.youmail.local>, proto=ESMTP,
> daemon=MTA, relay=ms1.youmail.local [10.1.1.28]
> Sep 18 16:33:30 mail001 sendmail[12623]: l8INXUoL012623:
> from=<nagios at ms1.youmail.local>, size=661, class=0, nrcpts=1,
> msgid=<20070918233803.A3CA9199CDB at ms1.youmail.local>, proto=ESMTP,
> daemon=MTA, relay=ms1.youmail.local [10.1.1.28]
> Sep 18 16:33:30 mail001 sendmail[12626]: l8INXUCe012626:
> from=<nagios at ms1.youmail.local>, size=661, class=0, nrcpts=1,
> msgid=<20070918233803.B8520199CDB at ms1.youmail.local>, proto=ESMTP,
> daemon=MTA, relay=ms1.youmail.local [10.1.1.28]
> 
> Now it looks to me as if the mail is being accepted and I'm not sure

Yes it was but the logs above don't show anything related to the
accepted queue-id of l8INLUgm012155. In fact, anything around that time
is curiously absent from the log. Did you neglect to post full log
entries? grep that queue ID out of the log file and see what you see.

> what the deal is with "did not issue MAIL/EXPN/VRFY/ETRN" as searching
> and searching and looking at the solutions I've found so far don't
apply

I'm going to bet that you're using check_smtp or check_tcp from nagios
to monitor this mail server every 5 minutes. Those plugins don't
initiate a full SMTP transaction so sendmail complains. They're safe to
ignore.

> but again if I mail out from the nagios system using the mail command
it
> works yet email through nagios doesn't work.
> 
> Any ideas?

Mail001.youmail.com accepts the message but does something bad with it
afterward. You should see what in the maillog on that machine.

--
Marc

-------------------------------------------------------------------------
This SF.net email is sponsored by: Microsoft
Defy all challenges. Microsoft(R) Visual Studio 2005.
http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/
_______________________________________________
Nagios-users mailing list
Nagios-users at lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/nagios-users
::: Please include Nagios version, plugin version (-v) and OS when reporting any issue. 
::: Messages without supporting info will risk being sent to /dev/null