On 19/01/2026 10:02 EET Christophe Lohr via dovecot <dovecot@dovecot.org> wrote:
Le 16/01/2026 à 21:56, John Fawcett via dovecot a écrit :
Maybe this should be
protocol lmtp { auth_username_format = %{user | lower} }
Great, thankyouverymuch! Authenticationseemstobeworkingfinenow.
Thingsareprogressingwell... but... Istillhaveoneproblem: thereisnodelivery.
WhichdirectionshouldIlook?
postqueue -p -Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient------- 7496C880028 7846 Mon Jan 19 08:48:06 bob7@my_domain.org (lost connection with pc-serveur.my_domain.org[private/dovecot-lmtp] while sending end of data -- message may be sent more than once) alice7@my_domain.org
1F8D38800E7 7846 Mon Jan 19 08:21:42 bob7@my_domain.org (lost connection with pc-serveur.my_domain.org[private/dovecot-lmtp] while sending end of data -- message may be sent more than once) alice7@my_domain.org
-- 15 Kbytes in 2 Requests.
Postfix logs:
2026-01-19T08:48:06.446455+01:00 pc-serveur postfix/smtpd[2476]: connect from linux7.my_domain.org[192.168.100.17] 2026-01-19T08:48:06.477701+01:00 pc-serveur postfix/smtpd[2476]: 7496C880028: client=linux7.my_domain.org[192.168.100.17], sasl_method=PLAIN, sasl_username=bob7@my_domain.org 2026-01-19T08:48:06.482176+01:00 pc-serveur postfix/cleanup[2480]: 7496C880028: message-id=<89c5190c-0cf6-4d9d-86d9-bd92070cfa87@my_domain.org> 2026-01-19T08:48:06.483278+01:00 pc-serveur postfix/qmgr[1721]: 7496C880028: from=<bob7@my_domain.org>, size=7846, nrcpt=1 (queue active) 2026-01-19T08:48:06.510492+01:00 pc-serveur postfix/lmtp[2481]: 7496C880028: to=<alice7@my_domain.org>, relay=pc-serveur.my_domain.org[private/dovecot-lmtp], delay=0.04, delays=0.02/0.01/0.01/0, dsn=4.4.2, status=deferred (lost connection with pc-serveur.my_domain.org[private/dovecot-lmtp] while sending end of data -- message may be sent more than once) 2026-01-19T08:48:11.487184+01:00 pc-serveur postfix/smtpd[2476]: disconnect from linux7.my_domain.org[192.168.100.17] ehlo=1 auth=1 mail=1 rcpt=1 data=1 quit=1 commands=6
Dovecot logs:
Jan 19 08:48:06 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: Server accepted connection (fd=22) Jan 19 08:48:06 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: auth client connected (pid=0) Jan 19 08:48:06 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: client in: AUTH 1 PLAIN service=smtp nologin lip=192.168.100.1 rip=192.168.100.17 resp=AGJvYjdAdHAtcmVzZWF1eC5lbnN0Yi5vcmcAYm9iN3NlY3JldA== (previous base64 data may contain sensitive data) Jan 19 08:48:06 auth(bob7@my_domain.org,192.168.100.17,sasl:plain): Debug: passwd-file: Performing passdb lookup Jan 19 08:48:06 auth(bob7@my_domain.org,192.168.100.17,sasl:plain): Debug: passwd-file: lookup: user=bob7@my_domain.org file=/etc/dovecot/passwd Jan 19 08:48:06 auth(bob7@my_domain.org,192.168.100.17,sasl:plain): Debug: passwd-file: Finished passdb lookup Jan 19 08:48:06 auth(bob7@my_domain.org,192.168.100.17,sasl:plain): Debug: Auth request finished Jan 19 08:48:06 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: client passdb out: OK 1 user=bob7@my_domain.org Jan 19 08:48:06 lmtp(2482): Info: Connect from local Jan 19 08:48:06 auth: Debug: conn unix:/run/dovecot/auth-userdb (pid=2482,uid=118): Server accepted connection (fd=24) Jan 19 08:48:06 auth: Debug: master in: USER 1 alice7@my_domain.org protocol=lmtp Jan 19 08:48:06 auth(alice7@my_domain.org): Debug: passwd-file: Performing userdb lookup Jan 19 08:48:06 auth(alice7@my_domain.org): Debug: passwd-file: lookup: user=alice7@my_domain.org file=/etc/dovecot/passwd Jan 19 08:48:06 auth(alice7@my_domain.org): Debug: passwd-file: Finished userdb lookup Jan 19 08:48:06 auth: Debug: userdb out: USER 1 alice7@my_domain.org uid=2222 gid=2222 home=/var/spool/vmail/alice7@my_domain.org Jan 19 08:48:06 auth: Debug: conn unix:/run/dovecot/auth-userdb (pid=2482,uid=118): auth-master client: Disconnected: Connection closed (fd=24) (created 2 msecs ago, handshake 2 msecs ago) Jan 19 08:49:51 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: Disconnected: Connection closed (fd=22)
Many thanks ! Regards, Christophe
Le 16/01/2026 `a 21:56, John Fawcett via dovecot a ecrit :
Maybe this should be protocol lmtp { auth_username_format = %{user | lower} }Great, thank you very much! Authentication seems to be working fine now. Things are progressing well... but... I still have one problem: there is no delivery.
Which direction should I look?
postqueue -p -Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient------- 7496C880028 7846 Mon Jan 19 08:48:06 [1]bob7@my_domain.org (lost connection with pc-serveur.my_domain.org[private/dovecot-lmtp] while sending end of data -- message may be sent more than once) [2]alice7@my_domain.org
1F8D38800E7 7846 Mon Jan 19 08:21:42 [3]bob7@my_domain.org (lost connection with pc-serveur.my_domain.org[private/dovecot-lmtp] while sending end of data -- message may be sent more than once) [4]alice7@my_domain.org
-- 15 Kbytes in 2 Requests.
Postfix logs:
2026-01-19T08:48:06.446455+01:00 pc-serveur postfix/smtpd[2476]: connect from linux7.my_domain.org[192.168.100.17] 2026-01-19T08:48:06.477701+01:00 pc-serveur postfix/smtpd[2476]: 7496C880028: client=linux7.my_domain.org[192.168.100.17], sasl_method=PLAIN, [5]sasl_username=bob7@my_domain.org 2026-01-19T08:48:06.482176+01:00 pc-serveur postfix/cleanup[2480]: 7496C880028: message-id=[6]<89c5190c-0cf6-4d9d-86d9-bd92070cfa87@my_domain.org> 2026-01-19T08:48:06.483278+01:00 pc-serveur postfix/qmgr[1721]: 7496C880028: from=[7]<bob7@my_domain.org>, size=7846, nrcpt=1 (queue active) 2026-01-19T08:48:06.510492+01:00 pc-serveur postfix/lmtp[2481]: 7496C880028: to=[8]<alice7@my_domain.org>, relay=pc-serveur.my_domain.org[private/dovecot-lmtp], delay=0.04, delays=0.02/0.01/0.01/0, dsn=4.4.2, status=deferred (lost connection with pc-serveur.my_domain.org[private/dovecot-lmtp] while sending end of data -- message may be sent more than once) 2026-01-19T08:48:11.487184+01:00 pc-serveur postfix/smtpd[2476]: disconnect from linux7.my_domain.org[192.168.100.17] ehlo=1 auth=1 mail=1 rcpt=1 data=1 quit=1 commands=6
Dovecot logs:
Jan 19 08:48:06 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: Server accepted connection (fd=22) Jan 19 08:48:06 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: auth client connected (pid=0) Jan 19 08:48:06 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: client in: AUTH 1 PLAIN service=smtp nologin lip=192.168.100.1 rip=192.168.100.17 resp=AGJvYjdAdHAtcmVzZWF1eC5lbnN0Yi5vcmcAYm9iN3NlY3JldA== (previous base64 data may contain sensitive data) Jan 19 08:48:06 auth([9]bob7@my_domain.org,192.168.100.17,sasl:plain): Debug: passwd-file: Performing passdb lookup Jan 19 08:48:06 auth([10]bob7@my_domain.org,192.168.100.17,sasl:plain): Debug: passwd-file: lookup: [11]user=bob7@my_domain.org file=/etc/dovecot/passwd Jan 19 08:48:06 auth([12]bob7@my_domain.org,192.168.100.17,sasl:plain): Debug: passwd-file: Finished passdb lookup Jan 19 08:48:06 auth([13]bob7@my_domain.org,192.168.100.17,sasl:plain): Debug: Auth request finished Jan 19 08:48:06 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: client passdb out: OK 1 [14]user=bob7@my_domain.org Jan 19 08:48:06 lmtp(2482): Info: Connect from local Jan 19 08:48:06 auth: Debug: conn unix:/run/dovecot/auth-userdb (pid=2482,uid=118): Server accepted connection (fd=24) Jan 19 08:48:06 auth: Debug: master in: USER 1 [15]alice7@my_domain.org protocol=lmtp Jan 19 08:48:06 auth([16]alice7@my_domain.org): Debug: passwd-file: Performing userdb lookup Jan 19 08:48:06 auth([17]alice7@my_domain.org): Debug: passwd-file: lookup: [18]user=alice7@my_domain.org file=/etc/dovecot/passwd Jan 19 08:48:06 auth([19]alice7@my_domain.org): Debug: passwd-file: Finished userdb lookup Jan 19 08:48:06 auth: Debug: userdb out: USER 1 [20]alice7@my_domain.org uid=2222 gid=2222 [21]home=/var/spool/vmail/alice7@my_domain.org Jan 19 08:48:06 auth: Debug: conn unix:/run/dovecot/auth-userdb (pid=2482,uid=118): auth-master client: Disconnected: Connection closed (fd=24) (created 2 msecs ago, handshake 2 msecs ago) Jan 19 08:49:51 auth: Debug: conn unix:auth (pid=2476,uid=122) [3]: Disconnected: Connection closed (fd=22)
Many thanks ! Regards, Christophe
Looks like the logs are missing all the useful things that would've hopefully come after this. The error is coming from lmtp, not auth.
Aki