RE: [389-users] Consumer failed to replay change

[Brodie, Kent]

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.