Skip to main content

Hello,

I am getting time out error while saving custom field on IFS Cloud upgrade Project/Page Designer.

On OData POD i am getting following error

{"type":"liberty_message","host":"ifsapp-odata-bff474985-2d2zw","ibm_userDir":"\/opt\/ifs\/wlp\/usr\/","ibm_serverName":"defaultServer","message":"TRAS0112W: Request AAAAGga7Fry_AAAAAAAAACe has been running on thread 00000038 for at least 10000.992ms. The following stack trace shows what this thread is currently running.\n\n \t at java.base@11.0.15\/sun.nio.ch.FileDispatcherImpl.read0(Native Method)\n\t at java.base@11.0.15\/sun.nio.ch.SocketDispatcher.read(Unknown Source)\n\t at java.base@11.0.15\/sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source)\n\t at java.base@11.0.15\/sun.nio.ch.IOUtil.read(Unknown Source)\n\t at java.base@11.0.15\/sun.nio.ch.IOUtil.read(Unknown Source)\n\t at java.base@11.0.15\/sun.nio.ch.SocketChannelImpl.read(Unknown Source)\n\t at oracle.net.nt.TimeoutSocketChannel.read(TimeoutSocketChannel.java:174)\n\t at oracle.net.ns.NSProtocolNIO.doSocketRead(NSProtocolNIO.java:555)\n\t at oracle.net.ns.NIOPacket.readHeader(NIOPacket.java:258)\n\t at oracle.net.ns.NIOPacket.readPacketFromSocketChannel(NIOPacket.java:190)\n\t at oracle.net.ns.NIOPacket.readFromSocketChannel(NIOPacket.java:132)\n\t at oracle.net.ns.NIOPacket.readFromSocketChannel(NIOPacket.java:105)\n\t at oracle.net.ns.NIONSDataChannel.readDataFromSocketChannel(NIONSDataChannel.java:91)\n\t at oracle.jdbc.driver.T4CMAREngineNIO.prepareForUnmarshall(T4CMAREngineNIO.java:764)\n\t at oracle.jdbc.driver.T4CMAREngineNIO.unmarshalUB1(T4CMAREngineNIO.java:429)\n\t at oracle.jdbc.driver.T4CTTIfun.receive(T4CTTIfun.java:407)\n\t at oracle.jdbc.driver.T4CTTIfun.doRPC(T4CTTIfun.java:268)\n\t at oracle.jdbc.driver.T4C8Oall.doOALL(T4C8Oall.java:655)\n\t at oracle.jdbc.driver.T4CPreparedStatement.doOall8(T4CPreparedStatement.java:270)\n\t at oracle.jdbc.driver.T4CPreparedStatement.doOall8(T4CPreparedStatement.java:91)\n\t at oracle.jdbc.driver.T4CPreparedStatement.executeForRows(T4CPreparedStatement.java:970)\n\t at oracle.jdbc.driver.OracleStatement.executeMaybeDescribe(OracleStatement.java:1012)\n\t at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:1168)\n\t at oracle.jdbc.driver.OraclePreparedStatement.executeInternal(OraclePreparedStatement.java:3666)\n\t at oracle.jdbc.driver.T4CPreparedStatement.executeInternal(T4CPreparedStatement.java:1426)\n\t at oracle.jdbc.driver.OraclePreparedStatement.executeQuery(OraclePreparedStatement.java:3713)\n\t at oracle.jdbc.driver.OraclePreparedStatementWrapper.executeQuery(OraclePreparedStatementWrapper.java:1167)\n\t at oracle.ucp.jdbc.proxy.oracle$1ucp$1jdbc$1proxy$1oracle$1StatementProxy$2oracle$1jdbc$1internal$1OraclePreparedStatement$$$Proxy.executeQuery(Unknown Source)\n\t at com.ibm.ws.rsadapter.jdbc.WSJdbcPreparedStatement.executeQuery(WSJdbcPreparedStatement.java:475)\n\t at com.ifsworld.fnd.storage.SqlStatementExecutor.executeStatement(SqlStatementExecutor.java:173)\n\t at com.ifsworld.fnd.storage.SqlStatementExecutor.execute(SqlStatementExecutor.java:149)\n\t at com.ifsworld.fnd.storage.SqlStatementExecutor.execute(SqlStatementExecutor.java:77)\n\t at com.ifsworld.fnd.storage.invoker.SqlOperationInvoker.invoke(SqlOperationInvoker.java:57)\n\t at com.ifsworld.fnd.storage.invoker.BpaInvokerWrapper.invoke(BpaInvokerWrapper.java:170)\n\t at com.ifsworld.fnd.provider.processor.FndPrimitiveProcessor.readPrimitive(FndPrimitiveProcessor.java:215)\n\t at org.apache.olingo.server.core.ODataDispatcher.handlePrimitiveDispatching(ODataDispatcher.java:456)\n\t at org.apache.olingo.server.core.ODataDispatcher.handleFunctionDispatching(ODataDispatcher.java:202)\n\t at org.apache.olingo.server.core.ODataDispatcher.handleResourceDispatching(ODataDispatcher.java:146)\n\t at org.apache.olingo.server.core.ODataDispatcher.dispatch(ODataDispatcher.java:117)\n\t at org.apache.olingo.server.core.ODataHandlerImpl.processInternal(ODataHandlerImpl.java:164)\n\t at org.apache.olingo.server.core.ODataHandlerImpl.process(ODataHandlerImpl.java:85)\n\t at com.ifsworld.fnd.provider.ODataHandlerWrapper.process(ODataHandlerWrapper.java:64)\n\t at com.ifsworld.fnd.provider.ODataHandlerWrapper.process(ODataHandlerWrapper.java:77)\n\t at com.ifsworld.fnd.web.api.FndODataServletBase.serviceODataRequest(FndODataServletBase.java:329)\n\t at com.ifsworld.fnd.web.api.FndODataServletBase.serviceProjectionRequest(FndODataServletBase.java:162)\n\t at com.ifsworld.fnd.web.api.FndODataServletBase.service(FndODataServletBase.java:112)\n\t at javax.servlet.http.HttpServlet.service(HttpServlet.java:791)\n\t at com.ibm.ws.webcontainer.servlet.ServletWrapper.service(ServletWrapper.java:1258)\n\t at com.ibm.ws.webcontainer.servlet.ServletWrapper.handleRequest(ServletWrapper.java:746)\n\t at com.ibm.ws.webcontainer.servlet.ServletWrapper.handleRequest(ServletWrapper.java:443)\n\t at com.ibm.ws.webcontainer.filter.WebAppFilterChain.invokeTarget(WebAppFilterChain.java:193)\n\t at com.ibm.ws.webcontainer.filter.WebAppFilterChain.doFilter(WebAppFilterChain.java:98)\n\t at com.ifsworld.fnd.web.api.ResponseFilterBase.doFilter(ResponseFilterBase.java:60)\n\t at com.ibm.ws.webcontainer.filter.FilterInstanceWrapper.doFilter(FilterInstanceWrapper.java:201)\n\t at com.ibm.ws.webcontainer.filter.WebAppFilterChain.doFilter(WebAppFilterChain.java:91)\n\t at com.ifsworld.fnd.web.api.InitConfigurationFilterBase.doFilter(InitConfigurationFilterBase.java:201)\n\t at com.ibm.ws.webcontainer.filter.FilterInstanceWrapper.doFilter(FilterInstanceWrapper.java:201)\n\t at com.ibm.ws.webcontainer.filter.WebAppFilterChain.doFilter(WebAppFilterChain.java:91)\n\t at com.ibm.ws.security.jaspi.JaspiServletFilter.doFilter(JaspiServletFilter.java:56)\n\t at com.ibm.ws.webcontainer.filter.FilterInstanceWrapper.doFilter(FilterInstanceWrapper.java:201)\n\t at com.ibm.ws.webcontainer.filter.WebAppFilterChain.doFilter(WebAppFilterChain.java:91)\n\t at com.ibm.ws.webcontainer.filter.WebAppFilterManager.doFilter(WebAppFilterManager.java:1002)\n\t at com.ibm.ws.webcontainer.filter.WebAppFilterManager.invokeFilters(WebAppFilterManager.java:1140)\n\t at com.ibm.ws.webcontainer.webapp.WebApp.handleRequest(WebApp.java:5049)\n\t at com.ibm.ws.webcontainer.osgi.DynamicVirtualHost$2.handleRequest(DynamicVirtualHost.java:316)\n\t at com.ibm.ws.webcontainer.WebContainer.handleRequest(WebContainer.java:1007)\n\t at com.ibm.ws.webcontainer.osgi.DynamicVirtualHost$2.run(DynamicVirtualHost.java:281)\n\t at com.ibm.ws.http.dispatcher.internal.channel.HttpDispatcherLink$TaskWrapper.run(HttpDispatcherLink.java:1184)\n\t at com.ibm.ws.http.dispatcher.internal.channel.HttpDispatcherLink.wrapHandlerAndExecute(HttpDispatcherLink.java:453)\n\t at com.ibm.ws.http.dispatcher.internal.channel.HttpDispatcherLink.ready(HttpDispatcherLink.java:412)\n\t at com.ibm.ws.http.channel.internal.inbound.HttpInboundLink.handleDiscrimination(HttpInboundLink.java:566)\n\t at com.ibm.ws.http.channel.internal.inbound.HttpInboundLink.handleNewRequest(HttpInboundLink.java:500)\n\t at com.ibm.ws.http.channel.internal.inbound.HttpInboundLink.processRequest(HttpInboundLink.java:360)\n\t at com.ibm.ws.http.channel.internal.inbound.HttpICLReadCallback.complete(HttpICLReadCallback.java:70)\n\t at com.ibm.ws.tcpchannel.internal.WorkQueueManager.requestComplete(WorkQueueManager.java:504)\n\t at com.ibm.ws.tcpchannel.internal.WorkQueueManager.attemptIO(WorkQueueManager.java:574)\n\t at com.ibm.ws.tcpchannel.internal.WorkQueueManager.workerRun(WorkQueueManager.java:958)\n\t at com.ibm.ws.tcpchannel.internal.WorkQueueManager$Worker.run(WorkQueueManager.java:1047)\n\t at com.ibm.ws.threading.internal.ExecutorServiceImpl$RunnableWrapper.run(ExecutorServiceImpl.java:238)\n\t at java.base@11.0.15\/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)\n\t at java.base@11.0.15\/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)\n\t at java.base@11.0.15\/java.lang.Thread.run(Unknown Source)\n\nThe following table shows the events that have run during this request.\n\nDuration      Operation\n10002.879ms + websphere.servlet.service | ifs-odata-provider | OData-Provider | \/AurenaPageDesigner.svc\/GetOriginalMetadata(ModelId='ClientMetadata.client:ProjectMonitoring',ExcludeProjection=false)\n    2.432ms       websphere.datasource.psExecute | jdbc\/fndbas-odp | {call Login_SYS.Init_ODP_Sys_Session_()}\n 5079.416ms       websphere.datasource.psExecute | jdbc\/fndbas-odp | DECLARE\nBEGIN\n? := Fnd_Odata_Provider_Sys.Get_Projection_Metadata(?,?,?);\nEND;\n    0.488ms       websphere.datasource.psExecute | jdbc\/fndbas-odp | DECLARE\nBEGIN\nBpa_Sys.Init_Session;\nEND;\n    6.528ms       websphere.datasource.psExecute | jdbc\/fndbas-odp | {? = call Login_SYS.Init_Odp_Session_(?,?,?,?,?,?,?,?,?,?,?,?)}\n    0.775ms       websphere.datasource.psExecuteQuery | jdbc\/fndbas-odp | select process_key, workflow_execution_db, workflow_timing_db, projection_name, action, entity_set_name, call_name, is_read_enabled, is_create_enabled, is_update_enabled, is_delete_enabled   \r\nfrom BPMN_PROJECTION\r\ncross join (select ? as A, ? as B, ? as C, ? as D, ? as E,  ? as F from dual) D \r\nwhere  (A is null or A = projection_name) \r\n   AND (B is null or process_key = 😎 \r\n   AND (C is null or (C = 'R' and is_read_enabled = 'TRUE') or (C = 'C' and is_create_enabled = 'TRUE') or (C = 'U' and is_update_enabled = 'TRUE') or (C = 'D' and is_delete_enabled = 'TRUE')) \r\n   AND (is_enabled = 'TRUE') \r\n   AND (D is null or entity_set_name = D) \r\n   AND (E is null or call_name = E) \r\n   AND (F is null or workflow_timing_db = F)\n    0.407ms       websphere.datasource.psExecuteQuery | jdbc\/fndbas-odp | select process_key, workflow_execution_db, workflow_timing_db, projection_name, action, entity_set_name, call_name, is_read_enabled, is_create_enabled, is_update_enabled, is_delete_enabled   \r\nfrom BPMN_PROJECTION\r\ncross join (select ? as A, ? as B, ? as C, ? as D, ? as E,  ? as F from dual) D \r\nwhere  (A is null or A = projection_name) \r\n   AND (B is null or process_key = 😎 \r\n   AND (C is null or (C = 'R' and is_read_enabled = 'TRUE') or (C = 'C' and is_create_enabled = 'TRUE') or (C = 'U' and is_update_enabled = 'TRUE') or (C = 'D' and is_delete_enabled = 'TRUE')) \r\n   AND (is_enabled = 'TRUE') \r\n   AND (D is null or entity_set_name = D) \r\n   AND (E is null or call_name = E) \r\n   AND (F is null or workflow_timing_db = F)\n    0.349ms       websphere.datasource.psExecuteQuery | jdbc\/fndbas-odp | select process_key, workflow_execution_db, workflow_timing_db, projection_name, action, entity_set_name, call_name, is_read_enabled, is_create_enabled, is_update_enabled, is_delete_enabled   \r\nfrom BPMN_PROJECTION\r\ncross join (select ? as A, ? as B, ? as C, ? as D, ? as E,  ? as F from dual) D \r\nwhere  (A is null or A = projection_name) \r\n   AND (B is null or process_key = 😎 \r\n   AND (C is null or (C = 'R' and is_read_enabled = 'TRUE') or (C = 'C' and is_create_enabled = 'TRUE') or (C = 'U' and is_update_enabled = 'TRUE') or (C = 'D' and is_delete_enabled = 'TRUE')) \r\n   AND (is_enabled = 'TRUE') \r\n   AND (D is null or entity_set_name = D) \r\n   AND (E is null or call_name = E) \r\n   AND (F is null or workflow_timing_db = F)\n    5.446ms       websphere.datasource.psExecute | jdbc\/fndbas-odp | {? = call Login_SYS.Init_Odp_Session_(?,?,?,?,?,?,?,?,?,?,?,?)}\n 4827.389ms +     websphere.datasource.psExecuteQuery | jdbc\/fndbas-odp | SELECT * FROM TABLE(Aurena_Page_Designer_SVC.Rd_Get_Original_Metadata(?, ?, unbound## => '')) ","ibm_threadId":"00000024","ibm_datetime":"2023-06-06T13:19:10.548+0000","ibm_messageId":"TRAS0112W","module":"com.ibm.ws.request.timing.manager.SlowRequestManager","loglevel":"WARNING","ibm_sequence":"1686057550548_0000000000072"}

PLease suggest

Regards

Pankaj 

Hello,

Oracle trace has generated. 


 

*** 2023-06-06T13:06:27.231918+04:00 (CDB$ROOT(1))
Required IPC RDMAV_FORK_SAFE environment not set
Required IPC RDMAV_HUGEPAGES_SAFE environment not set
2023-06-06 15:45:23.518*:ksq.c@13169:ksqdld_hdr_dump(): 

*** 2023-06-06T15:45:23.518502+04:00 (DEVPDB(3))
*** SESSION ID:(1084.48203) 2023-06-06T15:45:23.518502+04:00
*** CLIENT ID:(ODP-IFSAPP-IFSAPP-ODATA-BFF474985-QBPB4) 2023-06-06T15:45:23.518502+04:00
*** SERVICE NAME:(devpdb) 2023-06-06T15:45:23.518502+04:00
*** MODULE NAME:(Aurena_Page_Designer_SVC) 2023-06-06T15:45:23.518502+04:00
*** ACTION NAME:(Do_Publish_Configurations) 2023-06-06T15:45:23.518502+04:00
*** CLIENT DRIVER:(jdbcthin : 19.3.0.0.0) 2023-06-06T15:45:23.518502+04:00
*** CONTAINER ID:(3) 2023-06-06T15:45:23.518502+04:00
 
 xid: 0x000a.012.00004f7fDEADLOCK DETECTED ( ORA-00060 )
See Note 60.1 at My Oracle Support for Troubleshooting ORA-60 Errors

pTransaction Deadlock]
 
The following deadlock is not an ORACLE error. It is a
deadlock due to user error in the design of an application
or from issuing incorrect ad-hoc SQL. The following
information may aid in determining the deadlock:
 
Deadlock graph:
                                          ------------Blocker(s)-----------  ------------Waiter(s)------------
Resource Name                             process session holds waits serial  process session holds waits serial
TX-00050009-00004038-928AB365-00000000        111    1084     X        48203     121     173           X  18758
TX-00020014-0000442B-928AB365-00000000        121     173     X        18758     111    1084           X  48203
 
----- Information for waiting sessions -----
Session 1084:
  sid: 1084 ser: 48203 audsid: 4280261 user: 89/IFSAPP
  pdb: 3/DEVPDB
    flags: (0x8000041) USR/- flags2: (0x40009) -/-/INC
    flags_idl: (0x1) status: BSY/-/-/- kill: -/-/-/-
  pid: 111 O/S info: user: SYSTEM, term: MCT-IFSTESTDB, ospid: 2220
    image: ORACLE.EXE (SHAD)
  client details:
    O/S info: user: ifs, term: unknown, ospid: 1234
    machine: ifsapp-odata-bff474985-qbpb4 program: JDBC Thin Client
    application name: Aurena_Page_Designer_SVC, hash value=1468954909
    action name: Do_Publish_Configurations, hash value=1054568899
  current SQL:
  UPDATE FND_MODEL_DESIGN_DATA_TAB SET VISIBILITY = :B5 WHERE MODEL_ID = :B4 AND SCOPE_ID = :B3 AND DATA_ID = :B2 AND LAYER_NO = :B1 
 
Session 173:
  sid: 173 ser: 18758 audsid: 4280818 user: 89/IFSAPP
  pdb: 3/DEVPDB
    flags: (0x8000041) USR/- flags2: (0x40009) -/-/INC
    flags_idl: (0x1) status: BSY/-/-/- kill: -/-/-/-
  pid: 121 O/S info: user: SYSTEM, term: MCT-IFSTESTDB, ospid: 724
    image: ORACLE.EXE (SHAD)
  client details:
    O/S info: user: ifs, term: unknown, ospid: 1234
    machine: ifsapp-odata-bff474985-w9kgv program: JDBC Thin Client
    application name: Aurena_Page_Designer_SVC, hash value=1468954909
    action name: Do_Save_Config_Content, hash value=4059285747
  current SQL:
  UPDATE FND_MODEL_DESIGN_TAB SET VERSION = SYSTIMESTAMP WHERE MODEL_ID = 'ServerMetadata.' || :B2 || ':' || :B1 OR MODEL_ID = 'ClientMetadata.' || :B2 || ':' || :B1 
 
----- End of information for waiting sessions -----


Can any one suggest

Regards

Pankaj


Hello,

this got resolved. Found that client had enabled anti virus on database server

Regards

Pankaj


Reply