Hi all,
i set up a multi master scenario using 2.4.21 on two servers. Online config (slapd.d) and ldap content is replicated fine, as long as both servers are up (means: i can change objects using ANY of the servers; and changes are transferred immediately to the other one. (Later in production, there will just one server be used actively, the other one shall be used by the clients just in case of failure)).
Then i shut down one of the servers, and do changes on the remaining one. I expect the switched-off server to get ALL the latest changes from the online-server as soon as it's up again. But this seems to happen only partially; for example: - deletion of a user object works ( = shows up on the former switched-off server immediately after coming up again) - adding of a user does not - changing just subordinate attributes of user objects, like telephone number, does not show up. It just gets replicated to the former switched-off machine, when something else of that object is changed while both servers are alive.
So finally i end up with different content on every machine... Did i miss something about how that works ?? Or is my config wrong for that; example:
syncrepl rid=001 provider="ldap://ldapmaster.local.site" type=refreshAndPersist retry="5 +" searchbase="dc=local,dc=site" bindmethod=simple binddn="cn=replicator,dc=local,dc=site" credentials="<secret>"
Thanks for help...
PS: my setup / slapd.conf is according to the book "openLDAP 2.4" by Oliver Liebel & John Martin Ungar.
-- elmar
Hi,
On 18/08/2010 11:01, Elmar Marschke wrote:
Hi all,
i set up a multi master scenario using 2.4.21 on two servers. Online config (slapd.d) and ldap content is replicated fine, as long as both servers are up (means: i can change objects using ANY of the servers; and changes are transferred immediately to the other one. (Later in production, there will just one server be used actively, the other one shall be used by the clients just in case of failure)).
Then i shut down one of the servers, and do changes on the remaining one. I expect the switched-off server to get ALL the latest changes from the online-server as soon as it's up again. But this seems to happen only partially; for example:
- deletion of a user object works ( = shows up on the former
switched-off server immediately after coming up again)
- adding of a user does not
- changing just subordinate attributes of user objects, like telephone
number, does not show up. It just gets replicated to the former switched-off machine, when something else of that object is changed while both servers are alive.
So finally i end up with different content on every machine... Did i miss something about how that works ??
No, this should work as you expect. I have several similar setups running that do.
Does your config also contain appropriate SID definitions and a syncrepl consumer for each master? With mirrormode set to TRUE? Are the servers tightly time synchronized, via NTP or equivalent? What error messages (if any) are given when running with "olcLogLevel: sync", on either nodes?
At least one bug related to replication was corrected in the latest version, 2.4.23. I suggest you try this version. If you still encounter problems, and your configuration is correct, it may be appropriate to file a bug.
Jonathan
Or is my config wrong for that; example:
syncrepl rid=001 provider="ldap://ldapmaster.local.site" type=refreshAndPersist retry="5 +" searchbase="dc=local,dc=site" bindmethod=simple binddn="cn=replicator,dc=local,dc=site" credentials="<secret>"
Thanks for help...
PS: my setup / slapd.conf is according to the book "openLDAP 2.4" by Oliver Liebel & John Martin Ungar.
-- elmar
Hello Jonathan & Oliver,
thanks for your answers... because you both are asking about config details, i'll try to answer but you can find my complete slapd.conf below, so that you can take a look by yourself.
On 18.08.2010 11:57, Jonathan Clarke wrote:
Hi,
Does your config also contain appropriate SID definitions and a syncrepl consumer for each master? With mirrormode set to TRUE?
i think so; please see my complete slapd.conf below. @Oliver: Server ID's are different, and i think i have also a separate syncrepl section for every server.
Are the servers tightly time synchronized, via NTP or equivalent?
I'm in doubt about that. Of course ntp is configured and works on both servers, but the offset from their master timeserver differs quite a bit: ldapmaster: remote refid offset ========================================= LOCAL(0) .LOCL. 0.000 *ns1.at.signintr 192.168.220.82 48.630 +a891lx03.schenk 192.168.220.82 188.350
ldapslave: remote refid offset ========================================= LOCAL(0) .LOCL. 0.000 *a890lx03.schenk 192.168.220.82 7.553 +ns2.at.signintr 192.168.220.82 132.635
I have read recommendations, that the offset of both servers should not differ more than one or two milliseconds, but i don't know how i could achieve / influence that. Both machines are in the same subnet and physically in the same location; and hardware and ntpd setup is the same.
What error messages (if any) are given when running with "olcLogLevel: sync", on either nodes?
It logs a lot, and i don't know for what to look exactly. Please give me some time to arrange it into a readable form...
Here's my complete slapd.conf: --------------------------------
loglevel 16384 # # See slapd.conf(5) for details on configuration options. # This file should NOT be world readable. # include /etc/openldap/schema/core.schema include /etc/openldap/schema/cosine.schema include /etc/openldap/schema/nis.schema include /etc/openldap/schema/inetorgperson.schema
# Define global ACLs to disable default read access.
# Do not enable referrals until AFTER you have a working directory # service AND an understanding of referrals. #referral ldap://root.openldap.org
pidfile /var/run/slapd/slapd.pid argsfile /var/run/slapd/slapd.args
# Load dynamic backend modules: # modulepath /usr/local/libexec/openldap # moduleload back_bdb.la # moduleload back_hdb.la # moduleload back_ldap.la
# Sample security restrictions # Require integrity protection (prevent hijacking) # Require 112-bit (3DES or better) encryption for updates # Require 63-bit encryption for simple bind # security ssf=1 update_ssf=112 simple_bind=64
# Sample access control policy: # Root DSE: allow anyone to read it # Subschema (sub)entry DSE: allow anyone to read it # Other DSEs: # Allow self write access # Allow authenticated users read access # Allow anonymous users to authenticate # Directives needed to implement policy:
access to dn.base="" by * read
access to dn.base="cn=Subschema" by * read
access to attrs=userPassword,userPKCS12 by self write by * auth
access to attrs=shadowLastChange by self write by * read
access to * by * read
# # if no access controls are present, the default policy # allows anyone and everyone to read anything but restricts # updates to rootdn. (e.g., "access to * by * read") # # rootdn can always read and write EVERYTHING!
## server-ids/urls fuer mmr ###
ServerID 1 "ldap://ldapmaster.local.site" ServerID 2 "ldap://ldapslave.local.site"
####################################################################### database config rootdn cn=config rootpw {SSHA}4PvZLcpQ7s1CyQG+yworyl5DcrFTn78q
### syncrepl- direktiven fuer mmr der olc ### syncrepl rid=003 provider="ldap://ldapmaster.local.site" searchbase="cn=config" type=refreshAndPersist retry="5 +" bindmethod=simple binddn="cn=config" credentials="<secret>" filter="(!(olcDatabase={0}config))"
syncrepl rid=004 provider="ldap://ldapslave.local.site" searchbase="cn=config" type=refreshAndPersist retry="5 +" bindmethod=simple binddn="cn=config" credentials="<secret>" filter="(!(olcDatabase={0}config))"
overlay syncprov MirrorMode On
####################################################################### # BDB database definitions #######################################################################
database hdb suffix "dc=local,dc=site" rootdn "cn=ldapadmin,dc=local,dc=site" # Cleartext passwords, especially for the rootdn, should # be avoid. See slappasswd(8) and slapd.conf(5) for details. # Use of strong authentication encouraged. rootpw {SSHA}iLwhoppdqOjJ+0HUroiScDJ3cpbOgo4u # The database directory MUST exist prior to running slapd AND # should only be accessible by the slapd and slap tools. # Mode 700 recommended. directory /var/lib/ldap/ # Indices to maintain index objectClass eq index entryUUID,entryCSN eq
overlay syncprov syncprov-checkpoint 10 1 syncprov-sessionlog 100
limits dn.exact="cn=replicator,dc=local,dc=site" size=unlimited time=unlimited
access to * by dn.exact="cn=replicator,dc=local,dc=site" read by * break
### syncrepl- direktiven fuer mmr des hauptkontextes ### syncrepl rid=001 provider="ldap://ldapmaster.local.site" type=refreshAndPersist retry="5 +" searchbase="dc=local,dc=site" bindmethod=simple binddn="cn=replicator,dc=local,dc=site" credentials="<secret>"
syncrepl rid=002 provider=ldap://ldapslave.local.site type=refreshAndPersist retry="5 +" searchbase="dc=local,dc=site" bindmethod=simple binddn="cn=replicator,dc=local,dc=site" credentials="<secret>"
MirrorMode On
----------------------------------------
best regards.. -- elmar
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>
Am 18.08.2010 13:21, schrieb Elmar Marschke:
Hello Jonathan & Oliver,
thanks for your answers... because you both are asking about config details, i'll try to answer but you can find my complete slapd.conf below, so that you can take a look by yourself.
On 18.08.2010 11:57, Jonathan Clarke wrote:
Hi,
Does your config also contain appropriate SID definitions and a syncrepl consumer for each master? With mirrormode set to TRUE?
i think so; please see my complete slapd.conf below. @Oliver: Server ID's are different, and i think i have also a separate syncrepl section for every server.
Are the servers tightly time synchronized, via NTP or equivalent?
I'm in doubt about that. Of course ntp is configured and works on both servers, but the offset from their master timeserver differs quite a bit: ldapmaster: remote refid offset ========================================= LOCAL(0) .LOCL. 0.000 *ns1.at.signintr 192.168.220.82 48.630 +a891lx03.schenk 192.168.220.82 188.350
ldapslave: remote refid offset ========================================= LOCAL(0) .LOCL. 0.000 *a890lx03.schenk 192.168.220.82 7.553 +ns2.at.signintr 192.168.220.82 132.635
I have read recommendations, that the offset of both servers should not differ more than one or two milliseconds, but i don't know how i could achieve / influence that. Both machines are in the same subnet and physically in the same location; and hardware and ntpd setup is the same.
What error messages (if any) are given when running with "olcLogLevel: sync", on either nodes?
It logs a lot, and i don't know for what to look exactly. Please give me some time to arrange it into a readable form...
Here's my complete slapd.conf:
loglevel 16384 # # See slapd.conf(5) for details on configuration options. # This file should NOT be world readable. # include /etc/openldap/schema/core.schema include /etc/openldap/schema/cosine.schema include /etc/openldap/schema/nis.schema include /etc/openldap/schema/inetorgperson.schema
# Define global ACLs to disable default read access.
# Do not enable referrals until AFTER you have a working directory # service AND an understanding of referrals. #referral ldap://root.openldap.org
pidfile /var/run/slapd/slapd.pid argsfile /var/run/slapd/slapd.args
# Load dynamic backend modules: # modulepath /usr/local/libexec/openldap # moduleload back_bdb.la # moduleload back_hdb.la # moduleload back_ldap.la
# Sample security restrictions # Require integrity protection (prevent hijacking) # Require 112-bit (3DES or better) encryption for updates # Require 63-bit encryption for simple bind # security ssf=1 update_ssf=112 simple_bind=64
# Sample access control policy: # Root DSE: allow anyone to read it # Subschema (sub)entry DSE: allow anyone to read it # Other DSEs: # Allow self write access # Allow authenticated users read access # Allow anonymous users to authenticate # Directives needed to implement policy:
access to dn.base="" by * read
access to dn.base="cn=Subschema" by * read
access to attrs=userPassword,userPKCS12 by self write by * auth
access to attrs=shadowLastChange by self write by * read
access to * by * read
# # if no access controls are present, the default policy # allows anyone and everyone to read anything but restricts # updates to rootdn. (e.g., "access to * by * read") # # rootdn can always read and write EVERYTHING!
## server-ids/urls fuer mmr ###
ServerID 1 "ldap://ldapmaster.local.site" ServerID 2 "ldap://ldapslave.local.site"
####################################################################### database config rootdn cn=config rootpw {SSHA}4PvZLcpQ7s1CyQG+yworyl5DcrFTn78q
### syncrepl- direktiven fuer mmr der olc ### syncrepl rid=003 provider="ldap://ldapmaster.local.site" searchbase="cn=config" type=refreshAndPersist retry="5 +" bindmethod=simple binddn="cn=config" credentials="<secret>" filter="(!(olcDatabase={0}config))"
syncrepl rid=004 provider="ldap://ldapslave.local.site" searchbase="cn=config" type=refreshAndPersist retry="5 +" bindmethod=simple binddn="cn=config" credentials="<secret>" filter="(!(olcDatabase={0}config))"
overlay syncprov MirrorMode On
####################################################################### # BDB database definitions #######################################################################
database hdb suffix "dc=local,dc=site" rootdn "cn=ldapadmin,dc=local,dc=site" # Cleartext passwords, especially for the rootdn, should # be avoid. See slappasswd(8) and slapd.conf(5) for details. # Use of strong authentication encouraged. rootpw {SSHA}iLwhoppdqOjJ+0HUroiScDJ3cpbOgo4u # The database directory MUST exist prior to running slapd AND # should only be accessible by the slapd and slap tools. # Mode 700 recommended. directory /var/lib/ldap/ # Indices to maintain index objectClass eq index entryUUID,entryCSN eq
overlay syncprov syncprov-checkpoint 10 1 syncprov-sessionlog 100
limits dn.exact="cn=replicator,dc=local,dc=site" size=unlimited time=unlimited
access to * by dn.exact="cn=replicator,dc=local,dc=site" read by * break
### syncrepl- direktiven fuer mmr des hauptkontextes ### syncrepl rid=001 provider="ldap://ldapmaster.local.site" type=refreshAndPersist retry="5 +" searchbase="dc=local,dc=site" bindmethod=simple binddn="cn=replicator,dc=local,dc=site" credentials="<secret>"
syncrepl rid=002 provider=ldap://ldapslave.local.site type=refreshAndPersist retry="5 +" searchbase="dc=local,dc=site" bindmethod=simple binddn="cn=replicator,dc=local,dc=site" credentials="<secret>"
MirrorMode On
best regards..
elmar
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
please sync the time first as exact as possible, and make an initial resync with a fresh test-database
Am 18.08.2010 16:28, schrieb Elmar Marschke:
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
On 18/08/2010 16:28, Elmar Marschke wrote:
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_===
Clearly, slapd has not taken into account the SID parameters. You can see this in these CSN, the 3rd fields (the 3 digits before the last #) is 000, which is the default SID.
The reason for this is most likely that slapd cannot recognize that "ldapmaster.local.site" refers to the machine it's running on. I can see a few options to solve this: - configure /etc/hosts so that host --fqdn returns this name - run slapd with -h ldap://ldapmaster.local.site
After changing it, I recommend a full re-sync of your backup instance.
This should help!
Jonathan
Am 18.08.2010 11:01, schrieb Elmar Marschke:
Hi all,
i set up a multi master scenario using 2.4.21 on two servers. Online config (slapd.d) and ldap content is replicated fine, as long as both servers are up (means: i can change objects using ANY of the servers; and changes are transferred immediately to the other one. (Later in production, there will just one server be used actively, the other one shall be used by the clients just in case of failure)).
Then i shut down one of the servers, and do changes on the remaining one. I expect the switched-off server to get ALL the latest changes from the online-server as soon as it's up again. But this seems to happen only partially; for example:
- deletion of a user object works ( = shows up on the former
switched-off server immediately after coming up again)
- adding of a user does not
- changing just subordinate attributes of user objects, like
telephone number, does not show up. It just gets replicated to the former switched-off machine, when something else of that object is changed while both servers are alive.
So finally i end up with different content on every machine... Did i miss something about how that works ?? Or is my config wrong for that; example:
syncrepl rid=001 provider="ldap://ldapmaster.local.site" type=refreshAndPersist retry="5 +" searchbase="dc=local,dc=site" bindmethod=simple binddn="cn=replicator,dc=local,dc=site" credentials="<secret>"
Thanks for help...
did you setup the serverids correctly, e.g.:
ServerID 1 "ldap://master1.local.site" ServerID 2 "ldap://master2.local.site"
you also need a separate syncrepl section for every server in your multimaster-setup
PS: my setup / slapd.conf is according to the book "openLDAP 2.4" by Oliver Liebel & John Martin Ungar.
-- elmar
openldap-technical@openldap.org
-
Elmar Marschke -
Jonathan CLARKE -
Jonathan Clarke -
Oliver Liebel