Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on 1 descriptor Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on: Aug 24 14:06:40 box1 slapd[11404]: Aug 24 14:06:40 box1 slapd[11404]: >>> slap_listener(ldap:///) Aug 24 14:06:40 box1 slapd[11404]: daemon: listen=7, new connection on 14 Aug 24 14:06:40 box1 slapd[11404]: daemon: added 14r (active) listener=(nil) Aug 24 14:06:40 box1 slapd[11404]: conn=5 fd=14 ACCEPT from IP=127.0.0.1:38057 (IP=0.0.0.0:389) Aug 24 14:06:40 box1 slapd[11404]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:06:40 box1 slapd[11404]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on 1 descriptor Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on: Aug 24 14:06:40 box1 slapd[11404]: 14r Aug 24 14:06:40 box1 slapd[11404]: Aug 24 14:06:40 box1 slapd[11404]: daemon: read active on 14 Aug 24 14:06:40 box1 slapd[11404]: connection_get(14) Aug 24 14:06:40 box1 slapd[11404]: connection_get(14): got connid=5 Aug 24 14:06:40 box1 slapd[11404]: connection_read(14): checking for input on id=5 Aug 24 14:06:40 box1 slapd[11404]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:06:40 box1 slapd[11404]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:06:40 box1 slapd[11404]: do_bind Aug 24 14:06:40 box1 slapd[11404]: >>> dnPrettyNormal: Aug 24 14:06:40 box1 slapd[11404]: <<< dnPrettyNormal: , Aug 24 14:06:40 box1 slapd[11404]: do_bind: version=3 dn="cn=admin,dc=example,dc=com" method=128 Aug 24 14:06:40 box1 slapd[11404]: conn=5 op=0 BIND dn="cn=admin,dc=example,dc=com" method=128 Aug 24 14:06:40 box1 slapd[11404]: ==> hdb_bind: dn: cn=admin,dc=example,dc=com Aug 24 14:06:40 box1 slapd[11404]: conn=5 op=0 BIND dn="cn=admin,dc=example,dc=com" mech=SIMPLE ssf=0 Aug 24 14:06:40 box1 slapd[11404]: do_bind: v3 bind: "cn=admin,dc=example,dc=com" to "cn=admin,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: send_ldap_result: conn=5 op=0 p=3 Aug 24 14:06:40 box1 slapd[11404]: send_ldap_result: err=0 matched="" text="" Aug 24 14:06:40 box1 slapd[11404]: send_ldap_response: msgid=1 tag=97 err=0 Aug 24 14:06:40 box1 slapd[11404]: conn=5 op=0 RESULT tag=97 err=0 text= Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on 1 descriptor Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on: Aug 24 14:06:40 box1 slapd[11404]: 14r Aug 24 14:06:40 box1 slapd[11404]: Aug 24 14:06:40 box1 slapd[11404]: daemon: read active on 14 Aug 24 14:06:40 box1 slapd[11404]: connection_get(14) Aug 24 14:06:40 box1 slapd[11404]: connection_get(14): got connid=5 Aug 24 14:06:40 box1 slapd[11404]: connection_read(14): checking for input on id=5 Aug 24 14:06:40 box1 slapd[11404]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:06:40 box1 slapd[11404]: do_add Aug 24 14:06:40 box1 slapd[11404]: >>> dnPrettyNormal: Aug 24 14:06:40 box1 slapd[11404]: <<< dnPrettyNormal: , Aug 24 14:06:40 box1 slapd[11404]: do_add: dn (ou=ldif3,ou=branch2,dc=example,dc=com) Aug 24 14:06:40 box1 slapd[11404]: conn=5 op=1 ADD dn="ou=ldif3,ou=branch2,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: bdb_dn2entry("ou=ldif3,ou=branch2,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: => hdb_dn2id("ou=ldif3,ou=branch2,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: <= hdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30989) Aug 24 14:06:40 box1 slapd[11404]: hdb_referrals: op=104 target="ou=ldif3,ou=branch2,dc=example,dc=com" matched="ou=branch2,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: ==> hdb_add: ou=ldif3,ou=branch2,dc=example,dc=com Aug 24 14:06:40 box1 slapd[11404]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:06:40 box1 slapd[11404]: oc_check_required entry (ou=ldif3,ou=branch2,dc=example,dc=com), objectClass "organizationalUnit" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "objectClass" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "ou" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "structuralObjectClass" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "entryUUID" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "creatorsName" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "createTimestamp" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "entryCSN" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "modifiersName" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "modifyTimestamp" Aug 24 14:06:40 box1 slapd[11404]: bdb_dn2entry("ou=ldif3,ou=branch2,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: => hdb_dn2id("ou=ldif3,ou=branch2,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: <= hdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30989) Aug 24 14:06:40 box1 slapd[11404]: => access_allowed: add access to "ou=branch2,dc=example,dc=com" "children" requested Aug 24 14:06:40 box1 slapd[11404]: <= root access granted Aug 24 14:06:40 box1 slapd[11404]: => access_allowed: add access to "ou=ldif3,ou=branch2,dc=example,dc=com" "entry" requested Aug 24 14:06:40 box1 slapd[11404]: <= root access granted Aug 24 14:06:40 box1 slapd[11404]: => hdb_dn2id_add 0x12: "ou=ldif3,ou=branch2,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: <= hdb_dn2id_add 0x12: 0 Aug 24 14:06:40 box1 slapd[11404]: => index_entry_add( 18, "ou=ldif3,ou=branch2,dc=example,dc=com" ) Aug 24 14:06:40 box1 slapd[11404]: => key_change(ADD,12) Aug 24 14:06:40 box1 slapd[11404]: bdb_idl_insert_key: 12 [9bee355f] Aug 24 14:06:40 box1 slapd[11404]: <= key_change 0 Aug 24 14:06:40 box1 slapd[11404]: => key_change(ADD,12) Aug 24 14:06:40 box1 slapd[11404]: bdb_idl_insert_key: 12 [0096defd] Aug 24 14:06:40 box1 slapd[11404]: <= key_change 0 Aug 24 14:06:40 box1 slapd[11404]: => key_change(ADD,12) Aug 24 14:06:40 box1 slapd[11404]: bdb_idl_insert_key: 12 [f5ab0825] Aug 24 14:06:40 box1 slapd[11404]: <= key_change 0 Aug 24 14:06:40 box1 slapd[11404]: => key_change(ADD,12) Aug 24 14:06:40 box1 slapd[11404]: bdb_idl_insert_key: 12 Aug 24 14:06:40 box1 slapd[11404]: <= key_change 0 Aug 24 14:06:40 box1 slapd[11404]: <= index_entry_add( 18, "ou=ldif3,ou=branch2,dc=example,dc=com" ) success Aug 24 14:06:40 box1 slapd[11404]: => entry_encode(0x00000012): Aug 24 14:06:40 box1 slapd[11404]: hdb_add: added id=00000012 dn="ou=ldif3,ou=branch2,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: send_ldap_result: conn=5 op=1 p=3 Aug 24 14:06:40 box1 slapd[11404]: send_ldap_result: err=0 matched="" text="" Aug 24 14:06:40 box1 slapd[11404]: hdb_modify: dc=example,dc=com Aug 24 14:06:40 box1 slapd[11404]: bdb_dn2entry("dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: bdb_modify_internal: 0x00000001: dc=example,dc=com Aug 24 14:06:40 box1 slapd[11404]: <= acl_access_allowed: granted to database root Aug 24 14:06:40 box1 slapd[11404]: bdb_modify_internal: replace contextCSN Aug 24 14:06:40 box1 slapd[11404]: oc_check_required entry (dc=example,dc=com), objectClass "organizationalUnit" Aug 24 14:06:40 box1 slapd[11404]: oc_check_required entry (dc=example,dc=com), objectClass "dcObject" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "objectClass" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "dc" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "ou" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "structuralObjectClass" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "entryUUID" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "creatorsName" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "createTimestamp" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "entryCSN" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "modifiersName" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "modifyTimestamp" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "contextCSN" Aug 24 14:06:40 box1 slapd[11404]: => entry_encode(0x00000001): Aug 24 14:06:40 box1 slapd[11404]: hdb_modify: updated id=00000001 dn="dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: send_ldap_result: conn=5 op=1 p=3 Aug 24 14:06:40 box1 slapd[11404]: send_ldap_result: err=0 matched="" text="" Aug 24 14:06:40 box1 slapd[11404]: => test_filter Aug 24 14:06:40 box1 slapd[11404]: PRESENT Aug 24 14:06:40 box1 slapd[11404]: => access_allowed: search access to "ou=ldif3,ou=branch2,dc=example,dc=com" "objectClass" requested Aug 24 14:06:40 box1 slapd[11404]: <= root access granted Aug 24 14:06:40 box1 slapd[11404]: <= test_filter 6 Aug 24 14:06:40 box1 slapd[11404]: send_ldap_response: msgid=2 tag=105 err=0 Aug 24 14:06:40 box1 slapd[11404]: conn=5 op=1 RESULT tag=105 err=0 text= Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on 1 descriptor Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on: Aug 24 14:06:40 box1 slapd[11404]: Aug 24 14:06:40 box1 slapd[11404]: daemon: epoll: listen=7 active_threads=0 tvp=zero Aug 24 14:06:40 box1 slapd[11404]: => bdb_entry_get: ndn: "ou=ldif3,ou=branch2,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: => bdb_entry_get: oc: "(null)", at: "(null)" Aug 24 14:06:40 box1 slapd[11404]: bdb_dn2entry("ou=ldif3,ou=branch2,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: => bdb_entry_get: found entry: "ou=ldif3,ou=branch2,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: bdb_entry_get: rc=0 Aug 24 14:06:40 box1 slapd[11404]: => send_search_entry: conn 4 dn="ou=ldif3,ou=branch2,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: => access_allowed: read access to "ou=ldif3,ou=branch2,dc=example,dc=com" "entry" requested Aug 24 14:06:40 box1 slapd[11404]: => dn: [2] ou=branch2,dc=example,dc=com Aug 24 14:06:40 box1 slapd[11404]: => acl_get: [2] matched Aug 24 14:06:40 box1 slapd[11404]: => acl_get: [2] attr entry Aug 24 14:06:40 box1 slapd[11404]: => acl_mask: access to entry "ou=ldif3,ou=branch2,dc=example,dc=com", attr "entry" requested Aug 24 14:06:40 box1 slapd[11404]: => acl_mask: to all values by "uid=syncrepl,dc=example,dc=com", (=0) Aug 24 14:06:40 box1 slapd[11404]: <= check a_dn_pat: uid=syncrepl,dc=example,dc=com Aug 24 14:06:40 box1 slapd[11404]: <= acl_mask: [1] applying none(=0) (stop) Aug 24 14:06:40 box1 slapd[11404]: <= acl_mask: [1] mask: none(=0) Aug 24 14:06:40 box1 slapd[11404]: => access_allowed: read access denied by none(=0) Aug 24 14:06:40 box1 slapd[11404]: send_search_entry: conn 4 access to entry (ou=ldif3,ou=branch2,dc=example,dc=com) not allowed Aug 24 14:06:40 box1 slapd[11404]: daemon: epoll: listen=8 active_threads=0 tvp=zero Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on 1 descriptor Aug 24 14:06:40 box1 slapd[11404]: daemon: activity on: Aug 24 14:06:40 box1 slapd[11404]: 14r Aug 24 14:06:40 box1 slapd[11404]: Aug 24 14:06:40 box1 slapd[11404]: daemon: read active on 14 Aug 24 14:06:40 box1 slapd[11404]: connection_get(14) Aug 24 14:06:40 box1 slapd[11404]: connection_get(14): got connid=5 Aug 24 14:06:40 box1 slapd[11404]: connection_read(14): checking for input on id=5 Aug 24 14:06:40 box1 slapd[11404]: do_add Aug 24 14:06:40 box1 slapd[11404]: >>> dnPrettyNormal: Aug 24 14:06:40 box1 slapd[11404]: <<< dnPrettyNormal: , Aug 24 14:06:40 box1 slapd[11404]: do_add: dn (ou=ldif4,ou=branch1,dc=example,dc=com) Aug 24 14:06:40 box1 slapd[11404]: conn=5 op=2 ADD dn="ou=ldif4,ou=branch1,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: bdb_dn2entry("ou=ldif4,ou=branch1,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: => hdb_dn2id("ou=ldif4,ou=branch1,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: <= hdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30989) Aug 24 14:06:40 box1 slapd[11404]: hdb_referrals: op=104 target="ou=ldif4,ou=branch1,dc=example,dc=com" matched="ou=branch1,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: ==> hdb_add: ou=ldif4,ou=branch1,dc=example,dc=com Aug 24 14:06:40 box1 slapd[11404]: oc_check_required entry (ou=ldif4,ou=branch1,dc=example,dc=com), objectClass "organizationalUnit" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "objectClass" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "ou" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "structuralObjectClass" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "entryUUID" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "creatorsName" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "createTimestamp" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "entryCSN" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "modifiersName" Aug 24 14:06:40 box1 slapd[11404]: oc_check_allowed type "modifyTimestamp" Aug 24 14:06:40 box1 slapd[11404]: bdb_dn2entry("ou=ldif4,ou=branch1,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: => hdb_dn2id("ou=ldif4,ou=branch1,dc=example,dc=com") Aug 24 14:06:40 box1 slapd[11404]: <= hdb_dn2id: get failed: DB_NOTFOUND: No matching key/data pair found (-30989) Aug 24 14:06:40 box1 slapd[11404]: => access_allowed: add access to "ou=branch1,dc=example,dc=com" "children" requested Aug 24 14:06:40 box1 slapd[11404]: <= root access granted Aug 24 14:06:40 box1 slapd[11404]: => access_allowed: add access to "ou=ldif4,ou=branch1,dc=example,dc=com" "entry" requested Aug 24 14:06:40 box1 slapd[11404]: <= root access granted Aug 24 14:06:40 box1 slapd[11404]: => hdb_dn2id_add 0x13: "ou=ldif4,ou=branch1,dc=example,dc=com" Aug 24 14:06:40 box1 slapd[11404]: <= hdb_dn2id_add 0x13: 0 Aug 24 14:06:41 box1 slapd[11404]: => index_entry_add( 19, "ou=ldif4,ou=branch1,dc=example,dc=com" ) Aug 24 14:06:41 box1 slapd[11404]: => key_change(ADD,13) Aug 24 14:06:41 box1 slapd[11404]: bdb_idl_insert_key: 13 [9bee355f] Aug 24 14:06:41 box1 slapd[11404]: <= key_change 0 Aug 24 14:06:41 box1 slapd[11404]: => key_change(ADD,13) Aug 24 14:06:41 box1 slapd[11404]: bdb_idl_insert_key: 13 [0096defd] Aug 24 14:06:41 box1 slapd[11404]: daemon: epoll: listen=7 active_threads=0 tvp=NULL Aug 24 14:06:41 box1 slapd[11404]: daemon: epoll: listen=8 active_threads=0 tvp=NULL Aug 24 14:06:41 box1 slapd[11404]: <= key_change 0 Aug 24 14:06:41 box1 slapd[11404]: => key_change(ADD,13) Aug 24 14:06:41 box1 slapd[11404]: bdb_idl_insert_key: 13 [5e063a40] Aug 24 14:06:41 box1 slapd[11404]: <= key_change 0 Aug 24 14:06:41 box1 slapd[11404]: => key_change(ADD,13) Aug 24 14:06:41 box1 slapd[11404]: bdb_idl_insert_key: 13 Aug 24 14:06:41 box1 slapd[11404]: <= key_change 0 Aug 24 14:06:41 box1 slapd[11404]: <= index_entry_add( 19, "ou=ldif4,ou=branch1,dc=example,dc=com" ) success Aug 24 14:06:41 box1 slapd[11404]: => entry_encode(0x00000013): Aug 24 14:06:41 box1 slapd[11404]: hdb_add: added id=00000013 dn="ou=ldif4,ou=branch1,dc=example,dc=com" Aug 24 14:06:41 box1 slapd[11404]: send_ldap_result: conn=5 op=2 p=3 Aug 24 14:06:41 box1 slapd[11404]: send_ldap_result: err=0 matched="" text="" Aug 24 14:06:41 box1 slapd[11404]: => test_filter Aug 24 14:06:41 box1 slapd[11404]: PRESENT Aug 24 14:06:41 box1 slapd[11404]: => access_allowed: search access to "ou=ldif4,ou=branch1,dc=example,dc=com" "objectClass" requested Aug 24 14:06:41 box1 slapd[11404]: <= root access granted Aug 24 14:06:41 box1 slapd[11404]: <= test_filter 6