On Tue, Mar 27, 2012 at 20:07:07 -0500, Rich Megginson wrote:
> On 03/27/2012 05:11 PM, Iain Morgan wrote:
> > Hi,
> >
> > I recently tried to setup a slave replica using fractional replication.
> > The slave replica works, but the attributes which I had intended to
> > exclude are still being replicated.
> >
> > The replication agreement on the supplier includes:
> >
> > % ldapsearch -b cn=config '(cn=Slave)' nsds5replicatedattributelist
> > dn: cn=Slave,...
> > nsds5replicatedattributelist: (objectClass=*) $ EXCLUDE passwordAllowChangeTim
> > e passwordExpirationTime passwordGraceUserTime shadowLastChange passwordHisto
> > ry
> >
> >
> > Are there circumstances where nsds5ReplicatedAttributeList is ignored?
> >
> No. What is your platform and 389-ds-base version?
> Can you reproduce the problem with the replication log level set?
> http://port389.org/wiki/FAQ#Troubleshooting
RHEL 6.2 and 389-ds-base-1.2.10.4-1.el6.
I set nsslapd-errorlog-level to 8192 and then changed the
shadowLastChange attribute for an entry. The change was propagated to
the slave replica despite the fact that shadowLastChange is one of the
excluded attributes. I've attached the log output.
--
Iain Morgan
[27/Mar/2012:20:37:52 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): State: wait_for_changes -> wait_for_changes
[27/Mar/2012:20:37:52 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): State: wait_for_changes -> start
[27/Mar/2012:20:37:52 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): No linger to cancel on the connection
[27/Mar/2012:20:37:52 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): Disconnected from the consumer
[27/Mar/2012:20:37:52 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): State: start -> ready_to_acquire_replica
[27/Mar/2012:20:37:52 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): State: ready_to_acquire_replica -> wait_for_changes
[27/Mar/2012:20:37:56 -0700] - _csngen_adjust_local_time: gen state before 4f72565b0001:1332893273:0:2
[27/Mar/2012:20:37:56 -0700] - _csngen_adjust_local_time: gen state after 4f7287960000:1332905876:0:2
[27/Mar/2012:20:37:56 -0700] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 4f7287960000006e0000 into pending list
[27/Mar/2012:20:37:56 -0700] NSMMReplicationPlugin - Purged state information from entry uid=imorgan,ou=People,dc=nas,dc=nasa,dc=gov up to CSN 4f67c5640000006d0000
[27/Mar/2012:20:37:56 -0700] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 25b8920 for database /var/lib/dirsrv/slapd-ds2/changelog.db/e6380003-3e2411e1-b1199aad-2e19ff47_4f1094320000006d0000.db4
[27/Mar/2012:20:37:56 -0700] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 25b8920 for database /var/lib/dirsrv/slapd-ds2/changelog.db/e6380003-3e2411e1-b1199aad-2e19ff47_4f1094320000006d0000.db4
[27/Mar/2012:20:37:56 -0700] NSMMReplicationPlugin - ruv_update_ruv: successfully committed csn 4f7287960000006e0000
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): State: wait_for_changes -> wait_for_changes
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): State: wait_for_changes -> ready_to_acquire_replica
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): Trying secure slapi_ldap_init_ext
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): binddn = cn=Replication Manager,cn=config, passwd = {DES}6hHzwbU+nlLTfkFxe5D5Y7W1/26yLZAukOL2muo3z0KxVr5EtIw70Q2J1yo915hq
3+O3zOnSWYQGoKAJJzvi0w==
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): State: wait_for_changes -> wait_for_changes
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): State: wait_for_changes -> ready_to_acquire_replica
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): Trying secure slapi_ldap_init_ext
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): binddn = cn=Replication Manager,cn=config, passwd = {DES}6hHzwbU+nlLTfkFxe5D5Y7W1/26yLZAukOL2muo3z0KxVr5EtIw70Q2J1yo915hq
3+O3zOnSWYQGoKAJJzvi0w==
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): No linger to cancel on the connection
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): No linger to cancel on the connection
[27/Mar/2012:20:37:57 -0700] - _csngen_adjust_local_time: gen state before 4f7287960001:1332905876:0:2
[27/Mar/2012:20:37:57 -0700] - _csngen_adjust_local_time: gen state after 4f7287970000:1332905877:0:2
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): Replica was successfully acquired.
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): Replica was successfully acquired.
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): State: ready_to_acquire_replica -> sending_updates
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): State: ready_to_acquire_replica -> sending_updates
[27/Mar/2012:20:37:57 -0700] - csngen_adjust_time: gen state before 4f7287970002:1332905877:0:2
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 25b8920 for database /var/lib/dirsrv/slapd-ds2/changelog.db/e6380003-3e2411e1-b1199aad-2e19ff47_4f1094320000006d0000.db4
[27/Mar/2012:20:37:57 -0700] - csngen_adjust_time: gen state before 4f7287970002:1332905877:0:2
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 25b8920 for database /var/lib/dirsrv/slapd-ds2/changelog.db/e6380003-3e2411e1-b1199aad-2e19ff47_4f1094320000006d0000.db4
[27/Mar/2012:20:37:57 -0700] - _cl5PositionCursorForReplay (agmt="cn=Slave" (ds2-slave:636)): Consumer RUV:
[27/Mar/2012:20:37:57 -0700] - _cl5PositionCursorForReplay (agmt="cn=Multimaster" (ds1:636)): Consumer RUV:
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): {replicageneration} 4f1094320000006d0000
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): {replicageneration} 4f1094320000006d0000
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): {replica 109 ldap://ds1.nas.nasa.gov:389} 4f10a5640000006d0000 4f70ffe40000006d0000 00000000
[27/Mar/2012:20:37:57 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): {replica 110 ldap://ds2.nas.nasa.gov:389} 4f15d4cd0000006e0000 4f471d650000006e0000 00000000
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): {replica 110 ldap://ds2.nas.nasa.gov:389} 4f15d4cd0000006e0000 4f471d650000006e0000 00000000
[27/Mar/2012:20:37:58 -0700] - _cl5PositionCursorForReplay (agmt="cn=Multimaster" (ds1:636)): Supplier RUV:
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): {replicageneration} 4f1094320000006d0000
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): {replica 109 ldap://ds1.nas.nasa.gov:389} 4f10a5640000006d0000 4f70ffe40000006d0000 00000000
[27/Mar/2012:20:37:58 -0700] - _cl5PositionCursorForReplay (agmt="cn=Slave" (ds2-slave:636)): Supplier RUV:
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): {replicageneration} 4f1094320000006d0000
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): {replica 110 ldap://ds2.nas.nasa.gov:389} 4f15d4cd0000006e0000 4f7287960000006e0000 4f728794
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): {replica 110 ldap://ds2.nas.nasa.gov:389} 4f15d4cd0000006e0000 4f7287960000006e0000 4f728794
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): {replica 109 ldap://ds1.nas.nasa.gov:389} 4f10a5640000006d0000 4f70ffe40000006d0000 4f70ffe2
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): {replica 109 ldap://ds1.nas.nasa.gov:389} 4f10a5640000006d0000 4f70ffe40000006d0000 4f70ffe2
[27/Mar/2012:20:37:58 -0700] agmt="cn=Multimaster" (ds1:636) - session start: anchorcsn=4f471d650000006e0000
[27/Mar/2012:20:37:58 -0700] agmt="cn=Slave" (ds2-slave:636) - session start: anchorcsn=4f471d650000006e0000
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - changelog program - agmt="cn=Multimaster" (ds1:636): CSN 4f471d650000006e0000 found, position set for replay
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - changelog program - agmt="cn=Slave" (ds2-slave:636): CSN 4f471d650000006e0000 found, position set for replay
[27/Mar/2012:20:37:58 -0700] agmt="cn=Multimaster" (ds1:636) - load=1 rec=15 csn=4f7287960000006e0000
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): replay_update: Sending modify operation (dn="uid=imorgan,ou=People,dc=nas,dc=nasa,dc=gov" csn=4f7287960000006e0000)
[27/Mar/2012:20:37:58 -0700] agmt="cn=Slave" (ds2-slave:636) - load=1 rec=15 csn=4f7287960000006e0000
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): replay_update: Sending modify operation (dn="uid=imorgan,ou=People,dc=nas,dc=nasa,dc=gov" csn=4f7287960000006e0000)
[27/Mar/2012:20:37:58 -0700] - repl5_inc_result_threadmain starting
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): replay_update: Consumer successfully sent operation with csn 4f7287960000006e0000
[27/Mar/2012:20:37:58 -0700] agmt="cn=Multimaster" (ds1:636) - clcache_load_buffer: rc=-30988
[27/Mar/2012:20:37:58 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): No more updates to send (cl5GetNextOperationToReplay)
[27/Mar/2012:20:37:58 -0700] - repl5_inc_waitfor_async_results: 0 6
[27/Mar/2012:20:37:58 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:58 -0700] - repl5_inc_result_threadmain: result 3, 0, 0, 6, (null)
[27/Mar/2012:20:37:58 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] NSMMReplicationPlugin - conn=600 op=4 repl="dc=nas,dc=nasa,dc=gov": Begin incremental protocol
[27/Mar/2012:20:37:59 -0700] - csngen_adjust_time: gen state before 4f7287970002:1332905877:0:2
[27/Mar/2012:20:37:59 -0700] - _csngen_adjust_local_time: gen state before 4f7287970002:1332905877:0:2
[27/Mar/2012:20:37:59 -0700] - _csngen_adjust_local_time: gen state after 4f7287990000:1332905879:0:2
[27/Mar/2012:20:37:59 -0700] - csngen_adjust_time: gen state after 4f7287990000:1332905879:0:2
[27/Mar/2012:20:37:59 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): replay_update: Consumer successfully sent operation with csn 4f7287960000006e0000
[27/Mar/2012:20:37:59 -0700] agmt="cn=Slave" (ds2-slave:636) - clcache_load_buffer: rc=-30988
[27/Mar/2012:20:37:59 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): No more updates to send (cl5GetNextOperationToReplay)
[27/Mar/2012:20:37:59 -0700] - repl5_inc_waitfor_async_results: 0 6
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain starting
[27/Mar/2012:20:37:59 -0700] NSMMReplicationPlugin - conn=600 op=4 repl="dc=nas,dc=nasa,dc=gov": Acquired replica
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: result 3, 0, 0, 6, (null)
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] NSMMReplicationPlugin - conn=600 op=4 repl="dc=nas,dc=nasa,dc=gov": StartNSDS90ReplicationRequest: response=0 rc=0
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] NSMMReplicationPlugin - conn=600 op=5 repl="dc=nas,dc=nasa,dc=gov": Released replica
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:37:59 -0700] - repl5_inc_waitfor_async_results: 6 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_waitfor_async_results: 6 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:00 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:01 -0700] - repl5_inc_result_threadmain exiting
[27/Mar/2012:20:38:01 -0700] agmt="cn=Multimaster" (ds1:636) - session end: state=5 load=1 sent=1 skipped=14
[27/Mar/2012:20:38:01 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): Successfully released consumer
[27/Mar/2012:20:38:01 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): Beginning linger on the connection
[27/Mar/2012:20:38:01 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): State: sending_updates -> wait_for_changes
[27/Mar/2012:20:38:01 -0700] - repl5_inc_result_threadmain: read result for message_id 6
[27/Mar/2012:20:38:02 -0700] - repl5_inc_result_threadmain exiting
[27/Mar/2012:20:38:02 -0700] agmt="cn=Slave" (ds2-slave:636) - session end: state=5 load=1 sent=1 skipped=14
[27/Mar/2012:20:38:02 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): Successfully released consumer
[27/Mar/2012:20:38:02 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): Beginning linger on the connection
[27/Mar/2012:20:38:02 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): State: sending_updates -> wait_for_changes
[27/Mar/2012:20:39:02 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): Linger timeout has expired on the connection
[27/Mar/2012:20:39:02 -0700] NSMMReplicationPlugin - agmt="cn=Multimaster" (ds1:636): Disconnected from the consumer
[27/Mar/2012:20:39:03 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): Linger timeout has expired on the connection
[27/Mar/2012:20:39:03 -0700] NSMMReplicationPlugin - agmt="cn=Slave" (ds2-slave:636): Disconnected from the consumer
--
389 users mailing list
389-users@xxxxxxxxxxxxxxxxxxxxxxx
https://admin.fedoraproject.org/mailman/listinfo/389-users
