> On 19/01/2026 10:02 EET Christophe Lohr via dovecot <[email protected]> > 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 _______________________________________________ dovecot mailing list -- [email protected] To unsubscribe send an email to [email protected]
