what happens in between?

Hi,

on a (a little old) openldap 2.3 server there are 3 meta backends and 
local bdb backends glued together under one suffix ou=foo.

meta: ou=allgv,ou=foo
conn-ttl 30
idle-timeout 1m30s

bdb: ou=humans,ou=foo

When I ask the server for data exactly under one or the other suffix, 
the answer is there immediately.

May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 fd=73 
ACCEPT from IP=127.0.0.1:43816 (IP=0.0.0.0:389)
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=0 
BIND dn="" method=128
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=0 
RESULT tag=97 err=0 text=
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=1 
SRCH base="ou=allgv,ou=foo" scope=2 deref=0 
filter="(&(&(objectClass=person)(|(mail=*@ofd-st.foo)(mail=*@ofd-z.foo)(mail=*@ofd-lbv.foo)(mail=*@ofd-bl.foo)))(|(mail=*franz*)(cn=*franz*)(givenName=*franz*)(sn=*franz*)))"
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=1 
ENTRY 
dn="cn=franziska.afffl,cn=nlbv,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=1 
ENTRY 
dn="cn=franziska.pfffr,cn=ofd-lba,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=1 
ENTRY 
dn="cn=fwfffand,cn=nlbv,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=1 
ENTRY 
dn="cn=cffffen.franz,cn=nlbv,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=1 
ENTRY 
dn="cn=jefffffer.franzen,cn=nlbv,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=1 
SEARCH RESULT tag=101 err=0 nentries=5 text=
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 op=2 UNBIND
May  6 15:52:25 rzhs199 local4:debug slapd[4505742]: conn=321210 fd=73 
closed

May  6 15:54:49 rzhs199 local4:debug slapd[4505742]: conn=321234 fd=17 
ACCEPT from IP=127.0.0.1:43886 (IP=0.0.0.0:389)
May  6 15:54:49 rzhs199 local4:debug slapd[4505742]: conn=321234 op=0 
BIND dn="" method=128
May  6 15:54:49 rzhs199 local4:debug slapd[4505742]: conn=321234 op=0 
RESULT tag=97 err=0 text=
May  6 15:54:49 rzhs199 local4:debug slapd[4505742]: conn=321234 op=1 
SRCH base="ou=humans,ou=foo" scope=2 deref=0 
filter="(&(&(objectClass=person)(|(mail=*@ofd-st.foo)(mail=*@ofd-z.foo)(mail=*@ofd-lbv.foo)(mail=*@ofd-bl.foo)))(|(mail=*franz*)(cn=*franz*)(givenName=*franz*)(sn=*franz*)))"
May  6 15:54:49 rzhs199 local4:debug slapd[4505742]: conn=321234 op=1 
ENTRY dn="employeeNumber=10110,ou=humans,ou=foo"
May  6 15:54:49 rzhs199 local4:debug slapd[4505742]: conn=321234 op=1 
SEARCH RESULT tag=101 err=0 nentries=1 text=
May  6 15:54:49 rzhs199 local4:debug slapd[4505742]: conn=321234 op=2 UNBIND
May  6 15:54:49 rzhs199 local4:debug slapd[4505742]: conn=321234 fd=17 
closed




When I ask with base ou=foo, there is an exact 2:30 min break in between.

May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 fd=28 
ACCEPT from IP=127.0.0.1:43052 (IP=0.0.0.0:389)
May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 op=0 
BIND dn="" method=128
May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 op=0 
RESULT tag=97 err=0 text=
May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 op=1 
SRCH base="ou=foo" scope=2 deref=0 
filter="(&(&(objectClass=person)(|(mail=*@ofd-st.foo)(mail=*@ofd-z.foo)(mail=*@ofd-lbv.foo)(mail=*@ofd-bl.foo)))(|(mail=*franz*)(cn=*franz*)(givenName=*franz*)(sn=*franz*)))"
May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 op=1 
ENTRY 
dn="cn=franziska.axxxl,cn=nlbv,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 op=1 
ENTRY 
dn="cn=franziska.pxxxer,cn=ofd-lba,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 op=1 
ENTRY 
dn="cn=fwexxxnd,cn=nlbv,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 op=1 
ENTRY 
dn="cn=cxxx.franz,cn=nlbv,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:27:54 rzhs199 local4:debug slapd[4505742]: conn=320932 op=1 
ENTRY 
dn="cn=jexxxxer.franzen,cn=nlbv,cn=migstand,cn=recipients,ou=inf,ou=allgv,ou=foo"
May  6 15:30:24 rzhs199 local4:debug slapd[4505742]: conn=320932 op=1 
ENTRY dn="employeeNumber=10110,ou=humans,ou=foo"
May  6 15:30:24 rzhs199 local4:debug slapd[4505742]: conn=320932 op=1 
SEARCH RESULT tag=101 err=0 nentries=6 text=
May  6 15:30:24 rzhs199 local4:debug slapd[4505742]: conn=320932 op=2 UNBIND
May  6 15:30:24 rzhs199 local4:debug slapd[4505742]: conn=320932 fd=28 
closed

What could cause this?


Marc