[389-users] Consumer failed to replay change

Thu Oct 22 15:05:20 UTC 2009

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 at redhat.com
> https://www.redhat.com/mailman/listinfo/fedora-directory-users
>   

-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/x-pkcs7-signature
Size: 3258 bytes
Desc: S/MIME Cryptographic Signature
URL: <http://listman.redhat.com/archives/fedora-directory-users/attachments/20091022/a4066b65/attachment.bin>