[Date Prev][Date Next] [Chronological] [Thread] [Top]
Re: diagnosing test failures

To: openldap-software@OpenLDAP.org
Subject: Re: diagnosing test failures
From: Rick Jones <raj@cup.hp.com>
Date: Sat, 29 Jul 2000 11:54:43 -0700 (PDT)
In-reply-to: <4.3.2.7.0.20000729081710.00b0e3c0@infidel.boolean.net> from "Kurt D. Zeilenga" at Jul "29," 2000 "08:18:12" am
> >I've no clue where to begin.
> The FAQ?

I did come across a couple of those entries.

> http://www.openldap.org/faq/index.cgi?file=345

The system does not have tcpwrappers installed, and TCP/IP is working
fine in loopback. The configure did not specify --without-ldbm, and
I've not yet seen any error talking about an illegal flag so far.

> http://www.openldap.org/faq/index.cgi?file=82

Configure was happy and had no issues with wait3 that I can see, and
it would seem the slapd is not crashing in regex code. The threads
being used on HP-UX 11 are "proper" POSIX threads and not the
user-space-only threads one is limited to on 10.20.

Someone pointed me towards the master.log, which does seem to have
some interesting errors in it:

slapd 1.2.11-Release (Fri Jul 28 10:35:48 PDT 2000)
	raj@tardy:/home/raj/openldap-1.2.11/servers/slapd
slapd starting
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (cn=MANAGER)
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
=> ldbm_cache_open( "./test-db/dn2id.dbb", 7, 600 )
ldbm_cache_open (blksize 8192) (maxids 2046) (maxindirect 2)
<= ldbm_cache_open (opened 0)
<= dn2id 18
=> id2entry_r( 18 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
ldbm_cache_open (blksize 8192) (maxids 2046) (maxindirect 2)
<= ldbm_cache_open (opened 1)
=> str2entry
<= str2entry 0x401232c0
entry_rdwr_rlock: ID: 18
<= id2entry_r( 18 ) (disk)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open NULL "./test-db/objectclass.dbb" errno 9 reason "Bad file number")
<= index_read NULL (could not open objectclass.dbb)
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "cn" "=" "MANAGER" )
=> ldbm_cache_open( "./test-db/cn.dbb", 7, 600 )
<= ldbm_cache_open NULL "./test-db/cn.dbb" errno 9 reason "Bad file number")
<= index_read NULL (could not open cn.dbb)

the above was interesting with the two errno 9's so that seems to be
an interesting place to try and dig deeper. the db I am running is (it
would seem):

Berkeley DB: version 2.7.7, 08/20/99

<= ava_candidates 0
<= filter_candidates 0
<= list_candidates 0
<= filter_candidates 0
send_ldap_result 0::
do_unbind
ber_get_next on fd 5 failed errno 0 (Error 0)
*** got 0 of 0 so far
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (cn=MANAGER)
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
====> cache_find_entry_dn2id: found dn: O=UNIVERSITY OF MICHIGAN,C=US
<= dn2id 18 (in cache)
=> id2entry_r( 18 )
====> cache_find_entry_dn2id: found id: 18 rw: 0
entry_rdwr_rtrylock: ID: 18
<= id2entry_r 0x401232c0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open NULL "./test-db/objectclass.dbb" errno 9 reason "Bad file number")
<= index_read NULL (could not open objectclass.dbb)
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "cn" "=" "MANAGER" )
=> ldbm_cache_open( "./test-db/cn.dbb", 7, 600 )
<= ldbm_cache_open NULL "./test-db/cn.dbb" errno 9 reason "Bad file number")
<= index_read NULL (could not open cn.dbb)
<= ava_candidates 0
<= filter_candidates 0
<= list_candidates 0
<= filter_candidates 0
send_ldap_result 0::
do_unbind
ber_get_next on fd 5 failed errno 0 (Error 0)
*** got 0 of 0 so far
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (cn=MANAGER)
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
====> cache_find_entry_dn2id: found dn: O=UNIVERSITY OF MICHIGAN,C=US
<= dn2id 18 (in cache)
=> id2entry_r( 18 )
====> cache_find_entry_dn2id: found id: 18 rw: 0
entry_rdwr_rtrylock: ID: 18
<= id2entry_r 0x401232c0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open NULL "./test-db/objectclass.dbb" errno 9 reason "Bad file number")
<= index_read NULL (could not open objectclass.dbb)
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "cn" "=" "MANAGER" )
=> ldbm_cache_open( "./test-db/cn.dbb", 7, 600 )
<= ldbm_cache_open NULL "./test-db/cn.dbb" errno 9 reason "Bad file number")
<= index_read NULL (could not open cn.dbb)
<= ava_candidates 0
<= filter_candidates 0
<= list_candidates 0
<= filter_candidates 0
send_ldap_result 0::
do_unbind
ber_get_next on fd -1 failed errno 9 (Bad file number)
*** got 0 of 0 so far
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (cn=MANAGER)
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
====> cache_find_entry_dn2id: found dn: O=UNIVERSITY OF MICHIGAN,C=US
<= dn2id 18 (in cache)
=> id2entry_r( 18 )
====> cache_find_entry_dn2id: found id: 18 rw: 0
entry_rdwr_rtrylock: ID: 18
<= id2entry_r 0x401232c0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
ldbm_cache_open (blksize 8192) (maxids 2046) (maxindirect 2)
<= ldbm_cache_open (opened 2)
<= index_read 0 candidates
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "cn" "=" "MANAGER" )
=> ldbm_cache_open( "./test-db/cn.dbb", 7, 600 )
ldbm_cache_open (blksize 8192) (maxids 2046) (maxindirect 2)
<= ldbm_cache_open (opened 3)
<= index_read 1 candidates
<= ava_candidates 1
<= filter_candidates 1
<= list_candidates 1
<= filter_candidates 1
=> id2entry_r( 15 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x40124fb0
entry_rdwr_rlock: ID: 15
<= id2entry_r( 15 ) (disk)
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=Manager, o=University of Michigan, c=US)
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 15
send_ldap_result 0::
do_unbind
ber_get_next on fd 5 failed errno 0 (Error 0)
*** got 0 of 0 so far
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (cn=MANAGER)
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
====> cache_find_entry_dn2id: found dn: O=UNIVERSITY OF MICHIGAN,C=US
<= dn2id 18 (in cache)
=> id2entry_r( 18 )
====> cache_find_entry_dn2id: found id: 18 rw: 0
entry_rdwr_rtrylock: ID: 18
<= id2entry_r 0x401232c0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 0 candidates
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "cn" "=" "MANAGER" )
=> ldbm_cache_open( "./test-db/cn.dbb", 7, 600 )
<= ldbm_cache_open (cache 3)
<= index_read 1 candidates
<= ava_candidates 1
<= filter_candidates 1
<= list_candidates 1
<= filter_candidates 1
=> id2entry_r( 15 )
====> cache_find_entry_dn2id: found id: 15 rw: 0
entry_rdwr_rtrylock: ID: 15
<= id2entry_r 0x40124fb0 (cache)
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=Manager, o=University of Michigan, c=US)
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=MANAGER,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 15
send_ldap_result 0::
ber_get_next on fd 5 failed errno 0 (Error 0)
*** got 0 of 0 so far
do_unbind
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (sn=JENSEN)
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
====> cache_find_entry_dn2id: found dn: O=UNIVERSITY OF MICHIGAN,C=US
<= dn2id 18 (in cache)
=> id2entry_r( 18 )
====> cache_find_entry_dn2id: found id: 18 rw: 0
entry_rdwr_rtrylock: ID: 18
<= id2entry_r 0x401232c0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 0 candidates
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "sn" "=" "JENSEN" )
=> ldbm_cache_open( "./test-db/sn.dbb", 7, 600 )
<= ldbm_cache_open NULL "./test-db/sn.dbb" errno 9 reason "Bad file number")
<= index_read NULL (could not open sn.dbb)
<= ava_candidates 0
<= filter_candidates 0
<= list_candidates 0
<= filter_candidates 0
send_ldap_result 0::
do_unbind
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (|(objectclass=RFC822MAILGROUP)(sn=JONES))
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
====> cache_find_entry_dn2id: found dn: O=UNIVERSITY OF MICHIGAN,C=US
<= dn2id 18 (in cache)
=> id2entry_r( 18 )
====> cache_find_entry_dn2id: found id: 18 rw: 0
entry_rdwr_rtrylock: ID: 18
<= id2entry_r 0x401232c0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 0 candidates
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "RFC822MAILGROUP" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 3 candidates
<= ava_candidates 3
<= filter_candidates 3
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "sn" "=" "JONES" )
=> ldbm_cache_open( "./test-db/sn.dbb", 7, 600 )
<= ldbm_cache_open NULL "./test-db/sn.dbb" errno 9 reason "Bad file number")
<= index_read NULL (could not open sn.dbb)
<= ava_candidates 0
<= filter_candidates 0
idl_free: called with NULL pointer
<= list_candidates 3
<= filter_candidates 3
<= list_candidates 3
<= filter_candidates 3
=> id2entry_r( 1 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x40124b78
entry_rdwr_rlock: ID: 1
<= id2entry_r( 1 ) (disk)
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=All Staff,ou=Groups,o=University of Michigan,c=US)
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 1
=> id2entry_r( 2 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x40125ea0
entry_rdwr_rlock: ID: 2
<= id2entry_r( 2 ) (disk)
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=Alumni Assoc Staff,ou=Groups,o=University of Michigan,c=US)
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 2
=> id2entry_r( 9 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x40127128
entry_rdwr_rlock: ID: 9
<= id2entry_r( 9 ) (disk)
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=ITD Staff,ou=Groups,o=University of Michigan,c=US)
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 9
send_ldap_result 0::
do_unbind
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (&(objectclass=RFC822MAILGROUP)(cn=A*))
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
====> cache_find_entry_dn2id: found dn: O=UNIVERSITY OF MICHIGAN,C=US
<= dn2id 18 (in cache)
=> id2entry_r( 18 )
====> cache_find_entry_dn2id: found id: 18 rw: 0
entry_rdwr_rtrylock: ID: 18
<= id2entry_r 0x401232c0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 0 candidates
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> list_candidates 0xa0
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "RFC822MAILGROUP" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 3 candidates
<= ava_candidates 3
<= filter_candidates 3
=> filter_candidates
=> substring_candidates
<= substring_candidates 20
<= filter_candidates 20
<= list_candidates 3
<= filter_candidates 3
<= list_candidates 3
<= filter_candidates 3
=> id2entry_r( 1 )
====> cache_find_entry_dn2id: found id: 1 rw: 0
entry_rdwr_rtrylock: ID: 1
<= id2entry_r 0x40124b78 (cache)
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=All Staff,ou=Groups,o=University of Michigan,c=US)
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 1
=> id2entry_r( 2 )
====> cache_find_entry_dn2id: found id: 2 rw: 0
entry_rdwr_rtrylock: ID: 2
<= id2entry_r 0x40125ea0 (cache)
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=Alumni Assoc Staff,ou=Groups,o=University of Michigan,c=US)
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 2
=> id2entry_r( 9 )
====> cache_find_entry_dn2id: found id: 9 rw: 0
entry_rdwr_rtrylock: ID: 9
<= id2entry_r 0x40127128 (cache)
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
====> cache_return_entry_r
entry_rdwr_runlock: ID: 9
send_ldap_result 0::
do_unbind
do_bind
do_bind: version 2 dn () method 128
send_ldap_result 0::
do_search
SRCH "O=UNIVERSITY OF MICHIGAN,C=US" 2 0    0 0 0
    filter: (!(objectclass=PERSON))
    attrs:
=> ldbm_back_search
using base "O=UNIVERSITY OF MICHIGAN,C=US"
subtree_candidates: base: "O=UNIVERSITY OF MICHIGAN,C=US" lookupbase
dn2entry_r: dn: "O=UNIVERSITY OF MICHIGAN,C=US"
=> dn2id( "O=UNIVERSITY OF MICHIGAN,C=US" )
====> cache_find_entry_dn2id: found dn: O=UNIVERSITY OF MICHIGAN,C=US
<= dn2id 18 (in cache)
=> id2entry_r( 18 )
====> cache_find_entry_dn2id: found id: 18 rw: 0
entry_rdwr_rtrylock: ID: 18
<= id2entry_r 0x401232c0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
=> filter_candidates
=> list_candidates 0xa1
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 0 candidates
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
=> filter_candidates
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "PERSON" )
=> ldbm_cache_open( "./test-db/objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 11 candidates
<= ava_candidates 11
<= filter_candidates 11
<= filter_candidates 8
<= list_candidates 8
<= filter_candidates 8
=> id2entry_r( 1 )
====> cache_find_entry_dn2id: found id: 1 rw: 0
entry_rdwr_rtrylock: ID: 1
<= id2entry_r 0x40124b78 (cache)
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=All Staff,ou=Groups,o=University of Michigan,c=US)
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALL STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 1
=> id2entry_r( 2 )
====> cache_find_entry_dn2id: found id: 2 rw: 0
entry_rdwr_rtrylock: ID: 2
<= id2entry_r 0x40125ea0 (cache)
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=Alumni Assoc Staff,ou=Groups,o=University of Michigan,c=US)
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ALUMNI ASSOC STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 2
=> id2entry_r( 3 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x401253c0
entry_rdwr_rlock: ID: 3
<= id2entry_r( 3 ) (disk)
=> acl_get: edn OU=ALUMNI ASSOCIATION,OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (ou=Alumni Association, ou=People, o=University of Michigan, c=US)
=> acl_get: edn OU=ALUMNI ASSOCIATION,OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=ALUMNI ASSOCIATION,OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=ALUMNI ASSOCIATION,OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 3
=> id2entry_r( 7 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x401257d8
entry_rdwr_rlock: ID: 7
<= id2entry_r( 7 ) (disk)
=> acl_get: edn OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (ou=Groups, o=University of Michigan, c=US)
=> acl_get: edn OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 7
=> id2entry_r( 8 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x401233a8
entry_rdwr_rlock: ID: 8
<= id2entry_r( 8 ) (disk)
=> acl_get: edn OU=INFORMATION TECHNOLOGY DIVISION,OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (ou=Information Technology Division, ou=People, o=University of Michigan, c=US)
=> acl_get: edn OU=INFORMATION TECHNOLOGY DIVISION,OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=INFORMATION TECHNOLOGY DIVISION,OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=INFORMATION TECHNOLOGY DIVISION,OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 8
=> id2entry_r( 9 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x40123ab0
entry_rdwr_rlock: ID: 9
<= id2entry_r( 9 ) (disk)
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (cn=ITD Staff,ou=Groups,o=University of Michigan,c=US)
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn CN=ITD STAFF,OU=GROUPS,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 9
=> id2entry_r( 17 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x40123848
entry_rdwr_rlock: ID: 17
<= id2entry_r( 17 ) (disk)
=> acl_get: edn OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (ou=People, o=University of Michigan, c=US)
=> acl_get: edn OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn OU=PEOPLE,O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 17
=> id2entry_r( 18 )
=> ldbm_cache_open( "./test-db/id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x40125f78
entry_rdwr_rlock: ID: 18
<= id2entry_r( 18 ) (disk)
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> send_search_entry (o=University of Michigan, c=US)
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
=> acl_get: edn O=UNIVERSITY OF MICHIGAN,C=US
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 18
send_ldap_result 0::
do_unbind
slapd shutting down - waiting for 0 threads to terminate
slapd shutting down - waiting for backends to close down
ldbm backend saving nextid
ldbm backend syncing
ldbm flushing db (./test-db/dn2id.dbb)
ldbm flushing db (./test-db/id2entry.dbb)
ldbm flushing db (./test-db/objectclass.dbb)
ldbm flushing db (./test-db/cn.dbb)
ldbm backend done syncing
slapd stopping
Follow-Ups:
- Re: diagnosing test failures
  - From: Patrick Timmons <ptimmons@courriel.polymtl.ca>
References:
- Re: diagnosing test failures
  - From: "Kurt D. Zeilenga" <Kurt@OpenLDAP.org>
Prev by Date: Re: diagnosing test failures
Next by Date: Re: diagnosing test failures
Index(es):
- Chronological
- Thread