OID 11g Intermittent Timeouts From Applications or LDAP Searches (ldapsearch). OID Server Log Shows: OID-5019: Cataloging For Attr <attribute> Was Not Performed, As Resource Is Busy. Alert Log Shows High OPTime

(Doc ID 2396734.1)

Last updated on MAY 09, 2018

Applies to:

Oracle Internet Directory - Version 11.1.1 and later
Information in this document applies to any platform.

Symptoms

Oracle Internet Directory (OID) 11g, e.g., 11.1.1.9.0.

OID servicing multiple applications and users for authentication and synchronization.

Since going to production, applications are getting timeouts while communicating with OID.

Sometimes it takes a while to open ldap entries in ldap browsers.

When using command line ldapsearch it is almost always fast (responds in milliseconds), but sometimes it also times out.

The OID server logs show the following messages, for example:

ConnID:4564 mesgID:2 OpID:1  OpName:search ConnIP:::ffff:<IP Address:Port> ConnDN:cn=user1,cn=users,dc=example,dc=com
gslbCallCatalog: OID-5019: Cataloging for attr memberof was not performed, as resource is busy.
END
]]
[2018-05-07T13:16:22.703381+02:00] [OID] [NOTIFICATION:16] [] [OIDLDAPD] [host: myoidhost.example.com] [pid: 181206] [tid: 19]  ServerWorker (REG):[[
BEGIN
ConnID:4564 mesgID:2 OpID:1  OpName:search ConnIP:::ffff:<IP Address:Port> ConnDN:cn=user1,cn=users,dc=example,dc=com
gslbmAutoCatalog: Initiated catalog with status = 2 for attribute memberof:OID-5019: Cataloging for attr memberof was not performed, as resource is busy.
END
]]


The alert logs show high or negative high OPTime (operation times):

2018-05-07T13:58:23.988059][PID:181210][TID:23][connDN:cn=user1,cn=users,dc=example,dc=com][connID:4533][connAddr:::ffff:<IP Address:Port>][ECID:<ECID number>][OP:Search][BaseDN:cn=users,dc=example,dc=com][Filter:(&(&(objectclass=person)(orclmemberof=cn=group1,cn=groups,dc=example,dc=com))(cn=user1))][scp:2][ra:uid mail givenname cn sn ][nentries:0][OPTime:-1056545519 micro sec]
...
[2018-05-07T13:59:30.246265][PID:181210][TID:8][connDN:cn=orcladmin][connID:4636][connAddr:::ffff:<IP Address:Port>][ECID:<ECID number>][OP:Search][BaseDN:dc=example,dc=com][Filter:(objectclass=top)][scp:2][ra:cn orclguid ][nentries:9722][OPTime:8163848 micro sec]



Changes

 

Cause

Sign In with your My Oracle Support account

Don't have a My Oracle Support account? Click to get started

My Oracle Support provides customers with access to over a
Million Knowledge Articles and hundreds of Community platforms