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

OpenLDAP and PROFTPD



I am trying to link Proftpd with Openldap. I have created a user
on openldap "posixtest2".  I cannot login to proftpd with this user
although proftpd is making a connection and searching for user info
on openldap. I do not know whether the error in my openldap output
is openldap related or proftpd related.  

I would very much appreciate someone taking a look at the following
ouput from openldap to determine why login to proftpd is failing.

Thankyou for any help,
Kristina

LOGIN USER****************************
cn=posixtest2,o=fsas,c=JP
objectclass=top
objectclass=person
objectclass=OrganizationalPerson
objectclass=InetOrgPerson
objectclass=posixAccount
uid=posixtest2
userpassword=pass123
cn=posixtest2
sn=posixtest2
givenname=posixtest2
uidnumber=140
gidnumber=150
homedirectory=/home/posixtest2

***OUTPUT FROM OPENLDAP (Please look at the error at the very bottom********

select activity on 1 descriptors
new connection on 5
activity on:
listening for connections on 3, activity on: 5r
before select active_threads 0
select activity on 1 descriptors
activity on: 5r
read activity on 5
ber_get_next
ber_get_next: tag 0x30 len 37 contents:
ber_dump: buf 0xa4fd8, ptr 0xa4fd8, end 0xa4ffd
          current len 37, contents:
        02 01 01  ` 20 02 01 02 04 15  c  n  =  r  o  o
         t  , 20  o  =  f  s  a  s  , 20  c  =  J  P 80
        04  f  s  a  s
do_bind
do_bind: version 2 dn (cn=root, o=fsas, c=JP) method 128
==> ldbm_back_bind: dn: CN=ROOT,O=FSAS,C=JP
dn2entry_r: dn: "CN=ROOT,O=FSAS,C=JP"
=> dn2id( "CN=ROOT,O=FSAS,C=JP" )
=> ldbm_cache_open( "/usr/local/LDBM//dn2id.dbb", 7, 600 )
ldbm_cache_open (blksize 8192) (maxids 2046) (maxindirect 2)
<= ldbm_cache_open (opened 0)
<= dn2id 2
=> id2entry_r( 2 )
=> ldbm_cache_open( "/usr/local/LDBM//id2entry.dbb", 7, 600 )
ldbm_cache_open (blksize 8192) (maxids 2046) (maxindirect 2)
<= ldbm_cache_open (opened 1)
=> str2entry
<= str2entry 0xa1fd0
entry_rdwr_rlock: ID: 2
<= id2entry_r( 2 ) (disk)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 2
do_bind: bound "cn=root, o=fsas, c=JP" to "cn=root, o=fsas, c=JP"
send_ldap_result 0::
ber_flush: 14 bytes to sd 5
         0 0c 02 01 01  a 07 0a 01 00 04 00 04 00
listening for connections on 3, activity on: 5r
before select active_threads 0
select activity on 1 descriptors
activity on: 5r
read activity on 5
ber_get_next
ber_get_next: tag 0x30 len 141 contents:
ber_dump: buf 0xa1eb0, ptr 0xa1eb0, end 0xa1f3d
          current len 141, contents:
        02 01 02  c 81 87 04 0c  o  =  f  s  a  s  , 20
         c  =  J  P 0a 01 02 0a 01 00 02 01 00 02 01 00
        01 01 00 a0  0 a3 11 04 03  u  i  d 04 0a  p  o
         s  i  x  t  e  s  t  2 a3 1b 04 0b  o  b  j  e
         c  t  c  l  a  s  s 04 0c  p  o  s  i  x  A  c
         c  o  u  n  t  0  6 04 03  u  i  d 04 09  u  i
         d  N  u  m  b  e  r 04 09  g  i  d  N  u  m  b
         e  r 04 0d  h  o  m  e  D  i  r  e  c  t  o  r
         y 04 0a  l  o  g  i  n  S  h  e  l  l
do_search
SRCH "O=FSAS,C=JP" 2 0    0 0 0
begin get_filter
AND
begin get_filter_list
begin get_filter
EQUALITY
end get_filter 0
begin get_filter
EQUALITY
end get_filter 0
end get_filter_list
end get_filter 0
    filter: (&(uid=POSIXTEST2)(objectclass=POSIXACCOUNT))
    attrs: uid uidnumber gidnumber homedirectory loginshell
=> ldbm_back_search
using base "O=FSAS,C=JP"
subtree_candidates: base: "O=FSAS,C=JP" lookupbase
dn2entry_r: dn: "O=FSAS,C=JP"
=> dn2id( "O=FSAS,C=JP" )
=> ldbm_cache_open( "/usr/local/LDBM//dn2id.dbb", 7, 600 )
<= ldbm_cache_open (cache 0)
<= dn2id 1
=> id2entry_r( 1 )
=> ldbm_cache_open( "/usr/local/LDBM//id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0xa26b0
entry_rdwr_rlock: ID: 1
<= id2entry_r( 1 ) (disk)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 1
=> filter_candidates
        OR
=> list_candidates 0xa1
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "/usr/local/LDBM//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
        AND
=> list_candidates 0xa0
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "uid" "=" "POSIXTEST2" )
<= index_read 6 candidates (allids - not indexed)
<= ava_candidates 6
<= filter_candidates 6
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "POSIXACCOUNT" )
=> ldbm_cache_open( "/usr/local/LDBM//objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 4 candidates
<= ava_candidates 4
<= filter_candidates 4
<= list_candidates 4
<= filter_candidates 4
<= list_candidates 4
<= filter_candidates 4
=> id2entry_r( 2 )
====> cache_find_entry_dn2id: found id: 2 rw: 0
entry_rdwr_rtrylock: ID: 2
<= id2entry_r 0xa1fd0 (cache)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=root,o=fsas,c=JP) attr (uid)

=> acl_get: entry (cn=root,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=root,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=root,o=fsas,c=JP) attr (uid)
<= test_filter 1
<= test_filter_list 1
<= test_filter 1
====> cache_return_entry_r
entry_rdwr_runlock: ID: 2
=> id2entry_r( 3 )
=> ldbm_cache_open( "/usr/local/LDBM//id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x111bc8
entry_rdwr_rlock: ID: 3
<= id2entry_r( 3 ) (disk)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (uid=posixtest1,o=fsas,c=JP) attr (uid)

=> acl_get: entry (uid=posixtest1,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "uid=posixtest1,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (uid=posixtest1,o=fsas,c=JP) attr (uid)
<= test_filter 1
<= test_filter_list 1
<= test_filter 1
====> cache_return_entry_r
entry_rdwr_runlock: ID: 3
=> id2entry_r( 4 )
=> ldbm_cache_open( "/usr/local/LDBM//id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x112050
entry_rdwr_rlock: ID: 4
<= id2entry_r( 4 ) (disk)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= test_filter 0
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (objectclass)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (objectclass)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXACCOUNT" by 
"CN=ROOT,O=FSAS,
C=JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (objectclass)
<= test_filter 0
<= test_filter_list 0
<= test_filter 0
=> send_search_entry (cn=posixtest2,o=fsas,c=JP)

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (entry)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (entry)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (entry)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uidnumber)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (gidnumber)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (homedirectory)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root
ber_flush: 135 bytes to sd 5
         0 81 84 02 01 02  d 7f 04 19  c  n  =  p  o  s
         i  x  t  e  s  t  2  ,  o  =  f  s  a  s  ,  c
         =  J  P  0  b  0 13 04 03  u  i  d  1 0c 04 0a
         p  o  s  i  x  t  e  s  t  2  0 12 04 09  u  i
         d  n  u  m  b  e  r  1 05 04 03  1  4  0  0 12
        04 09  g  i  d  n  u  m  b  e  r  1 05 04 03  1
         5  0  0  # 04 0d  h  o  m  e  d  i  r  e  c  t
         o  r  y  1 12 04 10  /  h  o  m  e  /  p  o  s
         i  x  t  e  s  t  2
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 4
=> id2entry_r( 5 )
=> ldbm_cache_open( "/usr/local/LDBM//id2entry.dbb", 7, 600 )
<= ldbm_cache_open (cache 1)
=> str2entry
<= str2entry 0x111a88
entry_rdwr_rlock: ID: 5
<= id2entry_r( 5 ) (disk)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (uid=posixtest3,o=fsas,c=JP) attr (uid)

=> acl_get: entry (uid=posixtest3,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "uid=posixtest3,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (uid=posixtest3,o=fsas,c=JP) attr (uid)
<= test_filter 1
<= test_filter_list 1
<= test_filter 1
====> cache_return_entry_r
entry_rdwr_runlock: ID: 5
listening for connections on 3, activity on: 5r
before select active_threads 1
send_ldap_result 0::
ber_flush: 14 bytes to sd 5
         0 0c 02 01 02  e 07 0a 01 00 04 00 04 00
select activity on 1 descriptors
activity on: 5r
read activity on 5
ber_get_next
ber_get_next: tag 0x30 len 141 contents:
ber_dump: buf 0x111b08, ptr 0x111b08, end 0x111b95
          current len 141, contents:
        02 01 03  c 81 87 04 0c  o  =  f  s  a  s  , 20
         c  =  J  P 0a 01 02 0a 01 00 02 01 00 02 01 00
        01 01 00 a0  0 a3 11 04 03  u  i  d 04 0a  p  o
         s  i  x  t  e  s  t  2 a3 1b 04 0b  o  b  j  e
         c  t  c  l  a  s  s 04 0c  p  o  s  i  x  A  c
         c  o  u  n  t  0  6 04 03  u  i  d 04 09  u  i
         d  N  u  m  b  e  r 04 09  g  i  d  N  u  m  b
         e  r 04 0d  h  o  m  e  D  i  r  e  c  t  o  r
         y 04 0a  l  o  g  i  n  S  h  e  l  l
do_search
SRCH "O=FSAS,C=JP" 2 0    0 0 0
begin get_filter
AND
begin get_filter_list
begin get_filter
EQUALITY
end get_filter 0
begin get_filter
EQUALITY
end get_filter 0
end get_filter_list
end get_filter 0
    filter: (&(uid=POSIXTEST2)(objectclass=POSIXACCOUNT))
    attrs: uid uidnumber gidnumber homedirectory loginshell
=> ldbm_back_search
using base "O=FSAS,C=JP"
subtree_candidates: base: "O=FSAS,C=JP" lookupbase
dn2entry_r: dn: "O=FSAS,C=JP"
=> dn2id( "O=FSAS,C=JP" )
====> cache_find_entry_dn2id: found dn: O=FSAS,C=JP
<= dn2id 1 (in cache)
=> id2entry_r( 1 )
====> cache_find_entry_dn2id: found id: 1 rw: 0
entry_rdwr_rtrylock: ID: 1
<= id2entry_r 0xa26b0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 1
=> filter_candidates
        OR
=> list_candidates 0xa1
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "/usr/local/LDBM//objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 0 candidates
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
        AND
=> list_candidates 0xa0
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "uid" "=" "POSIXTEST2" )
<= index_read 6 candidates (allids - not indexed)
<= ava_candidates 6
<= filter_candidates 6
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "POSIXACCOUNT" )
=> ldbm_cache_open( "/usr/local/LDBM//objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 4 candidates
<= ava_candidates 4
<= filter_candidates 4
<= list_candidates 4
<= filter_candidates 4
<= list_candidates 4
<= filter_candidates 4
=> id2entry_r( 2 )
====> cache_find_entry_dn2id: found id: 2 rw: 0
entry_rdwr_rtrylock: ID: 2
<= id2entry_r 0xa1fd0 (cache)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=root,o=fsas,c=JP) attr (uid)

=> acl_get: entry (cn=root,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=root,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=root,o=fsas,c=JP) attr (uid)
<= test_filter 1
<= test_filter_list 1
<= test_filter 1
====> cache_return_entry_r
entry_rdwr_runlock: ID: 2
listening for connections on 3, activity on: 5r
before select active_threads 1
=> id2entry_r( 3 )
====> cache_find_entry_dn2id: found id: 3 rw: 0
entry_rdwr_rtrylock: ID: 3
<= id2entry_r 0x111bc8 (cache)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (uid=posixtest1,o=fsas,c=JP) attr (uid)

=> acl_get: entry (uid=posixtest1,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "uid=posixtest1,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (uid=posixtest1,o=fsas,c=JP) attr (uid)
<= test_filter 1
<= test_filter_list 1
<= test_filter 1
====> cache_return_entry_r
entry_rdwr_runlock: ID: 3
=> id2entry_r( 4 )
====> cache_find_entry_dn2id: found id: 4 rw: 0
entry_rdwr_rtrylock: ID: 4
<= id2entry_r 0x112050 (cache)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= test_filter 0
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (objectclass)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (objectclass)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXACCOUNT" by 
"CN=ROOT,O=FSAS,
C=JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (objectclass)
<= test_filter 0
<= test_filter_list 0
<= test_filter 0
=> send_search_entry (cn=posixtest2,o=fsas,c=JP)

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (entry)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (entry)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (entry)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uidnumber)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (gidnumber)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (homedirectory)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root
ber_flush: 135 bytes to sd 5
         0 81 84 02 01 03  d 7f 04 19  c  n  =  p  o  s
         i  x  t  e  s  t  2  ,  o  =  f  s  a  s  ,  c
         =  J  P  0  b  0 13 04 03  u  i  d  1 0c 04 0a
         p  o  s  i  x  t  e  s  t  2  0 12 04 09  u  i
         d  n  u  m  b  e  r  1 05 04 03  1  4  0  0 12
        04 09  g  i  d  n  u  m  b  e  r  1 05 04 03  1
         5  0  0  # 04 0d  h  o  m  e  d  i  r  e  c  t
         o  r  y  1 12 04 10  /  h  o  m  e  /  p  o  s
         i  x  t  e  s  t  2
<= send_search_entry
====> cache_return_entry_r
entry_rdwr_runlock: ID: 4
=> id2entry_r( 5 )
====> cache_find_entry_dn2id: found id: 5 rw: 0
entry_rdwr_rtrylock: ID: 5
<= id2entry_r 0x111a88 (cache)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (uid=posixtest3,o=fsas,c=JP) attr (uid)

=> acl_get: entry (uid=posixtest3,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "uid=posixtest3,o=fsas,c=JP"
select activity on 1 descriptors
activity on: 5r
read activity on 5
ber_get_next
ber_get_next: tag 0x30 len 141 contents:
ber_dump: buf 0x1126d8, ptr 0x1126d8, end 0x112765
          current len 141, contents:
        02 01 04  c 81 87 04 0c  o  =  f  s  a  s  , 20
         c  =  J  P 0a 01 02 0a 01 00 02 01 00 02 01 00
        01 01 00 a0  0 a3 11 04 03  u  i  d 04 0a  p  o
         s  i  x  t  e  s  t  2 a3 1b 04 0b  o  b  j  e
         c  t  c  l  a  s  s 04 0c  p  o  s  i  x  A  c
         c  o  u  n  t  0  6 04 03  u  i  d 04 09  u  i
         d  N  u  m  b  e  r 04 09  g  i  d  N  u  m  b
         e  r 04 0d  h  o  m  e  D  i  r  e  c  t  o  r
         y 04 0a  l  o  g  i  n  S  h  e  l  l
do_search
SRCH "O=FSAS,C=JP" 2 0    0 0 0
begin get_filter
AND
begin get_filter_list
begin get_filter
EQUALITY
end get_filter 0
begin get_filter
EQUALITY
end get_filter 0
end get_filter_list
end get_filter 0
    filter: (&(uid=POSIXTEST2)(objectclass=POSIXACCOUNT))
    attrs: uid uidnumber gidnumber homedirectory loginshell
=> ldbm_back_search
using base "O=FSAS,C=JP"
subtree_candidates: base: "O=FSAS,C=JP" lookupbase
dn2entry_r: dn: "O=FSAS,C=JP"
=> dn2id( "O=FSAS,C=JP" )
====> cache_find_entry_dn2id: found dn: O=FSAS,C=JP
<= dn2id 1 (in cache)
=> id2entry_r( 1 )
====> cache_find_entry_dn2id: found id: 1 rw: 0
entry_rdwr_rtrylock: ID: 1
<= id2entry_r 0xa26b0 (cache)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 1
=> filter_candidates
        OR
=> list_candidates 0xa1
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "REFERRAL" )
=> ldbm_cache_open( "/usr/local/LDBM//objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 0 candidates
<= ava_candidates 0
<= filter_candidates 0
=> filter_candidates
        AND
=> list_candidates 0xa0
listening for connections on 3, activity on: 5r
before select active_threads 2

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (uid=posixtest3,o=fsas,c=JP) attr (uid)
<= test_filter 1
<= test_filter_list 1
<= test_filter 1
====> cache_return_entry_r
entry_rdwr_runlock: ID: 5
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "uid" "=" "POSIXTEST2" )
<= index_read 6 candidates (allids - not indexed)
<= ava_candidates 6
<= filter_candidates 6
=> filter_candidates
        EQUALITY
=> ava_candidates 0xa3
=> index_read( "objectclass" "=" "POSIXACCOUNT" )
=> ldbm_cache_open( "/usr/local/LDBM//objectclass.dbb", 7, 600 )
<= ldbm_cache_open (cache 2)
<= index_read 4 candidates
<= ava_candidates 4
<= filter_candidates 4
<= list_candidates 4
<= filter_candidates 4
<= list_candidates 4
<= filter_candidates 4
=> id2entry_r( 2 )
====> cache_find_entry_dn2id: found id: 2 rw: 0
entry_rdwr_rtrylock: ID: 2
<= id2entry_r 0xa1fd0 (cache)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=root,o=fsas,c=JP) attr (uid)

=> acl_get: entry (cn=root,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=root,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=root,o=fsas,c=JP) attr (uid)
<= test_filter 1
<= test_filter_list 1
<= test_filter 1
====> cache_return_entry_r
entry_rdwr_runlock: ID: 2
send_ldap_result 0::
ber_flush: 14 bytes to sd 5
         0 0c 02 01 03  e 07 0a 01 00 04 00 04 00
=> id2entry_r( 3 )
====> cache_find_entry_dn2id: found id: 3 rw: 0
entry_rdwr_rtrylock: ID: 3
<= id2entry_r 0x111bc8 (cache)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (uid=posixtest1,o=fsas,c=JP) attr (uid)

=> acl_get: entry (uid=posixtest1,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "uid=posixtest1,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (uid=posixtest1,o=fsas,c=JP) attr (uid)
<= test_filter 1
<= test_filter_list 1
<= test_filter 1
====> cache_return_entry_r
entry_rdwr_runlock: ID: 3
=> id2entry_r( 4 )
====> cache_find_entry_dn2id: found id: 4 rw: 0
select activity on 1 descriptors
activity on: 5r
read activity on 5
ber_get_next
ber_get_next on fd 5 failed errno 0 (Error 0)
*** got 0 of 0 so far
listening for connections on 3, activity on:
before select active_threads 1
entry_rdwr_rtrylock: ID: 4
<= id2entry_r 0x112050 (cache)
=> test_filter
    AND
=> test_filter_list
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXTEST2" by 
"CN=ROOT,O=FSAS,C=
JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= test_filter 0
=> test_filter
    EQUALITY

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (objectclass)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (objectclass)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: search access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: search access to value "POSIXACCOUNT" by 
"CN=ROOT,O=FSAS,
C=JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (objectclass)
<= test_filter 0
<= test_filter_list 0
<= test_filter 0
=> send_search_entry (cn=posixtest2,o=fsas,c=JP)

=> access_allowed: entry (cn=posixtest2,o=fsas,c=JP) attr (entry)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (entry)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> access_allowed: exit (cn=posixtest2,o=fsas,c=JP) attr (entry)

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uid)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (uidnumber)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (gidnumber)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root

=> acl_get: entry (cn=posixtest2,o=fsas,c=JP) attr (homedirectory)
<= acl_get: no acl applicable to database root

=> acl_access_allowed: read access to entry "cn=posixtest2,o=fsas,c=JP"

=> acl_access_allowed: read access to value "any" by "CN=ROOT,O=FSAS,C=JP"
<= acl_access_allowed: granted to database root
ber_flush: 135 bytes to sd -1
         0 81 84 02 01 04  d 7f 04 19  c  n  =  p  o  s
         i  x  t  e  s  t  2  ,  o  =  f  s  a  s  ,  c
         =  J  P  0  b  0 13 04 03  u  i  d  1 0c 04 0a
         p  o  s  i  x  t  e  s  t  2  0 12 04 09  u  i
         d  n  u  m  b  e  r  1 05 04 03  1  4  0  0 12
        04 09  g  i  d  n  u  m  b  e  r  1 05 04 03  1
         5  0  0  # 04 0d  h  o  m  e  d  i  r  e  c  t
         o  r  y  1 12 04 10  /  h  o  m  e  /  p  o  s
         i  x  t  e  s  t  2
ber_flush failed errno 9 msg (Bad file number)
====> cache_return_entry_r
entry_rdwr_runlock: ID: 4



****************************