389-Directory/1.2.11.15 B2014.023.1959
	test389ds0.example.com:389 (/etc/dirsrv/slapd-test389ds0)
[18/Feb/2014:19:23:10 +0100] - WARNING: Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[18/Feb/2014:19:23:11 +0100] - check_and_set_import_cache: pagesize: 4096, pages: 126502, procpages: 47619
[18/Feb/2014:19:23:11 +0100] - WARNING: After allocating import cache 202400KB, the available memory is 303608KB, which is less than the soft limit 1048576KB. You may want to decrease the import cache size and rerun import.
[18/Feb/2014:19:23:11 +0100] - Import allocates 202400KB import cache.
[18/Feb/2014:19:23:11 +0100] - import userRoot: Beginning import job...
[18/Feb/2014:19:23:11 +0100] - import userRoot: Index buffering enabled with bucket size 100
[18/Feb/2014:19:23:11 +0100] - import userRoot: Processing file "/tmp/ldifOfXaZ1.ldif"
[18/Feb/2014:19:23:11 +0100] - import userRoot: Finished scanning file "/tmp/ldifOfXaZ1.ldif" (9 entries)
[18/Feb/2014:19:23:11 +0100] - import userRoot: Workers finished; cleaning up...
[18/Feb/2014:19:23:12 +0100] - import userRoot: Workers cleaned up.
[18/Feb/2014:19:23:12 +0100] - import userRoot: Cleaning up producer thread...
[18/Feb/2014:19:23:12 +0100] - import userRoot: Indexing complete.  Post-processing...
[18/Feb/2014:19:23:12 +0100] - import userRoot: Generating numSubordinates complete.
[18/Feb/2014:19:23:12 +0100] - import userRoot: Flushing caches...
[18/Feb/2014:19:23:12 +0100] - import userRoot: Closing files...
[18/Feb/2014:19:23:12 +0100] - All database threads now stopped
[18/Feb/2014:19:23:12 +0100] - import userRoot: Import complete.  Processed 9 entries in 1 seconds. (9.00 entries/sec)
[18/Feb/2014:19:23:12 +0100] - 389-Directory/1.2.11.15 B2014.023.1959 starting up
[18/Feb/2014:19:23:12 +0100] - Db home directory is not set. Possibly nsslapd-directory (optinally nsslapd-db-home-directory) is missing in the config file.
[18/Feb/2014:19:23:12 +0100] - I'm resizing my cache now...cache was 207257600 and is now 8000000
[18/Feb/2014:19:23:13 +0100] - slapd started.  Listening on All Interfaces port 389 for LDAP requests
[18/Feb/2014:20:25:06 +0100] NSMMReplicationPlugin - changelog program - _cl5AppInit: fetched backend dbEnv (1558100)
[18/Feb/2014:20:25:06 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpen: opened 0 existing databases in /var/lib/dirsrv/slapd-test389ds0/changelogdb
[18/Feb/2014:20:25:06 +0100] NSMMReplicationPlugin - replica_add_by_dn: added dn (dc=domain,dc=com)
[18/Feb/2014:20:25:06 +0100] NSMMReplicationPlugin - _replica_configure_ruv: No ruv tombstone found for replica dc=domain,dc=com. Created a new one
[18/Feb/2014:20:25:06 +0100] NSMMReplicationPlugin - repl_set_mtn_referrals: could not set referrals for replica dc=domain,dc=com: 32
[18/Feb/2014:20:25:06 +0100] NSMMReplicationPlugin - replica_delete_by_dn: removed dn (dc=domain,dc=com)
[18/Feb/2014:20:26:41 +0100] NSMMReplicationPlugin - agmt_add: begin
[18/Feb/2014:20:26:41 +0100] NSMMReplicationPlugin - : Update window will close at Wed Feb 19 00:00:00 2014
[18/Feb/2014:20:26:41 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Trying non-secure slapi_ldap_init_ext
[18/Feb/2014:20:26:41 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): binddn = cn=replication manager,cn=config,  passwd = {DES}B2OG7Oq1vHUZ8y8xwLoxMw==
[18/Feb/2014:20:26:41 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No linger to cancel on the connection
[18/Feb/2014:20:26:41 +0100] - _csngen_adjust_local_time: gen state before 5303b3920001:1392751506:0:0
[18/Feb/2014:20:26:41 +0100] - _csngen_adjust_local_time: gen state after 5303b3f10000:1392751601:0:0
[18/Feb/2014:20:26:41 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Replica was successfully acquired.
[18/Feb/2014:20:26:41 +0100] NSMMReplicationPlugin - Beginning total update of replica "agmt="cn=testreplica" (test389ds1:389)".
[18/Feb/2014:20:26:41 +0100] - repl5_tot_waitfor_async_results: 0 6
[18/Feb/2014:20:26:42 +0100] - repl5_tot_waitfor_async_results: 6 6
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Successfully released consumer
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Beginning linger on the connection
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - Finished total update of replica "agmt="cn=testreplica" (test389ds1:389)". Sent 2 entries.
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Cancelling linger on the connection
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Disconnected from the consumer
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: start -> ready_to_acquire_replica
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Trying non-secure slapi_ldap_init_ext
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): binddn = cn=replication manager,cn=config,  passwd = {DES}B2OG7Oq1vHUZ8y8xwLoxMw==
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No linger to cancel on the connection
[18/Feb/2014:20:26:46 +0100] - _csngen_adjust_local_time: gen state before 5303b3f10001:1392751601:0:0
[18/Feb/2014:20:26:46 +0100] - _csngen_adjust_local_time: gen state after 5303b3f60000:1392751606:0:0
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Replica was successfully acquired.
[18/Feb/2014:20:26:46 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: ready_to_acquire_replica -> sending_updates
[18/Feb/2014:20:26:47 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: no DB object found for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:26:47 +0100] NSMMReplicationPlugin - changelog program - cl5CreateReplayIterator: could not find DB object for replica
[18/Feb/2014:20:26:47 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No changes to send
[18/Feb/2014:20:26:47 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Successfully released consumer
[18/Feb/2014:20:26:47 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Beginning linger on the connection
[18/Feb/2014:20:26:47 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: sending_updates -> wait_for_changes
[18/Feb/2014:20:27:48 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Linger timeout has expired on the connection
[18/Feb/2014:20:27:48 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Disconnected from the consumer
[18/Feb/2014:20:29:56 +0100] - _csngen_adjust_local_time: gen state before 5303b3f60001:1392751606:0:0
[18/Feb/2014:20:29:56 +0100] - _csngen_adjust_local_time: gen state after 5303b4b40000:1392751796:0:0
[18/Feb/2014:20:29:56 +0100] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 5303b4b4000000010000 into pending list
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: no DB object found for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - changelog program - _cl5NewDBFile: semaphore /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121.sema
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - changelog program - _cl5NewDBFile: maxConcurrentWrites=2
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - changelog program - _cl5GetEntryCount: 0 changes for replica 3f12d603-98d211e3-be33f1f9-9beb3121
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - changelog program - _cl5AddDBFile: Added new DB object 14afa80
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpenFileByReplicaName: created new DB object 14afa80
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 14afa80 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - ruv_update_ruv: successfully committed csn 5303b4b4000000010000
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> wait_for_changes
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> ready_to_acquire_replica
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Trying non-secure slapi_ldap_init_ext
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): binddn = cn=replication manager,cn=config,  passwd = {DES}B2OG7Oq1vHUZ8y8xwLoxMw==
[18/Feb/2014:20:29:57 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No linger to cancel on the connection
[18/Feb/2014:20:29:57 +0100] - _csngen_adjust_local_time: gen state before 5303b4b40001:1392751796:0:0
[18/Feb/2014:20:29:57 +0100] - _csngen_adjust_local_time: gen state after 5303b4b50000:1392751797:0:0
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Replica was successfully acquired.
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: ready_to_acquire_replica -> sending_updates
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 14afa80 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:29:58 +0100] - _cl5PositionCursorForReplay (agmt="cn=testreplica" (test389ds1:389)): Consumer RUV:
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replicageneration} 5303b392000000010000
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replica 1 ldap://test389ds0.example.com:389}
[18/Feb/2014:20:29:58 +0100] - _cl5PositionCursorForReplay (agmt="cn=testreplica" (test389ds1:389)): Supplier RUV:
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replicageneration} 5303b392000000010000
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replica 1 ldap://test389ds0.example.com:389} 5303b4b4000000010000 5303b4b4000000010000 5303b4b5
[18/Feb/2014:20:29:58 +0100] agmt="cn=testreplica" (test389ds1:389) - session start: anchorcsn=5303b4b4000000010000
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - changelog program - agmt="cn=testreplica" (test389ds1:389): CSN 5303b4b4000000010000 found, position set for replay
[18/Feb/2014:20:29:58 +0100] agmt="cn=testreplica" (test389ds1:389) - load=1 rec=1 csn=5303b4b4000000010000
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): replay_update: Sending add operation (dn="uid=user1,ou=People,dc=domain,dc=com" csn=5303b4b4000000010000)
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): replay_update: Consumer successfully sent operation with csn 5303b4b4000000010000
[18/Feb/2014:20:29:58 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_load_buffer: rc=-30987
[18/Feb/2014:20:29:58 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No more updates to send (cl5GetNextOperationToReplay)
[18/Feb/2014:20:29:58 +0100] - repl5_inc_waitfor_async_results: 0 6
[18/Feb/2014:20:29:58 +0100] - repl5_inc_result_threadmain starting
[18/Feb/2014:20:29:58 +0100] - repl5_inc_result_threadmain: read result for message_id 0
[18/Feb/2014:20:29:58 +0100] - repl5_inc_result_threadmain: read result for message_id 0
[18/Feb/2014:20:29:58 +0100] - repl5_inc_result_threadmain: read result for message_id 0
[18/Feb/2014:20:29:58 +0100] - repl5_inc_result_threadmain: read result for message_id 0
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 0
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 0
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 0
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 0
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: result 1, 0, 0, 6, (null)
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_waitfor_async_results: 6 6
[18/Feb/2014:20:29:59 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:30:00 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:30:00 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:30:00 +0100] - repl5_inc_result_threadmain exiting
[18/Feb/2014:20:30:00 +0100] agmt="cn=testreplica" (test389ds1:389) - session end: state=5 load=1 sent=1 skipped=0
[18/Feb/2014:20:30:01 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Successfully released consumer
[18/Feb/2014:20:30:01 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Beginning linger on the connection
[18/Feb/2014:20:30:01 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: sending_updates -> wait_for_changes
[18/Feb/2014:20:30:07 +0100] - Changelog purge skipped anchor csn 5303b4b4000000010000
[18/Feb/2014:20:30:08 +0100] NSMMReplicationPlugin - _replica_update_state: failed to update state of csn generator for replica dc=domain,dc=com: LDAP error - 32
[18/Feb/2014:20:30:22 +0100] - _csngen_adjust_local_time: gen state before 5303b4b50001:1392751797:0:0
[18/Feb/2014:20:30:23 +0100] - _csngen_adjust_local_time: gen state after 5303b4ce0000:1392751822:0:0
[18/Feb/2014:20:30:23 +0100] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 5303b4ce000000010000 into pending list
[18/Feb/2014:20:30:23 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 14afa80 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:30:23 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 14afa80 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:30:23 +0100] NSMMReplicationPlugin - ruv_update_ruv: successfully committed csn 5303b4ce000000010000
[18/Feb/2014:20:30:23 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> wait_for_changes
[18/Feb/2014:20:30:23 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> ready_to_acquire_replica
[18/Feb/2014:20:30:23 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Cancelling linger on the connection
[18/Feb/2014:20:30:23 +0100] - _csngen_adjust_local_time: gen state before 5303b4ce0001:1392751822:0:0
[18/Feb/2014:20:30:23 +0100] - _csngen_adjust_local_time: gen state after 5303b4cf0000:1392751823:0:0
[18/Feb/2014:20:30:23 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Replica was successfully acquired.
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: ready_to_acquire_replica -> sending_updates
[18/Feb/2014:20:30:24 +0100] - csngen_adjust_time: gen state before 5303b4cf0001:1392751823:0:0
[18/Feb/2014:20:30:24 +0100] - _csngen_adjust_local_time: gen state before 5303b4cf0001:1392751823:0:0
[18/Feb/2014:20:30:24 +0100] - _csngen_adjust_local_time: gen state after 5303b4d00000:1392751824:0:0
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 14afa80 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:30:24 +0100] - _cl5PositionCursorForReplay (agmt="cn=testreplica" (test389ds1:389)): Consumer RUV:
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replicageneration} 5303b392000000010000
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replica 1 ldap://test389ds0.example.com:389} 5303b4b4000000010000 5303b4b4000000010000 00000000
[18/Feb/2014:20:30:24 +0100] - _cl5PositionCursorForReplay (agmt="cn=testreplica" (test389ds1:389)): Supplier RUV:
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replicageneration} 5303b392000000010000
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replica 1 ldap://test389ds0.example.com:389} 5303b4b4000000010000 5303b4ce000000010000 5303b4cf
[18/Feb/2014:20:30:24 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_get_buffer: found thread private buffer cache 14b0a80
[18/Feb/2014:20:30:24 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_get_buffer: _pool is 16a3410 _pool->pl_busy_lists is 166c900 _pool->pl_busy_lists->bl_buffers is 14b0a80
[18/Feb/2014:20:30:24 +0100] agmt="cn=testreplica" (test389ds1:389) - session start: anchorcsn=5303b4b4000000010000
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - changelog program - agmt="cn=testreplica" (test389ds1:389): CSN 5303b4b4000000010000 found, position set for replay
[18/Feb/2014:20:30:24 +0100] agmt="cn=testreplica" (test389ds1:389) - load=1 rec=1 csn=5303b4ce000000010000
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): replay_update: Sending add operation (dn="uid=user2,ou=People,dc=domain,dc=com" csn=5303b4ce000000010000)
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): replay_update: Consumer successfully sent operation with csn 5303b4ce000000010000
[18/Feb/2014:20:30:24 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_load_buffer: rc=-30987
[18/Feb/2014:20:30:24 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No more updates to send (cl5GetNextOperationToReplay)
[18/Feb/2014:20:30:24 +0100] - repl5_inc_waitfor_async_results: 0 10
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain starting
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain: result 1, 0, 0, 10, (null)
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:24 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:25 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:25 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:25 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:25 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:25 +0100] - repl5_inc_waitfor_async_results: 10 10
[18/Feb/2014:20:30:26 +0100] - repl5_inc_result_threadmain: read result for message_id 10
[18/Feb/2014:20:30:27 +0100] - repl5_inc_result_threadmain exiting
[18/Feb/2014:20:30:27 +0100] agmt="cn=testreplica" (test389ds1:389) - session end: state=5 load=1 sent=1 skipped=0
[18/Feb/2014:20:30:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Successfully released consumer
[18/Feb/2014:20:30:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Beginning linger on the connection
[18/Feb/2014:20:30:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: sending_updates -> wait_for_changes
[18/Feb/2014:20:30:38 +0100] NSMMReplicationPlugin - _replica_update_state: failed to update state of csn generator for replica dc=domain,dc=com: LDAP error - 32
[18/Feb/2014:20:31:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Linger timeout has expired on the connection
[18/Feb/2014:20:31:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Disconnected from the consumer
[18/Feb/2014:20:32:27 +0100] - _csngen_adjust_local_time: gen state before 5303b4d00000:1392751824:0:0
[18/Feb/2014:20:32:27 +0100] - _csngen_adjust_local_time: gen state after 5303b54b0000:1392751947:0:0
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 5303b54b000000010000 into pending list
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 14afa80 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 14afa80 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - ruv_update_ruv: successfully committed csn 5303b54b000000010000
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> wait_for_changes
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> ready_to_acquire_replica
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Trying non-secure slapi_ldap_init_ext
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): binddn = cn=replication manager,cn=config,  passwd = {DES}B2OG7Oq1vHUZ8y8xwLoxMw==
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No linger to cancel on the connection
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Replica was successfully acquired.
[18/Feb/2014:20:32:27 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: ready_to_acquire_replica -> sending_updates
[18/Feb/2014:20:32:27 +0100] - csngen_adjust_time: gen state before 5303b54b0002:1392751947:0:0
[18/Feb/2014:20:32:28 +0100] - _csngen_adjust_local_time: gen state before 5303b54b0002:1392751947:0:0
[18/Feb/2014:20:32:28 +0100] - _csngen_adjust_local_time: gen state after 5303b54c0000:1392751948:0:0
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 14afa80 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:32:28 +0100] - _cl5PositionCursorForReplay (agmt="cn=testreplica" (test389ds1:389)): Consumer RUV:
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replicageneration} 5303b392000000010000
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replica 1 ldap://test389ds0.example.com:389} 5303b4b4000000010000 5303b4ce000000010000 00000000
[18/Feb/2014:20:32:28 +0100] - _cl5PositionCursorForReplay (agmt="cn=testreplica" (test389ds1:389)): Supplier RUV:
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replicageneration} 5303b392000000010000
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replica 1 ldap://test389ds0.example.com:389} 5303b4b4000000010000 5303b54b000000010000 5303b54b
[18/Feb/2014:20:32:28 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_get_buffer: found thread private buffer cache 14b0a80
[18/Feb/2014:20:32:28 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_get_buffer: _pool is 16a3410 _pool->pl_busy_lists is 166c900 _pool->pl_busy_lists->bl_buffers is 14b0a80
[18/Feb/2014:20:32:28 +0100] agmt="cn=testreplica" (test389ds1:389) - session start: anchorcsn=5303b4ce000000010000
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - changelog program - agmt="cn=testreplica" (test389ds1:389): CSN 5303b4ce000000010000 found, position set for replay
[18/Feb/2014:20:32:28 +0100] agmt="cn=testreplica" (test389ds1:389) - load=1 rec=1 csn=5303b54b000000010000
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): replay_update: Sending add operation (dn="uid=user3,ou=People,dc=domain,dc=com" csn=5303b54b000000010000)
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): replay_update: Consumer successfully sent operation with csn 5303b54b000000010000
[18/Feb/2014:20:32:28 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_load_buffer: rc=-30987
[18/Feb/2014:20:32:28 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No more updates to send (cl5GetNextOperationToReplay)
[18/Feb/2014:20:32:28 +0100] - repl5_inc_waitfor_async_results: 0 6
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain starting
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: result 1, 0, 0, 6, (null)
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:28 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:29 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:29 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:29 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:29 +0100] - repl5_inc_waitfor_async_results: 6 6
[18/Feb/2014:20:32:30 +0100] - repl5_inc_result_threadmain: read result for message_id 6
[18/Feb/2014:20:32:31 +0100] - repl5_inc_result_threadmain exiting
[18/Feb/2014:20:32:31 +0100] agmt="cn=testreplica" (test389ds1:389) - session end: state=5 load=1 sent=1 skipped=0
[18/Feb/2014:20:32:31 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Successfully released consumer
[18/Feb/2014:20:32:31 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Beginning linger on the connection
[18/Feb/2014:20:32:31 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: sending_updates -> wait_for_changes
[18/Feb/2014:20:32:38 +0100] NSMMReplicationPlugin - _replica_update_state: failed to update state of csn generator for replica dc=domain,dc=com: LDAP error - 32
[18/Feb/2014:20:33:31 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Linger timeout has expired on the connection
[18/Feb/2014:20:33:31 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Disconnected from the consumer
[18/Feb/2014:20:34:59 +0100] - slapd shutting down - signaling operation threads
[18/Feb/2014:20:34:59 +0100] - slapd shutting down - closing down internal subsystems and plugins
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> wait_for_changes
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> wait_for_changes
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No linger to cancel on the connection
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Disconnected from the consumer
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): repl5_inc_stop: protocol stopped after 0 seconds
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - changelog program - _cl5TrimMain: exiting
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - changelog program - _cl5DBClose: deleting DB object 14afa80
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - changelog program - _cl5DBClose: closing databases in /var/lib/dirsrv/slapd-test389ds0/changelogdb
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - changelog program - _cl5DBCloseFile: Closing database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - changelog program - _cl5DBCloseFile: Closed the changelog database handle for /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4 (rc: 0)
[18/Feb/2014:20:35:00 +0100] NSMMReplicationPlugin - _replica_update_state: failed to update state of csn generator for replica dc=domain,dc=com: LDAP error - 32
[18/Feb/2014:20:35:00 +0100] - Waiting for 4 database threads to stop
[18/Feb/2014:20:35:01 +0100] - All database threads now stopped
[18/Feb/2014:20:35:01 +0100] - slapd stopped.
[18/Feb/2014:20:35:26 +0100] - 389-Directory/1.2.11.15 B2014.023.1959 starting up
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - changelog program - _cl5AppInit: fetched backend dbEnv (11a4d90)
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - changelog program - _cl5NewDBFile: semaphore /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121.sema
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - changelog program - _cl5NewDBFile: maxConcurrentWrites=2
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - changelog program - _cl5GetEntryCount: 3 changes for replica 3f12d603-98d211e3-be33f1f9-9beb3121
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - changelog program - _cl5AddDBFile: Added new DB object 1373140
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpenFileByReplicaName: created new DB object 1373140
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpen: opened 1 existing databases in /var/lib/dirsrv/slapd-test389ds0/changelogdb
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - Found replication agreement named "cn=testreplica,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config".
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - : Update window will close at Wed Feb 19 00:00:00 2014
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - The replication agreement named "cn=testreplica,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config" could not be correctly parsed. No replication will occur with this replica.
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - agmtlist_config_init: found 0 replication agreements in DIT
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 1373140 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - repl_set_mtn_referrals: could not set referrals for replica dc=domain,dc=com: 32
[18/Feb/2014:20:35:26 +0100] - _csngen_adjust_local_time: gen state before 5303b3920001:1392751506:0:0
[18/Feb/2014:20:35:26 +0100] - _csngen_adjust_local_time: gen state after 5303b5fe0000:1392752126:0:0
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 5303b5fe000000010000 into pending list
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin - Purged state information from entry dc=example,dc=com up to CSN 52fa7acb000000010000
[18/Feb/2014:20:35:26 +0100] NSMMReplicationPlugin -  csn=5303b5fe000000010000 process postop: canceling operation csn
[18/Feb/2014:20:35:26 +0100] - slapd started.  Listening on All Interfaces port 389 for LDAP requests
[18/Feb/2014:20:35:28 +0100] NSMMReplicationPlugin - _replica_update_state: failed to update state of csn generator for replica dc=domain,dc=com: LDAP error - 32
[18/Feb/2014:20:40:24 +0100] NSMMReplicationPlugin - Total update aborted: Replication agreement for "agmt="cn=testreplica" (test389ds1:389)" can not be updated while the replica is disabled
[18/Feb/2014:20:40:24 +0100] NSMMReplicationPlugin - (If the suffix is disabled you must enable it then restart the server for replication to take place).
[18/Feb/2014:20:42:10 +0100] NSMMReplicationPlugin - agmt_delete: begin
[18/Feb/2014:20:42:30 +0100] NSMMReplicationPlugin - agmt_add: begin
[18/Feb/2014:20:42:30 +0100] NSMMReplicationPlugin - : Update window will close at Wed Feb 19 00:00:00 2014
[18/Feb/2014:20:42:30 +0100] NSMMReplicationPlugin - agmtlist_add_callback: Can't start agreement "cn=testreplica,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config"
[18/Feb/2014:20:43:36 +0100] - slapd shutting down - signaling operation threads
[18/Feb/2014:20:43:36 +0100] - slapd shutting down - closing down internal subsystems and plugins
[18/Feb/2014:20:43:36 +0100] NSMMReplicationPlugin - changelog program - _cl5TrimMain: exiting
[18/Feb/2014:20:43:36 +0100] NSMMReplicationPlugin - changelog program - _cl5DBClose: deleting DB object 1373140
[18/Feb/2014:20:43:36 +0100] NSMMReplicationPlugin - changelog program - _cl5DBClose: closing databases in /var/lib/dirsrv/slapd-test389ds0/changelogdb
[18/Feb/2014:20:43:36 +0100] NSMMReplicationPlugin - changelog program - _cl5DBCloseFile: Closing database /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:43:37 +0100] NSMMReplicationPlugin - changelog program - _cl5DBCloseFile: Closed the changelog database handle for /var/lib/dirsrv/slapd-test389ds0/changelogdb/3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4 (rc: 0)
[18/Feb/2014:20:43:37 +0100] NSMMReplicationPlugin - _replica_update_state: failed to update state of csn generator for replica dc=domain,dc=com: LDAP error - 32
[18/Feb/2014:20:43:37 +0100] - Waiting for 4 database threads to stop
[18/Feb/2014:20:43:37 +0100] - All database threads now stopped
[18/Feb/2014:20:43:37 +0100] - replica_destroy
[18/Feb/2014:20:43:37 +0100] - slapd stopped.
[18/Feb/2014:20:43:39 +0100] - 389-Directory/1.2.11.15 B2014.023.1959 starting up
[18/Feb/2014:20:43:39 +0100] NSMMReplicationPlugin - changelog program - _cl5AppInit: fetched backend dbEnv (16df600)
[18/Feb/2014:20:43:39 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpen: file 3f12d603-98d211e3-be33f1f9-9beb3121_5303b392000000010000.db4 has no matching replica; removing
[18/Feb/2014:20:43:39 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpen: opened 0 existing databases in /var/lib/dirsrv/slapd-test389ds0/changelogdb
[18/Feb/2014:20:43:40 +0100] NSMMReplicationPlugin - agmtlist_config_init: found 0 replication agreements in DIT
[18/Feb/2014:20:43:40 +0100] - slapd started.  Listening on All Interfaces port 389 for LDAP requests
[18/Feb/2014:20:43:54 +0100] NSMMReplicationPlugin - replica_add_by_dn: added dn (dc=domain,dc=com)
[18/Feb/2014:20:43:54 +0100] NSMMReplicationPlugin - repl_set_mtn_referrals: could not set referrals for replica dc=domain,dc=com: 32
[18/Feb/2014:20:43:54 +0100] NSMMReplicationPlugin - replica_delete_by_dn: removed dn (dc=domain,dc=com)
[18/Feb/2014:20:44:02 +0100] NSMMReplicationPlugin - agmt_add: begin
[18/Feb/2014:20:44:03 +0100] NSMMReplicationPlugin - : Update window will close at Wed Feb 19 00:00:00 2014
[18/Feb/2014:20:44:03 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Trying non-secure slapi_ldap_init_ext
[18/Feb/2014:20:44:03 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): binddn = cn=replication manager,cn=config,  passwd = {DES}B2OG7Oq1vHUZ8y8xwLoxMw==
[18/Feb/2014:20:44:03 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No linger to cancel on the connection
[18/Feb/2014:20:44:03 +0100] - _csngen_adjust_local_time: gen state before 5303b7fa0000:1392752634:0:0
[18/Feb/2014:20:44:03 +0100] - _csngen_adjust_local_time: gen state after 5303b8030000:1392752643:0:0
[18/Feb/2014:20:44:03 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Replica was successfully acquired.
[18/Feb/2014:20:44:03 +0100] NSMMReplicationPlugin - Beginning total update of replica "agmt="cn=testreplica" (test389ds1:389)".
[18/Feb/2014:20:44:03 +0100] - repl5_tot_waitfor_async_results: 0 9
[18/Feb/2014:20:44:05 +0100] - repl5_tot_waitfor_async_results: 9 9
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Successfully released consumer
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Beginning linger on the connection
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - Finished total update of replica "agmt="cn=testreplica" (test389ds1:389)". Sent 5 entries.
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Cancelling linger on the connection
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Disconnected from the consumer
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: start -> ready_to_acquire_replica
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Trying non-secure slapi_ldap_init_ext
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): binddn = cn=replication manager,cn=config,  passwd = {DES}B2OG7Oq1vHUZ8y8xwLoxMw==
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No linger to cancel on the connection
[18/Feb/2014:20:44:08 +0100] - _csngen_adjust_local_time: gen state before 5303b8030001:1392752643:0:0
[18/Feb/2014:20:44:08 +0100] - _csngen_adjust_local_time: gen state after 5303b8080000:1392752648:0:0
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Replica was successfully acquired.
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: ready_to_acquire_replica -> sending_updates
[18/Feb/2014:20:44:08 +0100] - csngen_adjust_time: gen state before 5303b8080001:1392752648:0:0
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: no DB object found for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - changelog program - cl5CreateReplayIterator: could not find DB object for replica
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No changes to send
[18/Feb/2014:20:44:08 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Successfully released consumer
[18/Feb/2014:20:44:09 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Beginning linger on the connection
[18/Feb/2014:20:44:09 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: sending_updates -> wait_for_changes
[18/Feb/2014:20:45:01 +0100] - _csngen_adjust_local_time: gen state before 5303b8080001:1392752648:0:0
[18/Feb/2014:20:45:01 +0100] - _csngen_adjust_local_time: gen state after 5303b83d0000:1392752701:0:0
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 5303b83d000000010000 into pending list
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: no DB object found for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - changelog program - _cl5NewDBFile: semaphore /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121.sema
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - changelog program - _cl5NewDBFile: maxConcurrentWrites=2
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - changelog program - _cl5GetEntryCount: 0 changes for replica f7417e02-98d411e3-be33f1f9-9beb3121
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - changelog program - _cl5AddDBFile: Added new DB object 18aae00
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpenFileByReplicaName: created new DB object 18aae00
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 18aae00 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - ruv_update_ruv: successfully committed csn 5303b83d000000010000
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> wait_for_changes
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> ready_to_acquire_replica
[18/Feb/2014:20:45:01 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Cancelling linger on the connection
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Replica was successfully acquired.
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: ready_to_acquire_replica -> sending_updates
[18/Feb/2014:20:45:02 +0100] - csngen_adjust_time: gen state before 5303b83d0002:1392752701:0:0
[18/Feb/2014:20:45:02 +0100] - _csngen_adjust_local_time: gen state before 5303b83d0002:1392752701:0:0
[18/Feb/2014:20:45:02 +0100] - _csngen_adjust_local_time: gen state after 5303b83e0000:1392752702:0:0
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 18aae00 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:45:02 +0100] - _cl5PositionCursorForReplay (agmt="cn=testreplica" (test389ds1:389)): Consumer RUV:
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replicageneration} 5303b392000000010000
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replica 1 ldap://test389ds0.example.com:389} 5303b4b4000000010000 5303b54b000000010000 00000000
[18/Feb/2014:20:45:02 +0100] - _cl5PositionCursorForReplay (agmt="cn=testreplica" (test389ds1:389)): Supplier RUV:
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replicageneration} 5303b392000000010000
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): {replica 1 ldap://test389ds0.example.com:389} 5303b4b4000000010000 5303b83d000000010000 5303b83d
[18/Feb/2014:20:45:02 +0100] agmt="cn=testreplica" (test389ds1:389) - session start: anchorcsn=5303b54b000000010000
[18/Feb/2014:20:45:02 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_load_buffer: rc=-30987
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - changelog program - agmt="cn=testreplica" (test389ds1:389): CSN 5303b83d000000010000 not found and no purging, probably a reinit
[18/Feb/2014:20:45:02 +0100] NSMMReplicationPlugin - changelog program - agmt="cn=testreplica" (test389ds1:389): Will try to use supplier min CSN 5303b83d000000010000 to load changelog
[18/Feb/2014:20:45:03 +0100] agmt="cn=testreplica" (test389ds1:389) - session start: anchorcsn=5303b83d000000010000
[18/Feb/2014:20:45:03 +0100] NSMMReplicationPlugin - changelog program - agmt="cn=testreplica" (test389ds1:389): CSN 5303b83d000000010000 found, position set for replay
[18/Feb/2014:20:45:03 +0100] agmt="cn=testreplica" (test389ds1:389) - load=1 rec=1 csn=5303b83d000000010000
[18/Feb/2014:20:45:03 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): replay_update: Sending add operation (dn="uid=user4,ou=People,dc=domain,dc=com" csn=5303b83d000000010000)
[18/Feb/2014:20:45:03 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): replay_update: Consumer successfully sent operation with csn 5303b83d000000010000
[18/Feb/2014:20:45:03 +0100] agmt="cn=testreplica" (test389ds1:389) - clcache_load_buffer: rc=-30987
[18/Feb/2014:20:45:03 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): No more updates to send (cl5GetNextOperationToReplay)
[18/Feb/2014:20:45:03 +0100] - repl5_inc_waitfor_async_results: 0 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain starting
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: result 1, 0, 0, 8, (null)
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:03 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:04 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:04 +0100] - repl5_inc_waitfor_async_results: 8 8
[18/Feb/2014:20:45:04 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:05 +0100] - repl5_inc_result_threadmain: read result for message_id 8
[18/Feb/2014:20:45:06 +0100] - repl5_inc_result_threadmain exiting
[18/Feb/2014:20:45:06 +0100] agmt="cn=testreplica" (test389ds1:389) - session end: state=5 load=1 sent=1 skipped=0
[18/Feb/2014:20:45:06 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Successfully released consumer
[18/Feb/2014:20:45:06 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Beginning linger on the connection
[18/Feb/2014:20:45:06 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: sending_updates -> wait_for_changes
[18/Feb/2014:20:45:14 +0100] - slapd shutting down - signaling operation threads
[18/Feb/2014:20:45:14 +0100] - slapd shutting down - closing down internal subsystems and plugins
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> wait_for_changes
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): State: wait_for_changes -> wait_for_changes
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Cancelling linger on the connection
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): Disconnected from the consumer
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - agmt="cn=testreplica" (test389ds1:389): repl5_inc_stop: protocol stopped after 0 seconds
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - changelog program - _cl5TrimMain: exiting
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - changelog program - _cl5DBClose: deleting DB object 18aae00
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - changelog program - _cl5DBClose: closing databases in /var/lib/dirsrv/slapd-test389ds0/changelogdb
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - changelog program - _cl5DBCloseFile: Closing database /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - changelog program - _cl5DBCloseFile: Closed the changelog database handle for /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121_5303b392000000010000.db4 (rc: 0)
[18/Feb/2014:20:45:14 +0100] NSMMReplicationPlugin - _replica_update_state: failed to update state of csn generator for replica dc=domain,dc=com: LDAP error - 32
[18/Feb/2014:20:45:14 +0100] - Waiting for 4 database threads to stop
[18/Feb/2014:20:45:15 +0100] - All database threads now stopped
[18/Feb/2014:20:45:15 +0100] - slapd stopped.
[18/Feb/2014:20:45:21 +0100] - 389-Directory/1.2.11.15 B2014.023.1959 starting up
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - changelog program - _cl5AppInit: fetched backend dbEnv (cda4d0)
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - changelog program - _cl5NewDBFile: semaphore /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121.sema
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - changelog program - _cl5NewDBFile: maxConcurrentWrites=2
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - changelog program - _cl5GetEntryCount: 1 changes for replica f7417e02-98d411e3-be33f1f9-9beb3121
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - changelog program - _cl5AddDBFile: Added new DB object c33720
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpenFileByReplicaName: created new DB object c33720
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - changelog program - _cl5DBOpen: opened 1 existing databases in /var/lib/dirsrv/slapd-test389ds0/changelogdb
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - Found replication agreement named "cn=testreplica,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config".
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - : Update window will close at Wed Feb 19 00:00:00 2014
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - The replication agreement named "cn=testreplica,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config" could not be correctly parsed. No replication will occur with this replica.
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - agmtlist_config_init: found 0 replication agreements in DIT
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object c33720 for database /var/lib/dirsrv/slapd-test389ds0/changelogdb/f7417e02-98d411e3-be33f1f9-9beb3121_5303b392000000010000.db4
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - repl_set_mtn_referrals: could not set referrals for replica dc=domain,dc=com: 32
[18/Feb/2014:20:45:21 +0100] - _csngen_adjust_local_time: gen state before 5303b7fa0000:1392752634:0:0
[18/Feb/2014:20:45:21 +0100] - _csngen_adjust_local_time: gen state after 5303b8510000:1392752721:0:0
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 5303b851000000010000 into pending list
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin - Purged state information from entry dc=example,dc=com up to CSN 52fa7dbd000000010000
[18/Feb/2014:20:45:21 +0100] NSMMReplicationPlugin -  csn=5303b851000000010000 process postop: canceling operation csn
[18/Feb/2014:20:45:21 +0100] - slapd started.  Listening on All Interfaces port 389 for LDAP requests
[18/Feb/2014:20:45:23 +0100] NSMMReplicationPlugin - _replica_update_state: failed to update state of csn generator for replica dc=domain,dc=com: LDAP error - 32