[389-users] problem initializing replica

Wed Aug 15 14:36:41 UTC 2012

On 08/14/2012 03:10 PM, Vladimir Elisseev wrote:
> Rich,
>
> First of all thanks for helping me. Below is the error log and
> corresponding access and log entries. The strange thing is that I've
> done the same for "o=netscaperoot" successfully, but user data it fails.
>
> error log
> -----------------------------------------------------------------------------------------------------------------
> [14/Aug/2012:13:26:04 +0200] NSMMReplicationPlugin - multimaster_be_state_change: replica dc=cids is going offline; disabling replication
> [14/Aug/2012:13:26:04 +0200] - WARNING: Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
> [14/Aug/2012:13:26:04 +0200] - ERROR bulk import abandoned
> -----------------------------------------------------------------------------------------------------------------
>
> access log
> -----------------------------------------------------------------------------------------------------------------
> [14/Aug/2012:13:26:01 +0200] conn=534 op=-1 fd=69 closed - Encountered end of file.
> [14/Aug/2012:13:26:01 +0200] conn=525 op=18 EXT oid="2.16.840.1.113730.3.5.12" name="replication-multimaster-extop"
> [14/Aug/2012:13:26:01 +0200] conn=525 op=18 RESULT err=0 tag=120 nentries=0 etime=0
> [14/Aug/2012:13:26:01 +0200] conn=525 op=19 EXT oid="2.16.840.1.113730.3.5.5" name="Netscape Replication End Session"
> [14/Aug/2012:13:26:01 +0200] conn=525 op=19 RESULT err=0 tag=120 nentries=0 etime=0
> [14/Aug/2012:13:26:03 +0200] conn=535 fd=69 slot=69 SSL connection from 10.233.128.3 to 10.233.128.217
> [14/Aug/2012:13:26:03 +0200] conn=535 op=-1 fd=69 closed - Encountered end of file.
> [14/Aug/2012:13:26:03 +0200] conn=520 op=21 UNBIND
> [14/Aug/2012:13:26:03 +0200] conn=520 op=21 fd=67 closed - U1
> [14/Aug/2012:13:26:04 +0200] conn=536 fd=67 slot=67 SSL connection from 10.233.128.216 to 10.233.128.217
> [14/Aug/2012:13:26:04 +0200] conn=536 SSL 256-bit AES
> [14/Aug/2012:13:26:04 +0200] conn=536 op=0 BIND dn="cn=replication manager,cn=config" method=128 version=3
> [14/Aug/2012:13:26:04 +0200] conn=536 op=0 RESULT err=0 tag=97 nentries=0 etime=0 dn="cn=replication manager,cn=config"
> [14/Aug/2012:13:26:04 +0200] conn=536 op=1 SRCH base="" scope=0 filter="(objectClass=*)" attrs="supportedControl supportedExtension"
> [14/Aug/2012:13:26:04 +0200] conn=536 op=1 RESULT err=0 tag=101 nentries=1 etime=0
> [14/Aug/2012:13:26:04 +0200] conn=536 op=2 SRCH base="" scope=0 filter="(objectClass=*)" attrs="supportedControl supportedExtension"
> [14/Aug/2012:13:26:04 +0200] conn=536 op=2 RESULT err=0 tag=101 nentries=1 etime=0
> [14/Aug/2012:13:26:04 +0200] conn=536 op=3 EXT oid="2.16.840.1.113730.3.5.12" name="replication-multimaster-extop"
> [14/Aug/2012:13:26:04 +0200] conn=536 op=3 RESULT err=0 tag=120 nentries=0 etime=0
> [14/Aug/2012:13:26:04 +0200] conn=536 op=69 RESULT err=0 tag=120 nentries=0 etime=0
> [14/Aug/2012:13:26:04 +0200] conn=536 op=70 EXT oid="2.16.840.1.113730.3.5.6" name="Netscape Replication Total Update Entry"
> [14/Aug/2012:13:26:04 +0200] conn=536 op=70 RESULT err=0 tag=120 nentries=0 etime=0
> -----------------------------------------------------------------------------------------------------------------
> a lot of the same entries as these two above
> -----------------------------------------------------------------------------------------------------------------
> [14/Aug/2012:13:26:04 +0200] conn=536 op=71 EXT oid="2.16.840.1.113730.3.5.6" name="Netscape Replication Total Update Entry"
> [14/Aug/2012:13:26:04 +0200] conn=536 op=116 EXT oid="2.16.840.1.113730.3.5.6" name="Netscape Replication Total Update Entry"
> [14/Aug/2012:13:26:04 +0200] conn=536 op=116 RESULT err=0 tag=120 nentries=0 etime=0
> [14/Aug/2012:13:26:04 +0200] conn=536 op=-1 fd=67 closed error 34 (Numerical result out of range) - B2

Hmm - this is very interesting - err 34 - this means the directory 
server received a packet that was too big.  What sorts of entries are 
you replicating?  Do you have very large group entries?  Try increasing 
the nsslapd-maxbersize in cn=config.

> -----------------------------------------------------------------------------------------------------------------
>
> Regards,
> Vlad
>
> On Tue, 2012-08-14 at 14:24 -0600, Rich Megginson wrote:
>> On 08/14/2012 11:26 AM, Vladimir Elisseev wrote:
>>> Version of 389-ds-base is 1.2.10.2.
>>>
>>> Regards,
>>> Vlad.
>>>
>>> On Tue, 2012-08-14 at 11:21 -0600, Rich Megginson wrote:
>>>> On 08/14/2012 10:50 AM, Vladimir Elisseev wrote:
>>>>> Hello,
>>>>>
>>>>> I've problems for initializing replica from Admin console or using
>>>>> ldapmodify. Although, I'm able to initialize replica from LDIF file
>>>>> successfully. Below is a snip from errorlog:
>>>>>
>>>>> ************* snip start *****************
>>>>> [14/Aug/2012:15:09:04 +0200] NSMMReplicationPlugin - multimaster_be_state_change: replica dc=cids is going offline; disabling replication
>>>>> [14/Aug/2012:15:09:04 +0200] - WARNING: Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
>>>>> [14/Aug/2012:15:09:04 +0200] - ERROR bulk import abandoned
>> Can you paste excerpts of your access log from around this time?  And
>> also - when was the last EXT operation from the access log before
>> 14/Aug/2012:15:09:04 +0200?
>>
>>>>> [14/Aug/2012:15:09:04 +0200] - import userRoot: Aborting all Import threads...
>>>>> [14/Aug/2012:15:09:11 +0200] - import userRoot: Import threads aborted.
>>>>> [14/Aug/2012:15:09:11 +0200] - import userRoot: Closing files...
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/cIDSMemberOf.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/mail.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/nsuniqueid.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/id2entry.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/sn.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/objectclass.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/ou.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/aci.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/cIDSEntityID.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/cn.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/entryrdn.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/member.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/telephoneNumber.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - libdb: userRoot/parentid.db4: unable to flush: No such file or directory
>>>>> [14/Aug/2012:15:09:11 +0200] - import userRoot: Import failed.
>>>>> [14/Aug/2012:15:09:11 +0200] - process_bulk_import_op: NULL target sdn
>>>>> ************* snip end *****************
>>>>>
>>>>> These databases are for custom indexes, but I have no clue why they
>>>>> aren't created automatically (all the indexes as well as custom schema
>>>>> has been defined before the initialization). I'd greatly appreciate any
>>>>> help/thoughts.
>> They weren't created because it appears the replica init (bulk import)
>> was aborted before it could be started.  I'd like to find out why that
>> happened.
>>
>>>>> Thanks in advance,
>>>>> Vlad.
>>>> What are your supplier and consumer platforms?  What versions of
>>>> 389-ds-base?
>>>>> --
>>>>> 389 users mailing list
>>>>> 389-users at lists.fedoraproject.org
>>>>> https://admin.fedoraproject.org/mailman/listinfo/389-users
>