[Date Prev][Date Next]
[Chronological]
[Thread]
[Top]
memberOf-overlay and custom schema (slapd.logs attched)
- To: openldap-technical@openldap.org
- Subject: memberOf-overlay and custom schema (slapd.logs attched)
- From: Dora Paula <deepee@gmx.net>
- Date: Tue, 1 Dec 2015 20:37:05 +0100
- User-agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:38.0) Gecko/20100101 Thunderbird/38.3.0
Hi,
I want to have some kind of dn-based association between two custom
entries (e.g. account<--dn-->device). Therefore I use a combination of
the memberOf- and refint overlay (configuration inspired by test057).
I use a custom schema:
An auxiliary objectClass called "association". It has only one
may-attribute called "associate".
The custom 'memberOf'-Attribute is called "associationOf", which is a
may-attribute of the objectClass "myAccount" (derived from account):
OpenLDAP Version: git OPENLDAP_REL_ENG_2_4
#example.com.schema
#
attributeType ( 1.3.6.1.4.1.9999999.10.1.1 NAME 'associate'
DESC 'an associate' SUP distinguishedName )
attributeType ( 1.3.6.1.4.1.9999999.10.1.2 NAME 'associateOf'
DESC 'associate of' SUP distinguishedName )
objectClass ( 1.3.6.1.4.1.9999999.10.2.1 NAME 'association'
DESC 'association'
SUP top
AUXILIARY
MAY ( associate ) )
objectClass ( 1.3.6.1.4.1.9999999.10.2.2 NAME 'myAccount'
DESC 'my account'
SUP account
STRUCTURAL
MAY ( associateOf ) )
#slapd.conf
#
include /opt/slapd_example.com/etc/openldap/schema/core.schema
include /opt/slapd_example.com/etc/openldap/schema/cosine.schema
include /opt/slapd_example.com/etc/openldap/schema/ppolicy.schema
include /opt/slapd_example.com/etc/example.com.schema
pidfile /opt/slapd_example.com/var/run/slapd.pid
argsfile /opt/slapd_example.com/var/run/slapd.args
loglevel none
password-hash {CLEARTEXT}
attributeOptions lang-
access to dn.base="" by * read
access to dn.base="cn=Subschema" by * read
database config
rootdn "cn=admin,dc=example,dc=com"
database mdb
suffix "dc=example,dc=com"
rootdn "cn=admin,dc=example,dc=com"
rootpw "admin"
directory /opt/slapd_example.com/var/openldap-data
index objectClass,cn,ou,o,dc eq
add_content_acl on
overlay memberof
memberof-group-oc association
memberof-member-ad associate
memberof-memberof-ad associateOf
memberof-dangling ignore
memberof-refint FALSE
memberof-dn olcOverlay={1}memberof,olcDatabase={2}mdb,cn=config
overlay refint
refint_attributes associate associateOf
refint_nothing olcOverlay={0}refint,olcDatabase={2}mdb,cn=config
refint_modifiersname olcOverlay={0}refint,olcDatabase={2}mdb,cn=config
#After starting the server, first I add a custom account:
#
cat << EOF | ldapmodify -x -H "ldap://localhost:2389/"; -D
"cn=admin,dc=example,dc=com" -w admin
dn: uid=myAccount,dc=example,dc=com
changetype: add
objectClass: top
objectClass: myAccount
uid: myAccount
EOF
#Then I add a naked device:
#
cat << EOF | ldapmodify -x -H "ldap://localhost:2389/"; -D
"cn=admin,dc=example,dc=com" -w admin
dn: cn=device,dc=example,dc=com
changetype: add
objectClass: top
objectClass: device
cn: device
EOF
Now I try to associate them using a single modify operation (adding the
aux-objectClass association and the associate attribute).
This approach fails, that means: The operation passes without error, but
the account's associationOf attribute isn't added.
#This modify operation seems to be ignored by memberOf-overlay:
#
cat << EOF | ldapmodify -x -H "ldap://localhost:2389/"; -D
"cn=admin,dc=example,dc=com" -w admin
dn: cn=device,dc=example,dc=com
changetype: modify
add: objectClass
objectClass: association
-
add: associate
associate: uid=myAccount,dc=example,dc=com
-
EOF
#slapd-log (loglevel -1), see attached all-inclusive-modify.log
#Checking the results:
#
ldapsearch -x -H "ldap://localhost:2389/"; -D
"cn=admin,dc=example,dc=com" -w admin -b "dc=example,dc=com" -s one
'(objectClass=*)'
# extended LDIF
#
# LDAPv3
# base <dc=example,dc=com> with scope oneLevel
# filter: (objectClass=*)
# requesting: ALL
#
# device, example.com
dn: cn=device,dc=example,dc=com
objectClass: top
objectClass: device
objectClass: association
cn: device
associate: uid=myAccount,dc=example,dc=com
# myAccount, example.com
dn: uid=myAccount,dc=example,dc=com
objectClass: top
objectClass: myAccount
uid: myAccount
# search result
search: 2
result: 0 Success
# numResponses: 3
# numEntries: 2
The second approach (resetting the device and myAccount entry and split
the above modify operation into two operations works fine. Details see here:
#Splitting the previous modify operation into two works fine:
#
cat << EOF | ldapmodify -x -H "ldap://localhost:2389/"; -D
"cn=admin,dc=example,dc=com" -w admin
dn: cn=device,dc=example,dc=com
changetype: modify
add: objectClass
objectClass: association
EOF
cat << EOF | ldapmodify -x -H "ldap://localhost:2389/"; -D
"cn=admin,dc=example,dc=com" -w admin
dn: cn=device,dc=example,dc=com
changetype: modify
add: associate
associate: uid=myAccount,dc=example,dc=com
EOF
#slapd-log (loglevel -1), see attached split-modify.log
#Checking the results:
#
ldapsearch -x -H "ldap://localhost:2389/"; -D
"cn=admin,dc=example,dc=com" -w admin -b "dc=example,dc=com" -s one
'(objectClass=*)'
# extended LDIF
#
# LDAPv3
# base <dc=example,dc=com> with scope oneLevel
# filter: (objectClass=*)
# requesting: ALL
#
# device, example.com
dn: cn=device,dc=example,dc=com
objectClass: top
objectClass: device
objectClass: association
cn: device
associate: uid=myAccount,dc=example,dc=com
# myAccount, example.com
dn: uid=myAccount,dc=example,dc=com
objectClass: top
objectClass: myAccount
uid: myAccount
associateOf: cn=device,dc=example,dc=com
# search result
search: 2
result: 0 Success
# numResponses: 3
# numEntries: 2
Long story, short question:
Is this the intended behaviour? If not is it caused by a
misconfiguration or a bug?
Thank you very much for your help!
Best regards
Dora
565de6bb daemon: activity on 1 descriptor
565de6bb daemon: activity on:
565de6bb slap_listener_activate(8):
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de6bb daemon: epoll: listen=8 busy
565de6bb >>> slap_listener(ldap://[::]:2389/)
565de6bb daemon: listen=8, new connection on 13
565de6bb daemon: activity on 1 descriptor
565de6bb daemon: activity on:
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de6bb daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de6bb daemon: added 13r (active) listener=(nil)
565de6bb daemon: activity on 1 descriptor
565de6bb conn=1001 fd=13 ACCEPT from IP=[::1]:59144 (IP=[::]:2389)
565de6bb daemon: activity on: 13r
565de6bb daemon: read active on 13
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de6bb daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de6bb connection_get(13)
565de6bb connection_get(13): got connid=1001
565de6bb connection_read(13): checking for input on id=1001
ber_get_next
ldap_read: want=8, got=8
0000: 30 2b 02 01 01 60 26 02 0+...`&.
ldap_read: want=37, got=37
0000: 01 03 04 1a 63 6e 3d 61 64 6d 69 6e 2c 64 63 3d ....cn=admin,dc=
0010: 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 80 05 example,dc=com..
0020: 61 64 6d 69 6e admin
ber_get_next: tag 0x30 len 43 contents:
565de6bb daemon: activity on 1 descriptor
565de6bb daemon: activity on:
ber_dump: buf=0x7f5978104950 ptr=0x7f5978104950 end=0x7f597810497b len=43
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de6bb daemon: epoll: listen=8 active_threads=0 tvp=NULL
0000: 02 01 01 60 26 02 01 03 04 1a 63 6e 3d 61 64 6d ...`&.....cn=adm
0010: 69 6e 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 in,dc=example,dc
0020: 3d 63 6f 6d 80 05 61 64 6d 69 6e =com..admin
565de6bb op tag 0x60, time 1448994491
ber_get_next
ldap_read: want=8 error=Unknown error
565de6bb conn=1001 op=0 do_bind
ber_scanf fmt ({imt) ber:
ber_dump: buf=0x7f5978104950 ptr=0x7f5978104953 end=0x7f597810497b len=40
0000: 60 26 02 01 03 04 1a 63 6e 3d 61 64 6d 69 6e 2c `&.....cn=admin,
0010: 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f dc=example,dc=co
0020: 6d 80 05 61 64 6d 69 6e m..admin
ber_scanf fmt (m}) ber:
ber_dump: buf=0x7f5978104950 ptr=0x7f5978104974 end=0x7f597810497b len=7
0000: 00 05 61 64 6d 69 6e ..admin
565de6bb >>> dnPrettyNormal: <cn=admin,dc=example,dc=com>
=> ldap_bv2dn(cn=admin,dc=example,dc=com,0)
<= ldap_bv2dn(cn=admin,dc=example,dc=com)=0
565de6bb daemon: activity on 1 descriptor
565de6bb daemon: activity on:
=> ldap_dn2bv(272)
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
<= ldap_dn2bv(cn=admin,dc=example,dc=com)=0
565de6bb daemon: epoll: listen=8 active_threads=0 tvp=NULL
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=admin,dc=example,dc=com)=0
565de6bb <<< dnPrettyNormal: <cn=admin,dc=example,dc=com>, <cn=admin,dc=example,dc=com>
565de6bb conn=1001 op=0 BIND dn="cn=admin,dc=example,dc=com" method=128
565de6bb do_bind: version=3 dn="cn=admin,dc=example,dc=com" method=128
565de6bb ==> mdb_bind: dn: cn=admin,dc=example,dc=com
565de6bb conn=1001 op=0 BIND dn="cn=admin,dc=example,dc=com" mech=SIMPLE ssf=0
565de6bb do_bind: v3 bind: "cn=admin,dc=example,dc=com" to "cn=admin,dc=example,dc=com"
565de6bb send_ldap_result: conn=1001 op=0 p=3
565de6bb send_ldap_result: err=0 matched="" text=""
565de6bb send_ldap_response: msgid=1 tag=97 err=0
ber_flush2: 14 bytes to sd 13
0000: 30 0c 02 01 01 61 07 0a 01 00 04 00 04 00 0....a........
ldap_write: want=14, written=14
0000: 30 0c 02 01 01 61 07 0a 01 00 04 00 04 00 0....a........
565de6bb conn=1001 op=0 RESULT tag=97 err=0 text=
565de6bb daemon: activity on 1 descriptor
565de6bb daemon: activity on: 13r
565de6bb daemon: read active on 13
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de6bb daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de6bb connection_get(13)
565de6bb connection_get(13): got connid=1001
565de6bb connection_read(13): checking for input on id=1001
ber_get_next
ldap_read: want=8, got=8
0000: 30 7c 02 01 02 66 77 04 0|...fw.
ldap_read: want=118, got=118
0000: 1b 63 6e 3d 64 65 76 69 63 65 2c 64 63 3d 65 78 .cn=device,dc=ex
0010: 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 30 58 30 21 ample,dc=com0X0!
0020: 0a 01 00 30 1c 04 0b 6f 62 6a 65 63 74 43 6c 61 ...0...objectCla
0030: 73 73 31 0d 04 0b 61 73 73 6f 63 69 61 74 69 6f ss1...associatio
0040: 6e 30 33 0a 01 00 30 2e 04 09 61 73 73 6f 63 69 n03...0...associ
0050: 61 74 65 31 21 04 1f 75 69 64 3d 6d 79 41 63 63 ate1!..uid=myAcc
0060: 6f 75 6e 74 2c 64 63 3d 65 78 61 6d 70 6c 65 2c ount,dc=example,
0070: 64 63 3d 63 6f 6d dc=com
ber_get_next: tag 0x30 len 124 contents:
ber_dump: buf=0x7f59781066d0 ptr=0x7f59781066d0 end=0x7f597810674c len=124
0000: 02 01 02 66 77 04 1b 63 6e 3d 64 65 76 69 63 65 ...fw..cn=device
0010: 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 ,dc=example,dc=c
0020: 6f 6d 30 58 30 21 0a 01 00 30 1c 04 0b 6f 62 6a om0X0!...0...obj
0030: 65 63 74 43 6c 61 73 73 31 0d 04 0b 61 73 73 6f ectClass1...asso
0040: 63 69 61 74 69 6f 6e 30 33 0a 01 00 30 2e 04 09 ciation03...0...
0050: 61 73 73 6f 63 69 61 74 65 31 21 04 1f 75 69 64 associate1!..uid
0060: 3d 6d 79 41 63 63 6f 75 6e 74 2c 64 63 3d 65 78 =myAccount,dc=ex
0070: 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d ample,dc=com
565de6bb op tag 0x66, time 1448994491
ber_get_next
ldap_read: want=8 error=Unknown error
565de6bb conn=1001 op=1 do_modify
ber_scanf fmt ({m) ber:
ber_dump: buf=0x7f59781066d0 ptr=0x7f59781066d3 end=0x7f597810674c len=121
0000: 66 77 04 1b 63 6e 3d 64 65 76 69 63 65 2c 64 63 fw..cn=device,dc
0010: 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 30 =example,dc=com0
0020: 58 30 21 0a 01 00 30 1c 04 0b 6f 62 6a 65 63 74 X0!...0...object
0030: 43 6c 61 73 73 31 0d 04 0b 61 73 73 6f 63 69 61 Class1...associa
0040: 74 69 6f 6e 30 33 0a 01 00 30 2e 04 09 61 73 73 tion03...0...ass
0050: 6f 63 69 61 74 65 31 21 04 1f 75 69 64 3d 6d 79 ociate1!..uid=my
0060: 41 63 63 6f 75 6e 74 2c 64 63 3d 65 78 61 6d 70 Account,dc=examp
0070: 6c 65 2c 64 63 3d 63 6f 6d le,dc=com
565de6bb conn=1001 op=1 do_modify: dn (cn=device,dc=example,dc=com)
ber_scanf fmt ({e{m[W]}}) ber:
ber_dump: buf=0x7f59781066d0 ptr=0x7f59781066f4 end=0x7f597810674c len=88
0000: 30 21 0a 01 00 30 1c 04 0b 6f 62 6a 65 63 74 43 0!...0...objectC
565de6bb daemon: activity on 1 descriptor
565de6bb daemon: activity on:
0010: 6c 61 73 73 31 0d 04 0b 61 73 73 6f 63 69 61 74 lass1...associat
0020: 69 6f 6e 30 33 0a 01 00 30 2e 04 09 61 73 73 6f ion03...0...asso
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de6bb daemon: epoll: listen=8 active_threads=0 tvp=NULL
0030: 63 69 61 74 65 31 21 04 1f 75 69 64 3d 6d 79 41 ciate1!..uid=myA
0040: 63 63 6f 75 6e 74 2c 64 63 3d 65 78 61 6d 70 6c ccount,dc=exampl
0050: 65 2c 64 63 3d 63 6f 6d e,dc=com
ber_scanf fmt ({e{m[W]}}) ber:
ber_dump: buf=0x7f59781066d0 ptr=0x7f5978106717 end=0x7f597810674c len=53
0000: 30 33 0a 01 00 30 2e 04 09 61 73 73 6f 63 69 61 03...0...associa
0010: 74 65 31 21 04 1f 75 69 64 3d 6d 79 41 63 63 6f te1!..uid=myAcco
0020: 75 6e 74 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 unt,dc=example,d
0030: 63 3d 63 6f 6d c=com
565de6bb >>> dnPrettyNormal: <cn=device,dc=example,dc=com>
=> ldap_bv2dn(cn=device,dc=example,dc=com,0)
<= ldap_bv2dn(cn=device,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=device,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=device,dc=example,dc=com)=0
565de6bb <<< dnPrettyNormal: <cn=device,dc=example,dc=com>, <cn=device,dc=example,dc=com>
565de6bb conn=1001 op=1 modifications:
565de6bb add: objectClass
565de6bb one value, length 11
565de6bb add: associate
565de6bb one value, length 31
565de6bb conn=1001 op=1 MOD dn="cn=device,dc=example,dc=com"
565de6bb conn=1001 op=1 MOD attr=objectClass associate
565de6bb >>> dnPretty: <uid=myAccount,dc=example,dc=com>
=> ldap_bv2dn(uid=myAccount,dc=example,dc=com,0)
<= ldap_bv2dn(uid=myAccount,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(uid=myAccount,dc=example,dc=com)=0
565de6bb <<< dnPretty: <uid=myAccount,dc=example,dc=com>
565de6bb >>> dnNormalize: <uid=myAccount,dc=example,dc=com>
=> ldap_bv2dn(uid=myAccount,dc=example,dc=com,0)
<= ldap_bv2dn(uid=myAccount,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(uid=myaccount,dc=example,dc=com)=0
565de6bb <<< dnNormalize: <uid=myaccount,dc=example,dc=com>
565de6bb => mdb_search
565de6bb mdb_dn2entry("cn=device,dc=example,dc=com")
565de6bb => mdb_dn2id("cn=device,dc=example,dc=com")
565de6bb <= mdb_dn2id: got id=0x3
565de6bb => mdb_entry_decode:
565de6bb <= mdb_entry_decode
565de6bb => access_allowed: search access to "cn=device,dc=example,dc=com" "entry" requested
565de6bb <= root access granted
565de6bb => access_allowed: search access granted by manage(=mwrscxd)
565de6bb base_candidates: base: "cn=device,dc=example,dc=com" (0x00000003)
565de6bb => test_filter
565de6bb EQUALITY
565de6bb => access_allowed: search access to "cn=device,dc=example,dc=com" "objectClass" requested
565de6bb <= root access granted
565de6bb => access_allowed: search access granted by manage(=mwrscxd)
565de6bb <= test_filter 5
565de6bb mdb_search: 3 does not match filter
565de6bb send_ldap_result: conn=1001 op=1 p=3
565de6bb send_ldap_result: err=0 matched="" text=""
565de6bb mdb_modify: cn=device,dc=example,dc=com
565de6bb slap_queue_csn: queueing 0x7f59781048d0 20151201182811.570849Z#000000#000#000000
565de6bb mdb_dn2entry("cn=device,dc=example,dc=com")
565de6bb => mdb_dn2id("cn=device,dc=example,dc=com")
565de6bb <= mdb_dn2id: got id=0x3
565de6bb => mdb_entry_decode:
565de6bb <= mdb_entry_decode
565de6bb mdb_modify_internal: 0x00000003: cn=device,dc=example,dc=com
565de6bb <= acl_access_allowed: granted to database root
565de6bb mdb_modify_internal: add objectClass
565de6bb mdb_modify_internal: add associate
565de6bb mdb_modify_internal: replace entryCSN
565de6bb mdb_modify_internal: replace modifiersName
565de6bb mdb_modify_internal: replace modifyTimestamp
565de6bb oc_check_required entry (cn=device,dc=example,dc=com), objectClass "device"
565de6bb oc_check_required entry (cn=device,dc=example,dc=com), objectClass "association"
565de6bb oc_check_allowed type "objectClass"
565de6bb oc_check_allowed type "cn"
565de6bb oc_check_allowed type "structuralObjectClass"
565de6bb oc_check_allowed type "entryUUID"
565de6bb oc_check_allowed type "creatorsName"
565de6bb oc_check_allowed type "createTimestamp"
565de6bb oc_check_allowed type "associate"
565de6bb oc_check_allowed type "entryCSN"
565de6bb oc_check_allowed type "modifiersName"
565de6bb oc_check_allowed type "modifyTimestamp"
565de6bb mdb_idl_insert_keys: 3 [602d1f90]
565de6bb => mdb_entry_encode(0x00000003): cn=device,dc=example,dc=com
565de6bb <= mdb_entry_encode(0x00000003): cn=device,dc=example,dc=com
565de6bb mdb_modify: updated id=00000003 dn="cn=device,dc=example,dc=com"
565de6bb send_ldap_result: conn=1001 op=1 p=3
565de6bb send_ldap_result: err=0 matched="" text=""
565de6bb send_ldap_response: msgid=2 tag=103 err=0
ber_flush2: 14 bytes to sd 13
0000: 30 0c 02 01 02 67 07 0a 01 00 04 00 04 00 0....g........
ldap_write: want=14, written=14
0000: 30 0c 02 01 02 67 07 0a 01 00 04 00 04 00 0....g........
565de6bb conn=1001 op=1 RESULT tag=103 err=0 text=
565de6bb slap_graduate_commit_csn: removing 0x7f59781048d0 20151201182811.570849Z#000000#000#000000
565de6bb daemon: activity on 1 descriptor
565de6bb daemon: activity on: 13r
565de6bb daemon: read active on 13
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de6bb daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de6bb connection_get(13)
565de6bb connection_get(13): got connid=1001
565de6bb connection_read(13): checking for input on id=1001
ber_get_next
ldap_read: want=8, got=7
0000: 30 05 02 01 03 42 00 0....B.
ber_get_next: tag 0x30 len 5 contents:
ber_dump: buf=0x7f5978104750 ptr=0x7f5978104750 end=0x7f5978104755 len=5
0000: 02 01 03 42 00 ...B.
565de6bb op tag 0x42, time 1448994491
ber_get_next
ldap_read: want=8, got=0
565de6bb ber_get_next on fd 13 failed errno=0 (Success)
565de6bb connection_read(13): input error=-2 id=1001, closing.
565de6bb connection_closing: readying conn=1001 sd=13 for close
565de6bb connection_close: deferring conn=1001 sd=13
565de6bb conn=1001 op=2 do_unbind
565de6bb conn=1001 op=2 UNBIND
565de6bb connection_resched: attempting closing conn=1001 sd=13
565de6bb connection_close: conn=1001 sd=13
565de6bb daemon: activity on 1 descriptor
565de6bb daemon: activity on:
565de6bb daemon: removing 13
565de6bb conn=1001 fd=13 closed
565de6bb daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de6bb daemon: epoll: listen=8 active_threads=0 tvp=NULL
#--------Log (level -1)--------
565de72b daemon: activity on 1 descriptor
565de72b daemon: activity on:
565de72b slap_listener_activate(8):
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 busy
565de72b >>> slap_listener(ldap://[::]:2389/)
565de72b daemon: activity on 1 descriptor
565de72b daemon: listen=8, new connection on 13
565de72b daemon: activity on:
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72b daemon: added 13r (active) listener=(nil)
565de72b daemon: activity on 1 descriptor
565de72b daemon: activity on: 13r565de72b
565de72b daemon: read active on 13
conn=1002 fd=13 ACCEPT from IP=[::1]:59146 (IP=[::]:2389)
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72b daemon: activity on 1 descriptor
565de72b daemon: activity on:
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72b connection_get(13)
565de72b connection_get(13): got connid=1002
565de72b connection_read(13): checking for input on id=1002
ber_get_next
ldap_read: want=8, got=8
0000: 30 2b 02 01 01 60 26 02 0+...`&.
ldap_read: want=37, got=37
0000: 01 03 04 1a 63 6e 3d 61 64 6d 69 6e 2c 64 63 3d ....cn=admin,dc=
0010: 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 80 05 example,dc=com..
0020: 61 64 6d 69 6e admin
ber_get_next: tag 0x30 len 43 contents:
ber_dump: buf=0x7f5978106870 ptr=0x7f5978106870 end=0x7f597810689b len=43
0000: 02 01 01 60 26 02 01 03 04 1a 63 6e 3d 61 64 6d ...`&.....cn=adm
0010: 69 6e 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 in,dc=example,dc
0020: 3d 63 6f 6d 80 05 61 64 6d 69 6e =com..admin
565de72b op tag 0x60, time 1448994603
ber_get_next
ldap_read: want=8 error=Unknown error
565de72b conn=1002 op=0 do_bind
ber_scanf fmt ({imt) ber:
ber_dump: buf=0x7f5978106870 ptr=0x7f5978106873 end=0x7f597810689b len=40
0000: 60 26 02 01 03 04 1a 63 6e 3d 61 64 6d 69 6e 2c `&.....cn=admin,
0010: 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f dc=example,dc=co
0020: 6d 80 05 61 64 6d 69 6e m..admin
ber_scanf fmt (m}) ber:
ber_dump: buf=0x7f5978106870 ptr=0x7f5978106894 end=0x7f597810689b len=7
0000: 00 05 61 64 6d 69 6e ..admin
565de72b daemon: activity on 1 descriptor
565de72b daemon: activity on:
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72b >>> dnPrettyNormal: <cn=admin,dc=example,dc=com>
=> ldap_bv2dn(cn=admin,dc=example,dc=com,0)
<= ldap_bv2dn(cn=admin,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=admin,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=admin,dc=example,dc=com)=0
565de72b <<< dnPrettyNormal: <cn=admin,dc=example,dc=com>, <cn=admin,dc=example,dc=com>
565de72b conn=1002 op=0 BIND dn="cn=admin,dc=example,dc=com" method=128
565de72b do_bind: version=3 dn="cn=admin,dc=example,dc=com" method=128
565de72b ==> mdb_bind: dn: cn=admin,dc=example,dc=com
565de72b conn=1002 op=0 BIND dn="cn=admin,dc=example,dc=com" mech=SIMPLE ssf=0
565de72b do_bind: v3 bind: "cn=admin,dc=example,dc=com" to "cn=admin,dc=example,dc=com"
565de72b send_ldap_result: conn=1002 op=0 p=3
565de72b send_ldap_result: err=0 matched="" text=""
565de72b send_ldap_response: msgid=1 tag=97 err=0
ber_flush2: 14 bytes to sd 13
0000: 30 0c 02 01 01 61 07 0a 01 00 04 00 04 00 0....a........
ldap_write: want=14, written=14
0000: 30 0c 02 01 01 61 07 0a 01 00 04 00 04 00 0....a........
565de72b conn=1002 op=0 RESULT tag=97 err=0 text=
565de72b daemon: activity on 1 descriptor
565de72b daemon: activity on: 13r
565de72b daemon: read active on 13
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72b connection_get(13)
565de72b connection_get(13): got connid=1002
565de72b connection_read(13): checking for input on id=1002
ber_get_next
ldap_read: want=8, got=8
0000: 30 47 02 01 02 66 42 04 0G...fB.
ldap_read: want=65, got=65
0000: 1b 63 6e 3d 64 65 76 69 63 65 2c 64 63 3d 65 78 .cn=device,dc=ex
0010: 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 30 23 30 21 ample,dc=com0#0!
0020: 0a 01 00 30 1c 04 0b 6f 62 6a 65 63 74 43 6c 61 ...0...objectCla
0030: 73 73 31 0d 04 0b 61 73 73 6f 63 69 61 74 69 6f ss1...associatio
0040: 6e n
ber_get_next: tag 0x30 len 71 contents:
ber_dump: buf=0x7f597c0008c0 ptr=0x7f597c0008c0 end=0x7f597c000907 len=71
0000: 02 01 02 66 42 04 1b 63 6e 3d 64 65 76 69 63 65 ...fB..cn=device
0010: 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 ,dc=example,dc=c
0020: 6f 6d 30 23 30 21 0a 01 00 30 1c 04 0b 6f 62 6a om0#0!...0...obj
0030: 65 63 74 43 6c 61 73 73 31 0d 04 0b 61 73 73 6f ectClass1...asso
0040: 63 69 61 74 69 6f 6e ciation
565de72b op tag 0x66, time 1448994603
ber_get_next
ldap_read: want=8 error=Unknown error
565de72b conn=1002 op=1 do_modify
ber_scanf fmt ({m) ber:
ber_dump: buf=0x7f597c0008c0 ptr=0x7f597c0008c3 end=0x7f597c000907 len=68
0000: 66 42 04 1b 63 6e 3d 64 65 76 69 63 65 2c 64 63 fB..cn=device,dc
0010: 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 30 =example,dc=com0
0020: 23 30 21 0a 01 00 30 1c 04 0b 6f 62 6a 65 63 74 #0!...0...object
0030: 43 6c 61 73 73 31 0d 04 0b 61 73 73 6f 63 69 61 Class1...associa
565de72b daemon: activity on 1 descriptor
565de72b daemon: activity on:
0040: 74 69 6f 6e tion
565de72b conn=1002 op=1 do_modify: dn (cn=device,dc=example,dc=com)
ber_scanf fmt ({e{m[W]}}) ber:
ber_dump: buf=0x7f597c0008c0 ptr=0x7f597c0008e4 end=0x7f597c000907 len=35
0000: 30 21 0a 01 00 30 1c 04 0b 6f 62 6a 65 63 74 43 0!...0...objectC
0010: 6c 61 73 73 31 0d 04 0b 61 73 73 6f 63 69 61 74 lass1...associat
0020: 69 6f 6e ion
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72b >>> dnPrettyNormal: <cn=device,dc=example,dc=com>
=> ldap_bv2dn(cn=device,dc=example,dc=com,0)
<= ldap_bv2dn(cn=device,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=device,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=device,dc=example,dc=com)=0
565de72b <<< dnPrettyNormal: <cn=device,dc=example,dc=com>, <cn=device,dc=example,dc=com>
565de72b conn=1002 op=1 modifications:
565de72b add: objectClass
565de72b one value, length 11
565de72b conn=1002 op=1 MOD dn="cn=device,dc=example,dc=com"
565de72b conn=1002 op=1 MOD attr=objectClass
565de72b => mdb_search
565de72b mdb_dn2entry("cn=device,dc=example,dc=com")
565de72b => mdb_dn2id("cn=device,dc=example,dc=com")
565de72b <= mdb_dn2id: got id=0x3
565de72b => mdb_entry_decode:
565de72b <= mdb_entry_decode
565de72b => access_allowed: search access to "cn=device,dc=example,dc=com" "entry" requested
565de72b <= root access granted
565de72b => access_allowed: search access granted by manage(=mwrscxd)
565de72b base_candidates: base: "cn=device,dc=example,dc=com" (0x00000003)
565de72b => test_filter
565de72b EQUALITY
565de72b => access_allowed: search access to "cn=device,dc=example,dc=com" "objectClass" requested
565de72b <= root access granted
565de72b => access_allowed: search access granted by manage(=mwrscxd)
565de72b <= test_filter 5
565de72b mdb_search: 3 does not match filter
565de72b send_ldap_result: conn=1002 op=1 p=3
565de72b send_ldap_result: err=0 matched="" text=""
565de72b mdb_modify: cn=device,dc=example,dc=com
565de72b slap_queue_csn: queueing 0x7f597c102920 20151201183003.961042Z#000000#000#000000
565de72b mdb_dn2entry("cn=device,dc=example,dc=com")
565de72b => mdb_dn2id("cn=device,dc=example,dc=com")
565de72b <= mdb_dn2id: got id=0x3
565de72b => mdb_entry_decode:
565de72b <= mdb_entry_decode
565de72b mdb_modify_internal: 0x00000003: cn=device,dc=example,dc=com
565de72b <= acl_access_allowed: granted to database root
565de72b mdb_modify_internal: add objectClass
565de72b mdb_modify_internal: replace entryCSN
565de72b mdb_modify_internal: replace modifiersName
565de72b mdb_modify_internal: replace modifyTimestamp
565de72b oc_check_required entry (cn=device,dc=example,dc=com), objectClass "device"
565de72b oc_check_required entry (cn=device,dc=example,dc=com), objectClass "association"
565de72b oc_check_allowed type "objectClass"
565de72b oc_check_allowed type "cn"
565de72b oc_check_allowed type "structuralObjectClass"
565de72b oc_check_allowed type "entryUUID"
565de72b oc_check_allowed type "creatorsName"
565de72b oc_check_allowed type "createTimestamp"
565de72b oc_check_allowed type "entryCSN"
565de72b oc_check_allowed type "modifiersName"
565de72b oc_check_allowed type "modifyTimestamp"
565de72b mdb_idl_insert_keys: 3 [602d1f90]
565de72b => mdb_entry_encode(0x00000003): cn=device,dc=example,dc=com
565de72b <= mdb_entry_encode(0x00000003): cn=device,dc=example,dc=com
565de72b mdb_modify: updated id=00000003 dn="cn=device,dc=example,dc=com"
565de72b send_ldap_result: conn=1002 op=1 p=3
565de72b send_ldap_result: err=0 matched="" text=""
565de72b send_ldap_response: msgid=2 tag=103 err=0
ber_flush2: 14 bytes to sd 13
0000: 30 0c 02 01 02 67 07 0a 01 00 04 00 04 00 0....g........
ldap_write: want=14, written=14
0000: 30 0c 02 01 02 67 07 0a 01 00 04 00 04 00 0....g........
565de72b conn=1002 op=1 RESULT tag=103 err=0 text=
565de72b slap_graduate_commit_csn: removing 0x7f597c102920 20151201183003.961042Z#000000#000#000000
565de72b daemon: activity on 1 descriptor
565de72b daemon: activity on: 13r
565de72b daemon: read active on 13
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72b connection_get(13)
565de72b connection_get(13): got connid=1002
565de72b connection_read(13): checking for input on id=1002
ber_get_next
ldap_read: want=8, got=7
0000: 30 05 02 01 03 42 00 0....B.
ber_get_next: tag 0x30 len 5 contents:
ber_dump: buf=0x7f59781068e0 ptr=0x7f59781068e0 end=0x7f59781068e5 len=5
0000: 02 01 03 42 00 ...B.
565de72b op tag 0x42, time 1448994603
ber_get_next
ldap_read: want=8, got=0
565de72b ber_get_next on fd 13 failed errno=0 (Success)
565de72b connection_read(13): input error=-2 id=1002, closing.
565de72b connection_closing: readying conn=1002 sd=13 for close
565de72b connection_close: deferring conn=1002 sd=13
565de72b daemon: activity on 1 descriptor
565de72b conn=1002 op=2 do_unbind
conn=1002 op=2 UNBIND
565de72b daemon: activity on:565de72b connection_resched: attempting closing conn=1002 sd=13
565de72b connection_close: conn=1002 sd=13
565de72b
565de72b daemon: removing 13
565de72b conn=1002 fd=13 closed
565de72b daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72b daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72d daemon: activity on 1 descriptor
565de72d daemon: activity on:
565de72d slap_listener_activate(8):
565de72d daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72d daemon: epoll: listen=8 busy
565de72d >>> slap_listener(ldap://[::]:2389/)
565de72d daemon: listen=8, new connection on 13
565de72d daemon: activity on 1 descriptor
565de72d daemon: activity on:
565de72d daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72d daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72d daemon: added 13r (active) listener=(nil)
565de72d conn=1003 fd=13 ACCEPT from IP=[::1]:59148 (IP=[::]:2389)
565de72d daemon: activity on 2 descriptors
565de72d daemon: activity on: 13r
565de72d daemon: read active on 13
565de72d daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72d daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72d connection_get(13)
565de72d connection_get(13): got connid=1003
565de72d connection_read(13): checking for input on id=1003
ber_get_next
ldap_read: want=8, got=8
0000: 30 2b 02 01 01 60 26 02 0+...`&.
ldap_read: want=37, got=37
0000: 01 03 04 1a 63 6e 3d 61 64 6d 69 6e 2c 64 63 3d ....cn=admin,dc=
0010: 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 80 05 example,dc=com..
0020: 61 64 6d 69 6e admin
ber_get_next: tag 0x30 len 43 contents:
ber_dump: buf=0x7f5978104860 ptr=0x7f5978104860 end=0x7f597810488b len=43
0000: 02 01 01 60 26 02 01 03 04 1a 63 6e 3d 61 64 6d ...`&.....cn=adm
0010: 69 6e 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 in,dc=example,dc
0020: 3d 63 6f 6d 80 05 61 64 6d 69 6e =com..admin
565de72d op tag 0x60, time 1448994605
ber_get_next
ldap_read: want=8 error=Unknown error
565de72d conn=1003 op=0 do_bind
ber_scanf fmt ({imt) ber:
ber_dump: buf=0x7f5978104860 ptr=0x7f5978104863 end=0x7f597810488b len=40
0000: 60 26 02 01 03 04 1a 63 6e 3d 61 64 6d 69 6e 2c `&.....cn=admin,
0010: 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f dc=example,dc=co
0020: 6d 80 05 61 64 6d 69 6e m..admin
ber_scanf fmt (m}) ber:
ber_dump: buf=0x7f5978104860 ptr=0x7f5978104884 end=0x7f597810488b len=7
0000: 00 05 61 64 6d 69 6e ..admin
565de72d daemon: activity on 1 descriptor
565de72d daemon: activity on:
565de72d daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72d daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72d >>> dnPrettyNormal: <cn=admin,dc=example,dc=com>
=> ldap_bv2dn(cn=admin,dc=example,dc=com,0)
<= ldap_bv2dn(cn=admin,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=admin,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=admin,dc=example,dc=com)=0
565de72d <<< dnPrettyNormal: <cn=admin,dc=example,dc=com>, <cn=admin,dc=example,dc=com>
565de72d conn=1003 op=0 BIND dn="cn=admin,dc=example,dc=com" method=128
565de72d do_bind: version=3 dn="cn=admin,dc=example,dc=com" method=128
565de72d ==> mdb_bind: dn: cn=admin,dc=example,dc=com
565de72d conn=1003 op=0 BIND dn="cn=admin,dc=example,dc=com" mech=SIMPLE ssf=0
565de72d do_bind: v3 bind: "cn=admin,dc=example,dc=com" to "cn=admin,dc=example,dc=com"
565de72d send_ldap_result: conn=1003 op=0 p=3
565de72d send_ldap_result: err=0 matched="" text=""
565de72d send_ldap_response: msgid=1 tag=97 err=0
ber_flush2: 14 bytes to sd 13
0000: 30 0c 02 01 01 61 07 0a 01 00 04 00 04 00 0....a........
ldap_write: want=14, written=14
0000: 30 0c 02 01 01 61 07 0a 01 00 04 00 04 00 0....a........
565de72d conn=1003 op=0 RESULT tag=97 err=0 text=
565de72d daemon: activity on 1 descriptor
565de72d daemon: activity on: 13r
565de72d daemon: read active on 13
565de72d daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72d daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72d connection_get(13)
565de72d connection_get(13): got connid=1003
565de72d connection_read(13): checking for input on id=1003
ber_get_next
ldap_read: want=8, got=8
0000: 30 59 02 01 02 66 54 04 0Y...fT.
ldap_read: want=83, got=83
0000: 1b 63 6e 3d 64 65 76 69 63 65 2c 64 63 3d 65 78 .cn=device,dc=ex
0010: 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 30 35 30 33 ample,dc=com0503
0020: 0a 01 00 30 2e 04 09 61 73 73 6f 63 69 61 74 65 ...0...associate
0030: 31 21 04 1f 75 69 64 3d 6d 79 41 63 63 6f 75 6e 1!..uid=myAccoun
0040: 74 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 3d t,dc=example,dc=
0050: 63 6f 6d com
ber_get_next: tag 0x30 len 89 contents:
ber_dump: buf=0x7f597c103120 ptr=0x7f597c103120 end=0x7f597c103179 len=89
0000: 02 01 02 66 54 04 1b 63 6e 3d 64 65 76 69 63 65 ...fT..cn=device
0010: 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 ,dc=example,dc=c
0020: 6f 6d 30 35 30 33 0a 01 00 30 2e 04 09 61 73 73 om0503...0...ass
0030: 6f 63 69 61 74 65 31 21 04 1f 75 69 64 3d 6d 79 ociate1!..uid=my
0040: 41 63 63 6f 75 6e 74 2c 64 63 3d 65 78 61 6d 70 Account,dc=examp
0050: 6c 65 2c 64 63 3d 63 6f 6d le,dc=com
565de72d op tag 0x66, time 1448994605
ber_get_next
ldap_read: want=8 error=Unknown error
565de72d daemon: activity on 1 descriptor
565de72d conn=1003 op=1 do_modify
ber_scanf fmt ({m) ber:
ber_dump: buf=0x7f597c103120 ptr=0x7f597c103123 end=0x7f597c103179 len=86
0000: 66 54 04 1b 63 6e 3d 64 65 76 69 63 65 2c 64 63 fT..cn=device,dc
0010: 3d 65 78 61 6d 70 6c 65 2c 64 63 3d 63 6f 6d 30 =example,dc=com0
0020: 35 30 33 0a 01 00 30 2e 04 09 61 73 73 6f 63 69 503...0...associ
0030: 61 74 65 31 21 04 1f 75 69 64 3d 6d 79 41 63 63 ate1!..uid=myAcc
0040: 6f 75 6e 74 2c 64 63 3d 65 78 61 6d 70 6c 65 2c ount,dc=example,
565de72d daemon: activity on:
0050: 64 63 3d 63 6f 6d dc=com
565de72d conn=1003 op=1 do_modify: dn (cn=device,dc=example,dc=com)
ber_scanf fmt ({e{m[W]}}) ber:
ber_dump: buf=0x7f597c103120 ptr=0x7f597c103144 end=0x7f597c103179 len=53
0000: 30 33 0a 01 00 30 2e 04 09 61 73 73 6f 63 69 61 03...0...associa
565de72d daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72d daemon: epoll: listen=8 active_threads=0 tvp=NULL
0010: 74 65 31 21 04 1f 75 69 64 3d 6d 79 41 63 63 6f te1!..uid=myAcco
0020: 75 6e 74 2c 64 63 3d 65 78 61 6d 70 6c 65 2c 64 unt,dc=example,d
0030: 63 3d 63 6f 6d c=com
565de72d >>> dnPrettyNormal: <cn=device,dc=example,dc=com>
=> ldap_bv2dn(cn=device,dc=example,dc=com,0)
<= ldap_bv2dn(cn=device,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=device,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=device,dc=example,dc=com)=0
565de72d <<< dnPrettyNormal: <cn=device,dc=example,dc=com>, <cn=device,dc=example,dc=com>
565de72d conn=1003 op=1 modifications:
565de72d add: associate
565de72d one value, length 31
565de72d conn=1003 op=1 MOD dn="cn=device,dc=example,dc=com"
565de72d conn=1003 op=1 MOD attr=associate
565de72d >>> dnPretty: <uid=myAccount,dc=example,dc=com>
=> ldap_bv2dn(uid=myAccount,dc=example,dc=com,0)
<= ldap_bv2dn(uid=myAccount,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(uid=myAccount,dc=example,dc=com)=0
565de72d <<< dnPretty: <uid=myAccount,dc=example,dc=com>
565de72d >>> dnNormalize: <uid=myAccount,dc=example,dc=com>
=> ldap_bv2dn(uid=myAccount,dc=example,dc=com,0)
<= ldap_bv2dn(uid=myAccount,dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(uid=myaccount,dc=example,dc=com)=0
565de72d <<< dnNormalize: <uid=myaccount,dc=example,dc=com>
565de72d => mdb_search
565de72d mdb_dn2entry("cn=device,dc=example,dc=com")
565de72d => mdb_dn2id("cn=device,dc=example,dc=com")
565de72d <= mdb_dn2id: got id=0x3
565de72d => mdb_entry_decode:
565de72d <= mdb_entry_decode
565de72d => access_allowed: search access to "cn=device,dc=example,dc=com" "entry" requested
565de72d <= root access granted
565de72d => access_allowed: search access granted by manage(=mwrscxd)
565de72d base_candidates: base: "cn=device,dc=example,dc=com" (0x00000003)
565de72d => test_filter
565de72d EQUALITY
565de72d => access_allowed: search access to "cn=device,dc=example,dc=com" "objectClass" requested
565de72d <= root access granted
565de72d => access_allowed: search access granted by manage(=mwrscxd)
565de72d <= test_filter 6
565de72d send_ldap_result: conn=1003 op=1 p=3
565de72d send_ldap_result: err=0 matched="" text=""
565de72d mdb_modify: cn=device,dc=example,dc=com
565de72d slap_queue_csn: queueing 0x7f597c1048f0 20151201183005.205511Z#000000#000#000000
565de72d mdb_dn2entry("cn=device,dc=example,dc=com")
565de72d => mdb_dn2id("cn=device,dc=example,dc=com")
565de72d <= mdb_dn2id: got id=0x3
565de72d => mdb_entry_decode:
565de72d <= mdb_entry_decode
565de72d mdb_modify_internal: 0x00000003: cn=device,dc=example,dc=com
565de72d <= acl_access_allowed: granted to database root
565de72d mdb_modify_internal: add associate
565de72d mdb_modify_internal: replace entryCSN
565de72d mdb_modify_internal: replace modifiersName
565de72d mdb_modify_internal: replace modifyTimestamp
565de72d oc_check_required entry (cn=device,dc=example,dc=com), objectClass "device"
565de72d oc_check_required entry (cn=device,dc=example,dc=com), objectClass "association"
565de72d oc_check_allowed type "objectClass"
565de72d oc_check_allowed type "cn"
565de72d oc_check_allowed type "structuralObjectClass"
565de72d oc_check_allowed type "entryUUID"
565de72d oc_check_allowed type "creatorsName"
565de72d oc_check_allowed type "createTimestamp"
565de72d oc_check_allowed type "associate"
565de72d oc_check_allowed type "entryCSN"
565de72d oc_check_allowed type "modifiersName"
565de72d oc_check_allowed type "modifyTimestamp"
565de72d => mdb_entry_encode(0x00000003): cn=device,dc=example,dc=com
565de72d <= mdb_entry_encode(0x00000003): cn=device,dc=example,dc=com
565de72d mdb_modify: updated id=00000003 dn="cn=device,dc=example,dc=com"
565de72d send_ldap_result: conn=1003 op=1 p=3
565de72d send_ldap_result: err=0 matched="" text=""
565de72d mdb_modify: uid=myaccount,dc=example,dc=com
565de72d mdb_dn2entry("uid=myaccount,dc=example,dc=com")
565de72d => mdb_dn2id("uid=myaccount,dc=example,dc=com")
565de72d <= mdb_dn2id: got id=0x2
565de72d => mdb_entry_decode:
565de72d <= mdb_entry_decode
565de72d mdb_modify_internal: 0x00000002: uid=myAccount,dc=example,dc=com
565de72d <= acl_access_allowed: granted to database root
565de72d mdb_modify_internal: add associateOf
565de72d mdb_modify_internal: replace modifiersName
565de72d oc_check_required entry (uid=myAccount,dc=example,dc=com), objectClass "myAccount"
565de72d oc_check_allowed type "objectClass"
565de72d oc_check_allowed type "uid"
565de72d oc_check_allowed type "structuralObjectClass"
565de72d oc_check_allowed type "entryUUID"
565de72d oc_check_allowed type "creatorsName"
565de72d oc_check_allowed type "createTimestamp"
565de72d oc_check_allowed type "entryCSN"
565de72d oc_check_allowed type "modifyTimestamp"
565de72d oc_check_allowed type "associateOf"
565de72d oc_check_allowed type "modifiersName"
565de72d => mdb_entry_encode(0x00000002): uid=myAccount,dc=example,dc=com
565de72d <= mdb_entry_encode(0x00000002): uid=myAccount,dc=example,dc=com
565de72d mdb_modify: updated id=00000002 dn="uid=myaccount,dc=example,dc=com"
565de72d send_ldap_result: conn=1003 op=1 p=3
565de72d send_ldap_result: err=0 matched="" text=""
565de72d send_ldap_response: msgid=2 tag=103 err=0
ber_flush2: 14 bytes to sd 13
0000: 30 0c 02 01 02 67 07 0a 01 00 04 00 04 00 0....g........
ldap_write: want=14, written=14
0000: 30 0c 02 01 02 67 07 0a 01 00 04 00 04 00 0....g........
565de72d conn=1003 op=1 RESULT tag=103 err=0 text=
565de72d slap_graduate_commit_csn: removing 0x7f597c1048f0 20151201183005.205511Z#000000#000#000000
565de72d daemon: activity on 1 descriptor
565de72d daemon: activity on: 13r
565de72d daemon: read active on 13
565de72d daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72d daemon: epoll: listen=8 active_threads=0 tvp=NULL
565de72d connection_get(13)
565de72d connection_get(13): got connid=1003
565de72d connection_read(13): checking for input on id=1003
ber_get_next
ldap_read: want=8, got=7
0000: 30 05 02 01 03 42 00 0....B.
ber_get_next: tag 0x30 len 5 contents:
ber_dump: buf=0x7f59781068e0 ptr=0x7f59781068e0 end=0x7f59781068e5 len=5
0000: 02 01 03 42 00 ...B.
565de72d op tag 0x42, time 1448994605
ber_get_next
ldap_read: want=8, got=0
565de72d ber_get_next on fd 13 failed errno=0 (Success)
565de72d connection_read(13): input error=-2 id=1003, closing.
565de72d connection_closing: readying conn=1003 sd=13 for close
565de72d connection_close: deferring conn=1003 sd=13
565de72d conn=1003 op=2 do_unbind
565de72d conn=1003 op=2 UNBIND
565de72d connection_resched: attempting closing conn=1003 sd=13
565de72d connection_close: conn=1003 sd=13
565de72d daemon: activity on 1 descriptor
565de72d daemon: activity on:daemon: removing 13
565de72d
565de72d conn=1003 fd=13 closed
565de72d daemon: epoll: listen=7 active_threads=0 tvp=NULL
565de72d daemon: epoll: listen=8 active_threads=0 tvp=NULL