Problem getting quota-warning script to function.
Hello,
I'm trying to implement quota enforcement in our mailservers, and it is all working properly except that the quota warnings are not firing when the quota levels are passed. the server stops accepting email when the quota is reached, and you can see tyhe quota usage through the email client connected through, but as the quota passes the set levels the /usr/local/bin/quota-warning.sh script is not called. I checked from the appropriate user and the script executes from the command line with no problem, but dovecot does not invoke it when the quota levels are crossed. The relevant configs are below, any ideas about what the problem may be or how I should approach this?
/etc/dovecot/dovecot.conf
Dovecot configuration file
Protocols we want to be serving.
protocols = imap pop3 lmtp sieve
mailbox_list_index = yes
Avoid spending excessive time waiting for the quota calculation to
finish when
mails' vsizes aren't already cached. If this many mails are opened,
finish the
quota calculation on background in indexer-worker process. Mail
deliveries will
be assumed to succeed, and explicit quota lookups will return internal
error.
(v2.2.28+)
mail_vsize_bg_after_count = 100
plugin { quota_grace = 10%% # 10% is the default # quota_status_success = DUNNO # quota_status_nouser = DUNNO quota_status_overquota = "552 5.2.2 Mailbox is full" quota_vsizes = yes }
service quota-status { executable = quota-status -p postfix inet_listener { port = 10070 # You can choose any port you want } client_limit = 1 }
mail_plugins = $mail_plugins quota mail_log notify
protocol imap { # Space separated list of plugins to load (default is global mail_plugins). mail_plugins = $mail_plugins imap_quota }
Greeting message for clients.
login_greeting = Welcome to easyMail.
Show more verbose process titles (in ps). Currently shows user name and
IP address. Useful for seeing who are actually using the IMAP processes
(eg. shared mailboxes or if same uid is used for multiple accounts).
verbose_proctitle = yes
Should all processes be killed when Dovecot master process shuts down.
Setting this to "no" means that Dovecot can be upgraded without
forcing existing client connections to close (although that could also be
a problem if the upgrade is e.g. because of a security fix).
shutdown_clients = yes
A config file can also tried to be included without giving an error if
it's not found:
!include_try local.conf
service auth { unix_listener auth-master { mode = 0600 user = vmail } }
/etc/dovecot/dovecot-sql.conf.ext
Database driver: mysql, pgsql, sqlite
driver = mysql
connect = host=redacted dbname=redacted user=redacted password=reallyredacted
Default password scheme.
List of supported schemes is in
http://wiki2.dovecot.org/Authentication/PasswordSchemes
default_pass_scheme = CRYPT
password_query = select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = '%u' and banned = '0' and active = '1'
user_query = SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = '%u' and banned = '0' and active = '1'
/etc/dovecot/conf.d/90-quota.conf
Quota configuration.
Quota warnings
You can execute a given command when user exceeds a specified quota limit.
Each quota root has separate limits. Only the command for the first
exceeded limit is excecuted, so put the highest limit first.
The commands are executed via script service by connecting to the named
UNIX socket (quota-warning below).
Note that % needs to be escaped as %%, otherwise "% " expands to empty.
plugin { quota_warning = storage=95%% quota-warning 95 %u quota_warning2 = storage=90%% quota-warning 90 %u quota_warning3 = storage=80%% quota-warning 80 %u }
Example quota-warning service. The unix listener's permissions should be
set in a way that mail processes can connect to it. Below example assumes
that mail processes run as vmail user. If you use mode=0666, all
system users
can generate quota warnings to anyone.
service quota-warning { executable = script /usr/local/bin/quota-warning.sh user = dovecot unix_listener quota-warning { user = dovecot mode = 0666 } }
Quota backends
Multiple backends are supported:
# dirsize: Find and sum all the files found from mail directory. # Extremely SLOW with Maildir. It'll eat your CPU and disk I/O. # dict: Keep quota stored in dictionary (eg. SQL) # maildir: Maildir++ quota # fs: Read-only support for filesystem quota
plugin { #quota = dirsize:User quota #quota = maildir:User quota #quota = dict:User quota::proxy::quota quota = count:User quota #quota = fs:User quota }
/usr/local/bin/quota-warning.sh
#!/bin/sh
warning email for approaching their quota
usage=$1 email=$2
/usr/bin/mailx -r "support@easydns.com" -s "Quota Usager Notification" "${email:?}" < /home/vmail/quota"${usage:?}".txt
echo "$(date) ${usage} : ${email}" >> /var/log/quotatest.log
Thank you Ted easyDNS Technologies
Hello,
Most of the work was done with dovecot 2.2.27 but I just upgraded to 2.3.2 and didn't see any change. Some debug logs are below, is there something specific I could search them for?
Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: sql(billing@edwardcsmith.ca,64.68.203.197,<x3/sZhl263lARMvF>): query: select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'billing@edwardcsmith.ca' and banned = '0' and active = '1' Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: client passdb out: OK#01158#011user=billing@edwardcsmith.ca#011home=/svmail/03/edwardcsmith.ca/billing#011uid=5000#011gid=5000#011quota_rule=*:storage=2G Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master in: REQUEST#0111314258945#0111045#01158#0117968dddd529039bd9545c6d15e171c19#011session_pid=8301#011request_auth_token Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: sql(billing@edwardcsmith.ca,64.68.203.197,<x3/sZhl263lARMvF>): SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'billing@edwardcsmith.ca' and banned = '0' and active = '1' Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master userdb out: USER#0111314258945#011billing@edwardcsmith.ca#011mail=maildir:/svmail/03/edwardcsmith.ca/billing/Maildir#011home=/svmail/03/edwardcsmith.ca/billing#011uid=5000#011gid=5000#011quota_rule=*:storage=2G#011auth_token=742b2329fc04dd160562ecaeed77c6b4ac784f3f Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8300): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8300): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Added userdb setting: mail=maildir:/svmail/03/edwardcsmith.ca/billing/Maildir Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Added userdb setting: plugin/quota_rule=*:storage=2G Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Effective uid=5000, gid=5000, home=/svmail/03/edwardcsmith.ca/billing Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: client in: AUTH#01160#011PLAIN#011service=imap#011session=xevsZhl2jT5ARMvF#011lip=172.17.10.211#011rip=64.68.203.197#011lport=143#011rport=16013#011resp=<hidden> Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: sql(ted@edwardcsmith.ca,64.68.203.197,<xevsZhl2jT5ARMvF>): query: select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'ted@edwardcsmith.ca' and banned = '0' and active = '1' Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota root: name=User quota backend=count args= Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota rule: root=User quota mailbox=* bytes=2147483648 messages=0 Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota warning: bytes=2040109465 (95%) messages=0 reverse=no command=quota-warning 95 billing@edwardcsmith.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota warning: bytes=1932735283 (90%) messages=0 reverse=no command=quota-warning 90 billing@edwardcsmith.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota warning: bytes=1717986918 (80%) messages=0 reverse=no command=quota-warning 80 billing@edwardcsmith.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota grace: root=User quota bytes=214748364 (10%) Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/svmail/03/edwardcsmith.ca/billing/Maildir Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: maildir++: root=/svmail/03/edwardcsmith.ca/billing/Maildir, index=, indexpvt=, control=, inbox=/svmail/03/edwardcsmith.ca/billing/Maildir, alt= Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Sep 17 23:19:16 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: client passdb out: OK#01160#011user=ted@edwardcsmith.ca#011home=/svmail/03/edwardcsmith.ca/ted#011uid=5000#011gid=5000#011quota_rule=*:storage=2G Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8300): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master in: REQUEST#011770310145#0111045#01160#0117968dddd529039bd9545c6d15e171c19#011session_pid=8302#011request_auth_token Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: sql(ted@edwardcsmith.ca,64.68.203.197,<xevsZhl2jT5ARMvF>): SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'ted@edwardcsmith.ca' and banned = '0' and active = '1' Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: client in: AUTH#01161#011PLAIN#011service=imap#011session=5f7sZhl2IxRARMvF#011lip=172.17.10.211#011rip=64.68.203.197#011lport=143#011rport=5155#011resp=<hidden> Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master userdb out: USER#011770310145#011ted@edwardcsmith.ca#011mail=maildir:/svmail/03/edwardcsmith.ca/ted/Maildir#011home=/svmail/03/edwardcsmith.ca/ted#011uid=5000#011gid=5000#011quota_rule=*:storage=2G#011auth_token=21114b3fb366b3868d3dbb5003156728ea5fdeb7 Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Added userdb setting: mail=maildir:/svmail/03/edwardcsmith.ca/ted/Maildir Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Added userdb setting: plugin/quota_rule=*:storage=2G Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Effective uid=5000, gid=5000, home=/svmail/03/edwardcsmith.ca/ted Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota root: name=User quota backend=count args= Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota rule: root=User quota mailbox=* bytes=2147483648 messages=0 Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota warning: bytes=2040109465 (95%) messages=0 reverse=no command=quota-warning 95 ted@edwardcsmith.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota warning: bytes=1932735283 (90%) messages=0 reverse=no command=quota-warning 90 ted@edwardcsmith.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota warning: bytes=1717986918 (80%) messages=0 reverse=no command=quota-warning 80 ted@edwardcsmith.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota grace: root=User quota bytes=214748364 (10%) Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/svmail/03/edwardcsmith.ca/ted/Maildir Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: maildir++: root=/svmail/03/edwardcsmith.ca/ted/Maildir, index=, indexpvt=, control=, inbox=/svmail/03/edwardcsmith.ca/ted/Maildir, alt= Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Sep 17 23:19:16 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8300): Debug: sql(other@quailmail.ca,64.68.203.197,<upDsZhl2+T5ARMvF>): query: select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'other@quailmail.ca' and banned = '0' and active = '1' Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: client passdb out: OK#01159#011user=other@quailmail.ca#011home=/svmail/04/quailmail.ca/other#011uid=5000#011gid=5000#011quota_rule=*:storage=1G Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master in: REQUEST#0111747189761#0111045#01159#0117968dddd529039bd9545c6d15e171c19#011session_pid=8304#011request_auth_token Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: sql(other@quailmail.ca,64.68.203.197,<upDsZhl2+T5ARMvF>): SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'other@quailmail.ca' and banned = '0' and active = '1' Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master userdb out: USER#0111747189761#011other@quailmail.ca#011mail=maildir:/svmail/04/quailmail.ca/other/Maildir#011home=/svmail/04/quailmail.ca/other#011uid=5000#011gid=5000#011quota_rule=*:storage=1G#011auth_token=93f39dddaa2ae363a337e9331172edd8fd900052 Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Added userdb setting: mail=maildir:/svmail/04/quailmail.ca/other/Maildir Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Added userdb setting: plugin/quota_rule=*:storage=1G Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Effective uid=5000, gid=5000, home=/svmail/04/quailmail.ca/other Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8303): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8303): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota root: name=User quota backend=count args= Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota rule: root=User quota mailbox=* bytes=1073741824 messages=0 Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota warning: bytes=1020054732 (95%) messages=0 reverse=no command=quota-warning 95 other@quailmail.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota warning: bytes=966367641 (90%) messages=0 reverse=no command=quota-warning 90 other@quailmail.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota warning: bytes=858993459 (80%) messages=0 reverse=no command=quota-warning 80 other@quailmail.ca Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota grace: root=User quota bytes=107374182 (10%) Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/svmail/04/quailmail.ca/other/Maildir Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: maildir++: root=/svmail/04/quailmail.ca/other/Maildir, index=, indexpvt=, control=, inbox=/svmail/04/quailmail.ca/other/Maildir, alt= Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Sep 17 23:19:16 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8303): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8303): Debug: sql(00@hosehead.ninja,64.68.203.197,<5f7sZhl2IxRARMvF>): query: select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = '00@hosehead.ninja' and banned = '0' and active = '1' Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: client passdb out: OK#01161#011user=00@hosehead.ninja#011home=/svmail/03/hosehead.ninja/00#011uid=5000#011gid=5000#011quota_rule=*:storage=0G Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master in: REQUEST#0113512991745#0111045#01161#0117968dddd529039bd9545c6d15e171c19#011session_pid=8305#011request_auth_token Sep 17 23:19:16 ezm11-pco dovecot: auth-worker(8299): Debug: sql(00@hosehead.ninja,64.68.203.197,<5f7sZhl2IxRARMvF>): SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = '00@hosehead.ninja' and banned = '0' and active = '1' Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master userdb out: USER#0113512991745#01100@hosehead.ninja#011mail=maildir:/svmail/03/hosehead.ninja/00/Maildir#011home=/svmail/03/hosehead.ninja/00#011uid=5000#011gid=5000#011quota_rule=*:storage=0G#011auth_token=b431dc6c8cf37e624e300dab3e7195b4f1bdd02c Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Added userdb setting: mail=maildir:/svmail/03/hosehead.ninja/00/Maildir Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Added userdb setting: plugin/quota_rule=*:storage=0G Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Effective uid=5000, gid=5000, home=/svmail/03/hosehead.ninja/00 Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota root: name=User quota backend=count args= Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota rule: root=User quota mailbox=* bytes=0 messages=0 Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota warning: bytes=0 (95%) messages=0 reverse=no command=quota-warning 95 00@hosehead.ninja Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota warning: bytes=0 (90%) messages=0 reverse=no command=quota-warning 90 00@hosehead.ninja Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota warning: bytes=0 (80%) messages=0 reverse=no command=quota-warning 80 00@hosehead.ninja Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota grace: root=User quota bytes=0 (10%) Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/svmail/03/hosehead.ninja/00/Maildir Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: maildir++: root=/svmail/03/hosehead.ninja/00/Maildir, index=, indexpvt=, control=, inbox=/svmail/03/hosehead.ninja/00/Maildir, alt= Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Sep 17 23:19:16 ezm11-pco dovecot: imap(00@hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: INBOX: Mailbox opened because: STATUS Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: INBOX: Mailbox opened because: STATUS Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: INBOX: Mailbox opened because: STATUS Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: INBOX: Mailbox opened because: SELECT Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: INBOX: Mailbox opened because: SELECT Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: INBOX: Mailbox opened because: SELECT Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: client in: AUTH#01162#011PLAIN#011service=imap#011session=Uvv6Zhl2MBJARMvF#011lip=172.17.10.211#011rip=64.68.203.197#011lport=143#011rport=4656#011resp=<hidden> Sep 17 23:19:17 ezm11-pco dovecot: auth-worker(8299): Debug: sql(other@quailmail.ca,64.68.203.197,<Uvv6Zhl2MBJARMvF>): query: select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'other@quailmail.ca' and banned = '0' and active = '1' Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: client passdb out: OK#01162#011user=other@quailmail.ca#011home=/svmail/04/quailmail.ca/other#011uid=5000#011gid=5000#011quota_rule=*:storage=1G Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: client in: AUTH#01163#011PLAIN#011service=imap#011session=TxD7Zhl23hJARMvF#011lip=172.17.10.211#011rip=64.68.203.197#011lport=143#011rport=4830#011resp=<hidden> Sep 17 23:19:17 ezm11-pco dovecot: auth-worker(8299): Debug: sql(ted@edwardcsmith.ca,64.68.203.197,<TxD7Zhl23hJARMvF>): query: select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'ted@edwardcsmith.ca' and banned = '0' and active = '1' Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master in: REQUEST#0113224240129#0111045#01162#0117968dddd529039bd9545c6d15e171c19#011session_pid=8306#011request_auth_token Sep 17 23:19:17 ezm11-pco dovecot: auth-worker(8300): Debug: sql(other@quailmail.ca,64.68.203.197,<Uvv6Zhl2MBJARMvF>): SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'other@quailmail.ca' and banned = '0' and active = '1' Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: client passdb out: OK#01163#011user=ted@edwardcsmith.ca#011home=/svmail/03/edwardcsmith.ca/ted#011uid=5000#011gid=5000#011quota_rule=*:storage=2G Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master userdb out: USER#0113224240129#011other@quailmail.ca#011mail=maildir:/svmail/04/quailmail.ca/other/Maildir#011home=/svmail/04/quailmail.ca/other#011uid=5000#011gid=5000#011quota_rule=*:storage=1G#011auth_token=4232c07d106c0080f7f4d8e8aaa43fcb73784f08 Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: client in: AUTH#01164#011PLAIN#011service=imap#011session=tx/7Zhl2tmBARMvF#011lip=172.17.10.211#011rip=64.68.203.197#011lport=143#011rport=24758#011resp=<hidden> Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Added userdb setting: mail=maildir:/svmail/04/quailmail.ca/other/Maildir Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Added userdb setting: plugin/quota_rule=*:storage=1G Sep 17 23:19:17 ezm11-pco dovecot: auth-worker(8299): Debug: sql(billing@edwardcsmith.ca,64.68.203.197,<tx/7Zhl2tmBARMvF>): query: select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'billing@edwardcsmith.ca' and banned = '0' and active = '1' Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Effective uid=5000, gid=5000, home=/svmail/04/quailmail.ca/other Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota root: name=User quota backend=count args= Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota rule: root=User quota mailbox=* bytes=1073741824 messages=0 Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota warning: bytes=1020054732 (95%) messages=0 reverse=no command=quota-warning 95 other@quailmail.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota warning: bytes=966367641 (90%) messages=0 reverse=no command=quota-warning 90 other@quailmail.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota warning: bytes=858993459 (80%) messages=0 reverse=no command=quota-warning 80 other@quailmail.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota grace: root=User quota bytes=107374182 (10%) Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/svmail/04/quailmail.ca/other/Maildir Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: maildir++: root=/svmail/04/quailmail.ca/other/Maildir, index=, indexpvt=, control=, inbox=/svmail/04/quailmail.ca/other/Maildir, alt= Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Sep 17 23:19:17 ezm11-pco dovecot: imap(other@quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master in: REQUEST#011819855361#0111045#01163#0117968dddd529039bd9545c6d15e171c19#011session_pid=8307#011request_auth_token Sep 17 23:19:17 ezm11-pco dovecot: auth-worker(8300): Debug: sql(ted@edwardcsmith.ca,64.68.203.197,<TxD7Zhl23hJARMvF>): SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'ted@edwardcsmith.ca' and banned = '0' and active = '1' Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: client passdb out: OK#01164#011user=billing@edwardcsmith.ca#011home=/svmail/03/edwardcsmith.ca/billing#011uid=5000#011gid=5000#011quota_rule=*:storage=2G Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master userdb out: USER#011819855361#011ted@edwardcsmith.ca#011mail=maildir:/svmail/03/edwardcsmith.ca/ted/Maildir#011home=/svmail/03/edwardcsmith.ca/ted#011uid=5000#011gid=5000#011quota_rule=*:storage=2G#011auth_token=0751d36dc0d274430348b40caa89049f5551c7af Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Added userdb setting: mail=maildir:/svmail/03/edwardcsmith.ca/ted/Maildir Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Added userdb setting: plugin/quota_rule=*:storage=2G Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master in: REQUEST#0111251213313#0111045#01164#0117968dddd529039bd9545c6d15e171c19#011session_pid=8308#011request_auth_token Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Effective uid=5000, gid=5000, home=/svmail/03/edwardcsmith.ca/ted Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota root: name=User quota backend=count args= Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota rule: root=User quota mailbox=* bytes=2147483648 messages=0 Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota warning: bytes=2040109465 (95%) messages=0 reverse=no command=quota-warning 95 ted@edwardcsmith.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota warning: bytes=1932735283 (90%) messages=0 reverse=no command=quota-warning 90 ted@edwardcsmith.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota warning: bytes=1717986918 (80%) messages=0 reverse=no command=quota-warning 80 ted@edwardcsmith.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota grace: root=User quota bytes=214748364 (10%) Sep 17 23:19:17 ezm11-pco dovecot: auth-worker(8299): Debug: sql(billing@edwardcsmith.ca,64.68.203.197,<tx/7Zhl2tmBARMvF>): SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = 'billing@edwardcsmith.ca' and banned = '0' and active = '1' Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/svmail/03/edwardcsmith.ca/ted/Maildir Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: maildir++: root=/svmail/03/edwardcsmith.ca/ted/Maildir, index=, indexpvt=, control=, inbox=/svmail/03/edwardcsmith.ca/ted/Maildir, alt= Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Sep 17 23:19:17 ezm11-pco dovecot: imap(ted@edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master userdb out: USER#0111251213313#011billing@edwardcsmith.ca#011mail=maildir:/svmail/03/edwardcsmith.ca/billing/Maildir#011home=/svmail/03/edwardcsmith.ca/billing#011uid=5000#011gid=5000#011quota_rule=*:storage=2G#011auth_token=90acc0680ea51065bd01e5789c4d10453ef6a748 Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Added userdb setting: mail=maildir:/svmail/03/edwardcsmith.ca/billing/Maildir Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Added userdb setting: plugin/quota_rule=*:storage=2G Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Effective uid=5000, gid=5000, home=/svmail/03/edwardcsmith.ca/billing Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota root: name=User quota backend=count args= Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota rule: root=User quota mailbox=* bytes=2147483648 messages=0 Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota warning: bytes=2040109465 (95%) messages=0 reverse=no command=quota-warning 95 billing@edwardcsmith.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota warning: bytes=1932735283 (90%) messages=0 reverse=no command=quota-warning 90 billing@edwardcsmith.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota warning: bytes=1717986918 (80%) messages=0 reverse=no command=quota-warning 80 billing@edwardcsmith.ca Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota grace: root=User quota bytes=214748364 (10%) Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/svmail/03/edwardcsmith.ca/billing/Maildir Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: maildir++: root=/svmail/03/edwardcsmith.ca/billing/Maildir, index=, indexpvt=, control=, inbox=/svmail/03/edwardcsmith.ca/billing/Maildir, alt= Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Sep 17 23:19:17 ezm11-pco dovecot: imap(billing@edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: client in: AUTH#01165#011PLAIN#011service=imap#011session=00z7Zhl211lARMvF#011lip=172.17.10.211#011rip=64.68.203.197#011lport=143#011rport=22999#011resp=<hidden> Sep 17 23:19:17 ezm11-pco dovecot: auth-worker(8299): Debug: sql(00@hosehead.ninja,64.68.203.197,<00z7Zhl211lARMvF>): query: select email as user, crypt_password as password, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = '00@hosehead.ninja' and banned = '0' and active = '1' Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: client passdb out: OK#01165#011user=00@hosehead.ninja#011home=/svmail/03/hosehead.ninja/00#011uid=5000#011gid=5000#011quota_rule=*:storage=0G Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master in: REQUEST#0114049862657#0111045#01165#0117968dddd529039bd9545c6d15e171c19#011session_pid=8309#011request_auth_token Sep 17 23:19:17 ezm11-pco dovecot: auth-worker(8299): Debug: sql(00@hosehead.ninja,64.68.203.197,<00z7Zhl211lARMvF>): SELECT CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir') AS mail, CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1)) AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit ,'G') AS quota_rule from easymail_users where email = '00@hosehead.ninja' and banned = '0' and active = '1' Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master userdb out: USER#0114049862657#01100@hosehead.ninja#011mail=maildir:/svmail/03/hosehead.ninja/00/Maildir#011home=/svmail/03/hosehead.ninja/00#011uid=5000#011gid=5000#011quota_rule=*:storage=0G#011auth_token=4e2572405b7d42c5dc4f273ba1e8d0ed5545f123 Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Loading modules from directory: /usr/lib/dovecot/modules Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib11_imap_quota_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Added userdb setting: mail=maildir:/svmail/03/hosehead.ninja/00/Maildir Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Added userdb setting: plugin/quota_rule=*:storage=0G Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Effective uid=5000, gid=5000, home=/svmail/03/hosehead.ninja/00 Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota root: name=User quota backend=count args= Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota rule: root=User quota mailbox=* bytes=0 messages=0 Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota warning: bytes=0 (95%) messages=0 reverse=no command=quota-warning 95 00@hosehead.ninja Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota warning: bytes=0 (90%) messages=0 reverse=no command=quota-warning 90 00@hosehead.ninja Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota warning: bytes=0 (80%) messages=0 reverse=no command=quota-warning 80 00@hosehead.ninja Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota grace: root=User quota bytes=0 (10%) Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/svmail/03/hosehead.ninja/00/Maildir Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: maildir++: root=/svmail/03/hosehead.ninja/00/Maildir, index=, indexpvt=, control=, inbox=/svmail/03/hosehead.ninja/00/Maildir, alt= Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: quota: quota_over_flag check: quota_over_script unset - skipping Sep 17 23:19:17 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: INBOX: Mailbox opened because: STATUS Sep 17 23:19:18 ezm11-pco dovecot: imap(00@hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: INBOX: Mailbox opened because: SELECT
Thank you Ted easyDNS Technologies
On 2018-09-19 02:52 AM, Aki Tuomi wrote:
Hello,
Here are the logs of the emails which sent the usage from 79% to 80%, this is from Friday but it was made using the same configs I sent earlier:
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Added userdb setting:
mail=maildir:/svmail/04/quailmail.ca/other/Maildir
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Added userdb setting: plugin/quota_rule=*:storage=1G
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Effective uid=5000, gid=5000, home=/svmail/04/quailmail.ca/other
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota root: name=User quota backend=count args=
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota rule: root=User quota mailbox=* bytes=1073741824 messages=0
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota warning: bytes=1020054732 (95%) messages=0 reverse=no
command=quota-warning 95 other@quailmail.ca
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota warning: bytes=966367641 (90%) messages=0 reverse=no
command=quota-warning 90 other@quailmail.ca
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota warning: bytes=858993459 (80%) messages=0 reverse=no
command=quota-warning 80 other@quailmail.ca
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota grace: root=User quota bytes=107374182 (10%)
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes,
hidden=no, list=yes, subscriptions=yes
location=maildir:/svmail/04/quailmail.ca/other/Maildir
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: maildir++: root=/svmail/04/quailmail.ca/other/Maildir, index=,
indexpvt=, control=, inbox=/svmail/04/quailmail.ca/other/Maildir, alt=
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes,
list=no, subscriptions=no location=fail::LAYOUT=none
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: none: root=, index=, indexpvt=, control=, inbox=, alt=
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Namespace INBOX.: Using permissions from
/svmail/04/quailmail.ca/other/Maildir: mode=0700 gid=default
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: quota: quota_over_flag check: STORAGE ret=1 value=836364
limit=1048576
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: quota: quota_over_flag check: MESSAGE ret=0 value=7577 limit=0
Sep 14 18:10:32 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: quota: quota_over_flag=0((null)) vs currently overquota=0
Sep 14 18:10:32 ezm11-pco amavis[1132]: (01132-02) ESMTP
[127.0.0.1]:10024
/var/lib/amavis/tmp/amavis-20180914T172035-01132-s1SrEj_J:
<ted@easydns.com> -> <other@quailmail.ca> SIZE=10997937 BODY=7BIT
Received: from smtp.easymail.ca ([127.0.0.1]) by localhost
(ezm11-pco.easydns.vpn [127.0.0.1]) (amavisd-new, port 10024) with ESMTP
for <other@quailmail.ca>; Fri, 14 Sep 2018 18:10:32 +0000 (UTC)
Sep 14 18:10:32 ezm11-pco amavis[1132]: (01132-02) Checking:
sFADxdNkitz8 [64.68.203.197] <ted@easydns.com> -> <other@quailmail.ca>
Sep 14 18:10:33 ezm11-pco amavis[1132]: (01132-02) spam-tag,
<ted@easydns.com> -> <other@quailmail.ca>, No, score=0 required=4
tests=[SPF_PASS=-0.001, TVD_SPACE_RATIO=0.001] autolearn=ham
autolearn_force=no
Sep 14 18:10:33 ezm11-pco amavis[1132]: (01132-02) sFADxdNkitz8 FWD from
<ted@easydns.com> -> <other@quailmail.ca>, BODY=7BIT 250 2.0.0 from
MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as A3FC4207A5
Sep 14 18:10:33 ezm11-pco amavis[1132]: (01132-02) Passed CLEAN
{RelayedInbound}, [64.68.203.197]:9154 [64.68.203.197] <ted@easydns.com>
-> <other@quailmail.ca>, Queue-ID: 3CF122077F, Message-ID:
<aeb7398b-0ce0-187b-9ac5-8ca76375edb5@easydns.com>, mail_id:
sFADxdNkitz8, Hits: 0, size: 10997937, queued_as: A3FC4207A5, 1310 ms
Sep 14 18:10:33 ezm11-pco postfix/smtp[17037]: 3CF122077F:
to=<other@quailmail.ca>, relay=127.0.0.1[127.0.0.1]:10024, delay=1.8,
delays=0.5/0.01/0/1.3, dsn=2.0.0, status=sent (250 2.0.0 from
MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as A3FC4207A5)
Sep 14 18:10:33 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
Loading modules from directory: /usr/lib/dovecot/modules
Sep 14 18:10:33 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
Module loaded: /usr/lib/dovecot/modules/lib90_sieve_plugin.so
Sep 14 18:10:33 ezm11-pco dovecot: auth: Debug: master in:
USER#0111#011other@quailmail.ca#011service=lmtp
Sep 14 18:10:33 ezm11-pco dovecot: auth-worker(17035): Debug:
sql(other@quailmail.ca): SELECT
CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir')
AS mail,
CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1))
AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit
,'G') AS quota_rule from easymail_users where email =
'other@quailmail.ca' and banned = '0' and active = '1'
Sep 14 18:10:33 ezm11-pco dovecot: auth: Debug: userdb out:
USER#0111#011other@quailmail.ca#011mail=maildir:/svmail/04/quailmail.ca/other/Maildir#011home=/svmail/04/quailmail.ca/other#011uid=5000#011gid=5000#011quota_rule=*:storage=1G
Sep 14 18:10:33 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug: auth
USER input: other@quailmail.ca
mail=maildir:/svmail/04/quailmail.ca/other/Maildir
home=/svmail/04/quailmail.ca/other uid=5000 gid=5000 quota_rule=*:storage=1G
Sep 14 18:10:33 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
Added userdb setting: mail=maildir:/svmail/04/quailmail.ca/other/Maildir
Sep 14 18:10:33 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
Added userdb setting: plugin/quota_rule=*:storage=1G
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(17044, other@quailmail.ca):
Debug: Effective uid=5000, gid=5000, home=/svmail/04/quailmail.ca/other
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(17044, other@quailmail.ca):
Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes,
hidden=no, list=yes, subscriptions=yes
location=maildir:/svmail/04/quailmail.ca/other/Maildir
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(17044, other@quailmail.ca):
Debug: maildir++: root=/svmail/04/quailmail.ca/other/Maildir, index=,
indexpvt=, control=, inbox=/svmail/04/quailmail.ca/other/Maildir, alt=
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(17044, other@quailmail.ca):
Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes,
list=no, subscriptions=no location=fail::LAYOUT=none
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(17044, other@quailmail.ca):
Debug: none: root=, index=, indexpvt=, control=, inbox=, alt=
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
sieve: Pigeonhole version 0.4.16 (fed8554) initializing
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
sieve: include: sieve_global is not set; it is currently not possible to
include :global' scripts. Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug: 8+waOpn5m1uUQgAAXc2cvg: sieve: file storage: Storage path
/svmail/04/quailmail.ca/other/sieve' not found
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
8+waOpn5m1uUQgAAXc2cvg: sieve: file storage: Storage path
`/svmail/04/quailmail.ca/other/.dovecot.sieve' not found
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
8+waOpn5m1uUQgAAXc2cvg: sieve: storage: No default script location
configured
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
8+waOpn5m1uUQgAAXc2cvg: sieve: User has no personal script
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
8+waOpn5m1uUQgAAXc2cvg: sieve: No scripts to execute: reverting to
default delivery.
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
Mailbox <lmtp DATA>: Opened mail UID=1 because: copying
Sep 14 18:10:34 ezm11-pco dovecot: lmtp(other@quailmail.ca):
8+waOpn5m1uUQgAAXc2cvg:
msgid=<aeb7398b-0ce0-187b-9ac5-8ca76375edb5@easydns.com>: saved mail to
INBOX
Sep 14 18:10:34 ezm11-pco postfix/lmtp[17043]: A3FC4207A5:
to=<other@quailmail.ca>, relay=smtp.easymail.ca[private/dovecot-lmtp],
delay=0.64, delays=0.29/0.01/0.01/0.34, dsn=2.0.0, status=sent (250
2.0.0 <other@quailmail.ca> 8+waOpn5m1uUQgAAXc2cvg Saved)
Sep 14 18:11:04 ezm11-pco dovecot: auth: Debug: master in:
USER#0112#011other@quailmail.ca#011service=quota-status
Sep 14 18:11:04 ezm11-pco dovecot: auth-worker(17035): Debug:
sql(other@quailmail.ca): SELECT
CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir')
AS mail,
CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1))
AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit
,'G') AS quota_rule from easymail_users where email =
'other@quailmail.ca' and banned = '0' and active = '1'
Sep 14 18:11:04 ezm11-pco dovecot: auth: Debug: userdb out:
USER#0112#011other@quailmail.ca#011mail=maildir:/svmail/04/quailmail.ca/other/Maildir#011home=/svmail/04/quailmail.ca/other#011uid=5000#011gid=5000#011quota_rule=*:storage=1G
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: auth USER input: other@quailmail.ca
mail=maildir:/svmail/04/quailmail.ca/other/Maildir
home=/svmail/04/quailmail.ca/other uid=5000 gid=5000 quota_rule=*:storage=1G
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Added userdb setting:
mail=maildir:/svmail/04/quailmail.ca/other/Maildir
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Added userdb setting: plugin/quota_rule=*:storage=1G
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Effective uid=5000, gid=5000, home=/svmail/04/quailmail.ca/other
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota root: name=User quota backend=count args=
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota rule: root=User quota mailbox=* bytes=1073741824 messages=0
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota warning: bytes=1020054732 (95%) messages=0 reverse=no
command=quota-warning 95 other@quailmail.ca
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota warning: bytes=966367641 (90%) messages=0 reverse=no
command=quota-warning 90 other@quailmail.ca
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota warning: bytes=858993459 (80%) messages=0 reverse=no
command=quota-warning 80 other@quailmail.ca
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Quota grace: root=User quota bytes=107374182 (10%)
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Namespace : type=private, prefix=INBOX., sep=., inbox=yes,
hidden=no, list=yes, subscriptions=yes
location=maildir:/svmail/04/quailmail.ca/other/Maildir
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: maildir++: root=/svmail/04/quailmail.ca/other/Maildir, index=,
indexpvt=, control=, inbox=/svmail/04/quailmail.ca/other/Maildir, alt=
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes,
list=no, subscriptions=no location=fail::LAYOUT=none
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: none: root=, index=, indexpvt=, control=, inbox=, alt=
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: Namespace INBOX.: Using permissions from
/svmail/04/quailmail.ca/other/Maildir: mode=0700 gid=default
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: quota: quota_over_flag check: STORAGE ret=1 value=847105
limit=1048576
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: quota: quota_over_flag check: MESSAGE ret=0 value=7578 limit=0
Sep 14 18:11:04 ezm11-pco dovecot: quota-status(other@quailmail.ca):
Debug: quota: quota_over_flag=0((null)) vs currently overquota=0
Sep 14 18:11:05 ezm11-pco amavis[1130]: (01130-01) ESMTP
[127.0.0.1]:10024
/var/lib/amavis/tmp/amavis-20180914T181105-01130-c1dnU_QP:
<ted@easydns.com> -> <other@quailmail.ca> SIZE=10997943 BODY=7BIT
Received: from smtp.easymail.ca ([127.0.0.1]) by localhost
(ezm11-pco.easydns.vpn [127.0.0.1]) (amavisd-new, port 10024) with ESMTP
for <other@quailmail.ca>; Fri, 14 Sep 2018 18:11:05 +0000 (UTC)
Sep 14 18:11:05 ezm11-pco amavis[1130]: (01130-01) Checking:
MAOKJ8YSvJXH [64.68.203.197] <ted@easydns.com> -> <other@quailmail.ca>
Sep 14 18:11:06 ezm11-pco amavis[1130]: (01130-01) spam-tag,
<ted@easydns.com> -> <other@quailmail.ca>, No, score=0 required=4
tests=[BODY_SINGLE_WORD=0.001, SPF_PASS=-0.001] autolearn=ham
autolearn_force=no
Sep 14 18:11:06 ezm11-pco amavis[1130]: (01130-01) MAOKJ8YSvJXH FWD from
<ted@easydns.com> -> <other@quailmail.ca>, BODY=7BIT 250 2.0.0 from
MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 526FA207A9
Sep 14 18:11:06 ezm11-pco dovecot: auth: Debug: master in:
USER#0112#011other@quailmail.ca#011service=lmtp
Sep 14 18:11:06 ezm11-pco dovecot: auth-worker(17035): Debug:
sql(other@quailmail.ca): SELECT
CONCAT('maildir:/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1),'/Maildir')
AS mail,
CONCAT('/',volume,'/',SUBSTRING_INDEX(email,'@',-1),'/',SUBSTRING_INDEX(email,'@',1))
AS home, 5000 AS uid, 5000 AS gid, concat('*:storage=', volume_limit
,'G') AS quota_rule from easymail_users where email =
'other@quailmail.ca' and banned = '0' and active = '1'
Sep 14 18:11:06 ezm11-pco amavis[1130]: (01130-01) Passed CLEAN
{RelayedInbound}, [64.68.203.197]:1101 [64.68.203.197] <ted@easydns.com>
-> <other@quailmail.ca>, Queue-ID: CD8CE2077F, Message-ID:
<0f892fdf-5e81-02bd-1985-240cb26501f9@easydns.com>, mail_id:
MAOKJ8YSvJXH, Hits: 0, size: 10997943, queued_as: 526FA207A9, 1241 ms
Sep 14 18:11:06 ezm11-pco dovecot: auth: Debug: userdb out:
USER#0112#011other@quailmail.ca#011mail=maildir:/svmail/04/quailmail.ca/other/Maildir#011home=/svmail/04/quailmail.ca/other#011uid=5000#011gid=5000#011quota_rule=*:storage=1G
Sep 14 18:11:06 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug: auth
USER input: other@quailmail.ca
mail=maildir:/svmail/04/quailmail.ca/other/Maildir
home=/svmail/04/quailmail.ca/other uid=5000 gid=5000 quota_rule=*:storage=1G
Sep 14 18:11:06 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
Added userdb setting: mail=maildir:/svmail/04/quailmail.ca/other/Maildir
Sep 14 18:11:06 ezm11-pco dovecot: lmtp(other@quailmail.ca): Debug:
Added userdb setting: plugin/quota_rule=*:storage=1G
Sep 14 18:11:06 ezm11-pco postfix/smtp[17037]: CD8CE2077F:
to=<other@quailmail.ca>, relay=127.0.0.1[127.0.0.1]:10024, delay=1.8,
delays=0.55/0/0.01/1.2, dsn=2.0.0, status=sent (250 2.0.0 from
MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 526FA207A9)
Thank you Ted easyDNS Technologies
On 2018-09-19 12:55 PM, Aki Tuomi wrote:
Hello,
I haven't received a reply since I sent my last logs in, so I thought I'd ask again and update the thread. I had trouble getting the server to work properly on dovecot 2.3.2 so I rebuilt the server back on 2.2.27. I've got the quota enforcement itself working, but the warnings still fail to fire. I've attached logs with mail_debug=yes for the period from the send which put the quota at 90% all the way until it is blocking email for being overquota.
Thank you Ted easyDNS Technologies
On 2018-09-19 12:55 PM, Aki Tuomi wrote:
Hello,
Do you mean you're using the configurations I sent as examples and are seeing the quota warning script effectively? I've continued to try changes to get it working but no luck yet. Might it be somehow related to the directories mail is stored in being shared NFS mounts?
Thank you Ted easyDNS Technologies
On 2018-10-09 09:45 AM, Aki Tuomi wrote:
participants (3)
-
Aki Tuomi
-
Aki Tuomi
-
Ted