(re-) dsync: INBOX Can't be deleted

PGNet Dev pgnet.dev at gmail.com
Fri Jul 24 17:34:30 EEST 2020


with

	dovecot --version
		2.3.10.1 (a3d0e1171)

exec of

	doveadm -v -o mail_fsync=never backup -R -f -u testuser at example.com imapc:

currently fails, here, with

	Error: Mailbox INBOX sync: mailbox_delete failed: INBOX can't be deleted

checking lists, this has been seen before

	@ https://dovecot.org/pipermail/dovecot/2016-January/102988.html

		On 24 Jan 2016, at 18:47, Timo Sirainen <tss at iki.fi> wrote:
		> During Migration with dsync I get these Errors:
		> Error: Mailbox INBOX sync: mailbox_delete failed: INBOX can't be deleted
		> Is there a way to work around these Errors ?

		With "doveadm backup" you have gotten into such a state that the INBOX needs to be deleted and recreated to preserve it exactly as it's in source. But you're using Maildir format, which doesn't allow deleting INBOX. So possibilities:

		a) Don't use Maildir format

		b) Delete the entire Maildir manually with rm -rf and the next doveadm backup should work (if not then something strange is going on)

per "b)", i explicitly 'rm' the Maildir

	rm -rf /data/vmail/example.com/testuser/Maildir
	tree /data/vmail/example.com/testuser/Maildir
		/data/vmail/example.com/testuser/Maildir [error opening dir]

on re-exec, the backup still fails

	dsync(testuser at example.com): Info: imapc(mx.example.com:993): Connected to 10.1.0.20:993 (local 10.1.0.114:42926)
	dsync(testuser at example.com): Error: Mailbox INBOX sync: mailbox_delete failed: INBOX can't be deleted.

where

	tree /data/vmail/example.com/testuser/Maildir
		/data/vmail/example.com/testuser/Maildir
		├── cur
		├── new
		├── sieve
		│   └── tmp
		└── tmp

and log detail,

	Debug: Loading modules from directory: /usr/lib64/dovecot
	Debug: Module loaded: /usr/lib64/dovecot/lib20_virtual_plugin.so
	Debug: Loading modules from directory: /usr/lib64/dovecot/doveadm
	doveadm(testuser at example.com)<>: Debug: auth-master: userdb lookup(testuser at example.com): Started userdb lookup
	doveadm(testuser at example.com)<>: Debug: auth-master: conn unix:/run/dovecot/auth-userdb: Connecting
	doveadm(testuser at example.com)<>: Debug: auth-master: conn unix:/run/dovecot/auth-userdb (pid=25419,uid=0): Client connected (fd=10)
	doveadm(testuser at example.com)<>: Debug: auth-master: userdb lookup(testuser at example.com): auth USER input: testuser at example.com uid=5000 gid=5000 home=/data/vmail/example.com/testuser/Maildir
	doveadm(testuser at example.com)<>: Debug: auth-master: userdb lookup(testuser at example.com): Finished userdb lookup (username=testuser at example.com uid=5000 gid=5000 home=/data/vmail/example.com/testuser/Maildir)
	doveadm(testuser at example.com): Debug: Effective uid=5000, gid=5000, home=/data/vmail/example.com/testuser/Maildir
	doveadm(testuser at example.com): Debug: Namespace inbox: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/data/vmail/example.com/testuser/Maildir:CONTROL=/data/vmail/example.com/testuser/_control:INDEX=/var/vmail-index/example.com/testuser:LAYOUT=fs:UTF-8
	doveadm(testuser at example.com): Debug: fs: root=/data/vmail/example.com/testuser/Maildir, index=/var/vmail-index/example.com/testuser, indexpvt=, control=/data/vmail/example.com/testuser/_control, inbox=/data/vmail/example.com/testuser/Maildir, alt=
	doveadm(testuser at example.com): Debug: Namespace shared: type=shared, prefix=shared/%u/, sep=/, inbox=no, hidden=no, list=children, subscriptions=yes location=maildir:/data/vmail/example.com/testuser/Maildir:INDEXPVT=/var/vmail-index/shared/%u
	doveadm(testuser at example.com): Debug: shared: root=/run/dovecot/, index=, indexpvt=, control=, inbox=, alt=
	doveadm(testuser at example.com): Debug: Namespace virtual: type=private, prefix=virtual/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes location=virtual:/data/vmail/virtual:INDEX=MEMORY
	doveadm(testuser at example.com): Debug: fs: root=/data/vmail/virtual, index=, indexpvt=, control=, inbox=, alt=
	doveadm(testuser at example.com): Debug: brain M: Namespace  has location maildir:/data/vmail/example.com/testuser/Maildir:CONTROL=/data/vmail/example.com/testuser/_control:INDEX=/var/vmail-index/example.com/testuser:LAYOUT=fs:UTF-8
	doveadm(testuser at example.com): Debug: Namespace : Using permissions from /data/vmail/example.com/testuser/Maildir: mode=0700 gid=default
	dsync(testuser at example.com): Debug: Effective uid=5000, gid=5000, home=/data/vmail/example.com/testuser/Maildir
	dsync(testuser at example.com): Debug: Namespace inbox: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes location=imapc:
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Created new connection
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Looking up IP address (reconnect_ok=true, last_connect=1595599741)
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Connecting to 10.1.0.20:993
	dsync(testuser at example.com): Info: imapc(mx.example.com:993): Connected to 10.1.0.20:993 (local 10.1.0.114:42924)
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Starting SSL handshake
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): SSL handshake successful
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Server capabilities: IMAP4rev1 AUTH=PLAIN ACL BINARY CATENATE CHILDREN CONDSTORE ENABLE ESEARCH ESORT I18NLEVEL=1 ID IDLE LIST-EXTENDED LIST-STATUS LITERAL+ LOGIN-REFERRALS MULTIAPPEND NAMESPACE QRESYNC QUOTA RIGHTS=ektx SASL-IR SEARCHRES SORT THREAD=ORDEREDSUBJECT UIDPLUS UNSELECT WITHIN XLIST
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Authenticating as testuser at example.com
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Server capabilities: IMAP4rev1 ACL BINARY CATENATE CHILDREN CONDSTORE ENABLE ESEARCH ESORT I18NLEVEL=1 ID IDLE LIST-EXTENDED LIST-STATUS LITERAL+ LOGIN-REFERRALS MULTIAPPEND NAMESPACE QRESYNC QUOTA RIGHTS=ektx SASL-IR SEARCHRES SORT THREAD=ORDEREDSUBJECT UIDPLUS UNSELECT WITHIN XLIST
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Authenticated successfully
	dsync(testuser at example.com): Debug: imapc: root=, index=, indexpvt=, control=, inbox=, alt=
	dsync(testuser at example.com): Debug: Namespace shared: type=shared, prefix=shared/%u/, sep=/, inbox=no, hidden=no, list=children, subscriptions=yes location=maildir:/data/vmail/example.com/testuser/Maildir:INDEXPVT=/var/vmail-index/shared/%u
	dsync(testuser at example.com): Debug: shared: root=/run/dovecot/, index=, indexpvt=, control=, inbox=, alt=
	dsync(testuser at example.com): Debug: Namespace virtual: type=private, prefix=virtual/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=yes location=virtual:/data/vmail/virtual:INDEX=MEMORY
	dsync(testuser at example.com): Debug: fs: root=/data/vmail/virtual, index=, indexpvt=, control=, inbox=, alt=
	dsync(testuser at example.com): Debug: brain S: Namespace  has location imapc:
	dsync(testuser at example.com): Debug: brain M: Local mailbox tree: Sent guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=yes last_change=0 last_subs=1595535189
	dsync(testuser at example.com): Debug: brain S: Local mailbox tree: Trash guid=7f5af7ba291b2df1a11d573bdb55d7e9 uid_validity=1 uid_next=89207 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Local mailbox tree: Drafts guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=yes last_change=0 last_subs=1595535189
	dsync(testuser at example.com): Debug: brain S: Local mailbox tree: Templates guid=d9f092123a43acb205a6869342cf9dd6 uid_validity=126054 uid_next=22295 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Local mailbox tree: Trash guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=yes last_change=0 last_subs=1595534293
	dsync(testuser at example.com): Debug: brain S: Local mailbox tree: Sent guid=bfb2e03fdce327671e82bf173b1ccb8b uid_validity=1 uid_next=89068 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Local mailbox tree: INBOX guid=6f51443ad5eb195f9f4400005439fba4 uid_validity=1595534294 uid_next=1 subs=no last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Local mailbox tree: Junk guid=6e51443ad5eb195f9f4400005439fba4 uid_validity=1595534293 uid_next=1 subs=yes last_change=0 last_subs=1595534293
	dsync(testuser at example.com): Debug: brain S: Local mailbox tree: Junk guid=87680557f26fcb26843f739f7427e8c4 uid_validity=1 uid_next=88649 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain S: Local mailbox tree: INBOX guid=c92f64f79f0d1ed01e6d5b314f04886c uid_validity=1 uid_next=89209 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Remote mailbox tree: Trash guid=7f5af7ba291b2df1a11d573bdb55d7e9 uid_validity=1 uid_next=89207 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Remote mailbox tree: Templates guid=d9f092123a43acb205a6869342cf9dd6 uid_validity=126054 uid_next=22295 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Remote mailbox tree: Sent guid=bfb2e03fdce327671e82bf173b1ccb8b uid_validity=1 uid_next=89068 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Remote mailbox tree: Junk guid=87680557f26fcb26843f739f7427e8c4 uid_validity=1 uid_next=88649 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Remote mailbox tree: INBOX guid=c92f64f79f0d1ed01e6d5b314f04886c uid_validity=1 uid_next=89209 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain S: Local mailbox tree: Drafts guid=e0187b65e763143666d22094cedfe6a4 uid_validity=1 uid_next=88914 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Remote mailbox tree: Drafts guid=e0187b65e763143666d22094cedfe6a4 uid_validity=1 uid_next=88914 subs=yes last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain M: Mailbox Drafts: local=00000000000000000000000000000000/0/2, remote=e0187b65e763143666d22094cedfe6a4/0/1: mailbox not selectable yet
	dsync(testuser at example.com): Debug: brain M: Mailbox INBOX: local=6f51443ad5eb195f9f4400005439fba4/0/1, remote=c92f64f79f0d1ed01e6d5b314f04886c/0/1: GUIDs conflict - will be merged later
	dsync(testuser at example.com): Debug: brain M: Mailbox Junk: local=6e51443ad5eb195f9f4400005439fba4/0/1, remote=87680557f26fcb26843f739f7427e8c4/0/1: GUIDs conflict - will be merged later
	dsync(testuser at example.com): Debug: brain M: Mailbox Sent: local=00000000000000000000000000000000/0/0, remote=bfb2e03fdce327671e82bf173b1ccb8b/0/1: mailbox not selectable yet
	dsync(testuser at example.com): Debug: brain M: Mailbox Templates: local=00000000000000000000000000000000/0/0, remote=d9f092123a43acb205a6869342cf9dd6/0/1: mailbox not selectable yet
	dsync(testuser at example.com): Debug: brain M: Mailbox Trash: local=00000000000000000000000000000000/0/0, remote=7f5af7ba291b2df1a11d573bdb55d7e9/0/1: mailbox not selectable yet
	dsync(testuser at example.com): Debug: brain M: Deleting mailbox 'INBOX' (GUID 6f51443ad5eb195f9f4400005439fba4): UIDVALIDITY changed (1 -> 1595534294)
	dsync(testuser at example.com): Debug: brain M: Deleting mailbox 'Junk' (GUID 6e51443ad5eb195f9f4400005439fba4): UIDVALIDITY changed (1 -> 1595534293)
	dsync(testuser at example.com): Error: Mailbox INBOX sync: mailbox_delete failed: INBOX can't be deleted.
	dsync(testuser at example.com): Debug: brain S: Remote mailbox tree: Sent guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=yes last_change=0 last_subs=1595535189
	dsync(testuser at example.com): Debug: brain S: Remote mailbox tree: Drafts guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=yes last_change=0 last_subs=1595535189
	dsync(testuser at example.com): Debug: brain S: Remote mailbox tree: Trash guid=00000000000000000000000000000000 uid_validity=0 uid_next=0 subs=yes last_change=0 last_subs=1595534293
	dsync(testuser at example.com): Debug: brain S: Remote mailbox tree: INBOX guid=6f51443ad5eb195f9f4400005439fba4 uid_validity=1595534294 uid_next=1 subs=no last_change=0 last_subs=0
	dsync(testuser at example.com): Debug: brain S: Remote mailbox tree: Junk guid=6e51443ad5eb195f9f4400005439fba4 uid_validity=1595534293 uid_next=1 subs=yes last_change=0 last_subs=1595534293
	dsync(testuser at example.com): Debug: imapc(mx.example.com:993): Disconnected
	dsync(testuser at example.com): Debug: auth-master: conn unix:/run/dovecot/auth-userdb (pid=25419,uid=0): Disconnected: Connection closed (fd=10)

given the error again on "next doveadm backup", it indeed appears "something strange is going on" ...

hints/suggestions as to what?



More information about the dovecot mailing list