Lazy_expunge issue...

26 Mar 2015

      Hello everybody,
I have a question about lazy_expunge plugin.
I set up Dovecot with lazy_expunge plugin on a Debian Wheezy machine; I
setup also a global acl to keep .expunged namespace readonly for all
users. Imap client is Horde Webmail Edition 5.2.3
When I try to delete folders containing subfolders, lazy_expunge copies
into expunged private namespace only the upper level folder. The
subfolder and all messages inside it are lost. Quota is also corrupted
(subfolder messages are still accounted in the Message counter and disk
quota) and a recalc is needed to fix.
Problem can be reproduced this way:
1)Create with horde a folder "extern"
2)Create a subfolder "extern/subfolder"
3) copy some messages into extern and some into subfolder (in my test 5
messages each)
4) verify quota usage:
root@gold:# doveadm quota get -u testimap2.csia@unipd.it
Quota name Type    Value   Limit
%
User quota STORAGE     4 1126400
0
User quota MESSAGE     10       -
0
3)Delete extern with option to delete all subfolders.
4)verify that all folders and messages have been deleted
5)verify that only extern folder has been copied into .expunged
namespace and verify that quota still reports the 5  messages that were
in subfolder:
root@gold:# doveadm quota get -u testimap2.csia@unipd.it
Quota name Type    Value   Limit
%
User quota STORAGE     2 1126400
0
User quota MESSAGE     5       -
0
6) recalc quota and check again that quota has benne corrected:
# doveadm quota recalc -u testimap2.csia@unipd.it
root@gold:# doveadm quota get -u testimap2.csia@unipd.it
Quota name Type    Value   Limit
%
User quota STORAGE     0 1126400
0
User quota MESSAGE     0       -
0
Is there anything I am missing?
Thanks in advance.
Best regards.
Giorgio Paolucci
================================================
dovecot version 2.2.13
OS :Linux bronze 3.2.0-4-amd64 #1 SMP Debian 3.2.65-1+deb7u1 x86_64
GNU/Linux
root@bronze:/etc/dovecot/conf.d# more ../dovecot-acl
_PostaCancellataPerErrore* owner lrik
=================================================
root@bronze:/etc/dovecot/conf.d# dovecot -n
# 2.2.13: /etc/dovecot/dovecot.conf
# OS: Linux 3.2.0-4-amd64 x86_64 Debian 7.8
auth_default_realm = unipd.it
disable_plaintext_auth = no
log_path = /var/log/dovecot/dovecot.log
mail_debug = yes
mail_fsync = always
mail_nfs_index = yes
mail_nfs_storage = yes
mail_plugins = quota  listescape lazy_expunge acl  mail_log notify
mdbox_rotate_interval = 1 days
mdbox_rotate_size = 10 M
mmap_disable = yes
namespace {
hidden = no
inbox = no
list = yes
location = maildir:/SYSTEM/USERS/mailbox/%d/%1n/%u/expunged
mailbox .LEGGIMI {
auto = subscribe
}
mailbox INBOX.Trash {
auto = subscribe
special_use = \Trash
}
prefix = _PostaCancellataPerErrore/
separator = /
subscriptions = yes
type = private
}
namespace inbox {
inbox = yes
location =
mailbox Drafts {
special_use = \Drafts
}
mailbox Junk {
special_use = \Junk
}
mailbox Sent {
special_use = \Sent
}
mailbox "Sent Messages" {
special_use = \Sent
}
mailbox Trash {
special_use = \Trash
}
prefix =
separator = /
}
passdb {
args = /etc/dovecot/conf.d/dovecot-sql.conf.ext
driver = sql
}
passdb {
args = /etc/dovecot/conf.d/dovecot-sqlSSO.conf.ext
driver = sql
}
plugin {
acl = vfile:/etc/dovecot/dovecot-acl
lazy_expunge = _PostaCancellataPerErrore/
lazy_expunge_only_last_instance = yes
mail_log_events = delete undelete expunge copy mailbox_delete
mailbox_rename
mail_log_fields = uid box msgid size
pop3_migration_mailbox = INBOX
quota = dict:User quota::file:/SYSTEM/USERS/homes/%d/%1u/%u/%u.quota
quota_rule = *:storage=1100M
quota_rule2 = Trash:storage=+100M
quota_warning = storage=95%% quota-warning 95 %u
quota_warning2 = storage=80%% quota-warning 80 %u
sieve = ~/.dovecot.sieve
sieve_dir = ~/sieve
}
pop3c_host = 147.162.10.68
protocols = imap pop3 lmtp
service auth {
unix_listener /var/spool/postfix/private/auth {
group = postfix
mode = 0660
user = postfix
}
unix_listener auth-userdb {
group = vmail
mode = 0600
user = vmail
}
}
service imap-login {
inet_listener imaps {
ssl = no
}
process_min_avail = 20
service_count = 1
}
service imap {
executable = imap postlogin
}
service lmtp {
executable = lmtp
inet_listener lmtp {
port = 2223
}
process_min_avail = 15
}
service pop3-login {
inet_listener pop3s {
ssl = no
}
}
service postlogin {
executable = script-login -d rawlog
user = vmail
}
service quota-warning {
executable = script /SYSTEM/DOVECOT/scripts/quota-warning.sh
unix_listener quota-warning {
user = vmail
}
user = vmail
}
ssl = no
userdb {
driver = prefetch
}
userdb {
args = /etc/dovecot/conf.d/dovecot-sql.conf.ext
driver = sql
}
userdb {
driver = prefetch
}
userdb {
args = /etc/dovecot/conf.d/dovecot-sqlSSO.conf.ext
driver = sql
}
protocol lmtp {
info_log_path = /tmp/dovecot-lmtp-info.log
log_path = /tmp/dovecot-lmtp.log
mail_plugins = quota  listescape lazy_expunge acl  mail_log notify
}
protocol imap {
imap_max_line_length = 128 k
mail_plugins = quota  listescape lazy_expunge acl  mail_log notify
imap_quota acl imap_acl
}
protocol doveadm {
mail_plugins = quota  listescape lazy_expunge acl  mail_log notify
pop3_migration
}
protocol pop3 {
pop3_no_flag_updates = yes
pop3_uidl_format = %10v.%09u
}
===================================
root@gold:# doveadm  user -u testimap2.csia@unipd.it
userdb: testimap2.csia@unipd.it
uid       : 1003
gid       : 1003
home      : /SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it
mail      :
sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Here follows IMAP transaction in and out from rawlog
IN:
2 ENABLE QRESYNC
3 NAMESPACE
4 LIST () "" (extern/*)
5 DELETE extern
6 UNSUBSCRIBE extern
7 DELETE extern/subfolder
8 UNSUBSCRIBE extern/subfolder
9 LOGOUT
OUT:
root@gold:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it/dovecot.rawlog#
more 20150326-095943-1773.out
1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE
IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=OR
DEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN
NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARC
H ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY
MOVE QUOTA ACL RIGHTS=texk] Logged in

ENABLED QRESYNC
2 OK Enabled.
NAMESPACE (("" "/")("_PostaCancellataPerErrore/" "/")) NIL NIL
3 OK Namespace completed.
LIST () "/" extern/subfolder
4 OK List completed.
5 OK Delete completed.
6 OK Unsubscribe completed.
7 OK Delete completed.
8 OK Unsubscribe completed.
BYE Logging out
9 OK Logout completed.

==================================
Here follows debug log from dovecot.
Mar 26 09:59:43 imap-login: Info: Login: user=testimap2.csia@unipd.it,
method=PLAIN, rip=147.162.10.141, lip=147.162.10.84, mpid=1771,
session=<DXefPC0SWQCTogqN>
Mar 26 09:59:43 script-login: Debug: Added userdb setting:
mail_location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:43 imap: Debug: Loading modules from directory:
/usr/lib/dovecot/modules
Mar 26 09:59:43 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib01_acl_plugin.so
Mar 26 09:59:43 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib02_imap_acl_plugin.so
Mar 26 09:59:43 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib02_lazy_expunge_plugin.so
Mar 26 09:59:43 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib10_quota_plugin.so
Mar 26 09:59:43 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib11_imap_quota_plugin.so
Mar 26 09:59:43 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib15_notify_plugin.so
Mar 26 09:59:43 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib20_listescape_plugin.so
Mar 26 09:59:43 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib20_mail_log_plugin.so
Mar 26 09:59:43 imap: Debug: Added userdb setting:
mail_location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Effective
uid=1003, gid=1003,
home=/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl: No
acl_shared_dict setting - shared mailbox listing is disabled
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Quota root:
name=User quota backend=dict
args=:file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it/testimap2.csia@unipd.it.quota
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Quota rule:
root=User quota mailbox=* bytes=1153433600 messages=0
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Quota rule:
root=User quota mailbox=Trash bytes=+104857600 messages=0
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Quota warning:
bytes=1095761920 (95%) messages=0 reverse=no command=quota-warning 95
testimap2.csia@unipd.it
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Quota warning:
bytes=922746880 (80%) messages=0 reverse=no command=quota-warning 80
testimap2.csia@unipd.it
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Quota grace:
root=User quota bytes=115343360 (10%)
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: dict quota:
user=testimap2.csia@unipd.it,
uri=file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it/testimap2.csia@unipd.it.quota,
noenforcing=0
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Namespace inbox:
type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes,
subscriptions=yes
location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: fs:
root=/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox,
index=/INDEX1/unipd.it/t/testimap2.csia@unipd.it, indexpvt=, control=,
inbox=, alt=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl: acl username
= testimap2.csia@unipd.it
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl: owner = 1
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Namespace :
type=private, prefix=_PostaCancellataPerErrore/, sep=/, inbox=no,
hidden=no, list=yes, subscriptions=yes
location=maildir:/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: maildir++:
root=/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged,
index=, indexpvt=, control=, inbox=, alt=
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl: acl username
= testimap2.csia@unipd.it
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl: owner = 1
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/extern/subfolder/dbox-Mails/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/extern/dbox-Mails/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged/.extern/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Namespace
_PostaCancellataPerErrore/:
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged/.extern doesn't
exist yet, using default permissions
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Namespace
_PostaCancellataPerErrore/: Using permissions from
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged:
mode=0700 gid=default
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged/.extern/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=1, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=2, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=3, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=4, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=5, msgid=, size=524
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: expunge:
box=extern, uid=1, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: expunge:
box=extern, uid=2, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: expunge:
box=extern, uid=3, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: expunge:
box=extern, uid=4, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: expunge:
box=extern, uid=5, msgid=, size=524
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Debug: Namespace : Using
permissions from /NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:
mode=0755 gid=default
Mar 26 09:59:43 imap(testimap2.csia@unipd.it): Info: Mailbox deleted: extern
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Info: Mailbox deleted:
extern/subfolder
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Info: Disconnected:
Logged out in=162 out=704
Mar 26 09:59:44 imap-login: Info: Login: user=testimap2.csia@unipd.it,
method=PLAIN, rip=147.162.10.141, lip=147.162.10.84, mpid=1778,
session=<vUqqPC0SXACTogqN>
Mar 26 09:59:44 script-login: Debug: Added userdb setting:
mail_location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:44 imap: Debug: Loading modules from directory:
/usr/lib/dovecot/modules
Mar 26 09:59:44 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib01_acl_plugin.so
Mar 26 09:59:44 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib02_imap_acl_plugin.so
Mar 26 09:59:44 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib02_lazy_expunge_plugin.so
Mar 26 09:59:44 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib10_quota_plugin.so
Mar 26 09:59:44 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib11_imap_quota_plugin.so
Mar 26 09:59:44 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib15_notify_plugin.so
Mar 26 09:59:44 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib20_listescape_plugin.so
Mar 26 09:59:44 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib20_mail_log_plugin.so
Mar 26 09:59:44 imap: Debug: Added userdb setting:
mail_location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Effective
uid=1003, gid=1003,
home=/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl: No
acl_shared_dict setting - shared mailbox listing is disabled
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Quota root:
name=User quota backend=dict
args=:file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it/testimap2.csia@unipd.it.quota
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Quota rule:
root=User quota mailbox=* bytes=1153433600 messages=0
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Quota rule:
root=User quota mailbox=Trash bytes=+104857600 messages=0
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Quota warning:
bytes=1095761920 (95%) messages=0 reverse=no command=quota-warning 95
testimap2.csia@unipd.it
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Quota warning:
bytes=922746880 (80%) messages=0 reverse=no command=quota-warning 80
testimap2.csia@unipd.it
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Quota grace:
root=User quota bytes=115343360 (10%)
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: dict quota:
user=testimap2.csia@unipd.it,
uri=file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it/testimap2.csia@unipd.it.quota,
noenforcing=0
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Namespace inbox:
type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes,
subscriptions=yes
location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: fs:
root=/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox,
index=/INDEX1/unipd.it/t/testimap2.csia@unipd.it, indexpvt=, control=,
inbox=, alt=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl: acl username
= testimap2.csia@unipd.it
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl: owner = 1
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: Namespace :
type=private, prefix=_PostaCancellataPerErrore/, sep=/, inbox=no,
hidden=no, list=yes, subscriptions=yes
location=maildir:/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: maildir++:
root=/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged,
index=, indexpvt=, control=, inbox=, alt=
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl: acl username
= testimap2.csia@unipd.it
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl: owner = 1
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/INBOX/dbox-Mails/dovecot-acl
not found
Mar 26 09:59:44 imap(testimap2.csia@unipd.it): Info: Disconnected:
Logged out in=77 out=1009
Mar 26 10:01:50 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged/.INBOX\2eTrash/dovecot-acl
not found
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged/.\2eLEGGIMI/dovecot-acl
not found
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/INBOX.Trash/dbox-Mails/dovecot-acl
not found
Mar 26 10:01:51 imap-login: Info: Login: user=testimap2.csia@unipd.it,
method=PLAIN, rip=147.162.200.67, lip=147.162.10.86, mpid=2474,
session=<VWBCRC0SugCToshD>
Mar 26 10:01:51 script-login: Debug: Added userdb setting:
mail_location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 10:01:51 imap: Debug: Loading modules from directory:
/usr/lib/dovecot/modules
Mar 26 10:01:51 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib01_acl_plugin.so
Mar 26 10:01:51 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib02_imap_acl_plugin.so
Mar 26 10:01:51 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib02_lazy_expunge_plugin.so
Mar 26 10:01:51 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib10_quota_plugin.so
Mar 26 10:01:51 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib11_imap_quota_plugin.so
Mar 26 10:01:51 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib15_notify_plugin.so
Mar 26 10:01:51 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib20_listescape_plugin.so
Mar 26 10:01:51 imap: Debug: Module loaded:
/usr/lib/dovecot/modules/lib20_mail_log_plugin.so
Mar 26 10:01:51 imap: Debug: Added userdb setting:
mail_location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Effective
uid=1003, gid=1003,
home=/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl: No
acl_shared_dict setting - shared mailbox listing is disabled
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Quota root:
name=User quota backend=dict
args=:file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it/testimap2.csia@unipd.it.quota
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Quota rule:
root=User quota mailbox=* bytes=1153433600 messages=0
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Quota rule:
root=User quota mailbox=Trash bytes=+104857600 messages=0
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Quota warning:
bytes=1095761920 (95%) messages=0 reverse=no command=quota-warning 95
testimap2.csia@unipd.it
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Quota warning:
bytes=922746880 (80%) messages=0 reverse=no command=quota-warning 80
testimap2.csia@unipd.it
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Quota grace:
root=User quota bytes=115343360 (10%)
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: dict quota:
user=testimap2.csia@unipd.it,
uri=file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia@unipd.it/testimap2.csia@unipd.it.quota,
noenforcing=0
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Namespace inbox:
type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes,
subscriptions=yes
location=sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia@unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: fs:
root=/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox,
index=/INDEX1/unipd.it/t/testimap2.csia@unipd.it, indexpvt=, control=,
inbox=, alt=/ALTSTORAGE1/unipd.it/t/testimap2.csia@unipd.it
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl: acl username
= testimap2.csia@unipd.it
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl: owner = 1
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: Namespace :
type=private, prefix=_PostaCancellataPerErrore/, sep=/, inbox=no,
hidden=no, list=yes, subscriptions=yes
location=maildir:/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: maildir++:
root=/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia@unipd.it/expunged,
index=, indexpvt=, control=, inbox=, alt=
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl: acl username
= testimap2.csia@unipd.it
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl: owner = 1
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/INBOX.Trash/dbox-Mails/dovecot-acl
not found
Mar 26 10:01:51 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/Drafts/dbox-Mails/dovecot-acl
not found
Mar 26 10:01:52 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/Spam/dbox-Mails/dovecot-acl
not found
Mar 26 10:01:52 imap(testimap2.csia@unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia@unipd.it/sdbox/mailboxes/Templates/dbox-Mails/dovecot-acl
not found

Lazy_expunge issue...

giorgio paolucci

dovecot version 2.2.13 OS :Linux bronze 3.2.0-4-amd64 #1 SMP Debian 3.2.65-1+deb7u1 x86_64 GNU/Linux