some watchdog timeout

[Patrick Proniewski]

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-u...@dionysos.domain.tld>, orig_to=<some-u...@domain.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-u...@mail.domain.tld>, orig_to=<some-u...@domain.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-u...@mail.domain.tld>, orig_to=<some-u...@domain.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-u...@mail.domain.tld>, orig_to=<some-u...@domain.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-u...@mail.domain.tld>, orig_to=<some-u...@domain.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-u...@mail.domain.tld>, orig_to=<some-u...@domain.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-u...@mail.domain.tld>, orig_to=<some-u...@domain.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-u...@mail.domain.tld>, orig_to=<some-u...@domain.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-u...@mail.domain.tld>, orig_to=<some-u...@domain.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

smime.p7s
Description: S/MIME cryptographic signature