[Freeipa-users] Freeipa 4.3.0 replica installation fails with DuplicateEntry: This entry already exists

Mon Jan 18 17:58:28 UTC 2016

Nathan Peters wrote:
> I assume you mean look at the DS log on the machine being installed?\

I think he meant on the master that generated the prepare file. There
may be some left-over, unexpected entry.

rob

> 
> There is no "err=68" anywhere in the access file :
> 
> [root at dc2-ipa-dev-van slapd-DEV-GLOBALRELAY-NET]# grep "err=68" access
> [root at dc2-ipa-dev-van slapd-DEV-GLOBALRELAY-NET]#
> 
> 
> Here is the last few lines of the latest attempt to join so we can see time for context : 
> 
> [27/43]: restarting directory server
>   [28/43]: setting up initial replication
>   [error] DuplicateEntry: This entry already exists
> Your system may be partly configured.
> Run /usr/sbin/ipa-server-install --uninstall to clean up.
> 
> ipa.ipapython.install.cli.install_tool(Replica): ERROR    This entry already exists
> ipa.ipapython.install.cli.install_tool(Replica): ERROR    The ipa-replica-install command failed. See /var/log/ipareplica-install.log for more information
> [root at dc2-ipa-dev-van dirsrv]# tail /var/log/ipareplica-install.log
>   File "/usr/lib/python2.7/site-packages/ipapython/ipaldap.py", line 1442, in add_entry
>     self.conn.add_s(str(entry.dn), list(attrs.items()))
>   File "/usr/lib64/python2.7/contextlib.py", line 35, in __exit__
>     self.gen.throw(type, value, traceback)
>   File "/usr/lib/python2.7/site-packages/ipapython/ipaldap.py", line 947, in error_handler
>     raise errors.DuplicateEntry()
> 
> 2016-01-18T17:28:33Z DEBUG The ipa-replica-install command failed, exception: DuplicateEntry: This entry already exists
> 2016-01-18T17:28:33Z ERROR This entry already exists
> 2016-01-18T17:28:33Z ERROR The ipa-replica-install command failed. See /var/log/ipareplica-install.log for more information
> 
> And here is the access log (for some reason access is in PST and the install log is in UTC, but the equivalent time was 9:28:33
> 
> The last add result before the installation crash appears to be this one (which appears to happen successfully): 
> 
> [18/Jan/2016:09:28:32 -0800] conn=2 op=10 ADD dn="cn=Peer Master,cn=mapping,cn=sasl,cn=config"
> [18/Jan/2016:09:28:32 -0800] conn=2 op=10 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=11 UNBIND
> 
> As you can see from the logs below, the server keeps running ,and I have included another entry almost a minute after the crash, so it is obviously still logging, but just doesn't seem to log the failure.
> 
> Also included is the ldapsearch of that branch on the master : 
> 
> [root at dc1-ipa-dev-nvan slapd-MYDOMAIN-NET]# ldapsearch -D "cn=directory manager" -W -b "cn=mapping,cn=sasl,cn=config"
> Enter LDAP Password:
> # extended LDIF
> #
> # LDAPv3
> # base <cn=mapping,cn=sasl,cn=config> with scope subtree
> # filter: (objectclass=*)
> # requesting: ALL
> #
> 
> # mapping, sasl, config
> dn: cn=mapping,cn=sasl,cn=config
> cn: mapping
> objectClass: top
> objectClass: nsContainer
> 
> # Full Principal, mapping, sasl, config
> dn: cn=Full Principal,cn=mapping,cn=sasl,cn=config
> cn: Full Principal
> nsSaslMapBaseDNTemplate: dc=mydomain,dc=net
> nsSaslMapFilterTemplate: (krbPrincipalName=\1@\2)
> nsSaslMapPriority: 10
> nsSaslMapRegexString: \(.*\)@\(.*\)
> objectClass: top
> objectClass: nsSaslMapping
> 
> # Name Only, mapping, sasl, config
> dn: cn=Name Only,cn=mapping,cn=sasl,cn=config
> cn: Name Only
> nsSaslMapBaseDNTemplate: dc=mydomain,dc=net
> nsSaslMapFilterTemplate: (krbPrincipalName=&@MYDOMAIN.NET)
> nsSaslMapPriority: 10
> nsSaslMapRegexString: ^[^:@]+$
> objectClass: top
> objectClass: nsSaslMapping
> 
> # search result
> search: 2
> result: 0 Success
> 
> # numResponses: 4
> # numEntries: 3
> 
> 
> --- access logs from the server I attempted to join ---
> 
> [18/Jan/2016:09:28:17 -0800] conn=1 fd=64 slot=64 connection from 10.21.5.241 to 10.21.0.98
> [18/Jan/2016:09:28:17 -0800] conn=1 op=0 SRCH base="" scope=0 filter="(objectClass=*)" attrs="* altServer namingContexts supportedControl supportedExtension supportedFeatures supportedLDAPVersion supportedSASLMechanisms domaincontrollerfunctionality defaultnamingcontext lastusn highestcommittedusn aci"
> [18/Jan/2016:09:28:17 -0800] conn=1 op=0 RESULT err=0 tag=101 nentries=1 etime=0
> [18/Jan/2016:09:28:18 -0800] conn=1 op=1 UNBIND
> [18/Jan/2016:09:28:18 -0800] conn=1 op=1 fd=64 closed - U1
> [18/Jan/2016:09:28:19 -0800] conn=1 fd=64 slot=64 connection from ::1 to ::1
> [18/Jan/2016:09:28:19 -0800] conn=1 op=-1 fd=64 closed - B1
> [18/Jan/2016:09:28:19 -0800] conn=2 fd=64 slot=64 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:19 -0800] conn=2 op=0 BIND dn="cn=directory manager" method=128 version=3
> [18/Jan/2016:09:28:19 -0800] conn=2 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:19 -0800] conn=3 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:19 -0800] conn=3 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:19 -0800] conn=3 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:19 -0800] conn=3 op=1 MOD dn="cn=MemberOf Plugin,cn=plugins,cn=config"
> [18/Jan/2016:09:28:19 -0800] conn=3 op=1 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:19 -0800] conn=3 op=2 UNBIND
> [18/Jan/2016:09:28:19 -0800] conn=3 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:19 -0800] conn=4 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:19 -0800] conn=4 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:19 -0800] conn=4 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:19 -0800] conn=4 op=1 ADD dn="cn=ipa-winsync,cn=plugins,cn=config"
> [18/Jan/2016:09:28:19 -0800] conn=4 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:19 -0800] conn=4 op=2 UNBIND
> [18/Jan/2016:09:28:19 -0800] conn=4 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:19 -0800] conn=5 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:19 -0800] conn=5 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:19 -0800] conn=5 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:19 -0800] conn=5 op=1 ADD dn="cn=IPA Version Replication,cn=plugins,cn=config"
> [18/Jan/2016:09:28:19 -0800] conn=5 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:19 -0800] conn=5 op=2 UNBIND
> [18/Jan/2016:09:28:19 -0800] conn=5 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:19 -0800] conn=6 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:19 -0800] conn=6 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:19 -0800] conn=6 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:19 -0800] conn=6 op=1 ADD dn="cn=ipa_enrollment_extop,cn=plugins,cn=config"
> [18/Jan/2016:09:28:19 -0800] conn=6 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=6 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=6 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=7 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=7 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=7 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=7 op=1 MOD dn="cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=7 op=1 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=7 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=7 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=8 fd=66 slot=66 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=8 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=8 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=8 op=1 ADD dn="cn=krbPrincipalName uniqueness,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=8 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=8 op=2 ADD dn="cn=krbCanonicalName uniqueness,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=8 op=2 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=8 op=3 ADD dn="cn=netgroup uniqueness,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=8 op=3 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=8 op=4 ADD dn="cn=ipaUniqueID uniqueness,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=8 op=4 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=8 op=5 ADD dn="cn=sudorule name uniqueness,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=8 op=6 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=8 op=6 fd=66 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=8 op=5 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=9 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=9 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=9 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=9 op=1 ADD dn="cn=IPA UUID,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=9 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=9 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=9 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=10 fd=66 slot=66 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=10 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=10 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=10 op=1 ADD dn="cn=IPA Unique IDs,cn=IPA UUID,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=10 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=10 op=2 ADD dn="cn=IPK11 Unique IDs,cn=IPA UUID,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=10 op=3 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=10 op=3 fd=66 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=10 op=2 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=11 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=11 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=11 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=11 op=1 ADD dn="cn=IPA MODRDN,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=11 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=11 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=11 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=12 fd=66 slot=66 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=12 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=12 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=12 op=1 ADD dn="cn=Kerberos Principal Name,cn=IPA MODRDN,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=12 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=12 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=12 op=2 fd=66 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=13 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=13 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=13 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=13 op=1 ADD dn="cn=IPA DNS,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=13 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=13 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=13 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=14 fd=66 slot=66 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=14 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=14 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=14 op=1 MOD dn="cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=14 op=1 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=14 op=2 MOD dn="cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=14 op=2 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=14 op=3 MOD dn="cn=USN,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=14 op=4 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=14 op=4 fd=66 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=14 op=3 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=15 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=15 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=15 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=15 op=1 ADD dn="cn=IPA Lockout,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=15 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=15 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=15 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=16 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=16 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=16 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=16 op=1 ADD dn="cn=IPA Topology Configuration,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=16 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=16 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=16 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=17 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=17 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=17 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=1 ADD dn="cn=krbPrincipalName,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=2 ADD dn="cn=ou,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=2 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=3 ADD dn="cn=carLicense,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=3 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=4 ADD dn="cn=title,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=4 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=5 ADD dn="cn=manager,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=5 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=6 ADD dn="cn=secretary,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=6 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=7 ADD dn="cn=displayname,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=7 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=8 MOD dn="cn=uid,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=8 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=9 ADD dn="cn=uidnumber,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=9 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=10 ADD dn="cn=gidnumber,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=10 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=11 MOD dn="cn=ntUniqueId,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=11 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=12 MOD dn="cn=ntUserDomainId,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=12 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=13 ADD dn="cn=fqdn,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=13 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=14 ADD dn="cn=macAddress,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=14 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=15 ADD dn="cn=memberHost,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=15 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=16 ADD dn="cn=memberUser,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=16 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=17 ADD dn="cn=sourcehost,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=17 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=18 ADD dn="cn=memberservice,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=18 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=19 ADD dn="cn=managedby,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=19 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=20 ADD dn="cn=memberallowcmd,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=20 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=21 ADD dn="cn=memberdenycmd,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=21 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=22 ADD dn="cn=ipasudorunas,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=22 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=23 ADD dn="cn=ipasudorunasgroup,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=23 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=24 ADD dn="cn=automountkey,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=24 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=25 ADD dn="cn=ipakrbprincipalalias,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=25 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=26 ADD dn="cn=ipauniqueid,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=26 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=27 ADD dn="cn=ipaMemberCa,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=27 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=28 ADD dn="cn=ipaMemberCertProfile,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=28 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=29 ADD dn="cn=userCertificate,cn=index,cn=userRoot,cn=ldbm database,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=17 op=29 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=17 op=30 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=17 op=30 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=18 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=18 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=18 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=18 op=1 MOD dn="cn=referential integrity postoperation,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=18 op=1 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=18 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=18 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=19 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=19 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=19 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=19 op=1 ADD dn="cn=root-autobind,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=19 op=1 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=19 op=2 MOD dn="cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=19 op=2 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=19 op=3 MOD dn="cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=19 op=3 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=19 op=4 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=19 op=4 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=20 fd=66 slot=66 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=20 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=20 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=20 op=1 MOD dn="cn=Managed Entries,cn=plugins,cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=20 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=20 op=2 fd=66 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=20 op=1 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=21 fd=65 slot=65 connection from 10.21.0.98 to 10.21.0.98
> [18/Jan/2016:09:28:20 -0800] conn=21 op=0 BIND dn="cn=Directory Manager" method=128 version=3
> [18/Jan/2016:09:28:20 -0800] conn=21 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:20 -0800] conn=21 op=1 MOD dn="cn=config"
> [18/Jan/2016:09:28:20 -0800] conn=21 op=2 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=21 op=2 fd=65 closed - U1
> [18/Jan/2016:09:28:20 -0800] conn=21 op=1 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:20 -0800] conn=2 op=1 UNBIND
> [18/Jan/2016:09:28:20 -0800] conn=2 op=1 fd=64 closed - U1
> [18/Jan/2016:09:28:21 -0800] conn=1 fd=64 slot=64 connection from ::1 to ::1
> [18/Jan/2016:09:28:21 -0800] conn=1 op=-1 fd=64 closed - B1
> [18/Jan/2016:09:28:27 -0800] conn=2 fd=64 slot=64 connection from local to /var/run/slapd-MYDOMAIN-NET.socket
> [18/Jan/2016:09:28:27 -0800] conn=2 AUTOBIND dn="cn=Directory Manager"
> [18/Jan/2016:09:28:27 -0800] conn=2 op=0 BIND dn="cn=Directory Manager" method=sasl version=3 mech=EXTERNAL
> [18/Jan/2016:09:28:27 -0800] conn=2 op=1 MOD dn="cn=encryption,cn=config"
> [18/Jan/2016:09:28:27 -0800] conn=2 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=Directory Manager"
> [18/Jan/2016:09:28:27 -0800] conn=2 op=1 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:27 -0800] conn=2 op=2 MOD dn="cn=config"
> [18/Jan/2016:09:28:27 -0800] conn=2 op=2 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:27 -0800] conn=2 op=3 SRCH base="cn=schema" scope=0 filter="(objectClass=*)" attrs="attributeTypes objectClasses"
> [18/Jan/2016:09:28:27 -0800] conn=2 op=3 RESULT err=0 tag=101 nentries=1 etime=0
> [18/Jan/2016:09:28:27 -0800] conn=2 op=4 ADD dn="cn=RSA,cn=encryption,cn=config"
> [18/Jan/2016:09:28:27 -0800] conn=2 op=4 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:27 -0800] conn=2 op=5 UNBIND
> [18/Jan/2016:09:28:27 -0800] conn=2 op=5 fd=64 closed - U1
> [18/Jan/2016:09:28:29 -0800] conn=1 fd=64 slot=64 connection from ::1 to ::1
> [18/Jan/2016:09:28:29 -0800] conn=1 op=-1 fd=64 closed - B1
> [18/Jan/2016:09:28:29 -0800] conn=2 fd=64 slot=64 connection from local to /var/run/slapd-MYDOMAIN-NET.socket
> [18/Jan/2016:09:28:29 -0800] conn=2 op=0 BIND dn="cn=directory manager" method=128 version=3
> [18/Jan/2016:09:28:29 -0800] conn=2 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=directory manager"
> [18/Jan/2016:09:28:29 -0800] conn=2 op=1 SRCH base="cn=IPA Version Replication,cn=plugins,cn=config" scope=0 filter="(objectClass=*)" attrs=ALL
> [18/Jan/2016:09:28:29 -0800] conn=2 op=1 RESULT err=0 tag=101 nentries=1 etime=0
> [18/Jan/2016:09:28:29 -0800] conn=2 op=2 SRCH base="cn=schema" scope=0 filter="(objectClass=*)" attrs="attributeTypes objectClasses"
> [18/Jan/2016:09:28:29 -0800] conn=2 op=2 RESULT err=0 tag=101 nentries=1 etime=0
> [18/Jan/2016:09:28:30 -0800] conn=2 op=3 MOD dn="cn=IPA Version Replication,cn=plugins,cn=config"
> [18/Jan/2016:09:28:30 -0800] conn=2 op=3 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:30 -0800] conn=2 op=4 UNBIND
> [18/Jan/2016:09:28:30 -0800] conn=2 op=4 fd=64 closed - U1
> [18/Jan/2016:09:28:31 -0800] conn=1 fd=64 slot=64 connection from ::1 to ::1
> [18/Jan/2016:09:28:31 -0800] conn=2 fd=65 slot=65 connection from local to /var/run/slapd-MYDOMAIN-NET.socket
> [18/Jan/2016:09:28:31 -0800] conn=1 op=-1 fd=64 closed - B1
> [18/Jan/2016:09:28:31 -0800] conn=2 AUTOBIND dn="cn=Directory Manager"
> [18/Jan/2016:09:28:31 -0800] conn=2 op=0 BIND dn="cn=Directory Manager" method=sasl version=3 mech=EXTERNAL
> [18/Jan/2016:09:28:31 -0800] conn=2 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=Directory Manager"
> [18/Jan/2016:09:28:31 -0800] conn=2 op=1 SRCH base="cn=replica,cn=dc\3Dmydomain\2Cdc\3Dnet,cn=mapping tree,cn=config" scope=0 filter="(objectClass=*)" attrs=ALL
> [18/Jan/2016:09:28:31 -0800] conn=2 op=1 RESULT err=32 tag=101 nentries=0 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=2 SRCH base="cn=replica,cn=dc\3Dmydomain\2Cdc\3Dnet,cn=mapping tree,cn=config" scope=0 filter="(objectClass=*)" attrs=ALL
> [18/Jan/2016:09:28:32 -0800] conn=2 op=2 RESULT err=32 tag=101 nentries=0 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=3 SRCH base="cn=schema" scope=0 filter="(objectClass=*)" attrs="attributeTypes objectClasses"
> [18/Jan/2016:09:28:32 -0800] conn=2 op=3 RESULT err=0 tag=101 nentries=1 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=4 ADD dn="cn=replica,cn=dc\3Dmydomain\2Cdc\3Dnet,cn=mapping tree,cn=config"
> [18/Jan/2016:09:28:32 -0800] conn=2 op=4 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=5 SRCH base="cn=config,cn=ldbm database,cn=plugins,cn=config" scope=0 filter="(objectClass=*)" attrs="nsslapd-directory"
> [18/Jan/2016:09:28:32 -0800] conn=2 op=5 RESULT err=0 tag=101 nentries=1 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=6 ADD dn="cn=changelog5,cn=config"
> [18/Jan/2016:09:28:32 -0800] conn=2 op=6 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=7 ADD dn="cn=ldap/dc2-ipa-dev-nvan.mydomain.net at mydomain.NET,cn=config"
> [18/Jan/2016:09:28:32 -0800] conn=2 op=7 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=8 SRCH base="cn=replica,cn=dc\3Dmydomain\2Cdc\3Dnet,cn=mapping tree,cn=config" scope=0 filter="(objectClass=*)" attrs=ALL
> [18/Jan/2016:09:28:32 -0800] conn=2 op=8 RESULT err=0 tag=101 nentries=1 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=9 MOD dn="cn=replica,cn=dc\3Dmydomain\2Cdc\3Dnet,cn=mapping tree,cn=config"
> [18/Jan/2016:09:28:32 -0800] conn=2 op=9 RESULT err=0 tag=103 nentries=0 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=10 ADD dn="cn=Peer Master,cn=mapping,cn=sasl,cn=config"
> [18/Jan/2016:09:28:32 -0800] conn=2 op=10 RESULT err=0 tag=105 nentries=0 etime=0
> [18/Jan/2016:09:28:32 -0800] conn=2 op=11 UNBIND
> [18/Jan/2016:09:28:32 -0800] conn=2 op=11 fd=65 closed - U1
> [18/Jan/2016:09:28:33 -0800] conn=3 fd=64 slot=64 connection from 10.178.6.56 to 10.21.0.98
> [18/Jan/2016:09:28:33 -0800] conn=3 op=0 SRCH base="" scope=0 filter="(objectClass=*)" attrs="* altServer namingContexts supportedControl supportedExtension supportedFeatures supportedLDAPVersion supportedSASLMechanisms domaincontrollerfunctionality defaultnamingcontext lastusn highestcommittedusn aci"
> [18/Jan/2016:09:28:33 -0800] conn=3 op=0 RESULT err=0 tag=101 nentries=1 etime=0
> [18/Jan/2016:09:28:33 -0800] conn=3 op=1 UNBIND
> [18/Jan/2016:09:28:33 -0800] conn=3 op=1 fd=64 closed - U1
> ==== INSTALLATION CRASHED HERE BUT CLEARLY THE DS SERVICE ITSELF IS STILL RUNNING BECAUSE MORE LOGS HAPPEN BELOW
> [18/Jan/2016:09:29:11 -0800] conn=4 fd=64 slot=64 connection from 10.21.5.132 to 10.21.0.98
> 
> 
> 
> 
> -----Original Message-----
> From: freeipa-users-bounces at redhat.com [mailto:freeipa-users-bounces at redhat.com] On Behalf Of Petr Vobornik
> Sent: January-18-16 3:57 AM
> To: freeipa-users at redhat.com
> Subject: Re: [Freeipa-users] Freeipa 4.3.0 replica installation fails with DuplicateEntry: This entry already exists
> 
>> look at the DS access log, you should see an ADD operation with RESULT  
>> err=68 tag=105
> 
> According to code it's most likely
>   cn=replica,cn=$DOMAIN_SUFFIX,cn=mapping tree,cn=config
> 
> I don't know why it happens because installer should add it only if the entry does not exist. Would be worth to check the DS access log if base search(which should happen before the add) for the dn fails or succeeds.
> 
> 