Cool ok, so here is a log of information when I save an email to drafts that has no attachments
Jul 20 14:54:31 auth: Debug: auth client connected (pid=2104)
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x10, ret=1: before SSL
initialization
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: before SSL
initialization
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2002, ret=-1: before SSL
initialization
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2002, ret=-1: before SSL
initialization
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: before SSL
initialization
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS read
client hello
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS
write server hello
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS
write change cipher spec
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: TLSv1.3 write
encrypted extensions
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS
write certificate
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: TLSv1.3 write
server certificate verify
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS
write finished
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: TLSv1.3 early
data
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2002, ret=-1: TLSv1.3 early
data
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2002, ret=-1: TLSv1.3 early
data
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2002, ret=-1: TLSv1.3 early
data
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2002, ret=-1: TLSv1.3 early
data
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: TLSv1.3 early
data
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS read
finished
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x20, ret=1: SSLv3/TLS write
session ticket
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS
write session ticket
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS
write session ticket
Jul 20 14:54:31 imap-login: Debug: SSL: where=0x2002, ret=1: SSL
negotiation finished successfully
Jul 20 14:54:31 auth: Debug: client in: AUTH 1 PLAIN service=imap
secured=tls session=8NppU+wAq8VfWumz lip=207.180.242.208 rip=95.90.233.179 lport=143 rport=50603 real_lip=10.38.0.18 real_rip=10.40.0.0 real_rport=60316 local_name=imap.mydomain.com ssl_cipher=TLS_AES_128_GCM_SHA256 ssl_cipher_bits=128 ssl_pfs=KxANY ssl_protocol=TLSv1.3
Jul 20 14:54:31 auth: Debug: client passdb out: CONT 1
Jul 20 14:54:32 auth: Debug: client in: CONT 1 (SECRET BASE64 THINGY)
(previous base64 data may contain sensitive data)
Jul 20 14:54:32 auth-worker(2065): Debug: sql(myuser@mydomain.com,95.90.233.179,<8NppU+wAq8VfWumz>):
query: select u.username, d.name as domain, u.password, concat('*:bytes=', quota_limit_bytes) AS userdb_quota_rule from virtual_users as u inner join virtual_domains as d on d.id = u.domain_id where u.is_active = 'Y' and u.username='myuser' and d.name='mydomain.com'
Jul 20 14:54:32 auth: Debug: client passdb out: OK 1 user=
myuser@mydomain.com
Jul 20 14:54:32 auth: Debug: master in: REQUEST 933232641 2104 1
d92abd773ab27d7f296ca9e28d6d20c9 session_pid=2105 request_auth_token
Jul 20 14:54:32 auth: Debug: prefetch(myuser@mydomain.com,95.90.233.179,<8NppU+wAq8VfWumz>):
success
Jul 20 14:54:32 auth: Debug: master userdb out: USER 933232641
myuser@mydomain.com quota_rule=*:bytes=0 auth_token=ecc46a3e8110c8878e4ecc6bf3a6b0031d9d2cc4
Jul 20 14:54:32 imap-login: Info: Login: user=myuser@mydomain.com,
method=PLAIN, rip=95.90.233.179, lip=207.180.242.208, mpid=2105, TLS, session=<8NppU+wAq8VfWumz>
Jul 20 14:54:32 imap(myuser@mydomain.com)<2105><8NppU+wAq8VfWumz>: Debug:
Loading modules from directory: /usr/lib/dovecot/modules
Jul 20 14:54:32 imap(myuser@mydomain.com)<2105><8NppU+wAq8VfWumz>: Debug:
Module loaded: /usr/lib/dovecot/modules/lib20_zlib_plugin.so
Jul 20 14:54:32 imap(myuser@mydomain.com)<2105><8NppU+wAq8VfWumz>: Debug:
Added userdb setting: plugin/quota_rule=*:bytes=0
Jul 20 14:54:32 imap(myuser@mydomain.com)<2105><8NppU+wAq8VfWumz>: Debug:
Effective uid=8, gid=8, home=/mail/mydomain.com/myuser
Jul 20 14:54:32 imap(myuser@mydomain.com)<2105><8NppU+wAq8VfWumz>: Debug:
Namespace inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/mail/mydomain.com/myuser
Jul 20 14:54:32 imap(myuser@mydomain.com)<2105><8NppU+wAq8VfWumz>: Debug:
maildir++: root=/mail/mydomain.com/myuser, index=, indexpvt=, control=, inbox=/mail/mydomain.com/myuser, alt=
Jul 20 14:54:32 imap(myuser@mydomain.com)<2105><8NppU+wAq8VfWumz>: Debug:
Mailbox Drafts: Mailbox opened because: SELECT
Jul 20 14:54:32 imap(myuser@mydomain.com)<2105><8NppU+wAq8VfWumz>: Debug:
Mailbox Drafts: saving UID 0: Opened mail because: mail stream
and here is the log entries that are generated when I close the window, write a new email, same to address, subject=save to drafts with attachment, body=hello world. Then I hit the attach button, select a random pdf from my computer, hit the save button, just like I did with the email that had no attachments and I get this instead
Jul 20 15:06:20 auth: Debug: auth client connected (pid=2180)
Jul 20 15:06:20 imap-login: Debug: SSL: where=0x10, ret=1: before SSL initialization Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: before SSL initialization Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2002, ret=-1: before SSL initialization Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2002, ret=-1: before SSL initialization Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: before SSL initialization Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS read client hello Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS write server hello Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS write change cipher spec Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: TLSv1.3 write encrypted extensions Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS write certificate Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: TLSv1.3 write server certificate verify Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS write finished Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: TLSv1.3 early data Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2002, ret=-1: TLSv1.3 early data Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2002, ret=-1: TLSv1.3 early data Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2002, ret=-1: TLSv1.3 early data Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2002, ret=-1: TLSv1.3 early data Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: TLSv1.3 early data Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS read finished Jul 20 15:06:20 imap-login: Debug: SSL: where=0x20, ret=1: SSLv3/TLS write session ticket Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS write session ticket Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2001, ret=1: SSLv3/TLS write session ticket Jul 20 15:06:20 imap-login: Debug: SSL: where=0x2002, ret=1: SSL negotiation finished successfully Jul 20 15:06:20 auth: Debug: client in: AUTH 1 PLAIN service=imap secured=tls session=7W6sfewAb8VfWumz lip=207.180.242.208 rip=95.90.233.179 lport=143 rport=50543 real_lip=10.38.0.18 real_rip=10.40.0.0 real_rport=40242 local_name=imap.mydomain.com ssl_cipher=TLS_AES_128_GCM_SHA256 ssl_cipher_bits=128 ssl_pfs=KxANY ssl_protocol=TLSv1.3 Jul 20 15:06:20 auth: Debug: client passdb out: CONT 1 Jul 20 15:06:21 auth: Debug: client in: CONT 1 (SECRET BASE64 THINGY) (previous base64 data may contain sensitive data) Jul 20 15:06:21 auth-worker(2161): Debug: sql(myuser@mydomain.com,95.90.233.179,<7W6sfewAb8VfWumz>): query: select u.username, d.name as domain, u.password, concat('*:bytes=', quota_limit_bytes) AS userdb_quota_rule from virtual_users as u inner join virtual_domains as d on d.id = u.domain_id where u.is_active = 'Y' and u.username='myuser' and d.name='mydomain.com' Jul 20 15:06:21 auth: Debug: client passdb out: OK 1 user= myuser@mydomain.com Jul 20 15:06:21 auth: Debug: master in: REQUEST 3522953217 2180 1 467d12a6b0e9167d87e1cb1534b7a0f7 session_pid=2181 request_auth_token Jul 20 15:06:21 auth: Debug: prefetch(myuser@mydomain.com,95.90.233.179,<7W6sfewAb8VfWumz>): success Jul 20 15:06:21 auth: Debug: master userdb out: USER 3522953217 myuser@mydomain.com quota_rule=*:bytes=0 auth_token=a045a382574201aebfe9c9fa19540280369e614a Jul 20 15:06:21 imap-login: Info: Login: user=myuser@mydomain.com, method=PLAIN, rip=95.90.233.179, lip=207.180.242.208, mpid=2181, TLS, session=<7W6sfewAb8VfWumz> Jul 20 15:06:21 imap(myuser@mydomain.com)<2181><7W6sfewAb8VfWumz>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Jul 20 15:06:21 imap(myuser@mydomain.com)<2181><7W6sfewAb8VfWumz>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_zlib_plugin.so Jul 20 15:06:21 imap(myuser@mydomain.com)<2181><7W6sfewAb8VfWumz>: Debug: Added userdb setting: plugin/quota_rule=*:bytes=0 Jul 20 15:06:21 imap(myuser@mydomain.com)<2181><7W6sfewAb8VfWumz>: Debug: Effective uid=8, gid=8, home=/mail/mydomain.com/myuser Jul 20 15:06:21 imap(myuser@mydomain.com)<2181><7W6sfewAb8VfWumz>: Debug: Namespace inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/mail/mydomain.com/myuser Jul 20 15:06:21 imap(myuser@mydomain.com)<2181><7W6sfewAb8VfWumz>: Debug: maildir++: root=/mail/mydomain.com/myuser, index=, indexpvt=, control=, inbox=/mail/mydomain.com/myuser, alt= Jul 20 15:06:21 imap(myuser@mydomain.com)<2181><7W6sfewAb8VfWumz>: Debug: Mailbox Drafts: Mailbox opened because: SELECT
In the thunderbird client, I wait for ages before a popup appears saying "Your draft message was not copied to your drafts folder (Drafts) due to network or file access errors. You can retry or save the draft locally to Local Folders"
I've tried searching around for information on what the problem could be, but I've not found anything that would explain this problem. Have any ideas?
Chris
On Thu, Jul 20, 2023 at 3:20 PM William Edwards wedwards@cyberfusion.nl wrote:
Op 20 jul. 2023 om 14:26 heeft Chris Thomas chris.alex.thomas@gmail.com het volgende geschreven:
Hi,
I'm getting a curious problem where if I write a draft without an attachment and click save. It'll work without any issue at all.
But if I do the same, then attach a file to the email, it'll sit there for a couple of minutes before timing out (I'm using thunderbird), it'll eventually give you a message saying
"Your draft message was not copied to your drafts folder (Drafts) due to network or file access errors."
I've got all of dovecots verbose logging turned on.
Cool! So … where is it?
I'm using dovecot as a submission server through to the postfix server to do the actual sending. All the logging is turned on there too. But I can't figure out what the problem is.
Is there anything I can look for in the logs that will help me out?
chris
dovecot mailing list -- dovecot@dovecot.org To unsubscribe send an email to dovecot-leave@dovecot.org