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
luca@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/
hyc@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@sys-net.it -----------------------------------
Luca Scamoni wrote: > hyc@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/
hyc@symas.com ha scritto: > Luca Scamoni wrote: >> hyc@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@sys-net.it -----------------------------------
hyc@symas.com wrote: > Luca Scamoni wrote: >> hyc@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.
Pierangelo Masarati wrote: > hyc@symas.com wrote: >> Luca Scamoni wrote: >>> hyc@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/
hyc@symas.com wrote: > Pierangelo Masarati wrote: >> hyc@symas.com wrote: >>> Luca Scamoni wrote: >>>> hyc@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.
hyc@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@sys-net.it -----------------------------------
Luca Scamoni wrote: > hyc@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... Everything looks normal here. (I generally would do: p *entry->e_attrs p *entry->e_attrs->a_desc p *entry->e_attrs->a_vals p *entry->e_attrs->a_next p *entry->e_attrs->a_next->a_desc p *entry->e_attrs->a_next->a_vals ... Obviously it's easier with command history/editing...) Judging from the provider log, there's nothing special about the DN either. Can't imagine that it would get corrupted in the consumer, but we'll need to see what turns up in the LDAPMessage. > (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"} -- -- Howard Chu CTO, Symas Corp. http://www.symas.com Director, Highland Sun http://highlandsun.com/hyc/ Chief Architect, OpenLDAP http://www.openldap.org/project/
Howard Chu ha scritto: > Luca Scamoni wrote: >> hyc@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... > > Everything looks normal here. > > (I generally would do: > p *entry->e_attrs > p *entry->e_attrs->a_desc > p *entry->e_attrs->a_vals > p *entry->e_attrs->a_next > p *entry->e_attrs->a_next->a_desc > p *entry->e_attrs->a_next->a_vals > ... > > Obviously it's easier with command history/editing...) > > Judging from the provider log, there's nothing special about the DN > either. Can't imagine that it would get corrupted in the consumer, but > we'll need to see what turns up in the LDAPMessage. > This one? (gdb) frame 6 #6 0x080eebcf in do_syncrep2 (op=0x380acf0, si=0x8fdebf8) at ../../../servers/slapd/syncrepl.c:892 892 ../../../servers/slapd/syncrepl.c: No such file or directory. in ../../../servers/slapd/syncrepl.c (gdb) p *msg $1 = {lm_msgid = 2, lm_msgtype = 100, lm_ber = 0x4d32d5d0, lm_chain = 0x0, lm_chain_tail = 0x4d3741f0, lm_next = 0x0, lm_time = 0} (gdb) p *msg->lm_ber $2 = {ber_opts = {lbo_valid = 2, lbo_options = 1, lbo_debug = 0}, ber_tag = 100, ber_len = 1194, ber_usertag = 0, ber_buf = 0x4d32c958 "\002\001\002d\202\0046\004", ber_ptr = 0x4d32c95b "d\202\0046\004", ber_end = 0x4d32ce02 "", ber_sos = 0x0, ber_rwptr = 0x0, ber_memctx = 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@sys-net.it -----------------------------------
hyc@symas.com ha scritto: > > 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. > This is the msg from frame 6 (gdb) p msg->lm_ber->ber_buf[0]@100 $4 = "\002\001\002d\202\0046\004\000\000\202\004003\004\tentryUUID\000&\004$6a0ba116-b291-11da-8006-87f6e679f1bd0*\004\vobjectClass\000\033\004\024cRLDistribution" (gdb) p msg->lm_ber->ber_buf[100]@100 $5 = "Point\004\003top0\r\004\002cn\000\a\004\005CRL190&\004\fcreatorsName\000\026\004\024cn=directory manager0$\004\017createTimestamp\000\021\004\017200603131301" (gdb) p msg->lm_ber->ber_buf[200]@100 $6 = "20Z0/\004\025structuralObjectClass\000\026\004\024cRLDistributionPoint0\202\002X\004 certificateRevocationList;binary\000\202\0022\004\202\002.0\202" (gdb) p msg->lm_ber->ber_buf[300]@100 $7 = "\002*0\202\001\022\002\001\0010\r\006\t*\206H\206÷\r\001\001\005\005\0000\201\2101\v0\t\006\003U\004\006\023\002IT1\0270\025\006\003U\004\n\023\016Actalis S.p.A.1\"0 \006\003U\004\v\023\031Servizi di certificazion" (gdb) p msg->lm_ber->ber_buf[400]@100 $8 = "e1<0:\006\003U\004\003\0233Regione Siciliana Certification Authority Cittadini\027\r090507070003Z\027\r090508070003Z0\"0 \002\001\025" (gdb) p msg->lm_ber->ber_buf[500]@100 $9 = "\027\r060330073258Z0\f0\n\006\003U\035\025\004\003\n\001\001 10/0\f\006\003U\035\024\004\005\002\003\002\031(0\037\006\003U\035#\004\0300\026\200\024¾\232á\027'ü\214»\004)i ,x\"©<\226\210E0\r\006\t*\206H\206÷\r\001\001\005\005\000\003\202\001\001" (gdb) p msg->lm_ber->ber_buf[600]@100 $10 = "E6¥©×\023t\"ía\235Z¤²@(É(\222ÆGé'R!\234\\¤\027ªzÄ~l¹z\032ûÕ©êqÃ\202::c³áÈ#\n2±Âè+A¯Ö´¥¶#ü^¡\003Í\030\235`¨\032\027±ÍßvùOØ\230¶\233ã\004K\201&\\r]É\233DÏ>]R" (gdb) p msg->lm_ber->ber_buf[700]@100 $11 = "4\213\t\a>X^Ê\224ø]å\034#eÂQ\215îú×zb¯èfíPC«\202ã¯Z\177\024¯¼%Óζ8\022ͯ<\234»ZÍk\213µ\b¼Qè×Q\205\232Û¥\n3\212v\noåe+U\034Å\212\207\215\231+\2374«êÖN¤¡\222\000±hËY¸+ô8-" (gdb) p msg->lm_ber->ber_buf[800]@100 $12 = "à¯\234\214ì8ôa\177I§¨k¤\001N\217~,°>£\217\1771Èó½xÛ\206Ù\214íO5Ì%5»:Á\206Âá\235ð?\nòVáãW\"\21606\004\bentryCSN\000*\004(20090507070014.234380Z#00000" (gdb) p msg->lm_ber->ber_buf[900]@100 $13 = "0#000#00000005\004\rmodifiersName\000$\004\"cn=manager,dc=a,dc=prod,dc=actalis0$\004\017modifyTimestamp\000\021\004\0172009050707" (gdb) p msg->lm_ber->ber_buf[1000]@100 $14 = "0014Z0\r\004\aentryDN\000\002\004\0000#\004\021subschemaSubentry\000\016\004\fcn=Subschema0\032\004\017hasSubordinates\000\a\004\005FALSE k0i\004\0301.3.6.1.4" (gdb) p msg->lm_ber->ber_buf[1100]@100 $15 = ".1.4203.1.9.1.2\004M0K\n\001\002\004\020j\v¡\026²\221\021Ú\200\006\207öæyñ½\0044rid=002,csn=20090507070014.234380Z#000000#000#000000\0000%\000\000" I fear the dn is already lost... :-( 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@sys-net.it -----------------------------------
luca.scamoni@sys-net.it wrote: > hyc@symas.com ha scritto: >> 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. >> > This is the msg from frame 6 > > (gdb) p msg->lm_ber->ber_buf[0]@100 > $4 = > "\002\001\002d\202\0046\004\000\000\202\004003\004\tentryUUID\000&\004$6a0ba116-b291-11da-8006-87f6e679f1bd0*\004\vobjectClass\000\033\004\024cRLDistribution" The second '\000' after '\004' (octet string) was probably put by ber_scanf("m") to terminate the string, but the first one seems to be the length of the octet string! So apparently the message actually contained an empty DN, which makes little sense. Could the error be at the provider's side? p. > (gdb) p msg->lm_ber->ber_buf[100]@100 > $5 = > "Point\004\003top0\r\004\002cn\000\a\004\005CRL190&\004\fcreatorsName\000\026\004\024cn=directory > manager0$\004\017createTimestamp\000\021\004\017200603131301" > (gdb) p msg->lm_ber->ber_buf[200]@100 > $6 = > "20Z0/\004\025structuralObjectClass\000\026\004\024cRLDistributionPoint0\202\002X\004 > certificateRevocationList;binary\000\202\0022\004\202\002.0\202" > (gdb) p msg->lm_ber->ber_buf[300]@100 > $7 = > "\002*0\202\001\022\002\001\0010\r\006\t*\206H\206÷\r\001\001\005\005\0000\201\2101\v0\t\006\003U\004\006\023\002IT1\0270\025\006\003U\004\n\023\016Actalis > S.p.A.1\"0 \006\003U\004\v\023\031Servizi di certificazion" > (gdb) p msg->lm_ber->ber_buf[400]@100 > $8 = "e1<0:\006\003U\004\003\0233Regione Siciliana Certification > Authority Cittadini\027\r090507070003Z\027\r090508070003Z0\"0 \002\001\025" > (gdb) p msg->lm_ber->ber_buf[500]@100 > $9 = > "\027\r060330073258Z0\f0\n\006\003U\035\025\004\003\n\001\001 10/0\f\006\003U\035\024\004\005\002\003\002\031(0\037\006\003U\035#\004\0300\026\200\024¾\232á\027'ü\214»\004)i > ,x\"©<\226\210E0\r\006\t*\206H\206÷\r\001\001\005\005\000\003\202\001\001" > (gdb) p msg->lm_ber->ber_buf[600]@100 > $10 = > "E6¥©×\023t\"Ãa\235Z¤²@(É(\222ÆGé'R!\234\\¤\027ªzÄ~l¹z\032ûÕ©êqÃ\202::c³áÈ#\n2±Âè+A¯Ö´¥¶#ü^¡\003Ã�\030\235`¨\032\027±Ã�ßvùOØ\230¶\233ã\004K\201&\\r]É\233DÃ�>]R" > (gdb) p msg->lm_ber->ber_buf[700]@100 > $11 = > "4\213\t\a>X^Ê\224ø]Ã¥\034#eÂQ\215îú×zb¯èfÃPC«\202ã¯Z\177\024¯¼%Óζ8\022Ã�¯<\234»ZÃ�k\213µ\b¼Qè×Q\205\232Û¥\n3\212v\noÃ¥e+U\034Ã…\212\207\215\231+\2374«êÖN¤¡\222\000±hËY¸+ô8-" > (gdb) p msg->lm_ber->ber_buf[800]@100 > $12 = > "à ¯\234\214ì8ôa\177I§¨k¤\001N\217~,°>£\217\1771Èó½xÛ\206Ù\214ÃO5ÃŒ%5»:Ã�\206Âá\235ð?\nòVáãW\"\21606\004\bentryCSN\000*\004(20090507070014.234380Z#00000" > (gdb) p msg->lm_ber->ber_buf[900]@100 > $13 = > "0#000#00000005\004\rmodifiersName\000$\004\"cn=manager,dc=a,dc=prod,dc=actalis0$\004\017modifyTimestamp\000\021\004\0172009050707" > (gdb) p msg->lm_ber->ber_buf[1000]@100 > $14 = > "0014Z0\r\004\aentryDN\000\002\004\0000#\004\021subschemaSubentry\000\016\004\fcn=Subschema0\032\004\017hasSubordinates\000\a\004\005FALSE k0i\004\0301.3.6.1.4" > (gdb) p msg->lm_ber->ber_buf[1100]@100 > $15 = > ".1.4203.1.9.1.2\004M0K\n\001\002\004\020j\v¡\026²\221\021Ú\200\006\207öæyñ½\0044rid=002,csn=20090507070014.234380Z#000000#000#000000\0000%\000\000" > > I fear the dn is already lost... :-( > > > 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@sys-net.it > ----------------------------------- > > >
masarati@aero.polimi.it wrote: > luca.scamoni@sys-net.it wrote: >> hyc@symas.com ha scritto: >>> 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. >>> >> This is the msg from frame 6 >> >> (gdb) p msg->lm_ber->ber_buf[0]@100 >> $4 = >> "\002\001\002d\202\0046\004\000\000\202\004003\004\tentryUUID\000&\004$6a0ba116-b291-11da-8006-87f6e679f1bd0*\004\vobjectClass\000\033\004\024cRLDistribution" > > The second '\000' after '\004' (octet string) was probably put by > ber_scanf("m") to terminate the string, but the first one seems to be > the length of the octet string! So apparently the message actually > contained an empty DN, which makes little sense. Could the error be at > the provider's side? It certainly looks that way. What version is the provider, what version is the consumer? You only said there were mixed versions before, you never specified which was which. -- -- Howard Chu CTO, Symas Corp. http://www.symas.com Director, Highland Sun http://highlandsun.com/hyc/ Chief Architect, OpenLDAP http://www.openldap.org/project/
hyc@symas.com ha scritto: > > It certainly looks that way. What version is the provider, what version is the > consumer? You only said there were mixed versions before, you never specified > which was which. > Both are same version: 2.4.16 + patches (looking at RELENG I simply anticipated Quanah in merging from HEAD). 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@sys-net.it -----------------------------------
moved from Incoming to Software Bugs
Should be fixed in the decade plus of fixes to syncrepl. If you can reproduce with current OpenLDAP, please reopen