[Date Prev][Date Next] [Chronological] [Thread] [Top]

proxycache not caching ?



Hi,
I'm still struggling with proxycache overlay,that is a first query is 
being answered but a repeated query is not answered, I presume that 
probabely no data is written to cache, although the files sn.bdb and 
telephonenumber.bdb are created.

My search command is
./ldapsearch -H ldapi:// -b ou=adressbuch,o=HDK,c=DE sn=testuser \ 
telephonenumber

As slapd is allways exiting in gdb here my slapd.conf and an excerpt 
from -d3 logging 

-Dieter

.-.-.-.-slapd.conf.-.-.-.-.
database	ldap
lastmod off
rewriteEngine on
suffix	"o=HDK,c=DE"
suffixMassage "o=HDK,c=DE" "o=avci,c=de"
rootdn "cn=admin,o=HDK,c=DE"
rootpw secret
binddn "cn=admanager,o=avci,c=de"
bindpw  secret
uri ldapi://%2fusr%2flocal%2fvar%2frun%2fldapi
overlay proxycache
proxycache bdb 50000 2 500 3600
proxyAttrset 0 mail
proxyAttrset 1 telephoneNumber
proxytemplate (cn=) 0 3600
proxytemplate (cn=) 1 3600
proxytemplate (sn=) 0 3600
proxytemplate (sn=) 1 3600
proxytemplate (&(cn=)(objectclass=)) 0 3600
proxytemplate (&(sn=)(objectclass=)) 0 3600
proxytemplate (&(sn=)(objectclass=)) 1 3600
proxytemplate (&(cn=)(objectclass=)) 1 3600
directory	/home/dieter/openldap/var/db
access to dn.subtree=o=HDK,c=DE by * read
cachesize 20
index objectclass eq
index cn,sn,mail,telephoneNumber pres,eq,sub
-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.--..

.-.-.-.-.-.log of 1. search-.-.-.-.-.-.-.-.-.
[...]
query template of incoming query = (sn=)
Lock QC index = 3
Not answerable: Unlock QC index=3
QUERY NOT ANSWERABLE
QUERY CACHEABLE
put_filter: "(sn=testuser)"
put_filter: simple
put_simple_filter: "sn=testuser"
ber_scanf fmt ({mm}) ber:
ldap_create
ldap_url_parse_ext(ldapi://%2fusr%2flocal%2fvar%2frun%2fldapi)
=>ldap_back_getconn: conn 0x81614d0 inserted
[...]
ldap_msgfree
==> rewrite_context_apply [depth=1] string='ou=adressbuch,o=hdk,c=de'
==> rewrite_rule_apply rule='(.*)o=HDK,[ ]?c=DE' 
string='ou=adressbuch,o=hdk,c=de' [1 pass(es)]
==> rewrite_context_apply [depth=1] 
res={0,'ou=adressbuch,o=avci,c=de'}
==> rewrite_context_apply [depth=1] string='(sn=testuser)'
==> rewrite_context_apply [depth=1] res={0,'NULL'}
ldap_search_ext
put_filter: "(sn=testuser)"
put_filter: simple
put_simple_filter: "sn=testuser"
ldap_send_initial_request
ldap_send_server_request
ber_flush: 103 bytes to sd 20
[...]
==> rewrite_context_apply [depth=1] string='cn=Tes01 
Testuser,ou=adressbuch,o=avci,c=de'
==> rewrite_rule_apply rule='(.*)o=avci,[ ]?c=de' string='cn=Tes01 
Testuser,ou=adressbuch,o=avci,c=de' [1 pass(es)]
==> rewrite_context_apply [depth=1] res={0,'cn=Tes01 
Testuser,ou=adressbuch,o=HDK,c=DE'}
>>> dnNormalize: <cn=Tes01 Testuser,ou=adressbuch,o=HDK,c=DE>
=> ldap_bv2dn(cn=Tes01 Testuser,ou=adressbuch,o=HDK,c=DE,0)
<= ldap_bv2dn(cn=Tes01 Testuser,ou=adressbuch,o=HDK,c=DE,0)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=tes01 testuser,ou=adressbuch,o=hdk,c=de,272)=0
<<< dnNormalize: <cn=tes01 testuser,ou=adressbuch,o=hdk,c=de>
[...]
=> send_search_entry: dn="cn=Tes01 Testuser,ou=adressbuch,o=HDK,c=DE"
[...]
-- send_ldap_result: conn=0 op=4 p=3
UUID for query being added = b84c70a2-1c08-1028-877b-809776f29f6c
bdb_dn2entry("cn=tes01 testuser,ou=adressbuch,o=hdk,c=de")
=> bdb_dn2id( "o=hdk,c=de" )
<= bdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found 
(-30990)
bdb_add: entry at root denied
send_ldap_result: conn=0 op=4 p=3
bdb_dn2entry("o=hdk,c=de")
=> bdb_dn2id( "o=hdk,c=de" )
<= bdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found 
(-30990)
=> bdb_dn2id_add( "o=hdk,c=de", 0x00000002 )
<= bdb_dn2id_add: 0
=> entry_encode(0x00000002): o=HDK,c=DE
=> index_entry_add( 2, "o=HDK,c=DE" )
=> key_change(ADD,2)
<= key_change 0
=> key_change(ADD,2)
<= key_change 0
<= index_entry_add( 2, "o=HDK,c=DE" ) success
bdb_dn2entry("cn=ldapsync,o=hdk,c=de")
=> bdb_dn2id( "o=hdk,c=de" )
<= bdb_dn2id: got id=0x00000002
=> bdb_dn2id( "cn=ldapsync,o=hdk,c=de" )
<= bdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found 
(-30990)
entry_decode: "o=HDK,c=DE"
<= index_entry_add( 3, "ou=adressbuch,o=HDK,c=DE" ) success
bdb_dn2entry("cn=ldapsync,o=hdk,c=de")
=> bdb_dn2id( "cn=ldapsync,o=hdk,c=de" )
<= bdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found 
(-30990)
bdb_add: added id=00000003 dn="ou=adressbuch,o=HDK,c=DE"
send_ldap_result: conn=0 op=4 p=3
bdb_dn2entry("cn=tes01 testuser,ou=adressbuch,o=hdk,c=de")
=> bdb_dn2id( "cn=tes01 testuser,ou=adressbuch,o=hdk,c=de" )
<= bdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found 
(-30990)
=> bdb_dn2id_add( "cn=tes01 testuser,ou=adressbuch,o=hdk,c=de", 
0x00000004 )
<= bdb_dn2id_add: 0
=> entry_encode(0x00000004): cn=Tes01 
Testuser,ou=adressbuch,o=HDK,c=DE
=> index_entry_add( 4, "cn=Tes01 Testuser,ou=adressbuch,o=HDK,c=DE" )
[...]
<= index_entry_add( 4, "cn=Tes01 Testuser,ou=adressbuch,o=HDK,c=DE" ) 
success
bdb_dn2entry("cn=ldapsync,o=hdk,c=de")
=> bdb_dn2id( "cn=ldapsync,o=hdk,c=de" )
<= bdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found 
(-30990)
bdb_add: added id=00000004 dn="cn=Tes01 
Testuser,ou=adressbuch,o=HDK,c=DE"
send_ldap_result: conn=0 op=4 p=3
ENTRY ADDED/MERGED, CACHED ENTRIES=1
STORED QUERIES = 1
Added query expires at 1081254273
Lock AQ index = 3
TEMPLATE 3 QUERIES++ 1
Unlock AQ index = 3 
Base of added query = ou=adressbuch,o=hdk,c=de
send_ldap_response: msgid=5 tag=101 err=0
[...]
sasl_write: want=77, written=77
lsasl_read: want=4, got=4
ldap_write: want=14, written=14
ldap_read: want=8, got=7
sasl_read: want=4, got=0
ldap_read: want=8, got=0
[...]
ber_get_next on fd 19 failed errno=0 (Success)
connection_read(19): input error=-2 id=0, closing.
connection_closing: readying conn=0 sd=19 for close
connection_close: deferring conn=0 sd=19
do_unbind
connection_resched: attempting closing conn=0 sd=19
connection_close: conn=0 sd=19
=>ldap_back_conn_destroy: fetching conn 0
-.-.-.-.-.-.-.-.-.-.-.-.end of 1st. log.-.-.-.-.-.-.-.-

-.-.-.-.-repeated search log.-.-.-.-.-.-.-.--.--.
[...]
query template of incoming query = (sn=)
Lock QC index = 3
QUERY ANSWERABLE
=> bdb_search
bdb_dn2entry("ou=adressbuch,o=hdk,c=de")
search_candidates: base="ou=adressbuch,o=hdk,c=de" (0x00000003) 
scope=2
=> bdb_equality_candidates (objectClass)
=> key_read
<= bdb_index_read: failed (-30990)
<= bdb_equality_candidates: id=0, first=0, last=0
=> bdb_dn2idl( "ou=adressbuch,o=hdk,c=de" )
<= bdb_dn2idl: id=2 first=3 last=4
=> bdb_equality_candidates (objectClass)
=> key_read
<= bdb_index_read: failed (-30990)
<= bdb_equality_candidates: id=0, first=0, last=0
=> bdb_equality_candidates (sn)
=> key_read
<= bdb_index_read: failed (-30990)
<= bdb_equality_candidates: id=0, first=0, last=0
bdb_search_candidates: id=0 first=3 last=0
bdb_search: no candidates
[...]
ldap_read: want=8, got=0
ber_get_next on fd 19 failed errno=0 (Success)
connection_read(19): input error=-2 id=1, closing.
connection_closing: readying conn=1 sd=19 for close
connection_close: deferring conn=1 sd=19
do_unbind
connection_resched: attempting closing conn=1 sd=19
connection_close: conn=1 sd=19
=>ldap_back_conn_destroy: fetching conn 1
-.-.-.-.-.-.-.-.-.EOF-.-.-.-.-.-.-.-.-.-..

--
Dieter Klünter | Systemberatung
Tel.: +49.40.64861967
Fax : +49.40.64891521
http://www.avci.de