[21/Aug/2018:16:51:30.168834443 +0800] - INFO - main - 389-Directory/1.3.7.5 B2018.184.1411 starting up
[21/Aug/2018:16:51:35.006461563 +0800] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000
[21/Aug/2018:16:51:35.007885403 +0800] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000
[21/Aug/2018:16:51:35.015868971 +0800] - NOTICE - ldbm_back_start - found 2219580k physical memory
[21/Aug/2018:16:51:35.016700438 +0800] - NOTICE - ldbm_back_start - found 1257972k available
[21/Aug/2018:16:51:35.017973097 +0800] - NOTICE - ldbm_back_start - cache autosizing: db cache: 55489k
[21/Aug/2018:16:51:35.020954233 +0800] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 196608k
[21/Aug/2018:16:51:35.027256227 +0800] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k
[21/Aug/2018:16:51:35.029853072 +0800] - NOTICE - ldbm_back_start - total cache size: 263560806 B; 
[21/Aug/2018:16:51:35.149290354 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bcedf0001:1534840543:0:0
[21/Aug/2018:16:51:35.151445993 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2970000:1534841495:0:0
[21/Aug/2018:16:51:35.166347780 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 560.
[21/Aug/2018:16:51:35.168703556 +0800] - DEBUG - NSMMReplicationPlugin - _replica_configure_ruv - No ruv tombstone found for replica dc=local,dc=nonet. Created a new one
[21/Aug/2018:16:51:35.170060011 +0800] - DEBUG - NSMMReplicationPlugin - agmtlist_config_init - Found 0 replication agreements in DIT
[21/Aug/2018:16:51:35.181924292 +0800] - INFO - slapd_daemon - slapd started.  Listening on All Interfaces port 2389 for LDAP requests
[21/Aug/2018:16:51:35.182870220 +0800] - INFO - slapd_daemon - Listening on /var/run/slapd-cms1b.socket for LDAPI requests
[21/Aug/2018:16:51:37.265495629 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:51:44.118891609 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=3 Acquired consumer connection extension
[21/Aug/2018:16:51:44.120422973 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=3 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:51:44.124258493 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2970001:1534841495:0:0
[21/Aug/2018:16:51:44.126876203 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2970001:1534841495:0:0
[21/Aug/2018:16:51:44.128126165 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2a00000:1534841504:0:0
[21/Aug/2018:16:51:44.129185292 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2a00001:1534841504:0:0
[21/Aug/2018:16:51:44.131731889 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=3 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:51:44.133299342 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=3 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:51:44.135448898 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=3 Relinquishing consumer connection extension
[21/Aug/2018:16:51:44.144219898 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=4 Acquired consumer connection extension
[21/Aug/2018:16:51:44.149786217 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:51:44.153107632 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=4 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=3
[21/Aug/2018:16:51:44.155688250 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=4 Relinquishing consumer connection extension
[21/Aug/2018:16:51:47.178434956 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=5 Acquired consumer connection extension
[21/Aug/2018:16:51:47.179372780 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=5 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:51:47.180950706 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2a00001:1534841504:0:0
[21/Aug/2018:16:51:47.184021739 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2a00001:1534841504:0:0
[21/Aug/2018:16:51:47.187102492 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2a30000:1534841507:0:0
[21/Aug/2018:16:51:47.189234772 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2a30001:1534841507:0:0
[21/Aug/2018:16:51:47.190866261 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=5 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:51:47.193499989 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=5 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:51:47.196010710 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=5 Relinquishing consumer connection extension
[21/Aug/2018:16:51:47.218867933 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=6 Acquired consumer connection extension
[21/Aug/2018:16:51:47.220420107 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:51:47.222115415 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=6 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=5
[21/Aug/2018:16:51:47.224330699 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=6 Relinquishing consumer connection extension
[21/Aug/2018:16:51:50.241508821 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=7 Acquired consumer connection extension
[21/Aug/2018:16:51:50.244032889 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=7 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:51:50.245487326 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2a30001:1534841507:0:0
[21/Aug/2018:16:51:50.246727746 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2a30001:1534841507:0:0
[21/Aug/2018:16:51:50.248523074 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2a60000:1534841510:0:0
[21/Aug/2018:16:51:50.250775629 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2a60001:1534841510:0:0
[21/Aug/2018:16:51:50.252238776 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=7 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:51:50.254802690 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=7 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:51:50.256287746 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=7 Relinquishing consumer connection extension
[21/Aug/2018:16:51:50.264020407 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=8 Acquired consumer connection extension
[21/Aug/2018:16:51:50.266056493 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:51:50.267643773 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=8 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=7
[21/Aug/2018:16:51:50.269533917 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=8 Relinquishing consumer connection extension
[21/Aug/2018:16:51:53.282035348 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=10 Acquired consumer connection extension
[21/Aug/2018:16:51:53.282809974 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=10 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:51:53.284249038 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2a60001:1534841510:0:0
[21/Aug/2018:16:51:53.284783998 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2a60001:1534841510:0:0
[21/Aug/2018:16:51:53.285808565 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2a90000:1534841513:0:0
[21/Aug/2018:16:51:53.289849686 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2a90001:1534841513:0:0
[21/Aug/2018:16:51:53.290576521 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=10 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:51:53.292708341 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=10 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:51:53.295350440 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=10 Relinquishing consumer connection extension
[21/Aug/2018:16:51:53.304541294 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=11 Acquired consumer connection extension
[21/Aug/2018:16:51:53.306029565 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:51:53.307604840 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=11 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=10
[21/Aug/2018:16:51:53.312739243 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=11 Relinquishing consumer connection extension
[21/Aug/2018:16:51:56.327671618 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=12 Acquired consumer connection extension
[21/Aug/2018:16:51:56.328954690 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=12 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:51:56.331626348 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2a90001:1534841513:0:0
[21/Aug/2018:16:51:56.333542305 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2a90001:1534841513:0:0
[21/Aug/2018:16:51:56.335480765 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2ac0000:1534841516:0:0
[21/Aug/2018:16:51:56.336930822 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2ac0001:1534841516:0:0
[21/Aug/2018:16:51:56.338028861 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=12 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:51:56.340253108 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=12 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:51:56.342095424 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=12 Relinquishing consumer connection extension
[21/Aug/2018:16:51:56.347706283 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=13 Acquired consumer connection extension
[21/Aug/2018:16:51:56.349674625 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:51:56.351817228 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=13 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=12
[21/Aug/2018:16:51:56.353923685 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=13 Relinquishing consumer connection extension
[21/Aug/2018:16:51:59.366638259 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=15 Acquired consumer connection extension
[21/Aug/2018:16:51:59.367381638 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=15 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:51:59.368349894 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2ac0001:1534841516:0:0
[21/Aug/2018:16:51:59.369303252 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2ac0001:1534841516:0:0
[21/Aug/2018:16:51:59.370046814 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2af0000:1534841519:0:0
[21/Aug/2018:16:51:59.371649420 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2af0001:1534841519:0:0
[21/Aug/2018:16:51:59.373780424 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=15 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:51:59.375376526 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=15 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:51:59.377293043 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=15 Relinquishing consumer connection extension
[21/Aug/2018:16:51:59.387219510 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=16 Acquired consumer connection extension
[21/Aug/2018:16:51:59.390093342 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:51:59.393154019 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=16 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=15
[21/Aug/2018:16:51:59.394586741 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=16 Relinquishing consumer connection extension
[21/Aug/2018:16:52:02.406819977 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=17 Acquired consumer connection extension
[21/Aug/2018:16:52:02.408476940 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=17 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:02.409832722 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2af0001:1534841519:0:0
[21/Aug/2018:16:52:02.411727324 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2af0001:1534841519:0:0
[21/Aug/2018:16:52:02.413894749 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2b20000:1534841522:0:0
[21/Aug/2018:16:52:02.415249347 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2b20001:1534841522:0:0
[21/Aug/2018:16:52:02.417369201 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=17 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:02.419018000 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=17 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:02.420160255 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=17 Relinquishing consumer connection extension
[21/Aug/2018:16:52:02.428085216 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=18 Acquired consumer connection extension
[21/Aug/2018:16:52:02.429489512 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:02.431550715 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=18 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=17
[21/Aug/2018:16:52:02.434442498 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=18 Relinquishing consumer connection extension
[21/Aug/2018:16:52:05.459953311 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=20 Acquired consumer connection extension
[21/Aug/2018:16:52:05.468271218 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=20 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:05.470670528 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2b20001:1534841522:0:0
[21/Aug/2018:16:52:05.472724242 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2b20001:1534841522:0:0
[21/Aug/2018:16:52:05.475610111 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2b50000:1534841525:0:0
[21/Aug/2018:16:52:05.478465517 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2b50001:1534841525:0:0
[21/Aug/2018:16:52:05.479551085 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=20 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:05.481521189 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=20 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:05.482922185 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=20 Relinquishing consumer connection extension
[21/Aug/2018:16:52:05.502753129 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=21 Acquired consumer connection extension
[21/Aug/2018:16:52:05.506103675 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:05.507680005 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=21 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=20
[21/Aug/2018:16:52:05.508444408 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=21 Relinquishing consumer connection extension
[21/Aug/2018:16:52:07.270078045 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:08.526125005 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=22 Acquired consumer connection extension
[21/Aug/2018:16:52:08.527197061 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=22 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:08.532248840 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2b50001:1534841525:0:0
[21/Aug/2018:16:52:08.533625622 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2b50001:1534841525:0:0
[21/Aug/2018:16:52:08.535648082 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2b80000:1534841528:0:0
[21/Aug/2018:16:52:08.539115594 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2b80001:1534841528:0:0
[21/Aug/2018:16:52:08.540914763 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=22 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:08.542372119 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=22 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:08.544763599 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=22 Relinquishing consumer connection extension
[21/Aug/2018:16:52:08.566585515 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=23 Acquired consumer connection extension
[21/Aug/2018:16:52:08.569185250 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:08.571475885 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=23 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=22
[21/Aug/2018:16:52:08.572636119 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=23 Relinquishing consumer connection extension
[21/Aug/2018:16:52:11.590857364 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=25 Acquired consumer connection extension
[21/Aug/2018:16:52:11.595548668 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=25 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:11.602944608 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2b80001:1534841528:0:0
[21/Aug/2018:16:52:11.607239499 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2b80001:1534841528:0:0
[21/Aug/2018:16:52:11.611121173 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2bb0000:1534841531:0:0
[21/Aug/2018:16:52:11.615371576 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2bb0001:1534841531:0:0
[21/Aug/2018:16:52:11.622315577 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=25 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:11.627646705 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=25 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:11.631414983 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=25 Relinquishing consumer connection extension
[21/Aug/2018:16:52:11.666040631 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=26 Acquired consumer connection extension
[21/Aug/2018:16:52:11.670007494 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:11.673114689 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=26 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=25
[21/Aug/2018:16:52:11.675538862 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=26 Relinquishing consumer connection extension
[21/Aug/2018:16:52:14.735835847 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=27 Acquired consumer connection extension
[21/Aug/2018:16:52:14.737639091 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=27 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:14.739180375 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2bb0001:1534841531:0:0
[21/Aug/2018:16:52:14.740381064 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2bb0001:1534841531:0:0
[21/Aug/2018:16:52:14.742043961 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2be0000:1534841534:0:0
[21/Aug/2018:16:52:14.743091033 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2be0001:1534841534:0:0
[21/Aug/2018:16:52:14.745889539 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=27 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:14.748451643 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=27 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:14.750045736 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=27 Relinquishing consumer connection extension
[21/Aug/2018:16:52:14.755352967 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=28 Acquired consumer connection extension
[21/Aug/2018:16:52:14.756851687 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:14.760487693 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=28 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=27
[21/Aug/2018:16:52:14.763951885 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=28 Relinquishing consumer connection extension
[21/Aug/2018:16:52:17.803280811 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=30 Acquired consumer connection extension
[21/Aug/2018:16:52:17.805991539 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=30 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:17.807842393 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2be0001:1534841534:0:0
[21/Aug/2018:16:52:17.809156877 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2be0001:1534841534:0:0
[21/Aug/2018:16:52:17.810043122 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2c10000:1534841537:0:0
[21/Aug/2018:16:52:17.812444889 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2c10001:1534841537:0:0
[21/Aug/2018:16:52:17.813846502 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=30 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:17.816080413 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=30 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:17.817786550 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=30 Relinquishing consumer connection extension
[21/Aug/2018:16:52:17.821716261 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=31 Acquired consumer connection extension
[21/Aug/2018:16:52:17.823037833 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:17.825129987 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=31 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=30
[21/Aug/2018:16:52:17.826490300 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=31 Relinquishing consumer connection extension
[21/Aug/2018:16:52:20.854375484 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=32 Acquired consumer connection extension
[21/Aug/2018:16:52:20.858271780 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=32 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:20.862549737 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2c10001:1534841537:0:0
[21/Aug/2018:16:52:20.866056116 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2c10001:1534841537:0:0
[21/Aug/2018:16:52:20.870400099 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2c40000:1534841540:0:0
[21/Aug/2018:16:52:20.872382257 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2c40001:1534841540:0:0
[21/Aug/2018:16:52:20.874089669 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=32 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:20.876492334 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=32 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:20.878305855 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=32 Relinquishing consumer connection extension
[21/Aug/2018:16:52:20.898345458 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=33 Acquired consumer connection extension
[21/Aug/2018:16:52:20.900120677 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:20.901670610 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=33 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=32
[21/Aug/2018:16:52:20.903135842 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=33 Relinquishing consumer connection extension
[21/Aug/2018:16:52:23.926798453 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=35 Acquired consumer connection extension
[21/Aug/2018:16:52:23.929194295 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=35 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:23.931738510 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2c40001:1534841540:0:0
[21/Aug/2018:16:52:23.934065660 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2c40001:1534841540:0:0
[21/Aug/2018:16:52:23.936737600 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2c70000:1534841543:0:0
[21/Aug/2018:16:52:23.939545707 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2c70001:1534841543:0:0
[21/Aug/2018:16:52:23.942295909 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=35 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:23.947506200 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=35 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:23.953453065 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=35 Relinquishing consumer connection extension
[21/Aug/2018:16:52:23.970006140 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=36 Acquired consumer connection extension
[21/Aug/2018:16:52:23.971864463 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:23.974765788 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=36 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=35
[21/Aug/2018:16:52:23.978415514 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=36 Relinquishing consumer connection extension
[21/Aug/2018:16:52:27.002374913 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=37 Acquired consumer connection extension
[21/Aug/2018:16:52:27.008271445 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=37 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:27.010308628 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2c70001:1534841543:0:0
[21/Aug/2018:16:52:27.014626472 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2c70001:1534841543:0:0
[21/Aug/2018:16:52:27.017865681 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2cb0000:1534841547:0:0
[21/Aug/2018:16:52:27.020111916 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=37 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:27.022737251 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=37 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:27.023908203 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=37 Relinquishing consumer connection extension
[21/Aug/2018:16:52:27.048690597 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=38 Acquired consumer connection extension
[21/Aug/2018:16:52:27.050836993 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:27.053124489 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=38 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=37
[21/Aug/2018:16:52:27.054756770 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=38 Relinquishing consumer connection extension
[21/Aug/2018:16:52:30.074145138 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=40 Acquired consumer connection extension
[21/Aug/2018:16:52:30.075119258 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=40 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:30.076777008 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2cb0000:1534841547:0:0
[21/Aug/2018:16:52:30.078711474 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2cb0000:1534841547:0:0
[21/Aug/2018:16:52:30.081738543 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2ce0000:1534841550:0:0
[21/Aug/2018:16:52:30.082860307 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2ce0001:1534841550:0:0
[21/Aug/2018:16:52:30.084058308 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=40 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:30.084797590 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=40 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:30.086125002 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=40 Relinquishing consumer connection extension
[21/Aug/2018:16:52:30.094849210 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=41 Acquired consumer connection extension
[21/Aug/2018:16:52:30.096942380 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:30.098404066 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=41 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=40
[21/Aug/2018:16:52:30.099801771 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=41 Relinquishing consumer connection extension
[21/Aug/2018:16:52:33.112071298 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=42 Acquired consumer connection extension
[21/Aug/2018:16:52:33.113663523 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=42 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:33.114240190 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2ce0001:1534841550:0:0
[21/Aug/2018:16:52:33.115207247 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2ce0001:1534841550:0:0
[21/Aug/2018:16:52:33.116519528 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2d10000:1534841553:0:0
[21/Aug/2018:16:52:33.117599051 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2d10001:1534841553:0:0
[21/Aug/2018:16:52:33.118576375 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=42 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:33.119550999 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=42 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:33.120219153 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=42 Relinquishing consumer connection extension
[21/Aug/2018:16:52:33.133214018 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=43 Acquired consumer connection extension
[21/Aug/2018:16:52:33.136006493 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:33.139923807 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=43 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=42
[21/Aug/2018:16:52:33.141682145 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=43 Relinquishing consumer connection extension
[21/Aug/2018:16:52:36.157611107 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=45 Acquired consumer connection extension
[21/Aug/2018:16:52:36.159288360 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=45 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:36.165870734 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2d10001:1534841553:0:0
[21/Aug/2018:16:52:36.168233080 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2d10001:1534841553:0:0
[21/Aug/2018:16:52:36.170347896 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2d40000:1534841556:0:0
[21/Aug/2018:16:52:36.171986630 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2d40001:1534841556:0:0
[21/Aug/2018:16:52:36.173357575 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=45 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:36.174875157 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=45 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:36.176520126 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=45 Relinquishing consumer connection extension
[21/Aug/2018:16:52:36.195631388 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=46 Acquired consumer connection extension
[21/Aug/2018:16:52:36.197464180 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:36.199301233 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=46 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=45
[21/Aug/2018:16:52:36.201319600 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=46 Relinquishing consumer connection extension
[21/Aug/2018:16:52:37.278297658 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:39.219203761 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=47 Acquired consumer connection extension
[21/Aug/2018:16:52:39.221414921 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=47 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:39.222644330 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2d40001:1534841556:0:0
[21/Aug/2018:16:52:39.224179757 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2d40001:1534841556:0:0
[21/Aug/2018:16:52:39.225442396 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2d70000:1534841559:0:0
[21/Aug/2018:16:52:39.227634694 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2d70001:1534841559:0:0
[21/Aug/2018:16:52:39.229098878 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=47 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:39.230245178 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=47 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:39.230931759 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=47 Relinquishing consumer connection extension
[21/Aug/2018:16:52:39.240528248 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=48 Acquired consumer connection extension
[21/Aug/2018:16:52:39.244939372 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:39.247342392 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=48 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=47
[21/Aug/2018:16:52:39.249391490 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=48 Relinquishing consumer connection extension
[21/Aug/2018:16:52:42.272269873 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=50 Acquired consumer connection extension
[21/Aug/2018:16:52:42.274340816 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=50 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:42.276091499 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2d70001:1534841559:0:0
[21/Aug/2018:16:52:42.277424776 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2d70001:1534841559:0:0
[21/Aug/2018:16:52:42.278681637 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2da0000:1534841562:0:0
[21/Aug/2018:16:52:42.280021670 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2da0001:1534841562:0:0
[21/Aug/2018:16:52:42.283760329 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=50 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:42.288622616 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=50 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:42.291605642 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=50 Relinquishing consumer connection extension
[21/Aug/2018:16:52:42.314809795 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=51 Acquired consumer connection extension
[21/Aug/2018:16:52:42.317928015 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:42.320779909 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=51 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=50
[21/Aug/2018:16:52:42.322800600 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=51 Relinquishing consumer connection extension
[21/Aug/2018:16:52:45.347904606 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=52 Acquired consumer connection extension
[21/Aug/2018:16:52:45.349983149 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=52 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:45.352157257 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2da0001:1534841562:0:0
[21/Aug/2018:16:52:45.355211547 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2da0001:1534841562:0:0
[21/Aug/2018:16:52:45.357970493 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2dd0000:1534841565:0:0
[21/Aug/2018:16:52:45.360067882 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2dd0001:1534841565:0:0
[21/Aug/2018:16:52:45.362709386 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=52 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:45.366690750 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=52 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:45.370706672 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=52 Relinquishing consumer connection extension
[21/Aug/2018:16:52:45.389504069 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=53 Acquired consumer connection extension
[21/Aug/2018:16:52:45.392060798 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:45.393686131 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=53 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=52
[21/Aug/2018:16:52:45.394554523 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=53 Relinquishing consumer connection extension
[21/Aug/2018:16:52:48.415040782 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=55 Acquired consumer connection extension
[21/Aug/2018:16:52:48.416402928 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=55 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:48.417758437 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2dd0001:1534841565:0:0
[21/Aug/2018:16:52:48.418987236 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2dd0001:1534841565:0:0
[21/Aug/2018:16:52:48.420483675 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2e00000:1534841568:0:0
[21/Aug/2018:16:52:48.422486800 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2e00001:1534841568:0:0
[21/Aug/2018:16:52:48.423829528 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=55 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:48.425334698 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=55 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:48.426759757 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=55 Relinquishing consumer connection extension
[21/Aug/2018:16:52:48.436377310 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=56 Acquired consumer connection extension
[21/Aug/2018:16:52:48.438324039 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:48.440361107 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=56 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=55
[21/Aug/2018:16:52:48.441815080 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=56 Relinquishing consumer connection extension
[21/Aug/2018:16:52:51.462069469 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=57 Acquired consumer connection extension
[21/Aug/2018:16:52:51.463381204 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=57 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:51.465421480 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2e00001:1534841568:0:0
[21/Aug/2018:16:52:51.467453749 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2e00001:1534841568:0:0
[21/Aug/2018:16:52:51.469004677 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2e30000:1534841571:0:0
[21/Aug/2018:16:52:51.470944671 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2e30001:1534841571:0:0
[21/Aug/2018:16:52:51.472066133 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=57 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:51.473698352 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=57 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:51.474905776 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=57 Relinquishing consumer connection extension
[21/Aug/2018:16:52:51.482305206 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=58 Acquired consumer connection extension
[21/Aug/2018:16:52:51.484215522 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:51.487364263 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=58 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=57
[21/Aug/2018:16:52:51.489267524 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=58 Relinquishing consumer connection extension
[21/Aug/2018:16:52:54.508586062 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=60 Acquired consumer connection extension
[21/Aug/2018:16:52:54.513141186 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=60 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:54.515211217 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2e30001:1534841571:0:0
[21/Aug/2018:16:52:54.516675276 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2e30001:1534841571:0:0
[21/Aug/2018:16:52:54.518314345 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2e60000:1534841574:0:0
[21/Aug/2018:16:52:54.520813123 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2e60001:1534841574:0:0
[21/Aug/2018:16:52:54.522043080 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=60 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:54.525670009 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=60 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:54.527610545 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=60 Relinquishing consumer connection extension
[21/Aug/2018:16:52:54.548358536 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=61 Acquired consumer connection extension
[21/Aug/2018:16:52:54.550051962 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:54.552190045 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=61 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=60
[21/Aug/2018:16:52:54.555062872 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=61 Relinquishing consumer connection extension
[21/Aug/2018:16:52:57.569395035 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=62 Acquired consumer connection extension
[21/Aug/2018:16:52:57.570971216 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=62 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:52:57.571868290 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2e60001:1534841574:0:0
[21/Aug/2018:16:52:57.576551905 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2e60001:1534841574:0:0
[21/Aug/2018:16:52:57.578001617 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2e90000:1534841577:0:0
[21/Aug/2018:16:52:57.580657885 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2e90001:1534841577:0:0
[21/Aug/2018:16:52:57.582319494 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=62 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:52:57.585306425 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=62 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:52:57.587587795 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=62 Relinquishing consumer connection extension
[21/Aug/2018:16:52:57.594812987 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=63 Acquired consumer connection extension
[21/Aug/2018:16:52:57.596770036 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:52:57.598339113 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=63 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=62
[21/Aug/2018:16:52:57.604807531 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=63 Relinquishing consumer connection extension
[21/Aug/2018:16:53:00.636265655 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=65 Acquired consumer connection extension
[21/Aug/2018:16:53:00.638959308 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=65 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:00.640109821 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2e90001:1534841577:0:0
[21/Aug/2018:16:53:00.642146797 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2e90001:1534841577:0:0
[21/Aug/2018:16:53:00.644084726 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2ec0000:1534841580:0:0
[21/Aug/2018:16:53:00.646403008 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2ec0001:1534841580:0:0
[21/Aug/2018:16:53:00.648256150 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=65 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:00.651318191 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=65 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:00.652941340 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=65 Relinquishing consumer connection extension
[21/Aug/2018:16:53:00.675354588 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=66 Acquired consumer connection extension
[21/Aug/2018:16:53:00.678437190 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:00.682875619 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=66 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=65
[21/Aug/2018:16:53:00.684040873 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=66 Relinquishing consumer connection extension
[21/Aug/2018:16:53:03.707138631 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=67 Acquired consumer connection extension
[21/Aug/2018:16:53:03.710096814 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=67 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:03.711744035 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2ec0001:1534841580:0:0
[21/Aug/2018:16:53:03.713112556 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2ec0001:1534841580:0:0
[21/Aug/2018:16:53:03.714533938 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2ef0000:1534841583:0:0
[21/Aug/2018:16:53:03.716345414 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2ef0001:1534841583:0:0
[21/Aug/2018:16:53:03.718050466 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=67 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:03.719461578 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=67 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:03.720816284 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=67 Relinquishing consumer connection extension
[21/Aug/2018:16:53:03.729440249 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=68 Acquired consumer connection extension
[21/Aug/2018:16:53:03.732322802 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:03.733996735 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=68 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=67
[21/Aug/2018:16:53:03.735646611 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=68 Relinquishing consumer connection extension
[21/Aug/2018:16:53:06.755304830 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=70 Acquired consumer connection extension
[21/Aug/2018:16:53:06.756837012 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=70 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:06.758765184 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2ef0001:1534841583:0:0
[21/Aug/2018:16:53:06.760418892 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2ef0001:1534841583:0:0
[21/Aug/2018:16:53:06.761534730 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2f20000:1534841586:0:0
[21/Aug/2018:16:53:06.762128107 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2f20001:1534841586:0:0
[21/Aug/2018:16:53:06.763482923 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=70 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:06.765674971 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=70 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:06.767222340 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=70 Relinquishing consumer connection extension
[21/Aug/2018:16:53:06.776660898 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=71 Acquired consumer connection extension
[21/Aug/2018:16:53:06.778917447 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:06.782229890 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=71 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=70
[21/Aug/2018:16:53:06.782749728 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=71 Relinquishing consumer connection extension
[21/Aug/2018:16:53:07.282241685 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:09.802821342 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=72 Acquired consumer connection extension
[21/Aug/2018:16:53:09.804291165 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=72 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:09.805012314 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2f20001:1534841586:0:0
[21/Aug/2018:16:53:09.806315939 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2f20001:1534841586:0:0
[21/Aug/2018:16:53:09.807319986 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2f50000:1534841589:0:0
[21/Aug/2018:16:53:09.808417425 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2f50001:1534841589:0:0
[21/Aug/2018:16:53:09.813133198 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=72 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:09.814689266 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=72 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:09.816224492 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=72 Relinquishing consumer connection extension
[21/Aug/2018:16:53:09.825378565 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=73 Acquired consumer connection extension
[21/Aug/2018:16:53:09.828151399 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:09.830168616 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=73 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=72
[21/Aug/2018:16:53:09.831004732 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=73 Relinquishing consumer connection extension
[21/Aug/2018:16:53:12.848921879 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=75 Acquired consumer connection extension
[21/Aug/2018:16:53:12.850476304 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=75 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:12.851928855 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2f50001:1534841589:0:0
[21/Aug/2018:16:53:12.854121409 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2f50001:1534841589:0:0
[21/Aug/2018:16:53:12.855404423 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2f80000:1534841592:0:0
[21/Aug/2018:16:53:12.856809902 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2f80001:1534841592:0:0
[21/Aug/2018:16:53:12.858118694 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=75 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:12.860703140 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=75 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:12.863446075 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=75 Relinquishing consumer connection extension
[21/Aug/2018:16:53:12.868222014 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=76 Acquired consumer connection extension
[21/Aug/2018:16:53:12.869870287 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:12.871528477 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=76 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=75
[21/Aug/2018:16:53:12.873855672 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=76 Relinquishing consumer connection extension
[21/Aug/2018:16:53:15.887194815 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=77 Acquired consumer connection extension
[21/Aug/2018:16:53:15.888199252 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=77 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:15.893228443 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2f80001:1534841592:0:0
[21/Aug/2018:16:53:15.894651103 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2f80001:1534841592:0:0
[21/Aug/2018:16:53:15.895089937 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2fb0000:1534841595:0:0
[21/Aug/2018:16:53:15.895797811 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2fb0001:1534841595:0:0
[21/Aug/2018:16:53:15.896361391 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=77 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:15.897390304 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=77 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:15.898213889 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=77 Relinquishing consumer connection extension
[21/Aug/2018:16:53:15.910959428 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=78 Acquired consumer connection extension
[21/Aug/2018:16:53:15.911994956 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:15.913055041 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=78 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=77
[21/Aug/2018:16:53:15.914843166 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=78 Relinquishing consumer connection extension
[21/Aug/2018:16:53:18.938284011 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=80 Acquired consumer connection extension
[21/Aug/2018:16:53:18.939503985 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=80 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:18.940830619 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2fb0001:1534841595:0:0
[21/Aug/2018:16:53:18.942855725 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2fb0001:1534841595:0:0
[21/Aug/2018:16:53:18.944523205 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd2fe0000:1534841598:0:0
[21/Aug/2018:16:53:18.946654423 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd2fe0001:1534841598:0:0
[21/Aug/2018:16:53:18.948989306 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=80 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:18.950558876 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=80 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:18.952609620 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=80 Relinquishing consumer connection extension
[21/Aug/2018:16:53:18.958232057 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=81 Acquired consumer connection extension
[21/Aug/2018:16:53:18.959819844 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:18.961780004 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=81 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=80
[21/Aug/2018:16:53:18.969648273 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=81 Relinquishing consumer connection extension
[21/Aug/2018:16:53:21.991497930 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=82 Acquired consumer connection extension
[21/Aug/2018:16:53:21.993390062 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=82 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:21.994718867 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd2fe0001:1534841598:0:0
[21/Aug/2018:16:53:21.995986049 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd2fe0001:1534841598:0:0
[21/Aug/2018:16:53:21.997942545 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd3010000:1534841601:0:0
[21/Aug/2018:16:53:21.999147727 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd3010001:1534841601:0:0
[21/Aug/2018:16:53:22.001150923 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=82 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:22.003023855 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=82 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:22.004864420 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=82 Relinquishing consumer connection extension
[21/Aug/2018:16:53:22.013816683 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=83 Acquired consumer connection extension
[21/Aug/2018:16:53:22.016313942 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:22.018097134 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=83 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=82
[21/Aug/2018:16:53:22.020687140 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=83 Relinquishing consumer connection extension
[21/Aug/2018:16:53:25.038134319 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=85 Acquired consumer connection extension
[21/Aug/2018:16:53:25.040047099 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=85 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:25.041727136 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd3010001:1534841601:0:0
[21/Aug/2018:16:53:25.043303885 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd3010001:1534841601:0:0
[21/Aug/2018:16:53:25.044369856 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd3050000:1534841605:0:0
[21/Aug/2018:16:53:25.045838437 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd3050001:1534841605:0:0
[21/Aug/2018:16:53:25.047017401 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=85 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:25.048575629 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=85 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:25.050780858 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=85 Relinquishing consumer connection extension
[21/Aug/2018:16:53:25.058821845 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=86 Acquired consumer connection extension
[21/Aug/2018:16:53:25.060689907 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:25.064179892 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=86 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=85
[21/Aug/2018:16:53:25.065513862 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=86 Relinquishing consumer connection extension
[21/Aug/2018:16:53:28.077176298 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=87 Acquired consumer connection extension
[21/Aug/2018:16:53:28.077888358 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=87 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:28.078586590 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd3050001:1534841605:0:0
[21/Aug/2018:16:53:28.082901423 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd3050001:1534841605:0:0
[21/Aug/2018:16:53:28.084121150 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd3080000:1534841608:0:0
[21/Aug/2018:16:53:28.084997672 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd3080001:1534841608:0:0
[21/Aug/2018:16:53:28.085566888 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=87 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:28.086882672 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=87 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:28.088221172 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=87 Relinquishing consumer connection extension
[21/Aug/2018:16:53:28.099045239 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=88 Acquired consumer connection extension
[21/Aug/2018:16:53:28.100449200 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:28.103207399 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=88 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=87
[21/Aug/2018:16:53:28.105014222 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=88 Relinquishing consumer connection extension
[21/Aug/2018:16:53:31.125223868 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=90 Acquired consumer connection extension
[21/Aug/2018:16:53:31.127248838 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=90 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:31.129063530 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd3080001:1534841608:0:0
[21/Aug/2018:16:53:31.130526427 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd3080001:1534841608:0:0
[21/Aug/2018:16:53:31.132651919 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd30b0000:1534841611:0:0
[21/Aug/2018:16:53:31.133350149 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd30b0001:1534841611:0:0
[21/Aug/2018:16:53:31.133936313 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=90 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:31.134877000 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=90 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:31.135557967 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=90 Relinquishing consumer connection extension
[21/Aug/2018:16:53:31.144387439 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=91 Acquired consumer connection extension
[21/Aug/2018:16:53:31.147338985 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:31.150503656 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=91 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=90
[21/Aug/2018:16:53:31.155490367 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=91 Relinquishing consumer connection extension
[21/Aug/2018:16:53:34.175944844 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=92 Acquired consumer connection extension
[21/Aug/2018:16:53:34.180811484 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=92 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:34.183354685 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd30b0001:1534841611:0:0
[21/Aug/2018:16:53:34.184942253 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd30b0001:1534841611:0:0
[21/Aug/2018:16:53:34.186932681 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd30e0000:1534841614:0:0
[21/Aug/2018:16:53:34.188476955 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd30e0001:1534841614:0:0
[21/Aug/2018:16:53:34.189165983 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=92 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:34.190482255 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=92 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:34.191644769 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=92 Relinquishing consumer connection extension
[21/Aug/2018:16:53:34.204404325 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=93 Acquired consumer connection extension
[21/Aug/2018:16:53:34.206768752 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:34.208733687 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=93 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=92
[21/Aug/2018:16:53:34.211308313 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=93 Relinquishing consumer connection extension
[21/Aug/2018:16:53:37.231816757 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=95 Acquired consumer connection extension
[21/Aug/2018:16:53:37.233662903 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=95 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:37.234573555 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd30e0001:1534841614:0:0
[21/Aug/2018:16:53:37.238776724 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd30e0001:1534841614:0:0
[21/Aug/2018:16:53:37.240478288 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd3110000:1534841617:0:0
[21/Aug/2018:16:53:37.242614973 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd3110001:1534841617:0:0
[21/Aug/2018:16:53:37.244419433 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=95 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:37.246065554 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=95 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:37.247737913 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=95 Relinquishing consumer connection extension
[21/Aug/2018:16:53:37.268532626 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=96 Acquired consumer connection extension
[21/Aug/2018:16:53:37.270472428 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:37.274666375 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=96 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=95
[21/Aug/2018:16:53:37.277437534 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=96 Relinquishing consumer connection extension
[21/Aug/2018:16:53:37.289724682 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:40.308473446 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=97 Acquired consumer connection extension
[21/Aug/2018:16:53:40.310189745 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=97 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:40.312453520 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd3110001:1534841617:0:0
[21/Aug/2018:16:53:40.316943152 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd3110001:1534841617:0:0
[21/Aug/2018:16:53:40.319495092 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd3140000:1534841620:0:0
[21/Aug/2018:16:53:40.322516936 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd3140001:1534841620:0:0
[21/Aug/2018:16:53:40.323542397 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=97 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:40.324955416 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=97 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:40.326575474 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=97 Relinquishing consumer connection extension
[21/Aug/2018:16:53:40.334306573 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=98 Acquired consumer connection extension
[21/Aug/2018:16:53:40.335928754 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:40.337691424 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=98 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=97
[21/Aug/2018:16:53:40.340494141 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=98 Relinquishing consumer connection extension
[21/Aug/2018:16:53:43.362885588 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=100 Acquired consumer connection extension
[21/Aug/2018:16:53:43.366892568 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=100 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:43.370735792 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd3140001:1534841620:0:0
[21/Aug/2018:16:53:43.372488471 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd3140001:1534841620:0:0
[21/Aug/2018:16:53:43.374432367 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd3170000:1534841623:0:0
[21/Aug/2018:16:53:43.375839716 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd3170001:1534841623:0:0
[21/Aug/2018:16:53:43.377917229 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=100 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:43.383251868 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=100 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:43.386445029 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=100 Relinquishing consumer connection extension
[21/Aug/2018:16:53:43.399917958 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=101 Acquired consumer connection extension
[21/Aug/2018:16:53:43.403400382 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:43.404936670 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=101 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=100
[21/Aug/2018:16:53:43.406889762 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=101 Relinquishing consumer connection extension
[21/Aug/2018:16:53:46.440485933 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=102 Acquired consumer connection extension
[21/Aug/2018:16:53:46.444214114 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=102 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:46.447400707 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd3170001:1534841623:0:0
[21/Aug/2018:16:53:46.450880765 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd3170001:1534841623:0:0
[21/Aug/2018:16:53:46.452986574 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd31a0000:1534841626:0:0
[21/Aug/2018:16:53:46.454908701 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd31a0001:1534841626:0:0
[21/Aug/2018:16:53:46.457664572 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=102 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:46.459820343 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=102 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:46.464965856 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=102 Relinquishing consumer connection extension
[21/Aug/2018:16:53:46.480390890 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=103 Acquired consumer connection extension
[21/Aug/2018:16:53:46.482639664 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:46.485902069 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=103 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=102
[21/Aug/2018:16:53:46.488527998 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=103 Relinquishing consumer connection extension
[21/Aug/2018:16:53:49.506368810 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=105 Acquired consumer connection extension
[21/Aug/2018:16:53:49.507559854 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=105 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:49.508542532 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd31a0001:1534841626:0:0
[21/Aug/2018:16:53:49.509109515 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd31a0001:1534841626:0:0
[21/Aug/2018:16:53:49.509930806 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd31d0000:1534841629:0:0
[21/Aug/2018:16:53:49.510956484 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd31d0001:1534841629:0:0
[21/Aug/2018:16:53:49.512406412 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=105 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:49.514430348 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=105 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:49.515971707 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=105 Relinquishing consumer connection extension
[21/Aug/2018:16:53:49.526799640 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=106 Acquired consumer connection extension
[21/Aug/2018:16:53:49.529323154 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:49.530995770 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=106 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=105
[21/Aug/2018:16:53:49.535009426 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=106 Relinquishing consumer connection extension
[21/Aug/2018:16:53:52.559014731 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=107 Acquired consumer connection extension
[21/Aug/2018:16:53:52.560724295 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=107 repl="dc=local,dc=nonet": Begin incremental protocol
[21/Aug/2018:16:53:52.567217461 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd31d0001:1534841629:0:0
[21/Aug/2018:16:53:52.570056188 +0800] - DEBUG - _csngen_adjust_local_time - gen state before 5b7bd31d0001:1534841629:0:0
[21/Aug/2018:16:53:52.572902559 +0800] - DEBUG - _csngen_adjust_local_time - gen state after 5b7bd3200000:1534841632:0:0
[21/Aug/2018:16:53:52.575367890 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd3200001:1534841632:0:0
[21/Aug/2018:16:53:52.576872769 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=1 op=107 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:52.579479311 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=1 op=107 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:52.584931932 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=107 Relinquishing consumer connection extension
[21/Aug/2018:16:53:52.597519523 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=1 op=108 Acquired consumer connection extension
[21/Aug/2018:16:53:52.602794321 +0800] - DEBUG - replication - multimaster_mmr_postop - error 0 for oparation 561.
[21/Aug/2018:16:53:52.613935980 +0800] - DEBUG - NSMMReplicationPlugin - replica_relinquish_exclusive_access - conn=1 op=108 repl="dc=local,dc=nonet": Released replica held by locking_purl=conn=1 id=107
[21/Aug/2018:16:53:52.617088115 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=1 op=108 Relinquishing consumer connection extension
[21/Aug/2018:16:53:52.727939674 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_acquire_exclusive_access - conn=2 op=3 Acquired consumer connection extension
[21/Aug/2018:16:53:52.730611766 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=2 op=3 repl="dc=local,dc=nonet": Begin total protocol
[21/Aug/2018:16:53:52.735139664 +0800] - DEBUG - csngen_adjust_time - gen state before 5b7bd3200001:1534841632:0:0
[21/Aug/2018:16:53:52.736591510 +0800] - DEBUG - csngen_adjust_time - gen state after 5b7bd3200002:1534841632:0:0
[21/Aug/2018:16:53:52.738103306 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=2 op=3 repl="dc=local,dc=nonet": Acquired replica
[21/Aug/2018:16:53:52.746222737 +0800] - NOTICE - NSMMReplicationPlugin - multimaster_be_state_change - Replica dc=local,dc=nonet is going offline; disabling replication
[21/Aug/2018:16:53:52.747777126 +0800] - DEBUG - NSMMReplicationPlugin - replica_get_exclusive_access - conn=0 op=0 repl="dc=local,dc=nonet": Replica in use locking_purl=conn=2 id=3
[21/Aug/2018:16:53:52.749672605 +0800] - DEBUG - NSMMReplicationPlugin - replica_disable_replication - replica dc=local,dc=nonet is acquired
[21/Aug/2018:16:53:52.758410659 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:16:53:52.770963649 +0800] - DEBUG - NSMMReplicationPlugin - multimaster_extop_StartNSDS50ReplicationRequest - conn=2 op=3 repl="dc=local,dc=nonet": StartNSDS90ReplicationRequest: response=0 rc=0
[21/Aug/2018:16:53:52.772838107 +0800] - DEBUG - NSMMReplicationPlugin - consumer_connection_extension_relinquish_exclusive_access - conn=2 op=3 Relinquishing consumer connection extension
[21/Aug/2018:16:54:12.864578851 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:54:32.936263667 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:54:53.021102345 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:55:13.118577181 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:55:33.232590773 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:55:53.324684987 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:56:13.434938115 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:56:33.544304514 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:56:53.652070464 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:57:13.780310341 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:57:33.886747586 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:57:53.984749263 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:58:14.084943143 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:58:34.169507578 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:58:54.253722040 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries -- average rate 0.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:58:54.254938705 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:16:58:54.255914644 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 1 ...
[21/Aug/2018:16:58:54.457571192 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:16:58:54.458549242 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:16:58:54.660913749 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:16:58:54.662901176 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:16:58:54.667580031 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:16:58:54.670282000 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:16:58:54.671840297 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 2
[21/Aug/2018:16:59:14.750870599 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:59:34.856401877 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:16:59:54.949982210 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 71582788.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:00:15.059837842 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 53024287.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:00:35.169843735 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 42524428.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:00:55.282581817 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 35495597.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:01:15.373093254 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 30460761.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:01:35.467139200 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 26676815.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:01:55.548359940 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:02:15.651384360 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:02:35.741704823 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:02:55.877484375 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 17821441.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:03:15.983427934 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 16455813.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:03:36.089104724 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 15230380.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:03:56.172832338 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 2) -- average rate 14221746.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:03:56.174097963 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:03:56.175986940 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 2 ...
[21/Aug/2018:17:03:56.378748936 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:03:56.383605336 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:03:56.585972458 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:03:56.591916326 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:03:56.594199709 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:03:56.596272641 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:03:56.599161558 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 3
[21/Aug/2018:17:04:16.686500029 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:04:36.812399893 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:04:56.930505507 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 71582788.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:05:17.039875967 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 53024287.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:05:37.126731066 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 42524428.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:05:57.208560739 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 35495597.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:06:17.282431930 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 30460761.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:06:37.368399071 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 26676815.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:06:57.448043961 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:07:17.528454493 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:07:37.636502840 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:07:57.752417609 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 17821441.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:08:18.075823438 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 16393004.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:08:38.264424363 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 15230380.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:08:58.462016600 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 3) -- average rate 14221746.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:08:58.467508896 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:08:58.470577295 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 3 ...
[21/Aug/2018:17:08:58.673218882 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:08:58.676800793 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:08:58.879733667 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:08:58.881810402 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:08:58.887275891 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:08:58.889966781 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:08:58.892078792 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 4
[21/Aug/2018:17:09:19.029942409 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 204522252.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:09:39.143697939 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 104755299.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:09:59.234026992 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 70409299.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:10:19.354478812 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 53024287.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:10:39.459386459 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 42524428.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:10:59.545906420 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 35495597.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:11:19.681091120 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 30460761.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:11:39.788307824 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 26676815.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:11:59.897818165 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:12:20.009028611 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 21262214.3/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:12:40.125431830 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 19346699.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:13:00.242363842 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 17747798.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:13:20.349378012 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 16393004.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:13:40.455908420 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 15230380.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:14:00.683563806 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 4) -- average rate 14221746.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:14:00.688258232 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:14:00.689364701 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 4 ...
[21/Aug/2018:17:14:00.892358046 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:14:00.897700933 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:14:01.101360448 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:14:01.102663169 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:14:01.108310968 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:14:01.110407720 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:14:01.112174851 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 5
[21/Aug/2018:17:14:21.219712926 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:14:41.327962043 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:15:01.445737173 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 71582788.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:15:21.550851696 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 53687091.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:15:41.657179635 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 42949673.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:16:01.766769929 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 35791394.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:16:21.873848224 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 30678337.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:16:41.973286902 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 26843545.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:17:02.076215161 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:17:22.187906706 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:17:42.302395995 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:18:02.405054192 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 17821441.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:18:22.529409737 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 16455813.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:18:42.624668248 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 15284581.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:19:02.735335367 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 5) -- average rate 14268994.3/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:19:02.736601174 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:19:02.737796376 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 5 ...
[21/Aug/2018:17:19:02.843019323 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:19:02.847365555 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:19:03.049364887 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:19:03.056888638 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:19:03.064609468 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:19:03.068774270 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:19:03.072219232 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 6
[21/Aug/2018:17:19:23.155883511 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:19:43.265142965 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:20:03.425723163 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 71582788.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:20:23.515671175 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 53687091.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:20:43.627432613 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 42949673.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:21:03.732888656 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 35791394.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:21:23.845318975 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 30678337.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:21:43.953784882 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 26843545.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:22:04.058474147 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:22:24.167628240 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:22:44.275082914 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:23:04.385104343 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 17821441.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:23:24.517640812 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 16455813.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:23:44.645404087 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 15284581.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:24:04.756979906 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 6) -- average rate 14268994.3/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:24:04.758288810 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:24:04.762944477 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 6 ...
[21/Aug/2018:17:24:04.864468951 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:24:04.869419324 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:24:05.071785452 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:24:05.073037161 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:24:05.077489670 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:24:05.079216952 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:24:05.080715245 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 7
[21/Aug/2018:17:24:25.199506431 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:24:45.315148573 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:25:05.398740483 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 71582788.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:25:25.513559180 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 53687091.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:25:45.622924524 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 42949673.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:26:05.744264476 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 35791394.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:26:25.888377627 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 30678337.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:26:45.984398625 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 26843545.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:27:06.086459618 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:27:26.152400045 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:27:46.280562687 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:28:06.393698789 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 17821441.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:28:26.543011945 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 16455813.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:28:46.699197359 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 15284581.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:29:06.852391805 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 7) -- average rate 14268994.3/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:29:06.853837061 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:29:06.855232659 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 7 ...
[21/Aug/2018:17:29:07.060757453 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:29:07.065540275 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:29:07.268628812 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:29:07.273888522 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:29:07.276245095 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:29:07.278456502 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:29:07.281265595 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 8
[21/Aug/2018:17:29:27.383910519 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:29:47.491749510 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:30:07.581786361 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 71582788.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:30:27.703469386 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 53687091.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:30:47.816045244 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 42949673.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:31:07.941314266 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 35791394.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:31:28.067761108 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 30460761.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:31:48.200916460 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 26676815.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:32:08.311661323 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:32:28.405835560 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:32:48.514049404 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:33:08.621933070 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 17821441.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:33:28.738251791 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 16455813.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:33:48.837848551 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 15284581.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:34:08.933570645 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 8) -- average rate 14268994.3/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:34:08.938434618 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:34:08.940889625 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 8 ...
[21/Aug/2018:17:34:09.043207456 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:34:09.044390899 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:34:09.250216057 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:34:09.251797110 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:34:09.256531247 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:34:09.258603498 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:34:09.260931913 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 9
[21/Aug/2018:17:34:29.384320173 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:34:49.512338122 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:35:09.623758299 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 71582788.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:35:29.736964218 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 53687091.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:35:49.844715034 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 42949673.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:36:09.984376705 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 35791394.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:36:30.095197913 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 30460761.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:36:50.205193070 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 26676815.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:37:10.317642325 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:37:30.431549121 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:37:50.545337271 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:38:10.680288499 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 17821441.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:38:30.806007553 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 16455813.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:38:54.152575840 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 15070060.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:39:14.259220477 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 9) -- average rate 14081860.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:39:14.265403070 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:39:14.267338497 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 9 ...
[21/Aug/2018:17:39:14.470391021 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:39:14.472353182 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:39:14.675002253 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:39:14.676974744 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:39:14.678792638 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:39:14.681015137 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:39:14.686348917 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 10
[21/Aug/2018:17:39:34.791421034 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:39:54.904744731 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:40:15.008412898 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 70409299.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:40:35.119411454 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 53024287.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:40:55.229631758 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 42524428.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:41:15.339044590 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 35495597.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:41:35.488841564 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 30460761.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:41:55.585826335 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 26676815.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:42:15.672578082 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:42:35.809891491 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:42:55.930141977 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:43:16.025102589 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 17747798.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:43:36.106009695 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 16393004.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:43:56.254050148 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 15230380.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:44:16.371490224 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 10) -- average rate 14221746.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:44:16.376050620 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:44:16.378657760 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 10 ...
[21/Aug/2018:17:44:16.480540826 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:44:16.481441747 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:44:16.683586120 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:44:16.687660341 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:44:16.688783084 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:44:16.690619002 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:44:16.693542564 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 11
[21/Aug/2018:17:44:36.804127412 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:44:56.915429815 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:45:17.026078173 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 70409299.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:45:37.150682551 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 53024287.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:45:57.255734908 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 42524428.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:46:17.360999301 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 35495597.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:46:37.474327648 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 30460761.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:46:57.564135652 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 26676815.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:47:17.664051718 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:47:37.761859631 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:47:57.871091002 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 19434241.2/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:48:17.949900638 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 17821441.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:48:38.085061513 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 16393004.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:48:58.254910826 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 15230380.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:49:18.382617580 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 11) -- average rate 14221746.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:49:18.387291446 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:49:18.389073435 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 11 ...
[21/Aug/2018:17:49:18.491585499 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:49:18.492909992 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:49:18.699269830 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:49:18.700636381 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:49:18.702268056 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:49:18.704398745 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:49:18.705662247 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 12
[21/Aug/2018:17:49:38.816576612 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 214748364.8/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:49:58.926154578 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 107374182.4/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:50:19.063240459 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 70409299.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:50:39.168728291 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 53024287.6/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:50:59.299952951 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 42524428.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:51:19.441280107 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 35495597.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:51:39.580968464 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 30460761.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:51:59.715836196 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 26676815.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:52:19.798565339 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 23729101.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:52:39.901454193 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 21367996.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:53:00.011039897 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 19346699.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:53:20.139381766 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 17747798.7/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:53:40.260610579 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 16393004.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:54:00.389220288 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 15230380.5/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:54:20.484115642 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 12) -- average rate 14221746.0/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:54:20.486814420 +0800] - INFO - import_throw_in_towel - import userRoot: Decided to end this pass because the progress rate has dropped below the 50% threshold.
[21/Aug/2018:17:54:20.489513366 +0800] - INFO - import_monitor_threads - import userRoot: Ending pass number 12 ...
[21/Aug/2018:17:54:20.593102620 +0800] - INFO - import_monitor_threads - import userRoot: Foreman is done; waiting for workers to finish...
[21/Aug/2018:17:54:20.594475500 +0800] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up...
[21/Aug/2018:17:54:20.800080152 +0800] - INFO - import_monitor_threads - import userRoot: Workers cleaned up.
[21/Aug/2018:17:54:20.805332770 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweeping files for merging later...
[21/Aug/2018:17:54:20.807777939 +0800] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database
[21/Aug/2018:17:54:20.810862410 +0800] - INFO - import_sweep_after_pass - import userRoot: Sweep done.
[21/Aug/2018:17:54:20.813162933 +0800] - INFO - import_main_offline - import userRoot: Beginning pass number 13
[21/Aug/2018:17:54:41.036940337 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 13) -- average rate 204522252.1/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:55:01.147687785 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 13) -- average rate 104755299.9/sec, recent rate 0.0/sec, hit ratio 0%
[21/Aug/2018:17:55:21.245845331 +0800] - INFO - import_monitor_threads - import userRoot: Processed 1 entries (pass 13) -- average rate 70409299.9/sec, recent rate 0.0/sec, hit ratio 0%
