Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on 1 descriptor Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on: Aug 24 14:11:44 box1 slapd[15628]: Aug 24 14:11:44 box1 slapd[15628]: >>> slap_listener(ldap:///) Aug 24 14:11:44 box1 slapd[15628]: daemon: listen=7, new connection on 14 Aug 24 14:11:44 box1 slapd[15628]: daemon: added 14r (active) listener=(nil) Aug 24 14:11:44 box1 slapd[15628]: conn=6 fd=14 ACCEPT from IP=127.0.0.1:54860 (IP=0.0.0.0:389) Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on 1 descriptor Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on: Aug 24 14:11:44 box1 slapd[15628]: 14r Aug 24 14:11:44 box1 slapd[15628]: Aug 24 14:11:44 box1 slapd[15628]: daemon: read active on 14 Aug 24 14:11:44 box1 slapd[15628]: connection_get(14) Aug 24 14:11:44 box1 slapd[15628]: connection_get(14): got connid=6 Aug 24 14:11:44 box1 slapd[15628]: connection_read(14): checking for input on id=6 Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: do_bind Aug 24 14:11:44 box1 slapd[15628]: >>> dnPrettyNormal: Aug 24 14:11:44 box1 slapd[15628]: <<< dnPrettyNormal: , Aug 24 14:11:44 box1 slapd[15628]: do_bind: version=3 dn="cn=admin,dc=example,dc=com" method=128 Aug 24 14:11:44 box1 slapd[15628]: conn=6 op=0 BIND dn="cn=admin,dc=example,dc=com" method=128 Aug 24 14:11:44 box1 slapd[15628]: ==> hdb_bind: dn: cn=admin,dc=example,dc=com Aug 24 14:11:44 box1 slapd[15628]: conn=6 op=0 BIND dn="cn=admin,dc=example,dc=com" mech=SIMPLE ssf=0 Aug 24 14:11:44 box1 slapd[15628]: do_bind: v3 bind: "cn=admin,dc=example,dc=com" to "cn=admin,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: send_ldap_result: conn=6 op=0 p=3 Aug 24 14:11:44 box1 slapd[15628]: send_ldap_result: err=0 matched="" text="" Aug 24 14:11:44 box1 slapd[15628]: send_ldap_response: msgid=1 tag=97 err=0 Aug 24 14:11:44 box1 slapd[15628]: conn=6 op=0 RESULT tag=97 err=0 text= Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on 1 descriptor Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on: Aug 24 14:11:44 box1 slapd[15628]: 14r Aug 24 14:11:44 box1 slapd[15628]: Aug 24 14:11:44 box1 slapd[15628]: daemon: read active on 14 Aug 24 14:11:44 box1 slapd[15628]: connection_get(14) Aug 24 14:11:44 box1 slapd[15628]: connection_get(14): got connid=6 Aug 24 14:11:44 box1 slapd[15628]: connection_read(14): checking for input on id=6 Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: do_add Aug 24 14:11:44 box1 slapd[15628]: >>> dnPrettyNormal: Aug 24 14:11:44 box1 slapd[15628]: <<< dnPrettyNormal: , Aug 24 14:11:44 box1 slapd[15628]: do_add: dn (ou=ldif7,ou=branch2,dc=example,dc=com) Aug 24 14:11:44 box1 slapd[15628]: conn=6 op=1 ADD dn="ou=ldif7,ou=branch2,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: bdb_dn2entry("ou=ldif7,ou=branch2,dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: => hdb_dn2id("ou=ldif7,ou=branch2,dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: <= hdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30989) Aug 24 14:11:44 box1 slapd[15628]: hdb_referrals: op=104 target="ou=ldif7,ou=branch2,dc=example,dc=com" matched="ou=branch2,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: ==> hdb_add: ou=ldif7,ou=branch2,dc=example,dc=com Aug 24 14:11:44 box1 slapd[15628]: oc_check_required entry (ou=ldif7,ou=branch2,dc=example,dc=com), objectClass "organizationalUnit" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "objectClass" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "ou" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "structuralObjectClass" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "entryUUID" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "creatorsName" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "createTimestamp" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "entryCSN" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "modifiersName" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "modifyTimestamp" Aug 24 14:11:44 box1 slapd[15628]: bdb_dn2entry("ou=ldif7,ou=branch2,dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: => hdb_dn2id("ou=ldif7,ou=branch2,dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: <= hdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30989) Aug 24 14:11:44 box1 slapd[15628]: => access_allowed: add access to "ou=branch2,dc=example,dc=com" "children" requested Aug 24 14:11:44 box1 slapd[15628]: <= root access granted Aug 24 14:11:44 box1 slapd[15628]: => access_allowed: add access to "ou=ldif7,ou=branch2,dc=example,dc=com" "entry" requested Aug 24 14:11:44 box1 slapd[15628]: <= root access granted Aug 24 14:11:44 box1 slapd[15628]: => hdb_dn2id_add 0x18: "ou=ldif7,ou=branch2,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: <= hdb_dn2id_add 0x18: 0 Aug 24 14:11:44 box1 slapd[15628]: => index_entry_add( 24, "ou=ldif7,ou=branch2,dc=example,dc=com" ) Aug 24 14:11:44 box1 slapd[15628]: => key_change(ADD,18) Aug 24 14:11:44 box1 slapd[15628]: bdb_idl_insert_key: 18 [9bee355f] Aug 24 14:11:44 box1 slapd[15628]: <= key_change 0 Aug 24 14:11:44 box1 slapd[15628]: => key_change(ADD,18) Aug 24 14:11:44 box1 slapd[15628]: bdb_idl_insert_key: 18 [0096defd] Aug 24 14:11:44 box1 slapd[15628]: <= key_change 0 Aug 24 14:11:44 box1 slapd[15628]: => key_change(ADD,18) Aug 24 14:11:44 box1 slapd[15628]: bdb_idl_insert_key: 18 [0c24a57a] Aug 24 14:11:44 box1 slapd[15628]: <= key_change 0 Aug 24 14:11:44 box1 slapd[15628]: => key_change(ADD,18) Aug 24 14:11:44 box1 slapd[15628]: bdb_idl_insert_key: 18 Aug 24 14:11:44 box1 slapd[15628]: <= key_change 0 Aug 24 14:11:44 box1 slapd[15628]: <= index_entry_add( 24, "ou=ldif7,ou=branch2,dc=example,dc=com" ) success Aug 24 14:11:44 box1 slapd[15628]: => entry_encode(0x00000018): Aug 24 14:11:44 box1 slapd[15628]: hdb_add: added id=00000018 dn="ou=ldif7,ou=branch2,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: send_ldap_result: conn=6 op=1 p=3 Aug 24 14:11:44 box1 slapd[15628]: send_ldap_result: err=0 matched="" text="" Aug 24 14:11:44 box1 slapd[15628]: hdb_modify: dc=example,dc=com Aug 24 14:11:44 box1 slapd[15628]: bdb_dn2entry("dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: bdb_modify_internal: 0x00000001: dc=example,dc=com Aug 24 14:11:44 box1 slapd[15628]: <= acl_access_allowed: granted to database root Aug 24 14:11:44 box1 slapd[15628]: bdb_modify_internal: replace contextCSN Aug 24 14:11:44 box1 slapd[15628]: oc_check_required entry (dc=example,dc=com), objectClass "organizationalUnit" Aug 24 14:11:44 box1 slapd[15628]: oc_check_required entry (dc=example,dc=com), objectClass "dcObject" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "objectClass" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "dc" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "ou" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "structuralObjectClass" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "entryUUID" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "creatorsName" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "createTimestamp" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "entryCSN" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "modifiersName" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "modifyTimestamp" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "contextCSN" Aug 24 14:11:44 box1 slapd[15628]: => entry_encode(0x00000001): Aug 24 14:11:44 box1 slapd[15628]: hdb_modify: updated id=00000001 dn="dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: send_ldap_result: conn=6 op=1 p=3 Aug 24 14:11:44 box1 slapd[15628]: send_ldap_result: err=0 matched="" text="" Aug 24 14:11:44 box1 slapd[15628]: => test_filter Aug 24 14:11:44 box1 slapd[15628]: PRESENT Aug 24 14:11:44 box1 slapd[15628]: => access_allowed: search access to "ou=ldif7,ou=branch2,dc=example,dc=com" "objectClass" requested Aug 24 14:11:44 box1 slapd[15628]: <= root access granted Aug 24 14:11:44 box1 slapd[15628]: <= test_filter 6 Aug 24 14:11:44 box1 slapd[15628]: send_ldap_response: msgid=2 tag=105 err=0 Aug 24 14:11:44 box1 slapd[15628]: conn=6 op=1 RESULT tag=105 err=0 text= Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on 1 descriptor Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on: Aug 24 14:11:44 box1 slapd[15628]: 14r Aug 24 14:11:44 box1 slapd[15628]: Aug 24 14:11:44 box1 slapd[15628]: daemon: read active on 14 Aug 24 14:11:44 box1 slapd[15628]: connection_get(14) Aug 24 14:11:44 box1 slapd[15628]: connection_get(14): got connid=6 Aug 24 14:11:44 box1 slapd[15628]: connection_read(14): checking for input on id=6 Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: do_add Aug 24 14:11:44 box1 slapd[15628]: >>> dnPrettyNormal: Aug 24 14:11:44 box1 slapd[15628]: <<< dnPrettyNormal: , Aug 24 14:11:44 box1 slapd[15628]: do_add: dn (ou=ldif8,ou=branch1,dc=example,dc=com) Aug 24 14:11:44 box1 slapd[15628]: conn=6 op=2 ADD dn="ou=ldif8,ou=branch1,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: bdb_dn2entry("ou=ldif8,ou=branch1,dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: => hdb_dn2id("ou=ldif8,ou=branch1,dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: <= hdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30989) Aug 24 14:11:44 box1 slapd[15628]: hdb_referrals: op=104 target="ou=ldif8,ou=branch1,dc=example,dc=com" matched="ou=branch1,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: ==> hdb_add: ou=ldif8,ou=branch1,dc=example,dc=com Aug 24 14:11:44 box1 slapd[15628]: oc_check_required entry (ou=ldif8,ou=branch1,dc=example,dc=com), objectClass "organizationalUnit" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "objectClass" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "ou" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "structuralObjectClass" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "entryUUID" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "creatorsName" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "createTimestamp" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "entryCSN" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "modifiersName" Aug 24 14:11:44 box1 slapd[15628]: oc_check_allowed type "modifyTimestamp" Aug 24 14:11:44 box1 slapd[15628]: bdb_dn2entry("ou=ldif8,ou=branch1,dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: => hdb_dn2id("ou=ldif8,ou=branch1,dc=example,dc=com") Aug 24 14:11:44 box1 slapd[15628]: <= hdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30989) Aug 24 14:11:44 box1 slapd[15628]: => access_allowed: add access to "ou=branch1,dc=example,dc=com" "children" requested Aug 24 14:11:44 box1 slapd[15628]: <= root access granted Aug 24 14:11:44 box1 slapd[15628]: => access_allowed: add access to "ou=ldif8,ou=branch1,dc=example,dc=com" "entry" requested Aug 24 14:11:44 box1 slapd[15628]: <= root access granted Aug 24 14:11:44 box1 slapd[15628]: => hdb_dn2id_add 0x19: "ou=ldif8,ou=branch1,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: <= hdb_dn2id_add 0x19: 0 Aug 24 14:11:44 box1 slapd[15628]: => index_entry_add( 25, "ou=ldif8,ou=branch1,dc=example,dc=com" ) Aug 24 14:11:44 box1 slapd[15628]: => key_change(ADD,19) Aug 24 14:11:44 box1 slapd[15628]: bdb_idl_insert_key: 19 [9bee355f] Aug 24 14:11:44 box1 slapd[15628]: <= key_change 0 Aug 24 14:11:44 box1 slapd[15628]: => key_change(ADD,19) Aug 24 14:11:44 box1 slapd[15628]: bdb_idl_insert_key: 19 [0096defd] Aug 24 14:11:44 box1 slapd[15628]: <= key_change 0 Aug 24 14:11:44 box1 slapd[15628]: => key_change(ADD,19) Aug 24 14:11:44 box1 slapd[15628]: bdb_idl_insert_key: 19 [b2832d50] Aug 24 14:11:44 box1 slapd[15628]: <= key_change 0 Aug 24 14:11:44 box1 slapd[15628]: => key_change(ADD,19) Aug 24 14:11:44 box1 slapd[15628]: bdb_idl_insert_key: 19 Aug 24 14:11:44 box1 slapd[15628]: <= key_change 0 Aug 24 14:11:44 box1 slapd[15628]: <= index_entry_add( 25, "ou=ldif8,ou=branch1,dc=example,dc=com" ) success Aug 24 14:11:44 box1 slapd[15628]: => entry_encode(0x00000019): Aug 24 14:11:44 box1 slapd[15628]: hdb_add: added id=00000019 dn="ou=ldif8,ou=branch1,dc=example,dc=com" Aug 24 14:11:44 box1 slapd[15628]: send_ldap_result: conn=6 op=2 p=3 Aug 24 14:11:44 box1 slapd[15628]: send_ldap_result: err=0 matched="" text="" Aug 24 14:11:44 box1 slapd[15628]: => test_filter Aug 24 14:11:44 box1 slapd[15628]: PRESENT Aug 24 14:11:44 box1 slapd[15628]: => access_allowed: search access to "ou=ldif8,ou=branch1,dc=example,dc=com" "objectClass" requested Aug 24 14:11:44 box1 slapd[15628]: <= root access granted Aug 24 14:11:44 box1 slapd[15628]: <= test_filter 6 Aug 24 14:11:44 box1 slapd[15628]: send_ldap_response: msgid=3 tag=105 err=0 Aug 24 14:11:44 box1 slapd[15628]: conn=6 op=2 RESULT tag=105 err=0 text= Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on 1 descriptor Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on: Aug 24 14:11:44 box1 slapd[15628]: 14r Aug 24 14:11:44 box1 slapd[15628]: Aug 24 14:11:44 box1 slapd[15628]: daemon: read active on 14 Aug 24 14:11:44 box1 slapd[15628]: connection_get(14) Aug 24 14:11:44 box1 slapd[15628]: connection_get(14): got connid=6 Aug 24 14:11:44 box1 slapd[15628]: connection_read(14): checking for input on id=6 Aug 24 14:11:44 box1 slapd[15628]: ber_get_next on fd 14 failed errno=0 (Success) Aug 24 14:11:44 box1 slapd[15628]: connection_read(14): input error=-2 id=6, closing. Aug 24 14:11:44 box1 slapd[15628]: connection_closing: readying conn=6 sd=14 for close Aug 24 14:11:44 box1 slapd[15628]: connection_close: deferring conn=6 sd=-1 Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on 1 descriptor Aug 24 14:11:44 box1 slapd[15628]: daemon: activity on: Aug 24 14:11:44 box1 slapd[15628]: Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:11:44 box1 slapd[15628]: do_unbind Aug 24 14:11:44 box1 slapd[15628]: conn=6 op=3 UNBIND Aug 24 14:11:44 box1 slapd[15628]: connection_resched: attempting closing conn=6 sd=14 Aug 24 14:11:44 box1 slapd[15628]: connection_close: conn=6 sd=-1 Aug 24 14:11:44 box1 slapd[15628]: daemon: removing 14 Aug 24 14:11:44 box1 slapd[15628]: conn=6 fd=14 closed