[27/Jun/2013:13:23:56 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Linger timeout has expired on the connection [27/Jun/2013:13:23:56 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Disconnected from the consumer [27/Jun/2013:13:23:56 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): Linger timeout has expired on the connection [27/Jun/2013:13:23:56 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): Disconnected from the consumer [27/Jun/2013:13:25:27 +0200] - new SSL connection on 64 [27/Jun/2013:13:25:27 +0200] - activity on 64r [27/Jun/2013:13:25:27 +0200] - read activity on 64 [27/Jun/2013:13:25:27 +0200] - PR_Recv for connection 51881 returns -5938 (Encountered end of file.) [27/Jun/2013:13:25:27 +0200] - conn 51881 activity level = 0 [27/Jun/2013:13:25:27 +0200] - listener got signaled [27/Jun/2013:13:25:27 +0200] - new connection on 64 [27/Jun/2013:13:25:27 +0200] - activity on 64r [27/Jun/2013:13:25:27 +0200] - read activity on 64 [27/Jun/2013:13:25:27 +0200] - conn 51882 activity level = 0 [27/Jun/2013:13:25:27 +0200] - activity on 64r [27/Jun/2013:13:25:27 +0200] - listener got signaled [27/Jun/2013:13:25:27 +0200] - new connection on 64 [27/Jun/2013:13:25:27 +0200] - activity on 64r [27/Jun/2013:13:25:27 +0200] - read activity on 64 [27/Jun/2013:13:25:27 +0200] - conn 51883 activity level = 0 [27/Jun/2013:13:25:27 +0200] - listener got signaled [27/Jun/2013:13:25:27 +0200] - new connection on 65 [27/Jun/2013:13:25:27 +0200] - activity on 64r [27/Jun/2013:13:25:27 +0200] - read activity on 64 [27/Jun/2013:13:25:27 +0200] - activity on 65r [27/Jun/2013:13:25:27 +0200] - read activity on 65 [27/Jun/2013:13:25:27 +0200] - conn 51884 activity level = 0 [27/Jun/2013:13:25:27 +0200] - activity on 64r [27/Jun/2013:13:25:27 +0200] - read activity on 64 [27/Jun/2013:13:25:27 +0200] - listener got signaled [27/Jun/2013:13:25:27 +0200] - activity on 65r [27/Jun/2013:13:25:27 +0200] - read activity on 65 [27/Jun/2013:13:25:27 +0200] - listener got signaled [27/Jun/2013:13:25:27 +0200] - activity on 65r [27/Jun/2013:13:25:27 +0200] - read activity on 65 [27/Jun/2013:13:25:27 +0200] - listener got signaled [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: wait_for_changes -> wait_for_changes [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: wait_for_changes -> start [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): No linger to cancel on the connection [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Disconnected from the consumer [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: start -> ready_to_acquire_replica [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: ready_to_acquire_replica -> wait_for_changes [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): State: wait_for_changes -> wait_for_changes [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): State: wait_for_changes -> start [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): No linger to cancel on the connection [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): Disconnected from the consumer [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): State: start -> ready_to_acquire_replica [27/Jun/2013:13:27:55 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): State: ready_to_acquire_replica -> wait_for_changes [27/Jun/2013:13:30:27 +0200] - new SSL connection on 64 [27/Jun/2013:13:30:27 +0200] - activity on 64r [27/Jun/2013:13:30:27 +0200] - read activity on 64 [27/Jun/2013:13:30:27 +0200] - PR_Recv for connection 51885 returns -5938 (Encountered end of file.) [27/Jun/2013:13:30:27 +0200] - new connection on 65 [27/Jun/2013:13:30:27 +0200] - conn 51885 activity level = 0 [27/Jun/2013:13:30:27 +0200] - activity on 65r [27/Jun/2013:13:30:27 +0200] - read activity on 65 [27/Jun/2013:13:30:27 +0200] - conn 51886 activity level = 0 [27/Jun/2013:13:30:27 +0200] - activity on 65r [27/Jun/2013:13:30:27 +0200] - listener got signaled [27/Jun/2013:13:30:27 +0200] - new connection on 64 [27/Jun/2013:13:30:27 +0200] - activity on 64r [27/Jun/2013:13:30:27 +0200] - read activity on 64 [27/Jun/2013:13:30:27 +0200] - conn 51887 activity level = 0 [27/Jun/2013:13:30:27 +0200] - listener got signaled [27/Jun/2013:13:30:27 +0200] - activity on 64r [27/Jun/2013:13:30:27 +0200] - read activity on 64 [27/Jun/2013:13:30:27 +0200] - listener got signaled [27/Jun/2013:13:30:27 +0200] - activity on 64r [27/Jun/2013:13:30:27 +0200] - read activity on 64 [27/Jun/2013:13:30:27 +0200] - new connection on 64 [27/Jun/2013:13:30:27 +0200] - activity on 64r [27/Jun/2013:13:30:27 +0200] - read activity on 64 [27/Jun/2013:13:30:27 +0200] - conn 51888 activity level = 0 [27/Jun/2013:13:30:27 +0200] - listener got signaled [27/Jun/2013:13:30:27 +0200] - activity on 64r [27/Jun/2013:13:30:27 +0200] - read activity on 64 [27/Jun/2013:13:30:27 +0200] - listener got signaled [27/Jun/2013:13:30:27 +0200] - activity on 64r [27/Jun/2013:13:30:27 +0200] - read activity on 64 [27/Jun/2013:13:30:27 +0200] - listener got signaled [27/Jun/2013:13:31:00 +0200] - new SSL connection on 64 [27/Jun/2013:13:31:00 +0200] - activity on 64r [27/Jun/2013:13:31:00 +0200] - read activity on 64 [27/Jun/2013:13:31:00 +0200] - conn 51889 activity level = 0 [27/Jun/2013:13:31:00 +0200] - listener got signaled [27/Jun/2013:13:31:00 +0200] - activity on 64r [27/Jun/2013:13:31:00 +0200] - read activity on 64 [27/Jun/2013:13:31:00 +0200] - listener got signaled [27/Jun/2013:13:31:00 +0200] - activity on 64r [27/Jun/2013:13:31:00 +0200] - read activity on 64 [27/Jun/2013:13:31:00 +0200] - new SSL connection on 65 [27/Jun/2013:13:31:00 +0200] - activity on 65r [27/Jun/2013:13:31:00 +0200] - read activity on 65 [27/Jun/2013:13:31:00 +0200] - conn 51890 activity level = 0 [27/Jun/2013:13:31:00 +0200] - listener got signaled [27/Jun/2013:13:31:00 +0200] - activity on 65r [27/Jun/2013:13:31:00 +0200] - read activity on 65 [27/Jun/2013:13:31:00 +0200] - _csngen_adjust_local_time: gen state before 51cc20920002:1372332175:0:3 [27/Jun/2013:13:31:00 +0200] - listener got signaled [27/Jun/2013:13:31:00 +0200] - _csngen_adjust_local_time: gen state after 51cc22770000:1372332660:0:3 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 51cc22770000000b0000 into pending list [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - Purged state information from entry eduPersonPrincipalName=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si up to CSN 51c2e60f0001000b0000 [27/Jun/2013:13:31:00 +0200] - activity on 65r [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 51cc22770001000b0000 into pending list [27/Jun/2013:13:31:00 +0200] - read activity on 65 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - Purged state information from entry eduPersonPrincipalName=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si up to CSN 51c2e60f0001000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 97c47d8 for database e8adc488-1dd111b2-aa0fda0a-92130000_4c5167560000000b0000.db4 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 97c47d8 for database e8adc488-1dd111b2-aa0fda0a-92130000_4c5167560000000b0000.db4 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - ruv_update_ruv: successfully committed csn 51cc22770001000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 97c47d8 for database e8adc488-1dd111b2-aa0fda0a-92130000_4c5167560000000b0000.db4 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: wait_for_changes -> wait_for_changes [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: wait_for_changes -> ready_to_acquire_replica [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - changelog program - _cl5GetDBFileByReplicaName: found DB object 97c47d8 for database e8adc488-1dd111b2-aa0fda0a-92130000_4c5167560000000b0000.db4 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - ruv_update_ruv: successfully committed csn 51cc22770000000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: ready_to_acquire_replica -> wait_for_changes [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: wait_for_changes -> ready_to_acquire_replica [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Trying secure startTLS slapi_ldap_init_ext [27/Jun/2013:13:31:00 +0200] slapi_ldap_init_ext - configpluginpath == NULL [27/Jun/2013:13:31:00 +0200] slapi_ldap_init_ext - Success: set up conn to [OLD_CONSUMER.COMPANY.si:389] using startTLS [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): binddn = cn=replication manager,cn=config, passwd = {DES}aReDTgqOtkg3XQk54q1n3Q== [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): State: wait_for_changes -> wait_for_changes [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): State: wait_for_changes -> ready_to_acquire_replica [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): Trying secure startTLS slapi_ldap_init_ext [27/Jun/2013:13:31:00 +0200] slapi_ldap_init_ext - configpluginpath == NULL [27/Jun/2013:13:31:00 +0200] slapi_ldap_init_ext - Success: set up conn to [CONSUMER_TWO.COMPANY.si:389] using startTLS [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): binddn = cn=replication manager,cn=config, passwd = {DES}aReDTgqOtkg3XQk54q1n3Q== [27/Jun/2013:13:31:00 +0200] slapi_ldap_bind - startTLS started on connection [27/Jun/2013:13:31:00 +0200] slapi_ldap_bind - attempting SIMPLE bind with id [cn=replication manager,cn=config] creds [r3pl1k4t0r] [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): No linger to cancel on the connection [27/Jun/2013:13:31:00 +0200] slapi_ldap_bind - startTLS started on connection [27/Jun/2013:13:31:00 +0200] slapi_ldap_bind - attempting SIMPLE bind with id [cn=replication manager,cn=config] creds [r3pl1k4t0r] [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Replica was successfully acquired. [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: ready_to_acquire_replica -> sending_updates [27/Jun/2013:13:31:00 +0200] - csngen_adjust_time: gen state before 51cc22770003:1372332660:0:3 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 97c47d8 for database e8adc488-1dd111b2-aa0fda0a-92130000_4c5167560000000b0000.db4 [27/Jun/2013:13:31:00 +0200] - _cl5PositionCursorForReplay (agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389)): Consumer RUV: [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): {replicageneration} 4c5167560000000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): {replica 11 ldap://PROVIDER.COMPANY.si:389} 4c5170850000000b0000 51cc208f0001000b0000 00000000 [27/Jun/2013:13:31:00 +0200] - _cl5PositionCursorForReplay (agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389)): Supplier RUV: [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): {replicageneration} 4c5167560000000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): {replica 11 ldap://PROVIDER.COMPANY.si:389} 4c5170850000000b0000 51cc22770001000b0000 51cc2274 [27/Jun/2013:13:31:00 +0200] agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389) - clcache_get_buffer: found thread private buffer cache 9841320 [27/Jun/2013:13:31:00 +0200] agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389) - clcache_get_buffer: _pool is 97d9c20 _pool->pl_busy_lists is 9847928 _pool->pl_busy_lists->bl_buffers is a771370 [27/Jun/2013:13:31:00 +0200] agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389) - session start: anchorcsn=51cc208f0001000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - changelog program - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): CSN 51cc208f0001000b0000 found, position set for replay [27/Jun/2013:13:31:00 +0200] agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389) - load=1 rec=1 csn=51cc22770000000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): replay_update: Sending modify operation (dn="edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si" csn=51cc22770000000b0000) [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): replay_update: Consumer successfully sent operation with csn 51cc22770000000b0000 [27/Jun/2013:13:31:00 +0200] agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389) - load=1 rec=2 csn=51cc22770001000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): replay_update: Sending modify operation (dn="edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si" csn=51cc22770001000b0000) [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): replay_update: Consumer successfully sent operation with csn 51cc22770001000b0000 [27/Jun/2013:13:31:00 +0200] agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389) - clcache_load_buffer: rc=-30989 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): No more updates to send (cl5GetNextOperationToReplay) [27/Jun/2013:13:31:00 +0200] - repl5_inc_waitfor_async_results: 0 7 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): No linger to cancel on the connection [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain starting [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 0 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 0 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 6 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: result 3, 0, 0, 6, (null) [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 6 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 6 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 6 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 6 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: result 3, 0, 0, 7, (null) [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): Replica was successfully acquired. [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): State: ready_to_acquire_replica -> sending_updates [27/Jun/2013:13:31:00 +0200] - csngen_adjust_time: gen state before 51cc22770004:1372332660:0:3 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - changelog program - _cl5GetDBFile: found DB object 97c47d8 for database e8adc488-1dd111b2-aa0fda0a-92130000_4c5167560000000b0000.db4 [27/Jun/2013:13:31:00 +0200] - _cl5PositionCursorForReplay (agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389)): Consumer RUV: [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): {replicageneration} 4c5167560000000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): {replica 11 ldap://PROVIDER.COMPANY.si:389} 4c5170850000000b0000 51cc208f0001000b0000 00000000 [27/Jun/2013:13:31:00 +0200] - _cl5PositionCursorForReplay (agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389)): Supplier RUV: [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): {replicageneration} 4c5167560000000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): {replica 11 ldap://PROVIDER.COMPANY.si:389} 4c5170850000000b0000 51cc22770001000b0000 51cc2274 [27/Jun/2013:13:31:00 +0200] agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389) - clcache_get_buffer: found thread private buffer cache a771370 [27/Jun/2013:13:31:00 +0200] agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389) - clcache_get_buffer: _pool is 97d9c20 _pool->pl_busy_lists is 9847928 _pool->pl_busy_lists->bl_buffers is a771370 [27/Jun/2013:13:31:00 +0200] agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389) - session start: anchorcsn=51cc208f0001000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - changelog program - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): CSN 51cc208f0001000b0000 found, position set for replay [27/Jun/2013:13:31:00 +0200] agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389) - load=1 rec=1 csn=51cc22770000000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): replay_update: Sending modify operation (dn="edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si" csn=51cc22770000000b0000) [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): replay_update: Consumer successfully sent operation with csn 51cc22770000000b0000 [27/Jun/2013:13:31:00 +0200] agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389) - load=1 rec=2 csn=51cc22770001000b0000 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): replay_update: Sending modify operation (dn="edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si" csn=51cc22770001000b0000) [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): replay_update: Consumer successfully sent operation with csn 51cc22770001000b0000 [27/Jun/2013:13:31:00 +0200] agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389) - clcache_load_buffer: rc=-30989 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): No more updates to send (cl5GetNextOperationToReplay) [27/Jun/2013:13:31:00 +0200] - repl5_inc_waitfor_async_results: 0 7 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain starting [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 0 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 0 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 0 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): Disconnected from the consumer [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 0 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: result 3, 81, 2, 0, (null) [27/Jun/2013:13:31:00 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): Consumer failed to replay change (uniqueid de7a8356-1dd111b2-9c59da0a-92130000, CSN 51cc22770000000b0000): Can't contact LDAP server. Will retry later. [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: got op result 205 should finish 1 [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain exiting [27/Jun/2013:13:31:00 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:01 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:01 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:01 +0200] - repl5_inc_waitfor_async_results: 7 7 [27/Jun/2013:13:31:01 +0200] - repl5_inc_waitfor_async_results: 0 7 [27/Jun/2013:13:31:01 +0200] - repl5_inc_result_threadmain: read result for message_id 7 [27/Jun/2013:13:31:02 +0200] agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389) - session end: state=5 load=1 sent=2 skipped=0 [27/Jun/2013:13:31:02 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): Warning: unable to send endReplication extended operation (Can't contact LDAP server) [27/Jun/2013:13:31:02 +0200] NSMMReplicationPlugin - agmt="cn=CONSUMER_TWO (P-C)" (CONSUMER_TWO:389): State: sending_updates -> wait_for_changes [27/Jun/2013:13:31:02 +0200] - repl5_inc_result_threadmain exiting [27/Jun/2013:13:31:02 +0200] agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389) - session end: state=5 load=1 sent=2 skipped=0 [27/Jun/2013:13:31:03 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Successfully released consumer [27/Jun/2013:13:31:03 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Beginning linger on the connection [27/Jun/2013:13:31:03 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): State: sending_updates -> wait_for_changes [27/Jun/2013:13:32:03 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Linger timeout has expired on the connection [27/Jun/2013:13:32:03 +0200] NSMMReplicationPlugin - agmt="cn=OLD_CONSUMER (P-C)" (OLD_CONSUMER:389): Disconnected from the consumer [27/Jun/2013:13:34:57 +0200] - new connection on 64 [27/Jun/2013:13:34:57 +0200] - activity on 64r [27/Jun/2013:13:34:57 +0200] - read activity on 64 [27/Jun/2013:13:34:57 +0200] - conn 51891 activity level = 0 [27/Jun/2013:13:34:57 +0200] - listener got signaled [27/Jun/2013:13:34:57 +0200] - activity on 64r [27/Jun/2013:13:34:57 +0200] - read activity on 64 [27/Jun/2013:13:34:57 +0200] - listener got signaled [27/Jun/2013:13:34:57 +0200] - activity on 64r [27/Jun/2013:13:34:57 +0200] - read activity on 64 [27/Jun/2013:13:34:57 +0200] - listener got signaled