Sure, thanks for the reply and suggestion
To make testing/debugging easier, rather than using a remote provider, I created another domain and email account on the same server with just a few mails. I sent 3 jibberish mails to this new account, from this new account, so they are showing in both INBOX and Sent, as well as 1 mail from root on the CLI (mail -v
wah@hellodemo.ppl), These is also a draft mail saved in Drafts:
Source account file system layout:
# find /home/hellodemo/mail/hellodemo.ppl/wah/
/home/hellodemo/mail/hellodemo.ppl/wah/
/home/hellodemo/mail/hellodemo.ppl/wah/.Drafts
/home/hellodemo/mail/hellodemo.ppl/wah/.Drafts/cur
/home/hellodemo/mail/hellodemo.ppl/wah/.Drafts/cur/1652465670.M627726P3692.cent-7.darren.cpanel.net,S=341,W=353:2,S
/home/hellodemo/mail/hellodemo.ppl/wah/.Drafts/new
/home/hellodemo/mail/hellodemo.ppl/wah/.Drafts/tmp
/home/hellodemo/mail/hellodemo.ppl/wah/.Drafts/dovecot.index.log
/home/hellodemo/mail/hellodemo.ppl/wah/.Drafts/dovecot-uidlist
/home/hellodemo/mail/hellodemo.ppl/wah/.Drafts/dovecot.index.cache
/home/hellodemo/mail/hellodemo.ppl/wah/.Junk
/home/hellodemo/mail/hellodemo.ppl/wah/.Junk/cur
/home/hellodemo/mail/hellodemo.ppl/wah/.Junk/new
/home/hellodemo/mail/hellodemo.ppl/wah/.Junk/tmp
/home/hellodemo/mail/hellodemo.ppl/wah/.Junk/dovecot.index.log
/home/hellodemo/mail/hellodemo.ppl/wah/.Junk/dovecot-uidlist
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/cur
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/cur/1652465920.M799279P3808.cent-7.darren.cpanel.net,S=359,W=371:2,S
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/cur/1652465942.M469746P3832.cent-7.darren.cpanel.net,S=342,W=354:2,S
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/cur/1652465951.M751062P3906.cent-7.darren.cpanel.net,S=342,W=354:2,S
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/new
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/tmp
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/dovecot.index.log
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/dovecot-uidlist
/home/hellodemo/mail/hellodemo.ppl/wah/.Sent/dovecot.index.cache
/home/hellodemo/mail/hellodemo.ppl/wah/.Trash
/home/hellodemo/mail/hellodemo.ppl/wah/.Trash/cur
/home/hellodemo/mail/hellodemo.ppl/wah/.Trash/new
/home/hellodemo/mail/hellodemo.ppl/wah/.Trash/tmp
/home/hellodemo/mail/hellodemo.ppl/wah/.Trash/dovecot.index.log
/home/hellodemo/mail/hellodemo.ppl/wah/.Trash/dovecot-uidlist
/home/hellodemo/mail/hellodemo.ppl/wah/cur
/home/hellodemo/mail/hellodemo.ppl/wah/cur/1652465917.M799363P3797.cent-7.darren.cpanel.net,S=928,W=956:2,
/home/hellodemo/mail/hellodemo.ppl/wah/cur/1652465920.M628557P3797.cent-7.darren.cpanel.net,S=969,W=998:2,
/home/hellodemo/mail/hellodemo.ppl/wah/cur/1652465942.M304779P3797.cent-7.darren.cpanel.net,S=952,W=981:2,
/home/hellodemo/mail/hellodemo.ppl/wah/cur/1652465951.M583486P3797.cent-7.darren.cpanel.net,S=952,W=981:2,
/home/hellodemo/mail/hellodemo.ppl/wah/new
/home/hellodemo/mail/hellodemo.ppl/wah/tmp
/home/hellodemo/mail/hellodemo.ppl/wah/maildirsize
/home/hellodemo/mail/hellodemo.ppl/wah/.spam
/home/hellodemo/mail/hellodemo.ppl/wah/.spam/cur
/home/hellodemo/mail/hellodemo.ppl/wah/.spam/new
/home/hellodemo/mail/hellodemo.ppl/wah/.spam/tmp
/home/hellodemo/mail/hellodemo.ppl/wah/.spam/maildirfolder
/home/hellodemo/mail/hellodemo.ppl/wah/.spam/dovecot.index.log
/home/hellodemo/mail/hellodemo.ppl/wah/.spam/dovecot-uidlist
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot.list.index.log
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot.index.log
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot-uidvalidity
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot-uidlist
/home/hellodemo/mail/hellodemo.ppl/wah/subscriptions
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot.mailbox.log
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot-acl-list
/home/hellodemo/mail/hellodemo.ppl/wah/.Archive
/home/hellodemo/mail/hellodemo.ppl/wah/.Archive/cur
/home/hellodemo/mail/hellodemo.ppl/wah/.Archive/new
/home/hellodemo/mail/hellodemo.ppl/wah/.Archive/tmp
/home/hellodemo/mail/hellodemo.ppl/wah/.Archive/maildirfolder
/home/hellodemo/mail/hellodemo.ppl/wah/.Archive/dovecot.index.log
/home/hellodemo/mail/hellodemo.ppl/wah/.Archive/dovecot-uidlist
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot-uidvalidity.627e9e14
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot-quota
/home/hellodemo/mail/hellodemo.ppl/wah/dovecot.index.cache
I rm -rf'd /home/letest/mail/letest.tld/saywhatnow/ to be sure it was "clean" then ran the sync -1R command with the additional raw log option:
# /usr/bin/doveadm -Dv -o mail_fsync=never -o dsync_commit_msgs_interval=100 -o imapc_features=rfc822.size,fetch-headers -o dsync_features=empty-header-workaround -o imapc_port=143 -o imapc_host=127.0.0.1 -o imapc_password=redacted -o imapc_user=
wah@hellodemo.ppl -o imapc_rawlog_dir=/tmp/raww -o mail_prefetch_count=20 sync -1Ru
saywhatnow@letest.tld imapc:
Debug: Loading modules from directory: /usr/lib64/dovecot/doveadm
Debug: Skipping module doveadm_acl_plugin, because dlopen() failed: /usr/lib64/dovecot/doveadm/lib10_doveadm_acl_plugin.so: undefined symbol: acl_user_module (this is usually intentional, so just ignore this message)
Debug: Skipping module doveadm_quota_plugin, because dlopen() failed: /usr/lib64/dovecot/doveadm/lib10_doveadm_quota_plugin.so: undefined symbol: quota_user_module (this is usually intentional, so just ignore this message)
Debug: Skipping module doveadm_fts_plugin, because dlopen() failed: /usr/lib64/dovecot/doveadm/lib20_doveadm_fts_plugin.so: undefined symbol: fts_user_get_language_list (this is usually intentional, so just ignore this message)
Debug: Skipping module doveadm_mail_crypt_plugin, because dlopen() failed: /usr/lib64/dovecot/doveadm/libdoveadm_mail_crypt_plugin.so: undefined symbol: mail_crypt_box_get_pvt_digests (this is usually intentional, so just ignore this message)
May 13 13:28:17 Debug: Loading modules from directory: /usr/lib64/dovecot
May 13 13:28:17 Debug: Module loaded: /usr/lib64/dovecot/lib10_quota_plugin.so
May 13 13:28:17 Debug: Module loaded: /usr/lib64/dovecot/lib20_quota_clone_plugin.so
May 13 13:28:17 Debug: Module loaded: /usr/lib64/dovecot/lib20_zlib_plugin.so
May 13 13:28:17 Debug: Loading modules from directory: /usr/lib64/dovecot/doveadm
May 13 13:28:17 Debug: Skipping module doveadm_acl_plugin, because dlopen() failed: /usr/lib64/dovecot/doveadm/lib10_doveadm_acl_plugin.so: undefined symbol: acl_user_module (this is usually intentional, so just ignore this message)
May 13 13:28:17 Debug: Module loaded: /usr/lib64/dovecot/doveadm/lib10_doveadm_quota_plugin.so
May 13 13:28:17 Debug: Skipping module doveadm_fts_plugin, because dlopen() failed: /usr/lib64/dovecot/doveadm/lib20_doveadm_fts_plugin.so: undefined symbol: fts_user_get_language_list (this is usually intentional, so just ignore this message)
May 13 13:28:17 Debug: Skipping module doveadm_mail_crypt_plugin, because dlopen() failed: /usr/lib64/dovecot/doveadm/libdoveadm_mail_crypt_plugin.so: undefined symbol: mail_crypt_box_get_pvt_digests (this is usually intentional, so just ignore this message)
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb: Connecting
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb (pid=792,uid=0): Client connected (fd=10)
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: auth-master: userdb lookup(
saywhatnow@letest.tld): auth USER input:
saywhatnow@letest.tld quota=maildir:Mailbox:ns=INBOX. quota_rule3=INBOX.Trash:ignore userdb_home=/home/letest/mail/letest.tld/saywhatnow userdb_quota2_grace=0 userdb_user=
saywhatnow@letest.tld userdb_quota_status_overquota=552 5.2.2 Mailbox is full / Blocks limit exceeded / Inode limit exceeded quota_rule=*:messages=2147483647 mail=maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8 userdb_quota_vsizes=yes gid=1003 quota_rule2=INBOX.INBOX:ignore quota_clone_dict=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota quota2=fs:cPanel Account userdb_gid=1003 userdb_quota_rule3=INBOX.Trash:ignore quota_vsizes=yes quota2_grace=0 quota_status_overquota=552 5.2.2 Mailbox is full / Blocks limit exceeded / Inode limit exceeded lda_mailbox_autocreate=no userdb_mail=maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8 uid=1001 password=<hidden> userdb_quota_rule2=INBOX.INBOX:ignore userdb_uid=1001 userdb_password=<hidden> userdb_lda_mailbox_autocreate=no userdb_quota_clone_dict=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota userdb_quota=maildir:Mailbox:ns=INBOX. userdb_quota_rule=*:messages=2147483647 home=/home/letest/mail/letest.tld/saywhatnow userdb_quota2=fs:cPanel Account
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: auth-master: userdb lookup(
saywhatnow@letest.tld): Finished userdb lookup (username=
saywhatnow@letest.tld quota=maildir:Mailbox:ns=INBOX. quota_rule3=INBOX.Trash:ignore userdb_home=/home/letest/mail/letest.tld/saywhatnow userdb_quota2_grace=0 userdb_user=
saywhatnow@letest.tld userdb_quota_status_overquota=552 5.2.2 Mailbox is full / Blocks limit exceeded / Inode limit exceeded quota_rule=*:messages=2147483647 mail=maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8 userdb_quota_vsizes=yes gid=1003 quota_rule2=INBOX.INBOX:ignore quota_clone_dict=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota quota2=fs:cPanel Account userdb_gid=1003 userdb_quota_rule3=INBOX.Trash:ignore quota_vsizes=yes quota2_grace=0 quota_status_overquota=552 5.2.2 Mailbox is full / Blocks limit exceeded / Inode limit exceeded lda_mailbox_autocreate=no userdb_mail=maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8 uid=1001 password={CRYPT}$1$mmnope userdb_quota_rule2=INBOX.INBOX:ignore userdb_uid=1001 userdb_password={CRYPT}$1$mmnope userdb_lda_mailbox_autocreate=no userdb_quota_clone_dict=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota userdb_quota=maildir:Mailbox:ns=INBOX. userdb_quota_rule=*:messages=2147483647 home=/home/letest/mail/letest.tld/saywhatnow userdb_quota2=fs:cPanel Account)
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/lda_mailbox_autocreate=no
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: mail=maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/password=<hidden>
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota=maildir:Mailbox:ns=INBOX.
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota2=fs:cPanel Account
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota2_grace=0
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota_clone_dict=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota_rule=*:messages=2147483647
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota_rule2=INBOX.INBOX:ignore
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota_rule3=INBOX.Trash:ignore
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota_status_overquota=552 5.2.2 Mailbox is full / Blocks limit exceeded / Inode limit exceeded
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/quota_vsizes=yes
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_gid=1003
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_home=/home/letest/mail/letest.tld/saywhatnow
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_lda_mailbox_autocreate=no
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_mail=maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_password=<hidden>
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota=maildir:Mailbox:ns=INBOX.
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota2=fs:cPanel Account
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota2_grace=0
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota_clone_dict=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota_rule=*:messages=2147483647
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota_rule2=INBOX.INBOX:ignore
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota_rule3=INBOX.Trash:ignore
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota_status_overquota=552 5.2.2 Mailbox is full / Blocks limit exceeded / Inode limit exceeded
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_quota_vsizes=yes
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><>: Debug: Added userdb setting: plugin/userdb_uid=1001
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Effective uid=1001, gid=1003, home=/home/letest/mail/letest.tld/saywhatnow
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Home dir not found: /home/letest/mail/letest.tld/saywhatnow
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Quota root: name=Mailbox backend=maildir args=ns=INBOX.
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Quota rule: root=Mailbox mailbox=* bytes=0 messages=2147483647
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Quota rule: root=Mailbox mailbox=INBOX.INBOX ignored
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Quota rule: root=Mailbox mailbox=INBOX.Trash ignored
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Quota grace: root=Mailbox bytes=0 (10%)
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Quota root: name=cPanel Account backend=fs args=
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Quota grace: root=cPanel Account bytes=0
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: dict(file): dict created (uri=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota, base_dir=/var/run/dovecot)
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Namespace inbox: type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: maildir++: root=/home/letest/mail/letest.tld/saywhatnow, index=, indexpvt=, control=, inbox=/home/letest/mail/letest.tld/saywhatnow, alt=
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt=
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping
May 13 13:28:17 doveadm(
saywhatnow@letest.tld)<4045><lwqpK0GjfmLNDwAAU3wD4Q>: Debug: quota: quota_over_flag check: quota2_over_script unset - skipping
May 13 13:28:17 doveadm(
saywhatnow@letest.tld): Debug: brain M: Namespace INBOX. has location maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8
May 13 13:28:17 doveadm(
saywhatnow@letest.tld): Debug: Namespace INBOX.: /home/letest/mail/letest.tld/saywhatnow doesn't exist yet, using default permissions
May 13 13:28:17 doveadm(
saywhatnow@letest.tld): Debug: Namespace INBOX.: Using permissions from /home/letest/mail/letest.tld/saywhatnow: mode=0700 gid=default
May 13 13:28:17 doveadm(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Mailbox not found
May 13 13:28:17 doveadm(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Mailbox not found
May 13 13:28:17 doveadm(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Mailbox not found
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Effective uid=1001, gid=1003, home=/home/letest/mail/letest.tld/saywhatnow
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Quota root: name=Mailbox backend=maildir args=ns=INBOX.
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Quota rule: root=Mailbox mailbox=* bytes=0 messages=2147483647
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Quota rule: root=Mailbox mailbox=INBOX.INBOX ignored
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Quota rule: root=Mailbox mailbox=INBOX.Trash ignored
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: dict(file): dict created (uri=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota, base_dir=/var/run/dovecot)
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Namespace inbox: type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=imapc:
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: imapc(127.0.0.1:143): Looking up IP address (reconnect_ok=true, last_connect=1652466497)
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Info: imapc(127.0.0.1:143): Connected to 127.0.0.1:143 (local 127.0.0.1:53660)
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: imapc(127.0.0.1:143): Server capabilities: IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE NAMESPACE LITERAL+ STARTTLS AUTH=PLAIN AUTH=LOGIN
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: imapc(127.0.0.1:143): Server capabilities: IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE NAMESPACE LITERAL+ NOTIFY SPECIAL-USE COMPRESS=DEFLATE QUOTA
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: imapc: root=, index=, indexpvt=, control=, inbox=, alt=
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt=
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: quota: quota_over_flag check: quota_over_script unset - skipping
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: quota: quota_over_flag check: quota2_over_script unset - skipping
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX guid=980aa92b41a37e62cd0f0000537c03e1 uid_validity=1652466497 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX guid=c92f64f79f0d1ed01e6d5b314f04886c uid_validity=1652465166 uid_next=5 subs=no last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=no last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Drafts guid=004ef77f6a9c3669bcb43012f3a43855 uid_validity=1652465167 uid_next=2 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX guid=c92f64f79f0d1ed01e6d5b314f04886c uid_validity=1652465166 uid_next=5 subs=no last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=no last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Drafts guid=004ef77f6a9c3669bcb43012f3a43855 uid_validity=1652465167 uid_next=2 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Junk guid=4849549b42c9c7de02f5e49cfa8ae258 uid_validity=1652465168 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Junk guid=4849549b42c9c7de02f5e49cfa8ae258 uid_validity=1652465168 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Sent guid=7d3c7eaa71cdf47ee8a1192687cda8cd uid_validity=1652465169 uid_next=4 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Sent guid=7d3c7eaa71cdf47ee8a1192687cda8cd uid_validity=1652465169 uid_next=4 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Trash guid=b36f688eae08506001091708006abe5a uid_validity=1652465170 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Trash guid=b36f688eae08506001091708006abe5a uid_validity=1652465170 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.spam guid=1ec60921a9e03a229747d8dacdb97c65 uid_validity=1652465171 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.spam guid=1ec60921a9e03a229747d8dacdb97c65 uid_validity=1652465171 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Archive guid=e94acd62db4233e3f7add6d6159b1d11 uid_validity=1652465172 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Archive guid=e94acd62db4233e3f7add6d6159b1d11 uid_validity=1652465172 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX: local=980aa92b41a37e62cd0f0000537c03e1/0/1, remote=c92f64f79f0d1ed01e6d5b314f04886c/0/1: GUIDs conflict - will be merged later
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX: local=00000000000000000000000000000000/0/0, remote=00000000000000000000000000000000/0/0: Directory rename branch not found
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Archive: local=00000000000000000000000000000000/0/0, remote=e94acd62db4233e3f7add6d6159b1d11/0/1: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Drafts: local=00000000000000000000000000000000/0/0, remote=004ef77f6a9c3669bcb43012f3a43855/0/1: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Junk: local=00000000000000000000000000000000/0/0, remote=4849549b42c9c7de02f5e49cfa8ae258/0/1: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Sent: local=00000000000000000000000000000000/0/0, remote=7d3c7eaa71cdf47ee8a1192687cda8cd/0/1: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Trash: local=00000000000000000000000000000000/0/0, remote=b36f688eae08506001091708006abe5a/0/1: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.spam: local=00000000000000000000000000000000/0/0, remote=1ec60921a9e03a229747d8dacdb97c65/0/1: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Rotated transaction log /home/letest/mail/letest.tld/saywhatnow/dovecot.index.log (seq=3, reset=yes)
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Namespace INBOX.: /home/letest/mail/letest.tld/saywhatnow/.INBOX.Archive doesn't exist yet, using default permissions
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Refresh-flag set
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Namespace INBOX.: /home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts doesn't exist yet, using default permissions
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Refresh-flag set
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Namespace INBOX.: /home/letest/mail/letest.tld/saywhatnow/.INBOX.Junk doesn't exist yet, using default permissions
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Refresh-flag set
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Namespace INBOX.: /home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent doesn't exist yet, using default permissions
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Refresh-flag set
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Namespace INBOX.: /home/letest/mail/letest.tld/saywhatnow/.INBOX.Trash doesn't exist yet, using default permissions
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Refresh-flag set
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Namespace INBOX.: /home/letest/mail/letest.tld/saywhatnow/.INBOX.spam doesn't exist yet, using default permissions
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX: Couldn't open mailbox in list index: Refresh-flag set
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX guid=980aa92b41a37e62cd0f0000537c03e1 uid_validity=1652466497 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX: local=c92f64f79f0d1ed01e6d5b314f04886c/0/1, remote=980aa92b41a37e62cd0f0000537c03e1/0/1: GUIDs conflict - will be merged later
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX: local=00000000000000000000000000000000/0/0, remote=00000000000000000000000000000000/0/0: Directory rename branch not found
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Archive: local=e94acd62db4233e3f7add6d6159b1d11/0/1, remote=00000000000000000000000000000000/0/0: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Drafts: local=004ef77f6a9c3669bcb43012f3a43855/0/1, remote=00000000000000000000000000000000/0/0: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Junk: local=4849549b42c9c7de02f5e49cfa8ae258/0/1, remote=00000000000000000000000000000000/0/0: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Sent: local=7d3c7eaa71cdf47ee8a1192687cda8cd/0/1, remote=00000000000000000000000000000000/0/0: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Trash: local=b36f688eae08506001091708006abe5a/0/1, remote=00000000000000000000000000000000/0/0: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.spam: local=1ec60921a9e03a229747d8dacdb97c65/0/1, remote=00000000000000000000000000000000/0/0: mailbox not selectable yet
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Ignore nonexistent mailbox GUID c92f64f79f0d1ed01e6d5b314f04886c with -1 sync
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: We don't have mailbox c92f64f79f0d1ed01e6d5b314f04886c
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Ignoring missing remote box GUID c92f64f79f0d1ed01e6d5b314f04886c
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox e94acd62db4233e3f7add6d6159b1d11
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Drafts: Last common UID=0. Delayed expunges=
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Drafts: Import change type=save GUID= UID=1 hdr_hash= result=Mail's UID is above local UIDNEXT - No more local mails found
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Drafts: UID 1: Opened mail because: access
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Drafts: Import mail body for GUID= UID=1
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Drafts: Adding field flags to cache for the first time (uid=1)
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Drafts: saving UID 1: Opened mail because: mail stream
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Drafts: Purging (new file_seq=1652466497): creating cache
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Drafts: Purging finished, file_seq changed 0 -> 1652466497, size=0 -> 388, max_uid=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Drafts: Finish update: min_next_uid=2 min_first_recent_uid=2 min_highest_modseq=2 min_highest_pvt_modseq=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox 4849549b42c9c7de02f5e49cfa8ae258
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Sent: Last common UID=0. Delayed expunges=
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Sent: Import change type=save GUID= UID=1 hdr_hash= result=Mail's UID is above local UIDNEXT - No more local mails found
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Sent: Import change type=save GUID= UID=2 hdr_hash= result=New mail
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Sent: Import change type=save GUID= UID=3 hdr_hash= result=New mail
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Sent: UID 1: Opened mail because: access
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Sent: Import mail body for GUID= UID=1
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Sent: Adding field flags to cache for the first time (uid=1)
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Sent: saving UID 1: Opened mail because: mail stream
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Sent: Import mail body for GUID= UID=2
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Sent: saving UID 2: Opened mail because: mail stream
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Sent: Import mail body for GUID= UID=3
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Sent: saving UID 3: Opened mail because: mail stream
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Sent: Purging (new file_seq=1652466497): creating cache
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Sent: Purging finished, file_seq changed 0 -> 1652466497, size=0 -> 388, max_uid=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain M: Import INBOX.INBOX.Sent: Finish update: min_next_uid=4 min_first_recent_uid=1 min_highest_modseq=2 min_highest_pvt_modseq=0
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox b36f688eae08506001091708006abe5a
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox 1ec60921a9e03a229747d8dacdb97c65
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:28:17 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:28:17 doveadm(4045): Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb (pid=792,uid=0): Disconnected: Connection closed (fd=10)
This gave 2 files in /tmp/raww/ , the out file:
# cat /tmp/raww/20220513-132817.4045.1.out
1652466497.747397 2 LIST "" ""
1652466497.748167 3 STATUS "INBOX" (UIDNEXT UIDVALIDITY)
1652466497.748956 4 LIST "" "*"
1652466497.749897 5 STATUS "INBOX.Archive" (UIDNEXT UIDVALIDITY)
1652466497.750201 6 STATUS "INBOX.spam" (UIDNEXT UIDVALIDITY)
1652466497.750435 7 STATUS "INBOX.Trash" (UIDNEXT UIDVALIDITY)
1652466497.750665 8 STATUS "INBOX.Sent" (UIDNEXT UIDVALIDITY)
1652466497.751762 9 STATUS "INBOX.Junk" (UIDNEXT UIDVALIDITY)
1652466497.752068 10 STATUS "INBOX.Drafts" (UIDNEXT UIDVALIDITY)
1652466497.752308 11 LSUB "" "*"
1652466497.775368 12 LIST "" "*"
1652466497.776835 13 EXAMINE "INBOX.Archive"
1652466497.778211 14 EXAMINE "INBOX.Drafts"
1652466497.779216 15 UID FETCH 1:* (FLAGS)
1652466497.781243 16 UID FETCH 1 (INTERNALDATE SAVEDATE BODY.PEEK[])
1652466497.787228 17 EXAMINE "INBOX.Junk"
1652466497.788912 18 EXAMINE "INBOX.Sent"
1652466497.789232 19 UID FETCH 1:* (FLAGS)
1652466497.789837 20 UID FETCH 1,2,3 (INTERNALDATE SAVEDATE BODY.PEEK[])
1652466497.792515 21 EXAMINE "INBOX.Trash"
1652466497.793893 22 EXAMINE "INBOX.spam"
1652466497.794723 23 LOGOUT
and the in file:
# cat /tmp/raww/20220513-132817.4045.1.in
1652466497.747116 1 OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE NAMESPACE LITERAL+ NOTIFY SPECIAL-USE COMPRESS=DEFLATE QUOTA] Logged in
1652466497.747907 * LIST (\Noselect) "." ""
1652466497.747907 2 OK List completed (0.001 + 0.000 secs).
1652466497.748791 * STATUS INBOX (UIDNEXT 5 UIDVALIDITY 1652465166)
1652466497.748791 3 OK Status completed (0.001 + 0.000 secs).
1652466497.749533 * LIST (\HasChildren) "." INBOX
1652466497.749533 * LIST (\HasNoChildren \UnMarked \Archive) "." INBOX.Archive
1652466497.749533 * LIST (\HasNoChildren \UnMarked \Junk) "." INBOX.spam
1652466497.749533 * LIST (\HasNoChildren \UnMarked \Trash) "." INBOX.Trash
1652466497.749533 * LIST (\HasNoChildren \Sent) "." INBOX.Sent
1652466497.749533 * LIST (\HasNoChildren \UnMarked) "." INBOX.Junk
1652466497.749533 * LIST (\HasNoChildren \UnMarked \Drafts) "." INBOX.Drafts
1652466497.749533 4 OK List completed (0.001 + 0.000 secs).
1652466497.750045 * STATUS INBOX.Archive (UIDNEXT 1 UIDVALIDITY 1652465172)
1652466497.750045 5 OK Status completed (0.001 + 0.000 secs).
1652466497.750312 * STATUS INBOX.spam (UIDNEXT 1 UIDVALIDITY 1652465171)
1652466497.750312 6 OK Status completed (0.001 + 0.000 secs).
1652466497.750552 * STATUS INBOX.Trash (UIDNEXT 1 UIDVALIDITY 1652465170)
1652466497.750552 7 OK Status completed (0.001 + 0.000 secs).
1652466497.751510 * STATUS INBOX.Sent (UIDNEXT 4 UIDVALIDITY 1652465169)
1652466497.751510 8 OK Status completed (0.001 + 0.000 secs).
1652466497.751912 * STATUS INBOX.Junk (UIDNEXT 1 UIDVALIDITY 1652465168)
1652466497.751912 9 OK Status completed (0.001 + 0.000 secs).
1652466497.752208 * STATUS INBOX.Drafts (UIDNEXT 2 UIDVALIDITY 1652465167)
1652466497.752208 10 OK Status completed (0.001 + 0.000 secs).
1652466497.752600 * LSUB (\Junk) "." INBOX.spam
1652466497.752600 * LSUB (\Archive) "." INBOX.Archive
1652466497.752600 * LSUB (\Drafts) "." INBOX.Drafts
1652466497.752600 * LSUB (\Trash) "." INBOX.Trash
1652466497.752600 * LSUB (\Sent) "." INBOX.Sent
1652466497.752600 11 OK Lsub completed (0.001 + 0.000 secs).
1652466497.775980 * LIST (\HasChildren) "." INBOX
1652466497.775980 * LIST (\HasNoChildren \UnMarked \Archive) "." INBOX.Archive
1652466497.775980 * LIST (\HasNoChildren \UnMarked \Junk) "." INBOX.spam
1652466497.775980 * LIST (\HasNoChildren \UnMarked \Trash) "." INBOX.Trash
1652466497.775980 * LIST (\HasNoChildren \Marked \Sent) "." INBOX.Sent
1652466497.775980 * LIST (\HasNoChildren \UnMarked) "." INBOX.Junk
1652466497.775980 * LIST (\HasNoChildren \UnMarked \Drafts) "." INBOX.Drafts
1652466497.775980 12 OK List completed (0.001 + 0.000 secs).
1652466497.777411 * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
1652466497.777411 * OK [PERMANENTFLAGS ()] Read-only mailbox.
1652466497.777411 * 0 EXISTS
1652466497.777411 * 0 RECENT
1652466497.777411 * OK [UIDVALIDITY 1652465172] UIDs valid
1652466497.777411 * OK [UIDNEXT 1] Predicted next UID
1652466497.777411 13 OK [READ-ONLY] Examine completed (0.001 + 0.000 secs).
1652466497.779160 * OK [CLOSED] Previous mailbox closed.
1652466497.779160 * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
1652466497.779160 * OK [PERMANENTFLAGS ()] Read-only mailbox.
1652466497.779160 * 1 EXISTS
1652466497.779160 * 0 RECENT
1652466497.779160 * OK [UIDVALIDITY 1652465167] UIDs valid
1652466497.779160 * OK [UIDNEXT 2] Predicted next UID
1652466497.779160 * OK [HIGHESTMODSEQ 2] Highest
1652466497.779160 14 OK [READ-ONLY] Examine completed (0.001 + 0.000 secs).
1652466497.779851 * 1 FETCH (UID 1 FLAGS (\Seen))
1652466497.779851 15 OK Fetch completed (0.001 + 0.000 secs).
1652466497.782233 * 1 FETCH (UID 1 INTERNALDATE "13-May-2022 13:14:30 -0500" SAVEDATE "13-May-2022 13:14:30 -0500" BODY[] {353}
1652466497.782233 MIME-Version: 1.0
1652466497.782233 Date: Fri, 13 May 2022 13:14:30 -0500
1652466497.782233 Subject: awdfaw
1652466497.782233 User-Agent: Roundcube Webmail/1.5.2
1652466497.782233 Content-Type: text/plain; charset=US-ASCII;
1652466497.782233 format=flowed
1652466497.782233 Content-Transfer-Encoding: 7bit
1652466497.782233
1652466497.782233 awdawd)
1652466497.782233 16 OK Fetch completed (0.001 + 0.000 secs).
1652466497.787744 * OK [CLOSED] Previous mailbox closed.
1652466497.787744 * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
1652466497.787744 * OK [PERMANENTFLAGS ()] Read-only mailbox.
1652466497.787744 * 0 EXISTS
1652466497.787744 * 0 RECENT
1652466497.787744 * OK [UIDVALIDITY 1652465168] UIDs valid
1652466497.787744 * OK [UIDNEXT 1] Predicted next UID
1652466497.787744 17 OK [READ-ONLY] Examine completed (0.001 + 0.000 secs).
1652466497.789197 * OK [CLOSED] Previous mailbox closed.
1652466497.789197 * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
1652466497.789197 * OK [PERMANENTFLAGS ()] Read-only mailbox.
1652466497.789197 * 3 EXISTS
1652466497.789197 * 3 RECENT
1652466497.789197 * OK [UIDVALIDITY 1652465169] UIDs valid
1652466497.789197 * OK [UIDNEXT 4] Predicted next UID
1652466497.789197 18 OK [READ-ONLY] Examine completed (0.001 + 0.000 secs).
1652466497.789457 * 1 FETCH (UID 1 FLAGS (\Seen \Recent))
1652466497.789457 * 2 FETCH (UID 2 FLAGS (\Seen \Recent))
1652466497.789457 * 3 FETCH (UID 3 FLAGS (\Seen \Recent))
1652466497.789457 19 OK Fetch completed (0.001 + 0.000 secs).
1652466497.790590 * 1 FETCH (UID 1 INTERNALDATE "13-May-2022 13:18:40 -0500" SAVEDATE "13-May-2022 13:18:40 -0500" BODY[] {371}
1652466497.790590 MIME-Version: 1.0
1652466497.790590 Date: Fri, 13 May 2022 13:18:40 -0500
1652466497.790590 Subject: eswtest
1652466497.790590 User-Agent: Roundcube Webmail/1.5.2
1652466497.790590 Content-Type: text/plain; charset=US-ASCII;
1652466497.790590 format=flowed
1652466497.790590 Content-Transfer-Encoding: 7bit
1652466497.790590
1652466497.790590 wadaw dwadawdaw adw adw)
1652466497.790590 * 2 FETCH (UID 2 INTERNALDATE "13-May-2022 13:19:02 -0500" SAVEDATE "13-May-2022 13:19:02 -0500" BODY[] {354}
1652466497.790590 MIME-Version: 1.0
1652466497.790590 Date: Fri, 13 May 2022 13:19:02 -0500
1652466497.790590 Subject: test 1
1652466497.790590 User-Agent: Roundcube Webmail/1.5.2
1652466497.790590 Content-Type: text/plain; charset=US-ASCII;
1652466497.790590 format=flowed
1652466497.790590 Content-Transfer-Encoding: 7bit
1652466497.790590
1652466497.790590 ersert1)
1652466497.790590 * 3 FETCH (UID 3 INTERNALDATE "13-May-2022 13:19:11 -0500" SAVEDATE "13-May-2022 13:19:11 -0500" BODY[] {354}
1652466497.790590 MIME-Version: 1.0
1652466497.790590 Date: Fri, 13 May 2022 13:19:11 -0500
1652466497.790590 Subject: test2
1652466497.790590 User-Agent: Roundcube Webmail/1.5.2
1652466497.790590 Content-Type: text/plain; charset=US-ASCII;
1652466497.790590 format=flowed
1652466497.790590 Content-Transfer-Encoding: 7bit
1652466497.790590
1652466497.790590 res22222)
1652466497.790590 20 OK Fetch completed (0.001 + 0.000 secs).
1652466497.793104 * OK [CLOSED] Previous mailbox closed.
1652466497.793104 * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
1652466497.793104 * OK [PERMANENTFLAGS ()] Read-only mailbox.
1652466497.793104 * 0 EXISTS
1652466497.793104 * 0 RECENT
1652466497.793104 * OK [UIDVALIDITY 1652465170] UIDs valid
1652466497.793104 * OK [UIDNEXT 1] Predicted next UID
1652466497.793104 21 OK [READ-ONLY] Examine completed (0.001 + 0.000 secs).
1652466497.794356 * OK [CLOSED] Previous mailbox closed.
1652466497.794356 * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
1652466497.794356 * OK [PERMANENTFLAGS ()] Read-only mailbox.
1652466497.794356 * 0 EXISTS
1652466497.794356 * 0 RECENT
1652466497.794356 * OK [UIDVALIDITY 1652465171] UIDs valid
1652466497.794356 * OK [UIDNEXT 1] Predicted next UID
1652466497.794356 22 OK [READ-ONLY] Examine completed (0.001 + 0.000 secs).
1652466497.794913 * BYE Logging out
1652466497.794913 23 OK Logout completed (0.001 + 0.000 secs).
And when I look at the destination account, I see that everything got imported over EXCEPT the INBOX itself:
# find /home/letest/mail/letest.tld/saywhatnow
/home/letest/mail/letest.tld/saywhatnow
/home/letest/mail/letest.tld/saywhatnow/dovecot.list.index.log
/home/letest/mail/letest.tld/saywhatnow/cur
/home/letest/mail/letest.tld/saywhatnow/new
/home/letest/mail/letest.tld/saywhatnow/tmp
/home/letest/mail/letest.tld/saywhatnow/dovecot.index.log
/home/letest/mail/letest.tld/saywhatnow/dovecot-uidvalidity
/home/letest/mail/letest.tld/saywhatnow/dovecot-uidlist
/home/letest/mail/letest.tld/saywhatnow/maildirfolder
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Archive
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Archive/cur
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Archive/new
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Archive/tmp
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Archive/maildirfolder
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Archive/dovecot.index.log
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Archive/dovecot-uidlist
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts/cur
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts/cur/1652466497.M782233P4045.cent-7.darren.cpanel.net,S=341,W=353:2,S
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts/new
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts/tmp
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts/maildirfolder
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts/dovecot.index.log
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts/dovecot-uidlist
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Drafts/dovecot.index.cache
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Junk
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Junk/cur
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Junk/new
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Junk/tmp
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Junk/maildirfolder
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Junk/dovecot.index.log
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Junk/dovecot-uidlist
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/cur
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/cur/1652466497.M790590P4045.cent-7.darren.cpanel.net,S=359,W=371:2,S
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/cur/1652466497.M790591P4045.cent-7.darren.cpanel.net,S=342,W=354:2,S
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/cur/1652466497.M790592P4045.cent-7.darren.cpanel.net,S=342,W=354:2,S
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/new
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/tmp
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/maildirfolder
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/dovecot.index.log
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/dovecot-uidlist
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Sent/dovecot.index.cache
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Trash
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Trash/cur
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Trash/new
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Trash/tmp
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Trash/maildirfolder
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Trash/dovecot.index.log
/home/letest/mail/letest.tld/saywhatnow/.INBOX.Trash/dovecot-uidlist
/home/letest/mail/letest.tld/saywhatnow/.INBOX.spam
/home/letest/mail/letest.tld/saywhatnow/.INBOX.spam/cur
/home/letest/mail/letest.tld/saywhatnow/.INBOX.spam/new
/home/letest/mail/letest.tld/saywhatnow/.INBOX.spam/tmp
/home/letest/mail/letest.tld/saywhatnow/.INBOX.spam/maildirfolder
/home/letest/mail/letest.tld/saywhatnow/.INBOX.spam/dovecot.index.log
/home/letest/mail/letest.tld/saywhatnow/.INBOX.spam/dovecot-uidlist
/home/letest/mail/letest.tld/saywhatnow/dovecot-uidvalidity.627ea347
/home/letest/mail/letest.tld/saywhatnow/subscriptions
/home/letest/mail/letest.tld/saywhatnow/dovecot.mailbox.log
/home/letest/mail/letest.tld/saywhatnow/maildirsize
/home/letest/mail/letest.tld/saywhatnow/dovecot-quota
Running the same command a second time results in no changes to the destination account and the GUID issues with the inbox from my previous mail:
May 13 13:41:25 doveadm(
saywhatnow@letest.tld): Debug: brain M: Namespace INBOX. has location maildir:/home/letest/mail/letest.tld/saywhatnow:UTF-8
May 13 13:41:25 doveadm(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.spam: Couldn't open mailbox in list index: Storage size changed 168 != 256
May 13 13:41:25 doveadm(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Trash: Couldn't open mailbox in list index: Storage size changed 168 != 256
May 13 13:41:25 doveadm(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Junk: Couldn't open mailbox in list index: Storage size changed 168 != 256
May 13 13:41:25 doveadm(
saywhatnow@letest.tld): Debug: Mailbox INBOX.INBOX.Archive: Couldn't open mailbox in list index: Storage size changed 168 != 256
May 13 13:41:25 doveadm(
saywhatnow@letest.tld): Debug: Namespace INBOX.: Using permissions from /home/letest/mail/letest.tld/saywhatnow: mode=0700 gid=default
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: Effective uid=1001, gid=1003, home=/home/letest/mail/letest.tld/saywhatnow
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: Quota root: name=Mailbox backend=maildir args=ns=INBOX.
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: Quota rule: root=Mailbox mailbox=* bytes=0 messages=2147483647
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: Quota rule: root=Mailbox mailbox=INBOX.INBOX ignored
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: Quota rule: root=Mailbox mailbox=INBOX.Trash ignored
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: dict(file): dict created (uri=file:/home/letest/mail/letest.tld/saywhatnow/dovecot-quota, base_dir=/var/run/dovecot)
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: Namespace inbox: type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=imapc:
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: imapc(127.0.0.1:143): Looking up IP address (reconnect_ok=true, last_connect=1652467285)
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Info: imapc(127.0.0.1:143): Connected to 127.0.0.1:143 (local 127.0.0.1:53730)
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: imapc(127.0.0.1:143): Server capabilities: IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE NAMESPACE LITERAL+ STARTTLS AUTH=PLAIN AUTH=LOGIN
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: imapc(127.0.0.1:143): Server capabilities: IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE NAMESPACE LITERAL+ NOTIFY SPECIAL-USE COMPRESS=DEFLATE QUOTA
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: imapc: root=, index=, indexpvt=, control=, inbox=, alt=
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt=
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: quota: quota_over_flag check: quota_over_script unset - skipping
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: quota: quota_over_flag check: quota2_over_script unset - skipping
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX guid=c92f64f79f0d1ed01e6d5b314f04886c uid_validity=1652465166 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX guid=c92f64f79f0d1ed01e6d5b314f04886c uid_validity=1652465166 uid_next=5 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX.INBOX guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX.INBOX.Archive guid=e94acd62db4233e3f7add6d6159b1d11 uid_validity=1652465172 uid_next=1 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Drafts guid=004ef77f6a9c3669bcb43012f3a43855 uid_validity=1652465167 uid_next=2 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX.INBOX.Drafts guid=004ef77f6a9c3669bcb43012f3a43855 uid_validity=1652465167 uid_next=2 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Junk guid=4849549b42c9c7de02f5e49cfa8ae258 uid_validity=1652465168 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX.INBOX.Junk guid=4849549b42c9c7de02f5e49cfa8ae258 uid_validity=1652465168 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Sent guid=7d3c7eaa71cdf47ee8a1192687cda8cd uid_validity=1652465169 uid_next=4 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX.INBOX.Sent guid=7d3c7eaa71cdf47ee8a1192687cda8cd uid_validity=1652465169 uid_next=4 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Trash guid=b36f688eae08506001091708006abe5a uid_validity=1652465170 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX.INBOX.Trash guid=b36f688eae08506001091708006abe5a uid_validity=1652465170 uid_next=1 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.spam guid=1ec60921a9e03a229747d8dacdb97c65 uid_validity=1652465171 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Local mailbox tree: INBOX.INBOX.spam guid=1ec60921a9e03a229747d8dacdb97c65 uid_validity=1652465171 uid_next=1 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Local mailbox tree: INBOX.INBOX.Archive guid=e94acd62db4233e3f7add6d6159b1d11 uid_validity=1652465172 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX guid=c92f64f79f0d1ed01e6d5b314f04886c uid_validity=1652465166 uid_next=5 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Drafts guid=004ef77f6a9c3669bcb43012f3a43855 uid_validity=1652465167 uid_next=2 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Junk guid=4849549b42c9c7de02f5e49cfa8ae258 uid_validity=1652465168 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Sent guid=7d3c7eaa71cdf47ee8a1192687cda8cd uid_validity=1652465169 uid_next=4 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Trash guid=b36f688eae08506001091708006abe5a uid_validity=1652465170 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.spam guid=1ec60921a9e03a229747d8dacdb97c65 uid_validity=1652465171 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Remote mailbox tree: INBOX.INBOX.Archive guid=e94acd62db4233e3f7add6d6159b1d11 uid_validity=1652465172 uid_next=1 subs=yes last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX guid=c92f64f79f0d1ed01e6d5b314f04886c uid_validity=1652465166 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX.INBOX guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX.INBOX.Archive guid=e94acd62db4233e3f7add6d6159b1d11 uid_validity=1652465172 uid_next=1 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX.INBOX.Drafts guid=004ef77f6a9c3669bcb43012f3a43855 uid_validity=1652465167 uid_next=2 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX.INBOX.Junk guid=4849549b42c9c7de02f5e49cfa8ae258 uid_validity=1652465168 uid_next=1 subs=no last_change=0 last_subs=0
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX.INBOX.Sent guid=7d3c7eaa71cdf47ee8a1192687cda8cd uid_validity=1652465169 uid_next=4 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX.INBOX.Trash guid=b36f688eae08506001091708006abe5a uid_validity=1652465170 uid_next=1 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Remote mailbox tree: INBOX.INBOX.spam guid=1ec60921a9e03a229747d8dacdb97c65 uid_validity=1652465171 uid_next=1 subs=yes last_change=0 last_subs=1652466497
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX: local=c92f64f79f0d1ed01e6d5b314f04886c/0/1, remote=c92f64f79f0d1ed01e6d5b314f04886c/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX: local=00000000000000000000000000000000/0/0, remote=00000000000000000000000000000000/0/0: Directory name paths are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Archive: local=e94acd62db4233e3f7add6d6159b1d11/0/1, remote=e94acd62db4233e3f7add6d6159b1d11/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Drafts: local=004ef77f6a9c3669bcb43012f3a43855/0/1, remote=004ef77f6a9c3669bcb43012f3a43855/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Junk: local=4849549b42c9c7de02f5e49cfa8ae258/0/1, remote=4849549b42c9c7de02f5e49cfa8ae258/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Sent: local=7d3c7eaa71cdf47ee8a1192687cda8cd/0/1, remote=7d3c7eaa71cdf47ee8a1192687cda8cd/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.Trash: local=b36f688eae08506001091708006abe5a/0/1, remote=b36f688eae08506001091708006abe5a/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Mailbox INBOX.INBOX.spam: local=1ec60921a9e03a229747d8dacdb97c65/0/1, remote=1ec60921a9e03a229747d8dacdb97c65/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX: local=c92f64f79f0d1ed01e6d5b314f04886c/0/1, remote=c92f64f79f0d1ed01e6d5b314f04886c/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX: local=00000000000000000000000000000000/0/0, remote=00000000000000000000000000000000/0/0: Directory name paths are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Archive: local=e94acd62db4233e3f7add6d6159b1d11/0/1, remote=e94acd62db4233e3f7add6d6159b1d11/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Drafts: local=004ef77f6a9c3669bcb43012f3a43855/0/1, remote=004ef77f6a9c3669bcb43012f3a43855/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Junk: local=4849549b42c9c7de02f5e49cfa8ae258/0/1, remote=4849549b42c9c7de02f5e49cfa8ae258/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Sent: local=7d3c7eaa71cdf47ee8a1192687cda8cd/0/1, remote=7d3c7eaa71cdf47ee8a1192687cda8cd/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.Trash: local=b36f688eae08506001091708006abe5a/0/1, remote=b36f688eae08506001091708006abe5a/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Mailbox INBOX.INBOX.spam: local=1ec60921a9e03a229747d8dacdb97c65/0/1, remote=1ec60921a9e03a229747d8dacdb97c65/0/1: Mailboxes are equal
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Ignore nonexistent mailbox GUID c92f64f79f0d1ed01e6d5b314f04886c with -1 sync
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: We don't have mailbox c92f64f79f0d1ed01e6d5b314f04886c
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain M: Ignoring missing remote box GUID c92f64f79f0d1ed01e6d5b314f04886c
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox e94acd62db4233e3f7add6d6159b1d11
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox 004ef77f6a9c3669bcb43012f3a43855
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox 4849549b42c9c7de02f5e49cfa8ae258
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox 7d3c7eaa71cdf47ee8a1192687cda8cd
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox b36f688eae08506001091708006abe5a
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: brain S: Skipping unchanged mailbox 1ec60921a9e03a229747d8dacdb97c65
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:41:25 dsync(
saywhatnow@letest.tld): Debug: dict(file): Waiting for dict to finish pending operations
May 13 13:41:25 doveadm(4355): Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb (pid=792,uid=0): Disconnected: Connection closed (fd=10)
It may not be relevant, but here's the source dovecot-uidlist :
# cat /home/hellodemo/mail/hellodemo.ppl/wah/dovecot-uidlist
3 V1652465166 N1 Gd31223010e9e7e62410d0000537c03e1
1 :1652465917.M799363P3797.cent-7.darren.cpanel.net,S=928,W=956
2 :1652465920.M628557P3797.cent-7.darren.cpanel.net,S=969,W=998
3 :1652465942.M304779P3797.cent-7.darren.cpanel.net,S=952,W=981
4 :1652465951.M583486P3797.cent-7.darren.cpanel.net,S=952,W=981
and destination one:
# cat /home/letest/mail/letest.tld/saywhatnow/dovecot-uidlist
3 V1652465166 N1 Gc92f64f79f0d1ed01e6d5b314f04886c
Looking at the raw IMAPC logs, the main stinker I see is that output of LSUB doesn't show the INBOX, and was then skipped from the EXAMINE lines after it:
11 LSUB "" "*"
* LSUB (\Junk) "." INBOX.spam
* LSUB (\Archive) "." INBOX.Archive
* LSUB (\Drafts) "." INBOX.Drafts
* LSUB (\Trash) "." INBOX.Trash
* LSUB (\Sent) "." INBOX.Sent
However just now I manually subscribed to that folder..
14 subscribe INBOX
14 OK Subscribe completed (0.012 + 0.000 + 0.011 secs).
15 LSUB "" "*"
* LSUB () "." INBOX
* LSUB (\Junk) "." INBOX.spam
* LSUB (\Archive) "." INBOX.Archive
* LSUB (\Drafts) "." INBOX.Drafts
* LSUB (\Trash) "." INBOX.Trash
* LSUB (\Sent) "." INBOX.Sent
15 OK Lsub completed (0.001 + 0.000 secs).
16 logout
..and re-ran the above dovecot sync command, and still didn't get new mail, with the same error about GUID not existing.
Now INBOX itself is reflected in the raw in log:
1652468207.459766 10 OK Status completed (0.001 + 0.000 secs).
1652468207.460004 * LSUB () "." INBOX
1652468207.460004 * LSUB (\Junk) "." INBOX.spam
1652468207.460004 * LSUB (\Archive) "." INBOX.Archive
1652468207.460004 * LSUB (\Drafts) "." INBOX.Drafts
1652468207.460004 * LSUB (\Trash) "." INBOX.Trash
1652468207.460004 * LSUB (\Sent) "." INBOX.Sent
1652468207.460004 11 OK Lsub completed (0.001 + 0.000 secs).
1652468207.461635 * LIST (\HasChildren) "." INBOX
1652468207.461635 * LIST (\HasNoChildren \UnMarked \Archive) "." INBOX.Archive
1652468207.461635 * LIST (\HasNoChildren \UnMarked \Junk) "." INBOX.spam
1652468207.461635 * LIST (\HasNoChildren \UnMarked \Trash) "." INBOX.Trash
1652468207.461635 * LIST (\HasNoChildren \Marked \Sent) "." INBOX.Sent
1652468207.461635 * LIST (\HasNoChildren \UnMarked) "." INBOX.Junk
1652468207.461635 * LIST (\HasNoChildren \UnMarked \Drafts) "." INBOX.Drafts
1652468207.461635 12 OK List completed (0.001 + 0.000 secs).
However the raw out log doesn't show it EXAMINE'ing the INBOX itself, just the .folders :
1652468207.459810 11 LSUB "" "*"
1652468207.461358 12 LIST "" "*"
1652468207.462263 13 EXAMINE "INBOX.Archive"
1652468207.463239 14 EXAMINE "INBOX.Drafts"
1652468207.464101 15 UID FETCH 1:* (FLAGS)
1652468207.465088 16 EXAMINE "INBOX.Junk"
1652468207.465931 17 EXAMINE "INBOX.Sent"
1652468207.466437 18 UID FETCH 1:* (FLAGS)
1652468207.467218 19 EXAMINE "INBOX.Trash"
1652468207.468146 20 EXAMINE "INBOX.spam"
1652468207.468796 21 LOGOUT
My best guess at this point is the logs showing "INBOX" and "INBOX.INBOX" , perhaps there's something about the naming scheme that is throwing it off, but the "INBOX.INBOX.Drafts" and such are still being handled.
Thanks again for any direction!
Darren