[Dovecot] postfix + dspam + dovecot problem

[Lars Stavholm]

More investigation shows the following:

1) posfix + dovecot: works like a charm, extract from mail log:

Sep 11 10:08:02 nwinnie postfix/pickup[12904]: 0F2B4C03D: uid=1001
from=<stava>
Sep 11 10:08:02 nwinnie postfix/cleanup[20596]: 0F2B4C03D:
message-id=<20070911080802.0F2B4C03D at nwinnie.telcotec.se>
Sep 11 10:08:02 nwinnie postfix/qmgr[12905]: 0F2B4C03D:
from=<stava at telcotec.se>, size=436, nrcpt=1 (queue active)
Sep 11 10:08:02 nwinnie postfix/local[20598]: warning: dict_nis_init:
NIS domain name not set - NIS lookups disabled
Sep 11 10:08:02 nwinnie deliver(stava): Loading modules from directory:
/usr/lib/dovecot/modules/lda
Sep 11 10:08:02 nwinnie deliver(stava): Module loaded:
/usr/lib/dovecot/modules/lda/lib10_quota_plugin.so
Sep 11 10:08:02 nwinnie deliver(stava): Module loaded:
/usr/lib/dovecot/modules/lda/lib90_cmusieve_plugin.so
Sep 11 10:08:02 nwinnie deliver(stava): maildir: data=/home/stava/Mail
Sep 11 10:08:02 nwinnie deliver(stava): maildir: root=/home/stava/Mail,
index=/home/stava/Mail, control=, inbox=
Sep 11 10:08:02 nwinnie deliver(stava): fs quota add storage dir =
/home/stava/Mail
Sep 11 10:08:02 nwinnie deliver(stava): fs quota block device =
/dev/mapper/sys-home
Sep 11 10:08:02 nwinnie deliver(stava): fs quota mount point = /home
Sep 11 10:08:02 nwinnie deliver(stava):
msgid=<20070911080802.0F2B4C03D at nwinnie.telcotec.se>: saved mail to INBOX
Sep 11 10:08:02 nwinnie postfix/local[20598]: 0F2B4C03D:
to=<stava at nwinnie.telcotec.se>, orig_to=<stava>, relay=local,
delay=0.94, delays=0.2/0.01/0/0.73, dsn=2.0.0, status=sent (delivered to
command: /usr/lib/dovecot/deliver)
Sep 11 10:08:02 nwinnie postfix/qmgr[12905]: 0F2B4C03D: removed

2) postfix + dspam + dovecot: fails, extract from mail log:

Sep 11 10:10:48 nwinnie postfix/pickup[27124]: 612AEC03D: uid=1001
from=<stava>
Sep 11 10:10:48 nwinnie postfix/cleanup[27441]: 612AEC03D:
message-id=<20070911081048.612AEC03D at nwinnie.telcotec.se>
Sep 11 10:10:48 nwinnie postfix/qmgr[27126]: 612AEC03D:
from=<stava at telcotec.se>, size=436, nrcpt=1 (queue active)
Sep 11 10:10:48 nwinnie deliver(stava at nwinnie.telcotec.se): Loading
modules from directory: /usr/lib/dovecot/modules/lda
Sep 11 10:10:48 nwinnie deliver(stava at nwinnie.telcotec.se): Module
loaded: /usr/lib/dovecot/modules/lda/lib10_quota_plugin.so
Sep 11 10:10:48 nwinnie deliver(stava at nwinnie.telcotec.se): Module
loaded: /usr/lib/dovecot/modules/lda/lib90_cmusieve_plugin.so
Sep 11 10:10:48 nwinnie dovecot: auth(default): master in: USER 1
stava at nwinnie.telcotec.se       service=deliver
Sep 11 10:10:48 nwinnie dovecot: auth-worker(default):
passwd(stava at nwinnie.telcotec.se): lookup
Sep 11 10:10:48 nwinnie dovecot: auth-worker(default):
passwd(stava at nwinnie.telcotec.se): unknown user
Sep 11 10:10:48 nwinnie dovecot: auth(default): master out: NOTFOUND    1
Sep 11 10:10:48 nwinnie dspam[27127]: Delivery agent returned exit code
67: /usr/lib/dovecot/deliver -d stava at nwinnie.telcotec.se
Sep 11 10:10:48 nwinnie postfix/lmtp[27444]: 612AEC03D:
to=<stava at nwinnie.telcotec.se>, orig_to=<stava>,
relay=nwinnie.telcotec.se[/var/tmp/dspam.sock], delay=0.2,
delays=0.17/0.01/0.01/0.01, dsn=4.3.0, status=deferred (host
nwinnie.telcotec.se[/var/tmp/dspam.sock] said: 421 4.3.0
<stava at nwinnie.telcotec.se> Delivered (in reply to end of DATA command))

One big difference is that in case 1) the user being looked up
is "stava", whereas in case 2) it's "stava at domain", which fails.

"auth_username_format = %n" is set in dovecot.conf, but to no avail.

dovecot -n:
# 1.0.5: /etc/dovecot/dovecot.conf
base_dir: /var/run/dovecot/
protocols: imap imaps pop3 pop3s
ssl_disable: yes
disable_plaintext_auth: no
login_dir: /var/run/dovecot//login
login_executable(default): /usr/lib/dovecot/imap-login
login_executable(imap): /usr/lib/dovecot/imap-login
login_executable(pop3): /usr/lib/dovecot/pop3-login
mail_extra_groups: mail
mail_location: maildir:~/Mail
mail_debug: yes
mail_full_filesystem_access: yes
dotlock_use_excl: yes
maildir_copy_with_hardlinks: yes
mail_executable(default): /usr/lib/dovecot/imap
mail_executable(imap): /usr/lib/dovecot/imap
mail_executable(pop3): /usr/lib/dovecot/pop3
mail_plugins: quota trash
mail_plugin_dir(default): /usr/lib/dovecot/modules/imap
mail_plugin_dir(imap): /usr/lib/dovecot/modules/imap
mail_plugin_dir(pop3): /usr/lib/dovecot/modules/pop3
imap_client_workarounds(default): delay-newmail outlook-idle
imap_client_workarounds(imap): delay-newmail outlook-idle
imap_client_workarounds(pop3): outlook-idle
pop3_uidl_format(default):
pop3_uidl_format(imap):
pop3_uidl_format(pop3): %v.%u
pop3_client_workarounds(default):
pop3_client_workarounds(imap):
pop3_client_workarounds(pop3): outlook-no-nuls oe-ns-eoh
auth default:
  mechanisms: plain login gssapi
  username_format: %n
  verbose: yes
  debug: yes
  debug_passwords: yes
  passdb:
    driver: pam
    args: blocking=yes dovecot
  userdb:
    driver: passwd
    args: blocking=yes
  socket:
    type: listen
    master:
      path: /var/run/dovecot/auth-master
      mode: 384
plugin:
  quota: fs:storage=102400:messages=0
  trash: /etc/dovecot/trash.conf
  sieve: .dovecot.sieve

Any ideas appreciated

Cheers
/Lars