2 msgpostmap header_checks error

mailbox_command (dovecot lda) stopped working, ...
\ falz (24 Jan 2008)
. \ falz (24 Jan 2008)
. . \ Victor Duchovni (24 Jan 2008)
. . . \ falz (24 Jan 2008)
. . . . \ Victor Duchovni (24 Jan 2008)
. \ (Wietse Venema) (24 Jan 2008)
. . \ falz (27 Jan 2008)
. . . \ Charles Marcus (27 Jan 2008)
. . . . \ falz (27 Jan 2008)
. . . \ (Wietse Venema) (27 Jan 2008)

5 msgmynetworks via LDAP lookup
7 msgLocal recipient table in LDAP problem
2 msgproxy_read_maps
1 msgsmtpd_restriction_class and it's lookup
8 msgMessage Rejected
12 msgCustomize to filter attachment ?
2 msgRelay question
14 msgunnecessary routing?
4 msgdo not use 'reject_maps_rbl'?
9 msgFwd: Postfix in OpenVZ.
2 msgbypass body_checks for local mail?
4 msgcatchall with relay_recipient_maps
4 msg'Name server failure' is output
3 msgsmtp_sasl_password_maps lookups
16 msgPostfix Queues on a Ram Disk
15 msgsendmail compared to smtp sending
3 msgPostfix does not recognize remote emails
2 msgHow to disable SSLv2 on smtpd opportunistic TLS?
Subject:mailbox_command (dovecot lda) stopped working, always deferred
Group:Postfix-users
From:falz
Date:24 Jan 2008


 
Hello,

One server that was setup to use postfix (local users) and dovecot as
mailbox_command just decided to stop working recently. It's hard to
tell exactly where the issue is, and I'm trying to either find out
further debugging info, and to find out what a few postfix lines mean.

- Everything worked fine until one evening, it decided to stop. Any
incoming message would just get deferred:

3AF5C6FC4A7: to=<username>, relay=local, delay=0.15,
delays=0.09/0.01/0/0.04, dsn=4.3.0, status=deferred (temporary
failure)

- If I remove mailbox_command from the config, it works. However,
dovecot is there to run sieve scripts (vacation autoreply, etc), which
is the reason I'm using it.

The only debugging I can see that I can set in postfix is to give "-v"
to "local" in master.cf. Doing this gives (short snippet):


Jan 24 09:36:48 progressive postfix/local[94846]: set_eugid: euid 1001 egid 20
Jan 24 09:36:48 progressive postfix/local[94846]: set_eugid: euid 125 egid 125
Jan 24 09:36:48 progressive postfix/local[94846]: deliver_dotforward:
path /home/username/.forward expand_status 0 look_status -1
Jan 24 09:36:48 progressive postfix/local[94846]: deliver_mailbox[3]:
local username recip username exten deliver
username exp_from
Jan 24 09:36:48 progressive postfix/local[94846]: been_here: mailbox username: 0
Jan 24 09:36:48 progressive postfix/local[94846]: deliver_mailbox[3]:
set user_attr: username
Jan 24 09:36:48 progressive postfix/local[94846]: deliver_command[4]:
local username recip username exten deliver
username exp_from
Jan 24 09:36:48 progressive postfix/local[94846]: been_here: command
username:1001 /usr/local/libexec/dovecot/deliver: 0
Jan 24 09:36:48 progressive postfix/local[94846]: connect to subsystem
private/defer
Jan 24 09:36:48 progressive postfix/local[94846]: send attr nrequest = 0
Jan 24 09:36:48 progressive postfix/local[94846]: send attr flags = 0
Jan 24 09:36:48 progressive postfix/local[94846]: send attr queue_id =
3AF5C6FC4A7
Jan 24 09:36:48 progressive postfix/local[94846]: send attr
original_recipient = username
Jan 24 09:36:48 progressive postfix/local[94846]: send attr recipient
= username
Jan 24 09:36:48 progressive postfix/local[94846]: send attr offset = 214
Jan 24 09:36:48 progressive postfix/local[94846]: send attr dsn_orig_rcpt =
Jan 24 09:36:48 progressive postfix/local[94846]: send attr notify_flags = 0
Jan 24 09:36:48 progressive postfix/local[94846]: send attr status = 4.3.0
Jan 24 09:36:48 progressive postfix/local[94846]: send attr diag_type = x-unix
Jan 24 09:36:48 progressive postfix/local[94846]: send attr diag_text
= temporary failure
Jan 24 09:36:48 progressive postfix/local[94846]: send attr mta_type =
Jan 24 09:36:48 progressive postfix/local[94846]: send attr mta_mname =
Jan 24 09:36:48 progressive postfix/local[94846]: send attr action = delayed
Jan 24 09:36:48 progressive postfix/local[94846]: send attr reason =
temporary failure
Jan 24 09:36:48 progressive postfix/local[94846]: private/defer
socket: wanted attribute: status


So, is it truly calling "/usr/local/libexec/dovecot/deliver"? I can
run that command from the commandline with the uid/gid it says its
switching to. More importantly, that task is set to log, and it does
NOT log anything when the above happens. It DOES log if I run it from
the command line, and DID log until it decided to stop working.

I know the answer is that something has changed on my system, but I'm
trying to figure out exactly where that somethign is. The key
quiestion is is there any further debugging I can do besides "local
-v"? Any reason why it says it's going to deferred, or any true output
I can get when postfix calls the deliver command?

Thanks,
falz


Also, here's postconf -n:

command_directory = /usr/local/sbin
config_directory = /usr/local/etc/postfix
daemon_directory = /usr/local/libexec/postfix
debug_peer_level = 2
home_mailbox = Maildir/
html_directory = no
mail_owner = postfix
mailbox_command = /usr/local/libexec/dovecot/deliver
mailq_path = /usr/local/bin/mailq
manpage_directory = /usr/local/man
mydestination = $myhostname, localhost.$mydomain, localhost, $mydomain
myhostname = mail.example.com
mynetworks = 127.0.0.0/8, 10.170.10.152/29, 10.170.18.160/29
mynetworks_style = host
newaliases_path = /usr/local/bin/newaliases
queue_directory = /var/spool/postfix
readme_directory = no
sample_directory = /usr/local/etc/postfix
sendmail_path = /usr/local/sbin/sendmail
setgid_group = maildrop
smtp_tls_cert_file = /usr/local/etc/postfix/certs/example.com.crt
smtp_tls_key_file = /usr/local/etc/postfix/certs/example.com.key
smtp_tls_note_starttls_offer = yes
smtpd_client_restrictions = check_client_access
hash:/usr/local/etc/postfix/access
smtpd_recipient_restrictions = permit_mynetworks,
permit_sasl_authenticated, reject_unauth_destination
smtpd_sasl_auth_enable = yes
smtpd_sasl_local_domain = $mydomain
smtpd_sasl_path = private/auth
smtpd_sasl_type = dovecot
smtpd_tls_CAfile = /usr/local/etc/postfix/certs/gd_intermediate_bundle.crt
smtpd_tls_loglevel = 3
smtpd_tls_received_header = yes
smtpd_tls_session_cache_timeout = 3600s
smtpd_use_tls = yes
tls_random_source = dev:/dev/urandom
unknown_local_recipient_reject_code = 550


© 2004-2008 readlist.com