Hi everyone. We''re using FDS (389) 1.2.0. A few days ago, this started showing up in the logs on one of our two multi-master-replicated nodes: [17/Oct/2009:10:46:13 -0500] NSMMReplicationPlugin - agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Consumer failed to replay change (uniqueid 8d7abe0d-812811de-837b9eef-94681f9f, CSN 4a7b3c31000000020000): DSA is unwilling to perform. Will retry later. ... every 5 minutes. It''s all the same "uniqueid", so it appears to be one particular replication request that just didn''t work (don''t know why). Replication is otherwise "OK".... What can I do to : * find out what replication change is failing, * why--- and * what can I do about it? I''m a bit new to this, so any help is greatly appreciated! (Presumably, I need a way to either accept the change, or probably also just indicate that the change, whatever it was, doesn''t need to occur).
Brodie, Kent wrote:> Hi everyone. > > We''re using FDS (389) 1.2.0. > > A few days ago, this started showing up in the logs on one of our two > multi-master-replicated nodes: > > [17/Oct/2009:10:46:13 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Consumer > failed to replay change (uniqueid 8d7abe0d-812811de-837b9eef-94681f9f, > CSN 4a7b3c31000000020000): DSA is unwilling to perform. Will retry > later. > > ... every 5 minutes. It''s all the same "uniqueid", so it appears to > be one particular replication request that just didn''t work (don''t know > why). > > Replication is otherwise "OK".... > > What can I do to : > * find out what replication change is failing, >Search the access logs for CSN 4a7b3c31000000020000 to find out what operation that is Turn on the replication log level http://directory.fedoraproject.org/wiki/FAQ#Troubleshooting to see more information about the operation that is causing problems> * why--- and > * what can I do about it? > > I''m a bit new to this, so any help is greatly appreciated! > > (Presumably, I need a way to either accept the change, or probably also > just indicate that the change, whatever it was, doesn''t need to occur). > > > > -- > 389 users mailing list > 389-users@redhat.com > https://www.redhat.com/mailman/listinfo/fedora-directory-users >
Rich: Thanks for the debugging help! I''m still stuck, as I am not
sure exactly what I am looking at in terms of messages. I can see that
the same uniqueid 4922d291-be7a11de-adce9eef-94681f9f keeps failing, but
the message surrounding that error are anything but clear to me.
Can someone help look at this and translate what''s going on? The log
below is about 5 mins worth.
[22/Oct/2009:09:36:08 -0500] - Fedora-Directory/1.2.0 B2009.118.181
starting up
[22/Oct/2009:09:36:08 -0500] NSMMReplicationPlugin - changelog program -
_cl5CheckGuardian: found old style of guardian file:
bdb/4.3/libreplication-plugin
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program -
_cl5NewDBFile: semaphore
/var/lib/dirsrv/slapd-white/changelogdb/f2291084-807511de-837b9eef-94681
f9f.sema
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program -
_cl5NewDBFile: maxConcurrentWrites=2
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program -
_cl5GetEntryCount: 500 changes for replica
f2291084-807511de-837b9eef-94681f9f
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program -
_cl5AddDBFile: Added new DB object 13901670
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program -
_cl5DBOpenFileByReplicaName: created new DB object 13901670
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program -
_cl5DBOpen: opened 1 existing databases in
/var/lib/dirsrv/slapd-white/changelogdb
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - Found replication
agreement named "cn="Replication to
winters.hmgc.mcw.edu",cn=replica,cn="dc=hmgc, dc=mcw,
dc=edu",cn=mapping
tree,cn=config".
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin -
agmtlist_config_init: found 1 replication agreements in DIT
[22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program -
_cl5GetDBFile: found DB object 13901670 for database
f2291084-807511de-837b9eef-94681f9f_4a7758f2000000010000.db4
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): No linger
to cancel on the connection
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
Disconnected from the consumer
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
start -> ready_to_acquire_replica
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Trying
non-secure slapi_ldap_init_ext
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): binddn cn=repman,cn=config, passwd =
{DES}0U0krPfv0gLunf3SATmXQw=[22/Oct/2009:09:36:10 -0500] -
_csngen_adjust_local_time: gen state
before 4ae06db60001:1256222132:0:2
[22/Oct/2009:09:36:10 -0500] - _csngen_adjust_local_time: gen state
after 4ae06ddc0000:1256222170:0:2
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): No linger
to cancel on the connection
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
ruv_add_csn_inprogress: successfully inserted csn 4ae06ddc000000020000
into pending list
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - Purged state
information from entry dc=hmgc, dc=mcw, dc=edu up to CSN
4ad72c1e000000020000
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
csn=4ae06ddc000000020000 process postop: canceling operation csn
[22/Oct/2009:09:36:10 -0500] - slapd started. Listening on All
Interfaces port 389 for LDAP requests
[22/Oct/2009:09:36:10 -0500] - Listening on All Interfaces port 636 for
LDAPS requests
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Replica
was successfully acquired.
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
ready_to_acquire_replica -> sending_updates
[22/Oct/2009:09:36:10 -0500] - csngen_adjust_time: gen state before
4ae06ddc0002:1256222170:0:2
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - changelog program -
_cl5GetDBFile: found DB object 13901670 for database
f2291084-807511de-837b9eef-94681f9f_4a7758f2000000010000.db4
[22/Oct/2009:09:36:10 -0500] - _cl5PositionCursorForReplay
(agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389)):
Consumer RUV:
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
{replicageneration} 4a7758f2000000010000
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000
4adf7830000100010000 00000000
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000
4ae0612d000300020000 00000000
[22/Oct/2009:09:36:10 -0500] - _cl5PositionCursorForReplay
(agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389)):
Supplier RUV:
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
{replicageneration} 4a7758f2000000010000
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000
4ae0669e000000020000 00000000
[22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000
4adf7830000100010000 00000000
[22/Oct/2009:09:36:10 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - session start:
anchorcsn=4ae0612d000300020000
[22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - changelog program -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): CSN
4ae0612d000300020000 found, position set for replay
[22/Oct/2009:09:36:11 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=1
csn=4ae06697000000020000
[22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Sending modify operation
(dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu"
csn=4ae06697000000020000)
[22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Consumer successfully sent operation with csn
4ae06697000000020000
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain starting
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: result 3,
53, 1, 6, (null)
[22/Oct/2009:09:36:11 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=2
csn=4ae0669e000000020000
[22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Sending modify operation
(dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu"
csn=4ae0669e000000020000)
[22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Consumer
failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f,
CSN 4ae06697000000020000): DSA is unwilling to perform. Will retry
later.
[22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Consumer successfully sent operation with csn
4ae0669e000000020000
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: got op
result 202 should finish 1
[22/Oct/2009:09:36:11 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - clcache_load_buffer: rc=-30989
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): No more
updates to send (cl5GetNextOperationToReplay)
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: result 3,
53, 1, 7, (null)
[22/Oct/2009:09:36:11 -0500] - repl5_inc_waitfor_async_results: 7 7
[22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Consumer
failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f,
CSN 4ae0669e000000020000): DSA is unwilling to perform. Will retry
later.
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: got op
result 202 should finish 1
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result
for message_id 7
[22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain exiting
[22/Oct/2009:09:36:12 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - session end: state=5 load=1
sent=2 skipped=0
[22/Oct/2009:09:36:13 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
Successfully released consumer
[22/Oct/2009:09:36:14 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Beginning
linger on the connection
[22/Oct/2009:09:36:14 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
sending_updates -> wait_for_changes
[22/Oct/2009:09:37:14 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Linger
timeout has expired on the connection
[22/Oct/2009:09:37:14 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
Disconnected from the consumer
[22/Oct/2009:09:41:14 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
wait_for_changes -> wait_for_changes
[22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
wait_for_changes -> start
[22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): No linger
to cancel on the connection
[22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
Disconnected from the consumer
[22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
start -> ready_to_acquire_replica
[22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Trying
non-secure slapi_ldap_init_ext
[22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): binddn cn=repman,cn=config, passwd =
{DES}0U0krPfv0gLunf3SATmXQw=[22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): No linger
to cancel on the connection
[22/Oct/2009:09:41:15 -0500] - _csngen_adjust_local_time: gen state
before 4ae06ddc0002:1256222170:0:2
[22/Oct/2009:09:41:15 -0500] - _csngen_adjust_local_time: gen state
after 4ae06f0d0000:1256222475:0:2
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Replica
was successfully acquired.
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
ready_to_acquire_replica -> sending_updates
[22/Oct/2009:09:41:16 -0500] - csngen_adjust_time: gen state before
4ae06f0d0001:1256222475:0:2
[22/Oct/2009:09:41:16 -0500] - _csngen_adjust_local_time: gen state
before 4ae06f0d0001:1256222475:0:2
[22/Oct/2009:09:41:16 -0500] - _csngen_adjust_local_time: gen state
after 4ae06f0e0000:1256222476:0:2
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - changelog program -
_cl5GetDBFile: found DB object 13901670 for database
f2291084-807511de-837b9eef-94681f9f_4a7758f2000000010000.db4
[22/Oct/2009:09:41:16 -0500] - _cl5PositionCursorForReplay
(agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389)):
Consumer RUV:
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
{replicageneration} 4a7758f2000000010000
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000
4adf7830000100010000 00000000
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000
4ae0612d000300020000 00000000
[22/Oct/2009:09:41:16 -0500] - _cl5PositionCursorForReplay
(agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389)):
Supplier RUV:
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
{replicageneration} 4a7758f2000000010000
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000
4ae0669e000000020000 00000000
[22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000
4adf7830000100010000 00000000
[22/Oct/2009:09:41:16 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - clcache_get_buffer: found
thread
private buffer cache 13a76610
[22/Oct/2009:09:41:16 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - clcache_get_buffer: _pool is
13963c90 _pool->pl_busy_lists is 13903780
_pool->pl_busy_lists->bl_buffers is 13a76610
[22/Oct/2009:09:41:17 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - session start:
anchorcsn=4ae0612d000300020000
[22/Oct/2009:09:41:17 -0500] NSMMReplicationPlugin - changelog program -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): CSN
4ae0612d000300020000 found, position set for replay
[22/Oct/2009:09:41:17 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=1
csn=4ae06697000000020000
[22/Oct/2009:09:41:17 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Sending modify operation
(dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu"
csn=4ae06697000000020000)
[22/Oct/2009:09:41:17 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Consumer successfully sent operation with csn
4ae06697000000020000
[22/Oct/2009:09:41:17 -0500] - repl5_inc_result_threadmain starting
[22/Oct/2009:09:41:17 -0500] - repl5_inc_result_threadmain: read result
for message_id 5
[22/Oct/2009:09:41:17 -0500] - repl5_inc_result_threadmain: result 3,
53, 1, 5, (null)
[22/Oct/2009:09:41:17 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=2
csn=4ae0669e000000020000
[22/Oct/2009:09:41:18 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Consumer
failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f,
CSN 4ae06697000000020000): DSA is unwilling to perform. Will retry
later.
[22/Oct/2009:09:41:19 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Sending modify operation
(dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu"
csn=4ae0669e000000020000)
[22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: got op
result 202 should finish 1
[22/Oct/2009:09:41:19 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Consumer successfully sent operation with csn
4ae0669e000000020000
[22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:19 -0500] - repl5_inc_waitfor_async_results: 5 6
[22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: result 3,
53, 1, 6, (null)
[22/Oct/2009:09:41:19 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Consumer
failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f,
CSN 4ae0669e000000020000): DSA is unwilling to perform. Will retry
later.
[22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: got op
result 202 should finish 1
[22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result
for message_id 6
[22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain exiting
[22/Oct/2009:09:41:20 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - session end: state=0 load=1
sent=2 skipped=0
[22/Oct/2009:09:41:23 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
Successfully released consumer
[22/Oct/2009:09:41:23 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Beginning
linger on the connection
[22/Oct/2009:09:41:23 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
sending_updates -> start_backoff
[22/Oct/2009:09:41:26 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
start_backoff -> backoff
[22/Oct/2009:09:41:26 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
Cancelling linger on the connection
[22/Oct/2009:09:41:26 -0500] - _csngen_adjust_local_time: gen state
before 4ae06f0e0000:1256222476:0:2
[22/Oct/2009:09:41:26 -0500] - _csngen_adjust_local_time: gen state
after 4ae06f180000:1256222486:0:2
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Replica
was successfully acquired.
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
backoff -> sending_updates
[22/Oct/2009:09:41:27 -0500] - csngen_adjust_time: gen state before
4ae06f180001:1256222486:0:2
[22/Oct/2009:09:41:27 -0500] - _csngen_adjust_local_time: gen state
before 4ae06f180001:1256222486:0:2
[22/Oct/2009:09:41:27 -0500] - _csngen_adjust_local_time: gen state
after 4ae06f190000:1256222487:0:2
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - changelog program -
_cl5GetDBFile: found DB object 13901670 for database
f2291084-807511de-837b9eef-94681f9f_4a7758f2000000010000.db4
[22/Oct/2009:09:41:27 -0500] - _cl5PositionCursorForReplay
(agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389)):
Consumer RUV:
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
{replicageneration} 4a7758f2000000010000
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000
4adf7830000100010000 00000000
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000
4ae0612d000300020000 00000000
[22/Oct/2009:09:41:27 -0500] - _cl5PositionCursorForReplay
(agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389)):
Supplier RUV:
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
{replicageneration} 4a7758f2000000010000
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000
4ae0669e000000020000 00000000
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): {replica
1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000
4adf7830000100010000 00000000
[22/Oct/2009:09:41:27 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - clcache_get_buffer: found
thread
private buffer cache 13a76610
[22/Oct/2009:09:41:27 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - clcache_get_buffer: _pool is
13963c90 _pool->pl_busy_lists is 13903780
_pool->pl_busy_lists->bl_buffers is 13a76610
[22/Oct/2009:09:41:27 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - session start:
anchorcsn=4ae0612d000300020000
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - changelog program -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): CSN
4ae0612d000300020000 found, position set for replay
[22/Oct/2009:09:41:27 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=1
csn=4ae06697000000020000
[22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Sending modify operation
(dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu"
csn=4ae06697000000020000)
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain starting
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: read result
for message_id 9
[22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Consumer successfully sent operation with csn
4ae06697000000020000
[22/Oct/2009:09:41:28 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=2
csn=4ae0669e000000020000
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: result 3,
53, 1, 9, (null)
[22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Sending modify operation
(dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu"
csn=4ae0669e000000020000)
[22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Consumer
failed to replay change (uniqueid (null), CSN (null)): DSA is unwilling
to perform. Will retry later.
[22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
replay_update: Consumer successfully sent operation with csn
4ae0669e000000020000
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: got op
result 202 should finish 1
[22/Oct/2009:09:41:28 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - clcache_load_buffer: rc=-30989
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): No more
updates to send (cl5GetNextOperationToReplay)
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: result 3,
53, 1, 10, (null)
[22/Oct/2009:09:41:28 -0500] - repl5_inc_waitfor_async_results: 10 10
[22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Consumer
failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f,
CSN 4ae06697000000020000): DSA is unwilling to perform. Will retry
later.
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: got op
result 202 should finish 1
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result
for message_id 10
[22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain exiting
[22/Oct/2009:09:41:29 -0500] agmt="cn="Replication to
winters.hmgc.mcw.edu"" (winters:389) - session end: state=5 load=1
sent=2 skipped=0
[22/Oct/2009:09:41:30 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
Successfully released consumer
[22/Oct/2009:09:41:30 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Beginning
linger on the connection
[22/Oct/2009:09:41:30 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): State:
sending_updates -> wait_for_changes
[22/Oct/2009:09:42:30 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389): Linger
timeout has expired on the connection
[22/Oct/2009:09:42:30 -0500] NSMMReplicationPlugin -
agmt="cn="Replication to winters.hmgc.mcw.edu""
(winters:389):
Disconnected from the consumer
[22/Oct/2009:09:46:24 -0500] - slapd shutting down - signaling operation
threads
[22/Oct/2009:09:46:24 -0500] - slapd shutting down - waiting for 29
threads to terminate
[22/Oct/2009:09:46:24 -0500] - slapd shutting down - closing down
internal subsystems and plugins
[22/Oct/2009:09:46:26 -0500] - Waiting for 4 database threads to stop
[22/Oct/2009:09:46:27 -0500] - All database threads now stopped
[22/Oct/2009:09:46:27 -0500] - slapd stopped.
Brodie, Kent wrote:> Rich: Thanks for the debugging help! I''m still stuck, as I am not > sure exactly what I am looking at in terms of messages. I can see that > the same uniqueid 4922d291-be7a11de-adce9eef-94681f9f keeps failing, but > the message surrounding that error are anything but clear to me. >Try using the /usr/bin/cl-dump tool to dump the changelog (man cl-dump) - look for uniqueid 4922d291-be7a11de-adce9eef-94681f9f and dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu" and csn=4ae06697000000020000> Can someone help look at this and translate what''s going on? The log > below is about 5 mins worth. > > > [22/Oct/2009:09:36:08 -0500] - Fedora-Directory/1.2.0 B2009.118.181 > starting up > [22/Oct/2009:09:36:08 -0500] NSMMReplicationPlugin - changelog program - > _cl5CheckGuardian: found old style of guardian file: > bdb/4.3/libreplication-plugin > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program - > _cl5NewDBFile: semaphore > /var/lib/dirsrv/slapd-white/changelogdb/f2291084-807511de-837b9eef-94681 > f9f.sema > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program - > _cl5NewDBFile: maxConcurrentWrites=2 > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program - > _cl5GetEntryCount: 500 changes for replica > f2291084-807511de-837b9eef-94681f9f > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program - > _cl5AddDBFile: Added new DB object 13901670 > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program - > _cl5DBOpenFileByReplicaName: created new DB object 13901670 > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program - > _cl5DBOpen: opened 1 existing databases in > /var/lib/dirsrv/slapd-white/changelogdb > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - Found replication > agreement named "cn="Replication to > winters.hmgc.mcw.edu",cn=replica,cn="dc=hmgc, dc=mcw, dc=edu",cn=mapping > tree,cn=config". > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - > agmtlist_config_init: found 1 replication agreements in DIT > [22/Oct/2009:09:36:09 -0500] NSMMReplicationPlugin - changelog program - > _cl5GetDBFile: found DB object 13901670 for database > f2291084-807511de-837b9eef-94681f9f_4a7758f2000000010000.db4 > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): No linger > to cancel on the connection > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > Disconnected from the consumer > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > start -> ready_to_acquire_replica > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Trying > non-secure slapi_ldap_init_ext > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): binddn > cn=repman,cn=config, passwd = {DES}0U0krPfv0gLunf3SATmXQw=> [22/Oct/2009:09:36:10 -0500] - _csngen_adjust_local_time: gen state > before 4ae06db60001:1256222132:0:2 > [22/Oct/2009:09:36:10 -0500] - _csngen_adjust_local_time: gen state > after 4ae06ddc0000:1256222170:0:2 > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): No linger > to cancel on the connection > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > ruv_add_csn_inprogress: successfully inserted csn 4ae06ddc000000020000 > into pending list > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - Purged state > information from entry dc=hmgc, dc=mcw, dc=edu up to CSN > 4ad72c1e000000020000 > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > csn=4ae06ddc000000020000 process postop: canceling operation csn > [22/Oct/2009:09:36:10 -0500] - slapd started. Listening on All > Interfaces port 389 for LDAP requests > [22/Oct/2009:09:36:10 -0500] - Listening on All Interfaces port 636 for > LDAPS requests > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Replica > was successfully acquired. > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > ready_to_acquire_replica -> sending_updates > [22/Oct/2009:09:36:10 -0500] - csngen_adjust_time: gen state before > 4ae06ddc0002:1256222170:0:2 > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - changelog program - > _cl5GetDBFile: found DB object 13901670 for database > f2291084-807511de-837b9eef-94681f9f_4a7758f2000000010000.db4 > [22/Oct/2009:09:36:10 -0500] - _cl5PositionCursorForReplay > (agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389)): > Consumer RUV: > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > {replicageneration} 4a7758f2000000010000 > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000 > 4adf7830000100010000 00000000 > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000 > 4ae0612d000300020000 00000000 > [22/Oct/2009:09:36:10 -0500] - _cl5PositionCursorForReplay > (agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389)): > Supplier RUV: > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > {replicageneration} 4a7758f2000000010000 > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000 > 4ae0669e000000020000 00000000 > [22/Oct/2009:09:36:10 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000 > 4adf7830000100010000 00000000 > [22/Oct/2009:09:36:10 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - session start: > anchorcsn=4ae0612d000300020000 > [22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - changelog program - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): CSN > 4ae0612d000300020000 found, position set for replay > [22/Oct/2009:09:36:11 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=1 > csn=4ae06697000000020000 > [22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Sending modify operation > (dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu" > csn=4ae06697000000020000) > [22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Consumer successfully sent operation with csn > 4ae06697000000020000 > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain starting > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: result 3, > 53, 1, 6, (null) > [22/Oct/2009:09:36:11 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=2 > csn=4ae0669e000000020000 > [22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Sending modify operation > (dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu" > csn=4ae0669e000000020000) > [22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Consumer > failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f, > CSN 4ae06697000000020000): DSA is unwilling to perform. Will retry > later. > [22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Consumer successfully sent operation with csn > 4ae0669e000000020000 > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: got op > result 202 should finish 1 > [22/Oct/2009:09:36:11 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - clcache_load_buffer: rc=-30989 > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): No more > updates to send (cl5GetNextOperationToReplay) > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: result 3, > 53, 1, 7, (null) > [22/Oct/2009:09:36:11 -0500] - repl5_inc_waitfor_async_results: 7 7 > [22/Oct/2009:09:36:11 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Consumer > failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f, > CSN 4ae0669e000000020000): DSA is unwilling to perform. Will retry > later. > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: got op > result 202 should finish 1 > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:11 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain: read result > for message_id 7 > [22/Oct/2009:09:36:12 -0500] - repl5_inc_result_threadmain exiting > [22/Oct/2009:09:36:12 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - session end: state=5 load=1 > sent=2 skipped=0 > [22/Oct/2009:09:36:13 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > Successfully released consumer > [22/Oct/2009:09:36:14 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Beginning > linger on the connection > [22/Oct/2009:09:36:14 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > sending_updates -> wait_for_changes > [22/Oct/2009:09:37:14 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Linger > timeout has expired on the connection > [22/Oct/2009:09:37:14 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > Disconnected from the consumer > > > [22/Oct/2009:09:41:14 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > wait_for_changes -> wait_for_changes > [22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > wait_for_changes -> start > [22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): No linger > to cancel on the connection > [22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > Disconnected from the consumer > [22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > start -> ready_to_acquire_replica > [22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Trying > non-secure slapi_ldap_init_ext > [22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): binddn > cn=repman,cn=config, passwd = {DES}0U0krPfv0gLunf3SATmXQw=> [22/Oct/2009:09:41:15 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): No linger > to cancel on the connection > [22/Oct/2009:09:41:15 -0500] - _csngen_adjust_local_time: gen state > before 4ae06ddc0002:1256222170:0:2 > [22/Oct/2009:09:41:15 -0500] - _csngen_adjust_local_time: gen state > after 4ae06f0d0000:1256222475:0:2 > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Replica > was successfully acquired. > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > ready_to_acquire_replica -> sending_updates > [22/Oct/2009:09:41:16 -0500] - csngen_adjust_time: gen state before > 4ae06f0d0001:1256222475:0:2 > [22/Oct/2009:09:41:16 -0500] - _csngen_adjust_local_time: gen state > before 4ae06f0d0001:1256222475:0:2 > [22/Oct/2009:09:41:16 -0500] - _csngen_adjust_local_time: gen state > after 4ae06f0e0000:1256222476:0:2 > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - changelog program - > _cl5GetDBFile: found DB object 13901670 for database > f2291084-807511de-837b9eef-94681f9f_4a7758f2000000010000.db4 > [22/Oct/2009:09:41:16 -0500] - _cl5PositionCursorForReplay > (agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389)): > Consumer RUV: > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > {replicageneration} 4a7758f2000000010000 > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000 > 4adf7830000100010000 00000000 > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000 > 4ae0612d000300020000 00000000 > [22/Oct/2009:09:41:16 -0500] - _cl5PositionCursorForReplay > (agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389)): > Supplier RUV: > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > {replicageneration} 4a7758f2000000010000 > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000 > 4ae0669e000000020000 00000000 > [22/Oct/2009:09:41:16 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000 > 4adf7830000100010000 00000000 > [22/Oct/2009:09:41:16 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - clcache_get_buffer: found thread > private buffer cache 13a76610 > [22/Oct/2009:09:41:16 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - clcache_get_buffer: _pool is > 13963c90 _pool->pl_busy_lists is 13903780 > _pool->pl_busy_lists->bl_buffers is 13a76610 > [22/Oct/2009:09:41:17 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - session start: > anchorcsn=4ae0612d000300020000 > [22/Oct/2009:09:41:17 -0500] NSMMReplicationPlugin - changelog program - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): CSN > 4ae0612d000300020000 found, position set for replay > [22/Oct/2009:09:41:17 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=1 > csn=4ae06697000000020000 > [22/Oct/2009:09:41:17 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Sending modify operation > (dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu" > csn=4ae06697000000020000) > [22/Oct/2009:09:41:17 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Consumer successfully sent operation with csn > 4ae06697000000020000 > [22/Oct/2009:09:41:17 -0500] - repl5_inc_result_threadmain starting > [22/Oct/2009:09:41:17 -0500] - repl5_inc_result_threadmain: read result > for message_id 5 > [22/Oct/2009:09:41:17 -0500] - repl5_inc_result_threadmain: result 3, > 53, 1, 5, (null) > [22/Oct/2009:09:41:17 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=2 > csn=4ae0669e000000020000 > [22/Oct/2009:09:41:18 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Consumer > failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f, > CSN 4ae06697000000020000): DSA is unwilling to perform. Will retry > later. > [22/Oct/2009:09:41:19 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Sending modify operation > (dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu" > csn=4ae0669e000000020000) > [22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: got op > result 202 should finish 1 > [22/Oct/2009:09:41:19 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Consumer successfully sent operation with csn > 4ae0669e000000020000 > [22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:19 -0500] - repl5_inc_waitfor_async_results: 5 6 > [22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: result 3, > 53, 1, 6, (null) > [22/Oct/2009:09:41:19 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Consumer > failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f, > CSN 4ae0669e000000020000): DSA is unwilling to perform. Will retry > later. > [22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: got op > result 202 should finish 1 > [22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:19 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain: read result > for message_id 6 > [22/Oct/2009:09:41:20 -0500] - repl5_inc_result_threadmain exiting > [22/Oct/2009:09:41:20 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - session end: state=0 load=1 > sent=2 skipped=0 > [22/Oct/2009:09:41:23 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > Successfully released consumer > [22/Oct/2009:09:41:23 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Beginning > linger on the connection > [22/Oct/2009:09:41:23 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > sending_updates -> start_backoff > [22/Oct/2009:09:41:26 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > start_backoff -> backoff > [22/Oct/2009:09:41:26 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > Cancelling linger on the connection > [22/Oct/2009:09:41:26 -0500] - _csngen_adjust_local_time: gen state > before 4ae06f0e0000:1256222476:0:2 > [22/Oct/2009:09:41:26 -0500] - _csngen_adjust_local_time: gen state > after 4ae06f180000:1256222486:0:2 > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Replica > was successfully acquired. > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > backoff -> sending_updates > [22/Oct/2009:09:41:27 -0500] - csngen_adjust_time: gen state before > 4ae06f180001:1256222486:0:2 > [22/Oct/2009:09:41:27 -0500] - _csngen_adjust_local_time: gen state > before 4ae06f180001:1256222486:0:2 > [22/Oct/2009:09:41:27 -0500] - _csngen_adjust_local_time: gen state > after 4ae06f190000:1256222487:0:2 > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - changelog program - > _cl5GetDBFile: found DB object 13901670 for database > f2291084-807511de-837b9eef-94681f9f_4a7758f2000000010000.db4 > [22/Oct/2009:09:41:27 -0500] - _cl5PositionCursorForReplay > (agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389)): > Consumer RUV: > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > {replicageneration} 4a7758f2000000010000 > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000 > 4adf7830000100010000 00000000 > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000 > 4ae0612d000300020000 00000000 > [22/Oct/2009:09:41:27 -0500] - _cl5PositionCursorForReplay > (agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389)): > Supplier RUV: > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > {replicageneration} 4a7758f2000000010000 > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 2 ldap://white.hmgc.mcw.edu:389} 4a78849d000000020000 > 4ae0669e000000020000 00000000 > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): {replica > 1 ldap://winters.hmgc.mcw.edu:389} 4a799281000000010000 > 4adf7830000100010000 00000000 > [22/Oct/2009:09:41:27 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - clcache_get_buffer: found thread > private buffer cache 13a76610 > [22/Oct/2009:09:41:27 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - clcache_get_buffer: _pool is > 13963c90 _pool->pl_busy_lists is 13903780 > _pool->pl_busy_lists->bl_buffers is 13a76610 > [22/Oct/2009:09:41:27 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - session start: > anchorcsn=4ae0612d000300020000 > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - changelog program - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): CSN > 4ae0612d000300020000 found, position set for replay > [22/Oct/2009:09:41:27 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=1 > csn=4ae06697000000020000 > [22/Oct/2009:09:41:27 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Sending modify operation > (dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu" > csn=4ae06697000000020000) > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain starting > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: read result > for message_id 9 > [22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Consumer successfully sent operation with csn > 4ae06697000000020000 > [22/Oct/2009:09:41:28 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - load=1 rec=2 > csn=4ae0669e000000020000 > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: result 3, > 53, 1, 9, (null) > [22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Sending modify operation > (dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu" > csn=4ae0669e000000020000) > [22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Consumer > failed to replay change (uniqueid (null), CSN (null)): DSA is unwilling > to perform. Will retry later. > [22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > replay_update: Consumer successfully sent operation with csn > 4ae0669e000000020000 > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: got op > result 202 should finish 1 > [22/Oct/2009:09:41:28 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - clcache_load_buffer: rc=-30989 > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): No more > updates to send (cl5GetNextOperationToReplay) > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: result 3, > 53, 1, 10, (null) > [22/Oct/2009:09:41:28 -0500] - repl5_inc_waitfor_async_results: 10 10 > [22/Oct/2009:09:41:28 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Consumer > failed to replay change (uniqueid 4922d291-be7a11de-adce9eef-94681f9f, > CSN 4ae06697000000020000): DSA is unwilling to perform. Will retry > later. > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: got op > result 202 should finish 1 > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:28 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain: read result > for message_id 10 > [22/Oct/2009:09:41:29 -0500] - repl5_inc_result_threadmain exiting > [22/Oct/2009:09:41:29 -0500] agmt="cn="Replication to > winters.hmgc.mcw.edu"" (winters:389) - session end: state=5 load=1 > sent=2 skipped=0 > [22/Oct/2009:09:41:30 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > Successfully released consumer > [22/Oct/2009:09:41:30 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Beginning > linger on the connection > [22/Oct/2009:09:41:30 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): State: > sending_updates -> wait_for_changes > [22/Oct/2009:09:42:30 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): Linger > timeout has expired on the connection > [22/Oct/2009:09:42:30 -0500] NSMMReplicationPlugin - > agmt="cn="Replication to winters.hmgc.mcw.edu"" (winters:389): > Disconnected from the consumer > > > [22/Oct/2009:09:46:24 -0500] - slapd shutting down - signaling operation > threads > [22/Oct/2009:09:46:24 -0500] - slapd shutting down - waiting for 29 > threads to terminate > [22/Oct/2009:09:46:24 -0500] - slapd shutting down - closing down > internal subsystems and plugins > [22/Oct/2009:09:46:26 -0500] - Waiting for 4 database threads to stop > [22/Oct/2009:09:46:27 -0500] - All database threads now stopped > [22/Oct/2009:09:46:27 -0500] - slapd stopped. > > > > > > > -- > 389 users mailing list > 389-users@redhat.com > https://www.redhat.com/mailman/listinfo/fedora-directory-users >
On 10/22/2009 10:05 AM, Rich Megginson wrote:> Brodie, Kent wrote: >> Rich: Thanks for the debugging help! I''m still stuck, as I am not >> sure exactly what I am looking at in terms of messages. I can see that >> the same uniqueid 4922d291-be7a11de-adce9eef-94681f9f keeps failing, but >> the message surrounding that error are anything but clear to me. > Try using the /usr/bin/cl-dump tool to dump the changelog (man > cl-dump) - look for uniqueid 4922d291-be7a11de-adce9eef-94681f9f and > dn="uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu" and > csn=4ae06697000000020000 > >Rich-- ok, we''re getting closer. cl-dump told me lots, and when I looked for all three items together (uniqueid, uid, and csn), I found this entry: changetype: modify replgen: 4a7758f2000000010000 csn: 4ae06697000000020000 nsuniqueid: 4922d291-be7a11de-adce9eef-94681f9f dn: uid=jgretzon,ou=spine,dc=hmgc,dc=mcw,dc=edu change:: replace: retryCountResetTime retryCountResetTime: 20091022141509Z - replace: passwordRetryCount passwordRetryCount: 1 So, there''s some sort of passwordretry entry that''s bogus (not sure why)-- I guess now my question is, now that I''ve found what the error entry is that''s giving replication fits, what do I do about it? I really appreciate the help--- this is good stuff for the email list archives for others to see when they have something like this.
OK. Further research-- it appears I have an issue with "passwordretrycount" not replicating-- which apparently (did some searches..) is a problem others have had, when the directory services is set up in a replicating fashion (multi-master in my case). Has to do with global password policy settings, and what is allowed to replicate-- or not. I found the offending entry (passwordretrycount existed for the user on one node, not the other) and deleted it. My question now is: What''s the correct solution to this? Forum postings I''ve found thus far are unclear. Any ideas appreciated! --Kent PS: Thanks for the tons of help, I learned a lot today on debugging this stuff for future issues...
Um, rats. OK, I had deleted the offending ldap attribute, but the replication engine is still trying to process/perform the failed replication thing that I need to remove. How do I ''kill'' a particular replication entry?
Brodie, Kent wrote:> OK. Further research-- it appears I have an issue with > "passwordretrycount" not replicating-- which apparently (did some > searches..) is a problem others have had, when the directory services is > set up in a replicating fashion (multi-master in my case). Has to do > with global password policy settings, and what is allowed to replicate-- > or not. > > I found the offending entry (passwordretrycount existed for the user on > one node, not the other) and deleted it. > > My question now is: What''s the correct solution to this? Forum > postings I''ve found thus far are unclear. > > Any ideas appreciated! --Kent > > PS: Thanks for the tons of help, I learned a lot today on debugging > this stuff for future issues... >If you have password policy on, the directory server will make modifications to users'' entries when they use password authentication. You may or may not want these to be replicated. For example, if you have password retry counting with lockout enabled - if this policy is local only, a hacker could attempt to hack an account N times on master 1, N times on master 2, etc. So instead of the password retry count being N, it''s really M x N. If you care about this, you can enable these password policy attributes to be replicated and accepted on the consumer, by turning on the passwordIsGlobalPolicy in cn=config on each consumer. If you do not want these attributes replicated at all, modify your replication agreement to exclude the following attributes from being replicated: retryCountResetTime passwordRetryCount accountUnlockTime> > -- > 389 users mailing list > 389-users@redhat.com > https://www.redhat.com/mailman/listinfo/fedora-directory-users >
Brodie, Kent wrote:> Um, rats. OK, I had deleted the offending ldap attribute, but the > replication engine is still trying to process/perform the failed > replication thing that I need to remove. How do I ''kill'' a particular > replication entry? >Try what I sent in my other email.> > -- > 389 users mailing list > 389-users@redhat.com > https://www.redhat.com/mailman/listinfo/fedora-directory-users >