[FC3] Ldap: anyone seen this?

[Craig White]

On Fri, 2004-12-10 at 06:42 -0600, Brian Fahrlander wrote:
>     I've finally managed to set up a new ldap server, which means
> breaking the stranglehold on the old server's data, and moving it over
> to the FC3 box...one step closer to going to FC3 there now, too.
> 
>     So the service is set up, tested; works.
> 
>     Until now:  (Sorry, it's long)
> 
> ec 10 06:38:59 stewie slapd[20555]: connection_get(10): got connid=0
> Dec 10 06:38:59 stewie slapd[20555]: connection_read(10): checking for input on id=0
> Dec 10 06:38:59 stewie slapd[20555]: ber_get_next on fd 10 failed errno=11 (Resource temporarily unavailable)
> Dec 10 06:38:59 stewie slapd[20555]: do_bind
> Dec 10 06:38:59 stewie slapd[20555]: >>> dnPrettyNormal: <cn=manager,dc=fahrlander,dc=net>
> Dec 10 06:38:59 stewie slapd[20555]: <<< dnPrettyNormal: <cn=manager,dc=fahrlander,dc=net>, <cn=manager,dc=fahrlander,dc=net>
> Dec 10 06:38:59 stewie slapd[20555]: do_bind: version=3 dn="cn=manager,dc=fahrlander,dc=net" method=128
> Dec 10 06:38:59 stewie slapd[20555]: bdb_dn2entry("cn=manager,dc=fahrlander,dc=net")
> Dec 10 06:38:59 stewie slapd[20555]: => bdb_dn2id( "dc=fahrlander,dc=net" )
> Dec 10 06:38:59 stewie slapd[20555]: <= bdb_dn2id: got id=0x00000001
> Dec 10 06:38:59 stewie slapd[20555]: => bdb_dn2id( "cn=manager,dc=fahrlander,dc=net" )
> Dec 10 06:38:59 stewie slapd[20555]: <= bdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30990)
> Dec 10 06:38:59 stewie slapd[20555]: entry_decode: "dc=fahrlander,dc=net"
> Dec 10 06:38:59 stewie slapd[20555]: <= entry_decode(dc=fahrlander,dc=net)
> Dec 10 06:38:59 stewie slapd[20555]: send_ldap_result: conn=0 op=0 p=3
> Dec 10 06:38:59 stewie slapd[20555]: send_ldap_response: msgid=1 tag=97 err=49
> Dec 10 06:39:16 stewie slapd[20555]: connection_get(10): got connid=0
> Dec 10 06:39:16 stewie slapd[20555]: connection_read(10): checking for input on id=0
> Dec 10 06:39:16 stewie slapd[20555]: ber_get_next on fd 10 failed errno=0 (Success)
> Dec 10 06:39:16 stewie slapd[20555]: connection_read(10): input error=-2 id=0, closing.
> Dec 10 06:39:16 stewie slapd[20555]: connection_closing: readying conn=0 sd=10 for close
> Dec 10 06:39:16 stewie slapd[20555]: connection_close: deferring conn=0 sd=10
> Dec 10 06:39:16 stewie slapd[20555]: do_unbind
> Dec 10 06:39:16 stewie slapd[20555]: connection_resched: attempting closing conn=0 sd=10
> Dec 10 06:39:16 stewie slapd[20555]: connection_close: conn=0 sd=10
> Dec 10 06:40:01 stewie slapd[20555]: connection_get(10): got connid=1
> Dec 10 06:40:01 stewie slapd[20555]: connection_read(10): checking for input on id=1
> Dec 10 06:40:01 stewie slapd[20555]: ber_get_next on fd 10 failed errno=11 (Resource temporarily unavailable)
> Dec 10 06:40:01 stewie slapd[20555]: do_bind
> Dec 10 06:40:01 stewie slapd[20555]: >>> dnPrettyNormal: <>
> Dec 10 06:40:01 stewie slapd[20555]: <<< dnPrettyNormal: <>, <>
> Dec 10 06:40:01 stewie slapd[20555]: do_bind: version=3 dn="" method=128
> Dec 10 06:40:01 stewie slapd[20555]: send_ldap_result: conn=1 op=0 p=3
> Dec 10 06:40:01 stewie slapd[20555]: send_ldap_response: msgid=1 tag=97 err=0
> Dec 10 06:40:01 stewie slapd[20555]: do_bind: v3 anonymous bind
> Dec 10 06:40:01 stewie slapd[20555]: connection_get(10): got connid=1
> Dec 10 06:40:01 stewie slapd[20555]: connection_read(10): checking for input on id=1
> Dec 10 06:40:01 stewie slapd[20555]: ber_get_next on fd 10 failed errno=11 (Resource temporarily unavailable)
> Dec 10 06:40:01 stewie slapd[20555]: do_search
> Dec 10 06:40:01 stewie slapd[20555]: >>> dnPrettyNormal: <dc=fahrlander,dc=net>
> Dec 10 06:40:01 stewie slapd[20555]: <<< dnPrettyNormal: <dc=fahrlander,dc=net>, <dc=fahrlander,dc=net>
> Dec 10 06:40:01 stewie slapd[20555]: ==> limits_get: conn=1 op=1 dn="[anonymous]"
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_search
> Dec 10 06:40:01 stewie slapd[20555]: bdb_dn2entry("dc=fahrlander,dc=net")
> Dec 10 06:40:01 stewie slapd[20555]: search_candidates: base="dc=fahrlander,dc=net" (0x00000001) scope=2
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_dn2idl( "dc=fahrlander,dc=net" )
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_equality_candidates (objectClass)
> Dec 10 06:40:01 stewie slapd[20555]: => key_read
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_index_read: failed (-30990)
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_equality_candidates: id=0, first=0, last=0
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_equality_candidates (objectClass)
> Dec 10 06:40:01 stewie slapd[20555]: => key_read
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_index_read 9 candidates
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_equality_candidates: id=9, first=5, last=14
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_equality_candidates (uid)
> Dec 10 06:40:01 stewie slapd[20555]: => key_read
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_index_read: failed (-30990)
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_equality_candidates: id=0, first=0, last=0
> Dec 10 06:40:01 stewie slapd[20555]: bdb_search_candidates: id=0 first=1 last=0
> Dec 10 06:40:01 stewie slapd[20555]: bdb_search: no candidates
> Dec 10 06:40:01 stewie slapd[20555]: send_ldap_result: conn=1 op=1 p=3
> Dec 10 06:40:01 stewie slapd[20555]: send_ldap_response: msgid=2 tag=101 err=0
> Dec 10 06:40:01 stewie slapd[20555]: connection_get(10): got connid=1
> Dec 10 06:40:01 stewie slapd[20555]: connection_read(10): checking for input on id=1
> Dec 10 06:40:01 stewie slapd[20555]: ber_get_next on fd 10 failed errno=11 (Resource temporarily unavailable)
> Dec 10 06:40:01 stewie slapd[20555]: do_search
> Dec 10 06:40:01 stewie slapd[20555]: >>> dnPrettyNormal: <dc=fahrlander,dc=net>
> Dec 10 06:40:01 stewie slapd[20555]: <<< dnPrettyNormal: <dc=fahrlander,dc=net>, <dc=fahrlander,dc=net>
> Dec 10 06:40:01 stewie slapd[20555]: ==> limits_get: conn=1 op=2 dn="[anonymous]"
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_search
> Dec 10 06:40:01 stewie slapd[20555]: bdb_dn2entry("dc=fahrlander,dc=net")
> Dec 10 06:40:01 stewie slapd[20555]: search_candidates: base="dc=fahrlander,dc=net" (0x00000001) scope=2
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_dn2idl( "dc=fahrlander,dc=net" )
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_equality_candidates (objectClass)
> Dec 10 06:40:01 stewie slapd[20555]: => key_read
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_index_read: failed (-30990)
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_equality_candidates: id=0, first=0, last=0
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_equality_candidates (objectClass)
> Dec 10 06:40:01 stewie slapd[20555]: => key_read
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_index_read 1 candidates
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_equality_candidates: id=1, first=13, last=13
> Dec 10 06:40:01 stewie slapd[20555]: => bdb_equality_candidates (memberUid)
> Dec 10 06:40:01 stewie slapd[20555]: => key_read
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_index_read: failed (-30990)
> Dec 10 06:40:01 stewie slapd[20555]: <= bdb_equality_candidates: id=0, first=0, last=0
> Dec 10 06:40:01 stewie slapd[20555]: bdb_search_candidates: id=0 first=1 last=0
> Dec 10 06:40:01 stewie slapd[20555]: bdb_search: no candidates
> Dec 10 06:40:01 stewie slapd[20555]: send_ldap_result: conn=1 op=2 p=3
> Dec 10 06:40:01 stewie slapd[20555]: send_ldap_response: msgid=3 tag=101 err=0
> Dec 10 06:40:01 stewie slapd[20555]: connection_get(10): got connid=1
> Dec 10 06:40:01 stewie slapd[20555]: connection_read(10): checking for input on id=1
> Dec 10 06:40:01 stewie slapd[20555]: ber_get_next on fd 10 failed errno=0 (Success)
> Dec 10 06:40:01 stewie slapd[20555]: connection_read(10): input error=-2 id=1, closing.
> Dec 10 06:40:01 stewie slapd[20555]: connection_closing: readying conn=1 sd=10 for close
> Dec 10 06:40:01 stewie slapd[20555]: connection_close: conn=1 sd=10
> 
>     What could be 'temporarily unavailable'?  It's all on the same
> server!
----
I am by no means an expert on this but...
(Resource temporarily unavailable) is somewhat normal and you should
ignore it

Dec 10 06:38:59 stewie slapd[20555]: send_ldap_response: msgid=1 tag=97
err=49 <- I believe that this means a credentials error

Dec 10 06:38:59 stewie slapd[20555]: <= bdb_dn2id: get failed:
DB_NOTFOUND: No matching key/data pair found (-30990) <-this has to be a
problem

Dec 10 06:40:01 stewie slapd[20555]: <= bdb_index_read: failed
(-30990)<-appears to be a problem

try 'slapindex'
do you have indexes for your search fields?

Seems what I saw is that you tried to do something that failed to do a bind to cn=manager,dc=fahrlander,dc=com and then it switched to anonymous.

What do your indexes look like? and if you change them, you must 'reindex.'

Craig