[Dovecot] AIX mail quota plugin problems + dotlock problem resolved

[Stewart Dean]

Duh.  Egg all over my face, for missing such a simple thing as a 
misspelled word.  OK.

Incidentally, I have found and resolved the dotlock problem.  Nothing to 
do with NFS, rather there was some legacy (something my predecessor had 
set up 10 years ago or more) instructions in the .procmailrc file that 
were trashing the locks;
 details to follow.

Anway, I corrected the misspelled filename, and Dovecot now loads 
successfully with the fs quota plugin...but that then fails...
Please again, your help...hopefully, this is not as elementary as a 
misspelled filename, but just as quickly fixable with your eagle eye.  
Again this is AIX; FWIW, what Ralf does doesn't use fs quota, so this is 
new territory.  There is a trace attached.
Here it goes:

I start DC under truss
truss -d -f -t !_poll dovecot > /tmp/dovecot.truss.out  2>&1

in maillog, I see:
======================================
Jun  5 11:31:38 mercury mail:info dovecot: Dovecot v1.0.0 starting up

Draves does some stuff:

Jun  5 11:32:56 mercury mail:info dovecot: auth(default): client out: OK        1       user=draves
Jun  5 11:32:56 mercury mail:info dovecot: auth(default): master in: REQUEST    1       851978  1
Jun  5 11:32:56 mercury mail:info dovecot: auth(default): passwd(draves,10.20.30.14): lookup
Jun  5 11:32:56 mercury mail:info dovecot: auth(default): master out: USER      1       draves  system_user=draves      uid=220 gid=20
0       home=/home/hcrc/draves
Jun  5 11:32:56 mercury mail:info dovecot: imap-login: Login: user=<draves>, method=PLAIN, rip=10.20.30.14, lip=192.246.229.21, TLS
Jun  5 11:32:56 mercury mail:info dovecot: IMAP(draves): Loading modules from directory: /usr/local/lib/dovecot/imap
Jun  5 11:32:56 mercury mail:info dovecot: IMAP(draves): Module loaded: /usr/local/lib/dovecot/imap/lib10_quota_plugin.so
Jun  5 11:32:56 mercury mail:info dovecot: IMAP(draves): Module loaded: /usr/local/lib/dovecot/imap/lib11_imap_quota_plugin.so
Jun  5 11:32:57 mercury mail:info dovecot: IMAP(draves): Effective uid=220, gid=200, home=/home/hcrc/draves
Jun  5 11:32:57 mercury mail:info dovecot: IMAP(draves): mbox: data=/home/hcrc/draves/mail:INBOX=/var/spool/mail/draves:INDEX=/var/dcn
dx/draves
Jun  5 11:32:57 mercury mail:info dovecot: IMAP(draves): mbox: root=/home/hcrc/draves/mail, index=/var/dcndx/draves, inbox=/var/spool/
mail/draves
Jun  5 11:32:57 mercury mail:info dovecot: IMAP(draves): fs quota add storage dir = /home/hcrc/draves/mail
Jun  5 11:32:57 mercury mail:info dovecot: IMAP(draves): fs quota block device = /dev/hd4
Jun  5 11:32:57 mercury mail:info dovecot: IMAP(draves): fs quota mount point = /
Jun  5 11:32:58 mercury mail:err|error dovecot: IMAP(draves): quotactl(Q_GETQUOTA, /) failed: Invalid argument

This is 1:20 or 80 seconds after the start of DC and about line 11982 in the trace file

I (sdean) do some stuff:

Jun  5 11:33:05 mercury mail:info dovecot: auth(default): client out: OK        1       user=sdean
Jun  5 11:33:05 mercury mail:info dovecot: auth(default): master in: REQUEST    2       1351756 1
Jun  5 11:33:05 mercury mail:info dovecot: auth(default): passwd(sdean,10.20.10.75): lookup
Jun  5 11:33:05 mercury mail:info dovecot: auth(default): master out: USER      2       sdean   system_user=sdean       uid=202 gid=20
0       home=/home/hcrc/sdean
Jun  5 11:33:05 mercury mail:info dovecot: imap-login: Login: user=<sdean>, method=PLAIN, rip=10.20.10.75, lip=192.246.229.21, TLS
Jun  5 11:33:05 mercury mail:info dovecot: IMAP(sdean): Loading modules from directory: /usr/local/lib/dovecot/imap
Jun  5 11:33:05 mercury mail:info dovecot: IMAP(sdean): Module loaded: /usr/local/lib/dovecot/imap/lib10_quota_plugin.so
Jun  5 11:33:05 mercury mail:info dovecot: IMAP(sdean): Module loaded: /usr/local/lib/dovecot/imap/lib11_imap_quota_plugin.so
Jun  5 11:33:06 mercury mail:info dovecot: IMAP(sdean): Effective uid=202, gid=200, home=/home/hcrc/sdean
Jun  5 11:33:06 mercury mail:info dovecot: IMAP(sdean): mbox: data=/home/hcrc/sdean/mail:INBOX=/var/spool/mail/sdean:INDEX=/var/dcndx/
sdean
Jun  5 11:33:06 mercury mail:info dovecot: IMAP(sdean): mbox: root=/home/hcrc/sdean/mail, index=/var/dcndx/sdean, inbox=/var/spool/mai
l/sdean
Jun  5 11:33:06 mercury mail:info dovecot: IMAP(sdean): fs quota add storage dir = /home/hcrc/sdean/mail
Jun  5 11:33:06 mercury mail:info dovecot: IMAP(sdean): fs quota block device = /dev/hd4
Jun  5 11:33:06 mercury mail:info dovecot: IMAP(sdean): fs quota mount point = /
Jun  5 11:33:06 mercury mail:err|error dovecot: IMAP(sdean): quotactl(Q_GETQUOTA, /) failed: Invalid argument

This is 1:28 or 88 seconds after the start of DC and about line 23478 in the trace file

======================================

In syslog, the same stuff:

4220 root at mercury:/var/log ## tail -f syslog | grep dovecot
Jun  5 11:32:58 mercury mail:err|error dovecot: IMAP(draves): quotactl(Q_GETQUOTA, /) failed: Invalid argument
Jun  5 11:33:06 mercury mail:err|error dovecot: IMAP(sdean): quotactl(Q_GETQUOTA, /) failed: Invalid argument
======================================


Compressed trace file attached.........

Thanks in advance.

Timo Sirainen wrote:
>
> Why is it "plugins"? It should be "plugin".
>   

-- 
====
Stewart Dean, Unix System Admin, Henderson Computer Resources 
Center of Bard College, Annandale-on-Hudson, New York  12504  
sdean at bard.edu  voice: 845-758-7475, fax: 845-758-7035