On 18.08.2010 15:10, Oliver Liebel wrote:
the mmr config was tested many times and should work as expected,
but your clockskew below may be to great for mmr to work as intended
are your servers are physical/paravirt vms or full emulated vms?
if they are full emulated (eg vmware server), you will always run into
major clock skews.
check out / set some of your ntp settings, e.g.
- tinker panic 0
- server <....> minpoll <value> maxpoll <value>
yes, the time thing looks suspect to me also. I will try to get that
better working; thanks for your hints.
But now, here's some log stuff; as announced ;) :
logfile excerpts from MASTER (hostname: ldapmaster) and STANDBY
(hostname: ldapslave). I tried to make it as clearly arranged as
possible by inserting self-explaining (hopefully ;)) tags into the
logfiles. Here's what i did; and where:
- started both machines in a well working state (replication fine in
every detail; freshly converted slapd.conf, freshly replicated). Both
machines up.
- insert tag "===_BEGIN_CHANGES_WHILE_BOTH_UP_===" into logfiles on
both machines (script based)
- MASTER: deleted user hcallahan (using jxplorer)
- MASTER: added user mmouse (using ldapadd)
- MASTER: changed attribute telephone number user vcorleone to
"0800-mafia" (using jxplorer)
- MASTER: insert tag "===_END_CHANGES_WHILE_BOTH_UP_==="
- insert tag "===_END_CHANGES_WHILE_BOTH_UP_===" into logfiles on
both machines
- STANDBY: rcldap stop
- insert tag "===_BEGIN_CHANGES_WHILE_STANDBY_DOWN_==="
- MASTER: delete user jmcclane
- MASTER: add user ggoofy
- MASTER: change telephone number user ckent to "0800-superman"
- insert tag "===_END_CHANGES_WHILE_STANDBY_DOWN_==="
- insert tag "===_BEGIN_STANDBY_COMING_BACK_==="
- STANDBY: rcldap start
- insert tag "===_END_STANDBY_COMING_BACK_==="
The tags are in MASTER's and STANDBY's logfile, so one can compare
what's going on at the same point in time.
The result on STANDBY at the end again is as i wrote before:
STANDBY:
- every operation while it was up was successfully replicated
- the operations while it was down are partially replicated after
coming up again:
-- deletion user jmcclane: OK
-- adding user ggoofy: obviously did not happen; not available on
STANDBY
-- changing phone number ckent: did not happen; on STANDBY it's the
same as before (means it differs now from the value it has on MASTER).
Here's the logfile of MASTER:
++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
===_BEGIN_CHANGES_WHILE_BOTH_UP_===
Aug 18 15:30:04 ldapmaster slapd[8017]: slap_queue_csn: queing
0x7f00f317b580 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapmaster slapd[8017]: slap_graduate_commit_csn:
removing 0x7f00fb4ee230 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapmaster slapd[8017]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapmaster slapd[8017]: do_syncrep2: rid=001 CSN too
old, ignoring 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapmaster slapd[8017]: do_syncrep2: rid=002
cookie=rid=002,csn=20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapmaster slapd[8017]: do_syncrep2: rid=002 CSN too
old, ignoring 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:25 ldapmaster slapd[8017]: slap_queue_csn: queing
0x7f00f397d060 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapmaster slapd[8017]: slap_graduate_commit_csn:
removing 0x7f00fb4ed9d0 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapmaster slapd[8017]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapmaster slapd[8017]: do_syncrep2: rid=001 CSN too
old, ignoring 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapmaster slapd[8017]: do_syncrep2: rid=002
cookie=rid=002,csn=20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapmaster slapd[8017]: do_syncrep2: rid=002 CSN too
old, ignoring 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:45 ldapmaster slapd[8017]: slap_queue_csn: queing
0x7f00f497e0f0 20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapmaster slapd[8017]: slap_graduate_commit_csn:
removing 0x7f00ec114bd0 20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapmaster slapd[8017]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapmaster slapd[8017]: do_syncrep2: rid=001 CSN too
old, ignoring 20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapmaster slapd[8017]: do_syncrep2: rid=002
cookie=rid=002,csn=20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapmaster slapd[8017]: do_syncrep2: rid=002 CSN too
old, ignoring 20100818133045.686887Z#000000#000#000000
===_END_CHANGES_WHILE_BOTH_UP_===
Aug 18 15:31:00 ldapmaster dhcpcd[2541]: eth0: renewing lease of
172.16.0.8
Aug 18 15:31:00 ldapmaster dhcpcd[2541]: eth0: leased 172.16.0.8 for
600 seconds
Aug 18 15:31:00 ldapmaster dhcpcd[2541]: eth0: adding IP address
172.16.0.8/24
Aug 18 15:31:00 ldapmaster dhcpcd[2541]: eth0: adding default route
via 172.16.0.1 metric 0
Aug 18 15:31:00 ldapmaster ifup: eth0 device: nVidia
Corporation MCP51 Ethernet Controller (rev a3)
Aug 18 15:31:20 ldapmaster dhclient: XMT: Solicit on eth0, interval
126630ms.
Aug 18 15:31:39 ldapmaster slapd[8017]: do_syncrep2: rid=004 (-1)
Can't contact LDAP server
Aug 18 15:31:39 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:31:39 ldapmaster slapd[8017]: do_syncrep2: rid=002 (-1)
Can't contact LDAP server
Aug 18 15:31:39 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:31:44 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:31:44 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:31:44 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:31:44 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:31:49 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:31:49 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:31:49 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:31:49 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:31:54 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:31:54 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:31:54 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:31:54 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:31:59 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:31:59 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:31:59 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:31:59 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:04 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:04 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:04 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:04 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:09 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:09 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:09 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:09 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
===_BEGIN_CHANGES_WHILE_STANDBY_DOWN_===
Aug 18 15:32:14 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:14 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:14 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:14 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:19 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:19 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:19 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:19 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:24 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:24 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:24 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:24 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:26 ldapmaster slapd[8017]: slap_queue_csn: queing
0x7f00f397d580 20100818133226.335074Z#000000#000#000000
Aug 18 15:32:26 ldapmaster slapd[8017]: slap_graduate_commit_csn:
removing 0x7f00fb4ed9d0 20100818133226.335074Z#000000#000#000000
Aug 18 15:32:26 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133226.335074Z#000000#000#000000
Aug 18 15:32:26 ldapmaster slapd[8017]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133226.335074Z#000000#000#000000
Aug 18 15:32:26 ldapmaster slapd[8017]: do_syncrep2: rid=001 CSN too
old, ignoring 20100818133226.335074Z#000000#000#000000
Aug 18 15:32:29 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:29 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:29 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:29 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:34 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:34 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:34 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:34 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:39 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:39 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:39 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:39 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:44 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:44 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:44 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:44 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:46 ldapmaster slapd[8017]: slap_queue_csn: queing
0x7f00f5180060 20100818133246.395672Z#000000#000#000000
Aug 18 15:32:46 ldapmaster slapd[8017]: slap_graduate_commit_csn:
removing 0x7f00ec10dde0 20100818133246.395672Z#000000#000#000000
Aug 18 15:32:46 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133246.395672Z#000000#000#000000
Aug 18 15:32:46 ldapmaster slapd[8017]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133246.395672Z#000000#000#000000
Aug 18 15:32:46 ldapmaster slapd[8017]: do_syncrep2: rid=001 CSN too
old, ignoring 20100818133246.395672Z#000000#000#000000
Aug 18 15:32:49 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:49 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:49 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:49 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:54 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:54 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:32:54 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:54 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:59 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:32:59 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:32:59 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:32:59 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:04 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:04 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:04 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:04 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:05 ldapmaster slapd[8017]: slap_queue_csn: queing
0x7f00f317a0f0 20100818133305.849116Z#000000#000#000000
Aug 18 15:33:05 ldapmaster slapd[8017]: syncprov_sendresp:
cookie=rid=001,csn=20100818133305.849116Z#000000#000#000000
Aug 18 15:33:05 ldapmaster slapd[8017]: slap_graduate_commit_csn:
removing 0x7f00ec111bc0 20100818133305.849116Z#000000#000#000000
Aug 18 15:33:05 ldapmaster slapd[8017]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133305.849116Z#000000#000#000000
Aug 18 15:33:05 ldapmaster slapd[8017]: do_syncrep2: rid=001 CSN too
old, ignoring 20100818133305.849116Z#000000#000#000000
Aug 18 15:33:09 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:09 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:09 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:09 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:14 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:14 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:14 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:14 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:19 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:19 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:19 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:19 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:24 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:24 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:24 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:24 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
===_END_CHANGES_WHILE_STANDBY_DOWN_===
Aug 18 15:33:27 ldapmaster dhclient: XMT: Solicit on eth0, interval
124510ms.
Aug 18 15:33:29 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:29 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:29 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:29 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:34 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:34 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:34 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:34 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:39 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:39 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:39 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:39 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:44 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:44 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:44 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:44 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:49 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:49 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:49 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:49 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
===_BEGIN_STANDBY_COMING_BACK_===
Aug 18 15:33:54 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:54 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:54 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:54 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:33:59 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=replicator,dc=local,dc=site"
ldap_sasl_bind_s failed (-1)
Aug 18 15:33:59 ldapmaster slapd[8017]: do_syncrepl: rid=002 rc -1
retrying
Aug 18 15:33:59 ldapmaster slapd[8017]: slap_client_connect:
URI=ldap://ldapslave.local.site DN="cn=config" ldap_sasl_bind_s failed
(-1)
Aug 18 15:33:59 ldapmaster slapd[8017]: do_syncrepl: rid=004 rc -1
retrying
Aug 18 15:34:03 ldapmaster slapd[8017]: srs csn
20100818133045.686887Z#000000#000#000000
Aug 18 15:34:03 ldapmaster slapd[8017]: log csn
20100818133004.663851Z#000000#000#000000
Aug 18 15:34:03 ldapmaster slapd[8017]: cmp -1, too old
Aug 18 15:34:03 ldapmaster slapd[8017]: log csn
20100818133045.686887Z#000000#000#000000
Aug 18 15:34:03 ldapmaster slapd[8017]: cmp 0, too old
Aug 18 15:34:03 ldapmaster slapd[8017]: log csn
20100818133226.335074Z#000000#000#000000
Aug 18 15:34:03 ldapmaster slapd[8017]: log csn
20100818133305.849116Z#000000#000#000000
Aug 18 15:34:03 ldapmaster slapd[8017]: syncprov_playlog:
cookie=rid=001,csn=20100818133226.335074Z#000000#000#000000
Aug 18 15:34:03 ldapmaster slapd[8017]: Entry
uid=vcorleone,ou=marketing,dc=local,dc=site changed by peer, ignored
Aug 18 15:34:03 ldapmaster slapd[8017]: Entry
uid=ckent,ou=verkauf,dc=local,dc=site changed by peer, ignored
Aug 18 15:34:03 ldapmaster slapd[8017]: Entry
uid=ggoofy,ou=verkauf,dc=local,dc=site changed by peer, ignored
Aug 18 15:34:03 ldapmaster slapd[8017]: syncprov_search_response:
cookie=rid=001,csn=20100818133305.849116Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN cn=config 20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: do_syncrep2: rid=002
LDAP_RES_INTERMEDIATE - REFRESH_DELETE
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
cn=schema,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 be_add
cn=schema,cn=config (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN cn={0}core,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
cn={0}core,cn=schema,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn={0}core,cn=schema,cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN cn={1}cosine,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
cn={1}cosine,cn=schema,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn={1}cosine,cn=schema,cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN cn={2}nis,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
cn={2}nis,cn=schema,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn={2}nis,cn=schema,cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN cn={3}inetorgperson,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
cn={3}inetorgperson,cn=schema,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn={3}inetorgperson,cn=schema,cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN olcDatabase={-1}frontend,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
olcDatabase={-1}frontend,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored (olcDatabase={-1}frontend,cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN olcOverlay={0}syncprov,olcDatabase={0}config,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
olcOverlay={0}syncprov,olcDatabase={0}config,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored
(olcOverlay={0}syncprov,olcDatabase={0}config,cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN olcDatabase={1}hdb,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
olcDatabase={1}hdb,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored (olcDatabase={1}hdb,cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:04 ldapmaster slapd[8017]: dn_callback : entries have
identical CSN olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004
olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config
Aug 18 15:34:04 ldapmaster slapd[8017]: syncrepl_entry: rid=004 entry
unchanged, ignored (olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config)
Aug 18 15:34:04 ldapmaster slapd[8017]: do_syncrep2: rid=004
LDAP_RES_INTERMEDIATE - REFRESH_DELETE
Aug 18 15:34:04 ldapmaster slapd[8017]: do_syncrep2: rid=004 cookie=
===_END_STANDBY_COMING_BACK_===
++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
And here's the logfile of STANDBY:
++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
===_BEGIN_CHANGES_WHILE_BOTH_UP_===
Aug 18 15:29:34 ldapslave sshd[10573]: Received disconnect from
172.16.0.8: 11: disconnected by user
Aug 18 15:30:04 ldapslave slapd[7860]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapslave slapd[7860]: syncrepl_entry: rid=001
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_DELETE)
Aug 18 15:30:04 ldapslave slapd[7860]: syncrepl_entry: rid=001
be_search (0)
Aug 18 15:30:04 ldapslave slapd[7860]: syncrepl_entry: rid=001
uid=hcallahan,ou=verkauf,dc=local,dc=site
Aug 18 15:30:04 ldapslave slapd[7860]: slap_queue_csn: queing
0x7fe5a2011990 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapslave slapd[7860]: slap_graduate_commit_csn:
removing 0x7fe5a2239a70 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapslave slapd[7860]: syncprov_sendresp:
cookie=rid=002,csn=20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapslave slapd[7860]: syncrepl_entry: rid=001
be_delete uid=hcallahan,ou=verkauf,dc=local,dc=site (0)
Aug 18 15:30:04 ldapslave slapd[7860]: slap_queue_csn: queing
0x7fe5a2011990 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapslave slapd[7860]: syncprov_sendresp:
cookie=rid=002,csn=20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapslave slapd[7860]: do_syncrep2: rid=002
cookie=rid=002,csn=20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapslave slapd[7860]: slap_graduate_commit_csn:
removing 0x7fe5a2230230 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:04 ldapslave slapd[7860]: do_syncrep2: rid=002 CSN too
old, ignoring 20100818133004.663851Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: syncrepl_entry: rid=001
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:30:25 ldapslave slapd[7860]: syncrepl_entry: rid=001
be_search (0)
Aug 18 15:30:25 ldapslave slapd[7860]: syncrepl_entry: rid=001
uid=mmouse,ou=verkauf,dc=local,dc=site
Aug 18 15:30:25 ldapslave slapd[7860]: slap_queue_csn: queing
0x7fe5a2239920 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: slap_graduate_commit_csn:
removing 0x7fe5a2237950 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: syncrepl_entry: rid=001 be_add
uid=mmouse,ou=verkauf,dc=local,dc=site (0)
Aug 18 15:30:25 ldapslave slapd[7860]: slap_queue_csn: queing
0x7fe5a2239920 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: syncprov_sendresp:
cookie=rid=002,csn=20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: syncprov_sendresp:
cookie=rid=002,csn=20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: do_syncrep2: rid=002
cookie=rid=002,csn=20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: slap_graduate_commit_csn:
removing 0x7fe5a2237950 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:25 ldapslave slapd[7860]: do_syncrep2: rid=002 CSN too
old, ignoring 20100818133025.570081Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: syncrepl_entry: rid=001
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY)
Aug 18 15:30:45 ldapslave slapd[7860]: syncrepl_entry: rid=001
be_search (0)
Aug 18 15:30:45 ldapslave slapd[7860]: syncrepl_entry: rid=001
uid=vcorleone,ou=marketing,dc=local,dc=site
Aug 18 15:30:45 ldapslave slapd[7860]: slap_queue_csn: queing
0x7fe5a2232c20 20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: slap_graduate_commit_csn:
removing 0x7fe5a22397e0 20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: syncrepl_entry: rid=001
be_modify uid=vcorleone,ou=marketing,dc=local,dc=site (0)
Aug 18 15:30:45 ldapslave slapd[7860]: syncprov_sendresp:
cookie=rid=002,csn=20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: slap_queue_csn: queing
0x7fe5a2232c20 20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: syncprov_sendresp:
cookie=rid=002,csn=20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: do_syncrep2: rid=002
cookie=rid=002,csn=20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: slap_graduate_commit_csn:
removing 0x7fe5a2014610 20100818133045.686887Z#000000#000#000000
Aug 18 15:30:45 ldapslave slapd[7860]: do_syncrep2: rid=002 CSN too
old, ignoring 20100818133045.686887Z#000000#000#000000
Aug 18 15:30:52 ldapslave dhcpcd[2588]: eth0: renewing lease of
172.16.0.9
Aug 18 15:30:52 ldapslave dhcpcd[2588]: eth0: leased 172.16.0.9 for
600 seconds
Aug 18 15:30:52 ldapslave dhcpcd[2588]: eth0: adding IP address
172.16.0.9/24
Aug 18 15:30:52 ldapslave dhcpcd[2588]: eth0: adding default route via
172.16.0.1 metric 0
Aug 18 15:30:52 ldapslave ifup: eth0 device: nVidia
Corporation MCP51 Ethernet Controller (rev a3)
Aug 18 15:31:00 ldapslave sshd[10870]: Accepted publickey for root
from 172.16.0.8 port 52210 ssh2
===_END_CHANGES_WHILE_BOTH_UP_===
Aug 18 15:31:00 ldapslave sshd[10870]: Received disconnect from
172.16.0.8: 11: disconnected by user
Aug 18 15:31:39 ldapslave slapd[7860]: daemon: shutdown requested and
initiated.
Aug 18 15:31:39 ldapslave slapd[7860]: slapd shutdown: waiting for 0
operations/tasks to finish
Aug 18 15:31:39 ldapslave slapd[7860]: slapd stopped.
Aug 18 15:31:40 ldapslave dhclient: XMT: Solicit on eth0, interval
122570ms.
Aug 18 15:32:13 ldapslave sshd[10906]: Accepted publickey for root
from 172.16.0.8 port 52223 ssh2
===_BEGIN_CHANGES_WHILE_STANDBY_DOWN_===
Aug 18 15:32:13 ldapslave sshd[10906]: Received disconnect from
172.16.0.8: 11: disconnected by user
Aug 18 15:33:26 ldapslave sshd[10936]: Accepted publickey for root
from 172.16.0.8 port 52255 ssh2
===_END_CHANGES_WHILE_STANDBY_DOWN_===
Aug 18 15:33:26 ldapslave sshd[10936]: Received disconnect from
172.16.0.8: 11: disconnected by user
Aug 18 15:33:42 ldapslave dhclient: XMT: Solicit on eth0, interval
110550ms.
Aug 18 15:33:54 ldapslave sshd[10964]: Accepted publickey for root
from 172.16.0.8 port 52266 ssh2
===_BEGIN_STANDBY_COMING_BACK_===
Aug 18 15:33:54 ldapslave sshd[10964]: Received disconnect from
172.16.0.8: 11: disconnected by user
Aug 18 15:34:03 ldapslave slapd[11015]: @(#) $OpenLDAP: slapd 2.4.21
(Jul 5 2010 13:35:22)
$#012#011abuild@build16:/usr/src/packages/BUILD/openldap-2.4.21/servers/slapd
Aug 18 15:34:03 ldapslave slapd[11017]: hdb_monitor_db_open:
monitoring disabled; configure monitor database to enable
Aug 18 15:34:03 ldapslave slapd[11017]: slapd starting
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=002
LDAP_RES_INTERMEDIATE - REFRESH_DELETE
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1cace9be-3f11-102f-86fc-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn=config 20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=001
LDAP_RES_INTERMEDIATE - SYNC_ID_SET
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133226.335074Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: slap_queue_csn: queing
0x7f0c980030d0 20100818133226.335074Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1cad9d96-3f11-102f-86fd-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1cace9be-3f11-102f-86fc-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn=config 20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1cae0042-3f11-102f-86fe-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn={0}core,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
cn={0}core,cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn={0}core,cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored (cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1cae28ba-3f11-102f-86ff-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn={1}cosine,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
cn={1}cosine,cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn={1}cosine,cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1cae4250-3f11-102f-8700-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn={2}nis,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
cn={2}nis,cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn={2}nis,cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1cae5006-3f11-102f-8701-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn={3}inetorgperson,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
cn={3}inetorgperson,cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (cn={3}inetorgperson,cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1cae5b5a-3f11-102f-8702-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN olcDatabase={-1}frontend,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
olcDatabase={-1}frontend,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (olcDatabase={-1}frontend,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1cae6b04-3f11-102f-8704-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN olcOverlay={0}syncprov,olcDatabase={0}config,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
olcOverlay={0}syncprov,olcDatabase={0}config,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored
(olcOverlay={0}syncprov,olcDatabase={0}config,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1caf4e66-3f11-102f-8705-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN olcDatabase={1}hdb,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
olcDatabase={1}hdb,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (olcDatabase={1}hdb,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
inserted UUID 1caf57bc-3f11-102f-8706-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004
olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=004 entry
unchanged, ignored (olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=004
LDAP_RES_INTERMEDIATE - REFRESH_DELETE
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=004 cookie=
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 913a0bf0-3f10-102f-92d5-4381bc469c2b
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: slap_graduate_commit_csn:
removing 0x7f0c98016a70 20100818133226.335074Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_del_nonpresent:
rid=001 be_delete uid=jmcclane,ou=security,dc=local,dc=site (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 be_add
cn=schema,cn=config (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncprov_sendresp:
cookie=rid=002,csn=20100818133226.335074Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=002
cookie=rid=002,csn=20100818133226.335074Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=002 CSN too
old, ignoring 20100818133226.335074Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=001
LDAP_RES_INTERMEDIATE - REFRESH_DELETE
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=001
cookie=rid=001,csn=20100818133305.849116Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1cae0042-3f11-102f-86fe-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn={0}core,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
cn={0}core,cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored (cn={0}core,cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: slap_queue_csn: queing
0x7f0c980161b0 20100818133305.849116Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1cae28ba-3f11-102f-86ff-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn={1}cosine,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
cn={1}cosine,cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored (cn={1}cosine,cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1cae4250-3f11-102f-8700-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn={2}nis,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
cn={2}nis,cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored (cn={2}nis,cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1cae5006-3f11-102f-8701-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN cn={3}inetorgperson,cn=schema,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
cn={3}inetorgperson,cn=schema,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored (cn={3}inetorgperson,cn=schema,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1cae5b5a-3f11-102f-8702-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN olcDatabase={-1}frontend,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
olcDatabase={-1}frontend,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored (olcDatabase={-1}frontend,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1cae6b04-3f11-102f-8704-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN olcOverlay={0}syncprov,olcDatabase={0}config,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
olcOverlay={0}syncprov,olcDatabase={0}config,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored
(olcOverlay={0}syncprov,olcDatabase={0}config,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1caf4e66-3f11-102f-8705-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN olcDatabase={1}hdb,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
olcDatabase={1}hdb,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored (olcDatabase={1}hdb,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
inserted UUID 1caf57bc-3f11-102f-8706-4bc6808babfc
Aug 18 15:34:03 ldapslave slapd[11017]: dn_callback : entries have
identical CSN olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config
20100818123730.848689Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
be_search (0)
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003
olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config
Aug 18 15:34:03 ldapslave slapd[11017]: syncrepl_entry: rid=003 entry
unchanged, ignored (olcOverlay={0}syncprov,olcDatabase={1}hdb,cn=config)
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=003
LDAP_RES_INTERMEDIATE - REFRESH_DELETE
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=003 cookie=
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=002
LDAP_RES_INTERMEDIATE - NEW_COOKIE
Aug 18 15:34:03 ldapslave slapd[11017]: do_syncrep2: rid=002
NEW_COOKIE: rid=002,csn=20100818133305.849116Z#000000#000#000000
Aug 18 15:34:03 ldapslave slapd[11017]: slap_graduate_commit_csn:
removing 0x7f0c980129b0 20100818133305.849116Z#000000#000#000000
Aug 18 15:34:48 ldapslave sshd[11032]: Accepted publickey for root
from 172.16.0.8 port 52273 ssh2
===_END_STANDBY_COMING_BACK_===
+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
Sorry for that lots of stuff... ;)
and thanks for your time !
best regards
--
elmar