Re: [Fwd: SyncRepl Problem 2.3.20 RefreshandPersist]

[Duncan Brannen <dbb@st-andrews.ac.uk>]

Aaron Richton wrote:
> Erm...
>
>   
> > The last slave I switched back to using RefreshOnly which has picked up
> > all the changes.
> >     
>
> Sorry, overlooked that. Are you patched for ITS#4417? (I use refreshOnly
> and am still in a bad spot...)
>
>   
I haven't patched for 4417, and while my slaves are picking up the 
changes I do still see those delete
messages in the logs, just not always.  The slaves have all been in 
RefreshOnly mode since yesterday
afternoon, all changes seem to have propagated successfully in that 
time.  What I haven' managed to do
is search for the entry in the 13 seconds between a the del_nonpresent: 
be_delete line and the be_add
line. 

What's different from your case is that these are connections modifying 
a single user, albeit 3 attributes, though
I did see more of the be_delete entries and more frequently yesterday 
when I was re modifying
a large number of users (same 3 attributes) that hadn't propagated.

I'm reading your posts on -dev and only seeing stuff about 4365 which is 
similar?


For Example

Master.log
Mar 21 16:03:45 master slapd[14964]: [ID 215403 local4.debug] conn=1797 
op=1 BIND dn="uid=USER1,ou=People,dc=example,dc=com" method=128
Mar 21 16:03:45 master slapd[14964]: [ID 600343 local4.debug] conn=1797 
op=1 BIND dn="uid=USER1,ou=People,dc=example,dc=com" mech=SIMPLE ssf=0
Mar 21 16:03:45 master slapd[14964]: [ID 588225 local4.debug] conn=1797 
op=1 RESULT tag=97 err=0 text=
Mar 21 16:03:45 master slapd[14964]: [ID 249368 local4.debug] conn=1797 
op=2 MOD dn="uid=USER1,ou=People,dc=example,dc=com"
Mar 21 16:03:45 master slapd[14964]: [ID 396994 local4.debug] conn=1797 
op=2 MOD attr=sambaLMPassword sambaNTPassword userPassword

Slave1.log
Mar 21 16:03:52 slave1 slapd[22162]: [ID 151447 local4.debug] 
syncrepl_del_nonpresent: be_delete uid=USER1,ou=People,dc=example,dc=com (0)
Mar 21 16:04:15 slave1 slapd[22162]: [ID 801593 local4.debug] 
syncrepl_entry: LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Mar 21 16:04:15 slave1 slapd[22162]: [ID 181434 local4.debug] 
syncrepl_entry: be_search (0)
Mar 21 16:04:15 slave1 slapd[22162]: [ID 260518 local4.debug] 
syncrepl_entry: uid=USER1,ou=People,dc=example,dc=com
Mar 21 16:04:15 slave1 slapd[22162]: [ID 181434 local4.debug] 
syncrepl_entry: be_add (0)



Slave2.log
Mar 21 16:03:52 slave2 slapd[17389]: [ID 151447 local4.debug] 
syncrepl_del_nonpresent: be_delete uid=USER1,ou=People,dc=example,dc=com (0)
Mar 21 16:04:15 slave2 slapd[17389]: [ID 260518 local4.debug] 
syncrepl_entry: uid=USER1,ou=People,dc=example,dc=com

Slave3.log
Mar 21 16:03:58 slave3 slapd[25755]: [ID 260518 local4.debug] 
syncrepl_entry: uid=USER1,ou=People,dc=example,dc=com
Mar 21 16:03:58 slave3 slapd[25755]: [ID 181434 local4.debug] 
syncrepl_entry: be_modify (0)

Versus

Master.log
Mar 21 16:04:28 master slapd[14964]: [ID 215403 local4.debug] conn=1805 
op=1 BIND dn="uid=USER2,ou=People,dc=example,dc=com" method=128
Mar 21 16:04:28 master slapd[14964]: [ID 600343 local4.debug] conn=1805 
op=1 BIND dn="uid=USER2,ou=People,dc=example,dc=com" mech=SIMPLE ssf=0
Mar 21 16:04:28 master slapd[14964]: [ID 588225 local4.debug] conn=1805 
op=1 RESULT tag=97 err=0 text=
Mar 21 16:04:28 master slapd[14964]: [ID 249368 local4.debug] conn=1805 
op=2 MOD dn="uid=USER2,ou=People,dc=example,dc=com"
Mar 21 16:04:28 master slapd[14964]: [ID 396994 local4.debug] conn=1805 
op=2 MOD attr=sambaLMPassword sambaNTPassword userPassword
Mar 21 16:04:28 master slapd[14964]: [ID 588225 local4.debug] conn=1805 
op=2 RESULT tag=103 err=0 text=
Mar 21 16:04:28 master slapd[14964]: [ID 218904 local4.debug] conn=1805 
op=3 UNBIND
Mar 21 16:04:28 master slapd[14964]: [ID 952275 local4.debug] conn=1805 
fd=13 closed

Slave1
Mar 21 16:04:41 slave1 slapd[22162]: [ID 801593 local4.debug] 
syncrepl_entry: LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Mar 21 16:04:41 slave1 slapd[22162]: [ID 181434 local4.debug] 
syncrepl_entry: be_search (0)
Mar 21 16:04:41 slave1 slapd[22162]: [ID 260518 local4.debug] 
syncrepl_entry: uid=USER2,ou=People,dc=example,dc=com
Mar 21 16:04:41 slave1 slapd[22162]: [ID 181434 local4.debug] 
syncrepl_entry: be_modify (0)

Slave2
Mar 21 16:04:41 slave2 slapd[17389]: [ID 801593 local4.debug] 
syncrepl_entry: LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Mar 21 16:04:41 slave2 slapd[17389]: [ID 181434 local4.debug] 
syncrepl_entry: be_search (0)
Mar 21 16:04:41 slave2 slapd[17389]: [ID 260518 local4.debug] 
syncrepl_entry: uid=USER2,ou=People,dc=example,dc=com
Mar 21 16:04:41 slave2 slapd[17389]: [ID 181434 local4.debug] 
syncrepl_entry: be_modify (0)

Slave3
Mar 21 16:04:37 slave3 slapd[25755]: [ID 801593 local4.debug] 
syncrepl_entry: LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD)
Mar 21 16:04:37 slave3 slapd[25755]: [ID 181434 local4.debug] 
syncrepl_entry: be_search (0)
Mar 21 16:04:37 slave3 slapd[25755]: [ID 260518 local4.debug] 
syncrepl_entry: uid=USER2,ou=People,dc=example,dc=com
Mar 21 16:04:37 slave3 slapd[25755]: [ID 181434 local4.debug] 
syncrepl_entry: be_modify (0)