openldap-bugs May 2009

openldap-bugs@openldap.org

42 participants
159 discussions

Re: (ITS#6096) 2.4.16 replica segfault
by luca.scamoni＠sys-net.it 07 May '09

07 May '09

hyc(a)symas.com ha scritto: > Can you print out the a_desc and values of all of the attributes in > entry->e_attrs ? > Don't know if this is the "right" way to do it, but here it is... (gdb) p *(entry->e_attrs->a_desc) $10 = {ad_next = 0x0, ad_type = 0x8f08d70, ad_cname = {bv_len = 9, bv_val = 0x8f08cb0 "entryUUID"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} (gdb) p *(entry->e_attrs->a_vals) $11 = {bv_len = 36, bv_val = 0x4d36f498 "6a0ba116-b291-11da-8006-87f6e679f1bd"} (gdb) p *(entry->e_attrs->a_nvals) $12 = {bv_len = 16, bv_val = 0x45631550 "j\v¡\026²\221\021Ú\200\006\207öæyñ½"} p (struct Attribute) *0x54a2de9c $13 = {a_desc = 0x8f07d88, a_vals = 0x4d3e1dd0, a_nvals = 0x4d3e1dd0, a_numvals = 2, a_flags = 0, a_next = 0x54a3c41c} p (AttributeDescription) *0x8f07d88 $14 = {ad_next = 0x0, ad_type = 0x8f07cb0, ad_cname = {bv_len = 11, bv_val = 0x8f07c28 "objectClass"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} (gdb) p (struct berval) *0x4d3e1dd0 $15 = {bv_len = 20, bv_val = 0x4d3f84d8 "cRLDistributionPoint"} (gdb) p (struct Attribute) *0x54a3c41c $16 = {a_desc = 0x8f0ce90, a_vals = 0x45665718, a_nvals = 0x4d366d98, a_numvals = 1, a_flags = 0, a_next = 0x54a41ec4} (gdb) p (AttributeDescription) *0x8f0ce90 $17 = {ad_next = 0x0, ad_type = 0x8f0cd70, ad_cname = {bv_len = 2, bv_val = 0x8f0ccf8 "cn"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} (gdb) p (struct berval) *0x45665718 $18 = {bv_len = 5, bv_val = 0x45662320 "CRL19"} (gdb) p (struct berval) *0x4d366d98 $19 = {bv_len = 5, bv_val = 0x45666fc8 "crl19"} (gdb) p (struct Attribute) *0x54a41ec4 $20 = {a_desc = 0x8f08508, a_vals = 0x4d366d80, a_nvals = 0x4d3f9cf8, a_numvals = 1, a_flags = 0, a_next = 0x54a47d44} (gdb) p (AttributeDescription) *0x8f08508 $21 = {ad_next = 0x0, ad_type = 0x8f08528, ad_cname = {bv_len = 12, bv_val = 0x8f08460 "creatorsName"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} (gdb) p (struct berval) *0x4d366d80 $22 = {bv_len = 20, bv_val = 0x4d301108 "cn=directory manager"} (gdb) p (struct berval) *0x4d3f9cf8 $23 = {bv_len = 20, bv_val = 0x45603f68 "cn=directory manager"} (gdb) p (struct Attribute) *0x54a47d44 $24 = {a_desc = 0x8f08178, a_vals = 0x45631538, a_nvals = 0x4d31b2b8, a_numvals = 1, a_flags = 0, a_next = 0x54a47f3c} (gdb) p (AttributeDescription) *0x8f08178 $25 = {ad_next = 0x0, ad_type = 0x8f08198, ad_cname = {bv_len = 15, bv_val = 0x8f08098 "createTimestamp"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} $26 = {a_desc = 0xf, a_vals = 0x4d36d8c0, a_nvals = 0x0, a_numvals = 0, a_flags = 0, a_next = 0x11} (gdb) p (struct berval) *0x45631538 $27 = {bv_len = 15, bv_val = 0x4d31b2a0 "20060313130120Z"} (gdb) p (struct berval) *0x4d31b2b8 $28 = {bv_len = 15, bv_val = 0x4d36d8c0 "20060313130120Z"} (gdb) p (struct Attribute) *0x54a47f3c $29 = {a_desc = 0x8f07f10, a_vals = 0x4d36d8d8, a_nvals = 0x4d36d8d8, a_numvals = 1, a_flags = 0, a_next = 0x54a39c74} (gdb) p (AttributeDescription) *0x8f07f10 $30 = {ad_next = 0x0, ad_type = 0x8f07f30, ad_cname = {bv_len = 21, bv_val = 0x8f07e50 "structuralObjectClass"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} (gdb) p (struct berval) *0x4d36d8d8 $31 = {bv_len = 20, bv_val = 0x4d3650a0 "cRLDistributionPoint"} (gdb) p (struct berval) *0x4d36d8d8 $32 = {bv_len = 20, bv_val = 0x4d3650a0 "cRLDistributionPoint"} (gdb) p (struct Attribute) *0x54a39c74 $33 = {a_desc = 0x905aa98, a_vals = 0x4d3650c0, a_nvals = 0x4d3650c0, a_numvals = 1, a_flags = 0, a_next = 0x54a38d8c} (gdb) p (AttributeDescription) *0x905aa98 $34 = {ad_next = 0x0, ad_type = 0x8f24f48, ad_cname = {bv_len = 32, bv_val = 0x905aab4 "certificateRevocationList;binary"}, ad_tags = {bv_len = 0, bv_val = 0x6769666e ""}, ad_flags = 1} (gdb) p (struct berval) *0x4d3650c0 $35 = {bv_len = 558, bv_val = 0x4d303a58 "0\202\002*0\202\001\022\002\001\0010\r\006\t*\206H\206÷\r\001\001\005\005"} (gdb) p (struct Attribute) *0x54a38d8c $36 = {a_desc = 0x8f08f10, a_vals = 0x4d34c588, a_nvals = 0x4565ce58, a_numvals = 1, a_flags = 0, a_next = 0x54a38ce4} (gdb) p (AttributeDescription) *0x8f08f10 $37 = {ad_next = 0x0, ad_type = 0x8f08f30, ad_cname = {bv_len = 8, bv_val = 0x8f08e50 "entryCSN"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} (gdb) p (struct berval) *0x4d34c588 $38 = {bv_len = 40, bv_val = 0x4d303c90 "20090507070014.234380Z#000000#000#000000"} (gdb) p (struct berval) *0x4565ce58 $39 = {bv_len = 40, bv_val = 0x4d3fb340 "20090507070014.234380Z#000000#000#000000"} (gdb) p (struct Attribute) *0x54a38ce4 $40 = {a_desc = 0x8f086b8, a_vals = 0x4565ce70, a_nvals = 0x456197b0, a_numvals = 1, a_flags = 0, a_next = 0x54a480a4} (gdb) p (AttributeDescription) *0x8f086b8 $41 = {ad_next = 0x0, ad_type = 0x8f086d8, ad_cname = {bv_len = 13, bv_val = 0x8f08608 "modifiersName"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} (gdb) p (struct berval) *0x4565ce70 $42 = {bv_len = 34, bv_val = 0x456076d8 "cn=manager,dc=a,dc=prod,dc=actalis"} (gdb) p (struct berval) *0x456197b0 $43 = {bv_len = 34, bv_val = 0x4565baa8 "cn=manager,dc=a,dc=prod,dc=actalis"} (gdb) p (struct Attribute) *0x54a480a4 $44 = {a_desc = 0x8f08360, a_vals = 0x456197c8, a_nvals = 0x4d3677e8, a_numvals = 1, a_flags = 0, a_next = 0x0} (gdb) p (AttributeDescription) *0x8f08360 $45 = {ad_next = 0x0, ad_type = 0x8f08380, ad_cname = {bv_len = 15, bv_val = 0x8f08278 "modifyTimestamp"}, ad_tags = {bv_len = 0, bv_val = 0x0}, ad_flags = 0} (gdb) p (struct berval) *0x456197c8 $46 = {bv_len = 15, bv_val = 0x4d3677d0 "20090507070014Z"} (gdb) p (struct berval) *0x4d3677e8 $47 = {bv_len = 15, bv_val = 0x4d36a008 "20090507070014Z"} Ing. Luca Scamoni Responsabile Ricerca e Sviluppo SysNet s.r.l. via Dossi, 8 - 27100 Pavia - ITALIA http://www.sys-net.it ----------------------------------- Office: +39 0382 573859 (137) Fax: +39 0382 476497 Email: luca.scamoni(a)sys-net.it -----------------------------------

1 0

Re: (ITS#6096) 2.4.16 replica segfault
by masarati＠aero.polimi.it 07 May '09

07 May '09

hyc(a)symas.com wrote: > Pierangelo Masarati wrote: >> hyc(a)symas.com wrote: >>> Luca Scamoni wrote: >>>> hyc(a)symas.com ha scritto: >>>>> Apparently the name of the entry in the consumer doesn't match the name of the >>>>> entry received from the provider. >>>>> >>>> Yes, but this is very strange. This is a migration from 2.3.37 to 2.4.16 >>>> done through slapcat/slapadd procedure on the master. >>>>> In frame 5, print *entry >>>>> >>>> (gdb) frame 5 >>>> #5 0x080f39f4 in syncrepl_entry (si=0x8fdebf8, op=0x380acf0, >>>> entry=0x54eba5f4, modlist=0x380aaf4, syncstate=2, syncUUID=0x380ab50, >>>> syncCSN=0x4d3e0388) >>>> at ../../../servers/slapd/syncrepl.c:2278 >>>> 2278 ../../../servers/slapd/syncrepl.c: No such file or directory. >>>> in ../../../servers/slapd/syncrepl.c >>>> (gdb) p *entry >>>> $1 = {e_id = 0, e_name = {bv_len = 0, bv_val = 0x4d3691c8 ""}, e_nname = >>>> {bv_len = 0, bv_val = 0x4d31cff8 ""}, e_attrs = 0x54a371fc, e_ocflags = >>>> 0, e_bv = { >>>> bv_len = 0, bv_val = 0x0}, e_private = 0x0} >>>> >>> Well, that's certainly odd looking. Did you have SYNC logging at the time, do >>> you have the log messages from just before this occurred? >>> >>> Can you print out the a_desc and values of all of the attributes in >>> entry->e_attrs ? >> Howard, >> >> what's odd is that, as far as I understand, entry->e_name is set after >> op->o_req_dn, inside syncrepl_message_to_entry(), which is just >> extracted and normalized from the message. What could perhaps happen is >> that dnPrettyNormal() fails (in fact its result is not checked). I'm >> adding a check and some logging, just in case. > > Good idea. > > Also, I think the original LDAP message should still be intact, in frame 6 > "msg" - perhaps you can extract the DN that was actually received and figure > out why it had a problem. ... except for '\0' at the end of each berval... Luca, make sure you look at the full contents of the message (I fear one of the values is a CRL, which could be relatively large). p.

1 0

Re: (ITS#6096) 2.4.16 replica segfault
by hyc＠symas.com 07 May '09

07 May '09

Pierangelo Masarati wrote: > hyc(a)symas.com wrote: >> Luca Scamoni wrote: >>> hyc(a)symas.com ha scritto: >>>> Apparently the name of the entry in the consumer doesn't match the name of the >>>> entry received from the provider. >>>> >>> Yes, but this is very strange. This is a migration from 2.3.37 to 2.4.16 >>> done through slapcat/slapadd procedure on the master. >>>> In frame 5, print *entry >>>> >>> (gdb) frame 5 >>> #5 0x080f39f4 in syncrepl_entry (si=0x8fdebf8, op=0x380acf0, >>> entry=0x54eba5f4, modlist=0x380aaf4, syncstate=2, syncUUID=0x380ab50, >>> syncCSN=0x4d3e0388) >>> at ../../../servers/slapd/syncrepl.c:2278 >>> 2278 ../../../servers/slapd/syncrepl.c: No such file or directory. >>> in ../../../servers/slapd/syncrepl.c >>> (gdb) p *entry >>> $1 = {e_id = 0, e_name = {bv_len = 0, bv_val = 0x4d3691c8 ""}, e_nname = >>> {bv_len = 0, bv_val = 0x4d31cff8 ""}, e_attrs = 0x54a371fc, e_ocflags = >>> 0, e_bv = { >>> bv_len = 0, bv_val = 0x0}, e_private = 0x0} >>> >> Well, that's certainly odd looking. Did you have SYNC logging at the time, do >> you have the log messages from just before this occurred? >> >> Can you print out the a_desc and values of all of the attributes in >> entry->e_attrs ? > > Howard, > > what's odd is that, as far as I understand, entry->e_name is set after > op->o_req_dn, inside syncrepl_message_to_entry(), which is just > extracted and normalized from the message. What could perhaps happen is > that dnPrettyNormal() fails (in fact its result is not checked). I'm > adding a check and some logging, just in case. Good idea. Also, I think the original LDAP message should still be intact, in frame 6 "msg" - perhaps you can extract the DN that was actually received and figure out why it had a problem. -- -- Howard Chu CTO, Symas Corp. http://www.symas.com Director, Highland Sun http://highlandsun.com/hyc/ Chief Architect, OpenLDAP http://www.openldap.org/project/

1 0

Re: (ITS#6096) 2.4.16 replica segfault
by masarati＠aero.polimi.it 07 May '09

07 May '09

hyc(a)symas.com wrote: > Luca Scamoni wrote: >> hyc(a)symas.com ha scritto: >>> Apparently the name of the entry in the consumer doesn't match the name of the >>> entry received from the provider. >>> >> Yes, but this is very strange. This is a migration from 2.3.37 to 2.4.16 >> done through slapcat/slapadd procedure on the master. >>> In frame 5, print *entry >>> >> (gdb) frame 5 >> #5 0x080f39f4 in syncrepl_entry (si=0x8fdebf8, op=0x380acf0, >> entry=0x54eba5f4, modlist=0x380aaf4, syncstate=2, syncUUID=0x380ab50, >> syncCSN=0x4d3e0388) >> at ../../../servers/slapd/syncrepl.c:2278 >> 2278 ../../../servers/slapd/syncrepl.c: No such file or directory. >> in ../../../servers/slapd/syncrepl.c >> (gdb) p *entry >> $1 = {e_id = 0, e_name = {bv_len = 0, bv_val = 0x4d3691c8 ""}, e_nname = >> {bv_len = 0, bv_val = 0x4d31cff8 ""}, e_attrs = 0x54a371fc, e_ocflags = >> 0, e_bv = { >> bv_len = 0, bv_val = 0x0}, e_private = 0x0} >> > Well, that's certainly odd looking. Did you have SYNC logging at the time, do > you have the log messages from just before this occurred? > > Can you print out the a_desc and values of all of the attributes in > entry->e_attrs ? Howard, what's odd is that, as far as I understand, entry->e_name is set after op->o_req_dn, inside syncrepl_message_to_entry(), which is just extracted and normalized from the message. What could perhaps happen is that dnPrettyNormal() fails (in fact its result is not checked). I'm adding a check and some logging, just in case. p.

1 0

(ITS#6097) MMR problems with deletes
by hyc＠OpenLDAP.org 07 May '09

07 May '09

Full_Name: Howard Chu Version: 2.4/HEAD OS: Linux URL: ftp://ftp.openldap.org/incoming/ Submission from: (NULL) (76.91.220.157) Submitted by: hyc Given two multimaster servers, and an entry is deleted from one server at the same time that a child of that entry is created on the other server, the two servers will probably diverge. In Persist mode: 1) If the delete has the newer CSN, then on its original server the new child entry will be ignored because it's too old. On the other server, the delete will fail since the entry has children. 2) If the delete has the older CSN, then on its server the original entry (which has already been deleted) will be resurrected as a glue entry, and its original contents will be lost. On the other server the delete will be ignored because it's too old. In Refresh mode, there won't be a divergence, but the result may not make sense: 3) On the deleting server, the original entry will be resurrected as a glue entry and the child will be added. On the other server, the deleted entry will be turned into a glue entry. As such, both servers will agree, but they'll both contain a child entry with a basically invalid parent. As a first step, we should fix the obvious differences between each server. Then we need to figure out what actually makes sense... (I.e., for the moment, we accept (3) as correct behavior, and so nothing needs to be fixed for Refresh mode.) While we usually declare "last writer wins" these semantics don't make sense for Deletes. E.g. in (1), in a single-master environment the Delete would fail, and in (2) the Add would fail. But in a working MMR we'd get the opposite results. To fix the divergence: 1) When the delete fails, the target entry should be changed to a glue entry, same as in (3). 2) We should never ignore deletes even if they're old. In this case, the Delete will fail and the target will be turned into a glue entry. So our policy will be: A) Deletes always win B) Outside of A, last writer wins With just these changes, all 3 cases will end up with the same resulting trees. All of them will have glue entries in the tree that have real child entries though, which is bogus. To address this problem, the fix for (1) should delete the entire subtree of the target entry. Likewise for (2), and also we should not create glue entries when a parent entry is missing; instead we should just ignore the Add. For (3) we need to purge all glue entries and their children after the refresh completes successfully. (Note that in Refresh, we need to allow glue entries to be created, since children may be received before their parents in a regular refresh. But once the refresh has completed, there should not be any glue entries left, they should all have been turned into real entries.)

1 0

Re: (ITS#6096) 2.4.16 replica segfault
by luca.scamoni＠sys-net.it 07 May '09

07 May '09

hyc(a)symas.com ha scritto: > Luca Scamoni wrote: >> hyc(a)symas.com ha scritto: >>> Apparently the name of the entry in the consumer doesn't match the name of the >>> entry received from the provider. >>> >> Yes, but this is very strange. This is a migration from 2.3.37 to 2.4.16 >> done through slapcat/slapadd procedure on the master. >>> In frame 5, print *entry >>> >> (gdb) frame 5 >> #5 0x080f39f4 in syncrepl_entry (si=0x8fdebf8, op=0x380acf0, >> entry=0x54eba5f4, modlist=0x380aaf4, syncstate=2, syncUUID=0x380ab50, >> syncCSN=0x4d3e0388) >> at ../../../servers/slapd/syncrepl.c:2278 >> 2278 ../../../servers/slapd/syncrepl.c: No such file or directory. >> in ../../../servers/slapd/syncrepl.c >> (gdb) p *entry >> $1 = {e_id = 0, e_name = {bv_len = 0, bv_val = 0x4d3691c8 ""}, e_nname = >> {bv_len = 0, bv_val = 0x4d31cff8 ""}, e_attrs = 0x54a371fc, e_ocflags = >> 0, e_bv = { >> bv_len = 0, bv_val = 0x0}, e_private = 0x0} >> > Well, that's certainly odd looking. Did you have SYNC logging at the time, do > you have the log messages from just before this occurred? > > Can you print out the a_desc and values of all of the attributes in > entry->e_attrs ? > loglevel is stats sync. values in another email here an excerpt of the logs from master: May 7 09:00:13 quercia01 slapd[22251]: conn=144 op=604 MOD attr=certificateRevocationList;binary May 7 09:00:13 quercia01 slapd[22251]: slap_queue_csn: queing 0x1ce3980 20090507070013.853843Z#000000#000#000000 May 7 09:00:13 quercia01 slapd[22251]: conn=571 op=444 RESULT tag=103 err=0 text= May 7 09:00:13 quercia01 slapd[22251]: slap_graduate_commit_csn: removing 0x97c2860 20090507070013.733132Z#000000#000#000000 May 7 09:00:13 quercia01 slapd[22251]: conn=144 op=604 RESULT tag=103 err=0 text= May 7 09:00:14 quercia01 slapd[22251]: slap_graduate_commit_csn: removing 0x4564c8b0 20090507070013.853843Z#000000#000#000000 May 7 09:00:13 quercia01 slapd[22251]: syncprov_sendresp: cookie=rid=002,csn=20090507070013.733132Z#000000#000#000000 May 7 09:00:13 quercia01 slapd[22251]: conn=567 op=445 MOD dn="cn=CRL19,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a ,dc=prod,dc=actalis" May 7 09:00:14 quercia01 slapd[22251]: syncprov_sendresp: cookie=rid=002,csn=20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia01 slapd[22251]: conn=567 op=445 MOD attr=certificateRevocationList;binary May 7 09:00:14 quercia01 slapd[22251]: conn=130 op=605 MOD dn="cn=CRL7,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a, dc=prod,dc=actalis" May 7 09:00:14 quercia01 slapd[22251]: conn=130 op=605 MOD attr=certificateRevocationList;binary May 7 09:00:14 quercia01 slapd[22251]: slap_queue_csn: queing 0x38f3980 20090507070014.234380Z#000000#000#000000 May 7 09:00:14 quercia01 slapd[22251]: slap_queue_csn: queing 0x59ad980 20090507070014.312708Z#000000#000#000000 May 7 09:00:14 quercia01 slapd[22251]: conn=567 op=445 RESULT tag=103 err=0 text= May 7 09:00:14 quercia01 slapd[22251]: conn=130 op=605 RESULT tag=103 err=0 text= May 7 09:00:14 quercia01 slapd[22251]: syncprov_sendresp: cookie=rid=002,csn=20090507070014.234380Z#000000#000#000000 May 7 09:00:14 quercia01 slapd[22251]: syncprov_sendresp: cookie=rid=002,csn=20090507070014.312708Z#000000#000#000000 May 7 09:00:14 quercia01 slapd[22251]: slap_graduate_commit_csn: removing 0x460d6410 20090507070014.234380Z#000000#000#000000 May 7 09:00:14 quercia01 slapd[22251]: conn=129 op=620 MOD dn="cn=CRL41,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a ,dc=prod,dc=actalis" May 7 09:00:14 quercia01 slapd[22251]: slap_graduate_commit_csn: removing 0x97a6360 20090507070014.312708Z#000000#000#000000 and these from the replica: May 7 09:00:10 quercia02 slapd[13339]: do_syncrep2: cookie=rid=002,csn=20090507070009.855998Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: syncrepl_entry: rid=002 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) May 7 09:00:10 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) May 7 09:00:10 quercia02 slapd[13339]: syncrepl_entry: rid=002 cn=CRL24,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a ,dc=prod,dc=actalis May 7 09:00:10 quercia02 slapd[13339]: slap_queue_csn: queing 0x548befa8 20090507070009.855998Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x50067650 20090507070009.855998Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_modify cn=CRL24,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana ,c=IT,dc=a,dc=prod,dc=actalis (0) May 7 09:00:10 quercia02 slapd[13339]: slap_queue_csn: queing 0x548befa8 20090507070009.855998Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x507fb9e0 20090507070009.855998Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: do_syncrep2: cookie=rid=002,csn=20090507070010.127066Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: syncrepl_entry: rid=002 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) May 7 09:00:10 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) May 7 09:00:10 quercia02 slapd[13339]: syncrepl_entry: rid=002 cn=CRL43,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a ,dc=prod,dc=actalis May 7 09:00:10 quercia02 slapd[13339]: slap_queue_csn: queing 0x548befa8 20090507070010.127066Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x4c1db4a0 20090507070010.127066Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_modify cn=CRL43,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana ,c=IT,dc=a,dc=prod,dc=actalis (0) May 7 09:00:10 quercia02 slapd[13339]: slap_queue_csn: queing 0x548befa8 20090507070010.127066Z#000000#000#000000 May 7 09:00:10 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x4c1db4a0 20090507070010.127066Z#000000#000#000000 May 7 09:00:13 quercia02 slapd[13339]: conn=111 op=1710 SRCH base="o=Actalis S.p.A.,c=it,dc=b,dc=prod,dc=actalis" scope=0 deref=0 filter="(objectClass=*)" May 7 09:00:13 quercia02 slapd[13339]: conn=111 op=1710 SEARCH RESULT tag=101 err=0 nentries=1 text= May 7 09:00:13 quercia02 slapd[13339]: conn=110 op=1710 SRCH base="o=Actalis S.p.A.,c=it,dc=a,dc=prod,dc=actalis" scope=0 deref=0 filter="(objectClass=*)" May 7 09:00:13 quercia02 slapd[13339]: conn=110 op=1710 SEARCH RESULT tag=101 err=0 nentries=1 text= May 7 09:00:13 quercia02 slapd[13339]: do_syncrep2: cookie=rid=002,csn=20090507070013.348033Z#000000#000#000000 May 7 09:00:13 quercia02 slapd[13339]: syncrepl_entry: rid=002 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) May 7 09:00:13 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) May 7 09:00:13 quercia02 slapd[13339]: syncrepl_entry: rid=002 cn=CRL40,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a ,dc=prod,dc=actalis May 7 09:00:13 quercia02 slapd[13339]: slap_queue_csn: queing 0x548d78b0 20090507070013.348033Z#000000#000#000000 May 7 09:00:13 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x50488548 20090507070013.348033Z#000000#000#000000 May 7 09:00:13 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_modify cn=CRL40,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana ,c=IT,dc=a,dc=prod,dc=actalis (0) May 7 09:00:13 quercia02 slapd[13339]: slap_queue_csn: queing 0x548d78b0 20090507070013.348033Z#000000#000#000000 May 7 09:00:13 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x4c1db4a0 20090507070013.348033Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: conn=108 op=1719 SRCH base="ou=CA BNL Multiservizi S.p.A. Open Market,o=BNL Multiservizi S.p.A. Open Market,dc=a,dc=p rod,dc=actalis" scope=0 deref=0 filter="(objectClass=*)" May 7 09:00:14 quercia02 slapd[13339]: conn=108 op=1719 SEARCH RESULT tag=101 err=0 nentries=1 text= May 7 09:00:14 quercia02 slapd[13339]: conn=109 op=1719 SRCH base="ou=CA BNL Multiservizi S.p.A. Open Market,o=BNL Multiservizi S.p.A. Open Market,dc=b,dc=p rod,dc=actalis" scope=0 deref=0 filter="(objectClass=*)" May 7 09:00:14 quercia02 slapd[13339]: conn=109 op=1719 SEARCH RESULT tag=101 err=32 nentries=0 text= May 7 09:00:14 quercia02 slapd[13339]: do_syncrep2: cookie=rid=002,csn=20090507070013.733132Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 cn=CRL27,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a ,dc=prod,dc=actalis May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4c1db4a0 20090507070013.733132Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: conn=112 op=1705 SRCH base="cn=Actalis Qualified Certificates CA,ou=Qualified Certification Service Provider,o=Actali s S.p.A.,c=IT,dc=a,dc=prod,dc=actalis" scope=0 deref=0 filter="(objectClass=*)" May 7 09:00:14 quercia02 slapd[13339]: conn=112 op=1705 SEARCH RESULT tag=101 err=32 nentries=0 text= May 7 09:00:14 quercia02 slapd[13339]: conn=113 op=1705 SRCH base="cn=Actalis Qualified Certificates CA,ou=Qualified Certification Service Provider,o=Actali s S.p.A.,c=IT,dc=b,dc=prod,dc=actalis" scope=0 deref=0 filter="(objectClass=*)" May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x54834a08 20090507070013.733132Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_modify cn=CRL27,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana ,c=IT,dc=a,dc=prod,dc=actalis (0) May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4c1db4a0 20090507070013.733132Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: conn=113 op=1705 SEARCH RESULT tag=101 err=0 nentries=1 text= May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x548befa8 20090507070013.733132Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: do_syncrep2: cookie=rid=002,csn=20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 cn=CRL37,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a ,dc=prod,dc=actalis May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4d32e218 20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x4d3657d8 20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_modify cn=CRL37,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana ,c=IT,dc=a,dc=prod,dc=actalis (0) May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4d32e218 20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x45659710 20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: do_syncrep2: cookie=rid=002,csn=20090507070014.234380Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x45663bb0 20090507070014.234380Z#000000#000#000000 Ing. Luca Scamoni Responsabile Ricerca e Sviluppo SysNet s.r.l. via Dossi, 8 - 27100 Pavia - ITALIA http://www.sys-net.it ----------------------------------- Office: +39 0382 573859 (137) Fax: +39 0382 476497 Email: luca.scamoni(a)sys-net.it -----------------------------------

1 0

Re: (ITS#6096) 2.4.16 replica segfault
by hyc＠symas.com 07 May '09

07 May '09

Luca Scamoni wrote: > hyc(a)symas.com ha scritto: >> Apparently the name of the entry in the consumer doesn't match the name of the >> entry received from the provider. >> > Yes, but this is very strange. This is a migration from 2.3.37 to 2.4.16 > done through slapcat/slapadd procedure on the master. >> >> In frame 5, print *entry >> > (gdb) frame 5 > #5 0x080f39f4 in syncrepl_entry (si=0x8fdebf8, op=0x380acf0, > entry=0x54eba5f4, modlist=0x380aaf4, syncstate=2, syncUUID=0x380ab50, > syncCSN=0x4d3e0388) > at ../../../servers/slapd/syncrepl.c:2278 > 2278 ../../../servers/slapd/syncrepl.c: No such file or directory. > in ../../../servers/slapd/syncrepl.c > (gdb) p *entry > $1 = {e_id = 0, e_name = {bv_len = 0, bv_val = 0x4d3691c8 ""}, e_nname = > {bv_len = 0, bv_val = 0x4d31cff8 ""}, e_attrs = 0x54a371fc, e_ocflags = > 0, e_bv = { > bv_len = 0, bv_val = 0x0}, e_private = 0x0} > Well, that's certainly odd looking. Did you have SYNC logging at the time, do you have the log messages from just before this occurred? Can you print out the a_desc and values of all of the attributes in entry->e_attrs ? -- -- Howard Chu CTO, Symas Corp. http://www.symas.com Director, Highland Sun http://highlandsun.com/hyc/ Chief Architect, OpenLDAP http://www.openldap.org/project/

1 0

Re: (ITS#6096) 2.4.16 replica segfault
by luca.scamoni＠sys-net.it 07 May '09

07 May '09

hyc(a)symas.com ha scritto: > > An assert is not a segfault... > You're right. Let's say that from the perspective of the customer a core file is always a Bad Thing ;-) > > Apparently the name of the entry in the consumer doesn't match the name of the > entry received from the provider. > Yes, but this is very strange. This is a migration from 2.3.37 to 2.4.16 done through slapcat/slapadd procedure on the master. > > In frame 5, print *entry > (gdb) frame 5 #5 0x080f39f4 in syncrepl_entry (si=0x8fdebf8, op=0x380acf0, entry=0x54eba5f4, modlist=0x380aaf4, syncstate=2, syncUUID=0x380ab50, syncCSN=0x4d3e0388) at ../../../servers/slapd/syncrepl.c:2278 2278 ../../../servers/slapd/syncrepl.c: No such file or directory. in ../../../servers/slapd/syncrepl.c (gdb) p *entry $1 = {e_id = 0, e_name = {bv_len = 0, bv_val = 0x4d3691c8 ""}, e_nname = {bv_len = 0, bv_val = 0x4d31cff8 ""}, e_attrs = 0x54a371fc, e_ocflags = 0, e_bv = { bv_len = 0, bv_val = 0x0}, e_private = 0x0} Ing. Luca Scamoni Responsabile Ricerca e Sviluppo SysNet s.r.l. via Dossi, 8 - 27100 Pavia - ITALIA http://www.sys-net.it ----------------------------------- Office: +39 0382 573859 (137) Fax: +39 0382 476497 Email: luca.scamoni(a)sys-net.it -----------------------------------

1 0

Re: (ITS#6096) 2.4.16 replica segfault
by hyc＠symas.com 07 May '09

07 May '09

luca(a)OpenLDAP.org wrote: > Full_Name: Luca Scamoni > Version: 2.4.16 > OS: Linux > URL: ftp://ftp.openldap.org/incoming/ > Submission from: (NULL) (82.63.140.131) > > > Scenario: > OpenLDAP 2.4.16 with patches for back-bdb (up to may 1st) and syncrepl. > Master-slave configuration using syncrepl refreshAndPersist. Two hdb databases > per instance. > Replica hit assert in slap_modrdn2mods and dumped core. An assert is not a segfault... > Since no MODRDN > operation is ever being performed on entries I wonder why. Apparently the name of the entry in the consumer doesn't match the name of the entry received from the provider. > Anyway, here the bt > full: In frame 5, print *entry > #0 0x004017a2 in _dl_sysinfo_int80 () from /lib/ld-linux.so.2 > No symbol table info available. > #1 0x004427a5 in raise () from /lib/tls/libc.so.6 > No symbol table info available. > #2 0x00444209 in abort () from /lib/tls/libc.so.6 > No symbol table info available. > #3 0x0043bd91 in __assert_fail () from /lib/tls/libc.so.6 > No symbol table info available. > #4 0x0809c7e6 in slap_modrdn2mods (op=0x380acf0, rs=0x380a910) at > ../../../servers/slapd/modrdn.c:394 > a_cnt = 0 > d_cnt = 0 > old_rdn = 0x0 > new_rdn = 0x0 > __PRETTY_FUNCTION__ = "slap_modrdn2mods" > #5 0x080f39f4 in syncrepl_entry (si=0x8fdebf8, op=0x380acf0, entry=0x54eba5f4, > modlist=0x380aaf4, syncstate=2, syncUUID=0x380ab50, syncCSN=0x4d3e0388) > at ../../../servers/slapd/syncrepl.c:2278 > noldp = {bv_len = 1294991464, bv_val = 0x4d300048 ""} > newp = {bv_len = 0, bv_val = 0x4d3691c8 ""} > i = 4707898 > got_replace = 0 > just_rename = 0 > mod = (Modifications *) 0x54a371fc > modtail = (Modifications **) 0x1d2d14 > ml = (Modifications **) 0x4565bac8 > m2 = (Modifications *) 0x4d300010 > be = (Backend *) 0x8fdd988 > cb = {sc_next = 0x0, sc_response = 0x80f7618<null_callback>, sc_cleanup > = 0, sc_private = 0x8fdebf8} > syncuuid_inserted = 0 > syncUUID_strrep = {bv_len = 36, bv_val = 0x4ae0300c > "6a0ba116-b291-11da-8006-87f6e679f1bd"} > rs_search = {sr_type = REP_RESULT, sr_tag = 101, sr_msgid = 0, sr_err = > 0, sr_matched = 0x0, > sr_text = 0x813b938 "AttributeDescription contains inappropriate characters", > sr_ref = 0x0, sr_ctrls = 0x0, sr_un = {sru_sasl = {r_sasldata = 0x0}, > sru_extended = {r_rspoid = 0x0, r_rspdata = 0x0}, sru_search = {r_entry = > 0x0, r_attr_flags = 0, r_operational_attrs = 0x0, r_attrs = 0x8172ce0, > r_nentries = 0, r_v2ref = 0x0}}, sr_flags = 4} > rs_delete = {sr_type = REP_RESULT, sr_tag = 0, sr_msgid = 0, sr_err = 0, > sr_matched = 0x0, sr_text = 0x0, sr_ref = 0x0, sr_ctrls = 0x0, sr_un = { > sru_sasl = {r_sasldata = 0x0}, sru_extended = {r_rspoid = 0x0, r_rspdata = > 0x0}, sru_search = {r_entry = 0x0, r_attr_flags = 0, > r_operational_attrs = 0x0, r_attrs = 0x0, r_nentries = 0, r_v2ref = 0x0}}, > sr_flags = 0} > rs_add = {sr_type = REP_RESULT, sr_tag = 0, sr_msgid = 0, sr_err = 0, > sr_matched = 0x0, sr_text = 0x0, sr_ref = 0x0, sr_ctrls = 0x0, sr_un = { > sru_sasl = {r_sasldata = 0x0}, sru_extended = {r_rspoid = 0x0, r_rspdata = > 0x0}, sru_search = {r_entry = 0x0, r_attr_flags = 0, > r_operational_attrs = 0x0, r_attrs = 0x0, r_nentries = 0, r_v2ref = 0x0}}, > sr_flags = 0} > rs_modify = {sr_type = REP_RESULT, sr_tag = 0, sr_msgid = 0, sr_err = 0, > sr_matched = 0x0, sr_text = 0x0, sr_ref = 0x0, sr_ctrls = 0x0, sr_un = { > sru_sasl = {r_sasldata = 0x0}, sru_extended = {r_rspoid = 0x0, r_rspdata = > 0x0}, sru_search = {r_entry = 0x0, r_attr_flags = 0, > r_operational_attrs = 0x0, r_attrs = 0x0, r_nentries = 0, r_v2ref = 0x0}}, > sr_flags = 0} > f = {f_choice = 163, f_un = {f_un_result = 58763504, f_un_desc = > 0x380a8f0, f_un_ava = 0x380a8f0, f_un_ssa = 0x380a8f0, f_un_mra = 0x380a8f0, > f_un_complex = 0x380a8f0}, f_next = 0x0} > ava = {aa_desc = 0x8f08d50, aa_value = {bv_len = 16, bv_val = 0x45614117 > "j\v¡\026²\221\021Ú\200\006\207öæyñœ"}} > rc = 0 > pdn = {bv_len = 0, bv_val = 0x0} > dni = {new_entry = 0x54eba5f4, dn = {bv_len = 112, > bv_val = 0x4ae03074 "cn=CRL19,ou=Regione Siciliana Certification Authority > Cittadini,o=Regione Siciliana,c=IT,dc=a,dc=prod,dc=actalis"}, ndn = { > bv_len = 112, bv_val = 0x4ae030ec "cn=crl19,ou=regione siciliana > certification authority cittadini,o=regione > siciliana,c=it,dc=a,dc=prod,dc=actalis"}, > nnewSup = {bv_len = 0, bv_val = 0x4d31cff8 ""}, renamed = 1, delOldRDN = 0, > modlist = 0x380aaf4, mods = 0x456172e0, oldNattr = 0x54a38d74, > oldDesc = 0x8f0ce90, newDesc = 0x0} > retry = 1 > freecsn = 1 > __PRETTY_FUNCTION__ = "syncrepl_entry" > #6 0x080eebcf in do_syncrep2 (op=0x380acf0, si=0x8fdebf8) at > ../../../servers/slapd/syncrepl.c:892 > rctrlp = (LDAPControl *) 0x45635200 > rctrls = (LDAPControl **) 0x4d371e08 > berbuf = { > buffer = "\002\000\001", '\0'<repeats 17 times>, "\020AaE]AaE]AaE", '\0' > <repeats 16 times>, "Œ«\200\003 > *T\000\000\000\000\000\017\000\000\000ô/T\000\017\000\000\000?}S\000Ü«\200\003ô/T\000\a\000\000\000\000\000\000\000\001\000\000\000\020²\200\003È\200\0038¬\200\003fg\016\b\020²\200\003Õf\016\b\004¬\200\003\000\000\000\000ô/T\000È\200\003€±\200\003\030¬\200\003\"ÙE\000ð\200\003\000\001\000\000zª\022\b,¬\200\003@ç\026\b8¬\200\003\211°\a\bð\200\003'~\022\000zª\022\b\2046s\000\000\000\000\000€±\200\003\001\000\000\000³f`\0008>\027\b\204"..., > ialign = 65538, lalign = 65538, falign = 9.18382988e-41, > dalign = 3.2380074297143616e-319, palign = 0x10002<Address 0x10002 out of > bounds>} > ber = (BerElement *) 0x380ab70 > msg = (LDAPMessage *) 0x4d3741f0 > retoid = 0x0 > retdata = (struct berval *) 0x0 > entry = (Entry *) 0x54eba5f4 > syncstate = 2 > syncUUID = {bv_len = 16, bv_val = 0x45614117 > "j\v¡\026²\221\021Ú\200\006\207öæyñœ"} > syncCookie = {ctxcsn = 0x4d3e0388, octet_str = {bv_len = 52, bv_val = > 0x4d368850 "rid=002,csn=20090507070014.234380Z#000000#000#000000"}, rid = 2, > sid = -1, numcsns = 1, sids = 0x4d3fa4a8, sc_next = {stqe_next = 0x0}} > syncCookie_req = {ctxcsn = 0x45661888, octet_str = {bv_len = 52, bv_val > = 0x4d366330 "rid=002,csn=20090507070013.853843Z#000000#000#000000"}, > rid = 2, sid = -1, numcsns = 1, sids = 0x4d3655f8, sc_next = {stqe_next = > 0x0}} > cookie = {bv_len = 52, bv_val = 0x45614129 > "rid=002,csn=20090507070014.234380Z#000000#000#000000"} > rc = 0 > err = 0 > len = 52 > psub = (struct berval *) 0x8fde868 > modlist = (Modifications *) 0x4565ba00 > match = 4676822 > m = 58764284 > tout_p = (struct timeval *) 0x380aae0 > tout = {tv_sec = 0, tv_usec = 0} > refreshDeletes = 0 > syncUUIDs = 0x0 > si_tag = 813739759 > #7 0x080f081e in do_syncrepl (ctx=0x380b210, arg=0x8fdee98) at > ../../../servers/slapd/syncrepl.c:1357 > rtask = (struct re_s *) 0x8fdee98 > si = (syncinfo_t *) 0x8fdebf8 > conn = {c_struct_state = 0, c_conn_state = 0, c_conn_idx = -1, c_sd = 0, > c_close_reason = 0x0, c_mutex = {__m_reserved = 0, __m_count = 0, > __m_owner = 0x0, __m_kind = 0, __m_lock = {__status = 0, __spinlock = 0}}, > c_sb = 0x0, c_starttime = 0, c_activitytime = 0, c_connid = 4294967295, > c_peer_domain = {bv_len = 0, bv_val = 0x8123930 ""}, c_peer_name = {bv_len = > 0, bv_val = 0x8123930 ""}, c_listener = 0x816e080, c_sasl_bind_mech = { > bv_len = 0, bv_val = 0x0}, c_sasl_dn = {bv_len = 0, bv_val = 0x0}, > c_sasl_authz_dn = {bv_len = 0, bv_val = 0x0}, c_authz_backend = 0x0, > c_authz_cookie = 0x0, c_authz = {sai_method = 0, sai_mech = {bv_len = 0, > bv_val = 0x0}, sai_dn = {bv_len = 0, bv_val = 0x0}, sai_ndn = {bv_len = 0, > bv_val = 0x0}, sai_ssf = 0, sai_transport_ssf = 0, sai_tls_ssf = 0, > sai_sasl_ssf = 0}, c_protocol = 0, c_ops = {stqh_first = 0x0, stqh_last = 0x0}, > c_pending_ops = {stqh_first = 0x0, stqh_last = 0x0}, c_write1_mutex = > {__m_reserved = 0, __m_count = 0, __m_owner = 0x0, __m_kind = 0, __m_lock = { > __status = 0, __spinlock = 0}}, c_write1_cv = {__c_lock = {__status = 0, > __spinlock = 0}, __c_waiting = 0x0, __padding = '\0'<repeats 27 times>, > __align = 0}, c_write2_mutex = {__m_reserved = 0, __m_count = 0, __m_owner = > 0x0, __m_kind = 0, __m_lock = {__status = 0, __spinlock = 0}}, > c_write2_cv = {__c_lock = {__status = 0, __spinlock = 0}, __c_waiting = 0x0, > __padding = '\0'<repeats 27 times>, __align = 0}, c_currentber = 0x0, > c_writers = 0, c_sasl_bind_in_progress = 0 '\0', c_writewaiter = 0 '\0', > c_is_tls = 0 '\0', c_needs_tls_accept = 0 '\0', c_sasl_layers = 0 '\0', > c_sasl_done = 0 '\0', c_sasl_authctx = 0x0, c_sasl_sockctx = 0x0, c_sasl_extra > = 0x0, c_sasl_bindop = 0x0, c_pagedresults_state = {ps_be = 0x0, > ps_size = 0, ps_count = 0, ps_cookie = 0, ps_cookieval = {bv_len = 0, bv_val > = 0x0}}, c_n_ops_received = 0, c_n_ops_executing = 0, c_n_ops_pending = 0, > c_n_ops_completed = 0, c_n_get = 0, c_n_read = 0, c_n_write = 0, c_extensions > = 0x0, c_clientfunc = 0, c_clientarg = 0x0, > c_send_ldap_result = 0x808e8de<slap_send_ldap_result>, c_send_search_entry = > 0x808f65a<slap_send_search_entry>, > c_send_search_reference = 0x80917d2<slap_send_search_reference>, > c_send_ldap_extended = 0x808f180<slap_send_ldap_extended>, > c_send_ldap_intermediate = 0x808f420<slap_send_ldap_intermediate>} > opbuf = {ob_op = {o_hdr = 0x380adc8, o_tag = 108, o_time = 1241679614, > o_tincr = 9, o_bd = 0x8fdd988, o_req_dn = {bv_len = 112, > bv_val = 0x4ae03074 "cn=CRL19,ou=Regione Siciliana Certification Authority > Cittadini,o=Regione Siciliana,c=IT,dc=a,dc=prod,dc=actalis"}, o_req_ndn = { > bv_len = 112, > bv_val = 0x4ae030ec "cn=crl19,ou=regione siciliana certification authority > cittadini,o=regione siciliana,c=it,dc=a,dc=prod,dc=actalis"}, o_request = { > oq_add = {rs_modlist = 0x0, rs_e = 0x0}, oq_bind = {rb_method = 0, rb_cred > = {bv_len = 0, bv_val = 0x0}, rb_edn = {bv_len = 0, > bv_val = 0x4d3691c8 ""}, rb_ssf = 0, rb_mech = {bv_len = 1295110136, > bv_val = 0x380a750 ""}}, oq_compare = {rs_ava = 0x0}, oq_modify = { > rs_mods = {rs_modlist = 0x0, rs_no_opattrs = 0 '\0'}, rs_increment = 0}, > oq_modrdn = {rs_mods = {rs_modlist = 0x0, rs_no_opattrs = 0 '\0'}, > rs_deleteoldrdn = 0, rs_newrdn = {bv_len = 0, bv_val = 0x4d3691c8 ""}, > rs_nnewrdn = {bv_len = 0, bv_val = 0x4d31cff8 ""}, rs_newSup = 0x380a750, > rs_nnewSup = 0x380a8b4}, oq_search = {rs_scope = 0, rs_deref = 0, > rs_slimit = 0, rs_tlimit = 0, rs_limit = 0x4d3691c8, rs_attrsonly = 0, > rs_attrs = 0x4d31cff8, rs_filter = 0x380a750, rs_filterstr = {bv_len = > 58763444, > bv_val = 0x4ae0303c > "(entryUUID=6a0ba116-b291-11da-8006-87f6e679f1bd)"}}, oq_abandon = {rs_msgid = > 0}, oq_cancel = {rs_msgid = 0}, oq_extended = { > rs_reqoid = {bv_len = 0, bv_val = 0x0}, rs_flags = 0, rs_reqdata = 0x0}, > oq_pwdexop = {rs_extended = {rs_reqoid = {bv_len = 0, bv_val = 0x0}, > rs_flags = 0, rs_reqdata = 0x0}, rs_old = {bv_len = 1295421896, bv_val > = 0x0}, rs_new = {bv_len = 1295110136, bv_val = 0x380a750 ""}, > rs_mods = 0x380a8b4, rs_modtail = 0x4ae0303c}}, o_abandon = 0, o_cancel > = 0, o_groups = 0x0, o_do_not_cache = 0 '\0', o_is_auth_check = 0 '\0', > o_dont_replicate = 0 '\0', o_acl_priv = ACL_NONE, o_nocaching = 0 '\0', > o_delete_glue_parent = 0 '\0', o_no_schema_check = 1 '\001', > o_no_subordinate_glue = 0 '\0', o_ctrlflag = '\0'<repeats 14 times>, > "\002", '\0'<repeats 16 times>, o_controls = 0x380aef0, o_authz = { > sai_method = 0, sai_mech = {bv_len = 0, bv_val = 0x0}, sai_dn = {bv_len = > 34, bv_val = 0x8fde900 "cn=Manager,dc=a,dc=prod,dc=actalis"}, sai_ndn = { > bv_len = 34, bv_val = 0x8fde940 "cn=manager,dc=a,dc=prod,dc=actalis"}, > sai_ssf = 0, sai_transport_ssf = 0, sai_tls_ssf = 0, sai_sasl_ssf = 0}, > o_ber = 0x0, o_res_ber = 0x0, o_callback = 0x380aa20, o_ctrls = 0x0, o_csn = > {bv_len = 40, > bv_val = 0x4ae03164 "20090507070014.234380Z#000000#000#000000"}, o_private > = 0x0, o_extra = {slh_first = 0x0}, o_next = {stqe_next = 0x0}}, ob_hdr = { > oh_opid = 0, oh_connid = 4294967295, oh_conn = 0x380af70, oh_msgid = 0, > oh_protocol = 0, oh_tid = 58768288, oh_threadctx = 0x380b210, > oh_tmpmemctx = 0x94bd878, oh_tmpmfuncs = 0x8172f00, oh_counters = 0x81b65e0, > oh_log_prefix = "conn=-1 op=0", '\0'<repeats 243 times>}, ob_controls = { > 0x380ab30, 0x0<repeats 31 times>}} > op = (Operation *) 0x380acf0 > rc = 0 > dostop = 0 > s = 15 > i = 2 > defer = 1 > fail = 0 > be = (Backend *) 0x8fdd988 > #8 0x08079db6 in connection_read_thread (ctx=0x380b210, argv=0xf) at > ../../../servers/slapd/connection.c:1243 > rc = 0 > cri = {op = 0x0, func = 0x80f0371<do_syncrepl>, arg = 0x8fdee98, ctx = > 0x380b210, nullop = 0} > s = 15 > #9 0x00605389 in ldap_int_thread_pool_wrapper (xpool=0x8f0eab0) at > ../../../libraries/libldap_r/tpool.c:663 > pool = (struct ldap_int_thread_pool_s *) 0x8f0eab0 > task = (ldap_int_thread_task_t *) 0x94bb930 > work_list = (ldap_int_tpool_plist_t *) 0x8f0eb30 > ctx = {ltu_id = 58768288, ltu_key = {{ltk_key = 0x80793bb, ltk_data = > 0x94bd788, ltk_free = 0x80791ab<conn_counter_destroy>}, { > ltk_key = 0x80e66d5, ltk_data = 0x94bd878, ltk_free = 0x80e64e8 > <slap_sl_mem_destroy>}, {ltk_key = 0x90204a0, ltk_data = 0x942e8b8, > ltk_free = 0x225796<bdb_reader_free>}, {ltk_key = 0x8092ceb, ltk_data = > 0x9439e10, ltk_free = 0x8092c48<slap_op_q_destroy>}, {ltk_key = 0x90229a0, > ltk_data = 0x943a4e0, ltk_free = 0x225796<bdb_reader_free>}, {ltk_key = > 0x213b96, ltk_data = 0x457f1008, ltk_free = 0x213b6b<search_stack_free>}, { > ltk_key = 0x0, ltk_data = 0x0, ltk_free = 0}<repeats 26 times>}} > kctx = (ldap_int_thread_userctx_t *) 0x0 > i = 32 > keyslot = 158 > hash = 5957790 > __PRETTY_FUNCTION__ = "ldap_int_thread_pool_wrapper" > #10 0x001263cc in start_thread () from /lib/tls/libpthread.so.0 > No symbol table info available. > #11 0x004e3c3e in clone () from /lib/tls/libc.so.6 > No symbol table info available. > > soon after that (about 1 minute) master stopped accepting connection and load > average jumped to 18. > > Last rows of replica log: > May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4c1db4a0 > 20090507070013.733132Z#000000#000#000000 > May 7 09:00:14 quercia02 slapd[13339]: conn=113 op=1705 SEARCH RESULT tag=101 > err=0 nentries=1 text= > May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing > 0x548befa8 20090507070013.733132Z#000000#000#000000 > May 7 09:00:14 quercia02 slapd[13339]: do_syncrep2: > cookie=rid=002,csn=20090507070013.853843Z#000000#000#000000 > May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 > LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) > May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) > May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 > cn=CRL37,ou=Regione Siciliana Certification Authority Cittadini,o=Regione > Siciliana,c=IT,dc=a > ,dc=prod,dc=actalis > May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4d32e218 > 20090507070013.853843Z#000000#000#000000 > May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing > 0x4d3657d8 20090507070013.853843Z#000000#000#000000 > May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_modify > cn=CRL37,ou=Regione Siciliana Certification Authority Cittadini,o=Regione > Siciliana > ,c=IT,dc=a,dc=prod,dc=actalis (0) > May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4d32e218 > 20090507070013.853843Z#000000#000#000000 > May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing > 0x45659710 20090507070013.853843Z#000000#000#000000 > May 7 09:00:14 quercia02 slapd[13339]: do_syncrep2: > cookie=rid=002,csn=20090507070014.234380Z#000000#000#000000 > May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 > LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) > May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) > May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 > May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x45663bb0 > 20090507070014.234380Z#000000#000#000000 > > Any further information available upon request > TIA > -- -- Howard Chu CTO, Symas Corp. http://www.symas.com Director, Highland Sun http://highlandsun.com/hyc/ Chief Architect, OpenLDAP http://www.openldap.org/project/

1 0

(ITS#6096) 2.4.16 replica segfault
by luca＠OpenLDAP.org 07 May '09

07 May '09

Full_Name: Luca Scamoni Version: 2.4.16 OS: Linux URL: ftp://ftp.openldap.org/incoming/ Submission from: (NULL) (82.63.140.131) Scenario: OpenLDAP 2.4.16 with patches for back-bdb (up to may 1st) and syncrepl. Master-slave configuration using syncrepl refreshAndPersist. Two hdb databases per instance. Replica hit assert in slap_modrdn2mods and dumped core. Since no MODRDN operation is ever being performed on entries I wonder why. Anyway, here the bt full: #0 0x004017a2 in _dl_sysinfo_int80 () from /lib/ld-linux.so.2 No symbol table info available. #1 0x004427a5 in raise () from /lib/tls/libc.so.6 No symbol table info available. #2 0x00444209 in abort () from /lib/tls/libc.so.6 No symbol table info available. #3 0x0043bd91 in __assert_fail () from /lib/tls/libc.so.6 No symbol table info available. #4 0x0809c7e6 in slap_modrdn2mods (op=0x380acf0, rs=0x380a910) at ../../../servers/slapd/modrdn.c:394 a_cnt = 0 d_cnt = 0 old_rdn = 0x0 new_rdn = 0x0 __PRETTY_FUNCTION__ = "slap_modrdn2mods" #5 0x080f39f4 in syncrepl_entry (si=0x8fdebf8, op=0x380acf0, entry=0x54eba5f4, modlist=0x380aaf4, syncstate=2, syncUUID=0x380ab50, syncCSN=0x4d3e0388) at ../../../servers/slapd/syncrepl.c:2278 noldp = {bv_len = 1294991464, bv_val = 0x4d300048 ""} newp = {bv_len = 0, bv_val = 0x4d3691c8 ""} i = 4707898 got_replace = 0 just_rename = 0 mod = (Modifications *) 0x54a371fc modtail = (Modifications **) 0x1d2d14 ml = (Modifications **) 0x4565bac8 m2 = (Modifications *) 0x4d300010 be = (Backend *) 0x8fdd988 cb = {sc_next = 0x0, sc_response = 0x80f7618 <null_callback>, sc_cleanup = 0, sc_private = 0x8fdebf8} syncuuid_inserted = 0 syncUUID_strrep = {bv_len = 36, bv_val = 0x4ae0300c "6a0ba116-b291-11da-8006-87f6e679f1bd"} rs_search = {sr_type = REP_RESULT, sr_tag = 101, sr_msgid = 0, sr_err = 0, sr_matched = 0x0, sr_text = 0x813b938 "AttributeDescription contains inappropriate characters", sr_ref = 0x0, sr_ctrls = 0x0, sr_un = {sru_sasl = {r_sasldata = 0x0}, sru_extended = {r_rspoid = 0x0, r_rspdata = 0x0}, sru_search = {r_entry = 0x0, r_attr_flags = 0, r_operational_attrs = 0x0, r_attrs = 0x8172ce0, r_nentries = 0, r_v2ref = 0x0}}, sr_flags = 4} rs_delete = {sr_type = REP_RESULT, sr_tag = 0, sr_msgid = 0, sr_err = 0, sr_matched = 0x0, sr_text = 0x0, sr_ref = 0x0, sr_ctrls = 0x0, sr_un = { sru_sasl = {r_sasldata = 0x0}, sru_extended = {r_rspoid = 0x0, r_rspdata = 0x0}, sru_search = {r_entry = 0x0, r_attr_flags = 0, r_operational_attrs = 0x0, r_attrs = 0x0, r_nentries = 0, r_v2ref = 0x0}}, sr_flags = 0} rs_add = {sr_type = REP_RESULT, sr_tag = 0, sr_msgid = 0, sr_err = 0, sr_matched = 0x0, sr_text = 0x0, sr_ref = 0x0, sr_ctrls = 0x0, sr_un = { sru_sasl = {r_sasldata = 0x0}, sru_extended = {r_rspoid = 0x0, r_rspdata = 0x0}, sru_search = {r_entry = 0x0, r_attr_flags = 0, r_operational_attrs = 0x0, r_attrs = 0x0, r_nentries = 0, r_v2ref = 0x0}}, sr_flags = 0} rs_modify = {sr_type = REP_RESULT, sr_tag = 0, sr_msgid = 0, sr_err = 0, sr_matched = 0x0, sr_text = 0x0, sr_ref = 0x0, sr_ctrls = 0x0, sr_un = { sru_sasl = {r_sasldata = 0x0}, sru_extended = {r_rspoid = 0x0, r_rspdata = 0x0}, sru_search = {r_entry = 0x0, r_attr_flags = 0, r_operational_attrs = 0x0, r_attrs = 0x0, r_nentries = 0, r_v2ref = 0x0}}, sr_flags = 0} f = {f_choice = 163, f_un = {f_un_result = 58763504, f_un_desc = 0x380a8f0, f_un_ava = 0x380a8f0, f_un_ssa = 0x380a8f0, f_un_mra = 0x380a8f0, f_un_complex = 0x380a8f0}, f_next = 0x0} ava = {aa_desc = 0x8f08d50, aa_value = {bv_len = 16, bv_val = 0x45614117 "j\v¡\026²\221\021Ú\200\006\207öæyñœ"}} rc = 0 pdn = {bv_len = 0, bv_val = 0x0} dni = {new_entry = 0x54eba5f4, dn = {bv_len = 112, bv_val = 0x4ae03074 "cn=CRL19,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a,dc=prod,dc=actalis"}, ndn = { bv_len = 112, bv_val = 0x4ae030ec "cn=crl19,ou=regione siciliana certification authority cittadini,o=regione siciliana,c=it,dc=a,dc=prod,dc=actalis"}, nnewSup = {bv_len = 0, bv_val = 0x4d31cff8 ""}, renamed = 1, delOldRDN = 0, modlist = 0x380aaf4, mods = 0x456172e0, oldNattr = 0x54a38d74, oldDesc = 0x8f0ce90, newDesc = 0x0} retry = 1 freecsn = 1 __PRETTY_FUNCTION__ = "syncrepl_entry" #6 0x080eebcf in do_syncrep2 (op=0x380acf0, si=0x8fdebf8) at ../../../servers/slapd/syncrepl.c:892 rctrlp = (LDAPControl *) 0x45635200 rctrls = (LDAPControl **) 0x4d371e08 berbuf = { buffer = "\002\000\001", '\0' <repeats 17 times>, "\020AaE]AaE]AaE", '\0' <repeats 16 times>, "Œ«\200\003 *T\000\000\000\000\000\017\000\000\000ô/T\000\017\000\000\000?}S\000Ü«\200\003ô/T\000\a\000\000\000\000\000\000\000\001\000\000\000\020²\200\003È\200\0038¬\200\003fg\016\b\020²\200\003Õf\016\b\004¬\200\003\000\000\000\000ô/T\000È\200\003€±\200\003\030¬\200\003\"ÙE\000ð\200\003\000\001\000\000zª\022\b,¬\200\003@ç\026\b8¬\200\003\211°\a\bð\200\003'~\022\000zª\022\b\2046s\000\000\000\000\000€±\200\003\001\000\000\000³f`\0008>\027\b\204"..., ialign = 65538, lalign = 65538, falign = 9.18382988e-41, dalign = 3.2380074297143616e-319, palign = 0x10002 <Address 0x10002 out of bounds>} ber = (BerElement *) 0x380ab70 msg = (LDAPMessage *) 0x4d3741f0 retoid = 0x0 retdata = (struct berval *) 0x0 entry = (Entry *) 0x54eba5f4 syncstate = 2 syncUUID = {bv_len = 16, bv_val = 0x45614117 "j\v¡\026²\221\021Ú\200\006\207öæyñœ"} syncCookie = {ctxcsn = 0x4d3e0388, octet_str = {bv_len = 52, bv_val = 0x4d368850 "rid=002,csn=20090507070014.234380Z#000000#000#000000"}, rid = 2, sid = -1, numcsns = 1, sids = 0x4d3fa4a8, sc_next = {stqe_next = 0x0}} syncCookie_req = {ctxcsn = 0x45661888, octet_str = {bv_len = 52, bv_val = 0x4d366330 "rid=002,csn=20090507070013.853843Z#000000#000#000000"}, rid = 2, sid = -1, numcsns = 1, sids = 0x4d3655f8, sc_next = {stqe_next = 0x0}} cookie = {bv_len = 52, bv_val = 0x45614129 "rid=002,csn=20090507070014.234380Z#000000#000#000000"} rc = 0 err = 0 len = 52 psub = (struct berval *) 0x8fde868 modlist = (Modifications *) 0x4565ba00 match = 4676822 m = 58764284 tout_p = (struct timeval *) 0x380aae0 tout = {tv_sec = 0, tv_usec = 0} refreshDeletes = 0 syncUUIDs = 0x0 si_tag = 813739759 #7 0x080f081e in do_syncrepl (ctx=0x380b210, arg=0x8fdee98) at ../../../servers/slapd/syncrepl.c:1357 rtask = (struct re_s *) 0x8fdee98 si = (syncinfo_t *) 0x8fdebf8 conn = {c_struct_state = 0, c_conn_state = 0, c_conn_idx = -1, c_sd = 0, c_close_reason = 0x0, c_mutex = {__m_reserved = 0, __m_count = 0, __m_owner = 0x0, __m_kind = 0, __m_lock = {__status = 0, __spinlock = 0}}, c_sb = 0x0, c_starttime = 0, c_activitytime = 0, c_connid = 4294967295, c_peer_domain = {bv_len = 0, bv_val = 0x8123930 ""}, c_peer_name = {bv_len = 0, bv_val = 0x8123930 ""}, c_listener = 0x816e080, c_sasl_bind_mech = { bv_len = 0, bv_val = 0x0}, c_sasl_dn = {bv_len = 0, bv_val = 0x0}, c_sasl_authz_dn = {bv_len = 0, bv_val = 0x0}, c_authz_backend = 0x0, c_authz_cookie = 0x0, c_authz = {sai_method = 0, sai_mech = {bv_len = 0, bv_val = 0x0}, sai_dn = {bv_len = 0, bv_val = 0x0}, sai_ndn = {bv_len = 0, bv_val = 0x0}, sai_ssf = 0, sai_transport_ssf = 0, sai_tls_ssf = 0, sai_sasl_ssf = 0}, c_protocol = 0, c_ops = {stqh_first = 0x0, stqh_last = 0x0}, c_pending_ops = {stqh_first = 0x0, stqh_last = 0x0}, c_write1_mutex = {__m_reserved = 0, __m_count = 0, __m_owner = 0x0, __m_kind = 0, __m_lock = { __status = 0, __spinlock = 0}}, c_write1_cv = {__c_lock = {__status = 0, __spinlock = 0}, __c_waiting = 0x0, __padding = '\0' <repeats 27 times>, __align = 0}, c_write2_mutex = {__m_reserved = 0, __m_count = 0, __m_owner = 0x0, __m_kind = 0, __m_lock = {__status = 0, __spinlock = 0}}, c_write2_cv = {__c_lock = {__status = 0, __spinlock = 0}, __c_waiting = 0x0, __padding = '\0' <repeats 27 times>, __align = 0}, c_currentber = 0x0, c_writers = 0, c_sasl_bind_in_progress = 0 '\0', c_writewaiter = 0 '\0', c_is_tls = 0 '\0', c_needs_tls_accept = 0 '\0', c_sasl_layers = 0 '\0', c_sasl_done = 0 '\0', c_sasl_authctx = 0x0, c_sasl_sockctx = 0x0, c_sasl_extra = 0x0, c_sasl_bindop = 0x0, c_pagedresults_state = {ps_be = 0x0, ps_size = 0, ps_count = 0, ps_cookie = 0, ps_cookieval = {bv_len = 0, bv_val = 0x0}}, c_n_ops_received = 0, c_n_ops_executing = 0, c_n_ops_pending = 0, c_n_ops_completed = 0, c_n_get = 0, c_n_read = 0, c_n_write = 0, c_extensions = 0x0, c_clientfunc = 0, c_clientarg = 0x0, c_send_ldap_result = 0x808e8de <slap_send_ldap_result>, c_send_search_entry = 0x808f65a <slap_send_search_entry>, c_send_search_reference = 0x80917d2 <slap_send_search_reference>, c_send_ldap_extended = 0x808f180 <slap_send_ldap_extended>, c_send_ldap_intermediate = 0x808f420 <slap_send_ldap_intermediate>} opbuf = {ob_op = {o_hdr = 0x380adc8, o_tag = 108, o_time = 1241679614, o_tincr = 9, o_bd = 0x8fdd988, o_req_dn = {bv_len = 112, bv_val = 0x4ae03074 "cn=CRL19,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a,dc=prod,dc=actalis"}, o_req_ndn = { bv_len = 112, bv_val = 0x4ae030ec "cn=crl19,ou=regione siciliana certification authority cittadini,o=regione siciliana,c=it,dc=a,dc=prod,dc=actalis"}, o_request = { oq_add = {rs_modlist = 0x0, rs_e = 0x0}, oq_bind = {rb_method = 0, rb_cred = {bv_len = 0, bv_val = 0x0}, rb_edn = {bv_len = 0, bv_val = 0x4d3691c8 ""}, rb_ssf = 0, rb_mech = {bv_len = 1295110136, bv_val = 0x380a750 ""}}, oq_compare = {rs_ava = 0x0}, oq_modify = { rs_mods = {rs_modlist = 0x0, rs_no_opattrs = 0 '\0'}, rs_increment = 0}, oq_modrdn = {rs_mods = {rs_modlist = 0x0, rs_no_opattrs = 0 '\0'}, rs_deleteoldrdn = 0, rs_newrdn = {bv_len = 0, bv_val = 0x4d3691c8 ""}, rs_nnewrdn = {bv_len = 0, bv_val = 0x4d31cff8 ""}, rs_newSup = 0x380a750, rs_nnewSup = 0x380a8b4}, oq_search = {rs_scope = 0, rs_deref = 0, rs_slimit = 0, rs_tlimit = 0, rs_limit = 0x4d3691c8, rs_attrsonly = 0, rs_attrs = 0x4d31cff8, rs_filter = 0x380a750, rs_filterstr = {bv_len = 58763444, bv_val = 0x4ae0303c "(entryUUID=6a0ba116-b291-11da-8006-87f6e679f1bd)"}}, oq_abandon = {rs_msgid = 0}, oq_cancel = {rs_msgid = 0}, oq_extended = { rs_reqoid = {bv_len = 0, bv_val = 0x0}, rs_flags = 0, rs_reqdata = 0x0}, oq_pwdexop = {rs_extended = {rs_reqoid = {bv_len = 0, bv_val = 0x0}, rs_flags = 0, rs_reqdata = 0x0}, rs_old = {bv_len = 1295421896, bv_val = 0x0}, rs_new = {bv_len = 1295110136, bv_val = 0x380a750 ""}, rs_mods = 0x380a8b4, rs_modtail = 0x4ae0303c}}, o_abandon = 0, o_cancel = 0, o_groups = 0x0, o_do_not_cache = 0 '\0', o_is_auth_check = 0 '\0', o_dont_replicate = 0 '\0', o_acl_priv = ACL_NONE, o_nocaching = 0 '\0', o_delete_glue_parent = 0 '\0', o_no_schema_check = 1 '\001', o_no_subordinate_glue = 0 '\0', o_ctrlflag = '\0' <repeats 14 times>, "\002", '\0' <repeats 16 times>, o_controls = 0x380aef0, o_authz = { sai_method = 0, sai_mech = {bv_len = 0, bv_val = 0x0}, sai_dn = {bv_len = 34, bv_val = 0x8fde900 "cn=Manager,dc=a,dc=prod,dc=actalis"}, sai_ndn = { bv_len = 34, bv_val = 0x8fde940 "cn=manager,dc=a,dc=prod,dc=actalis"}, sai_ssf = 0, sai_transport_ssf = 0, sai_tls_ssf = 0, sai_sasl_ssf = 0}, o_ber = 0x0, o_res_ber = 0x0, o_callback = 0x380aa20, o_ctrls = 0x0, o_csn = {bv_len = 40, bv_val = 0x4ae03164 "20090507070014.234380Z#000000#000#000000"}, o_private = 0x0, o_extra = {slh_first = 0x0}, o_next = {stqe_next = 0x0}}, ob_hdr = { oh_opid = 0, oh_connid = 4294967295, oh_conn = 0x380af70, oh_msgid = 0, oh_protocol = 0, oh_tid = 58768288, oh_threadctx = 0x380b210, oh_tmpmemctx = 0x94bd878, oh_tmpmfuncs = 0x8172f00, oh_counters = 0x81b65e0, oh_log_prefix = "conn=-1 op=0", '\0' <repeats 243 times>}, ob_controls = { 0x380ab30, 0x0 <repeats 31 times>}} op = (Operation *) 0x380acf0 rc = 0 dostop = 0 s = 15 i = 2 defer = 1 fail = 0 be = (Backend *) 0x8fdd988 #8 0x08079db6 in connection_read_thread (ctx=0x380b210, argv=0xf) at ../../../servers/slapd/connection.c:1243 rc = 0 cri = {op = 0x0, func = 0x80f0371 <do_syncrepl>, arg = 0x8fdee98, ctx = 0x380b210, nullop = 0} s = 15 #9 0x00605389 in ldap_int_thread_pool_wrapper (xpool=0x8f0eab0) at ../../../libraries/libldap_r/tpool.c:663 pool = (struct ldap_int_thread_pool_s *) 0x8f0eab0 task = (ldap_int_thread_task_t *) 0x94bb930 work_list = (ldap_int_tpool_plist_t *) 0x8f0eb30 ctx = {ltu_id = 58768288, ltu_key = {{ltk_key = 0x80793bb, ltk_data = 0x94bd788, ltk_free = 0x80791ab <conn_counter_destroy>}, { ltk_key = 0x80e66d5, ltk_data = 0x94bd878, ltk_free = 0x80e64e8 <slap_sl_mem_destroy>}, {ltk_key = 0x90204a0, ltk_data = 0x942e8b8, ltk_free = 0x225796 <bdb_reader_free>}, {ltk_key = 0x8092ceb, ltk_data = 0x9439e10, ltk_free = 0x8092c48 <slap_op_q_destroy>}, {ltk_key = 0x90229a0, ltk_data = 0x943a4e0, ltk_free = 0x225796 <bdb_reader_free>}, {ltk_key = 0x213b96, ltk_data = 0x457f1008, ltk_free = 0x213b6b <search_stack_free>}, { ltk_key = 0x0, ltk_data = 0x0, ltk_free = 0} <repeats 26 times>}} kctx = (ldap_int_thread_userctx_t *) 0x0 i = 32 keyslot = 158 hash = 5957790 __PRETTY_FUNCTION__ = "ldap_int_thread_pool_wrapper" #10 0x001263cc in start_thread () from /lib/tls/libpthread.so.0 No symbol table info available. #11 0x004e3c3e in clone () from /lib/tls/libc.so.6 No symbol table info available. soon after that (about 1 minute) master stopped accepting connection and load average jumped to 18. Last rows of replica log: May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4c1db4a0 20090507070013.733132Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: conn=113 op=1705 SEARCH RESULT tag=101 err=0 nentries=1 text= May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x548befa8 20090507070013.733132Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: do_syncrep2: cookie=rid=002,csn=20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 cn=CRL37,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana,c=IT,dc=a ,dc=prod,dc=actalis May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4d32e218 20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x4d3657d8 20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_modify cn=CRL37,ou=Regione Siciliana Certification Authority Cittadini,o=Regione Siciliana ,c=IT,dc=a,dc=prod,dc=actalis (0) May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x4d32e218 20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: slap_graduate_commit_csn: removing 0x45659710 20090507070013.853843Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: do_syncrep2: cookie=rid=002,csn=20090507070014.234380Z#000000#000#000000 May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 be_search (0) May 7 09:00:14 quercia02 slapd[13339]: syncrepl_entry: rid=002 May 7 09:00:14 quercia02 slapd[13339]: slap_queue_csn: queing 0x45663bb0 20090507070014.234380Z#000000#000#000000 Any further information available upon request TIA

1 0

Jump to page:

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

openldap-bugs May 2009