[juneau-lug] Mail server stable, but logs messed up

  • From: James Zuelow <e5z8652@xxxxxxxxxx>
  • To: <juneau-lug@xxxxxxxxxxxxx>
  • Date: Sun, 17 Feb 2002 19:16:14 -0900 (AKST)

After today's upgrade I'm satisfied that I won't be losing mail anymore.
But now my logs are messed up.

Toby, you mentioned that your class is using SuSE 7.3 - you're probably
using Sendmail vice Postfix, but this is an interesting one.  When Postfix
makes log entries, it uses the local system time, *except* when it is
accepting messages back from Amavis:

Feb 17 17:20:39 Hobbes postfix/smtpd[26664]: disconnect from
Hobbes.sodorline.home[127.0.0.1]
Feb 17 17:20:40 Hobbes amavis[26668]: starting.  amavis perl-11 Fri Sep 28
17:04:45 GMT 2001
Feb 18 02:20:44 Hobbes postfix/smtpd[26672]: connect from
unknown[127.0.0.1]
Feb 18 02:20:44 Hobbes postfix/smtpd[26672]: 4D1D83AB5:
client=unknown[127.0.0.1]
Feb 17 17:20:44 Hobbes postfix/cleanup[26665]: 4D1D83AB5:
message-id=<1013998750.3000.76.camel@oilslick>
Feb 18 02:20:44 Hobbes postfix/smtpd[26672]: disconnect from
unknown[127.0.0.1]
Feb 17 17:20:44 Hobbes amavis[26668]: do_exit:830 - ending execution with
0
Feb 17 17:20:44 Hobbes postfix/qmgr[26281]: 4D1D83AB5:
from=<suse-linux-e-return-91103-zuelow=gci.net@xxxxxxxx>, size=3703,
nrcpt=1 (queue active)

The "disconnect from Hobbes.sodorline.home[127.0.0.1]" entry is Fetchmail
retrieving mail from gci.net and handing it over to Postfix. Note local
time.

Amavis starts scanning on the second line - it is scanning the 4D1D83AB5
message.  Note local time again.

Amavis connects to Postfix to return the message - note GMT!

Postfix recieves the message - note GMT again.

Postfix's cleanup daemon identifies the message, getting ready to deliver
it.  Now we're back to local time!

Postfix's smtpd disconnects from Amavis, once again in GMT.  Grr...

Amavis reports scanning the message, but not finding anything.  In
local time.

And finally, Postfix hands the message over to Procmail for final
delivery.  In local time.

This will play h#ll with my logs until I get it figured out.  Today I
updated both Postfix and Amavis with YaST2, then retrograded to the Amavis
on the installation CDs.  So at the end of the day, only Postfix was
upgraded.  The smtp daemon seems to have the difficulty with GMT, but once
again only when interacting with Amavis - direct smtp connections to
zuelow.net are logged in local time by smtpd:

Feb 17 18:29:44 Hobbes postfix/smtpd[969]: connect from
n30.groups.yahoo.com[216.115.96.80]
Feb 17 18:29:44 Hobbes postfix/smtpd[969]: A7D6239E4:
client=n30.groups.yahoo.com[216.115.96.80]
Feb 17 18:29:45 Hobbes postfix/smtpd[969]: disconnect from
n30.groups.yahoo.com[216.115.96.80]

If anyone has an idea why this would happen, please let me know!

Cheers,

James


------------------------------------
This is the Juneau-LUG mailing list.
To unsubscribe, send an e-mail to juneau-lug-request@xxxxxxxxxxxxx with the 
word unsubscribe in the subject header.

Other related posts:

  • » [juneau-lug] Mail server stable, but logs messed up
  1. Home
  2. juneau-lug
  3. Archive
  4. 02-2002