thr3ads.net - dovecot - (re-) dsync: INBOX Can't be deleted [Jul 2020]

If this information is useful, please help other people find it:
Share via:

PGNet Dev

2020-Jul-24 14:34 UTC

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

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?

PGNet Dev

2020-Jul-24 15:01 UTC

head link

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

> hints/suggestions as to what?
for anyone interested, the causal issue is NOT directly the one reported in the
non-verbose output

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

rather, the relevant 'hint' is the

	Debug: brain M: Mailbox INBOX: ... GUIDs conflict - will be merged later

message seen only in the debug output.

In addition to

	rm -rf /data/vmail/example.com/testuser/Maildir

clearing the index data, configured here in a separate tree,

	rm -rf /var/vmail-index/example.com/testuser/*

does the trick.

backup, now, appears to complete.

Possibly Parallel Threads

Search for more possibly parallel threads

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

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

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

Possibly Parallel Threads