Lazy_expunge issue...

giorgio paolucci giorgio.paolucci at unipd.it
Thu Mar 26 09:31:00 UTC 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 at gold:# doveadm quota get -u testimap2.csia at 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 at gold:# doveadm quota get -u testimap2.csia at 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 at unipd.it
root at gold:# doveadm quota get -u testimap2.csia at 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 at bronze:/etc/dovecot/conf.d# more ../dovecot-acl
_PostaCancellataPerErrore* owner lrik

=================================================
root at 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 at gold:# doveadm  user -u testimap2.csia at unipd.it
userdb: testimap2.csia at unipd.it
  uid       : 1003
  gid       : 1003
  home      : /SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it
  mail      :
sdbox:/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at 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 at gold:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at 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 at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Effective
uid=1003, gid=1003,
home=/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl: No
acl_shared_dict setting - shared mailbox listing is disabled
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Quota root:
name=User quota backend=dict
args=:file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it/testimap2.csia at unipd.it.quota
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Quota rule:
root=User quota mailbox=* bytes=1153433600 messages=0
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Quota rule:
root=User quota mailbox=Trash bytes=+104857600 messages=0
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Quota warning:
bytes=1095761920 (95%) messages=0 reverse=no command=quota-warning 95
testimap2.csia at unipd.it
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Quota warning:
bytes=922746880 (80%) messages=0 reverse=no command=quota-warning 80
testimap2.csia at unipd.it
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Quota grace:
root=User quota bytes=115343360 (10%)
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: dict quota:
user=testimap2.csia at unipd.it,
uri=file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it/testimap2.csia at unipd.it.quota,
noenforcing=0
Mar 26 09:59:43 imap(testimap2.csia at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: fs:
root=/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox,
index=/INDEX1/unipd.it/t/testimap2.csia at unipd.it, indexpvt=, control=,
inbox=, alt=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl: acl username
= testimap2.csia at unipd.it
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl: owner = 1
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 09:59:43 imap(testimap2.csia at 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 at unipd.it/expunged
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: maildir++:
root=/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged,
index=, indexpvt=, control=, inbox=, alt=
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl: acl username
= testimap2.csia at unipd.it
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl: owner = 1
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/extern/subfolder/dbox-Mails/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/extern/dbox-Mails/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged/.extern/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Namespace
_PostaCancellataPerErrore/:
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged/.extern doesn't
exist yet, using default permissions
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Namespace
_PostaCancellataPerErrore/: Using permissions from
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged:
mode=0700 gid=default
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged/.extern/dovecot-acl
not found
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=1, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=2, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=3, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=4, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: copy from extern:
box=_PostaCancellataPerErrore/extern, uid=5, msgid=, size=524
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: expunge:
box=extern, uid=1, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: expunge:
box=extern, uid=2, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: expunge:
box=extern, uid=3, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: expunge:
box=extern, uid=4, msgid=, size=523
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: expunge:
box=extern, uid=5, msgid=, size=524
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Debug: Namespace : Using
permissions from /NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox:
mode=0755 gid=default
Mar 26 09:59:43 imap(testimap2.csia at unipd.it): Info: Mailbox deleted: extern
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Info: Mailbox deleted:
extern/subfolder
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Info: Disconnected:
Logged out in=162 out=704
Mar 26 09:59:44 imap-login: Info: Login: user=<testimap2.csia at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: Effective
uid=1003, gid=1003,
home=/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl: No
acl_shared_dict setting - shared mailbox listing is disabled
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: Quota root:
name=User quota backend=dict
args=:file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it/testimap2.csia at unipd.it.quota
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: Quota rule:
root=User quota mailbox=* bytes=1153433600 messages=0
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: Quota rule:
root=User quota mailbox=Trash bytes=+104857600 messages=0
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: Quota warning:
bytes=1095761920 (95%) messages=0 reverse=no command=quota-warning 95
testimap2.csia at unipd.it
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: Quota warning:
bytes=922746880 (80%) messages=0 reverse=no command=quota-warning 80
testimap2.csia at unipd.it
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: Quota grace:
root=User quota bytes=115343360 (10%)
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: dict quota:
user=testimap2.csia at unipd.it,
uri=file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it/testimap2.csia at unipd.it.quota,
noenforcing=0
Mar 26 09:59:44 imap(testimap2.csia at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: fs:
root=/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox,
index=/INDEX1/unipd.it/t/testimap2.csia at unipd.it, indexpvt=, control=,
inbox=, alt=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl: acl username
= testimap2.csia at unipd.it
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl: owner = 1
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 09:59:44 imap(testimap2.csia at 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 at unipd.it/expunged
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: maildir++:
root=/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged,
index=, indexpvt=, control=, inbox=, alt=
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl: acl username
= testimap2.csia at unipd.it
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl: owner = 1
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/INBOX/dbox-Mails/dovecot-acl
not found
Mar 26 09:59:44 imap(testimap2.csia at unipd.it): Info: Disconnected:
Logged out in=77 out=1009
Mar 26 10:01:50 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged/.INBOX\2eTrash/dovecot-acl
not found
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged/.\2eLEGGIMI/dovecot-acl
not found
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/INBOX.Trash/dbox-Mails/dovecot-acl
not found
Mar 26 10:01:51 imap-login: Info: Login: user=<testimap2.csia at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: Effective
uid=1003, gid=1003,
home=/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl: No
acl_shared_dict setting - shared mailbox listing is disabled
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: Quota root:
name=User quota backend=dict
args=:file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it/testimap2.csia at unipd.it.quota
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: Quota rule:
root=User quota mailbox=* bytes=1153433600 messages=0
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: Quota rule:
root=User quota mailbox=Trash bytes=+104857600 messages=0
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: Quota warning:
bytes=1095761920 (95%) messages=0 reverse=no command=quota-warning 95
testimap2.csia at unipd.it
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: Quota warning:
bytes=922746880 (80%) messages=0 reverse=no command=quota-warning 80
testimap2.csia at unipd.it
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: Quota grace:
root=User quota bytes=115343360 (10%)
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: dict quota:
user=testimap2.csia at unipd.it,
uri=file:/SYSTEM/USERS/homes/unipd.it/t/testimap2.csia at unipd.it/testimap2.csia at unipd.it.quota,
noenforcing=0
Mar 26 10:01:51 imap(testimap2.csia at 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 at unipd.it/sdbox:INDEX=/INDEX1/unipd.it/t/testimap2.csia at unipd.it:ALT=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: fs:
root=/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox,
index=/INDEX1/unipd.it/t/testimap2.csia at unipd.it, indexpvt=, control=,
inbox=, alt=/ALTSTORAGE1/unipd.it/t/testimap2.csia at unipd.it
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl: acl username
= testimap2.csia at unipd.it
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl: owner = 1
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 10:01:51 imap(testimap2.csia at 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 at unipd.it/expunged
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: maildir++:
root=/SYSTEM/USERS/mailbox/unipd.it/t/testimap2.csia at unipd.it/expunged,
index=, indexpvt=, control=, inbox=, alt=
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl: initializing
backend with data: vfile:/etc/dovecot/dovecot-acl
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl: acl username
= testimap2.csia at unipd.it
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl: owner = 1
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl vfile: Global
ACL file: /etc/dovecot/dovecot-acl
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/INBOX.Trash/dbox-Mails/dovecot-acl
not found
Mar 26 10:01:51 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/Drafts/dbox-Mails/dovecot-acl
not found
Mar 26 10:01:52 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/Spam/dbox-Mails/dovecot-acl
not found
Mar 26 10:01:52 imap(testimap2.csia at unipd.it): Debug: acl vfile: file
/NAS1/unipd.it/mailbox/t/testimap2.csia at unipd.it/sdbox/mailboxes/Templates/dbox-Mails/dovecot-acl
not found

More information about the dovecot mailing list