I must be getting desperate.
We have a gateway Win 2003 cluster before passing emails to a SendMail box in a DMZ then going to outside world.
We are finding some outbound emails some of the time are being delayed with a 4.4.7 notification to the sending user.
Unix/DMZ folk say there is a delay on the message getting to DMZ but it isn't clearly in our queue. We check smtp logs (all options are ticked) but there is no unique message ID. There is such a volume of mail (several per hour from similar senders and to same recipients) that I'm finding it hard to work out which log entry belongs to which email.
There are concurrent threads meaning that the usual EHLO, MAIL, RCPT, DATA, QUIT sequence isn't followed in the logs. I hope it is followed in practice. How do you follow these when there is no way to link the starting EHLO, MAIL, RCPT commands with their corresponding DATA, QUIT parts ?
Here is an example where I'm working at Company.com
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 EHLO - +DMZ.Unix.mail.server 250 0 244 17 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 MAIL - +From:<[email protected]> 250 0 48 46 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 34 31 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 EHLO - +DMZ.Unix.mail.server 250 0 244 17 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 MAIL - +From:<[email protected]> 250 0 50 47 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 24 21 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 35 32 16 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 32 29 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 34 31 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 38 35 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 EHLO - +DMZ.Unix.mail.server 250 0 244 17 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 MAIL - +From:<[email protected] .com> 250 0 84 96 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 35 32 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 DATA - +<[email protected] domain> 250 0 140 23341 266 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 QUIT - DMZ.Unix.mail.server 240 266 71 4 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 DATA - +<[email protected]> 250 0 127 27286 343 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 QUIT - DMZ.Unix.mail.server 240 343 71 4 0 SMTP - - - -
2009-12-06 23:00:10 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 QUIT - DMZ.Unix.mail.server 240 1313 71 4 0 SMTP - - - -
Am I missing a trick here?
We have a gateway Win 2003 cluster before passing emails to a SendMail box in a DMZ then going to outside world.
We are finding some outbound emails some of the time are being delayed with a 4.4.7 notification to the sending user.
Unix/DMZ folk say there is a delay on the message getting to DMZ but it isn't clearly in our queue. We check smtp logs (all options are ticked) but there is no unique message ID. There is such a volume of mail (several per hour from similar senders and to same recipients) that I'm finding it hard to work out which log entry belongs to which email.
There are concurrent threads meaning that the usual EHLO, MAIL, RCPT, DATA, QUIT sequence isn't followed in the logs. I hope it is followed in practice. How do you follow these when there is no way to link the starting EHLO, MAIL, RCPT commands with their corresponding DATA, QUIT parts ?
Here is an example where I'm working at Company.com
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 EHLO - +DMZ.Unix.mail.server 250 0 244 17 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 MAIL - +From:<[email protected]> 250 0 48 46 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 34 31 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 EHLO - +DMZ.Unix.mail.server 250 0 244 17 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 MAIL - +From:<[email protected]> 250 0 50 47 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 24 21 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 35 32 16 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 32 29 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 34 31 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 38 35 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 EHLO - +DMZ.Unix.mail.server 250 0 244 17 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 MAIL - +From:<[email protected] .com> 250 0 84 96 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 RCPT - +To:<[email protected]> 250 0 35 32 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 DATA - +<[email protected] domain> 250 0 140 23341 266 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 QUIT - DMZ.Unix.mail.server 240 266 71 4 0 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 DATA - +<[email protected]> 250 0 127 27286 343 SMTP - - - -
2009-12-06 23:00:09 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 QUIT - DMZ.Unix.mail.server 240 343 71 4 0 SMTP - - - -
2009-12-06 23:00:10 201.1.1.55 DMZ.Unix.mail.server SMTPSVC1 GFI_smtp_server 10.108.1.80 0 QUIT - DMZ.Unix.mail.server 240 1313 71 4 0 SMTP - - - -
Am I missing a trick here?
Comment