From: Patrick Proniewski (patrick.proniewskiuniv-lyon2.fr)
Date: Sat Oct 09 2010 - 12:40:46 CDT

• Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

Hi all,

On my internal mail gateway, I've found in the log some "watchdog timeout". These last days, it's about one or two a day, sometimes none:

Oct 7 11:55:50 ru postfix-mailgw/smtp[96909]: fatal: watchdog timeout
Oct 7 11:55:51 ru postfix-mailgw/master[1167]: warning: process /usr/local/libexec/postfix/smtp pid 96909 exit status 1
Oct 7 21:21:23 ru postfix-mailgw/smtp[76389]: fatal: watchdog timeout
Oct 7 21:21:24 ru postfix-mailgw/master[1167]: warning: process /usr/local/libexec/postfix/smtp pid 76389 exit status 1
Oct 5 05:32:02 ru postfix-mailgw/smtp[30650]: fatal: watchdog timeout
Oct 5 05:32:03 ru postfix-mailgw/master[1167]: warning: process /usr/local/libexec/postfix/smtp pid 30650 exit status 1

It seems the smtp process does its job, then is not used for few hours, then dies:

(grep of PID number 64390 in mail logs)
Oct 8 05:47:05 ru postfix-mailgw/smtp[64390]: connect to 192.168.0.179[192.168.0.179]:25: Operation timed out
Oct 8 05:47:05 ru postfix-mailgw/smtp[64390]: 9962B17146A: to=<some-userdionysos.domain.tld>, orig_to=<some-userdomain.tld>, relay=none, delay=182748, delays=182718/0.01/30/0, dsn=4.4.1, status=deferred (connect to 192.168.0.179[192.168.0.179]:25: Operation timed out)
Oct 8 05:47:21 ru postfix-mailgw/smtp[64390]: 46DFE1714D6: to=<some-usermail.domain.tld>, orig_to=<some-userdomain.tld>, relay=192.168.0.76[192.168.0.76]:25, delay=3.3, delays=0.11/0/3/0.19, dsn=5.0.0, status=bounced (host 192.168.0.76[192.168.0.76] said: 552 Requested mail action aborted: exceeded storage allocation (in reply to RCPT TO command))
Oct 8 05:47:57 ru postfix-mailgw/smtp[64390]: A594C1714D6: to=<some-usermail.domain.tld>, orig_to=<some-userdomain.tld>, relay=192.168.0.76[192.168.0.76]:25, delay=3.2, delays=0.11/0/3/0.05, dsn=2.0.0, status=sent (250 OK)
Oct 8 05:48:44 ru postfix-mailgw/smtp[64390]: 296AE1714D6: to=<some-usermail.domain.tld>, orig_to=<some-userdomain.tld>, relay=192.168.0.76[192.168.0.76]:25, delay=0.14, delays=0.11/0/0/0.03, dsn=5.0.0, status=bounced (host 192.168.0.76[192.168.0.76] said: 552 Requested mail action aborted: exceeded storage allocation (in reply to RCPT TO command))
Oct 8 05:49:18 ru postfix-mailgw/smtp[64390]: 22C8B1714D3: to=<some-usermail.domain.tld>, orig_to=<some-userdomain.tld>, relay=192.168.0.76[192.168.0.76]:25, delay=1, delays=0.11/0/0/0.92, dsn=2.0.0, status=sent (250 OK)
../..
Oct 8 05:57:11 ru postfix-mailgw/smtp[64390]: 5B6791714D9: to=<some-usermail.domain.tld>, orig_to=<some-userdomain.tld>, relay=192.168.0.76[192.168.0.76]:25, delay=14, delays=0.02/0/0/14, dsn=2.0.0, status=sent (250 OK)
Oct 8 05:57:11 ru postfix-mailgw/smtp[64390]: 5B6791714D9: to=<some-usermail.domain.tld>, orig_to=<some-userdomain.tld>, relay=192.168.0.76[192.168.0.76]:25, delay=14, delays=0.02/0/0/14, dsn=2.0.0, status=sent (250 OK)
Oct 8 05:57:11 ru postfix-mailgw/smtp[64390]: 5B6791714D9: to=<some-usermail.domain.tld>, orig_to=<some-userdomain.tld>, relay=192.168.0.76[192.168.0.76]:25, delay=14, delays=0.02/0/0/14, dsn=2.0.0, status=sent (250 OK)
Oct 8 05:57:11 ru postfix-mailgw/smtp[64390]: 5B6791714D9: to=<some-usermail.domain.tld>, orig_to=<some-userdomain.tld>, relay=192.168.0.76[192.168.0.76]:25, delay=14, delays=0.02/0/0/14, dsn=2.0.0, status=sent (250 OK)
Oct 8 10:57:25 ru postfix-mailgw/smtp[64390]: fatal: watchdog timeout
Oct 8 10:57:26 ru postfix-mailgw/master[1167]: warning: process /usr/local/libexec/postfix/smtp pid 64390 exit status 1

postfix-mailgw is a part of my postfix multi setup, and it's main role is to rewrite addresses for emails received from the two other postfix instances, and to deliver those emails to the final destination.

Any idea about where I should start looking?

Patrick PRONIEWSKI
--
Administrateur Système - SENTIER - Université Lumière Lyon 2

• application/pkcs7-signature attachment: smime.p7s

• Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]