My Oracle Support Banner

Searching is Very Slow for Non-Administrative Users Following Upgrade to WebCenter Content (WCC) 11.1.1.8.0 (Doc ID 1681107.1)

Last updated on JUNE 01, 2018

Applies to:

Oracle WebCenter Content - Version 11.1.1.8.0 and later
Information in this document applies to any platform.

Symptoms

Non-administrative users are experiencing severe performance problems with searching. Users with administrative privileged appear to be fine.  Instead of taking the typical one second or so for searches, they're timing out and the results are never returned.  The content server log file shows this error message after a while.

Error 6/7/14 5:28 PM Event generated by user 'user1' at host 'contentserver.company.com'. Encountered an exception while executing a query. [ Details ]

An error has occurred. The stack trace below shows more information.

!csUserEventMessage,user1,contentserver.company.com!$!csJdbcGenericError
intradoc.common.ServiceException: !csJdbcGenericError
*ScriptStack GET_SEARCH_RESULTS
3:getSearchResults,**no captured values**
       at intradoc.server.SearchManager.retrieveSearchInfoAsBinder(SearchManager.java:297)
       at intradoc.server.SearchService.retrieveSearchInfo(SearchService.java:1990)
       at intradoc.server.SearchService.doLocalSearch(SearchService.java:1458)
       at intradoc.server.SearchService.getSearchResults(SearchService.java:697)
       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
       at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
       at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
       at java.lang.reflect.Method.invoke(Method.java:606)
       at intradoc.common.IdcMethodHolder.invokeMethod(IdcMethodHolder.java:86)
       at intradoc.common.ClassHelperUtils.executeMethodEx(ClassHelperUtils.java:310)
       at intradoc.common.ClassHelperUtils.executeMethod(ClassHelperUtils.java:295)
       at intradoc.server.Service.doCodeEx(Service.java:637)
       at intradoc.server.Service.doCode(Service.java:592)
       at intradoc.server.ServiceRequestImplementor.doAction(ServiceRequestImplementor.java:1693)
       at intradoc.server.Service.doAction(Service.java:564)
       at intradoc.server.ServiceRequestImplementor.doActions(ServiceRequestImplementor.java:1483)
       at intradoc.server.Service.doActions(Service.java:559)
       at intradoc.server.ServiceRequestImplementor.executeActions(ServiceRequestImplementor.java:1415)
       at intradoc.server.Service.executeActions(Service.java:545)
       at intradoc.server.ServiceRequestImplementor.doRequest(ServiceRequestImplementor.java:751)
       at intradoc.server.Service.doRequest(Service.java:1974)
       at intradoc.server.ServiceManager.processCommand(ServiceManager.java:486)
       at intradoc.server.IdcServerThread.processRequest(IdcServerThread.java:265)
       at intradoc.idcwls.IdcServletRequestUtils.doRequest(IdcServletRequestUtils.java:1355)
       at intradoc.idcwls.IdcServletRequestUtils.processFilterEvent(IdcServletRequestUtils.java:1748)
       at intradoc.idcwls.IdcIntegrateWrapper.processFilterEvent(IdcIntegrateWrapper.java:223)
       at sun.reflect.GeneratedMethodAccessor595.invoke(Unknown Source)
       at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
       at java.lang.reflect.Method.invoke(Method.java:606)
       at idcservlet.common.IdcMethodHolder.invokeMethod(IdcMethodHolder.java:87)
       at idcservlet.common.ClassHelperUtils.executeMethodEx(ClassHelperUtils.java:305)
       at idcservlet.common.ClassHelperUtils.executeMethodWithArgs(ClassHelperUtils.java:278)
       at idcservlet.ServletUtils.executeContentServerIntegrateMethodOnConfig(ServletUtils.java:1680)
       at idcservlet.IdcFilter.doFilter(IdcFilter.java:457)
       at weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:60)
       at oracle.security.jps.ee.http.JpsAbsFilter$1.run(JpsAbsFilter.java:119)
       at java.security.AccessController.doPrivileged(Native Method)
       at oracle.security.jps.util.JpsSubject.doAsPrivileged(JpsSubject.java:324)
       at oracle.security.jps.ee.util.JpsPlatformUtil.runJaasMode(JpsPlatformUtil.java:460)
       at oracle.security.jps.ee.http.JpsAbsFilter.runJaasMode(JpsAbsFilter.java:103)
       at oracle.security.jps.ee.http.JpsAbsFilter.doFilter(JpsAbsFilter.java:171)
       at oracle.security.jps.ee.http.JpsFilter.doFilter(JpsFilter.java:71)
       at weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:60)
       at oracle.dms.servlet.DMSServletFilter.doFilter(DMSServletFilter.java:163)
       at weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:60)
       at oracle.security.jps.ee.http.JpsAbsFilter$1.run(JpsAbsFilter.java:119)
       at java.security.AccessController.doPrivileged(Native Method)
       at oracle.security.jps.util.JpsSubject.doAsPrivileged(JpsSubject.java:324)
       at oracle.security.jps.ee.util.JpsPlatformUtil.runJaasMode(JpsPlatformUtil.java:460)
       at oracle.security.jps.ee.http.JpsAbsFilter.runJaasMode(JpsAbsFilter.java:103)
       at oracle.security.jps.ee.http.JpsAbsFilter.doFilter(JpsAbsFilter.java:171)
       at oracle.security.jps.ee.http.JpsFilter.doFilter(JpsFilter.java:71)
       at weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:60)
       at weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.wrapRun(WebAppServletContext.java:3748)
       at weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.run(WebAppServletContext.java:3714)
       at weblogic.security.acl.internal.AuthenticatedSubject.doAs(AuthenticatedSubject.java:321)
       at weblogic.security.service.SecurityManager.runAs(SecurityManager.java:120)
       at weblogic.servlet.internal.WebAppServletContext.securedExecute(WebAppServletContext.java:2283)
       at weblogic.servlet.internal.WebAppServletContext.execute(WebAppServletContext.java:2182)
       at weblogic.servlet.internal.ServletRequestImpl.run(ServletRequestImpl.java:1491)
       at weblogic.work.ExecuteThread.execute(ExecuteThread.java:256)
       at weblogic.work.ExecuteThread.run(ExecuteThread.java:221)

If we use <Note 1673300.1> and have SecureDataAllowedIntoStatusMessage=true set as a configuration entry, we can get more information from content server log files and systemdatabase tracing as well as the System Audit Information page. 

idccs_UCM_server1_1402174945000.log:>searchquery/7 06.07 16:54:10.714 IdcServer-49 Query by user1 from testserver.company.com
idccs_UCM_server1_1402174945000.log:>searchquery/6 06.07 16:54:10.733 IdcServer-49 preparedQueryText: <qsch>MyCompanyName</qsch>
idccs_UCM_server1_1402174945000.log:>searchquery/6 06.07 16:54:10.745 IdcServer-49 Setting substr to other operator conv flag: '<qsch>MyCompanyName</qsch>'
idccs_UCM_server1_1402174945000.log:>searchquery/6 06.07 16:54:10.745 IdcServer-49 Parsing universal query: '<qsch>MyCompanyName</qsch>'
idccs_UCM_server1_1402174945000.log:>searchquery/6 06.07 16:54:10.749 IdcServer-49 Converted native query: ' ((DEFINESCORE((MyCompanyName), RELEVANCE * .1)) )'
...................
idccs_UCM_server1_1402174945000.log:>searchquery/6 06.07 16:54:10.766 IdcServer-49 query(live): ((((DEFINESCORE((MyCompanyName), RELEVANCE * .1)) )))
and  ((((((z49504D5359535F4150505F30) WITHIN zdSecurityGroup))
or (((z4B42434F4E54454E54) WITHIN zdSecurityGroup))
or (((z504552534F4E414C535041434553) WITHIN zdSecurityGroup))
or (((z5055424C4943) WITHIN zdSecurityGroup))
or (((z57454243454E544552535041434553) WITHIN zdSecurityGroup)))
and ((((dummy%) WITHIN dDocAccount))
or (((CS/AllUS%) WITHIN dDocAccount))
or (((CS/Account1%) WITHIN dDocAccount))
or (((CS/Account2%) WITHIN dDocAccount))
or (((CS/Account3%) WITHIN dDocAccount))
or (((CS/Account4%) WITHIN dDocAccount))
or (((CS/Account5%) WITHIN dDocAccount))
or (((CS/Account6%) WITHIN dDocAccount))
or (((CS/Account7%) WITHIN dDocAccount))
or (((CS/Account8%) WITHIN dDocAccount))
or (((CS/Account9%) WITHIN dDocAccount))
or (((CS/AccountA%) WITHIN dDocAccount))
or (((idcnull) WITHIN dDocAccount))
or (((CS/AccountB%) WITHIN dDocAccount))
or (((CS/AccountC%) WITHIN dDocAccount))
or (((cs/AccountD%) WITHIN dDocAccount))
or (((CS/AccountE%) WITHIN dDocAccount))
or (((CS/AccountF%) WITHIN dDocAccount))
or (((CS/AccountG%) WITHIN dDocAccount))
or (((CS/AccountH%) WITHIN dDocAccount))
or (((CS/AccountI%) WITHIN dDocAccount))
or (((CS/AccountJ%) WITHIN dDocAccount))
or (((CS/AccountK%) WITHIN dDocAccount))
or (((AccountOne%) WITHIN dDocAccount))
or (((PEWebCenter/Account1%) WITHIN dDocAccount))
or (((WebcenterSpaces/Account1%) WITHIN dDocAccount))
or (((PUBLIC%) WITHIN dDocAccount))
or (((PRIVATE%) WITHIN dDocAccount))
or (((sceeabe83d0324eb8ae3d9b9dcfe7134a%) WITHIN dDocAccount))
or (((s7a5123c116954c7ea28717bc607c642a%) WITHIN dDocAccount))))) [1,20] sort(dInDate Desc)
idccs_UCM_server1_1402174945000.log:>systemdatabase/6 06.07 16:54:10.767 IdcServer-49 !csMonitorReadActions,1224
...................
idccs_UCM_server1_1402174945000.log:>systemdatabase/6 06.07 16:54:11.196 IdcServer-49 Parameters: ([indexName:FT_IdcText2],[tableName:IdcText2],
[queryText:((((DEFINESCORE((MyCompanyName), RELEVANCE * .1)) )))
and  ((((((z49504D5359535F4150505F30) WITHIN zdSecurityGroup))
or (((z4B42434F4E54454E54) WITHIN zdSecurityGroup))
or (((z504552534F4E414C535041434553) WITHIN zdSecurityGroup))
or (((z5055424C4943) WITHIN zdSecurityGroup))
or (((z57454243454E544552535041434553) WITHIN zdSecurityGroup)))
and ((((dummy%) WITHIN dDocAccount))
or (((CS/AllUS%) WITHIN dDocAccount))
or (((CS/Account1%) WITHIN dDocAccount))
or (((CS/Account2%) WITHIN dDocAccount))
or (((CS/Account3%) WITHIN dDocAccount))
or (((CS/Account4%) WITHIN dDocAccount))
or (((CS/Account5%) WITHIN dDocAccount))
or (((CS/Account6%) WITHIN dDocAccount))
or (((CS/Account7%) WITHIN dDocAccount))
or (((CS/Account8%) WITHIN dDocAccount))
or (((CS/Account9%) WITHIN dDocAccount))
or (((CS/AccountA%) WITHIN dDocAccount))
or (((idcnull) WITHIN dDocAccount))
or (((CS/AccountB%) WITHIN dDocAccount))
or (((CS/AccountC%) WITHIN dDocAccount))
or (((cs/AccountD%) WITHIN dDocAccount))
or (((CS/AccountE%) WITHIN dDocAccount))
or (((CS/AccountF%) WITHIN dDocAccount))
or (((CS/AccountG%) WITHIN dDocAccount))
or (((CS/AccountH%) WITHIN dDocAccount))
or (((CS/AccountI%) WITHIN dDocAccount))
or (((CS/AccountJ%) WITHIN dDocAccount))
or (((CS/AccountK%) WITHIN dDocAccount))
or (((AccountOne%) WITHIN dDocAccount))
or (((PEWebCenter/Account1%) WITHIN dDocAccount))
or (((WebcenterSpaces/Account1%) WITHIN dDocAccount))
or (((PUBLIC%) WITHIN dDocAccount))
or (((PRIVATE%) WITHIN dDocAccount))
or (((sceeabe83d0324eb8ae3d9b9dcfe7134a%) WITHIN dDocAccount))
or (((s7a5123c116954c7ea28717bc607c642a%) WITHIN dDocAccount)))))*10.0*10.0],
[returnFields:dDocName,dDocTitle,dDocType,dSecurityGroup,dInDate,xEFDocType,xHidden,xIPMSYS_BATCH_ID1,
xKBSubject,xWebsiteObjectType,xWebsiteSection,xDontShowInListsForWebsites,dRevisionID,dWebExtension,
xEFCategory,xRegionDefinition,xIPMSYS_BATCH_SEQ,xWCWorkflowAssignment,dDocCreatedDate,xIPMSYS_APP_ID,
dCreateDate,xWCTags,dOriginalName,dFormat,dPublishType,dDocFunction,xPortletItem,VaultFileSize,xKBEndDate,
xWebFlag,dOutDate,dGif,dExtension,dDocLastModifiedDate,xClbraRoleList,xInhibitUpdate,URL,AlternateFormat,
WebFileSize,xKBSubCategory,xClbraUserList,xKBStartDate,xReadOnly,dRevClassID,xWCWorkflowApproverUserList,
xHCMTransactionID,xCollectionID,xComments,xPartitionId,dRevLabel,xStorageRule,xAccessRoles,xKBCategory,
dFullTextFormat,xWebsites,dRendition2,xEMPID,dRendition1,dID,dDocAccount,xEmpMMDD,xIdcProfile,xClbraAliasList,
xCOE,otsFormat,otsCharset,otsLanguage,xDiscussionCount,xIPM_APP_0_24,xParentFolders,dDocClass,xIPM_APP_1_13,
xIPM_APP_1_10,xPackagedConversions,xIPM_APP_1_6,xIPM_APP_2_8,xIPM_APP_1_5,xIPM_APP_1_18,xIPM_APP_1_8,
xIPM_APP_1_19,xIPM_APP_1_7,xIPM_APP_1_16,xIPM_APP_1_17,xIPM_APP_2_5,xIPM_APP_1_14,xLibraryGUID,
xVideoRenditions,xIPM_APP_2_6,xIPM_APP_1_15,xIPM_APP_2_7,xIPM_APP_2_10,xDiscussionType,xIPM_APP_2_0,
xIPM_APP_2_1,xIPM_APP_2_2,xIPM_APP_1_0,xIPM_APP_2_14,xIPM_APP_1_2,xIPM_APP_2_13,xIPM_APP_1_1,xIPM_APP_0_2,
xIPM_APP_0_5,xIPM_APP_0_1,xIPM_APP_0_10,xIPM_APP_0_0,xIPM_APP_1_24,xIPM_APP_1_23,xIPM_APP_1_22,xIPM_APP_1_21,
xIPM_APP_0_7,xIPM_APP_1_20,xIPM_APP_0_6,xIPM_APP_0_8,xIPM_APP_0_14,xIPM_APP_0_13,xIPM_APP_0_16,xIPM_APP_2_21,
xIPM_APP_0_15,xIPM_APP_2_20,xIPM_APP_2_23,xIPM_APP_0_18,xIPM_APP_0_17,xIPM_APP_2_22,xIPM_APP_2_24,
xIPM_APP_0_19,xIPM_APP_2_15,xIPM_APP_2_16,xIPM_APP_2_17,xIPM_APP_2_18,xIPM_APP_2_19,xIPM_APP_0_20,
xIPM_APP_0_21,xIPM_APP_0_22,xIPM_APP_0_23,xDamConversionType],
[resultDescriptor:<ctx_result_set_descriptor><count exact="false"/><group sdata="sdDrillDown">
<count exact="true"/></group><hitlist start_hit_num="1" end_hit_num="20" order="dInDate Desc">
...................

idccs_UCM_server1_1402177278000.log:>systemdatabase/7 06.07 17:28:30.413 IdcServer-49 (start) SELECT 1 FROM DUAL
idccs_UCM_server1_1402177278000.log:>systemdatabase/6 06.07 17:28:30.414 IdcServer-49 1.16 ms. SELECT 1 FROM DUAL[Executed. Returned row(s): true]
idccs_UCM_server1_1402177278000.log:>systemdatabase/6 06.07 17:28:30.414 IdcServer-49 !csDbUnableToExecuteCallableQuery,CotsSearchQuery!$ORA-20000: Oracle Text error:
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 DRG-50857: oracle error in ctx_query.result_set
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-20000: Oracle Text error:
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 DRG-50850: ORA-01013: user requested cancel of current operation
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "CTXSYS.DRUE"\, line 160
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "CTXSYS.CTX_QUERY"\, line 800
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "TST_OCS.CONTENT_SEARCH"\, line 123
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at line 1
idccs_UCM_server1_1402177278000.log:>systemdatabase/6 06.07 17:28:30.414 IdcServer-49 2059254 ms. Executing CallableStatement ({call content_search.search_with_resultsetinterface(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)})[ORA-20000: Oracle Text error:
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 DRG-50857: oracle error in ctx_query.result_set
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-20000: Oracle Text error:
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 DRG-50850: ORA-01013: user requested cancel of current operation
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "CTXSYS.DRUE", line 160
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "CTXSYS.CTX_QUERY", line 800
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "TST_OCS.CONTENT_SEARCH", line 123
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at line 1
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ]
idccs_UCM_server1_1402177278000.log:>systemdatabase/6 06.07 17:28:30.414 IdcServer-49 !csMonitorTotalAuditMessages,2
idccs_UCM_server1_1402177278000.log:>systemdatabase/6 06.07 17:28:30.414 IdcServer-49 {[IdcServer-49([ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)').2(Not In Transaction) Active time:  2059s]}
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 {( Action executed
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 Executing CallableStatement ({call content_search.search_with_resultsetinterface(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)})[ORA-20000: Oracle Text error:
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 DRG-50857: oracle error in ctx_query.result_set
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-20000: Oracle Text error:
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 DRG-50850: ORA-01013: user requested cancel of current operation
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "CTXSYS.DRUE", line 160
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "CTXSYS.CTX_QUERY", line 800
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at "TST_OCS.CONTENT_SEARCH", line 123
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ORA-06512: at line 1
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 ] )}
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 Long execution starting at 6/7/14 5:28 PM for 2059 (secs)
idccs_UCM_server1_1402177278000.log: systemdatabase/6 06.07 17:28:30.414 IdcServer-49 Long connection active time at 6/7/14 5:28 PM for 2059 (secs)

<Note 1333414.1> Convert the querytext to a SQL statement that can be run from SQL Developer (note:query takes over 2000 seconds to complete in above example).

Changes

 The WCC server was upgraded from 11.1.1.4.0 to 11.1.1.8.0.

Cause

To view full details, sign in with your My Oracle Support account.

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


In this Document
Symptoms
Changes
Cause
Solution
 Solution One
 Solution Two
 Solution Three
References


My Oracle Support provides customers with access to over a million knowledge articles and a vibrant support community of peers and Oracle experts.