# The consumer sees some activity... Oct 3 11:32:30 consumer slapd[4353]: do_syncrep2: cookie=rid=001,sid=001,csn=2 0071003093228.079707Z#000000#000#000000 Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 LDAP_RES_SEARCH_ENT RY(LDAP_SYNC_ADD) Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 be_search (0) Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 ou=Test,dc=sys-net,dc=it # note here sending response with sid=000 !?! Oct 3 11:32:30 consumer slapd[4353]: syncprov_sendresp: cookie=rid=002,sid=000 Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 be_add (0) Oct 3 11:32:30 consumer slapd[4353]: do_syncrep2: cookie=rid=001,sid=001,csn=2 0071003093230.288430Z#000000#000#000000 Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_DELETE) Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 be_search (0) Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 (null) Oct 3 11:32:30 consumer slapd[4353]: do_syncrep2: cookie=rid=001,sid=001,csn=2 0071003093230.355817Z#000000#000#000000 Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_ADD) Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 be_search (0) Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 ou=Test,dc=sys-net,dc=it # apparently the delete did not succeed !?! (again the bogus sid) Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 be_add (68) Oct 3 11:32:30 consumer slapd[4353]: syncprov_sendresp: cookie=rid=002,sid=000 Oct 3 11:32:30 consumer slapd[4353]: syncrepl_entry: rid=1 be_modify (0) Oct 3 11:32:31 consumer slapd[4353]: do_syncrep2: cookie=rid=001,sid=001,csn=2 0071003093230.404017Z#000000#000#000000 Oct 3 11:32:31 consumer slapd[4353]: syncrepl_entry: rid=1 LDAP_RES_SEARCH_ENT RY(LDAP_SYNC_DELETE) Oct 3 11:32:31 consumer slapd[4353]: syncrepl_entry: rid=1 be_search (0) Oct 3 11:32:31 consumer slapd[4353]: syncrepl_entry: rid=1 (null) # ... a sync request comes in Oct 3 11:32:37 consumer slapd[4353]: conn=2 op=1 SRCH base="dc=a,dc=prod,dc=ac talis" scope=2 deref=0 filter="(objectClass=*)" Oct 3 11:32:37 consumer slapd[4353]: conn=2 op=1 SRCH attr=* + Oct 3 11:32:37 consumer slapd[4353]: srs csn 20071002095656.101415Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093230.355817Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093230.451608Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093230.591655Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093230.780601Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093231.378344Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093232.312808Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093232.633093Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093232.856402Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093233.091760Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093233.266701Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093233.514740Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093233.756128Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093233.925149Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093234.179124Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093234.384376Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093234.601645Z#000000#000#000000 Oct 3 11:32:37 consumer slapd[4353]: log csn 20071003093234.855526Z#000000#000#000000 # ... notice here, where the bogus cookie is generated Oct 3 11:32:37 consumer slapd[4353]: syncprov_playlog: cookie=rid=002,sid=000,csn= # ... the line below refers to an entry that was not touched # by this test, btw !?! Oct 3 11:32:37 consumer slapd[4353]: Entry cn=xxxxxx,dc=sys-net,dc=it changed by peer, ignored