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

trouble getting linux pam_ldap to authenticate



I am trying  set up an LDAP server to handle user authentication under
RedHat 6.2.  I am able to retrieve information from the server using the
following command:
/usr/local/bin/ldapsearch -W -x -D 'CN=MANAGER,DC=DEADTROUT,DC=COM' -b
'dc=deadtrout,dc=com' '(objectclass=*)'

I have installed the padl pam_ldap module and have changed /etc/pam/rlogin,
/etc/nsswitch.conf, and /etc/ldap.conf files accordingly. (I don't think
this is where my problem is so I'm not attaching the text of those files)

I have been trying to test the authentication by issuing the command:
rlogin -l otherguy localhost
but when I supply the password rlogin rejects it.

/var/log/pamlog shows the following entry:
Nov 10 16:07:32 mankind rlogind[10489]: PAM cannot assume gid

slapd debug output:


daemon: activity on 1 descriptors
daemon: new connection on 9
daemon: added 9r
daemon: activity on:
daemon: select: listen=6 active_threads=0 tvp=NULL
daemon: activity on 1 descriptors
daemon: activity on: 9r
daemon: read activity on 9
connection_get(9)
connection_get(9): got connid=9
connection_read(9): checking for input on id=9
ber_get_next
ldap_read: want=1, got=1
  0000:  30                                                 0
ldap_read: want=1, got=1
  0000:  2d                                                 -
ldap_read: want=45, got=45
  0000:  02 01 01 60 28 02 01 02  04 1b 63 6e 3d 6d 61 6e   ...`(.....cn=man
  0010:  61 67 65 72 2c 64 63 3d  69 6e 74 65 76 6f 2c 64
ager,dc=deadtrout,d
  0020:  63 3d 63 6f 6d 80 06 66  6f 6f 66 6f 6f            c=com..secret
ber_get_next: tag 0x30 len 45 contents:
ber_dump: buf=0x40204570 ptr=0x40204570 end=0x4020459d len=45
  0000:  02 01 01 60 28 02 01 02  04 1b 63 6e 3d 6d 61 6e   ...`(.....cn=man
  0010:  61 67 65 72 2c 64 63 3d  69 6e 74 65 76 6f 2c 64
ager,dc=deadtrout,d
  0020:  63 3d 63 6f 6d 80 06 66  6f 6f 66 6f 6f            c=com..secret
ber_get_next
ldap_read: want=1 error=Resource temporarily unavailable
ber_get_next on fd 9 failed errno=11 (Resource temporarily unavailable)
do_bind
ber_scanf fmt ({iat) ber:
ber_dump: buf=0x40204570 ptr=0x40204573 end=0x4020459d len=42
  0000:  60 28 02 01 02 04 1b 63  6e 3d 6d 61 6e 61 67 65   `(.....cn=manage
  0010:  72 2c 64 63 3d 69 6e 74  65 76 6f 2c 64 63 3d 63
r,dc=deadtrout,dc=c
  0020:  6f 6d 80 06 66 6f 6f 66  6f 6f                     om..secret
ber_scanf fmt (o}) ber:
ber_dump: buf=0x40204570 ptr=0x40204595 end=0x4020459d len=8
  0000:  80 06 66 6f 6f 66 6f 6f                            ..secret
do_bind: version=2 dn="cn=manager,dc=deadtrout,dc=com" method=128
==> ldbm_back_bind: dn: cn=manager,dc=deadtrout,dc=com
dn2entry_r: dn: "CN=MANAGER,DC=DEADTROUT,DC=COM"
=> dn2id( "CN=MANAGER,DC=DEADTROUT,DC=COM" )
=> ldbm_cache_open( "/usr/local/var/openldap-ldbm/dn2id.dbb", 7, 600 )
<= ldbm_cache_open (cache 0)
<= dn2id NOID
dn2entry_r: dn: "DC=DEADTROUT,DC=COM"
=> dn2id( "DC=DEADTROUT,DC=COM" )
====> cache_find_entry_dn2id("DC=DEADTROUT,DC=COM"): 1 (1 tries)
<= dn2id 1 (in cache)
=> id2entry_r( 1 )
entry_rdwr_rtrylock: ID: 1
====> cache_find_entry_id( 1 ) "dc=deadtrout, dc=com" (found) (1 tries)
<= id2entry_r( 1 ) 0x80e8c78 (cache)
entry_rdwr_runlock: ID: 1
====> cache_return_entry_r( 1 ): returned (0)
do_bind: v2 bind: "cn=manager,dc=deadtrout,dc=com" to "cn=Manager,
dc=deadtrout, dc=com"
send_ldap_result: conn=9 op=0 p=2
send_ldap_result: 0::
send_ldap_response: msgid=1 tag=97 err=0
ber_flush: 14 bytes to sd 9
  0000:  30 0c 02 01 01 61 07 0a  01 00 04 00 04 00         0....a........
ldap_write: want=14, written=14
  0000:  30 0c 02 01 01 61 07 0a  01 00 04 00 04 00         0....a........
daemon: select: listen=6 active_threads=0 tvp=NULL
daemon: activity on 1 descriptors
daemon: activity on: 9r
daemon: read activity on 9
connection_get(9)
connection_get(9): got connid=9
connection_read(9): checking for input on id=9
ber_get_next
ldap_read: want=1, got=1
  0000:  30                                                 0
ldap_read: want=1, got=1
  0000:  39                                                 9
ldap_read: want=57, got=57
  0000:  02 01 02 63 34 04 10 64  63 3d 69 6e 74 65 76 6f
...c4..dc=deadtrout
  0010:  2c 64 63 3d 63 6f 6d 0a  01 02 0a 01 00 02 01 01   ,dc=com.........
  0020:  02 01 00 01 01 00 a3 0f  04 03 75 69 64 04 08 6f   ..........uid..o
  0030:  74 68 65 72 67 75 79 30  00                        therguy0.
ber_get_next: tag 0x30 len 57 contents:
ber_dump: buf=0x40204540 ptr=0x40204540 end=0x40204579 len=57
  0000:  02 01 02 63 34 04 10 64  63 3d 69 6e 74 65 76 6f
...c4..dc=deadtrout
  0010:  2c 64 63 3d 63 6f 6d 0a  01 02 0a 01 00 02 01 01   ,dc=com.........
  0020:  02 01 00 01 01 00 a3 0f  04 03 75 69 64 04 08 6f   ..........uid..o
  0030:  74 68 65 72 67 75 79 30  00                        therguy0.
ber_get_next
ldap_read: want=1 error=Resource temporarily unavailable
ber_get_next on fd 9 failed errno=11 (Resource temporarily unavailable)
do_search
ber_scanf fmt ({aiiiib) ber:
ber_dump: buf=0x40204540 ptr=0x40204543 end=0x40204579 len=54
  0000:  63 34 04 10 64 63 3d 69  6e 74 65 76 6f 2c 64 63
c4..dc=deadtrout,dc
  0010:  3d 63 6f 6d 0a 01 02 0a  01 00 02 01 01 02 01 00   =com............
  0020:  01 01 00 a3 0f 04 03 75  69 64 04 08 6f 74 68 65   .......uid..othe
  0030:  72 67 75 79 30 00                                  rguy0.
SRCH "dc=deadtrout,dc=com" 2 0    1 0 0
begin get_filter
EQUALITY
ber_scanf fmt ({oo}) ber:
ber_dump: buf=0x40204540 ptr=0x40204566 end=0x40204579 len=19
  0000:  a3 0f 04 03 75 69 64 04  08 6f 74 68 65 72 67 75   ....uid..othergu
  0010:  79 30 00                                           y0.
end get_filter 0
    filter: (uid=otherguy)
ber_scanf fmt ({v}}) ber:
ber_dump: buf=0x40204540 ptr=0x40204577 end=0x40204579 len=2
  0000:  30 00                                              0.
    attrs:
=> ldbm_back_search
dn2entry_r: dn: "DC=DEADTROUT,DC=COM"
=> dn2id( "DC=DEADTROUT,DC=COM" )
====> cache_find_entry_dn2id("DC=DEADTROUT,DC=COM"): 1 (1 tries)
<= dn2id 1 (in cache)
=> id2entry_r( 1 )
entry_rdwr_rtrylock: ID: 1
====> cache_find_entry_id( 1 ) "dc=deadtrout, dc=com" (found) (1 tries)
<= id2entry_r( 1 ) 0x80e8c78 (cache)
search_candidates: base="DC=DEADTROUT,DC=COM" s=2 d=0
=> filter_candidates
        AND
=> list_candidates 0xa0
=> filter_candidates
        DN SUBTREE
=> dn2idl( "@DC=DEADTROUT,DC=COM" )
=> ldbm_cache_open( "/usr/local/var/openldap-ldbm/dn2id.dbb", 7, 600 )
<= ldbm_cache_open (cache 0)
<= filter_candidates 107
=> filter_candidates
daemon: select: listen=6 active_threads=1 tvp=NULL
        OR
=> list_candidates 0xa1
=> filter_candidates
        EQUALITY
=> equality_candidates
=> ldbm_cache_open( "/usr/local/var/openldap-ldbm/objectClass.dbb", 65536,
600 )
<= ldbm_cache_open (cache 3)
=> key_read
<= index_read 0 candidates
<= equality_candidates NULL
<= equality_candidates 0
<= filter_candidates 0
=> filter_candidates
        EQUALITY
=> equality_candidates
<= equality_candidates: index_param returned=18
<= filter_candidates 108
<= list_candidates 108
<= filter_candidates 108
<= list_candidates 107
<= filter_candidates 107
entry_rdwr_runlock: ID: 1
====> cache_return_entry_r( 1 ): returned (0)
=> id2entry_r( 1 )
entry_rdwr_rtrylock: ID: 1
====> cache_find_entry_id( 1 ) "dc=deadtrout, dc=com" (found) (1 tries)
<= id2entry_r( 1 ) 0x80e8c78 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "dc=deadtrout, dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 1 does not match filter
entry_rdwr_runlock: ID: 1
====> cache_return_entry_r( 1 ): returned (0)
=> id2entry_r( 2 )
entry_rdwr_rtrylock: ID: 2
====> cache_find_entry_id( 2 ) "ou=People,dc=deadtrout, dc=com" (found) (1
tries)
<= id2entry_r( 2 ) 0x80f6598 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=People,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 2 does not match filter
entry_rdwr_runlock: ID: 2
====> cache_return_entry_r( 2 ): returned (0)
=> id2entry_r( 3 )
entry_rdwr_rtrylock: ID: 3
====> cache_find_entry_id( 3 ) "ou=Rpc,dc=deadtrout, dc=com" (found) (1
tries)
<= id2entry_r( 3 ) 0x8103a40 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Rpc,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 3 does not match filter
entry_rdwr_runlock: ID: 3
====> cache_return_entry_r( 3 ): returned (0)
=> id2entry_r( 4 )
entry_rdwr_rtrylock: ID: 4
====> cache_find_entry_id( 4 ) "ou=Group,dc=deadtrout, dc=com" (found) (1
tries)
<= id2entry_r( 4 ) 0x8103a88 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Group,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 4 does not match filter
entry_rdwr_runlock: ID: 4
====> cache_return_entry_r( 4 ): returned (0)
=> id2entry_r( 5 )
entry_rdwr_rtrylock: ID: 5
====> cache_find_entry_id( 5 ) "ou=Networks,dc=deadtrout, dc=com" (found) (1
tries)
<= id2entry_r( 5 ) 0x8104450 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Networks,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 5 does not match filter
entry_rdwr_runlock: ID: 5
====> cache_return_entry_r( 5 ): returned (0)
=> id2entry_r( 6 )
entry_rdwr_rtrylock: ID: 6
====> cache_find_entry_id( 6 ) "nisMapName=netgroup.byhost,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 6 ) 0x8104748 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to
"nisMapName=netgroup.byhost,dc=deadtrout, dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 6 does not match filter
entry_rdwr_runlock: ID: 6
====> cache_return_entry_r( 6 ): returned (0)
=> id2entry_r( 7 )
entry_rdwr_rtrylock: ID: 7
====> cache_find_entry_id( 7 ) "nisMapName=netgroup.byuser,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 7 ) 0x8104de8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to
"nisMapName=netgroup.byuser,dc=deadtrout, dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 7 does not match filter
entry_rdwr_runlock: ID: 7
====> cache_return_entry_r( 7 ): returned (0)
=> id2entry_r( 8 )
entry_rdwr_rtrylock: ID: 8
====> cache_find_entry_id( 8 ) "ou=Netgroup,dc=deadtrout, dc=com" (found) (1
tries)
<= id2entry_r( 8 ) 0x8105270 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Netgroup,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 8 does not match filter
entry_rdwr_runlock: ID: 8
====> cache_return_entry_r( 8 ): returned (0)
=> id2entry_r( 9 )
entry_rdwr_rtrylock: ID: 9
====> cache_find_entry_id( 9 ) "ou=Hosts,dc=deadtrout, dc=com" (found) (1
tries)
<= id2entry_r( 9 ) 0x8105640 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Hosts,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 9 does not match filter
entry_rdwr_runlock: ID: 9
====> cache_return_entry_r( 9 ): returned (0)
=> id2entry_r( 10 )
entry_rdwr_rtrylock: ID: 10
====> cache_find_entry_id( 10 ) "ou=Services,dc=deadtrout, dc=com" (found)
(1 tries)
<= id2entry_r( 10 ) 0x8105960 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Services,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 10 does not match filter
entry_rdwr_runlock: ID: 10
====> cache_return_entry_r( 10 ): returned (0)
=> id2entry_r( 11 )
entry_rdwr_rtrylock: ID: 11
====> cache_find_entry_id( 11 ) "ou=Mounts,dc=deadtrout, dc=com" (found) (1
tries)
<= id2entry_r( 11 ) 0x8105ef8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Mounts,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 11 does not match filter
entry_rdwr_runlock: ID: 11
====> cache_return_entry_r( 11 ): returned (0)
=> id2entry_r( 12 )
entry_rdwr_rtrylock: ID: 12
====> cache_find_entry_id( 12 ) "ou=Aliases,dc=deadtrout, dc=com" (found) (1
tries)
<= id2entry_r( 12 ) 0x8106218 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Aliases,dc=deadtrout, dc=com" "uid"
requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 12 does not match filter
entry_rdwr_runlock: ID: 12
====> cache_return_entry_r( 12 ): returned (0)
=> id2entry_r( 13 )
entry_rdwr_rtrylock: ID: 13
====> cache_find_entry_id( 13 ) "ou=Protocols,dc=deadtrout, dc=com" (found)
(1 tries)
<= id2entry_r( 13 ) 0x81067a8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "ou=Protocols,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 13 does not match filter
entry_rdwr_runlock: ID: 13
====> cache_return_entry_r( 13 ): returned (0)
=> id2entry_r( 14 )
entry_rdwr_rtrylock: ID: 14
====> cache_find_entry_id( 14 ) "cn=root,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 14 ) 0x8106aa8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=root,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 14 does not match filter
entry_rdwr_runlock: ID: 14
====> cache_return_entry_r( 14 ): returned (0)
=> id2entry_r( 15 )
entry_rdwr_rtrylock: ID: 15
====> cache_find_entry_id( 15 ) "cn=bin,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 15 ) 0x8106fe0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=bin,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 15 does not match filter
entry_rdwr_runlock: ID: 15
====> cache_return_entry_r( 15 ): returned (0)
=> id2entry_r( 16 )
entry_rdwr_rtrylock: ID: 16
====> cache_find_entry_id( 16 ) "cn=daemon,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 16 ) 0x8107650 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=daemon,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 16 does not match filter
entry_rdwr_runlock: ID: 16
====> cache_return_entry_r( 16 ): returned (0)
=> id2entry_r( 17 )
entry_rdwr_rtrylock: ID: 17
====> cache_find_entry_id( 17 ) "cn=sys,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 17 ) 0x8107c58 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=sys,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 17 does not match filter
entry_rdwr_runlock: ID: 17
====> cache_return_entry_r( 17 ): returned (0)
=> id2entry_r( 18 )
entry_rdwr_rtrylock: ID: 18
====> cache_find_entry_id( 18 ) "cn=adm,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 18 ) 0x81082c8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=adm,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 18 does not match filter
entry_rdwr_runlock: ID: 18
====> cache_return_entry_r( 18 ): returned (0)
=> id2entry_r( 19 )
entry_rdwr_rtrylock: ID: 19
====> cache_find_entry_id( 19 ) "cn=tty,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 19 ) 0x81085a0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=tty,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 19 does not match filter
entry_rdwr_runlock: ID: 19
====> cache_return_entry_r( 19 ): returned (0)
=> id2entry_r( 20 )
entry_rdwr_rtrylock: ID: 20
====> cache_find_entry_id( 20 ) "cn=disk,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 20 ) 0x8108b68 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=disk,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 20 does not match filter
entry_rdwr_runlock: ID: 20
====> cache_return_entry_r( 20 ): returned (0)
=> id2entry_r( 21 )
entry_rdwr_rtrylock: ID: 21
====> cache_find_entry_id( 21 ) "cn=lp,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 21 ) 0x81093e8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=lp,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 21 does not match filter
entry_rdwr_runlock: ID: 21
====> cache_return_entry_r( 21 ): returned (0)
=> id2entry_r( 22 )
entry_rdwr_rtrylock: ID: 22
====> cache_find_entry_id( 22 ) "cn=mem,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 22 ) 0x81099d0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=mem,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 22 does not match filter
entry_rdwr_runlock: ID: 22
====> cache_return_entry_r( 22 ): returned (0)
=> id2entry_r( 23 )
entry_rdwr_rtrylock: ID: 23
====> cache_find_entry_id( 23 ) "cn=kmem,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 23 ) 0x8109f28 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=kmem,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 23 does not match filter
entry_rdwr_runlock: ID: 23
====> cache_return_entry_r( 23 ): returned (0)
=> id2entry_r( 24 )
entry_rdwr_rtrylock: ID: 24
====> cache_find_entry_id( 24 ) "cn=wheel,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 24 ) 0x810a490 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=wheel,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 24 does not match filter
entry_rdwr_runlock: ID: 24
====> cache_return_entry_r( 24 ): returned (0)
=> id2entry_r( 25 )
entry_rdwr_rtrylock: ID: 25
====> cache_find_entry_id( 25 ) "cn=mail,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 25 ) 0x810aa80 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=mail,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 25 does not match filter
entry_rdwr_runlock: ID: 25
====> cache_return_entry_r( 25 ): returned (0)
=> id2entry_r( 26 )
entry_rdwr_rtrylock: ID: 26
====> cache_find_entry_id( 26 ) "cn=news,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 26 ) 0x810afd8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=news,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 26 does not match filter
entry_rdwr_runlock: ID: 26
====> cache_return_entry_r( 26 ): returned (0)
=> id2entry_r( 27 )
entry_rdwr_rtrylock: ID: 27
====> cache_find_entry_id( 27 ) "cn=uucp,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 27 ) 0x810b540 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=uucp,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 27 does not match filter
entry_rdwr_runlock: ID: 27
====> cache_return_entry_r( 27 ): returned (0)
=> id2entry_r( 28 )
entry_rdwr_rtrylock: ID: 28
====> cache_find_entry_id( 28 ) "cn=man,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 28 ) 0x810baa8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=man,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 28 does not match filter
entry_rdwr_runlock: ID: 28
====> cache_return_entry_r( 28 ): returned (0)
=> id2entry_r( 29 )
entry_rdwr_rtrylock: ID: 29
====> cache_find_entry_id( 29 ) "cn=games,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 29 ) 0x810c010 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=games,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 29 does not match filter
entry_rdwr_runlock: ID: 29
====> cache_return_entry_r( 29 ): returned (0)
=> id2entry_r( 30 )
entry_rdwr_rtrylock: ID: 30
====> cache_find_entry_id( 30 ) "cn=gopher,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 30 ) 0x810c578 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=gopher,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 30 does not match filter
entry_rdwr_runlock: ID: 30
====> cache_return_entry_r( 30 ): returned (0)
=> id2entry_r( 31 )
entry_rdwr_rtrylock: ID: 31
====> cache_find_entry_id( 31 ) "cn=dip,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 31 ) 0x810cae0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=dip,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 31 does not match filter
entry_rdwr_runlock: ID: 31
====> cache_return_entry_r( 31 ): returned (0)
=> id2entry_r( 32 )
entry_rdwr_rtrylock: ID: 32
====> cache_find_entry_id( 32 ) "cn=ftp,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 32 ) 0x810d050 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=ftp,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 32 does not match filter
entry_rdwr_runlock: ID: 32
====> cache_return_entry_r( 32 ): returned (0)
=> id2entry_r( 33 )
entry_rdwr_rtrylock: ID: 33
====> cache_find_entry_id( 33 ) "cn=nobody,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 33 ) 0x810d5b8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=nobody,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 33 does not match filter
entry_rdwr_runlock: ID: 33
====> cache_return_entry_r( 33 ): returned (0)
=> id2entry_r( 34 )
entry_rdwr_rtrylock: ID: 34
====> cache_find_entry_id( 34 ) "cn=users,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 34 ) 0x810db20 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=users,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 34 does not match filter
entry_rdwr_runlock: ID: 34
====> cache_return_entry_r( 34 ): returned (0)
=> id2entry_r( 35 )
entry_rdwr_rtrylock: ID: 35
====> cache_find_entry_id( 35 ) "cn=floppy,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 35 ) 0x810e090 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=floppy,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 35 does not match filter
entry_rdwr_runlock: ID: 35
====> cache_return_entry_r( 35 ): returned (0)
=> id2entry_r( 36 )
entry_rdwr_rtrylock: ID: 36
====> cache_find_entry_id( 36 ) "cn=xfs,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 36 ) 0x810e5f8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=xfs,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 36 does not match filter
entry_rdwr_runlock: ID: 36
====> cache_return_entry_r( 36 ): returned (0)
=> id2entry_r( 37 )
entry_rdwr_rtrylock: ID: 37
====> cache_find_entry_id( 37 ) "cn=console,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 37 ) 0x810eb68 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=console,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 37 does not match filter
entry_rdwr_runlock: ID: 37
====> cache_return_entry_r( 37 ): returned (0)
=> id2entry_r( 38 )
entry_rdwr_rtrylock: ID: 38
====> cache_find_entry_id( 38 ) "cn=utmp,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 38 ) 0x810f0d8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=utmp,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 38 does not match filter
entry_rdwr_runlock: ID: 38
====> cache_return_entry_r( 38 ): returned (0)
=> id2entry_r( 39 )
entry_rdwr_rtrylock: ID: 39
====> cache_find_entry_id( 39 ) "cn=pppusers,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 39 ) 0x810f638 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=pppusers,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 39 does not match filter
entry_rdwr_runlock: ID: 39
====> cache_return_entry_r( 39 ): returned (0)
=> id2entry_r( 40 )
entry_rdwr_rtrylock: ID: 40
====> cache_find_entry_id( 40 ) "cn=popusers,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 40 ) 0x810fba8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=popusers,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 40 does not match filter
entry_rdwr_runlock: ID: 40
====> cache_return_entry_r( 40 ): returned (0)
=> id2entry_r( 41 )
entry_rdwr_rtrylock: ID: 41
====> cache_find_entry_id( 41 ) "cn=slipusers,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 41 ) 0x8110120 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=slipusers,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 41 does not match filter
entry_rdwr_runlock: ID: 41
====> cache_return_entry_r( 41 ): returned (0)
=> id2entry_r( 42 )
entry_rdwr_rtrylock: ID: 42
====> cache_find_entry_id( 42 ) "cn=slocate,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 42 ) 0x8110698 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=slocate,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 42 does not match filter
entry_rdwr_runlock: ID: 42
====> cache_return_entry_r( 42 ): returned (0)
=> id2entry_r( 43 )
entry_rdwr_rtrylock: ID: 43
====> cache_find_entry_id( 43 ) "cn=admin,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 43 ) 0x8110c10 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=admin,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 43 does not match filter
entry_rdwr_runlock: ID: 43
====> cache_return_entry_r( 43 ): returned (0)
=> id2entry_r( 44 )
entry_rdwr_rtrylock: ID: 44
====> cache_find_entry_id( 44 ) "cn=httpd,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 44 ) 0x8111278 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=httpd,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 44 does not match filter
entry_rdwr_runlock: ID: 44
====> cache_return_entry_r( 44 ): returned (0)
=> id2entry_r( 45 )
entry_rdwr_rtrylock: ID: 45
====> cache_find_entry_id( 45 ) "cn=dnscache,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 45 ) 0x81117a0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=dnscache,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 45 does not match filter
entry_rdwr_runlock: ID: 45
====> cache_return_entry_r( 45 ): returned (0)
=> id2entry_r( 46 )
entry_rdwr_rtrylock: ID: 46
====> cache_find_entry_id( 46 ) "cn=dnslog,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 46 ) 0x8111d10 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=dnslog,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 46 does not match filter
entry_rdwr_runlock: ID: 46
====> cache_return_entry_r( 46 ): returned (0)
=> id2entry_r( 47 )
entry_rdwr_rtrylock: ID: 47
====> cache_find_entry_id( 47 ) "cn=nofiles,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 47 ) 0x8112288 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=nofiles,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 47 does not match filter
entry_rdwr_runlock: ID: 47
====> cache_return_entry_r( 47 ): returned (0)
=> id2entry_r( 48 )
entry_rdwr_rtrylock: ID: 48
====> cache_find_entry_id( 48 ) "cn=qmail,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 48 ) 0x8112800 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=qmail,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 48 does not match filter
entry_rdwr_runlock: ID: 48
====> cache_return_entry_r( 48 ): returned (0)
=> id2entry_r( 49 )
entry_rdwr_rtrylock: ID: 49
====> cache_find_entry_id( 49 ) "cn=db2client,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 49 ) 0x8112d60 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=db2client,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 49 does not match filter
entry_rdwr_runlock: ID: 49
====> cache_return_entry_r( 49 ): returned (0)
=> id2entry_r( 50 )
entry_rdwr_rtrylock: ID: 50
====> cache_find_entry_id( 50 ) "cn=maild,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 50 ) 0x81132d0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=maild,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 50 does not match filter
entry_rdwr_runlock: ID: 50
====> cache_return_entry_r( 50 ): returned (0)
=> id2entry_r( 51 )
entry_rdwr_rtrylock: ID: 51
====> cache_find_entry_id( 51 ) "cn=named,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 51 ) 0x8113830 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=named,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 51 does not match filter
entry_rdwr_runlock: ID: 51
====> cache_return_entry_r( 51 ): returned (0)
=> id2entry_r( 52 )
entry_rdwr_rtrylock: ID: 52
====> cache_find_entry_id( 52 ) "cn=staff,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 52 ) 0x8113d98 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=staff,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 52 does not match filter
entry_rdwr_runlock: ID: 52
====> cache_return_entry_r( 52 ): returned (0)
=> id2entry_r( 53 )
entry_rdwr_rtrylock: ID: 53
====> cache_find_entry_id( 53 ) "cn=nick,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 53 ) 0x8114478 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=nick,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 53 does not match filter
entry_rdwr_runlock: ID: 53
====> cache_return_entry_r( 53 ): returned (0)
=> id2entry_r( 54 )
entry_rdwr_rtrylock: ID: 54
====> cache_find_entry_id( 54 ) "cn=lance,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 54 ) 0x8114920 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=lance,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 54 does not match filter
entry_rdwr_runlock: ID: 54
====> cache_return_entry_r( 54 ): returned (0)
=> id2entry_r( 55 )
entry_rdwr_rtrylock: ID: 55
====> cache_find_entry_id( 55 ) "cn=phil,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 55 ) 0x8114ef8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=phil,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 55 does not match filter
entry_rdwr_runlock: ID: 55
====> cache_return_entry_r( 55 ): returned (0)
=> id2entry_r( 56 )
entry_rdwr_rtrylock: ID: 56
====> cache_find_entry_id( 56 ) "cn=kabir,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 56 ) 0x8115460 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=kabir,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 56 does not match filter
entry_rdwr_runlock: ID: 56
====> cache_return_entry_r( 56 ): returned (0)
=> id2entry_r( 57 )
entry_rdwr_rtrylock: ID: 57
====> cache_find_entry_id( 57 ) "cn=steve,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 57 ) 0x81159c8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=steve,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 57 does not match filter
entry_rdwr_runlock: ID: 57
====> cache_return_entry_r( 57 ): returned (0)
=> id2entry_r( 58 )
entry_rdwr_rtrylock: ID: 58
====> cache_find_entry_id( 58 ) "cn=jeff,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 58 ) 0x8115f30 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=jeff,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 58 does not match filter
entry_rdwr_runlock: ID: 58
====> cache_return_entry_r( 58 ): returned (0)
=> id2entry_r( 59 )
entry_rdwr_rtrylock: ID: 59
====> cache_find_entry_id( 59 ) "cn=bert,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 59 ) 0x8116498 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=bert,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 59 does not match filter
entry_rdwr_runlock: ID: 59
====> cache_return_entry_r( 59 ): returned (0)
=> id2entry_r( 60 )
entry_rdwr_rtrylock: ID: 60
====> cache_find_entry_id( 60 ) "cn=evan,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 60 ) 0x8116a00 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=evan,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 60 does not match filter
entry_rdwr_runlock: ID: 60
====> cache_return_entry_r( 60 ): returned (0)
=> id2entry_r( 61 )
entry_rdwr_rtrylock: ID: 61
====> cache_find_entry_id( 61 ) "cn=terry,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 61 ) 0x8116f68 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=terry,ou=Group,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 61 does not match filter
entry_rdwr_runlock: ID: 61
====> cache_return_entry_r( 61 ): returned (0)
=> id2entry_r( 62 )
entry_rdwr_rtrylock: ID: 62
====> cache_find_entry_id( 62 ) "cn=guestmail,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 62 ) 0x81174d0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=guestmail,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 62 does not match filter
entry_rdwr_runlock: ID: 62
====> cache_return_entry_r( 62 ): returned (0)
=> id2entry_r( 63 )
entry_rdwr_rtrylock: ID: 63
====> cache_find_entry_id( 63 ) "uid=root,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 63 ) 0x8117a40 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=root,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 63 does not match filter
entry_rdwr_runlock: ID: 63
====> cache_return_entry_r( 63 ): returned (0)
=> id2entry_r( 64 )
entry_rdwr_rtrylock: ID: 64
====> cache_find_entry_id( 64 ) "uid=bin,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 64 ) 0x8118648 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=bin,ou=People,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 64 does not match filter
entry_rdwr_runlock: ID: 64
====> cache_return_entry_r( 64 ): returned (0)
=> id2entry_r( 65 )
entry_rdwr_rtrylock: ID: 65
====> cache_find_entry_id( 65 ) "uid=daemon,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 65 ) 0x8117d68 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=daemon,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 65 does not match filter
entry_rdwr_runlock: ID: 65
====> cache_return_entry_r( 65 ): returned (0)
=> id2entry_r( 66 )
entry_rdwr_rtrylock: ID: 66
====> cache_find_entry_id( 66 ) "uid=adm,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 66 ) 0x8119210 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=adm,ou=People,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 66 does not match filter
entry_rdwr_runlock: ID: 66
====> cache_return_entry_r( 66 ): returned (0)
=> id2entry_r( 67 )
entry_rdwr_rtrylock: ID: 67
====> cache_find_entry_id( 67 ) "uid=lp,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 67 ) 0x8119b60 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=lp,ou=People,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 67 does not match filter
entry_rdwr_runlock: ID: 67
====> cache_return_entry_r( 67 ): returned (0)
=> id2entry_r( 68 )
entry_rdwr_rtrylock: ID: 68
====> cache_find_entry_id( 68 ) "uid=sync,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 68 ) 0x811a480 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=sync,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 68 does not match filter
entry_rdwr_runlock: ID: 68
====> cache_return_entry_r( 68 ): returned (0)
=> id2entry_r( 69 )
entry_rdwr_rtrylock: ID: 69
====> cache_find_entry_id( 69 ) "uid=shutdown,ou=People,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 69 ) 0x811add0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=shutdown,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 69 does not match filter
entry_rdwr_runlock: ID: 69
====> cache_return_entry_r( 69 ): returned (0)
=> id2entry_r( 70 )
entry_rdwr_rtrylock: ID: 70
====> cache_find_entry_id( 70 ) "uid=halt,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 70 ) 0x811b798 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=halt,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 70 does not match filter
entry_rdwr_runlock: ID: 70
====> cache_return_entry_r( 70 ): returned (0)
=> id2entry_r( 71 )
entry_rdwr_rtrylock: ID: 71
====> cache_find_entry_id( 71 ) "uid=mail,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 71 ) 0x811c170 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=mail,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 71 does not match filter
entry_rdwr_runlock: ID: 71
====> cache_return_entry_r( 71 ): returned (0)
=> id2entry_r( 72 )
entry_rdwr_rtrylock: ID: 72
====> cache_find_entry_id( 72 ) "uid=news,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 72 ) 0x811cb08 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=news,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 72 does not match filter
entry_rdwr_runlock: ID: 72
====> cache_return_entry_r( 72 ): returned (0)
=> id2entry_r( 73 )
entry_rdwr_rtrylock: ID: 73
====> cache_find_entry_id( 73 ) "uid=uucp,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 73 ) 0x811d448 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=uucp,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 73 does not match filter
entry_rdwr_runlock: ID: 73
====> cache_return_entry_r( 73 ): returned (0)
=> id2entry_r( 74 )
entry_rdwr_rtrylock: ID: 74
====> cache_find_entry_id( 74 ) "uid=operator,ou=People,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 74 ) 0x811dd90 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=operator,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 74 does not match filter
entry_rdwr_runlock: ID: 74
====> cache_return_entry_r( 74 ): returned (0)
=> id2entry_r( 75 )
entry_rdwr_rtrylock: ID: 75
====> cache_find_entry_id( 75 ) "uid=games,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 75 ) 0x811e6d8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=games,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 75 does not match filter
entry_rdwr_runlock: ID: 75
====> cache_return_entry_r( 75 ): returned (0)
=> id2entry_r( 76 )
entry_rdwr_rtrylock: ID: 76
====> cache_find_entry_id( 76 ) "uid=gopher,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 76 ) 0x811f018 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=gopher,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 76 does not match filter
entry_rdwr_runlock: ID: 76
====> cache_return_entry_r( 76 ): returned (0)
=> id2entry_r( 77 )
entry_rdwr_rtrylock: ID: 77
====> cache_find_entry_id( 77 ) "uid=nobody,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 77 ) 0x811fe00 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=nobody,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 77 does not match filter
entry_rdwr_runlock: ID: 77
====> cache_return_entry_r( 77 ): returned (0)
=> id2entry_r( 78 )
entry_rdwr_rtrylock: ID: 78
====> cache_find_entry_id( 78 ) "uid=xfs,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 78 ) 0x81202d8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=xfs,ou=People,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 78 does not match filter
entry_rdwr_runlock: ID: 78
====> cache_return_entry_r( 78 ): returned (0)
=> id2entry_r( 79 )
entry_rdwr_rtrylock: ID: 79
====> cache_find_entry_id( 79 ) "uid=admin,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 79 ) 0x8120be8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=admin,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 79 does not match filter
entry_rdwr_runlock: ID: 79
====> cache_return_entry_r( 79 ): returned (0)
=> id2entry_r( 80 )
entry_rdwr_rtrylock: ID: 80
====> cache_find_entry_id( 80 ) "uid=httpd,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 80 ) 0x81217b0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=httpd,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 80 does not match filter
entry_rdwr_runlock: ID: 80
====> cache_return_entry_r( 80 ): returned (0)
=> id2entry_r( 81 )
entry_rdwr_rtrylock: ID: 81
====> cache_find_entry_id( 81 ) "uid=dnscache,ou=People,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 81 ) 0x8121618 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=dnscache,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 81 does not match filter
entry_rdwr_runlock: ID: 81
====> cache_return_entry_r( 81 ): returned (0)
=> id2entry_r( 82 )
entry_rdwr_rtrylock: ID: 82
====> cache_find_entry_id( 82 ) "uid=dnslog,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 82 ) 0x8123088 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=dnslog,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 82 does not match filter
entry_rdwr_runlock: ID: 82
====> cache_return_entry_r( 82 ): returned (0)
=> id2entry_r( 83 )
entry_rdwr_rtrylock: ID: 83
====> cache_find_entry_id( 83 ) "uid=alias,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 83 ) 0x81239d0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=alias,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 83 does not match filter
entry_rdwr_runlock: ID: 83
====> cache_return_entry_r( 83 ): returned (0)
=> id2entry_r( 84 )
entry_rdwr_rtrylock: ID: 84
====> cache_find_entry_id( 84 ) "uid=qmaild,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 84 ) 0x8123cb0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=qmaild,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 84 does not match filter
entry_rdwr_runlock: ID: 84
====> cache_return_entry_r( 84 ): returned (0)
=> id2entry_r( 85 )
entry_rdwr_rtrylock: ID: 85
====> cache_find_entry_id( 85 ) "uid=qmaill,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 85 ) 0x8124c90 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=qmaill,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 85 does not match filter
entry_rdwr_runlock: ID: 85
====> cache_return_entry_r( 85 ): returned (0)
=> id2entry_r( 86 )
entry_rdwr_rtrylock: ID: 86
====> cache_find_entry_id( 86 ) "uid=qmailp,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 86 ) 0x8125600 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=qmailp,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 86 does not match filter
entry_rdwr_runlock: ID: 86
====> cache_return_entry_r( 86 ): returned (0)
=> id2entry_r( 87 )
entry_rdwr_rtrylock: ID: 87
====> cache_find_entry_id( 87 ) "uid=qmailq,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 87 ) 0x8125f70 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=qmailq,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 87 does not match filter
entry_rdwr_runlock: ID: 87
====> cache_return_entry_r( 87 ): returned (0)
=> id2entry_r( 88 )
entry_rdwr_rtrylock: ID: 88
====> cache_find_entry_id( 88 ) "uid=qmailr,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 88 ) 0x81268e0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=qmailr,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 88 does not match filter
entry_rdwr_runlock: ID: 88
====> cache_return_entry_r( 88 ): returned (0)
=> id2entry_r( 89 )
entry_rdwr_rtrylock: ID: 89
====> cache_find_entry_id( 89 ) "uid=qmails,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 89 ) 0x8127250 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=qmails,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 89 does not match filter
entry_rdwr_runlock: ID: 89
====> cache_return_entry_r( 89 ): returned (0)
=> id2entry_r( 90 )
entry_rdwr_rtrylock: ID: 90
====> cache_find_entry_id( 90 ) "uid=db2client,ou=People,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 90 ) 0x8127bc0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=db2client,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 90 does not match filter
entry_rdwr_runlock: ID: 90
====> cache_return_entry_r( 90 ): returned (0)
=> id2entry_r( 91 )
entry_rdwr_rtrylock: ID: 91
====> cache_find_entry_id( 91 ) "uid=maild,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 91 ) 0x8128538 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=maild,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 91 does not match filter
entry_rdwr_runlock: ID: 91
====> cache_return_entry_r( 91 ): returned (0)
=> id2entry_r( 92 )
entry_rdwr_rtrylock: ID: 92
====> cache_find_entry_id( 92 ) "uid=named,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 92 ) 0x81292e0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=named,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 92 does not match filter
entry_rdwr_runlock: ID: 92
====> cache_return_entry_r( 92 ): returned (0)
=> id2entry_r( 93 )
entry_rdwr_rtrylock: ID: 93
====> cache_find_entry_id( 93 ) "uid=nick,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 93 ) 0x8128860 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=nick,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 93 does not match filter
entry_rdwr_runlock: ID: 93
====> cache_return_entry_r( 93 ): returned (0)
=> id2entry_r( 94 )
entry_rdwr_rtrylock: ID: 94
====> cache_find_entry_id( 94 ) "uid=lance,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 94 ) 0x8129c78 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=lance,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 94 does not match filter
entry_rdwr_runlock: ID: 94
====> cache_return_entry_r( 94 ): returned (0)
=> id2entry_r( 95 )
entry_rdwr_rtrylock: ID: 95
====> cache_find_entry_id( 95 ) "uid=phil,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 95 ) 0x812a5f8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=phil,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 95 does not match filter
entry_rdwr_runlock: ID: 95
====> cache_return_entry_r( 95 ): returned (0)
=> id2entry_r( 96 )
entry_rdwr_rtrylock: ID: 96
====> cache_find_entry_id( 96 ) "uid=kabir,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 96 ) 0x812af60 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=kabir,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 96 does not match filter
entry_rdwr_runlock: ID: 96
====> cache_return_entry_r( 96 ): returned (0)
=> id2entry_r( 97 )
entry_rdwr_rtrylock: ID: 97
====> cache_find_entry_id( 97 ) "uid=steve,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 97 ) 0x812b8c8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=steve,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 97 does not match filter
entry_rdwr_runlock: ID: 97
====> cache_return_entry_r( 97 ): returned (0)
=> id2entry_r( 98 )
entry_rdwr_rtrylock: ID: 98
====> cache_find_entry_id( 98 ) "uid=jeff,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 98 ) 0x812c3f0 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=jeff,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 98 does not match filter
entry_rdwr_runlock: ID: 98
====> cache_return_entry_r( 98 ): returned (0)
=> id2entry_r( 99 )
entry_rdwr_rtrylock: ID: 99
====> cache_find_entry_id( 99 ) "uid=bert,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 99 ) 0x812cd20 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=bert,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 99 does not match filter
entry_rdwr_runlock: ID: 99
====> cache_return_entry_r( 99 ): returned (0)
=> id2entry_r( 100 )
entry_rdwr_rtrylock: ID: 100
====> cache_find_entry_id( 100 ) "uid=evan,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 100 ) 0x812d848 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=evan,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 100 does not match filter
entry_rdwr_runlock: ID: 100
====> cache_return_entry_r( 100 ): returned (0)
=> id2entry_r( 101 )
entry_rdwr_rtrylock: ID: 101
====> cache_find_entry_id( 101 ) "uid=terry,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 101 ) 0x812e338 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=terry,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 101 does not match filter
entry_rdwr_runlock: ID: 101
====> cache_return_entry_r( 101 ): returned (0)
=> id2entry_r( 102 )
entry_rdwr_rtrylock: ID: 102
====> cache_find_entry_id( 102 ) "uid=sysadmin,ou=People,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 102 ) 0x812f740 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=sysadmin,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 102 does not match filter
entry_rdwr_runlock: ID: 102
====> cache_return_entry_r( 102 ): returned (0)
=> id2entry_r( 103 )
entry_rdwr_rtrylock: ID: 103
====> cache_find_entry_id( 103 ) "uid=postmaster,ou=People,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 103 ) 0x812fdc8 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=postmaster,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 103 does not match filter
entry_rdwr_runlock: ID: 103
====> cache_return_entry_r( 103 ): returned (0)
=> id2entry_r( 104 )
entry_rdwr_rtrylock: ID: 104
====> cache_find_entry_id( 104 ) "uid=ben,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 104 ) 0x8130748 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=ben,ou=People,dc=deadtrout, dc=com"
"uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 104 does not match filter
entry_rdwr_runlock: ID: 104
====> cache_return_entry_r( 104 ): returned (0)
=> id2entry_r( 105 )
entry_rdwr_rtrylock: ID: 105
====> cache_find_entry_id( 105 ) "uid=cathie,ou=People,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 105 ) 0x8130a28 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=cathie,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 105 does not match filter
entry_rdwr_runlock: ID: 105
====> cache_return_entry_r( 105 ): returned (0)
=> id2entry_r( 106 )
entry_rdwr_rtrylock: ID: 106
====> cache_find_entry_id( 106 ) "uid=otherguy,ou=People,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 106 ) 0x8131d88 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 6
=> send_search_entry: "uid=otherguy,ou=People,dc=deadtrout, dc=com"
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "entry" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "cn" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "cn" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "objectClass" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "objectClass" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "objectClass" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "objectClass" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "objectClass" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "userPassword" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "userPassword" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowLastChange" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowLastChange" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowMax" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowMax" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowWarning" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowWarning" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowInactive" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowInactive" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowExpire" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowExpire" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowFlag" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "shadowFlag" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "loginShell" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "loginShell" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "uidNumber" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "uidNumber" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "gidNumber" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "gidNumber" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "homeDirectory" requested
<= root access granted
=> access_allowed: read access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "homeDirectory" requested
<= root access granted
ber_flush: 463 bytes to sd 9
  0000:  30 82 01 cb 02 01 02 64  82 01 c4 04 28 75 69 64   0......d....(uid
  0010:  3d 6f 74 68 65 72 67 75  79 2c 6f 75 3d 50 65 6f   =otherguy,ou=Peo
  0020:  70 6c 65 2c 64 63 3d 69  6e 74 65 76 6f 2c 20 64
ple,dc=deadtrout, d
  0030:  63 3d 63 6f 6d 30 82 01  96 30 11 04 03 75 69 64   c=com0...0...uid
  0040:  31 0a 04 08 6f 74 68 65  72 67 75 79 30 10 04 02   1...otherguy0...
  0050:  63 6e 31 0a 04 08 6f 74  68 65 72 67 75 79 30 3a   cn1...otherguy0:
  0060:  04 0b 6f 62 6a 65 63 74  43 6c 61 73 73 31 2b 04   ..objectClass1+.
  0070:  07 61 63 63 6f 75 6e 74  04 0c 70 6f 73 69 78 41   .account..posixA
  0080:  63 63 6f 75 6e 74 04 03  74 6f 70 04 0d 73 68 61   ccount..top..sha
  0090:  64 6f 77 41 63 63 6f 75  6e 74 30 3b 04 0c 75 73   dowAccount0;..us
  00a0:  65 72 50 61 73 73 77 6f  72 64 31 2b 04 29 7b 63   erPassword1+.){c
  00b0:  72 79 70 74 7d 24 31 24  66 73 56 71 4e 2f 72 67   rypt}$1$fsVqN/rg
  00c0:  24 78 42 48 38 63 69 59  4c 58 6e 67 4c 64 52 44   $xBH8ciYLXngLdRD
  00d0:  45 45 68 4a 4a 67 30 30  1b 04 10 73 68 61 64 6f   EEhJJg00...shado
  00e0:  77 4c 61 73 74 43 68 61  6e 67 65 31 07 04 05 31   wLastChange1...1
  00f0:  31 32 36 37 30 14 04 09  73 68 61 64 6f 77 4d 61   12670...shadowMa
  0100:  78 31 07 04 05 39 39 39  39 39 30 14 04 0d 73 68   x1...999990...sh
  0110:  61 64 6f 77 57 61 72 6e  69 6e 67 31 03 04 01 37   adowWarning1...7
  0120:  30 16 04 0e 73 68 61 64  6f 77 49 6e 61 63 74 69   0...shadowInacti
  0130:  76 65 31 04 04 02 2d 31  30 14 04 0c 73 68 61 64   ve1...-10...shad
  0140:  6f 77 45 78 70 69 72 65  31 04 04 02 2d 31 30 19   owExpire1...-10.
  0150:  04 0a 73 68 61 64 6f 77  46 6c 61 67 31 0b 04 09   ..shadowFlag1...
  0160:  31 33 34 35 34 30 33 35  36 30 19 04 0a 6c 6f 67   1345403560...log
  0170:  69 6e 53 68 65 6c 6c 31  0b 04 09 2f 62 69 6e 2f   inShell1.../bin/
  0180:  62 61 73 68 30 12 04 09  75 69 64 4e 75 6d 62 65   bash0...uidNumbe
  0190:  72 31 05 04 03 37 32 31  30 12 04 09 67 69 64 4e   r1...7210...gidN
  01a0:  75 6d 62 65 72 31 05 04  03 37 32 32 30 21 04 0d   umber1...7220!..
  01b0:  68 6f 6d 65 44 69 72 65  63 74 6f 72 79 31 10 04   homeDirectory1..
  01c0:  0e 2f 68 6f 6d 65 2f 6f  74 68 65 72 67 75 79      ./home/otherguy
ldap_write: want=463, written=463
  0000:  30 82 01 cb 02 01 02 64  82 01 c4 04 28 75 69 64   0......d....(uid
  0010:  3d 6f 74 68 65 72 67 75  79 2c 6f 75 3d 50 65 6f   =otherguy,ou=Peo
  0020:  70 6c 65 2c 64 63 3d 69  6e 74 65 76 6f 2c 20 64
ple,dc=deadtrout, d
  0030:  63 3d 63 6f 6d 30 82 01  96 30 11 04 03 75 69 64   c=com0...0...uid
  0040:  31 0a 04 08 6f 74 68 65  72 67 75 79 30 10 04 02   1...otherguy0...
  0050:  63 6e 31 0a 04 08 6f 74  68 65 72 67 75 79 30 3a   cn1...otherguy0:
  0060:  04 0b 6f 62 6a 65 63 74  43 6c 61 73 73 31 2b 04   ..objectClass1+.
  0070:  07 61 63 63 6f 75 6e 74  04 0c 70 6f 73 69 78 41   .account..posixA
  0080:  63 63 6f 75 6e 74 04 03  74 6f 70 04 0d 73 68 61   ccount..top..sha
  0090:  64 6f 77 41 63 63 6f 75  6e 74 30 3b 04 0c 75 73   dowAccount0;..us
  00a0:  65 72 50 61 73 73 77 6f  72 64 31 2b 04 29 7b 63   erPassword1+.){c
  00b0:  72 79 70 74 7d 24 31 24  66 73 56 71 4e 2f 72 67   rypt}$1$fsVqN/rg
  00c0:  24 78 42 48 38 63 69 59  4c 58 6e 67 4c 64 52 44   $xBH8ciYLXngLdRD
  00d0:  45 45 68 4a 4a 67 30 30  1b 04 10 73 68 61 64 6f   EEhJJg00...shado
  00e0:  77 4c 61 73 74 43 68 61  6e 67 65 31 07 04 05 31   wLastChange1...1
  00f0:  31 32 36 37 30 14 04 09  73 68 61 64 6f 77 4d 61   12670...shadowMa
  0100:  78 31 07 04 05 39 39 39  39 39 30 14 04 0d 73 68   x1...999990...sh
  0110:  61 64 6f 77 57 61 72 6e  69 6e 67 31 03 04 01 37   adowWarning1...7
  0120:  30 16 04 0e 73 68 61 64  6f 77 49 6e 61 63 74 69   0...shadowInacti
  0130:  76 65 31 04 04 02 2d 31  30 14 04 0c 73 68 61 64   ve1...-10...shad
  0140:  6f 77 45 78 70 69 72 65  31 04 04 02 2d 31 30 19   owExpire1...-10.
  0150:  04 0a 73 68 61 64 6f 77  46 6c 61 67 31 0b 04 09   ..shadowFlag1...
  0160:  31 33 34 35 34 30 33 35  36 30 19 04 0a 6c 6f 67   1345403560...log
  0170:  69 6e 53 68 65 6c 6c 31  0b 04 09 2f 62 69 6e 2f   inShell1.../bin/
  0180:  62 61 73 68 30 12 04 09  75 69 64 4e 75 6d 62 65   bash0...uidNumbe
  0190:  72 31 05 04 03 37 32 31  30 12 04 09 67 69 64 4e   r1...7210...gidN
  01a0:  75 6d 62 65 72 31 05 04  03 37 32 32 30 21 04 0d   umber1...7220!..
  01b0:  68 6f 6d 65 44 69 72 65  63 74 6f 72 79 31 10 04   homeDirectory1..
  01c0:  0e 2f 68 6f 6d 65 2f 6f  74 68 65 72 67 75 79      ./home/otherguy
<= send_search_entry
entry_rdwr_runlock: ID: 106
====> cache_return_entry_r( 106 ): returned (0)
=> id2entry_r( 107 )
entry_rdwr_rtrylock: ID: 107
====> cache_find_entry_id( 107 ) "cn=otherguy,ou=Group,dc=deadtrout, dc=com"
(found) (1 tries)
<= id2entry_r( 107 ) 0x8132198 (cache)
=> test_filter
    EQUALITY
=> access_allowed: search access to "cn=otherguy,ou=Group,dc=deadtrout,
dc=com" "uid" requested
<= root access granted
<= test_filter 5
ldbm_search: candidate 107 does not match filter
entry_rdwr_runlock: ID: 107
====> cache_return_entry_r( 107 ): returned (0)
send_ldap_search_result 0::
send_ldap_response: msgid=2 tag=101 err=0
ber_flush: 14 bytes to sd 9
  0000:  30 0c 02 01 02 65 07 0a  01 00 04 00 04 00         0....e........
ldap_write: want=14, written=14
  0000:  30 0c 02 01 02 65 07 0a  01 00 04 00 04 00         0....e........
daemon: activity on 1 descriptors
daemon: activity on: 9r
daemon: read activity on 9
connection_get(9)
connection_get(9): got connid=9
connection_read(9): checking for input on id=9
ber_get_next
ldap_read: want=1, got=1
  0000:  30                                                 0
ldap_read: want=1, got=1
  0000:  05                                                 .
ldap_read: want=5, got=5
  0000:  02 01 03 42 00                                     ...B.
ber_get_next: tag 0x30 len 5 contents:
ber_dump: buf=0x40204540 ptr=0x40204540 end=0x40204545 len=5
  0000:  02 01 03 42 00                                     ...B.
ber_get_next
ldap_read: want=1, got=0
do_unbind

ber_get_next on fd 9 failed errno=4 (Interrupted system call)
connection_read(9): input error=-2 id=9, closing.
connection_closing: readying conn=9 sd=9 for close
connection_close: deferring conn=9 sd=9
connection_resched: attempting closing conn=9 sd=9
daemon: select: listen=6 active_threads=1 tvp=NULL
connection_close: conn=9 sd=9
daemon: activity on 2 descriptors
daemon: removing 9
daemon: select: listen=6 active_threads=1 tvp=NULL
daemon: activity on 1 descriptors
daemon: new connection on 9
daemon: added 9r
daemon: activity on:
daemon: select: listen=6 active_threads=0 tvp=NULL
daemon: activity on 1 descriptors
daemon: activity on: 9r
daemon: read activity on 9
connection_get(9)
connection_get(9): got connid=10
connection_read(9): checking for input on id=10
ber_get_next
ldap_read: want=1, got=1
  0000:  30                                                 0
ldap_read: want=1, got=1
  0000:  3a                                                 :
ldap_read: want=58, got=58
  0000:  02 01 01 60 35 02 01 02  04 28 75 69 64 3d 6f 74   ...`5....(uid=ot
  0010:  68 65 72 67 75 79 2c 6f  75 3d 50 65 6f 70 6c 65   herguy,ou=People
  0020:  2c 64 63 3d 69 6e 74 65  76 6f 2c 20 64 63 3d 63   ,dc=deadtrout,
dc=c
  0030:  6f 6d 80 06 71 71 77 65  72 74                     om..qqwert
ber_get_next: tag 0x30 len 58 contents:
ber_dump: buf=0x40204570 ptr=0x40204570 end=0x402045aa len=58
  0000:  02 01 01 60 35 02 01 02  04 28 75 69 64 3d 6f 74   ...`5....(uid=ot
  0010:  68 65 72 67 75 79 2c 6f  75 3d 50 65 6f 70 6c 65   herguy,ou=People
  0020:  2c 64 63 3d 69 6e 74 65  76 6f 2c 20 64 63 3d 63   ,dc=deadtrout,
dc=c
  0030:  6f 6d 80 06 71 71 77 65  72 74                     om..qqwert
ber_get_next
ldap_read: want=1 error=Resource temporarily unavailable
ber_get_next on fd 9 failed errno=11 (Resource temporarily unavailable)
do_bind
ber_scanf fmt ({iat) ber:
ber_dump: buf=0x40204570 ptr=0x40204573 end=0x402045aa len=55
  0000:  60 35 02 01 02 04 28 75  69 64 3d 6f 74 68 65 72   `5....(uid=other
  0010:  67 75 79 2c 6f 75 3d 50  65 6f 70 6c 65 2c 64 63   guy,ou=People,dc
  0020:  3d 69 6e 74 65 76 6f 2c  20 64 63 3d 63 6f 6d 80   =deadtrout,
dc=com.
  0030:  06 71 71 77 65 72 74                               .qqwert
ber_scanf fmt (o}) ber:
ber_dump: buf=0x40204570 ptr=0x402045a2 end=0x402045aa len=8
  0000:  80 06 71 71 77 65 72 74                            ..qqwert
do_bind: version=2 dn="uid=otherguy,ou=People,dc=deadtrout, dc=com"
method=128
==> ldbm_back_bind: dn: uid=otherguy,ou=People,dc=deadtrout, dc=com
dn2entry_r: dn: "UID=OTHERGUY,OU=PEOPLE,DC=DEADTROUT,DC=COM"
=> dn2id( "UID=OTHERGUY,OU=PEOPLE,DC=DEADTROUT,DC=COM" )
====> cache_find_entry_dn2id("UID=OTHERGUY,OU=PEOPLE,DC=DEADTROUT,DC=COM"):
106 (1 tries)
<= dn2id 106 (in cache)
=> id2entry_r( 106 )
entry_rdwr_rtrylock: ID: 106
====> cache_find_entry_id( 106 ) "uid=otherguy,ou=People,dc=deadtrout,
dc=com" (found) (1 tries)
<= id2entry_r( 106 ) 0x8131d88 (cache)
=> access_allowed: auth access to "uid=otherguy,ou=People,dc=deadtrout,
dc=com" "userPassword" requested
=> access_allowed: backend default auth access granted to ""
daemon: select: listen=6 active_threads=1 tvp=NULL
entry_rdwr_runlock: ID: 106
====> cache_return_entry_r( 106 ): returned (0)
do_bind: v2 bind: "uid=otherguy,ou=People,dc=deadtrout, dc=com" to
"uid=otherguy,ou=People,dc=deadtrout, dc=com"
send_ldap_result: conn=10 op=0 p=2
send_ldap_result: 0::
send_ldap_response: msgid=1 tag=97 err=0
ber_flush: 14 bytes to sd 9
  0000:  30 0c 02 01 01 61 07 0a  01 00 04 00 04 00         0....a........
ldap_write: want=14, written=14
  0000:  30 0c 02 01 01 61 07 0a  01 00 04 00 04 00         0....a........
daemon: activity on 1 descriptors
daemon: activity on: 9r
daemon: read activity on 9
connection_get(9)
connection_get(9): got connid=10
connection_read(9): checking for input on id=10
ber_get_next
ldap_read: want=1, got=1
  0000:  30                                                 0
ldap_read: want=1, got=1
  0000:  05                                                 .
ldap_read: want=5, got=5
  0000:  02 01 02 42 00                                     ...B.
ber_get_next: tag 0x30 len 5 contents:
ber_dump: buf=0x40204540 ptr=0x40204540 end=0x40204545 len=5
  0000:  02 01 02 42 00                                     ...B.
ber_get_next
ldap_read: want=1, got=0

ber_get_next on fd 9 failed errno=0 (Success)
connection_read(9): input error=-2 id=10, closing.
connection_closing: readying conn=10 sd=9 for close
connection_close: deferring conn=10 sd=9
daemon: select: listen=6 active_threads=1 tvp=NULL
daemon: activity on 2 descriptors
daemon: select: listen=6 active_threads=1 tvp=NULL
daemon: activity on 1 descriptors
daemon: new connection on 14
do_unbind
daemon: added 14r
daemon: activity on:
connection_resched: attempting closing conn=10 sd=9
connection_close: conn=10 sd=9
daemon: select: listen=6 active_threads=1 tvp=NULL
daemon: removing 9
daemon: activity on 1 descriptors
daemon: activity on: 14r
daemon: read activity on 14
connection_get(14)
connection_get(14): got connid=11
connection_read(14): checking for input on id=11
ber_get_next
ldap_read: want=1, got=1
  0000:  30                                                 0
ldap_read: want=1, got=1
  0000:  2d                                                 -
ldap_read: want=45, got=45
  0000:  02 01 01 60 28 02 01 02  04 1b 63 6e 3d 6d 61 6e   ...`(.....cn=man
  0010:  61 67 65 72 2c 64 63 3d  69 6e 74 65 76 6f 2c 64
ager,dc=deadtrout,d
  0020:  63 3d 63 6f 6d 80 06 66  6f 6f 66 6f 6f            c=com..secret
ber_get_next: tag 0x30 len 45 contents:
ber_dump: buf=0x40200440 ptr=0x40200440 end=0x4020046d len=45
  0000:  02 01 01 60 28 02 01 02  04 1b 63 6e 3d 6d 61 6e   ...`(.....cn=man
  0010:  61 67 65 72 2c 64 63 3d  69 6e 74 65 76 6f 2c 64
ager,dc=deadtrout,d
  0020:  63 3d 63 6f 6d 80 06 66  6f 6f 66 6f 6f            c=com..secret
ber_get_next
ldap_read: want=1 error=Resource temporarily unavailable
ber_get_next on fd 14 failed errno=11 (Resource temporarily unavailable)
do_bind
ber_scanf fmt ({iat) ber:
ber_dump: buf=0x40200440 ptr=0x40200443 end=0x4020046d len=42
  0000:  60 28 02 01 02 04 1b 63  6e 3d 6d 61 6e 61 67 65   `(.....cn=manage
  0010:  72 2c 64 63 3d 69 6e 74  65 76 6f 2c 64 63 3d 63
r,dc=deadtrout,dc=c
  0020:  6f 6d 80 06 66 6f 6f 66  6f 6f                     om..secret
ber_scanf fmt (o}) ber:
ber_dump: buf=0x40200440 ptr=0x40200465 end=0x4020046d len=8
  0000:  80 06 66 6f 6f 66 6f 6f                            ..secret
do_bind: version=2 dn="cn=manager,dc=deadtrout,dc=com" method=128
==> ldbm_back_bind: dn: cn=manager,dc=deadtrout,dc=com
dn2entry_r: dn: "CN=MANAGER,DC=DEADTROUT,DC=COM"
=> dn2id( "CN=MANAGER,DC=DEADTROUT,DC=COM" )
=> ldbm_cache_open( "/usr/local/var/openldap-ldbm/dn2id.dbb", 7, 600 )
<= ldbm_cache_open (cache 0)
<= dn2id NOID
dn2entry_r: dn: "DC=DEADTROUT,DC=COM"
=> dn2id( "DC=deadtrout,DC=COM" )
====> cache_find_entry_dn2id("DC=DEADTROUT,DC=COM"): 1 (1 tries)
<= dn2id 1 (in cache)
=> id2entry_r( 1 )
entry_rdwr_rtrylock: ID: 1
====> cache_find_entry_id( 1 ) "dc=deadtrout, dc=com" (found) (1 tries)
<= id2entry_r( 1 ) 0x80e8c78 (cache)
entry_rdwr_runlock: ID: 1
====> cache_return_entry_r( 1 ): returned (0)
do_bind: v2 bind: "cn=manager,dc=deadtrout,dc=com" to "cn=Manager,
dc=deadtrout, dc=com"
send_ldap_result: conn=11 op=0 p=2
send_ldap_result: 0::
send_ldap_response: msgid=1 tag=97 err=0
ber_flush: 14 bytes to sd 14
  0000:  30 0c 02 01 01 61 07 0a  01 00 04 00 04 00         0....a........
ldap_write: want=14, written=14
  0000:  30 0c 02 01 01 61 07 0a  01 00 04 00 04 00         0....a........
daemon: select: listen=6 active_threads=1 tvp=NULL
daemon: activity on 1 descriptors
daemon: activity on: 14r
daemon: read activity on 14
connection_get(14)
connection_get(14): got connid=11
connection_read(14): checking for input on id=11
ber_get_next
ldap_read: want=1, got=1
  0000:  30                                                 0
ldap_read: want=1, got=1
  0000:  05                                                 .
ldap_read: want=5, got=5
  0000:  02 01 02 42 00                                     ...B.
ber_get_next: tag 0x30 len 5 contents:
ber_dump: buf=0x40200558 ptr=0x40200558 end=0x4020055d len=5
  0000:  02 01 02 42 00                                     ...B.
ber_get_next
ldap_read: want=1 error=Resource temporarily unavailable
ber_get_next on fd 14 failed errno=11 (Resource temporarily unavailable)
do_unbind
daemon: select: listen=6 active_threads=1 tvp=NULL
connection_closing: readying conn=11 sd=14 for close
connection_resched: attempting closing conn=11 sd=14
connection_close: conn=11 sd=14
daemon: removing 14
daemon: activity on 1 descriptors
daemon: select: listen=6 active_threads=1 tvp=NULL