389-Directory/1.3.10.1 B2020.133.1625 freeipa6.MY.EXAMPLE.COM:389 (/etc/dirsrv/slapd-MY.EXAMPLE.COM) [16/Jun/2020:01:25:15.907578898 +0200] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [16/Jun/2020:01:25:15.953552353 +0200] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database [16/Jun/2020:01:25:15.971177042 +0200] - INFO - check_and_set_import_cache - pagesize: 4096, available bytes 3411873792, process usage 17973248 [16/Jun/2020:01:25:15.988572147 +0200] - INFO - check_and_set_import_cache - Import allocates 1332763KB import cache. [16/Jun/2020:01:25:16.127793536 +0200] - INFO - import_main_offline - import userRoot: Beginning import job... [16/Jun/2020:01:25:16.153302713 +0200] - INFO - import_main_offline - import userRoot: Index buffering enabled with bucket size 100 [16/Jun/2020:01:25:16.357347218 +0200] - INFO - import_producer - import userRoot: Processing file "/var/lib/dirsrv/boot.ldif" [16/Jun/2020:01:25:16.377552670 +0200] - INFO - import_producer - import userRoot: Finished scanning file "/var/lib/dirsrv/boot.ldif" (1 entries) [16/Jun/2020:01:25:16.956454225 +0200] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up... [16/Jun/2020:01:25:17.100441288 +0200] - INFO - import_monitor_threads - import userRoot: Workers cleaned up. [16/Jun/2020:01:25:17.118807743 +0200] - INFO - import_main_offline - import userRoot: Cleaning up producer thread... [16/Jun/2020:01:25:17.136637162 +0200] - INFO - import_main_offline - import userRoot: Indexing complete. Post-processing... [16/Jun/2020:01:25:17.160586614 +0200] - INFO - import_main_offline - import userRoot: Generating numsubordinates (this may take several minutes to complete)... [16/Jun/2020:01:25:17.204405327 +0200] - INFO - import_main_offline - import userRoot: Generating numSubordinates complete. [16/Jun/2020:01:25:17.227043527 +0200] - INFO - ldbm_get_nonleaf_ids - import userRoot: Gathering ancestorid non-leaf IDs... [16/Jun/2020:01:25:17.244227244 +0200] - INFO - ldbm_get_nonleaf_ids - import userRoot: Finished gathering ancestorid non-leaf IDs. [16/Jun/2020:01:25:17.286367896 +0200] - ERR - ldbm_ancestorid_new_idl_create_index - Nothing to do to build ancestorid index [16/Jun/2020:01:25:17.306694546 +0200] - INFO - ldbm_ancestorid_new_idl_create_index - import userRoot: Created ancestorid index (new idl). [16/Jun/2020:01:25:17.329616888 +0200] - INFO - import_main_offline - import userRoot: Flushing caches... [16/Jun/2020:01:25:17.352621988 +0200] - INFO - import_main_offline - import userRoot: Closing files... [16/Jun/2020:01:25:17.513885413 +0200] - INFO - dblayer_pre_close - All database threads now stopped [16/Jun/2020:01:25:17.537709625 +0200] - INFO - import_main_offline - import userRoot: Import complete. Processed 1 entries in 1 seconds. (1.00 entries/sec) [16/Jun/2020:01:25:18.713171777 +0200] - INFO - main - 389-Directory/1.3.10.1 B2020.133.1625 starting up [16/Jun/2020:01:25:18.751719394 +0200] - INFO - main - Setting the maximum file descriptor limit to: 16384 [16/Jun/2020:01:25:25.362486550 +0200] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [16/Jun/2020:01:25:25.403361524 +0200] - NOTICE - ldbm_back_start - found 3879800k physical memory [16/Jun/2020:01:25:25.446070213 +0200] - NOTICE - ldbm_back_start - found 3326052k available [16/Jun/2020:01:25:25.469645660 +0200] - NOTICE - ldbm_back_start - cache autosizing: db cache: 96995k [16/Jun/2020:01:25:25.488846304 +0200] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 262144k [16/Jun/2020:01:25:25.512691962 +0200] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [16/Jun/2020:01:25:25.543878705 +0200] - NOTICE - ldbm_back_start - total cache size: 364670976 B; [16/Jun/2020:01:25:25.569067523 +0200] - INFO - dblayer_start - Resizing db cache size: 1364749516 -> 79458304 [16/Jun/2020:01:25:26.306729992 +0200] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [16/Jun/2020:01:25:26.441690114 +0200] - INFO - postop_modify_config_dse - The change of nsslapd-ldapilisten will not take effect until the server is restarted [16/Jun/2020:01:25:26.811844935 +0200] - INFO - op_thread_cleanup - slapd shutting down - signaling operation threads - op stack size 1 max work q size 1 max work q stack size 1 [16/Jun/2020:01:25:26.839496755 +0200] - INFO - slapd_daemon - slapd shutting down - waiting for 19 threads to terminate [16/Jun/2020:01:25:26.862505113 +0200] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [16/Jun/2020:01:25:26.889201460 +0200] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [16/Jun/2020:01:25:28.597042739 +0200] - INFO - dblayer_pre_close - All database threads now stopped [16/Jun/2020:01:25:28.650876234 +0200] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [16/Jun/2020:01:25:28.668893464 +0200] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 1 work q stack objects - freed 1 op stack objects [16/Jun/2020:01:25:28.686390056 +0200] - INFO - main - slapd stopped. [16/Jun/2020:01:25:29.324757247 +0200] - INFO - main - 389-Directory/1.3.10.1 B2020.133.1625 starting up [16/Jun/2020:01:25:29.356505320 +0200] - INFO - main - Setting the maximum file descriptor limit to: 16384 [16/Jun/2020:01:25:35.795117058 +0200] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [16/Jun/2020:01:25:35.837324068 +0200] - NOTICE - ldbm_back_start - found 3879800k physical memory [16/Jun/2020:01:25:35.862722348 +0200] - NOTICE - ldbm_back_start - found 3332128k available [16/Jun/2020:01:25:35.927740771 +0200] - NOTICE - ldbm_back_start - cache autosizing: db cache: 96995k [16/Jun/2020:01:25:35.964351429 +0200] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 262144k [16/Jun/2020:01:25:35.990931658 +0200] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [16/Jun/2020:01:25:36.013943546 +0200] - NOTICE - ldbm_back_start - total cache size: 415002624 B; [16/Jun/2020:01:25:36.037969327 +0200] - NOTICE - dblayer_start - Resizing max db lock count: 10000 -> 50000 [16/Jun/2020:01:25:36.681598425 +0200] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [16/Jun/2020:01:25:36.729816930 +0200] - INFO - slapd_daemon - Listening on /var/run/slapd-MY.EXAMPLE.COM.socket for LDAPI requests [16/Jun/2020:01:25:36.792568273 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.001122601 nentries=0 notes=U [16/Jun/2020:01:25:36.900942839 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000699931 nentries=0 notes=U [16/Jun/2020:01:25:36.973804718 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000650812 nentries=0 notes=U [16/Jun/2020:01:25:37.059228633 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000634108 nentries=0 notes=U [16/Jun/2020:01:25:37.132615917 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000567292 nentries=0 notes=U [16/Jun/2020:01:25:37.466530476 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000558383 nentries=0 notes=U [16/Jun/2020:01:25:37.540900171 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000722304 nentries=0 notes=U [16/Jun/2020:01:25:37.634307981 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000624708 nentries=0 notes=U [16/Jun/2020:01:25:37.700081832 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000389745 nentries=0 notes=U [16/Jun/2020:01:25:37.771354045 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000469597 nentries=0 notes=U [16/Jun/2020:01:25:37.842002055 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000502777 nentries=0 notes=U [16/Jun/2020:01:25:37.913495970 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000592462 nentries=0 notes=U [16/Jun/2020:01:25:37.978092651 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000515208 nentries=0 notes=U [16/Jun/2020:01:25:38.069359134 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000552399 nentries=0 notes=U [16/Jun/2020:01:25:38.144673432 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000637714 nentries=0 notes=U [16/Jun/2020:01:25:38.213595809 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000513311 nentries=0 notes=U [16/Jun/2020:01:25:38.242524357 +0200] - WARN - default_mr_indexer_create - Plugin [caseIgnoreIA5Match] does not handle caseExactIA5Match [16/Jun/2020:01:25:38.688927331 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000479308 nentries=0 notes=U [16/Jun/2020:01:25:38.772594642 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000564748 nentries=0 notes=U [16/Jun/2020:01:25:38.898493152 +0200] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=MY,dc=EXAMPLE,dc=COM" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.000584793 nentries=0 notes=U [16/Jun/2020:01:25:39.237670899 +0200] - INFO - op_thread_cleanup - slapd shutting down - signaling operation threads - op stack size 2 max work q size 1 max work q stack size 1 [16/Jun/2020:01:25:39.260476568 +0200] - INFO - slapd_daemon - slapd shutting down - waiting for 21 threads to terminate [16/Jun/2020:01:25:39.285799929 +0200] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [16/Jun/2020:01:25:39.320244229 +0200] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [16/Jun/2020:01:25:41.584004438 +0200] - INFO - dblayer_pre_close - All database threads now stopped [16/Jun/2020:01:25:41.652410394 +0200] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [16/Jun/2020:01:25:41.675702432 +0200] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 1 work q stack objects - freed 2 op stack objects [16/Jun/2020:01:25:41.699174709 +0200] - INFO - main - slapd stopped. [16/Jun/2020:01:25:42.428415625 +0200] - INFO - main - 389-Directory/1.3.10.1 B2020.133.1625 starting up [16/Jun/2020:01:25:42.452951324 +0200] - INFO - main - Setting the maximum file descriptor limit to: 16384 [16/Jun/2020:01:25:48.957482894 +0200] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [16/Jun/2020:01:25:49.002228160 +0200] - WARN - default_mr_indexer_create - Plugin [caseIgnoreIA5Match] does not handle caseExactIA5Match [16/Jun/2020:01:25:49.023845432 +0200] - NOTICE - ldbm_back_start - found 3879800k physical memory [16/Jun/2020:01:25:49.042718402 +0200] - NOTICE - ldbm_back_start - found 3330412k available [16/Jun/2020:01:25:49.066896586 +0200] - NOTICE - ldbm_back_start - cache autosizing: db cache: 96995k [16/Jun/2020:01:25:49.109622574 +0200] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 262144k [16/Jun/2020:01:25:49.129311981 +0200] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [16/Jun/2020:01:25:49.145317322 +0200] - NOTICE - ldbm_back_start - total cache size: 415002624 B; [16/Jun/2020:01:25:49.439953959 +0200] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [16/Jun/2020:01:25:49.469163939 +0200] - INFO - slapd_daemon - Listening on /var/run/slapd-MY.EXAMPLE.COM.socket for LDAPI requests [16/Jun/2020:01:25:50.934311432 +0200] - INFO - op_thread_cleanup - slapd shutting down - signaling operation threads - op stack size 2 max work q size 2 max work q stack size 2 [16/Jun/2020:01:25:50.960817275 +0200] - INFO - slapd_daemon - slapd shutting down - waiting for 21 threads to terminate [16/Jun/2020:01:25:50.982338170 +0200] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [16/Jun/2020:01:25:51.018381356 +0200] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [16/Jun/2020:01:25:51.725119330 +0200] - INFO - dblayer_pre_close - All database threads now stopped [16/Jun/2020:01:25:51.795567232 +0200] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [16/Jun/2020:01:25:51.817260472 +0200] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 2 work q stack objects - freed 2 op stack objects [16/Jun/2020:01:25:51.839280307 +0200] - INFO - main - slapd stopped. [16/Jun/2020:01:25:52.365909004 +0200] - INFO - main - 389-Directory/1.3.10.1 B2020.133.1625 starting up [16/Jun/2020:01:25:52.387481821 +0200] - INFO - main - Setting the maximum file descriptor limit to: 16384 [16/Jun/2020:01:25:59.007788322 +0200] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [16/Jun/2020:01:25:59.046407828 +0200] - WARN - default_mr_indexer_create - Plugin [caseIgnoreIA5Match] does not handle caseExactIA5Match [16/Jun/2020:01:25:59.068617668 +0200] - NOTICE - ldbm_back_start - found 3879800k physical memory [16/Jun/2020:01:25:59.131497765 +0200] - NOTICE - ldbm_back_start - found 3329008k available [16/Jun/2020:01:25:59.198162878 +0200] - NOTICE - ldbm_back_start - cache autosizing: db cache: 96995k [16/Jun/2020:01:25:59.223876003 +0200] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 262144k [16/Jun/2020:01:25:59.249295401 +0200] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [16/Jun/2020:01:25:59.276291474 +0200] - NOTICE - ldbm_back_start - total cache size: 415002624 B; [16/Jun/2020:01:25:59.474939935 +0200] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [16/Jun/2020:01:25:59.509595201 +0200] - INFO - slapd_daemon - Listening on /var/run/slapd-MY.EXAMPLE.COM.socket for LDAPI requests [16/Jun/2020:01:26:00.674747749 +0200] - WARN - NSMMReplicationPlugin - repl5_inc_run - agmt="cn=meTofreeipa.MY.EXAMPLE.COM" (freeipa:389): The remote replica has a different database generation ID than the local database. You may have to reinitialize the remote replica, or the local replica.