dovecot - Jan 2009 - expire plugin error

Hi,

I am using the db backend for the expire plugin and found the following
error message in the logfile:


dovecot: Jan 22 10:10:55 Error: dict: secondary db: unable to allocate
space from the buffer cache
dovecot: Jan 22 10:10:55 Error: dict: Failed to initialize dictionary
'expire'
dovecot: Jan 22 10:10:55 Error: IMAP(xxxxxxxxxx):
read(/var/dovecot/dict-server) failed: Remote disconnected


Anyone know how to fix this? I do not want to use SQL as backend, that
would be overkill.


# ls -alFh /var/dovecot/dict-server
srwxrwxrwx  1 root  wheel  -    0B Jan 22 10:08 /var/dovecot/dict-server

# dovecot -n
# 1.1.8: /etc/dovecot.conf
# OS: OpenBSD 4.4 sparc64  ffs
base_dir: /var/dovecot/
log_path: /var/log/dovecot.log
protocols: imaps pop3s
listen: xxxxxxxxxxxx
ssl_ca_file: /etc/ssl/xxxxxxxxxxxx.ca
ssl_cert_file: /etc/ssl/xxxxxxxxxxxx.crt
ssl_key_file: /etc/ssl/private/xxxxxxxxxxxx.key
login_dir: /var/dovecot/login
login_executable(default): /usr/local/libexec/dovecot/imap-login
login_executable(imap): /usr/local/libexec/dovecot/imap-login
login_executable(pop3): /usr/local/libexec/dovecot/pop3-login
login_user: _dovecot
mail_max_userip_connections(default): 25
mail_max_userip_connections(imap): 25
mail_max_userip_connections(pop3): 5
first_valid_uid: 10000
last_valid_uid: 19999
first_valid_gid: 100
last_valid_gid: 100
mail_gid: 100
mail_location:
mbox:/var/data/mail/%u/mbox:INBOX=/var/data/mail/%u/mbox/inbox:INDEX=/var/dovecot/index/%u
mbox_write_locks: fcntl
mail_executable(default): /usr/local/libexec/dovecot/imap
mail_executable(imap): /usr/local/libexec/dovecot/imap
mail_executable(pop3): /usr/local/libexec/dovecot/pop3
mail_plugins(default): expire imap_quota quota trash zlib
mail_plugins(imap): expire imap_quota quota trash zlib
mail_plugins(pop3): expire quota zlib
mail_plugin_dir(default): /usr/local/lib/dovecot/imap
mail_plugin_dir(imap): /usr/local/lib/dovecot/imap
mail_plugin_dir(pop3): /usr/local/lib/dovecot/pop3
imap_client_workarounds(default): delay-newmail netscape-eoh
tb-extra-mailbox-sep
imap_client_workarounds(imap): delay-newmail netscape-eoh
tb-extra-mailbox-sep
imap_client_workarounds(pop3):
pop3_client_workarounds(default):
pop3_client_workarounds(imap):
pop3_client_workarounds(pop3): outlook-no-nuls oe-ns-eoh
auth default:
  passdb:
    driver: bsdauth
  userdb:
    driver: passwd
  socket:
    type: listen
    master:
      path: /var/run/dovecot/auth-master
      mode: 384
plugin:
  quota: fs:user:noenforcing:mount=/var/data
  trash: /etc/dovecot-trash.conf
  expire: Trash 14
  expire_dict: proxy:/var/dovecot/dict-server:expire
dict:
  expire: db:/var/lib/dovecot/expire.db

> Anyone know how to fix this?
In reply to myself, some more info...

Using Google I found some hints that maybe the cache size is too
small... but how can that be fixed? Or... since this is BDB and the
machine is a sparc64, it's some memory alignment issue?


# l /var/lib/dovecot/
total 3116
drwxr-x---  2 root  wheel  -  512B Jan 22 11:26 ./
drwxr-x---  3 root  wheel  -  512B Jan 15 11:23 ../
-rw-------  1 root  wheel  - 24.0K Jan 23 07:13 __db.001
-rw-------  1 root  wheel  - 64.0K Jan 23 07:13 __db.002
-rw-------  1 root  wheel  -  264K Jan 23 07:13 __db.003
-rw-------  1 root  wheel  - 96.0K Jan 23 07:13 __db.004
-rw-------  1 root  wheel  - 56.0K Jan 23 07:13 __db.005
-rw-------  1 root  wheel  -    0B Jan 15 12:01 auth-success
-rw-------  1 root  wheel  - 32.0K Jan 22 13:58 expire.db
-rw-------  1 root  wheel  - 10.0M Jan 22 23:03 log.0000000001
-rw-r--r--  2 root  wheel  -  236B Jan 22 11:26 ssl-parameters.dat



# db4_stat -d /var/lib/dovecot/expire.db
Fri Jan 23 08:30:19 2009        Local time
53162   Btree magic number
9       Btree version number
Big-endian      Byte order
        Flags
2       Minimum keys per-page
16384   Underlying database page size
4079    Overflow key/data size
1       Number of levels in the tree
1       Number of unique keys in the tree
1       Number of data items in the tree
0       Number of tree internal pages
0       Number of bytes free in tree internal pages (0% ff)
1       Number of tree leaf pages
16322   Number of bytes free in tree leaf pages (0% ff)
0       Number of tree duplicate pages
0       Number of bytes free in tree duplicate pages (0% ff)
0       Number of tree overflow pages
0       Number of bytes free in tree overflow pages (0% ff)
0       Number of empty pages
0       Number of pages on the free list



# db4_stat -mh /var/lib/dovecot
260KB 832B      Total cache size
1       Number of caches
1       Maximum number of caches
264KB   Pool individual cache size
0       Maximum memory-mapped file size
0       Maximum open file descriptors
0       Maximum sequential buffer writes
0       Sleep after writing maximum sequential buffers
0       Requested pages mapped into the process' address space
302     Requested pages found in the cache (70%)
129     Requested pages not found in the cache
118     Pages created in the cache
53      Pages read into the cache
9       Pages written from the cache to the backing file
48      Clean pages forced from the cache
94      Dirty pages forced from the cache
0       Dirty pages written by trickle-sync thread
29      Current total page count
0       Current clean page count
29      Current dirty page count
37      Number of hash buckets used for page location
678     Total number of times hash chains searched for a page
13      The longest hash chain searched for a page
3792    Total number of hash chain entries checked for page
0       The number of hash bucket locks that required waiting (0%)
0       The maximum number of times any hash bucket lock was waited for (0%)
0       The number of region locks that required waiting (0%)
0       The number of buffers frozen
0       The number of buffers thawed
0       The number of frozen buffers freed
362     The number of page allocations
16784   The number of hash buckets examined during allocations
266     The maximum number of hash buckets examined for an allocation
769     The number of pages examined during allocations
13      The max number of pages examined for an allocation
0       Threads waited on page I/O
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
3       Requested pages found in the cache (75%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
3       Requested pages found in the cache (75%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
3       Requested pages found in the cache (75%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
1       Requested pages found in the cache (20%)
4       Requested pages not found in the cache
2       Pages created in the cache
3       Pages read into the cache
2       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
3       Requested pages found in the cache (60%)
2       Requested pages not found in the cache
2       Pages created in the cache
1       Pages read into the cache
1       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
4       Requested pages found in the cache (80%)
1       Requested pages not found in the cache
2       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: unknown
8192    Page size
0       Requested pages mapped into the process' address space
0       Requested pages found in the cache (0%)
1       Requested pages not found in the cache
1       Pages created in the cache
0       Pages read into the cache
0       Pages written from the cache to the backing file
Pool File: /var/lib/dovecot/expire.db
16384   Page size
0       Requested pages mapped into the process' address space
159     Requested pages found in the cache (77%)
45      Requested pages not found in the cache
0       Pages created in the cache
45      Pages read into the cache
1       Pages written from the cache to the backing file



OpenBSD 4.4-current (GENERIC.MP) #585: Fri Jan  9 11:36:04 MST 2009
    todd at sparc64.openbsd.org:/usr/src/sys/arch/sparc64/compile/GENERIC.MP
real mem = 8589934592 (8192MB)
avail mem = 8369053696 (7981MB)
mainbus0 at root: Sun Fire V440
cpu0 at mainbus0: SUNW,UltraSPARC-IIIi (rev 2.4) @ 1062 MHz
cpu0: physical 32K instruction (32 b/l), 64K data (32 b/l), 1024K
external (64 b/l)
cpu1 at mainbus0: SUNW,UltraSPARC-IIIi (rev 2.4) @ 1062 MHz
cpu1: physical 32K instruction (32 b/l), 64K data (32 b/l), 1024K
external (64 b/l)
cpu2 at mainbus0: SUNW,UltraSPARC-IIIi (rev 2.4) @ 1062 MHz
cpu2: physical 32K instruction (32 b/l), 64K data (32 b/l), 1024K
external (64 b/l)
cpu3 at mainbus0: SUNW,UltraSPARC-IIIi (rev 2.4) @ 1062 MHz
cpu3: physical 32K instruction (32 b/l), 64K data (32 b/l), 1024K
external (64 b/l)
"memory-controller" at mainbus0 not configured
"memory-controller" at mainbus0 not configured
"memory-controller" at mainbus0 not configured
"memory-controller" at mainbus0 not configured
schizo0 at mainbus0: "Tomatillo", version 4, ign 700, bus A 0 to 0
schizo0: dvma map c0000000-dfffffff
pci0 at schizo0
cas0 at pci0 dev 2 function 0 "Sun Cassini" rev 0x20: ivec 0x718,
address 00:03:ba:66:75:d1
brgphy0 at cas0 phy 1: BCM5421 10/100/1000baseT PHY, rev. 1
"ppm" at mainbus0 not configured
schizo1 at mainbus0: "Tomatillo", version 4, ign 740, bus B 0 to 0
schizo1: dvma map c0000000-dfffffff
pci1 at schizo1
mpi0 at pci1 dev 2 function 0 "Symbios Logic 53c1030" rev 0x08: ivec
0x740
scsibus0 at mpi0: 16 targets, initiator 7
schizo2 at mainbus0: "Tomatillo", version 4, ign 780, bus A 0 to 0
schizo2: dvma map c0000000-dfffffff
pci2 at schizo2
ebus0 at pci2 dev 7 function 0 "Acer Labs M1533 ISA" rev 0x00
"flashprom" at ebus0 addr 0-fffff, 290-290 not configured
rtc0 at ebus0 addr 70-71: m5819p
pcfiic0 at ebus0 addr 320-321 ivec 0x1b
iic0 at pcfiic0
"SUNW,i2c-imax" at iic0 addr 0xb not configured
"SUNW,i2c-imax" at iic0 addr 0xc not configured
admtemp0 at iic0 addr 0x18: max1617, cannot get control register
"pca9555" at iic0 addr 0x21 not configured
"pca9555" at iic0 addr 0x22 not configured
"pca9555" at iic0 addr 0x23 not configured
"pca9555" at iic0 addr 0x24 not configured
"adm1026" at iic0 addr 0x2e not configured
admtemp1 at iic0 addr 0x32: max1617, cannot get control register
admtemp2 at iic0 addr 0x40: max1617, cannot get control register
admtemp3 at iic0 addr 0x48: max1617, cannot get control register
lmtemp0 at iic0 addr 0x4e: lm75, fails to respond
"spd" at iic0 addr 0x5b not configured
"spd" at iic0 addr 0x5c not configured
"spd" at iic0 addr 0x5d not configured
"spd" at iic0 addr 0x5e not configured
"spd" at iic0 addr 0x63 not configured
"spd" at iic0 addr 0x64 not configured
"spd" at iic0 addr 0x65 not configured
"spd" at iic0 addr 0x66 not configured
"spd" at iic0 addr 0x6b not configured
"spd" at iic0 addr 0x6c not configured
"spd" at iic0 addr 0x6d not configured
"spd" at iic0 addr 0x6e not configured
"spd" at iic0 addr 0x73 not configured
"spd" at iic0 addr 0x74 not configured
"spd" at iic0 addr 0x75 not configured
"spd" at iic0 addr 0x76 not configured
"ics951601" at iic0 addr 0x69 not configured
power0 at ebus0 addr 800-82f ivec 0x1a
com0 at ebus0 addr 3f8-3ff ivec 0x22: ns16550a, 16 byte fifo
com0: console
com1 at ebus0 addr 2e8-2ef ivec 0x22: ns16550a, 16 byte fifo
"rmc-comm" at ebus0 addr 3e8-3ef ivec 0x22 not configured
cas1 at pci2 dev 2 function 0 "NS Saturn" rev 0x30: ivec 0x78c,
address
00:14:4f:1e:d6:b4
gentbi0 at cas1 phy 0: Generic ten-bit interface, rev. 0
"ATI Rage XL" rev 0x27 at pci2 dev 4 function 0 not configured
alipm0 at pci2 dev 6 function 0 "Acer Labs M7101 Power" rev 0x00:
223KHz
clock
iic1 at alipm0
ohci0 at pci2 dev 10 function 0 "Acer Labs M5237 USB" rev 0x03: ivec
0x7a1, version 1.0, legacy support
ohci1 at pci2 dev 11 function 0 "Acer Labs M5237 USB" rev 0x03: ivec
0x7a5, version 1.0, legacy support
pciide0 at pci2 dev 13 function 0 "Acer Labs M5229 UDMA IDE" rev 0xc4:
DMA, channel 0 configured to native-PCI, channel 1 configured to native-PCI
pciide0: using ivec 0x7a6 for native-PCI interrupt
atapiscsi0 at pciide0 channel 0 drive 0
scsibus1 at atapiscsi0: 2 targets, initiator 7
cd0 at scsibus1 targ 0 lun 0: <TOSHIBA, DVD-ROM SD-C2612, 1011> ATAPI
5/cdrom removable
cd0(pciide0:0:0): using PIO mode 4, Ultra-DMA mode 2
pciide0: channel 1 disabled (no drives)
usb0 at ohci0: USB revision 1.0
uhub0 at usb0 "Acer Labs OHCI root hub" rev 1.00/1.00 addr 1
usb1 at ohci1: USB revision 1.0
uhub1 at usb1 "Acer Labs OHCI root hub" rev 1.00/1.00 addr 1
"ppm" at mainbus0 not configured
schizo3 at mainbus0: "Tomatillo", version 4, ign 7c0, bus B 0 to 0
schizo3: dvma map c0000000-dfffffff
pci3 at schizo3
cas2 at pci3 dev 1 function 0 "Sun Cassini" rev 0x20: ivec 0x7dc,
address 00:03:ba:66:75:d2
brgphy1 at cas2 phy 1: BCM5421 10/100/1000baseT PHY, rev. 1
mpi1 at pci3 dev 2 function 0 "Symbios Logic 53c1030" rev 0x07: ivec
0x7e3
scsibus2 at mpi1: 16 targets, initiator 7
sd0 at scsibus2 targ 0 lun 0: <LSILOGIC, 1030 IM IM, 1000> SCSI2
0/direct fixed
sd0: 34732MB, 512 bytes/sec, 71131136 sec total
sd1 at scsibus2 targ 2 lun 0: <ModusLnk, MXJ3073SC800600X, 5704> SCSI3
0/direct fixed
sd1: 70136MB, 512 bytes/sec, 143638992 sec total
sd2 at scsibus2 targ 3 lun 0: <ModusLnk, MXJ3073SC800600X, 5704> SCSI3
0/direct fixed
sd2: 70136MB, 512 bytes/sec, 143638992 sec total
mpi1: target 2 Sync at 160MHz width 16bit offset 127 QAS 1 DT 1 IU 1
mpi1: target 3 Sync at 160MHz width 16bit offset 127 QAS 1 DT 1 IU 1
mpi1: phys disk 1 Sync at 160MHz width 16bit offset 127 QAS 1 DT 1 IU 1
mpi1: phys disk 0 Sync at 160MHz width 16bit offset 127 QAS 1 DT 1 IU 1
mpi2 at pci3 dev 2 function 1 "Symbios Logic 53c1030" rev 0x07: ivec
0x7e4
scsibus3 at mpi2: 16 targets, initiator 7
"i2c" at mainbus0 not configured
softraid0 at root
scsibus4 at softraid0: 1 targets, initiator 1
sd3 at scsibus4 targ 0 lun 0: <OPENBSD, SR RAID 1, 003> SCSI2 0/direct
fixed
sd3: 70136MB, 512 bytes/sec, 143638913 sec total
bootpath: /pci at 1f,700000/scsi at 2,0/disk at 0,0
root on sd0a swap on sd0b dump on sd0b

Hi,

Michael Lechtermann schrieb:
>> There is a 1.1.9 release candidate available which contains fixes for
Berkeley DB. Maybe this solves your issues.
>>
>>     http://dovecot.org/tmp/dovecot-1.1.9.rc.tar.gz
>>
>> Changes since v1.1.8:
>>   ...
>>   - Berkeley DB memory/resource leak fixes.
>>   ...
> 
> Thanks for the info. Looks like that fixed it... if I get the old or
> another error related to BDB again I will raise my hand. ;-)
*raises hand*

Getting that error again, with 1.1.9 too. Looks like it has to run for
some time first...


Michael