[Date Prev][Date Next] [Chronological] [Thread] [Top]

(ITS#4198) Empty Accesslog DB causes delta-syncrepl problem



Full_Name: Quanah Gibson-mount
Version: 2.3.12 + HEAD accesslog overlay
OS: Solaris 8
URL: ftp://ftp.openldap.org/incoming/
Submission from: (NULL) (171.66.155.86)


I've set up delta-syncrepl yet again, using the accesslog.c from HEAD since it
has some necessary fixes.  However, I find that once the slapd is started, and
the accesslog DB created, clients connecting to it go into a frenzy trying to
get updates.

Here is the accesslog DB (definately exists...)

ldapsearch -LLL -Q -h ldap-dev0 -b "cn=accesslog" +
dn: cn=accesslog
structuralObjectClass: auditContainer
entryDN: cn=accesslog
subschemaSubentry: cn=Subschema
contextCSN: 20051122194620Z#000000#00#000000
hasSubordinates: FALSE

ldapsearch -LLL -Q -h ldap-dev0 -b "cn=accesslog"
dn: cn=accesslog
objectClass: auditContainer
cn: accesslog


And the log shows:

Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 848112 local4.debug]
conn=2 fd=21 ACCEPT from IP=171.67.16.106:55225 (IP=0.0.0.0:389)
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 215403 local4.debug]
conn=2 op=0 BIND dn="" method=163
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 588225 local4.debug]
conn=2 op=0 RESULT tag=97 err=14 text=
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 215403 local4.debug]
conn=2 op=1 BIND dn="" method=163
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 588225 local4.debug]
conn=2 op=1 RESULT tag=97 err=14 text=
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 215403 local4.debug]
conn=2 op=2 BIND dn="" method=163
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 538062 local4.debug]
conn=2 op=2 BIND authcid="ldap/ldap-dev3.stanford.edu@stanford.edu" authzid="ld
ap/ldap-dev3.stanford.edu@stanford.edu"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 690767 local4.debug]
conn=2 op=2 BIND dn="cn=ldap-dev3,cn=ldap,cn=operational,dc=stanford,dc=edu" me
ch=GSSAPI ssf=56
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 588225 local4.debug]
conn=2 op=2 RESULT tag=97 err=0 text=
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=3 SRCH base="cn=accesslog" scope=1 deref=0 filter="(&(objectClass=aud
itWriteObject)(reqResult=0))"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=3 SRCH attr=reqDN reqType reqMod reqNewRDN reqDeleteOldRDN reqNewSupe
rior entryCSN
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=3 SEARCH RESULT tag=101 err=4096 nentries=0 text=sync cookie is stale
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=4 SRCH base="dc=stanford,dc=edu" scope=2 deref=0 filter="(objectClass
=*)"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=4 SRCH attr=* +
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=4 SEARCH RESULT tag=101 err=0 nentries=0 text=
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=5 SRCH base="cn=accesslog" scope=1 deref=0 filter="(&(objectClass=aud
itWriteObject)(reqResult=0))"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=5 SRCH attr=reqDN reqType reqMod reqNewRDN reqDeleteOldRDN reqNewSupe
rior entryCSN
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=5 SEARCH RESULT tag=101 err=4096 nentries=0 text=sync cookie is stale
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=6 SRCH base="dc=stanford,dc=edu" scope=2 deref=0 filter="(objectClass
=*)"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=6 SRCH attr=* +
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=6 SEARCH RESULT tag=101 err=0 nentries=0 text=
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=7 SRCH base="cn=accesslog" scope=1 deref=0 filter="(&(objectClass=aud
itWriteObject)(reqResult=0))"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=7 SRCH attr=reqDN reqType reqMod reqNewRDN reqDeleteOldRDN reqNewSupe
rior entryCSN
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=7 SEARCH RESULT tag=101 err=4096 nentries=0 text=sync cookie is stale
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=8 SRCH base="dc=stanford,dc=edu" scope=2 deref=0 filter="(objectClass
=*)"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=8 SRCH attr=* +
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=8 SEARCH RESULT tag=101 err=0 nentries=0 text=
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=9 SRCH base="cn=accesslog" scope=1 deref=0 filter="(&(objectClass=aud
itWriteObject)(reqResult=0))"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=9 SRCH attr=reqDN reqType reqMod reqNewRDN reqDeleteOldRDN reqNewSupe
rior entryCSN
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=9 SEARCH RESULT tag=101 err=4096 nentries=0 text=sync cookie is stale
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=10 SRCH base="dc=stanford,dc=edu" scope=2 deref=0 filter="(objectClas
s=*)"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=10 SRCH attr=* +
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=10 SEARCH RESULT tag=101 err=0 nentries=0 text=
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 469902 local4.debug]
conn=2 op=11 SRCH base="cn=accesslog" scope=1 deref=0 filter="(&(objectClass=au
ditWriteObject)(reqResult=0))"
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 744844 local4.debug]
conn=2 op=11 SRCH attr=reqDN reqType reqMod reqNewRDN reqDeleteOldRDN reqNewSup
erior entryCSN
Nov 22 11:47:18 ldap-dev0.Stanford.EDU slapd[11394]: [ID 167594 local4.debug]
conn=2 op=11 SEARCH RESULT tag=101 err=4096 nentries=0 text=sync cookie is stal
e


....
etc for some 200,000 operations.

However, only one of my 3 replicas is doing this?!

--Quanah