389-Directory/1.4.0.16 B2018.236.216 node01.it-nux.ch:389 (/etc/dirsrv/slapd-IPA-INTERNUX-CH) [26/Nov/2018:18:18:18.793219774 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [26/Nov/2018:18:18:18.799197544 +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:18:18:18.800610476 +0100] - INFO - check_and_set_import_cache - pagesize: 4096, available bytes 3618172928, process usage 20774912 [26/Nov/2018:18:18:18.801798905 +0100] - INFO - check_and_set_import_cache - Import allocates 1413348KB import cache. [26/Nov/2018:18:18:18.876681275 +0100] - INFO - import_main_offline - import userRoot: Beginning import job... [26/Nov/2018:18:18:18.877945834 +0100] - INFO - import_main_offline - import userRoot: Index buffering enabled with bucket size 100 [26/Nov/2018:18:18:19.079142814 +0100] - INFO - import_producer - import userRoot: Processing file "/var/lib/dirsrv/boot.ldif" [26/Nov/2018:18:18:19.080952101 +0100] - INFO - import_producer - import userRoot: Finished scanning file "/var/lib/dirsrv/boot.ldif" (1 entries) [26/Nov/2018:18:18:19.580864554 +0100] - INFO - import_monitor_threads - import userRoot: Workers finished; cleaning up... [26/Nov/2018:18:18:19.782926322 +0100] - INFO - import_monitor_threads - import userRoot: Workers cleaned up. [26/Nov/2018:18:18:19.783959720 +0100] - INFO - import_main_offline - import userRoot: Cleaning up producer thread... [26/Nov/2018:18:18:19.784769907 +0100] - INFO - import_main_offline - import userRoot: Indexing complete. Post-processing... [26/Nov/2018:18:18:19.785483694 +0100] - INFO - import_main_offline - import userRoot: Generating numsubordinates (this may take several minutes to complete)... [26/Nov/2018:18:18:19.787453882 +0100] - INFO - import_main_offline - import userRoot: Generating numSubordinates complete. [26/Nov/2018:18:18:19.788295955 +0100] - INFO - ldbm_get_nonleaf_ids - import userRoot: Gathering ancestorid non-leaf IDs... [26/Nov/2018:18:18:19.789107636 +0100] - INFO - ldbm_get_nonleaf_ids - import userRoot: Finished gathering ancestorid non-leaf IDs. [26/Nov/2018:18:18:19.792120249 +0100] - ERR - ldbm_ancestorid_new_idl_create_index - Nothing to do to build ancestorid index [26/Nov/2018:18:18:19.792833081 +0100] - INFO - ldbm_ancestorid_new_idl_create_index - import userRoot: Created ancestorid index (new idl). [26/Nov/2018:18:18:19.793476768 +0100] - INFO - import_main_offline - import userRoot: Flushing caches... [26/Nov/2018:18:18:19.794141415 +0100] - INFO - import_main_offline - import userRoot: Closing files... [26/Nov/2018:18:18:19.805494326 +0100] - INFO - dblayer_pre_close - All database threads now stopped [26/Nov/2018:18:18:19.806277629 +0100] - INFO - import_main_offline - import userRoot: Import complete. Processed 1 entries in 1 seconds. (1.00 entries/sec) [26/Nov/2018:18:18:20.061188889 +0100] - INFO - main - 389-Directory/1.4.0.16 B2018.236.216 starting up [26/Nov/2018:18:18:21.004483284 +0100] - INFO - PBKDF2_SHA256 - Based on CPU performance, chose 2048 rounds [26/Nov/2018:18:18:21.013526457 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [26/Nov/2018:18:18:21.040131971 +0100] - NOTICE - ldbm_back_start - found 3938716k physical memory [26/Nov/2018:18:18:21.041700857 +0100] - NOTICE - ldbm_back_start - found 3532344k available [26/Nov/2018:18:18:21.042719390 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 98467k [26/Nov/2018:18:18:21.044211174 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 327680k [26/Nov/2018:18:18:21.045586789 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [26/Nov/2018:18:18:21.046798041 +0100] - NOTICE - ldbm_back_start - total cache size: 432986439 B; [26/Nov/2018:18:18:21.048072338 +0100] - INFO - dblayer_start - Resizing db cache size: 1447269171 -> 80664903 [26/Nov/2018:18:18:21.178081051 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [26/Nov/2018:18:18:21.306563610 +0100] - INFO - postop_modify_config_dse - The change of nsslapd-ldapilisten will not take effect until the server is restarted [26/Nov/2018:18:18:21.700404481 +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:18:18:21.704562392 +0100] - INFO - slapd_daemon - slapd shutting down - waiting for 4 threads to terminate [26/Nov/2018:18:18:21.705860328 +0100] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [26/Nov/2018:18:18:21.713616879 +0100] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [26/Nov/2018:18:18:23.601054058 +0100] - INFO - dblayer_pre_close - All database threads now stopped [26/Nov/2018:18:18:23.611929946 +0100] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [26/Nov/2018:18:18:23.615202873 +0100] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 1 work q stack objects - freed 1 op stack objects [26/Nov/2018:18:18:23.616566375 +0100] - INFO - main - slapd stopped. [26/Nov/2018:18:18:23.999357040 +0100] - INFO - main - 389-Directory/1.4.0.16 B2018.236.216 starting up [26/Nov/2018:18:18:24.926440371 +0100] - INFO - PBKDF2_SHA256 - Based on CPU performance, chose 2048 rounds [26/Nov/2018:18:18:24.932858235 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [26/Nov/2018:18:18:24.949284619 +0100] - NOTICE - ldbm_back_start - found 3938716k physical memory [26/Nov/2018:18:18:24.950810780 +0100] - NOTICE - ldbm_back_start - found 3547196k available [26/Nov/2018:18:18:24.952239728 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 98467k [26/Nov/2018:18:18:24.953613626 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 327680k [26/Nov/2018:18:18:24.955072673 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [26/Nov/2018:18:18:24.956853853 +0100] - NOTICE - ldbm_back_start - total cache size: 483318087 B; [26/Nov/2018:18:18:24.959051620 +0100] - NOTICE - dblayer_start - Resizing max db lock count: 10000 -> 50000 [26/Nov/2018:18:18:25.156590368 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [26/Nov/2018:18:18:25.158275869 +0100] - INFO - slapd_daemon - Listening on /var/run/slapd-IPA-INTERNUX-CH.socket for LDAPI requests [26/Nov/2018:18:18:25.175906514 +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.0000258997 nentries=0 notes=U [26/Nov/2018:18:18:25.187754742 +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.0000127608 nentries=0 notes=U [26/Nov/2018:18:18:25.202069299 +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.0000108569 nentries=0 notes=U [26/Nov/2018:18:18:25.215998486 +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.0000112385 nentries=0 notes=U [26/Nov/2018:18:18:25.241181037 +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.0000163631 nentries=0 notes=U [26/Nov/2018:18:18:25.520313508 +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.0000196440 nentries=0 notes=U [26/Nov/2018:18:18:25.823219705 +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.0000120283 nentries=0 notes=U [26/Nov/2018:18:18:26.092481827 +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.0000137874 nentries=0 notes=U [26/Nov/2018:18:18:26.112062271 +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.0000136395 nentries=0 notes=U [26/Nov/2018:18:18:26.131911693 +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.0000138678 nentries=0 notes=U [26/Nov/2018:18:18:26.151979108 +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.0000141587 nentries=0 notes=U [26/Nov/2018:18:18:26.171854631 +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.0000130950 nentries=0 notes=U [26/Nov/2018:18:18:26.207286051 +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.0000122115 nentries=0 notes=U [26/Nov/2018:18:18:26.225535704 +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.0000091101 nentries=0 notes=U [26/Nov/2018:18:18:26.242449387 +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.0000125770 nentries=0 notes=U [26/Nov/2018:18:18:26.644715423 +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.0000095570 nentries=0 notes=U [26/Nov/2018:18:18:26.674633078 +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.0000099578 nentries=0 notes=U [26/Nov/2018:18:18:26.696540606 +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.0000106293 nentries=0 notes=U [26/Nov/2018:18:18:26.962187636 +0100] - INFO - op_thread_cleanup - slapd shutting down - signaling operation threads - op stack size 3 max work q size 3 max work q stack size 3 [26/Nov/2018:18:18:26.966502901 +0100] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins [26/Nov/2018:18:18:26.976579243 +0100] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop [26/Nov/2018:18:18:27.589129137 +0100] - INFO - dblayer_pre_close - All database threads now stopped [26/Nov/2018:18:18:27.601872038 +0100] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed [26/Nov/2018:18:18:27.603681812 +0100] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 3 work q stack objects - freed 3 op stack objects [26/Nov/2018:18:18:27.604836896 +0100] - INFO - main - slapd stopped. [26/Nov/2018:18:18:27.837752620 +0100] - INFO - main - 389-Directory/1.4.0.16 B2018.236.216 starting up [26/Nov/2018:18:18:28.835692600 +0100] - INFO - PBKDF2_SHA256 - Based on CPU performance, chose 2048 rounds [26/Nov/2018:18:18:28.839144339 +0100] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000 [26/Nov/2018:18:18:28.844238941 +0100] - NOTICE - ldbm_back_start - found 3938716k physical memory [26/Nov/2018:18:18:28.845973568 +0100] - NOTICE - ldbm_back_start - found 3545256k available [26/Nov/2018:18:18:28.846935697 +0100] - NOTICE - ldbm_back_start - cache autosizing: db cache: 98467k [26/Nov/2018:18:18:28.848195877 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 327680k [26/Nov/2018:18:18:28.849301606 +0100] - NOTICE - ldbm_back_start - cache autosizing: userRoot dn cache (1 total): 65536k [26/Nov/2018:18:18:28.850423005 +0100] - NOTICE - ldbm_back_start - total cache size: 483318087 B; [26/Nov/2018:18:18:28.937049958 +0100] - ERR - ipalockout_get_global_config - [file ipa_lockout.c, line 185]: Failed to get default realm (-1765328160) [26/Nov/2018:18:18:28.940116279 +0100] - ERR - ipaenrollment_start - [file ipa_enrollment.c, line 408]: Failed to get default realm?! [26/Nov/2018:18:18:28.954993123 +0100] - INFO - slapd_daemon - slapd started. Listening on All Interfaces port 389 for LDAP requests [26/Nov/2018:18:18:28.956130128 +0100] - INFO - slapd_daemon - Listening on /var/run/slapd-IPA-INTERNUX-CH.socket for LDAPI requests [26/Nov/2018:18:18:30.085460789 +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:18:18:30.517250389 +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:18:18:30.525488990 +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:18:18:30.532468624 +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:18:18:30.539555216 +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:18:18:30.547229642 +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:18:18:30.556968046 +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:18:18:31.314115922 +0100] - INFO - memberof-plugin - memberof_fixup_task_thread - Memberof task starts (filter: "(objectclass=*)") ...