users@glassfish.java.net

Re: error in Connection pool to Oracle 11 after timeout

From: <glassfish_at_javadesktop.org>
Date: Fri, 28 May 2010 00:51:17 PDT

Thanks for the response,

Following is a trace from a successful and subsequently failing request to the connection pool:

[#|2010-05-27T08:54:24.885+0200|INFO|sun-appserver-ee8.2|class com.kpn.kas.services.user.UserServiceBean|_ThreadID=12;|START: getAuthUser|#]

[#|2010-05-27T08:54:24.886+0200|INFO|sun-appserver-ee8.2|UserManagerImpl|_ThreadID=12;|Retrieving AuthDomain for name='kpn.com'.|#]

[#|2010-05-27T08:54:24.887+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|RAR5036:Resource reference is not defined for JNDI name [jdbc/KAS-CP]|#]

[#|2010-05-27T08:54:24.888+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|returning the connector registry|#]

[#|2010-05-27T08:54:24.888+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|Returning the MCF from connector registry.|#]

[#|2010-05-27T08:54:24.889+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|Found/returing Connector descriptor in connector registry.|#]

[#|2010-05-27T08:54:24.889+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|ConnectorRegistry.getTxLevel - poolName => kaspool1|#]

[#|2010-05-27T08:54:24.889+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|ConnRegistry.getTxLevel - txLevel => 1|#]

[#|2010-05-27T08:54:24.890+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|ConnectionMgr: poolName kaspool1 txLevel : 1|#]

[#|2010-05-27T08:54:24.893+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|Pool: resourceEnlisted: 15|#]

[#|2010-05-27T08:54:24.898+0200|INFO|sun-appserver-ee8.2|UserManagerImpl|_ThreadID=12;|Retrieving AuthUser for uid='robert' and AuthDomain='kpn.com'.|#]

[#|2010-05-27T08:54:24.903+0200|INFO|sun-appserver-ee8.2|UserManagerImpl|_ThreadID=12;|AuthUser retrieved with id='25568'.|#]

[#|2010-05-27T08:54:24.918+0200|INFO|sun-appserver-ee8.2|class com.kpn.kas.services.user.UserServiceBean|_ThreadID=12;|END: getAuthUser: 33 [ms]|#]

[#|2010-05-27T08:54:24.922+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|Pool: resourceClosed: 15|#]

[#|2010-05-27T08:54:24.923+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|Pool: resourceFreed: 15|#]

[#|2010-05-27T08:54:24.923+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|Pool: transactionCompleted: 15|#]

[#|2010-05-27T08:54:25.568+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|IASNonSharedResourcePool: resize pool kaspool1|#]

[#|2010-05-27T08:54:25.568+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Sending to RA a set of free connections of size|#]

[#|2010-05-27T08:54:25.569+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|RA does not support ValidatingManagedConnectionFactory|#]

[#|2010-05-27T08:54:25.569+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: kaspool1 -- Idle resources freed: 0|#]

[#|2010-05-27T08:54:25.569+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: kaspool1 -- Invalid resources removed: 0|#]

[#|2010-05-27T08:54:25.570+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: kaspool1 -- Resource held: 5|#]

[#|2010-05-27T08:54:35.568+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|IASNonSharedResourcePool: resize pool kaspool1|#]

[#|2010-05-27T08:54:35.569+0200|FINEST|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|destroyResource for LocalTxConnectorAllocator done|#]

[#|2010-05-27T08:54:35.569+0200|FINEST|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|destroyResource for LocalTxConnectorAllocator done|#]

[#|2010-05-27T08:54:35.569+0200|FINEST|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|destroyResource for LocalTxConnectorAllocator done|#]

[#|2010-05-27T08:54:35.570+0200|FINEST|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|destroyResource for LocalTxConnectorAllocator done|#]

[#|2010-05-27T08:54:35.570+0200|FINEST|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|destroyResource for LocalTxConnectorAllocator done|#]

[#|2010-05-27T08:54:35.571+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Sending to RA a set of free connections of size|#]

[#|2010-05-27T08:54:35.571+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|RA does not support ValidatingManagedConnectionFactory|#]

[#|2010-05-27T08:54:35.571+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: kaspool1 -- Idle resources freed: 5|#]

[#|2010-05-27T08:54:35.572+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: kaspool1 -- Invalid resources removed: 0|#]

[#|2010-05-27T08:54:35.576+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: resource added: jdbc/KAS-CP_1,19|#]

[#|2010-05-27T08:54:35.579+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: resource added: jdbc/KAS-CP_1,20|#]

[#|2010-05-27T08:54:35.582+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: resource added: jdbc/KAS-CP_1,21|#]

[#|2010-05-27T08:54:35.584+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: resource added: jdbc/KAS-CP_1,22|#]

[#|2010-05-27T08:54:35.587+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: resource added: jdbc/KAS-CP_1,23|#]

[#|2010-05-27T08:54:35.588+0200|FINE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=13;|Pool: kaspool1 -- Resource held: 5|#]

[#|2010-05-27T08:54:43.915+0200|SEVERE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|RAR5031:System Exception
javax.resource.ResourceException: This Managed Connection is not valid as the phyiscal connection is not usable
        at com.sun.gjc.spi.ManagedConnection.checkIfValid(ManagedConnection.java:576)
        at com.sun.gjc.spi.ManagedConnection.getLocalTransaction(ManagedConnection.java:354)
        at com.sun.enterprise.resource.ConnectorXAResource.getResourceHandle(ConnectorXAResource.java:181)
        at com.sun.enterprise.resource.ConnectorXAResource.start(ConnectorXAResource.java:88)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerOpt.enlistResource(J2EETransactionManagerOpt.java:129)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerImpl.enlistComponentResources(J2EETransactionManagerImpl.java:484)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerImpl.enlistComponentResources(J2EETransactionManagerImpl.java:353)
        at com.sun.ejb.containers.BaseContainer.startNewTx(BaseContainer.java:2349)
        at com.sun.ejb.containers.BaseContainer.preInvokeTx(BaseContainer.java:2269)
        at com.sun.ejb.containers.BaseContainer.preInvoke(BaseContainer.java:804)
        at com.sun.ejb.containers.EJBLocalObjectInvocationHandler.invoke(EJBLocalObjectInvocationHandler.java:126)
        at $Proxy8.getAuthUser(Unknown Source)
        at com.xocs.kpn.kas.crm.model.Model.findUser(Unknown Source)
        at com.xocs.kpn.kas.crm.web.Controller.doTheWork(Unknown Source)
        at com.xocs.kpn.kas.crm.web.Controller.doPost(Unknown Source)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:767)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:860)
        at sun.reflect.GeneratedMethodAccessor72.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:592)
        at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:249)
        at java.security.AccessController.doPrivileged(Native Method)
        at javax.security.auth.Subject.doAsPrivileged(Subject.java:517)
        at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:282)
        at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:165)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:257)
        at org.apache.catalina.core.ApplicationFilterChain.access$000(ApplicationFilterChain.java:55)
        at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:161)
        at java.security.AccessController.doPrivileged(Native Method)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:263)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:225)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:173)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:933)
        at com.sun.enterprise.web.connector.httpservice.HttpServiceProcessor.process(HttpServiceProcessor.java:235)
        at com.sun.enterprise.web.HttpServiceWebContainer.service(HttpServiceWebContainer.java:2114)
|#]

[#|2010-05-27T08:54:43.919+0200|SEVERE|sun-appserver-ee8.2|javax.enterprise.resource.resourceadapter|_ThreadID=12;|RAR5031:System Exception
com.sun.enterprise.resource.PoolingException: javax.resource.ResourceException: This Managed Connection is not valid as the phyiscal connection is not usable
        at com.sun.enterprise.resource.ConnectorXAResource.getResourceHandle(ConnectorXAResource.java:186)
        at com.sun.enterprise.resource.ConnectorXAResource.start(ConnectorXAResource.java:88)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerOpt.enlistResource(J2EETransactionManagerOpt.java:129)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerImpl.enlistComponentResources(J2EETransactionManagerImpl.java:484)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerImpl.enlistComponentResources(J2EETransactionManagerImpl.java:353)
        at com.sun.ejb.containers.BaseContainer.startNewTx(BaseContainer.java:2349)
        at com.sun.ejb.containers.BaseContainer.preInvokeTx(BaseContainer.java:2269)
        at com.sun.ejb.containers.BaseContainer.preInvoke(BaseContainer.java:804)
        at com.sun.ejb.containers.EJBLocalObjectInvocationHandler.invoke(EJBLocalObjectInvocationHandler.java:126)
        at $Proxy8.getAuthUser(Unknown Source)
        at com.xocs.kpn.kas.crm.model.Model.findUser(Unknown Source)
        at com.xocs.kpn.kas.crm.web.Controller.doTheWork(Unknown Source)
        at com.xocs.kpn.kas.crm.web.Controller.doPost(Unknown Source)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:767)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:860)
        at sun.reflect.GeneratedMethodAccessor72.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:592)
        at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:249)
        at java.security.AccessController.doPrivileged(Native Method)
        at javax.security.auth.Subject.doAsPrivileged(Subject.java:517)
        at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:282)
        at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:165)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:257)
        at org.apache.catalina.core.ApplicationFilterChain.access$000(ApplicationFilterChain.java:55)
        at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:161)
        at java.security.AccessController.doPrivileged(Native Method)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:263)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:225)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:173)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:933)
        at com.sun.enterprise.web.connector.httpservice.HttpServiceProcessor.process(HttpServiceProcessor.java:235)
        at com.sun.enterprise.web.HttpServiceWebContainer.service(HttpServiceWebContainer.java:2114)
javax.resource.ResourceException: This Managed Connection is not valid as the phyiscal connection is not usable
        at com.sun.gjc.spi.ManagedConnection.checkIfValid(ManagedConnection.java:576)
        at com.sun.gjc.spi.ManagedConnection.getLocalTransaction(ManagedConnection.java:354)
        at com.sun.enterprise.resource.ConnectorXAResource.getResourceHandle(ConnectorXAResource.java:181)
        at com.sun.enterprise.resource.ConnectorXAResource.start(ConnectorXAResource.java:88)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerOpt.enlistResource(J2EETransactionManagerOpt.java:129)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerImpl.enlistComponentResources(J2EETransactionManagerImpl.java:484)
        at com.sun.enterprise.distributedtx.J2EETransactionManagerImpl.enlistComponentResources(J2EETransactionManagerImpl.java:353)
        at com.sun.ejb.containers.BaseContainer.startNewTx(BaseContainer.java:2349)
        at com.sun.ejb.containers.BaseContainer.preInvokeTx(BaseContainer.java:2269)
        at com.sun.ejb.containers.BaseContainer.preInvoke(BaseContainer.java:804)
        at com.sun.ejb.containers.EJBLocalObjectInvocationHandler.invoke(EJBLocalObjectInvocationHandler.java:126)
        at $Proxy8.getAuthUser(Unknown Source)
        at com.xocs.kpn.kas.crm.model.Model.findUser(Unknown Source)
        at com.xocs.kpn.kas.crm.web.Controller.doTheWork(Unknown Source)
        at com.xocs.kpn.kas.crm.web.Controller.doPost(Unknown Source)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:767)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:860)
        at sun.reflect.GeneratedMethodAccessor72.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:592)
        at org.apache.catalina.security.SecurityUtil$1.run(SecurityUtil.java:249)
        at java.security.AccessController.doPrivileged(Native Method)
        at javax.security.auth.Subject.doAsPrivileged(Subject.java:517)
        at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:282)
        at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:165)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:257)
        at org.apache.catalina.core.ApplicationFilterChain.access$000(ApplicationFilterChain.java:55)
        at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:161)
        at java.security.AccessController.doPrivileged(Native Method)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:263)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:225)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:173)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:170)
        at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:551)
        at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:933)
        at com.sun.enterprise.web.connector.httpservice.HttpServiceProcessor.process(HttpServiceProcessor.java:235)
        at com.sun.enterprise.web.HttpServiceWebContainer.service(HttpServiceWebContainer.java:2114)
|#][b][/b]
[Message sent by forum member 'robertatjavix']

http://forums.java.net/jive/thread.jspa?messageID=471801