(ITS#4694) syncrepl in refreshOnly dies when bind to provider gails - openldap-bugs

4 Oct 2006


      Full_Name: Buchan Milne
Version: 2.3.27
OS: Linux 2.4 (RHEL 3)
URL: ftp://ftp.openldap.org/incoming/
Submission from: (NULL) (196.15.129.131)
One of our replicas sits on the other side of a relatively congested WAN link
(with multiple firewalls in between, which I have no control over).
Since switching to syncrepl, it has been difficult to achieve a configuration
that supports replication reliably to any extent.
Using syncrepl consumer configuration with:
syncrepl rid=124
        provider=ldaps://<master hostname>
        type=refreshOnly
        interval="00:00:01:00"
        searchbase="<suffix>"
        scope=sub
        attrs="*"
        schemachecking=off
        bindmethod=simple
        binddn="<dn for consumer>"
        credentials="<password for consumer>"
I see replication dies frequently, it seems to occur when (for whatever reason)
the consumer fails to bind:
ct  2 12:03:36 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:03:36 leda slapd2.3[1332]: syncrepl_entry: uid=lere1
Oct  2 12:03:37 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:03:37 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:03:43 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_INTERMEDIATE -
SYNC_ID_SET
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=deleted/telkomsa133309,ou=radius,o=intekom,c=za (0)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=telkomsa158118
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=online517446
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: uid=online517446
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: uid=telkomsa158118
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: uid=online564666
Oct  2 12:03:46 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:03:46 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:04:25 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:04:25 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:04:37 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_INTERMEDIATE -
SYNC_ID_SET
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=telkomsa220585
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: uid=online188476
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: uid=mail109585
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: uid=telkomsa196118
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: uid=online313812
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:38 leda slapd2.3[1332]: syncrepl_entry: uid=online519355
Oct  2 12:04:39 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:39 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:39 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:39 leda slapd2.3[1332]: syncrepl_entry: uid=online536857
Oct  2 12:04:39 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:39 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:39 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:39 leda slapd2.3[1332]: syncrepl_entry: uid=telkomsa233527
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: uid=mail140571
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: uid=online564379
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: uid=online564667
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: uid=ihatechromium
Oct  2 12:04:40 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:04:40 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:04:46 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_INTERMEDIATE -
SYNC_ID_SET
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=online556601
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=online519355
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: uid=online519355
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: uid=online556601
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: uid=online564667
Oct  2 12:04:47 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:04:47 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:05:25 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:05:25 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:05:40 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_INTERMEDIATE -
SYNC_ID_SET
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=online534772
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=online18609
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry: uid=online188476
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry: uid=telkomdsl56797
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:05:40 leda slapd2.3[1332]: syncrepl_entry: uid=mail140571
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry: be_modify (0)
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry: uid=hotkaj
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry: uid=magda22
Oct  2 12:05:41 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:05:41 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:05:47 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_INTERMEDIATE -
SYNC_ID_SET
Oct  2 12:05:47 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=online561383
Oct  2 12:05:47 leda slapd2.3[1332]: syncrepl_del_nonpresent: be_delete
uid=online534772
Oct  2 12:05:47 leda slapd2.3[1332]: syncrepl_entry:
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Oct  2 12:05:47 leda slapd2.3[1332]: syncrepl_entry: be_search (0)
Oct  2 12:05:47 leda slapd2.3[1332]: syncrepl_entry: uid=online561383
Oct  2 12:05:47 leda slapd2.3[1332]: syncrepl_entry: be_add (0)
Oct  2 12:05:47 leda slapd2.3[1332]: do_syncrep2: LDAP_RES_SEARCH_RESULT
Oct  2 12:06:27 leda slapd2.3[1332]: do_syncrep1: ldap_sasl_bind_s failed (-1)