389-Directory/1.4.0.16 B2018.236.216 node01.it-nux.ch:389 (/etc/dirsrv/slapd-IPA-INTERNUX-CH) [27/Nov/2018:12:02:40.131649789 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [27/Nov/2018:12:02:40.139418415 +0100] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database [27/Nov/2018:12:02:40.140423017 +0100] - INFO - check_and_set_import_cache - pagesize: 4096, available bytes 3610251264, process usage 20877312 [27/Nov/2018:12:02:40.141162928 +0100] - INFO - check_and_set_import_cache - Import allocates 1410254KB import cache. [27/Nov/2018:12:02:40.283422262 +0100] - INFO - import_main_offline - import userRoot: Beginning import job... [27/Nov/2018:12:02:40.284697256 +0100] - INFO - import_main_offline - import userRoot: Index buffering enabled with bucket size 100 [27/Nov/2018:12:02:40.485716248 +0100] - INFO - import_producer - import userRoot: Processing file "/var/lib/dirsrv/boot.ldif" [27/Nov/2018:12:02:40.491863176 +0100] - INFO - import_producer - import userRoot: Finished scanning file "/var/lib/dirsrv/boot.ldif" (1 entries) [27/Nov/2018:12:02:40.991012896 +0100] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up... [27/Nov/2018:12:02:41.193064571 +0100] - INFO - import_monitor_threads - import userRoot: Workers cleaned up. [27/Nov/2018:12:02:41.194104480 +0100] - INFO - import_main_offline - import userRoot: Cleaning up producer thread... [27/Nov/2018:12:02:41.194967402 +0100] - INFO - import_main_offline - import userRoot: Indexing complete. Post-processing... [27/Nov/2018:12:02:41.195800128 +0100] - INFO - import_main_offline - import userRoot: Generating numsubordinates (this may take several minutes to complete)... [27/Nov/2018:12:02:41.197703297 +0100] - INFO - import_main_offline - import userRoot: Generating numSubordinates complete. [27/Nov/2018:12:02:41.198656788 +0100] - INFO - ldbm_get_nonleaf_ids - import userRoot: Gathering ancestorid non-leaf IDs... [27/Nov/2018:12:02:41.199493475 +0100] - INFO - ldbm_get_nonleaf_ids - import userRoot: Finished gathering ancestorid non-leaf IDs. [27/Nov/2018:12:02:41.202995735 +0100] - ERR - ldbm_ancestorid_new_idl_create_index - Nothing to do to build ancestorid index [27/Nov/2018:12:02:41.203810342 +0100] - INFO - ldbm_ancestorid_new_idl_create_index - import userRoot: Created ancestorid index (new idl). [27/Nov/2018:12:02:41.204607063 +0100] - INFO - import_main_offline - import userRoot: Flushing caches... [27/Nov/2018:12:02:41.205390029 +0100] - INFO - import_main_offline - import userRoot: Closing files... [27/Nov/2018:12:02:41.226625142 +0100] - INFO - dblayer_pre_close - All database threads now stopped [27/Nov/2018:12:02:41.227815052 +0100] - INFO - import_main_offline - import userRoot: Import complete. Processed 1 entries in 1 seconds. (1.00 entries/sec) [27/Nov/2018:12:02:41.543600453 +0100] - INFO - main - 389-Directory/1.4.0.16 B2018.236.216 starting up [27/Nov/2018:12:02:42.465319972 +0100] - INFO - PBKDF2_SHA256 - Based on CPU performance, chose 2048 rounds [27/Nov/2018:12:02:42.477861852 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [27/Nov/2018:12:02:42.493025031 +0100] - NOTICE - ldbm_back_start - found 3938716k physical memory [27/Nov/2018:12:02:42.494477488 +0100] - NOTICE - ldbm_back_start - found 3524908k available [27/Nov/2018:12:02:42.495463210 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 98467k [27/Nov/2018:12:02:42.496544012 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 327680k [27/Nov/2018:12:02:42.497644373 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [27/Nov/2018:12:02:42.498722660 +0100] - NOTICE - ldbm_back_start - total cache size: 432986439 B; [27/Nov/2018:12:02:42.500116386 +0100] - INFO - dblayer_start - Resizing db cache size: 1444100505 -> 80664903 [27/Nov/2018:12:02:42.696478659 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [27/Nov/2018:12:02:42.849510323 +0100] - INFO - postop_modify_config_dse - The change of nsslapd-ldapilisten will not take effect until the server is restarted [27/Nov/2018:12:02:43.273994565 +0100] - 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 [27/Nov/2018:12:02:43.277254980 +0100] - INFO - slapd_daemon - slapd shutting down - waiting for 6 threads to terminate [27/Nov/2018:12:02:43.278351059 +0100] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [27/Nov/2018:12:02:43.283641033 +0100] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [27/Nov/2018:12:02:45.055281059 +0100] - INFO - dblayer_pre_close - All database threads now stopped [27/Nov/2018:12:02:45.073401465 +0100] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [27/Nov/2018:12:02:45.074848257 +0100] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 1 work q stack objects - freed 2 op stack objects [27/Nov/2018:12:02:45.078054369 +0100] - INFO - main - slapd stopped. [27/Nov/2018:12:02:45.350567891 +0100] - INFO - main - 389-Directory/1.4.0.16 B2018.236.216 starting up [27/Nov/2018:12:02:46.407818056 +0100] - INFO - PBKDF2_SHA256 - Based on CPU performance, chose 2048 rounds [27/Nov/2018:12:02:46.417836446 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [27/Nov/2018:12:02:46.423859303 +0100] - NOTICE - ldbm_back_start - found 3938716k physical memory [27/Nov/2018:12:02:46.424805081 +0100] - NOTICE - ldbm_back_start - found 3538952k available [27/Nov/2018:12:02:46.425542757 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 98467k [27/Nov/2018:12:02:46.426435748 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 327680k [27/Nov/2018:12:02:46.427349852 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [27/Nov/2018:12:02:46.428156431 +0100] - NOTICE - ldbm_back_start - total cache size: 483318087 B; [27/Nov/2018:12:02:46.429498309 +0100] - NOTICE - dblayer_start - Resizing max db lock count: 10000 -> 50000 [27/Nov/2018:12:02:46.609238650 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [27/Nov/2018:12:02:46.610676738 +0100] - INFO - slapd_daemon - Listening on /var/run/slapd-IPA-INTERNUX-CH.socket for LDAPI requests [27/Nov/2018:12:02:46.630366710 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000397443 nentries=0 notes=U [27/Nov/2018:12:02:46.645618709 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000200083 nentries=0 notes=U [27/Nov/2018:12:02:46.665898463 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000163921 nentries=0 notes=U [27/Nov/2018:12:02:46.690305248 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000173201 nentries=0 notes=U [27/Nov/2018:12:02:46.710650134 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000176453 nentries=0 notes=U [27/Nov/2018:12:02:46.732566598 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000151977 nentries=0 notes=U [27/Nov/2018:12:02:47.020695879 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000142419 nentries=0 notes=U [27/Nov/2018:12:02:47.050963557 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000169242 nentries=0 notes=U [27/Nov/2018:12:02:47.321728573 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000168123 nentries=0 notes=U [27/Nov/2018:12:02:47.347959120 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000220318 nentries=0 notes=U [27/Nov/2018:12:02:47.380359884 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000176213 nentries=0 notes=U [27/Nov/2018:12:02:47.399554577 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000203702 nentries=0 notes=U [27/Nov/2018:12:02:47.434933658 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000175412 nentries=0 notes=U [27/Nov/2018:12:02:47.453476335 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000136202 nentries=0 notes=U [27/Nov/2018:12:02:47.724649023 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000144741 nentries=0 notes=U [27/Nov/2018:12:02:48.436890343 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000137726 nentries=0 notes=U [27/Nov/2018:12:02:48.490516518 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000150077 nentries=0 notes=U [27/Nov/2018:12:02:48.520345706 +0100] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=ipa,dc=internux,dc=ch" filter="(&(uidNumber=0)(gidNumber=0))" etime=0.0000255758 nentries=0 notes=U [27/Nov/2018:12:02:49.046438066 +0100] - 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 [27/Nov/2018:12:02:49.054537683 +0100] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [27/Nov/2018:12:02:49.068230166 +0100] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [27/Nov/2018:12:02:51.544508616 +0100] - INFO - dblayer_pre_close - All database threads now stopped [27/Nov/2018:12:02:51.734738760 +0100] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [27/Nov/2018:12:02:51.736324822 +0100] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 2 work q stack objects - freed 2 op stack objects [27/Nov/2018:12:02:51.737300632 +0100] - INFO - main - slapd stopped. [27/Nov/2018:12:02:52.198364732 +0100] - INFO - main - 389-Directory/1.4.0.16 B2018.236.216 starting up [27/Nov/2018:12:02:53.163852782 +0100] - INFO - PBKDF2_SHA256 - Based on CPU performance, chose 2048 rounds [27/Nov/2018:12:02:53.170856873 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [27/Nov/2018:12:02:53.178964271 +0100] - NOTICE - ldbm_back_start - found 3938716k physical memory [27/Nov/2018:12:02:53.180146098 +0100] - NOTICE - ldbm_back_start - found 3536636k available [27/Nov/2018:12:02:53.181191337 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 98467k [27/Nov/2018:12:02:53.182123825 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 327680k [27/Nov/2018:12:02:53.183251986 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [27/Nov/2018:12:02:53.184433101 +0100] - NOTICE - ldbm_back_start - total cache size: 483318087 B; [27/Nov/2018:12:02:53.280502112 +0100] - ERR - ipalockout_get_global_config - [file ipa_lockout.c, line 185]: Failed to get default realm (-1765328160) [27/Nov/2018:12:02:53.284777024 +0100] - ERR - ipaenrollment_start - [file ipa_enrollment.c, line 408]: Failed to get default realm?! [27/Nov/2018:12:02:53.298350117 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [27/Nov/2018:12:02:53.299926569 +0100] - INFO - slapd_daemon - Listening on /var/run/slapd-IPA-INTERNUX-CH.socket for LDAPI requests [27/Nov/2018:12:02:54.891291835 +0100] - ERR - cos-plugin - cos_dn_defs_cb - Skipping CoS Definition cn=Password Policy,cn=accounts,dc=ipa,dc=internux,dc=ch--no CoS Templates found, which should be added before the CoS Definition. [27/Nov/2018:12:02:55.252680197 +0100] - ERR - NSACLPlugin - acl_parse - The ACL target cn=retrieve certificate,cn=virtual operations,cn=etc,dc=ipa,dc=internux,dc=ch does not exist [27/Nov/2018:12:02:55.258735352 +0100] - ERR - NSACLPlugin - acl_parse - The ACL target cn=request certificate,cn=virtual operations,cn=etc,dc=ipa,dc=internux,dc=ch does not exist [27/Nov/2018:12:02:55.264446985 +0100] - ERR - NSACLPlugin - acl_parse - The ACL target cn=request certificate different host,cn=virtual operations,cn=etc,dc=ipa,dc=internux,dc=ch does not exist [27/Nov/2018:12:02:55.270820593 +0100] - ERR - NSACLPlugin - acl_parse - The ACL target cn=certificate status,cn=virtual operations,cn=etc,dc=ipa,dc=internux,dc=ch does not exist [27/Nov/2018:12:02:55.279007482 +0100] - ERR - NSACLPlugin - acl_parse - The ACL target cn=revoke certificate,cn=virtual operations,cn=etc,dc=ipa,dc=internux,dc=ch does not exist [27/Nov/2018:12:02:55.284832507 +0100] - ERR - NSACLPlugin - acl_parse - The ACL target cn=certificate remove hold,cn=virtual operations,cn=etc,dc=ipa,dc=internux,dc=ch does not exist [27/Nov/2018:12:02:56.629131946 +0100] - INFO - memberof-plugin - memberof_fixup_task_thread - Memberof task starts (filter: "(objectclass=*)") ... [27/Nov/2018:12:06:05.173777422 +0100] - 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 [27/Nov/2018:12:06:05.178470195 +0100] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [27/Nov/2018:12:06:05.186224645 +0100] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [27/Nov/2018:12:06:06.156826998 +0100] - INFO - dblayer_pre_close - All database threads now stopped [27/Nov/2018:12:06:06.177707205 +0100] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [27/Nov/2018:12:06:06.180130907 +0100] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 1 work q stack objects - freed 2 op stack objects [27/Nov/2018:12:06:06.181026474 +0100] - INFO - main - slapd stopped.