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

problem with updatedn directive



Hi

I have a strange problem with OpenLDAP 2.0.17:

when I set the updatedn in the slapd.conf, then I get the following when I do a ldapadd:

$ ldapadd -h localhost -x -D 'cn=Manager,dc=testdomain,dc=com' -w boguspw -v -f user1.ldif
ldap_init( localhost, 0 )
add cn:
user
add sn:
a
add userPassword:
user
add objectClass:
person
adding new entry "cn=user,dc=testdomain,dc=com"
ldap_add: No such object


ldif_record() = 32
$

when I take the updatedn line out of the config file, then I get the following:

$ ldapadd -h localhost -x -D 'cn=Manager,dc=testdomain,dc=com' -w boguspw -v -f user1.ldif
ldap_init( localhost, 0 )
add cn:
user
add sn:
a
add userPassword:
user
add objectClass:
person
adding new entry "cn=user,dc=testdomain,dc=com"
modify complete
$


for the first case (*with* updatedn line), the log (level 255) shows the following:

Nov 1 15:44:28 joe slapd[23269]: slapd startup: initiated.
Nov 1 15:44:28 joe slapd[23269]: slapd starting
Nov 1 15:44:28 joe slapd[23274]: daemon: added 6r
Nov 1 15:44:28 joe slapd[23274]: daemon: select: listen=6 active_threads=0 tvp=NULL
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on 1 descriptors
Nov 1 15:44:45 joe slapd[23274]: daemon: new connection on 9
Nov 1 15:44:45 joe slapd[23274]: daemon: added 9r
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on:
Nov 1 15:44:45 joe slapd[23274]: Nov 1 15:44:45 joe slapd[23274]: daemon: select: listen=6 active_threads=0 tvp=NULL
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on 1 descriptors
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on:
Nov 1 15:44:45 joe slapd[23274]: 9r
Nov 1 15:44:45 joe slapd[23274]: Nov 1 15:44:45 joe slapd[23274]: daemon: read activity on 9
Nov 1 15:44:45 joe slapd[23274]: connection_get(9)
Nov 1 15:44:45 joe slapd[23274]: connection_get(9): got connid=0
Nov 1 15:44:45 joe slapd[23274]: connection_read(9): checking for input on id=0
Nov 1 15:44:45 joe slapd[23277]: do_bind
Nov 1 15:44:45 joe slapd[23274]: ber_get_next on fd 9 failed errno=11 (Resource temporarily unavailable)
Nov 1 15:44:45 joe slapd[23277]: do_bind: version=3 dn="cn=Manager,dc=testdomain,dc=com" method=128
Nov 1 15:44:45 joe slapd[23274]: daemon: select: listen=6 active_threads=1 tvp=NULL
Nov 1 15:44:45 joe slapd[23277]: ==> ldbm_back_bind: dn: cn=Manager,dc=testdomain,dc=com
Nov 1 15:44:45 joe slapd[23277]: dn2entry_r: dn: "CN=MANAGER,DC=TESTDOMAIN,DC=COM"
Nov 1 15:44:45 joe slapd[23277]: => dn2id( "CN=MANAGER,DC=TESTDOMAIN,DC=COM" )
Nov 1 15:44:45 joe slapd[23277]: => ldbm_cache_open( "dn2id.dbb", 7, 1130 )
Nov 1 15:44:45 joe slapd[23277]: ldbm_cache_open (blksize 8192) (maxids 2046) (maxindirect 5)
Nov 1 15:44:45 joe slapd[23277]: <= ldbm_cache_open (opened 0)
Nov 1 15:44:45 joe slapd[23277]: <= dn2id NOID
Nov 1 15:44:45 joe slapd[23277]: dn2entry_r: dn: "DC=TESTDOMAIN,DC=COM"
Nov 1 15:44:45 joe slapd[23277]: => dn2id( "DC=TESTDOMAIN,DC=COM" )
Nov 1 15:44:45 joe slapd[23277]: => ldbm_cache_open( "dn2id.dbb", 7, 1130 )
Nov 1 15:44:45 joe slapd[23277]: <= ldbm_cache_open (cache 0)
Nov 1 15:44:45 joe slapd[23277]: <= dn2id 1
Nov 1 15:44:45 joe slapd[23277]: => id2entry_r( 1 )
Nov 1 15:44:45 joe slapd[23277]: => ldbm_cache_open( "id2entry.dbb", 7, 1130 )
Nov 1 15:44:45 joe slapd[23277]: ldbm_cache_open (blksize 8192) (maxids 2046) (maxindirect 5)
Nov 1 15:44:45 joe slapd[23277]: <= ldbm_cache_open (opened 1)
Nov 1 15:44:45 joe slapd[23277]: => str2entry
Nov 1 15:44:45 joe slapd[23277]: <= str2entry(dc=testdomain, dc=com) -> -1 (0x80dd848)
:Nov 1 15:44:45 joe slapd[23277]: entry_rdwr_rlock: ID: 1
Nov 1 15:44:45 joe slapd[23277]: <= id2entry_r( 1 ) 0x80dd848 (disk)
Nov 1 15:44:45 joe slapd[23277]: entry_rdwr_runlock: ID: 1
Nov 1 15:44:45 joe slapd[23277]: ====> cache_return_entry_r( 1 ): created (0)
Nov 1 15:44:45 joe slapd[23277]: do_bind: v3 bind: "cn=Manager,dc=testdomain,dc=com" to "cn=Manager, dc=testdomain, dc=com"
Nov 1 15:44:45 joe slapd[23277]: send_ldap_result: conn=0 op=0 p=3
Nov 1 15:44:45 joe slapd[23277]: send_ldap_result: 0::
Nov 1 15:44:45 joe slapd[23277]: send_ldap_response: msgid=1 tag=97 err=0
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on 1 descriptors
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on:
Nov 1 15:44:45 joe slapd[23274]: 9r
Nov 1 15:44:45 joe slapd[23274]: Nov 1 15:44:45 joe slapd[23274]: daemon: read activity on 9
Nov 1 15:44:45 joe slapd[23274]: connection_get(9)
Nov 1 15:44:45 joe slapd[23274]: connection_get(9): got connid=0
Nov 1 15:44:45 joe slapd[23274]: connection_read(9): checking for input on id=0
Nov 1 15:44:45 joe slapd[23274]: ber_get_next on fd 9 failed errno=11 (Resource temporarily unavailable)
Nov 1 15:44:45 joe slapd[23277]: do_add
Nov 1 15:44:45 joe slapd[23274]: daemon: select: listen=6 active_threads=1 tvp=NULL
Nov 1 15:44:45 joe slapd[23277]: do_add: ndn (CN=USER,DC=TESTDOMAIN,DC=COM)
Nov 1 15:44:45 joe slapd[23277]: dn2entry_r: dn: "CN=USER,DC=TESTDOMAIN,DC=COM"
Nov 1 15:44:45 joe slapd[23277]: => dn2id( "CN=USER,DC=TESTDOMAIN,DC=COM" )
Nov 1 15:44:45 joe slapd[23277]: => ldbm_cache_open( "dn2id.dbb", 7, 1130 )
Nov 1 15:44:45 joe slapd[23277]: <= ldbm_cache_open (cache 0)
Nov 1 15:44:45 joe slapd[23277]: <= dn2id NOID
Nov 1 15:44:45 joe slapd[23277]: dn2entry_r: dn: "DC=TESTDOMAIN,DC=COM"
Nov 1 15:44:45 joe slapd[23277]: => dn2id( "DC=TESTDOMAIN,DC=COM" )
Nov 1 15:44:45 joe slapd[23277]: ====> cache_find_entry_dn2id("DC=TESTDOMAIN,DC=COM"): 1 (1 tries)
Nov 1 15:44:45 joe slapd[23277]: <= dn2id 1 (in cache)
Nov 1 15:44:45 joe slapd[23277]: => id2entry_r( 1 ) Nov 1 15:44:45 joe slapd[23277]: entry_rdwr_rtrylock: ID: 1
Nov 1 15:44:45 joe slapd[23277]: ====> cache_find_entry_id( 1 ) "dc=testdomain, dc=com" (found) (1 tries)
Nov 1 15:44:45 joe slapd[23277]: <= id2entry_r( 1 ) 0x80dd848 (cache)
Nov 1 15:44:45 joe slapd[23277]: ldbm_referrals: op=104 target="cn=user,dc=testdomain,dc=com" matched="dc=testdomain, dc=com"
Nov 1 15:44:45 joe slapd[23277]: entry_rdwr_runlock: ID: 1
Nov 1 15:44:45 joe slapd[23277]: ====> cache_return_entry_r( 1 ): returned (0)
Nov 1 15:44:45 joe slapd[23277]: send_ldap_result: conn=0 op=1 p=3
Nov 1 15:44:45 joe slapd[23277]: send_ldap_result: 10::
Nov 1 15:44:45 joe slapd[23277]: send_ldap_response: msgid=2 tag=105 err=32
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on 1 descriptors
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on:
Nov 1 15:44:45 joe slapd[23274]: 9r
Nov 1 15:44:45 joe slapd[23274]: Nov 1 15:44:45 joe slapd[23274]: daemon: read activity on 9
Nov 1 15:44:45 joe slapd[23274]: connection_get(9)
Nov 1 15:44:45 joe slapd[23274]: connection_get(9): got connid=0
Nov 1 15:44:45 joe slapd[23274]: connection_read(9): checking for input on id=0
Nov 1 15:44:45 joe slapd[23274]: ber_get_next on fd 9 failed errno=0 (Success)
Nov 1 15:44:45 joe slapd[23277]: do_unbind
Nov 1 15:44:45 joe slapd[23274]: connection_read(9): input error=-2 id=0, closing.
Nov 1 15:44:45 joe slapd[23274]: connection_closing: readying conn=0 sd=9 for close
Nov 1 15:44:45 joe slapd[23274]: connection_close: deferring conn=0 sd=9
Nov 1 15:44:45 joe slapd[23277]: connection_resched: attempting closing conn=0 sd=9
Nov 1 15:44:45 joe slapd[23274]: daemon: select: listen=6 active_threads=1 tvp=NULL
Nov 1 15:44:45 joe slapd[23277]: connection_close: conn=0 sd=9
Nov 1 15:44:45 joe slapd[23274]: daemon: activity on 1 descriptors
Nov 1 15:44:45 joe slapd[23277]: daemon: removing 9
Nov 1 15:44:45 joe slapd[23274]: daemon: select: listen=6 active_threads=1 tvp=NULL




has anyone experienced something similar or knows how to interprete this behavior?

Regards
Joe Scherler