hi-
i've recently set up delta-syncrepl, with one provider and one consumer.
things seemed to be generally working, but i recently noticed that member
attributes in group entries were not getting replicated. after a bit of
testing, i also found that new groups added to the provider appear to not be
replicated to the consumer. it also appears that the operation of adding a
group is not being written to the accesslog. on a possibly related note, i'm
using the memberof overlay, and the memberof attribute modifications which
occur as a result of adding a group are written to the accesslog, and are
replicated to the consumer. i'm using 2.4.25, courtesy of ubuntu 11.10. what
can i do to better understand what is happening, and why? below is some
preliminary data, and log entries using olcloglevel: any
thanks
-ben
>cat add_group.ldif
dn: cn=test_group,ou=general,ou=groups,dc=example,dc=net
changetype: add
objectClass: top
objectClass: groupOfNames
description: test group
cn: test_group
member:
uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net
>ldapadd -xZZD 'uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net'
>-w 'xxxxxxxxxxxx' -f add_group.ldif
adding new entry "cn=test_group,ou=general,ou=groups,dc=example,dc=net"
subsequent ldapsearch on provider:
>ldapsearch -xLLLZZD
>'uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net' -w
>'xxxxxxxxxxxx' -b 'cn=test_group,ou=general,ou=groups,dc=example,dc=net' -s
>base '*' '+'
dn: cn=test_group,ou=general,ou=groups,dc=example,dc=net
objectClass: top
objectClass: groupOfNames
description: test group
cn: test_group
member: uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,
dc=net
structuralObjectClass: groupOfNames
entryUUID: d68c73e4-10c1-1031-8246-9dfa8daa46e0
creatorsName: uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net
createTimestamp: 20120402034404Z
entryCSN: 20120402034404.808333Z#000000#000#000000
modifiersName: uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net
modifyTimestamp: 20120402034404Z
entryDN: cn=test_group,ou=general,ou=groups,dc=example,dc=net
subschemaSubentry: cn=Subschema
hasSubordinates: FALSE
subsequent ldapsearch on consumer:
>ldapsearch -xLLLZZD
>'uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net' -w
>'xxxxxxxxxxxx' -b 'cn=test_group,ou=general,ou=groups,dc=example,dc=net' -s
>base '*' '+'
No such object (32)
Matched DN: ou=general,ou=groups,dc=example,dc=net
provider log entries:
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: slap_listener_activate(8):
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 busy
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: >>> slap_listener(ldap:///)
Apr 1 23:44:04 flip slapd[9255]: daemon: listen=8, new connection on 54
Apr 1 23:44:04 flip slapd[9255]: daemon: added 54r (active) listener=(nil)
Apr 1 23:44:04 flip slapd[9255]: conn=1378 fd=54 ACCEPT from
IP=192.168.1.1:47610 (IP=0.0.0.0:389)
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 2 descriptors
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]: 54r
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: read active on 54
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: connection_get(54)
Apr 1 23:44:04 flip slapd[9255]: connection_get(54): got connid=1378
Apr 1 23:44:04 flip slapd[9255]: connection_read(54): checking for input on
id=1378
Apr 1 23:44:04 flip slapd[9255]: op tag 0x77, time 1333338244
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=0 do_extended
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=0 EXT oid=1.3.6.1.4.1.1466.20037
Apr 1 23:44:04 flip slapd[9255]: do_extended: oid=1.3.6.1.4.1.1466.20037
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=0 STARTTLS
Apr 1 23:44:04 flip slapd[9255]: send_ldap_extended: err=0 oid= len=0
Apr 1 23:44:04 flip slapd[9255]: send_ldap_response: msgid=1 tag=120 err=0
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=0 RESULT oid= err=0 text=
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]: 54r
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: read active on 54
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: connection_get(54)
Apr 1 23:44:04 flip slapd[9255]: connection_get(54): got connid=1378
Apr 1 23:44:04 flip slapd[9255]: connection_read(54): checking for input on
id=1378
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]: 54r
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: read active on 54
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: connection_get(54)
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: connection_get(54): got connid=1378
Apr 1 23:44:04 flip slapd[9255]: connection_read(54): checking for input on
id=1378
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]: 54r
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: read active on 54
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: connection_get(54)
Apr 1 23:44:04 flip slapd[9255]: connection_get(54): got connid=1378
Apr 1 23:44:04 flip slapd[9255]: connection_read(54): checking for input on
id=1378
Apr 1 23:44:04 flip slapd[9255]: connection_read(54): unable to get TLS client
DN, error=49 id=1378
Apr 1 23:44:04 flip slapd[9255]: conn=1378 fd=54 TLS established tls_ssf=128
ssf=128
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]: 54r
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: read active on 54
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: connection_get(54)
Apr 1 23:44:04 flip slapd[9255]: connection_get(54): got connid=1378
Apr 1 23:44:04 flip slapd[9255]: connection_read(54): checking for input on
id=1378
Apr 1 23:44:04 flip slapd[9255]: op tag 0x60, time 1333338244
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=1 do_bind
Apr 1 23:44:04 flip slapd[9255]: >>> dnPrettyNormal:
<uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]: <<< dnPrettyNormal:
<uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net>,
<uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=1 BIND
dn="uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net" method=128
Apr 1 23:44:04 flip slapd[9255]: do_bind: version=3
dn="uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net" method=128
Apr 1 23:44:04 flip slapd[9255]: ==> hdb_bind: dn:
uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net
Apr 1 23:44:04 flip slapd[9255]:
bdb_dn2entry("uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net")
Apr 1 23:44:04 flip slapd[9255]: => access_allowed: result not in cache
(userPassword)
Apr 1 23:44:04 flip slapd[9255]: => access_allowed: auth access to
"uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net" "userPassword"
requested
Apr 1 23:44:04 flip slapd[9255]: => acl_get: [1] attr userPassword
Apr 1 23:44:04 flip slapd[9255]: => acl_mask: access to entry
"uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net", attr
"userPassword" requested
Apr 1 23:44:04 flip slapd[9255]: => acl_mask: to value by "", (=0)
Apr 1 23:44:04 flip slapd[9255]: <= check a_dn_pat: anonymous
Apr 1 23:44:04 flip slapd[9255]: <= acl_mask: [1] applying auth(=xd) (stop)
Apr 1 23:44:04 flip slapd[9255]: <= acl_mask: [1] mask: auth(=xd)
Apr 1 23:44:04 flip slapd[9255]: => slap_access_allowed: auth access granted
by auth(=xd)
Apr 1 23:44:04 flip slapd[9255]: => access_allowed: auth access granted by
auth(=xd)
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=1 BIND
dn="uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net" mech=SIMPLE
ssf=0
Apr 1 23:44:04 flip slapd[9255]: do_bind: v3 bind:
"uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net" to
"uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net"
Apr 1 23:44:04 flip slapd[9255]: send_ldap_result: conn=1378 op=1 p=3
Apr 1 23:44:04 flip slapd[9255]: send_ldap_result: err=0 matched="" text=""
Apr 1 23:44:04 flip slapd[9255]: send_ldap_response: msgid=2 tag=97 err=0
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on 1 descriptor
Apr 1 23:44:04 flip slapd[9255]: daemon: activity on:
Apr 1 23:44:04 flip slapd[9255]: 54r
Apr 1 23:44:04 flip slapd[9255]:
Apr 1 23:44:04 flip slapd[9255]: daemon: read active on 54
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=8 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=9 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=10 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: daemon: epoll: listen=11 active_threads=0
tvp=zero
Apr 1 23:44:04 flip slapd[9255]: connection_get(54)
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=1 RESULT tag=97 err=0 text=
Apr 1 23:44:04 flip slapd[9255]: connection_get(54): got connid=1378
Apr 1 23:44:04 flip slapd[9255]: connection_read(54): checking for input on
id=1378
Apr 1 23:44:04 flip slapd[9255]: op tag 0x68, time 1333338244
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=2 do_add
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=2 do_add: dn
(cn=test_group,ou=general,ou=groups,dc=example,dc=net)
Apr 1 23:44:04 flip slapd[9255]: >>> dnPrettyNormal:
<cn=test_group,ou=general,ou=groups,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]: <<< dnPrettyNormal:
<cn=test_group,ou=general,ou=groups,dc=example,dc=net>,
<cn=test_group,ou=general,ou=groups,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]: conn=1378 op=2 ADD
dn="cn=test_group,ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 flip slapd[9255]: >>> dnPretty:
<uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]: <<< dnPretty:
<uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]: >>> dnNormalize:
<uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]: <<< dnNormalize:
<uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]:
bdb_dn2entry("cn=test_group,ou=general,ou=groups,dc=example,dc=net")
Apr 1 23:44:04 flip slapd[9255]: =>
hdb_dn2id("cn=test_group,ou=general,ou=groups,dc=example,dc=net")
Apr 1 23:44:04 flip slapd[9255]: <= hdb_dn2id: get failed: DB_NOTFOUND: No
matching key/data pair found (-30988)
Apr 1 23:44:04 flip slapd[9255]: hdb_referrals: tag=104
target="cn=test_group,ou=general,ou=groups,dc=example,dc=net"
matched="ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 flip slapd[9255]: ==> unique_add
<cn=test_group,ou=general,ou=groups,dc=example,dc=net>
Apr 1 23:44:04 flip slapd[9255]: ==> hdb_add:
cn=test_group,ou=general,ou=groups,dc=example,dc=net
Apr 1 23:44:04 flip slapd[9255]: oc_check_required entry
(cn=test_group,ou=general,ou=groups,dc=example,dc=net), objectClass
"groupOfNames"
Apr 1 23:44:04 flip slapd[9255]: oc_check_allowed type "objectClass"
Apr 1 23:44:04 flip slapd[9255]: oc_check_allowed type "description"
Apr 1 23:44:04 flip slapd[9255]: oc_check_allowed type "cn"
Apr 1 23:44:04 flip slapd[9255]: oc_check_allowed type "member"
Apr 1 23:44:04 flip slapd[9255]: oc_check_allowed type "structuralObjectClass"
Apr 1 23:44:04 flip slapd[9255]: slap_queue_csn: queing 0xb2fcd8ce
20120402034404.808333Z#000000#000#000000
Apr 1 23:44:04 flip slapd[9255]:
bdb_dn2entry("cn=test_group,ou=general,ou=groups,dc=example,dc=net")
Apr 1 23:44:04 flip slapd[9255]: =>
hdb_dn2id("cn=test_group,ou=general,ou=groups,dc=example,dc=net")
Apr 1 23:44:04 flip slapd[9255]: <= hdb_dn2id: get failed: DB_NOTFOUND: No
matching key/data pair found (-30988)
Apr 1 23:44:04 flip slapd[9255]: => access_allowed: add access to
"ou=general,ou=groups,dc=example,dc=net" "children" requested
Apr 1 23:44:04 flip slapd[9255]: <= root access granted
Apr 1 23:44:04 flip slapd[9255]: => access_allowed: add access granted by
manage(=mwrscxd)
Apr 1 23:44:04 flip slapd[9255]: => access_allowed: add access to
"cn=test_group,ou=general,ou=groups,dc=example,dc=net" "entry" requested
Apr 1 23:44:04 flip slapd[9255]: <= root access granted
Apr 1 23:44:04 flip slapd[9255]: => access_allowed: add access granted by
manage(=mwrscxd)
Apr 1 23:44:04 flip slapd[9255]: => hdb_dn2id_add 0xe8:
"cn=test_group,ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 flip slapd[9255]: <= hdb_dn2id_add 0xe8: 0
Apr 1 23:44:04 flip slapd[9255]: => index_entry_add( 232,
"cn=test_group,ou=general,ou=groups,dc=example,dc=net" )
Apr 1 23:44:04 flip slapd[9255]: => key_change(ADD,e8)
Apr 1 23:44:04 flip slapd[9255]: bdb_idl_insert_key: e8 [0096defd]
Apr 1 23:44:04 flip slapd[9255]: <= key_change 0
Apr 1 23:44:04 flip slapd[9255]: => key_change(ADD,e8)
Apr 1 23:44:04 flip slapd[9255]: bdb_idl_insert_key: e8 [c14a3e76]
Apr 1 23:44:04 flip slapd[9255]: <= key_change 0
Apr 1 23:44:04 flip slapd[9255]: => key_change(ADD,e8)
Apr 1 23:44:04 flip slapd[9255]: bdb_idl_insert_key: e8 [943e86da]
Apr 1 23:44:04 flip slapd[9255]: <= key_change 0
Apr 1 23:44:04 flip slapd[9255]: => key_change(ADD,e8)
Apr 1 23:44:04 flip slapd[9255]: bdb_idl_insert_key: e8 [c866ab14]
Apr 1 23:44:04 flip slapd[9255]: <= key_change 0
consumer log entries:
Apr 1 23:44:04 exo slapd[8007]: daemon: activity on 1 descriptor
Apr 1 23:44:04 exo slapd[8007]: daemon: activity on:
Apr 1 23:44:04 exo slapd[8007]: 12r
Apr 1 23:44:04 exo slapd[8007]:
Apr 1 23:44:04 exo slapd[8007]: daemon: read active on 12
Apr 1 23:44:04 exo slapd[8007]: daemon: epoll: listen=8 active_threads=0
tvp=NULL
Apr 1 23:44:04 exo slapd[8007]: daemon: epoll: listen=9 active_threads=0
tvp=NULL
Apr 1 23:44:04 exo slapd[8007]: connection_get(12)
Apr 1 23:44:04 exo slapd[8007]: connection_get(12): got connid=0
Apr 1 23:44:04 exo slapd[8007]: =>do_syncrepl rid=000
Apr 1 23:44:04 exo slapd[8007]: =>do_syncrep2 rid=000
Apr 1 23:44:04 exo slapd[8007]: do_syncrep2: rid=000
cookie=rid=000,csn=20120402034404.869559Z#000000#000#000000
Apr 1 23:44:04 exo slapd[8007]: >>> dnPrettyNormal:
<uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: <<< dnPrettyNormal:
<uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net>,
<uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: slap_queue_csn: queing 0x7f6bd2694710
20120402034404.869559Z#000000#000#000000
Apr 1 23:44:04 exo slapd[8007]: >>> dnPretty:
<cn=test_group,ou=general,ou=groups,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: <<< dnPretty:
<cn=test_group,ou=general,ou=groups,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: >>> dnNormalize:
<cn=test_group,ou=general,ou=groups,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: <<< dnNormalize:
<cn=test_group,ou=general,ou=groups,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: >>> dnPretty:
<uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: <<< dnPretty:
<uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: >>> dnNormalize:
<uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: <<< dnNormalize:
<uid=dit_admin,ou=role_accounts,ou=accounts,dc=example,dc=net>
Apr 1 23:44:04 exo slapd[8007]: => bdb_entry_get: ndn:
"uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: => bdb_entry_get: oc: "(null)", at: "(null)"
Apr 1 23:44:04 exo slapd[8007]:
bdb_dn2entry("uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net")
Apr 1 23:44:04 exo slapd[8007]: => bdb_entry_get: found entry:
"uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: bdb_entry_get: rc=0
Apr 1 23:44:04 exo slapd[8007]: hdb_modify:
uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net
Apr 1 23:44:04 exo slapd[8007]:
bdb_dn2entry("uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net")
Apr 1 23:44:04 exo slapd[8007]: bdb_modify_internal: 0x0000001a:
uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net
Apr 1 23:44:04 exo slapd[8007]: <= acl_access_allowed: granted to database root
Apr 1 23:44:04 exo slapd[8007]: bdb_modify_internal: add memberOf
Apr 1 23:44:04 exo slapd[8007]: dnMatch
17#012#011"cn=dummy_default,ou=dummy_groups,ou=other,ou=groups,dc=example,dc=net"#012#011"cn=test_group,ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: dnMatch
-19#012#011"cn=all_people,ou=general,ou=groups,dc=example,dc=net"#012#011"cn=test_group,ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: dnMatch
2#012#011"cn=docs,ou=flip,ou=servers,ou=groups,dc=example,dc=net"#012#011"cn=test_group,ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: dnMatch
5#012#011"cn=monitor,ou=flip,ou=servers,ou=groups,dc=example,dc=net"#012#011"cn=test_group,ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: dnMatch
5#012#011"cn=systems,ou=flip,ou=servers,ou=groups,dc=example,dc=net"#012#011"cn=test_group,ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: dnMatch
14#012#011"cn=mail_submitters-non_auth,ou=general,ou=groups,dc=example,dc=net"#012#011"cn=test_group,ou=general,ou=groups,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: bdb_modify_internal: replace modifiersName
Apr 1 23:44:04 exo slapd[8007]: bdb_modify_internal: replace entryCSN
Apr 1 23:44:04 exo slapd[8007]: bdb_modify_internal: replace modifyTimestamp
Apr 1 23:44:04 exo slapd[8007]: oc_check_required entry
(uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net),
objectClass "inetOrgPerson"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "uid"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "objectClass"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "sn"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "cn"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "structuralObjectClass"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "entryUUID"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "creatorsName"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "createTimestamp"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "memberOf"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "modifiersName"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "entryCSN"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "modifyTimestamp"
Apr 1 23:44:04 exo slapd[8007]: => key_change(DELETE,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_delete_key: 1a
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => key_change(ADD,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_insert_key: 1a [768b75dc]
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => key_change(ADD,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_insert_key: 1a [7f0c99d1]
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => key_change(ADD,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_insert_key: 1a [0f345f5f]
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => key_change(ADD,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_insert_key: 1a [86c8d479]
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => key_change(ADD,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_insert_key: 1a [da3d54f3]
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => key_change(ADD,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_insert_key: 1a [8d6b497f]
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => key_change(ADD,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_insert_key: 1a [144a6a9d]
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => key_change(ADD,1a)
Apr 1 23:44:04 exo slapd[8007]: bdb_idl_insert_key: 1a
Apr 1 23:44:04 exo slapd[8007]: <= key_change 0
Apr 1 23:44:04 exo slapd[8007]: => entry_encode(0x0000001a):
Apr 1 23:44:04 exo slapd[8007]: <= entry_encode(0x0000001a):
Apr 1 23:44:04 exo slapd[8007]: hdb_modify: updated id=0000001a
dn="uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: send_ldap_result: conn=-1 op=0 p=0
Apr 1 23:44:04 exo slapd[8007]: send_ldap_result: err=0 matched="" text=""
Apr 1 23:44:04 exo slapd[8007]: slap_graduate_commit_csn: removing
0x7f6bd267ec10 20120402034404.869559Z#000000#000#000000
Apr 1 23:44:04 exo slapd[8007]: syncrepl_message_to_op: rid=000 be_modify
uid=dummy_default,ou=dummy_accounts,ou=other,ou=accounts,dc=example,dc=net (0)
Apr 1 23:44:04 exo slapd[8007]: slap_queue_csn: queing 0x7f6bd268f8c0
20120402034404.869559Z#000000#000#000000
Apr 1 23:44:04 exo slapd[8007]: => bdb_entry_get: ndn: "dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: => bdb_entry_get: oc: "(null)", at: "(null)"
Apr 1 23:44:04 exo slapd[8007]: bdb_dn2entry("dc=example,dc=net")
Apr 1 23:44:04 exo slapd[8007]: => bdb_entry_get: found entry:
"dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: bdb_entry_get: rc=0
Apr 1 23:44:04 exo slapd[8007]: hdb_modify: dc=example,dc=net
Apr 1 23:44:04 exo slapd[8007]: bdb_dn2entry("dc=example,dc=net")
Apr 1 23:44:04 exo slapd[8007]: bdb_modify_internal: 0x00000001:
dc=example,dc=net
Apr 1 23:44:04 exo slapd[8007]: <= acl_access_allowed: granted to database root
Apr 1 23:44:04 exo slapd[8007]: bdb_modify_internal: replace contextCSN
Apr 1 23:44:04 exo slapd[8007]: oc_check_required entry (dc=example,dc=net),
objectClass "organization"
Apr 1 23:44:04 exo slapd[8007]: oc_check_required entry (dc=example,dc=net),
objectClass "dcObject"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "dc"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "objectClass"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "o"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "structuralObjectClass"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "entryUUID"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "creatorsName"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "createTimestamp"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "l"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "st"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "postalCode"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "entryCSN"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "modifiersName"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "modifyTimestamp"
Apr 1 23:44:04 exo slapd[8007]: oc_check_allowed type "contextCSN"
Apr 1 23:44:04 exo slapd[8007]: => entry_encode(0x00000001):
Apr 1 23:44:04 exo slapd[8007]: <= entry_encode(0x00000001):
Apr 1 23:44:04 exo slapd[8007]: hdb_modify: updated id=00000001
dn="dc=example,dc=net"
Apr 1 23:44:04 exo slapd[8007]: send_ldap_result: conn=-1 op=0 p=0
Apr 1 23:44:04 exo slapd[8007]: send_ldap_result: err=0 matched="" text=""
Apr 1 23:44:04 exo slapd[8007]: slap_graduate_commit_csn: removing
0x7f6bd2674970 20120402034404.869559Z#000000#000#000000
Apr 1 23:44:04 exo slapd[8007]: daemon: activity on 1 descriptor
Apr 1 23:44:04 exo slapd[8007]: daemon: activity on:
Apr 1 23:44:04 exo slapd[8007]:
Apr 1 23:44:04 exo slapd[8007]: daemon: epoll: listen=8 active_threads=0
tvp=NULL
Apr 1 23:44:04 exo slapd[8007]: daemon: epoll: listen=9 active_threads=0
tvp=NULL
