More Postfix Woes

Thu Oct 18 16:05:00 PDT 2007

On Thu, 18 Oct 2007 15:42:22 -0700
Rem P Roberti <rem at remdog.net> confabulated:

> I don't know what is going on with respect to Postfix during my boot
> process, but the program starts, and stops, starts, and stops, and
> this newbie is a little perplexed.  Fortunately, it ends up started,
> so I have access to my email, but something is obviously not right
> here.  I have provided below entries from my latest maillog.  Any
> help is appreciated...
> 
> 
> 
> Oct 18 00:00:00 bsd newsyslog[2514]: logfile turned over
> Oct 18 02:20:02 bsd postfix/pickup[2799]: 9EBD01D119: uid=0
> from=<root> Oct 18 02:20:02 bsd postfix/trivial-rewrite[3032]:
> warning: database /usr/local/etc/postfix/transport.db is older than
> source file /usr/local/etc/postfix/transport
> Oct 18 02:20:02 bsd postfix/cleanup[3031]: 9EBD01D119:
> message-id=<20071018092002.9EBD01D119 at bsd.remdog.net>
> Oct 18 02:20:02 bsd postfix/qmgr[906]: 9EBD01D119:
> from=<root at bsd.remdog.net>, size=931, nrcpt=1 (queue active)
> Oct 18 02:20:02 bsd postfix/local[3033]: 9EBD01D119:
> to=<rem at bsd.remdog.net>, orig_to=<root>, relay=local, delay=302,
> delays=302/0.01/0/0.05, dsn=2.0.0, status=sent (delivered to command:
> IFS=' ' && exec /usr/local/bin/procmail -f- || exit 75 #rem)
> Oct 18 02:20:02 bsd postfix/qmgr[906]: 9EBD01D119: removed
> Oct 18 02:45:20 bsd postfix/pickup[2799]: 4E5D11D119: uid=0
> from=<root> Oct 18 02:45:20 bsd postfix/trivial-rewrite[3247]:
> warning: database /usr/local/etc/postfix/transport.db is older than
> source file /usr/local/etc/postfix/transport
> Oct 18 02:45:20 bsd postfix/cleanup[3246]: 4E5D11D119:
> message-id=<20071018094520.4E5D11D119 at bsd.remdog.net>
> Oct 18 02:45:20 bsd postfix/qmgr[906]: 4E5D11D119:
> from=<root at bsd.remdog.net>, size=939, nrcpt=1 (queue active)
> Oct 18 02:45:20 bsd postfix/local[3248]: 4E5D11D119:
> to=<rem at bsd.remdog.net>, orig_to=<root>, relay=local, delay=19,
> delays=19/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command:
> IFS=' ' && exec /usr/local/bin/procmail -f- || exit 75 #rem)
> Oct 18 02:45:20 bsd postfix/qmgr[906]: 4E5D11D119: removed
> Oct 18 03:02:23 bsd postfix/pickup[3268]: 582321D119: uid=0
> from=<root> Oct 18 03:02:23 bsd postfix/trivial-rewrite[3583]:
> warning: database /usr/local/etc/postfix/transport.db is older than
> source file /usr/local/etc/postfix/transport
> Oct 18 03:02:23 bsd postfix/cleanup[3576]: 582321D119:
> message-id=<20071018100223.582321D119 at bsd.remdog.net>
> Oct 18 03:02:23 bsd postfix/qmgr[906]: 582321D119:
> from=<root at bsd.remdog.net>, size=1189, nrcpt=1 (queue active)
> Oct 18 03:02:23 bsd postfix/pickup[3268]: 63AE11D11B: uid=0
> from=<root> Oct 18 03:02:23 bsd postfix/cleanup[3576]: 63AE11D11B:
> message-id=<20071018100223.63AE11D11B at bsd.remdog.net>
> Oct 18 03:02:23 bsd postfix/qmgr[906]: 63AE11D11B:
> from=<root at bsd.remdog.net>, size=2038, nrcpt=1 (queue active)
> Oct 18 03:02:23 bsd postfix/local[3589]: 63AE11D11B:
> to=<rem at bsd.remdog.net>, orig_to=<root>, relay=local, delay=0.08,
> delays=0.02/0.01/0/0.05, dsn=2.0.0, status=sent (delivered to command:
> IFS=' ' && exec /usr/local/bin/procmail -f- || exit 75 #rem)
> Oct 18 03:02:23 bsd postfix/qmgr[906]: 63AE11D11B: removed
> Oct 18 03:02:23 bsd postfix/local[3588]: 582321D119:
> to=<rem at bsd.remdog.net>, orig_to=<root>, relay=local, delay=0.17,
> delays=0.09/0.01/0/0.07, dsn=2.0.0, status=sent (delivered to command:
> IFS=' ' && exec /usr/local/bin/procmail -f- || exit 75 #rem)
> Oct 18 03:02:23 bsd postfix/qmgr[906]: 582321D119: removed
> Oct 18 03:16:21 bsd postfix/pickup[3268]: 4F6211D119: uid=0
> from=<root> Oct 18 03:16:21 bsd postfix/trivial-rewrite[16064]:
> warning: database /usr/local/etc/postfix/transport.db is older than
> source file /usr/local/etc/postfix/transport
> Oct 18 03:16:21 bsd postfix/cleanup[16063]: 4F6211D119:
> message-id=<20071018101621.4F6211D119 at bsd.remdog.net>
> Oct 18 03:16:21 bsd postfix/qmgr[906]: 4F6211D119:
> from=<root at bsd.remdog.net>, size=562, nrcpt=1 (queue active)
> Oct 18 03:16:21 bsd postfix/local[16065]: 4F6211D119:
> to=<rem at bsd.remdog.net>, orig_to=<root>, relay=local, delay=75,
> delays=75/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command:
> IFS=' ' && exec /usr/local/bin/procmail -f- || exit 75 #rem)
> Oct 18 03:16:21 bsd postfix/qmgr[906]: 4F6211D119: removed
> Oct 18 08:03:12 bsd postfix/postfix-script[17269]: stopping the
> Postfix mail system
> Oct 18 08:03:12 bsd postfix/master[894]: terminating on signal 15
> Oct 18 08:03:12 bsd postfix/postfix-script[17290]: fatal: the Postfix
> mail system is not running
> Oct 18 08:04:42 bsd postfix/postfix-script[894]: starting the Postfix
> mail system
> Oct 18 08:04:42 bsd postfix/master[895]: daemon started -- version
> 2.4.5, configuration /usr/local/etc/postfix
> Oct 18 08:04:42 bsd postfix/pickup[902]: B2EE61D131: uid=0 from=<root>
> Oct 18 08:04:42 bsd postfix/trivial-rewrite[919]: warning: database
> /usr/local/etc/postfix/transport.db is older than source file
> /usr/local/etc/postfix/transport
> Oct 18 08:04:42 bsd postfix/cleanup[914]: B2EE61D131:
> message-id=<20071018150442.B2EE61D131 at bsd.remdog.net>
> Oct 18 08:04:42 bsd postfix/qmgr[903]: B2EE61D131:
> from=<root at bsd.remdog.net>, size=701, nrcpt=1 (queue active)
> Oct 18 08:04:44 bsd postfix/postfix-script[943]: fatal: the Postfix
> mail system is already running
> Oct 18 08:04:45 bsd postfix/postfix-script[982]: fatal: the Postfix
> mail system is already running
> Oct 18 08:05:04 bsd postfix/local[921]: B2EE61D131:
> to=<rem at bsd.remdog.net>, orig_to=<rem>, relay=local, delay=23,
> delays=1.2/0.09/0/21, dsn=2.0.0, status=sent (delivered to command:
> IFS=' ' && exec /usr/local/bin/procmail -f- || exit 75 #rem)
> Oct 18 08:05:04 bsd postfix/qmgr[903]: B2EE61D131: removed
> Oct 18 14:28:15 bsd postfix/pickup[2602]: 9D9EF1D0C6: uid=1001
> from=<rem>
> Oct 18 14:28:15 bsd postfix/trivial-rewrite[2976]: warning: database
> /usr/local/etc/postfix/transport.db is older than source file
> /usr/local/etc/postfix/transport
> Oct 18 14:28:15 bsd postfix/cleanup[2975]: 9D9EF1D0C6:
> message-id=<20071018212815.GA2634 at remdog.net>
> Oct 18 14:28:15 bsd postfix/qmgr[903]: 9D9EF1D0C6:
> from=<rem at bsd.remdog.net>, size=2780, nrcpt=2 (queue active)
> Oct 18 14:28:16 bsd postfix/smtp[2977]: 9D9EF1D0C6:
> to=<mutt-users at mutt.org>, relay=smtp.comcast.net[204.127.225.17]:25,
> delay=1.1, delays=0.03/0.08/0.41/0.57, dsn=2.0.0, status=sent (250
> ok ; id=20071018212731b1100331cce)
> Oct 18 14:28:16 bsd postfix/smtp[2977]: 9D9EF1D0C6:
> to=<eyolf at oestrem.com>, relay=smtp.comcast.net[204.127.225.17]:25,
> delay=1.1, delays=0.03/0.08/0.41/0.57, dsn=2.0.0, status=sent (250
> ok ; id=20071018212731b1100331cce)
> Oct 18 14:28:16 bsd postfix/qmgr[903]: 9D9EF1D0C6: removed

First off, you should postmap your transport file to get rid of the
warning that is seen. Also, that warning tells me you may possibly
have an issue with your server keeping time. You should verify your
time has been set correctly on the server and something enabled that
keeps the time accurate (i.e. ntpd).

What does the relevant Postfix startup parameters look like
in /etc/rc.conf? Have you also totally disabled SendMail according
to /usr/ports/mail/postfix/pkg-message?

You might want to subscribe and post your issue on the Postfix list:

  http://www.postfix.org/lists.html

------
  _|_
 (_| |