[Date Prev][Date Next] [Chronological] [Thread] [Top]

Re: strange behaviour of syncrepl



Hi,

I'm running symas cds based on OpenLDAP: slapd 2.3.24. Our work requires us
to stick on this version.
Please see attachment for level 4 'heavy trace debugging' log, I don't see
much hints from the log.
I deleted one leaf in provider side, but this does not happen on the
consumer side after syncrepl.

BTW:what is the "sync" loglevel? Could you pls point me to the syncrepl bug
URL?

Thanks very much.
                     Tommy


On 9/25/06, Aaron Richton <richton@nbcs.rutgers.edu> wrote:

On Mon, 25 Sep 2006, thriller yan wrote:

> Basically, when I add some nodes in the provider side, these nodes are
> replicated to the consumer side, but when I delete some nodes in the
> provider side, the consumer side remain untouched. This confuse me.

For quite a while, this confused me too. It turns out there were some
syncrepl bugs, tracked down by this point. Are you running 2.3.27?
Upgrade if not, and observe closely. Turn on "sync" loglevel.

connection_get(12)
=> ldap_bv2dn(dc=example,dc=com,0)
<= ldap_bv2dn(dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(dc=example,dc=com)=0
==> bdb_bind: dn: dc=example,dc=com
send_ldap_result: err=0 matched="" text=""
connection_get(12)
=> ldap_bv2dn(dc=example,dc=com,0)
<= ldap_bv2dn(dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(dc=example,dc=com)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(dc=example,dc=com)=0
SRCH "dc=example,dc=com" 2 0    0 0 0
    filter: (objectClass=*)
    attrs: * +
send_ldap_result: err=0 matched="" text=""
connection_get(12)

=>do_syncrepl
ldap_create
ldap_url_parse_ext(ldap://bl35p-2.test:389)
ldap_sasl_bind_s
ldap_sasl_bind
ldap_send_initial_request
ldap_new_connection 1 1 0
ldap_int_open_connection
ldap_connect_to_host: TCP bl35p-2.test:389
ldap_new_socket: 11
ldap_prepare_socket: 11
ldap_connect_to_host: Trying 10.101.0.230:389
ldap_connect_timeout: fd: 11 tm: -1 async: 0
ldap_open_defconn: successful
ldap_send_server_request
ber_scanf fmt ({it) ber:
ber_scanf fmt ({i) ber:
ber_flush: 37 bytes to sd 11
ldap_result ld 0x60000000000b62b0 msgid 1
ldap_chkResponseList ld 0x60000000000b62b0 msgid 1 all 1
ldap_chkResponseList returns ld 0x60000000000b62b0 NULL
wait4msg ld 0x60000000000b62b0 msgid 1 (infinite timeout)
wait4msg continue ld 0x60000000000b62b0 msgid 1 all 1
** ld 0x60000000000b62b0 Connections:
* host: bl35p-2.test  port: 389  (default)
  refcnt: 2  status: Connected
  last used: Mon Sep 25 14:53:33 2006

** ld 0x60000000000b62b0 Outstanding Requests:
 * msgid 1,  origid 1, status InProgress
   outstanding referrals 0, parent count 0
** ld 0x60000000000b62b0 Response Queue:
   Empty
ldap_chkResponseList ld 0x60000000000b62b0 msgid 1 all 1
ldap_chkResponseList returns ld 0x60000000000b62b0 NULL
ldap_int_select
read1msg: ld 0x60000000000b62b0 msgid 1 all 1
ber_get_next
ber_get_next: tag 0x30 len 12 contents:
read1msg: ld 0x60000000000b62b0 msgid 1 message type bind
ber_scanf fmt ({eaa) ber:
ber_scanf fmt ({eaa}) ber:
new result:  res_errno: 0, res_error: <>, res_matched: <>
read1msg: ld 0x60000000000b62b0 0 new referrals
read1msg:  mark request completed, ld 0x60000000000b62b0 msgid 1
request done: ld 0x60000000000b62b0 msgid 1
res_errno: 0, res_error: <>, res_matched: <>
ldap_free_request (origid 1, msgid 1)
ldap_free_connection 0 1
ldap_free_connection: refcnt 1
ldap_parse_result
ber_scanf fmt ({iaa) ber:
ber_scanf fmt (}) ber:
ldap_msgfree
ldap_search_ext
put_filter: "(objectclass=*)"
put_filter: simple
put_simple_filter: "objectclass=*"
ldap_build_search_req ATTRS:
    *
    +
ldap_send_initial_request
ldap_send_server_request
ber_scanf fmt ({it) ber:
ber_scanf fmt ({) ber:
ber_flush: 149 bytes to sd 11
=>do_syncrep2
ldap_result ld 0x60000000000b62b0 msgid -1
ldap_chkResponseList ld 0x60000000000b62b0 msgid -1 all 0
ldap_chkResponseList returns ld 0x60000000000b62b0 NULL
wait4msg ld 0x60000000000b62b0 msgid -1 (infinite timeout)
wait4msg continue ld 0x60000000000b62b0 msgid -1 all 0
** ld 0x60000000000b62b0 Connections:
* host: bl35p-2.test  port: 389  (default)
  refcnt: 2  status: Connected
  last used: Mon Sep 25 14:53:33 2006

** ld 0x60000000000b62b0 Outstanding Requests:
 * msgid 2,  origid 2, status InProgress
   outstanding referrals 0, parent count 0
** ld 0x60000000000b62b0 Response Queue:
   Empty
ldap_chkResponseList ld 0x60000000000b62b0 msgid -1 all 0
ldap_chkResponseList returns ld 0x60000000000b62b0 NULL
ldap_int_select
read1msg: ld 0x60000000000b62b0 msgid -1 all 0
ber_get_next
ber_get_next: tag 0x30 len 49 contents:
read1msg: ld 0x60000000000b62b0 msgid 2 message type search-result
ber_scanf fmt ({eaa) ber:
ber_scanf fmt ({eaa}) ber:
new result:  res_errno: 0, res_error: <>, res_matched: <>
read1msg: ld 0x60000000000b62b0 0 new referrals
read1msg:  mark request completed, ld 0x60000000000b62b0 msgid 2
request done: ld 0x60000000000b62b0 msgid 2
res_errno: 0, res_error: <>, res_matched: <>
ldap_free_request (origid 2, msgid 2)
ldap_free_connection 0 1
ldap_free_connection: refcnt 1
ldap_parse_result
ber_scanf fmt ({iaa) ber:
ber_scanf fmt ({a) ber:
ber_scanf fmt (o) ber:
ber_scanf fmt (}) ber:
ber_scanf fmt ({) ber:
ber_scanf fmt (b) ber:
ber_scanf fmt (}) ber:
ldap_msgfree
ldap_free_connection 1 1
ldap_send_unbind
ber_flush: 7 bytes to sd 11
ldap_free_connection: actually freed