389-Directory/1.3.7.5 B2018.269.1826 node01.it-nux.ch:389 (/etc/dirsrv/slapd-IPA-INTERNUX-CH) [26/Nov/2018:17:26:23.068500642 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [26/Nov/2018:17:26:23.081236259 +0100] - INFO - dblayer_instance_start - Import is running with nsslapd-db-private-import-mem on; No other process is allowed to access the database [26/Nov/2018:17:26:23.082945367 +0100] - INFO - check_and_set_import_cache - pagesize: 4096, available bytes 3401629696, process usage 17555456 [26/Nov/2018:17:26:23.084037639 +0100] - INFO - check_and_set_import_cache - Import allocates 1328761KB import cache. [26/Nov/2018:17:26:23.139629104 +0100] - INFO - import_main_offline - import userRoot: Beginning import job... [26/Nov/2018:17:26:23.140599100 +0100] - INFO - import_main_offline - import userRoot: Index buffering enabled with bucket size 100 [26/Nov/2018:17:26:23.341951507 +0100] - INFO - import_producer - import userRoot: Processing file "/var/lib/dirsrv/boot.ldif" [26/Nov/2018:17:26:23.344057240 +0100] - INFO - import_producer - import userRoot: Finished scanning file "/var/lib/dirsrv/boot.ldif" (1 entries) [26/Nov/2018:17:26:23.843416515 +0100] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up... [26/Nov/2018:17:26:24.045185692 +0100] - INFO - import_monitor_threads - import userRoot: Workers cleaned up. [26/Nov/2018:17:26:24.050181266 +0100] - INFO - import_main_offline - import userRoot: Cleaning up producer thread... [26/Nov/2018:17:26:24.051258080 +0100] - INFO - import_main_offline - import userRoot: Indexing complete. Post-processing... [26/Nov/2018:17:26:24.052301839 +0100] - INFO - import_main_offline - import userRoot: Generating numsubordinates (this may take several minutes to complete)... [26/Nov/2018:17:26:24.055057801 +0100] - INFO - import_main_offline - import userRoot: Generating numSubordinates complete. [26/Nov/2018:17:26:24.056295394 +0100] - INFO - ldbm_get_nonleaf_ids - import userRoot: Gathering ancestorid non-leaf IDs... [26/Nov/2018:17:26:24.057347372 +0100] - INFO - ldbm_get_nonleaf_ids - import userRoot: Finished gathering ancestorid non-leaf IDs. [26/Nov/2018:17:26:24.059744670 +0100] - ERR - ldbm_ancestorid_new_idl_create_index - Nothing to do to build ancestorid index [26/Nov/2018:17:26:24.060697132 +0100] - INFO - ldbm_ancestorid_new_idl_create_index - import userRoot: Created ancestorid index (new idl). [26/Nov/2018:17:26:24.061683658 +0100] - INFO - import_main_offline - import userRoot: Flushing caches... [26/Nov/2018:17:26:24.062667758 +0100] - INFO - import_main_offline - import userRoot: Closing files... [26/Nov/2018:17:26:24.079129078 +0100] - INFO - dblayer_pre_close - All database threads now stopped [26/Nov/2018:17:26:24.081013616 +0100] - INFO - import_main_offline - import userRoot: Import complete. Processed 1 entries in 1 seconds. (1.00 entries/sec) [26/Nov/2018:17:26:24.521672874 +0100] - INFO - main - 389-Directory/1.3.7.5 B2018.269.1826 starting up [26/Nov/2018:17:26:28.230644216 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [26/Nov/2018:17:26:28.241707975 +0100] - NOTICE - ldbm_back_start - found 3782608k physical memory [26/Nov/2018:17:26:28.243412495 +0100] - NOTICE - ldbm_back_start - found 3316164k available [26/Nov/2018:17:26:28.244422770 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 94565k [26/Nov/2018:17:26:28.246266303 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 262144k [26/Nov/2018:17:26:28.252812406 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [26/Nov/2018:17:26:28.255124178 +0100] - NOTICE - ldbm_back_start - total cache size: 362680483 B; [26/Nov/2018:17:26:28.257543373 +0100] - INFO - dblayer_start - Resizing db cache size: 1360651878 -> 77467811 [26/Nov/2018:17:26:28.402533246 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [26/Nov/2018:17:26:28.434739624 +0100] - INFO - postop_modify_config_dse - The change of nsslapd-ldapilisten will not take effect until the server is restarted [26/Nov/2018:17:26:28.709172751 +0100] - 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 [26/Nov/2018:17:26:28.713980097 +0100] - INFO - slapd_daemon - slapd shutting down - waiting for 14 threads to terminate [26/Nov/2018:17:26:28.716076458 +0100] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [26/Nov/2018:17:26:28.725155501 +0100] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [26/Nov/2018:17:26:30.831746691 +0100] - INFO - dblayer_pre_close - All database threads now stopped [26/Nov/2018:17:26:30.836062893 +0100] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [26/Nov/2018:17:26:30.836968572 +0100] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 1 work q stack objects - freed 1 op stack objects [26/Nov/2018:17:26:30.837651628 +0100] - INFO - main - slapd stopped. [26/Nov/2018:17:26:31.037453572 +0100] - INFO - main - 389-Directory/1.3.7.5 B2018.269.1826 starting up [26/Nov/2018:17:26:34.914240706 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [26/Nov/2018:17:26:34.928492433 +0100] - NOTICE - ldbm_back_start - found 3782608k physical memory [26/Nov/2018:17:26:34.930052676 +0100] - NOTICE - ldbm_back_start - found 3324724k available [26/Nov/2018:17:26:34.931640031 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 94565k [26/Nov/2018:17:26:34.933250256 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 262144k [26/Nov/2018:17:26:34.936126700 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [26/Nov/2018:17:26:34.938397234 +0100] - NOTICE - ldbm_back_start - total cache size: 413012131 B; [26/Nov/2018:17:26:34.941413917 +0100] - NOTICE - dblayer_start - Resizing max db lock count: 10000 -> 50000 [26/Nov/2018:17:26:35.170596339 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [26/Nov/2018:17:26:35.172619662 +0100] - INFO - slapd_daemon - Listening on /var/run/slapd-IPA-INTERNUX-CH.socket for LDAPI requests [26/Nov/2018:17:26:35.191620677 +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.0000299128 nentries=0 notes=U [26/Nov/2018:17:26:35.206511275 +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.0000228841 nentries=0 notes=U [26/Nov/2018:17:26:35.226203226 +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.0000167608 nentries=0 notes=U [26/Nov/2018:17:26:35.256298889 +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.0000264340 nentries=0 notes=U [26/Nov/2018:17:26:35.286802154 +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.0000318809 nentries=0 notes=U [26/Nov/2018:17:26:35.312377476 +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.0000203981 nentries=0 notes=U [26/Nov/2018:17:26:35.602625039 +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.0000190270 nentries=0 notes=U [26/Nov/2018:17:26:35.624110369 +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.0000142851 nentries=0 notes=U [26/Nov/2018:17:26:35.650332509 +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.0000136305 nentries=0 notes=U [26/Nov/2018:17:26:35.670420009 +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.0000162080 nentries=0 notes=U [26/Nov/2018:17:26:35.707687464 +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.0000108734 nentries=0 notes=U [26/Nov/2018:17:26:35.735311198 +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.0000096361 nentries=0 notes=U [26/Nov/2018:17:26:35.767774054 +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.0000098369 nentries=0 notes=U [26/Nov/2018:17:26:35.800708444 +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.0000146346 nentries=0 notes=U [26/Nov/2018:17:26:35.823788854 +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.0000188986 nentries=0 notes=U [26/Nov/2018:17:26:35.826955621 +0100] - WARN - default_mr_indexer_create - Plugin [caseIgnoreIA5Match] does not handle caseExactIA5Match [26/Nov/2018:17:26:36.271709114 +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.0000118254 nentries=0 notes=U [26/Nov/2018:17:26:36.305373139 +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.0000295660 nentries=0 notes=U [26/Nov/2018:17:26:36.364477604 +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.0000150434 nentries=0 notes=U [26/Nov/2018:17:26:36.638127820 +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 [26/Nov/2018:17:26:36.643197647 +0100] - INFO - slapd_daemon - slapd shutting down - waiting for 15 threads to terminate [26/Nov/2018:17:26:36.645727455 +0100] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [26/Nov/2018:17:26:36.655947495 +0100] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [26/Nov/2018:17:26:37.576525082 +0100] - INFO - dblayer_pre_close - All database threads now stopped [26/Nov/2018:17:26:37.591516145 +0100] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [26/Nov/2018:17:26:37.594250746 +0100] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 2 work q stack objects - freed 2 op stack objects [26/Nov/2018:17:26:37.596011921 +0100] - INFO - main - slapd stopped. [26/Nov/2018:17:26:37.826548561 +0100] - INFO - main - 389-Directory/1.3.7.5 B2018.269.1826 starting up [26/Nov/2018:17:26:41.713118856 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [26/Nov/2018:17:26:41.729700824 +0100] - WARN - default_mr_indexer_create - Plugin [caseIgnoreIA5Match] does not handle caseExactIA5Match [26/Nov/2018:17:26:41.734120151 +0100] - NOTICE - ldbm_back_start - found 3782608k physical memory [26/Nov/2018:17:26:41.735558157 +0100] - NOTICE - ldbm_back_start - found 3322732k available [26/Nov/2018:17:26:41.737371444 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 94565k [26/Nov/2018:17:26:41.739162952 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 262144k [26/Nov/2018:17:26:41.740895037 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [26/Nov/2018:17:26:41.742437723 +0100] - NOTICE - ldbm_back_start - total cache size: 413012131 B; [26/Nov/2018:17:26:41.846495490 +0100] - ERR - ipalockout_get_global_config - [file ipa_lockout.c, line 185]: Failed to get default realm (-1765328160) [26/Nov/2018:17:26:41.850425695 +0100] - ERR - ipaenrollment_start - [file ipa_enrollment.c, line 408]: Failed to get default realm?! [26/Nov/2018:17:26:41.865574307 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [26/Nov/2018:17:26:41.867262932 +0100] - INFO - slapd_daemon - Listening on /var/run/slapd-IPA-INTERNUX-CH.socket for LDAPI requests [26/Nov/2018:17:26:42.395882240 +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. [26/Nov/2018:17:26:42.759958237 +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 [26/Nov/2018:17:26:42.764634422 +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 [26/Nov/2018:17:26:42.775514915 +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 [26/Nov/2018:17:26:42.785108161 +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 [26/Nov/2018:17:26:42.849261161 +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 [26/Nov/2018:17:26:42.859468681 +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 [26/Nov/2018:17:26:43.296809680 +0100] - INFO - memberof-plugin - memberof_fixup_task_thread - Memberof task starts (filter: "(objectclass=*)") ...