[Date Prev][Date Next] [Chronological] [Thread] [Top]
(ITS#8125) MMR throws away valid changes causing drift

To: openldap-its@OpenLDAP.org
Subject: (ITS#8125) MMR throws away valid changes causing drift
From: quanah@openldap.org
Date: Mon, 04 May 2015 20:00:43 +0000
Auto-submitted: auto-generated (OpenLDAP-ITS)
Full_Name: Quanah Gibson-Mount
Version: RE24
OS: Linux 2.6
URL: ftp://ftp.openldap.org/incoming/
Submission from: (NULL) (75.111.52.177)


On Friday May 1st, reloaded mmr00 from mmr01, so they had exactly the same
dataset.  Since Friday, significant drift has occurred between the two nodes. 
One example is the following MODRDN request that was made to mmr00.  Note:
Clocks are tightly in sync between both nodes.

On MMR00, we see the modrdn occur:

May  1 16:10:18 zm-mmr00 slapd[52931]: conn=13581 op=2 MODRDN
dn="uid=xxxxxx,ou=people,dc=yyyy,dc=net"
May  1 16:10:18 zm-mmr00 slapd[52931]: conn=1145 op=181451 SEARCH RESULT tag=101
err=0 nentries=1 text=
May  1 16:10:18 zm-mmr00 slapd[52931]: slap_queue_csn: queueing 0x7f2b8c8931c0
20150501201018.574740Z#000000#002#000000
May  1 16:10:18 zm-mmr00 slapd[52931]: slap_queue_csn: queueing 0x14ebeb40
20150501201018.574740Z#000000#002#000000
May  1 16:10:18 zm-mmr00 slapd[52931]: slap_graduate_commit_csn: removing
0x1d53e480 20150501201018.574740Z#000000#002#000000
May  1 16:10:18 zm-mmr00 slapd[52931]: conn=13581 op=2 RESULT tag=109 err=0
text=
May  1 16:10:18 zm-mmr00 slapd[52931]: slap_graduate_commit_csn: removing
0x10be8520 20150501201018.574740Z#000000#002#000000



Everything looks good on this host.  Now on to MMR01... nearly 1.5 HOURS LATER
WE SEE:

May  1 17:27:36 zm-mmr01 slapd[43243]: syncrepl_message_to_op: rid=100 be_modify
uid=someotherentry,ou=people,dc=differentdomain,dc=com (0)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201017.390034Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201017.390034Z#000000#00b#000000
(reqStart=20150501201017.000302Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201017.528111Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapdC43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201017.528111Z#000000#00b#000000
(reqStart=20150501201017.000362Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201017.619314Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201017.619314Z#000000#00b#000000
(reqStart=20150501201017.000406Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.502559Z#000000#002#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201018.502559Z#000000#002#000000
(reqStart=20150501201018.000269Z,cn=cecesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.519620Z#000000#002#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201018.519620Z#000000#002#000000
(reqStart=20150501201018.000289Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.574740Z#000000#002#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too
old,gignoring 20150501201018.574740Z#000000#002#000000
(reqStart=20150501201018.000336Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.611377Z#000000#002#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201018.611377Z#000000#002#000000
(reqStart=20150501201018.000358Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.577381Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201018.577381Z#000000#00b#000000
(reqStart=20150501201018.000366Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.628228Z#000000#002#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201018.628228Z#000000#002#000000
(reqStart=20150501201018.000378Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.648496Z#000000#002#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201018.648496Z#000000#002#000000
(reqStart=20150501201018.000398Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.747799Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201018.747799Z#000000#00b#000000
(reqStart=20150501201018.000517Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201018.926821Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201018.926821Z#000000#00b#000000
(reqStart=20150501201018.000639Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201019.051637Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201019.051637Z#000000#00b#000000
(reqStart=20150501201019.000052Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201019.107217Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201019.107217Z#000000#00b#000000
(reqStart=20150501201019.000092Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201019.165448Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201019.165448Z#000000#00b#000000
(reqStart=20150501201019.000146Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002,csn=20150501201019.221163Z#000000#00b#000000
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100 CSN too old,
ignoring 20150501201019.221163Z#000000#00b#000000
(reqStart=20150501201019.000190Z,cn=accesslog)
May  1 17:27:36 zm-mmr01 slapd[43243]: do_syncrep2: rid=100
cookie=rid=100,sid=002
May  1 17:27:37 zm-mmr01 slapd[43243]: do_syncrep2: rid=102
cookie=rid=102,sid=00b,csn=20150501212737.068523Z#000000#00b#000000
May  1 17:27:37 zm-mmr01 slapd[43243]: syncrepl_message_to_op: rid=102 be_modify
uid=anotherdifferententry,ou=people,dc=samedomain,dc=net (0)
Prev by Date: Re: (ITS#8117) Bugs related to key-size in lmdb and backend
Next by Date: (ITS#8126) pwdFailureTime on client authentication
Index(es):
- Chronological
- Thread