Problem getting quota-warning script to function.

Ted ted at easydns.com
Wed Sep 19 19:49:32 EEST 2018 

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 at 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 at 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 at 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 at 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 at edwardcsmith.ca' and banned = '0' and active = '1'
Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master userdb out:
USER#0111314258945#011billing at 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 at 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 at 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 at 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 at 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 at 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 at 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 at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Added
userdb setting: plugin/quota_rule=*:storage=2G
Sep 17 23:19:16 ezm11-pco dovecot:
imap(billing at 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 at 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 at edwardcsmith.ca' and banned = '0' and active = '1'
Sep 17 23:19:16 ezm11-pco dovecot:
imap(billing at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota
root: name=User quota backend=count args=
Sep 17 23:19:16 ezm11-pco dovecot:
imap(billing at 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 at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota
warning: bytes=2040109465 (95%) messages=0 reverse=no
command=quota-warning 95 billing at edwardcsmith.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(billing at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota
warning: bytes=1932735283 (90%) messages=0 reverse=no
command=quota-warning 90 billing at edwardcsmith.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(billing at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota
warning: bytes=1717986918 (80%) messages=0 reverse=no
command=quota-warning 80 billing at edwardcsmith.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(billing at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: Quota
grace: root=User quota bytes=214748364 (10%)
Sep 17 23:19:16 ezm11-pco dovecot:
imap(billing at 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 at 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 at 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 at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: none:
root=, index=, indexpvt=, control=, inbox=, alt=
Sep 17 23:19:16 ezm11-pco dovecot:
imap(billing at 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 at 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 at 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 at 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 at 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 at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Loading
modules from directory: /usr/lib/dovecot/modules
Sep 17 23:19:16 ezm11-pco dovecot:
imap(ted at 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 at 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 at 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 at 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 at 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 at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Added userdb
setting: plugin/quota_rule=*:storage=2G
Sep 17 23:19:16 ezm11-pco dovecot:
imap(ted at 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 at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota root:
name=User quota backend=count args=
Sep 17 23:19:16 ezm11-pco dovecot:
imap(ted at 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 at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota warning:
bytes=2040109465 (95%) messages=0 reverse=no command=quota-warning 95
ted at edwardcsmith.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(ted at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota warning:
bytes=1932735283 (90%) messages=0 reverse=no command=quota-warning 90
ted at edwardcsmith.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(ted at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota warning:
bytes=1717986918 (80%) messages=0 reverse=no command=quota-warning 80
ted at edwardcsmith.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(ted at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: Quota grace:
root=User quota bytes=214748364 (10%)
Sep 17 23:19:16 ezm11-pco dovecot:
imap(ted at 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 at 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 at 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 at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: none: root=,
index=, indexpvt=, control=, inbox=, alt=
Sep 17 23:19:16 ezm11-pco dovecot:
imap(ted at 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 at 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 at 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 at 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 at 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 at quailmail.ca' and banned = '0' and active = '1'
Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master userdb out:
USER#0111747189761#011other at 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 at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Loading modules
from directory: /usr/lib/dovecot/modules
Sep 17 23:19:16 ezm11-pco dovecot:
imap(other at 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 at 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 at 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 at 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 at 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 at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Added userdb
setting: plugin/quota_rule=*:storage=1G
Sep 17 23:19:16 ezm11-pco dovecot:
imap(other at 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 at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota root:
name=User quota backend=count args=
Sep 17 23:19:16 ezm11-pco dovecot:
imap(other at 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 at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota warning:
bytes=1020054732 (95%) messages=0 reverse=no command=quota-warning 95
other at quailmail.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(other at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota warning:
bytes=966367641 (90%) messages=0 reverse=no command=quota-warning 90
other at quailmail.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(other at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota warning:
bytes=858993459 (80%) messages=0 reverse=no command=quota-warning 80
other at quailmail.ca
Sep 17 23:19:16 ezm11-pco dovecot:
imap(other at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: Quota grace:
root=User quota bytes=107374182 (10%)
Sep 17 23:19:16 ezm11-pco dovecot:
imap(other at 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 at 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 at 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 at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: none: root=,
index=, indexpvt=, control=, inbox=, alt=
Sep 17 23:19:16 ezm11-pco dovecot:
imap(other at 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 at 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 at 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 at 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 at 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 at hosehead.ninja' and banned = '0' and active = '1'
Sep 17 23:19:16 ezm11-pco dovecot: auth: Debug: master userdb out:
USER#0113512991745#01100 at 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 at hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Loading modules
from directory: /usr/lib/dovecot/modules
Sep 17 23:19:16 ezm11-pco dovecot:
imap(00 at 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 at 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 at 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 at 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 at 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 at hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Added userdb
setting: plugin/quota_rule=*:storage=0G
Sep 17 23:19:16 ezm11-pco dovecot:
imap(00 at 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 at hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota root:
name=User quota backend=count args=
Sep 17 23:19:16 ezm11-pco dovecot:
imap(00 at 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 at hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota warning:
bytes=0 (95%) messages=0 reverse=no command=quota-warning 95
00 at hosehead.ninja
Sep 17 23:19:16 ezm11-pco dovecot:
imap(00 at hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota warning:
bytes=0 (90%) messages=0 reverse=no command=quota-warning 90
00 at hosehead.ninja
Sep 17 23:19:16 ezm11-pco dovecot:
imap(00 at hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota warning:
bytes=0 (80%) messages=0 reverse=no command=quota-warning 80
00 at hosehead.ninja
Sep 17 23:19:16 ezm11-pco dovecot:
imap(00 at hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: Quota grace:
root=User quota bytes=0 (10%)
Sep 17 23:19:16 ezm11-pco dovecot:
imap(00 at 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 at 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 at 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 at hosehead.ninja)<8305><5f7sZhl2IxRARMvF>: Debug: none: root=,
index=, indexpvt=, control=, inbox=, alt=
Sep 17 23:19:16 ezm11-pco dovecot:
imap(00 at 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 at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: INBOX:
Mailbox opened because: STATUS
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: INBOX: Mailbox
opened because: STATUS
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at edwardcsmith.ca)<8302><xevsZhl2jT5ARMvF>: Debug: INBOX: Mailbox
opened because: STATUS
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at quailmail.ca)<8304><upDsZhl2+T5ARMvF>: Debug: INBOX: Mailbox
opened because: SELECT
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at edwardcsmith.ca)<8301><x3/sZhl263lARMvF>: Debug: INBOX:
Mailbox opened because: SELECT
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Loading modules
from directory: /usr/lib/dovecot/modules
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at 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 at 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 at 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 at 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 at 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 at 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 at 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 at edwardcsmith.ca' and banned = '0' and active = '1'
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at 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 at quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota root:
name=User quota backend=count args=
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at 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 at quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota warning:
bytes=1020054732 (95%) messages=0 reverse=no command=quota-warning 95
other at quailmail.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota warning:
bytes=966367641 (90%) messages=0 reverse=no command=quota-warning 90
other at quailmail.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota warning:
bytes=858993459 (80%) messages=0 reverse=no command=quota-warning 80
other at quailmail.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: Quota grace:
root=User quota bytes=107374182 (10%)
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at 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 at 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 at 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 at quailmail.ca)<8306><Uvv6Zhl2MBJARMvF>: Debug: none: root=,
index=, indexpvt=, control=, inbox=, alt=
Sep 17 23:19:17 ezm11-pco dovecot:
imap(other at 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 at 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 at 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 at 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 at 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 at edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Loading
modules from directory: /usr/lib/dovecot/modules
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at 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 at 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 at 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 at 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 at 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 at 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 at 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 at edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota root:
name=User quota backend=count args=
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at 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 at edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota warning:
bytes=2040109465 (95%) messages=0 reverse=no command=quota-warning 95
ted at edwardcsmith.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota warning:
bytes=1932735283 (90%) messages=0 reverse=no command=quota-warning 90
ted at edwardcsmith.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: Quota warning:
bytes=1717986918 (80%) messages=0 reverse=no command=quota-warning 80
ted at edwardcsmith.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at 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 at 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 at edwardcsmith.ca' and banned = '0' and active = '1'
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at 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 at 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 at 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 at edwardcsmith.ca)<8307><TxD7Zhl23hJARMvF>: Debug: none: root=,
index=, indexpvt=, control=, inbox=, alt=
Sep 17 23:19:17 ezm11-pco dovecot:
imap(ted at 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 at 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 at edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Loading
modules from directory: /usr/lib/dovecot/modules
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at 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 at 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 at 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 at 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 at 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 at edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Added
userdb setting: plugin/quota_rule=*:storage=2G
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at 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 at edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota
root: name=User quota backend=count args=
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at 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 at edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota
warning: bytes=2040109465 (95%) messages=0 reverse=no
command=quota-warning 95 billing at edwardcsmith.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota
warning: bytes=1932735283 (90%) messages=0 reverse=no
command=quota-warning 90 billing at edwardcsmith.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota
warning: bytes=1717986918 (80%) messages=0 reverse=no
command=quota-warning 80 billing at edwardcsmith.ca
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: Quota
grace: root=User quota bytes=214748364 (10%)
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at 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 at 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 at 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 at edwardcsmith.ca)<8308><tx/7Zhl2tmBARMvF>: Debug: none:
root=, index=, indexpvt=, control=, inbox=, alt=
Sep 17 23:19:17 ezm11-pco dovecot:
imap(billing at 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 at 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 at 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 at 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 at 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 at hosehead.ninja' and banned = '0' and active = '1'
Sep 17 23:19:17 ezm11-pco dovecot: auth: Debug: master userdb out:
USER#0114049862657#01100 at 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 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Loading modules
from directory: /usr/lib/dovecot/modules
Sep 17 23:19:17 ezm11-pco dovecot:
imap(00 at 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 at 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 at 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 at 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 at 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 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Added userdb
setting: plugin/quota_rule=*:storage=0G
Sep 17 23:19:17 ezm11-pco dovecot:
imap(00 at 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 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota root:
name=User quota backend=count args=
Sep 17 23:19:17 ezm11-pco dovecot:
imap(00 at 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 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota warning:
bytes=0 (95%) messages=0 reverse=no command=quota-warning 95
00 at hosehead.ninja
Sep 17 23:19:17 ezm11-pco dovecot:
imap(00 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota warning:
bytes=0 (90%) messages=0 reverse=no command=quota-warning 90
00 at hosehead.ninja
Sep 17 23:19:17 ezm11-pco dovecot:
imap(00 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota warning:
bytes=0 (80%) messages=0 reverse=no command=quota-warning 80
00 at hosehead.ninja
Sep 17 23:19:17 ezm11-pco dovecot:
imap(00 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: Quota grace:
root=User quota bytes=0 (10%)
Sep 17 23:19:17 ezm11-pco dovecot:
imap(00 at 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 at 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 at 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 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: none: root=,
index=, indexpvt=, control=, inbox=, alt=
Sep 17 23:19:17 ezm11-pco dovecot:
imap(00 at 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 at hosehead.ninja)<8309><00z7Zhl211lARMvF>: Debug: INBOX: Mailbox
opened because: STATUS
Sep 17 23:19:18 ezm11-pco dovecot:
imap(00 at 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:
> Hi!
>
> Can you provide dovecot version? Also output of mail_debug=yes logs
> would be useful
>
> Aki
>
> On 19.09.2018 00:19, Ted wrote:
>> 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 at 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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://dovecot.org/pipermail/dovecot/attachments/20180919/485abf41/attachment-0001.html>

More information about the dovecot mailing list