Bug 1450832

Summary:	Internal unindexed and NSACLPlugin - acl_parse ERR messages seen in /var/log/dirsrv/slapd/errors
Product:	Red Hat Enterprise Linux 7	Reporter:	Sudhir Menon <sumenon>
Component:	389-ds-base	Assignee:	mreynolds
Status:	CLOSED DUPLICATE	QA Contact:	Viktor Ashirov <vashirov>
Severity:	unspecified	Docs Contact:
Priority:	unspecified
Version:	7.4	CC:	nkinder, pvoborni, rcritten, rmeggins, tbordaz, tscherf
Target Milestone:	rc	Keywords:	Regression
Target Release:	---
Hardware:	Unspecified
OS:	Unspecified
Whiteboard:
Fixed In Version:		Doc Type:	If docs needed, set a value
Doc Text:		Story Points:	---
Clone Of:		Environment:
Last Closed:	2017-05-18 15:08:43 UTC	Type:	Bug
Regression:	---	Mount Type:	---
Documentation:	---	CRM:
Verified Versions:		Category:	---
oVirt Team:	---	RHEL 7.3 requirements from Atomic Host:
Cloudforms Team:	---	Target Upstream Version:
Embargoed:

Description Sudhir Menon 2017-05-15 09:16:42 UTC

Description of problem: Internal unindexed search in errors file for dirsrv.


Version-Release number of selected component (if applicable):
389-ds-base-1.3.6.1-13.el7.x86_64
ipa-server-4.5.0-11.el7.x86_64

How reproducible: Always

Steps to Reproduce:
1. Install IPA server.
2. Check /var/log/dirsrv/slapd-TESTRELM-TEST/errors file.

Actual results: It displays the below messages
[15/May/2017:04:59:25.164429677 -0400] - INFO - slapd_daemon - slapd started.  Listening on All Interfaces port 389 for LDAP requests
[15/May/2017:04:59:25.165511359 -0400] - INFO - slapd_daemon - Listening on /var/run/slapd-TESTRELM-TEST.socket for LDAPI requests
[15/May/2017:04:59:25.196040063 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.208841921 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.223186481 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.235738241 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.248815149 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.261613878 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.286503365 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.299503705 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.314225695 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.325936673 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.340455730 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.353293575 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.380417630 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.392775610 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.405177852 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.406451523 -0400] - WARN - default_mr_indexer_create - Plugin [caseIgnoreIA5Match] does not handle caseExactIA5Match
[15/May/2017:04:59:25.499628453 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.514588722 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.542999772 -0400] - ERR - log_result - Internal unindexed search: source (cn=server,cn=plugins,cn=config) search base="dc=testrelm,dc=test" filter="(&(uidNumber=0)(gidNumber=0))" etime=0 nentries=0  notes=U
[15/May/2017:04:59:25.684855872 -0400] - 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
[15/May/2017:04:59:25.689325405 -0400] - INFO - slapd_daemon - slapd shutting down - closing down internal subsystems and plugins
[15/May/2017:04:59:25.694251845 -0400] - INFO - dblayer_pre_close - Waiting for 4 database threads to stop
[15/May/2017:04:59:26.098303896 -0400] - INFO - dblayer_pre_close - All database threads now stopped
[15/May/2017:04:59:26.105520902 -0400] - INFO - ldbm_back_instance_set_destructor - Set of instances destroyed
[15/May/2017:04:59:26.106641632 -0400] - INFO - connection_post_shutdown_cleanup - slapd shutting down - freed 2 work q stack objects - freed 2 op stack objects
[15/May/2017:04:59:26.165478950 -0400] - INFO - main - slapd stopped.
[15/May/2017:04:59:26.340940766 -0400] - INFO - main - 389-Directory/1.3.6.1 B2017.125.237 starting up
[15/May/2017:04:59:26.349551888 -0400] - INFO - ldbm_instance_config_cachememsize_set - force a minimal value 512000
[15/May/2017:04:59:26.354974444 -0400] - WARN - default_mr_indexer_create - Plugin [caseIgnoreIA5Match] does not handle caseExactIA5Match
[15/May/2017:04:59:26.359214897 -0400] - NOTICE - ldbm_back_start - found 3881984k physical memory
[15/May/2017:04:59:26.359936702 -0400] - NOTICE - ldbm_back_start - found 3176720k avaliable
[15/May/2017:04:59:26.360867096 -0400] - NOTICE - ldbm_back_start - cache autosizing: db cache: 155279k
[15/May/2017:04:59:26.361660387 -0400] - NOTICE - ldbm_back_start - cache autosizing: userRoot entry cache (1 total): 262144k
[15/May/2017:04:59:26.365753917 -0400] - NOTICE - ldbm_back_start - total cache size: 412417523 B; 
[15/May/2017:04:59:26.468694414 -0400] - ERR - ipalockout_get_global_config - [file ipa_lockout.c, line 185]: Failed to get default realm (-1765328160)
[15/May/2017:04:59:26.470732518 -0400] - ERR - ipaenrollment_start - [file ipa_enrollment.c, line 408]: Failed to get default realm?!
[15/May/2017:04:59:26.481685478 -0400] - INFO - slapd_daemon - slapd started.  Listening on All Interfaces port 389 for LDAP requests
[15/May/2017:04:59:26.482554589 -0400] - INFO - slapd_daemon - Listening on /var/run/slapd-TESTRELM-TEST.socket for LDAPI requests
[15/May/2017:04:59:26.857181537 -0400] - ERR - cos-plugin - cos_dn_defs_cb - Skipping CoS Definition cn=Password Policy,cn=accounts,dc=testrelm,dc=test--no CoS Templates found, which should be added before the CoS Definition.
[15/May/2017:04:59:26.933633260 -0400] - ERR - NSACLPlugin - acl_parse - The ACL target cn=retrieve certificate,cn=virtual operations,cn=etc,dc=testrelm,dc=test does not exist
[15/May/2017:04:59:26.938329231 -0400] - ERR - NSACLPlugin - acl_parse - The ACL target cn=request certificate,cn=virtual operations,cn=etc,dc=testrelm,dc=test does not exist
[15/May/2017:04:59:26.942974127 -0400] - ERR - NSACLPlugin - acl_parse - The ACL target cn=request certificate different host,cn=virtual operations,cn=etc,dc=testrelm,dc=test does not exist
[15/May/2017:04:59:26.947506342 -0400] - ERR - NSACLPlugin - acl_parse - The ACL target cn=certificate status,cn=virtual operations,cn=etc,dc=testrelm,dc=test does not exist
[15/May/2017:04:59:26.952206118 -0400] - ERR - NSACLPlugin - acl_parse - The ACL target cn=revoke certificate,cn=virtual operations,cn=etc,dc=testrelm,dc=test does not exist
[15/May/2017:04:59:26.956960163 -0400] - ERR - NSACLPlugin - acl_parse - The ACL target cn=certificate remove hold,cn=virtual operations,cn=etc,dc=testrelm,dc=test does not exist

Expected results: Fix the error message.

Additional info:

Comment 8 Nathan Kinder 2017-05-18 15:08:43 UTC


*** This bug has been marked as a duplicate of bug 1437492 ***